{"level":"warn","ts":"2026-07-15T04:57:21.393938Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T04:57:21.394055Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-07-15T04:57:21.394126Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T04:57:21.394141Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-07-15T04:57:21.394161Z","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-07-15T04:57:21.395048Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-07-15T04:57:21.39514Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-07-15T04:57:21.399796Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.409988ms"} {"level":"info","ts":"2026-07-15T04:57:21.404502Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-07-15T04:57:21.404588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T04:57:21.404617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-07-15T04:57:21.404638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T04:57:21.404652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-07-15T04:57:21.404699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-07-15T04:57:21.409669Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T04:57:21.411928Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T04:57:21.413163Z","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-07-15T04:57:21.415634Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T04:57:21.415881Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T04:57:21.415982Z","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-07-15T04:57:21.416309Z","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-07-15T04:57:21.416365Z","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-07-15T04:57:21.417195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-07-15T04:57:21.417394Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-07-15T04:57:21.420753Z","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-07-15T04:57:21.420791Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-07-15T04:57:21.420918Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-07-15T04:57:21.4218Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T04:57:21.421863Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T04:57:22.205169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T04:57:22.205261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T04:57:22.205316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-07-15T04:57:22.20534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-07-15T04:57:22.205359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-07-15T04:57:22.205376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-07-15T04:57:22.205398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-07-15T04:57:22.206657Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T04:57:22.206711Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T04:57:22.206673Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:57:22.20697Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T04:57:22.207046Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T04:57:22.207292Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T04:57:22.20787Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:57:22.208034Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:57:22.208078Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:57:22.210226Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-07-15T04:57:22.211131Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T04:57:38.564608Z","caller":"traceutil/trace.go:171","msg":"trace[1512962713] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"441.136284ms","start":"2026-07-15T04:57:38.12343Z","end":"2026-07-15T04:57:38.564567Z","steps":["trace[1512962713] 'process raft request' (duration: 440.90747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:57:38.565397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:57:38.123413Z","time spent":"441.326798ms","remote":"127.0.0.1:46510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T04:57:38.73979Z","caller":"traceutil/trace.go:171","msg":"trace[2123738105] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"134.642123ms","start":"2026-07-15T04:57:38.605095Z","end":"2026-07-15T04:57:38.739737Z","steps":["trace[2123738105] 'read index received' (duration: 133.52457ms)","trace[2123738105] 'applied index is now lower than readState.Index' (duration: 1.115533ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:57:38.739903Z","caller":"traceutil/trace.go:171","msg":"trace[687999581] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"162.240116ms","start":"2026-07-15T04:57:38.577589Z","end":"2026-07-15T04:57:38.739829Z","steps":["trace[687999581] 'process raft request' (duration: 160.98094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:57:38.739971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.819827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:57:38.740108Z","caller":"traceutil/trace.go:171","msg":"trace[671334738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"135.040141ms","start":"2026-07-15T04:57:38.605047Z","end":"2026-07-15T04:57:38.740088Z","steps":["trace[671334738] 'agreement among raft nodes before linearized reading' (duration: 134.820096ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:58:05.350225Z","caller":"traceutil/trace.go:171","msg":"trace[1006254052] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"307.90067ms","start":"2026-07-15T04:58:05.042281Z","end":"2026-07-15T04:58:05.350181Z","steps":["trace[1006254052] 'process raft request' (duration: 307.092554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:05.350671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:58:05.04226Z","time spent":"308.096763ms","remote":"127.0.0.1:46428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:58:05.841517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.039533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T04:58:05.841895Z","caller":"traceutil/trace.go:171","msg":"trace[41114334] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"691.804218ms","start":"2026-07-15T04:58:05.150034Z","end":"2026-07-15T04:58:05.841838Z","steps":["trace[41114334] 'read index received' (duration: 199.052232ms)","trace[41114334] 'applied index is now lower than readState.Index' (duration: 492.749696ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:58:05.841852Z","caller":"traceutil/trace.go:171","msg":"trace[227449947] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"795.049694ms","start":"2026-07-15T04:58:05.046754Z","end":"2026-07-15T04:58:05.841803Z","steps":["trace[227449947] 'process raft request' (duration: 393.40539ms)","trace[227449947] 'compare' (duration: 400.846608ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:58:05.842116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:58:05.046741Z","time spent":"795.3352ms","remote":"127.0.0.1:46510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:58:05.843268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.157526ms","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-07-15T04:58:05.843592Z","caller":"traceutil/trace.go:171","msg":"trace[1066672678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"693.402421ms","start":"2026-07-15T04:58:05.149977Z","end":"2026-07-15T04:58:05.843379Z","steps":["trace[1066672678] 'agreement among raft nodes before linearized reading' (duration: 691.973833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:05.843657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:58:05.14995Z","time spent":"693.696287ms","remote":"127.0.0.1:46588","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":"info","ts":"2026-07-15T04:58:06.068345Z","caller":"traceutil/trace.go:171","msg":"trace[396920822] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"219.47915ms","start":"2026-07-15T04:58:05.848823Z","end":"2026-07-15T04:58:06.068302Z","steps":["trace[396920822] 'process raft request' (duration: 219.377528ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:58:06.068443Z","caller":"traceutil/trace.go:171","msg":"trace[1337088447] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"226.373968ms","start":"2026-07-15T04:58:05.841991Z","end":"2026-07-15T04:58:06.068365Z","steps":["trace[1337088447] 'read index received' (duration: 192.327057ms)","trace[1337088447] 'applied index is now lower than readState.Index' (duration: 34.030881ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:58:06.068655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.934701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T04:58:06.068741Z","caller":"traceutil/trace.go:171","msg":"trace[479435363] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:633; }","duration":"739.121005ms","start":"2026-07-15T04:58:05.3296Z","end":"2026-07-15T04:58:06.068721Z","steps":["trace[479435363] 'agreement among raft nodes before linearized reading' (duration: 738.906281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:06.068786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.99492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T04:58:06.068793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:58:05.329573Z","time spent":"739.209826ms","remote":"127.0.0.1:46344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"info","ts":"2026-07-15T04:58:06.068837Z","caller":"traceutil/trace.go:171","msg":"trace[1854687531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"194.130274ms","start":"2026-07-15T04:58:05.874688Z","end":"2026-07-15T04:58:06.068819Z","steps":["trace[1854687531] 'agreement among raft nodes before linearized reading' (duration: 193.97348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:06.068865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.256588ms","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-07-15T04:58:06.068965Z","caller":"traceutil/trace.go:171","msg":"trace[1809980249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"747.415831ms","start":"2026-07-15T04:58:05.321523Z","end":"2026-07-15T04:58:06.068939Z","steps":["trace[1809980249] 'agreement among raft nodes before linearized reading' (duration: 747.111534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:06.069012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:58:05.321496Z","time spent":"747.508494ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-15T04:58:06.069282Z","caller":"traceutil/trace.go:171","msg":"trace[774129353] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"645.077284ms","start":"2026-07-15T04:58:05.423427Z","end":"2026-07-15T04:58:06.068504Z","steps":["trace[774129353] 'process raft request' (duration: 610.914931ms)","trace[774129353] 'compare' (duration: 33.619562ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:58:06.069529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:58:05.423402Z","time spent":"645.964313ms","remote":"127.0.0.1:46694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T04:58:21.391378Z","caller":"traceutil/trace.go:171","msg":"trace[743208194] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"192.326777ms","start":"2026-07-15T04:58:21.199029Z","end":"2026-07-15T04:58:21.391355Z","steps":["trace[743208194] 'process raft request' (duration: 192.137053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:45.517898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.665322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T04:59:45.518049Z","caller":"traceutil/trace.go:171","msg":"trace[400270871] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"112.300423ms","start":"2026-07-15T04:59:45.405714Z","end":"2026-07-15T04:59:45.518014Z","steps":["trace[400270871] 'compare' (duration: 105.497189ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:00:22.301896Z","caller":"traceutil/trace.go:171","msg":"trace[588816162] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"304.182709ms","start":"2026-07-15T05:00:21.997684Z","end":"2026-07-15T05:00:22.301867Z","steps":["trace[588816162] 'process raft request' (duration: 303.994695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:00:22.302065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:00:21.997661Z","time spent":"304.319721ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:00:22.368664Z","caller":"traceutil/trace.go:171","msg":"trace[813426183] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2239; }","duration":"347.7685ms","start":"2026-07-15T05:00:22.02087Z","end":"2026-07-15T05:00:22.368639Z","steps":["trace[813426183] 'read index received' (duration: 280.841763ms)","trace[813426183] 'applied index is now lower than readState.Index' (duration: 66.925737ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:00:22.368763Z","caller":"traceutil/trace.go:171","msg":"trace[281505803] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"355.429823ms","start":"2026-07-15T05:00:22.013295Z","end":"2026-07-15T05:00:22.368725Z","steps":["trace[281505803] 'process raft request' (duration: 330.146288ms)","trace[281505803] 'compare' (duration: 25.076631ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:00:22.368962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:00:22.013281Z","time spent":"355.561195ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:00:22.369027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.113987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T05:00:22.369243Z","caller":"traceutil/trace.go:171","msg":"trace[348463531] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2156; }","duration":"348.379652ms","start":"2026-07-15T05:00:22.020803Z","end":"2026-07-15T05:00:22.369182Z","steps":["trace[348463531] 'agreement among raft nodes before linearized reading' (duration: 347.981264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:00:22.36938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:00:22.020719Z","time spent":"348.614708ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":29,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-07-15T05:00:22.568246Z","caller":"traceutil/trace.go:171","msg":"trace[1486445494] linearizableReadLoop","detail":"{readStateIndex:2242; appliedIndex:2241; }","duration":"199.480777ms","start":"2026-07-15T05:00:22.368742Z","end":"2026-07-15T05:00:22.568223Z","steps":["trace[1486445494] 'read index received' (duration: 115.06131ms)","trace[1486445494] 'applied index is now lower than readState.Index' (duration: 84.418537ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:00:22.568303Z","caller":"traceutil/trace.go:171","msg":"trace[1755878706] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"221.142329ms","start":"2026-07-15T05:00:22.347112Z","end":"2026-07-15T05:00:22.568254Z","steps":["trace[1755878706] 'process raft request' (duration: 136.748252ms)","trace[1755878706] 'compare' (duration: 84.127041ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:00:22.568437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.924302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-15T05:00:22.568835Z","caller":"traceutil/trace.go:171","msg":"trace[1815756694] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2157; }","duration":"414.362221ms","start":"2026-07-15T05:00:22.154446Z","end":"2026-07-15T05:00:22.568808Z","steps":["trace[1815756694] 'agreement among raft nodes before linearized reading' (duration: 413.857031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:00:22.568949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:00:22.154424Z","time spent":"414.491043ms","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-15T05:00:22.568945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.547185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-15T05:00:22.569175Z","caller":"traceutil/trace.go:171","msg":"trace[316162333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2157; }","duration":"387.144227ms","start":"2026-07-15T05:00:22.182005Z","end":"2026-07-15T05:00:22.56915Z","steps":["trace[316162333] 'agreement among raft nodes before linearized reading' (duration: 386.28459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:00:22.569315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:00:22.181978Z","time spent":"387.315679ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-15T05:05:49.167174Z","caller":"traceutil/trace.go:171","msg":"trace[1465175226] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"181.867468ms","start":"2026-07-15T05:05:48.985272Z","end":"2026-07-15T05:05:49.167139Z","steps":["trace[1465175226] 'process raft request' (duration: 85.446303ms)","trace[1465175226] 'compare' (duration: 96.234891ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:05:49.211579Z","caller":"traceutil/trace.go:171","msg":"trace[1776840152] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"155.000533ms","start":"2026-07-15T05:05:49.056539Z","end":"2026-07-15T05:05:49.21154Z","steps":["trace[1776840152] 'process raft request' (duration: 154.454612ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:05:49.67509Z","caller":"traceutil/trace.go:171","msg":"trace[2057739930] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"184.780518ms","start":"2026-07-15T05:05:49.490285Z","end":"2026-07-15T05:05:49.675066Z","steps":["trace[2057739930] 'process raft request' (duration: 184.560493ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:05:49.686628Z","caller":"traceutil/trace.go:171","msg":"trace[924685142] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"106.231684ms","start":"2026-07-15T05:05:49.580368Z","end":"2026-07-15T05:05:49.6866Z","steps":["trace[924685142] 'process raft request' (duration: 106.066811ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:05:56.557184Z","caller":"traceutil/trace.go:171","msg":"trace[720414380] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"162.380143ms","start":"2026-07-15T05:05:56.394753Z","end":"2026-07-15T05:05:56.557134Z","steps":["trace[720414380] 'process raft request' (duration: 162.163788ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:06:19.942571Z","caller":"traceutil/trace.go:171","msg":"trace[771004921] linearizableReadLoop","detail":"{readStateIndex:5793; appliedIndex:5792; }","duration":"101.672184ms","start":"2026-07-15T05:06:19.840873Z","end":"2026-07-15T05:06:19.942545Z","steps":["trace[771004921] 'read index received' (duration: 101.517251ms)","trace[771004921] 'applied index is now lower than readState.Index' (duration: 153.543µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:06:19.942625Z","caller":"traceutil/trace.go:171","msg":"trace[1455498015] transaction","detail":"{read_only:false; response_revision:5584; number_of_response:1; }","duration":"103.734557ms","start":"2026-07-15T05:06:19.838849Z","end":"2026-07-15T05:06:19.942584Z","steps":["trace[1455498015] 'process raft request' (duration: 103.544413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:06:19.942888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.996101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ed8e1dc0-546c-4d20-ab78-4f1de8001c00\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-15T05:06:19.942954Z","caller":"traceutil/trace.go:171","msg":"trace[1004313503] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ed8e1dc0-546c-4d20-ab78-4f1de8001c00; range_end:; response_count:1; response_revision:5584; }","duration":"102.113113ms","start":"2026-07-15T05:06:19.840825Z","end":"2026-07-15T05:06:19.942938Z","steps":["trace[1004313503] 'agreement among raft nodes before linearized reading' (duration: 101.857948ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:07:22.231487Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3001} {"level":"info","ts":"2026-07-15T05:07:22.319381Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3001,"took":"86.52246ms","hash":783261481,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":31920128,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T05:07:22.319492Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":783261481,"revision":3001,"compact-revision":-1} {"level":"info","ts":"2026-07-15T05:12:02.702179Z","caller":"traceutil/trace.go:171","msg":"trace[2101583438] transaction","detail":"{read_only:false; response_revision:8198; number_of_response:1; }","duration":"377.999725ms","start":"2026-07-15T05:12:02.324156Z","end":"2026-07-15T05:12:02.702155Z","steps":["trace[2101583438] 'process raft request' (duration: 377.867032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:12:02.702335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:12:02.324137Z","time spent":"378.123997ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:12:02.702849Z","caller":"traceutil/trace.go:171","msg":"trace[636358057] linearizableReadLoop","detail":"{readStateIndex:8489; appliedIndex:8489; }","duration":"354.74377ms","start":"2026-07-15T05:12:02.348094Z","end":"2026-07-15T05:12:02.702837Z","steps":["trace[636358057] 'read index received' (duration: 354.73931ms)","trace[636358057] 'applied index is now lower than readState.Index' (duration: 3.68µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:12:02.702989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.864427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-15T05:12:02.703018Z","caller":"traceutil/trace.go:171","msg":"trace[2071556228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8198; }","duration":"266.931498ms","start":"2026-07-15T05:12:02.436078Z","end":"2026-07-15T05:12:02.70301Z","steps":["trace[2071556228] 'agreement among raft nodes before linearized reading' (duration: 266.825236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:12:02.703148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.053987ms","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-07-15T05:12:02.70318Z","caller":"traceutil/trace.go:171","msg":"trace[1600979920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8198; }","duration":"355.117988ms","start":"2026-07-15T05:12:02.348055Z","end":"2026-07-15T05:12:02.703173Z","steps":["trace[1600979920] 'agreement among raft nodes before linearized reading' (duration: 355.041476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:12:02.703212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:12:02.348038Z","time spent":"355.165329ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T05:12:02.703451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.199952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T05:12:02.703516Z","caller":"traceutil/trace.go:171","msg":"trace[331381738] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:8198; }","duration":"118.315754ms","start":"2026-07-15T05:12:02.585187Z","end":"2026-07-15T05:12:02.703503Z","steps":["trace[331381738] 'agreement among raft nodes before linearized reading' (duration: 118.193802ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:12:16.899991Z","caller":"traceutil/trace.go:171","msg":"trace[1017847836] transaction","detail":"{read_only:false; response_revision:8273; number_of_response:1; }","duration":"119.502959ms","start":"2026-07-15T05:12:16.780443Z","end":"2026-07-15T05:12:16.899946Z","steps":["trace[1017847836] 'process raft request' (duration: 102.734746ms)","trace[1017847836] 'compare' (duration: 16.284132ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:12:22.237612Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6108} {"level":"info","ts":"2026-07-15T05:12:22.32873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6108,"took":"89.560907ms","hash":3656403557,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":23441408,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-15T05:12:22.328807Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3656403557,"revision":6108,"compact-revision":3001} {"level":"info","ts":"2026-07-15T05:12:43.141235Z","caller":"traceutil/trace.go:171","msg":"trace[1838016686] linearizableReadLoop","detail":"{readStateIndex:8772; appliedIndex:8771; }","duration":"113.124998ms","start":"2026-07-15T05:12:43.028075Z","end":"2026-07-15T05:12:43.1412Z","steps":["trace[1838016686] 'read index received' (duration: 112.857713ms)","trace[1838016686] 'applied index is now lower than readState.Index' (duration: 265.605µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:12:43.141357Z","caller":"traceutil/trace.go:171","msg":"trace[1899237079] transaction","detail":"{read_only:false; response_revision:8469; number_of_response:1; }","duration":"117.666821ms","start":"2026-07-15T05:12:43.023658Z","end":"2026-07-15T05:12:43.141324Z","steps":["trace[1899237079] 'process raft request' (duration: 117.155591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:12:43.141624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.159199ms","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-07-15T05:12:43.141707Z","caller":"traceutil/trace.go:171","msg":"trace[1382245405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8469; }","duration":"112.273941ms","start":"2026-07-15T05:12:43.029415Z","end":"2026-07-15T05:12:43.141689Z","steps":["trace[1382245405] 'agreement among raft nodes before linearized reading' (duration: 112.037586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:12:43.141707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.601538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T05:12:43.141935Z","caller":"traceutil/trace.go:171","msg":"trace[1783391342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8469; }","duration":"113.904725ms","start":"2026-07-15T05:12:43.028015Z","end":"2026-07-15T05:12:43.14192Z","steps":["trace[1783391342] 'agreement among raft nodes before linearized reading' (duration: 113.368384ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:12:43.329333Z","caller":"traceutil/trace.go:171","msg":"trace[831318373] transaction","detail":"{read_only:false; response_revision:8470; number_of_response:1; }","duration":"181.538945ms","start":"2026-07-15T05:12:43.147766Z","end":"2026-07-15T05:12:43.329305Z","steps":["trace[831318373] 'process raft request' (duration: 177.728718ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:12:43.349976Z","caller":"traceutil/trace.go:171","msg":"trace[1387865589] transaction","detail":"{read_only:false; response_revision:8471; number_of_response:1; }","duration":"200.784627ms","start":"2026-07-15T05:12:43.149159Z","end":"2026-07-15T05:12:43.349943Z","steps":["trace[1387865589] 'process raft request' (duration: 200.376549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:12:55.931682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.573306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-15T05:12:55.93262Z","caller":"traceutil/trace.go:171","msg":"trace[600106322] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8579; }","duration":"110.584687ms","start":"2026-07-15T05:12:55.822012Z","end":"2026-07-15T05:12:55.932597Z","steps":["trace[600106322] 'range keys from in-memory index tree' (duration: 109.356312ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:12:56.612846Z","caller":"traceutil/trace.go:171","msg":"trace[754667080] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"133.699779ms","start":"2026-07-15T05:12:56.479117Z","end":"2026-07-15T05:12:56.612817Z","steps":["trace[754667080] 'process raft request' (duration: 133.549006ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:12:56.634625Z","caller":"traceutil/trace.go:171","msg":"trace[1377896695] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"154.961443ms","start":"2026-07-15T05:12:56.479644Z","end":"2026-07-15T05:12:56.634605Z","steps":["trace[1377896695] 'process raft request' (duration: 154.82221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:12:57.463103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.909344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-07-15T05:12:57.463267Z","caller":"traceutil/trace.go:171","msg":"trace[886406827] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8591; }","duration":"162.084238ms","start":"2026-07-15T05:12:57.301162Z","end":"2026-07-15T05:12:57.463246Z","steps":["trace[886406827] 'range keys from in-memory index tree' (duration: 161.747831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:12:57.755513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.071775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T05:12:57.755763Z","caller":"traceutil/trace.go:171","msg":"trace[671890305] linearizableReadLoop","detail":"{readStateIndex:8905; appliedIndex:8904; }","duration":"214.936077ms","start":"2026-07-15T05:12:57.540806Z","end":"2026-07-15T05:12:57.755742Z","steps":["trace[671890305] 'read index received' (duration: 36.440974ms)","trace[671890305] 'applied index is now lower than readState.Index' (duration: 178.493363ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:12:57.755998Z","caller":"traceutil/trace.go:171","msg":"trace[1296045556] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"218.780275ms","start":"2026-07-15T05:12:57.537168Z","end":"2026-07-15T05:12:57.755948Z","steps":["trace[1296045556] 'process raft request' (duration: 40.158159ms)","trace[1296045556] 'compare' (duration: 177.917152ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:12:57.756041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.230522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gzv8h\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-15T05:12:57.756172Z","caller":"traceutil/trace.go:171","msg":"trace[581965822] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gzv8h; range_end:; response_count:1; response_revision:8596; }","duration":"215.369026ms","start":"2026-07-15T05:12:57.540787Z","end":"2026-07-15T05:12:57.756156Z","steps":["trace[581965822] 'agreement among raft nodes before linearized reading' (duration: 215.107291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:12:57.756245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.799783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-07-15T05:12:57.756349Z","caller":"traceutil/trace.go:171","msg":"trace[645547750] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8596; }","duration":"188.891205ms","start":"2026-07-15T05:12:57.567406Z","end":"2026-07-15T05:12:57.756298Z","steps":["trace[645547750] 'agreement among raft nodes before linearized reading' (duration: 188.693411ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:13:37.6325Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002446.wal"} {"level":"warn","ts":"2026-07-15T05:14:10.48268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.306347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T05:14:10.482834Z","caller":"traceutil/trace.go:171","msg":"trace[1715615614] transaction","detail":"{read_only:false; response_revision:9319; number_of_response:1; }","duration":"187.207411ms","start":"2026-07-15T05:14:10.295604Z","end":"2026-07-15T05:14:10.482811Z","steps":["trace[1715615614] 'process raft request' (duration: 63.641899ms)","trace[1715615614] 'compare' (duration: 123.031051ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:14:57.890967Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T05:14:57.896147Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-15T05:14:57.896233Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-15T05:17:22.243845Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8309} {"level":"info","ts":"2026-07-15T05:17:22.324889Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8309,"took":"78.615189ms","hash":422912209,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":26464256,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-15T05:17:22.324955Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":422912209,"revision":8309,"compact-revision":6108} {"level":"warn","ts":"2026-07-15T05:17:46.751928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.214528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-07-15T05:17:46.752036Z","caller":"traceutil/trace.go:171","msg":"trace[967493856] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11126; }","duration":"134.347751ms","start":"2026-07-15T05:17:46.617665Z","end":"2026-07-15T05:17:46.752013Z","steps":["trace[967493856] 'range keys from in-memory index tree' (duration: 134.063894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:17:46.752055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.170631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:17:46.752247Z","caller":"traceutil/trace.go:171","msg":"trace[797679941] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11126; }","duration":"143.417706ms","start":"2026-07-15T05:17:46.608788Z","end":"2026-07-15T05:17:46.752206Z","steps":["trace[797679941] 'range keys from in-memory index tree' (duration: 143.080649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:17:46.752378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.589898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:17:46.752504Z","caller":"traceutil/trace.go:171","msg":"trace[2138235209] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11126; }","duration":"138.777471ms","start":"2026-07-15T05:17:46.613701Z","end":"2026-07-15T05:17:46.752478Z","steps":["trace[2138235209] 'range keys from in-memory index tree' (duration: 138.475056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:17:46.752588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.956951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T05:17:46.752409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.827152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:17:46.752705Z","caller":"traceutil/trace.go:171","msg":"trace[1227095988] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11126; }","duration":"130.106214ms","start":"2026-07-15T05:17:46.622573Z","end":"2026-07-15T05:17:46.752679Z","steps":["trace[1227095988] 'range keys from in-memory index tree' (duration: 129.89351ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:17:46.752727Z","caller":"traceutil/trace.go:171","msg":"trace[199357326] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11126; }","duration":"139.141978ms","start":"2026-07-15T05:17:46.613551Z","end":"2026-07-15T05:17:46.752693Z","steps":["trace[199357326] 'range keys from in-memory index tree' (duration: 138.784331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:17:46.752588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.845678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-15T05:17:46.752899Z","caller":"traceutil/trace.go:171","msg":"trace[2002189162] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11126; }","duration":"192.180075ms","start":"2026-07-15T05:17:46.560698Z","end":"2026-07-15T05:17:46.752878Z","steps":["trace[2002189162] 'range keys from in-memory index tree' (duration: 191.631013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:17:47.013666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.751775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:17:47.013755Z","caller":"traceutil/trace.go:171","msg":"trace[534648340] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:11127; }","duration":"146.898138ms","start":"2026-07-15T05:17:46.866837Z","end":"2026-07-15T05:17:47.013736Z","steps":["trace[534648340] 'range keys from in-memory index tree' (duration: 146.617652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:17:47.013749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.914442ms","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-07-15T05:17:47.013829Z","caller":"traceutil/trace.go:171","msg":"trace[516124585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11127; }","duration":"114.050475ms","start":"2026-07-15T05:17:46.899759Z","end":"2026-07-15T05:17:47.013809Z","steps":["trace[516124585] 'range keys from in-memory index tree' (duration: 113.694948ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:22:22.25029Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10960} {"level":"info","ts":"2026-07-15T05:22:22.332677Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10960,"took":"78.57848ms","hash":416708890,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":27684864,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-15T05:22:22.332751Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":416708890,"revision":10960,"compact-revision":8309} {"level":"warn","ts":"2026-07-15T05:24:10.785061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.46764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-15T05:24:10.785156Z","caller":"traceutil/trace.go:171","msg":"trace[1148989438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13715; }","duration":"163.604893ms","start":"2026-07-15T05:24:10.621529Z","end":"2026-07-15T05:24:10.785134Z","steps":["trace[1148989438] 'range keys from in-memory index tree' (duration: 163.301146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:24:10.784941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.74901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:24:10.786419Z","caller":"traceutil/trace.go:171","msg":"trace[2075512758] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:13715; }","duration":"195.255811ms","start":"2026-07-15T05:24:10.591133Z","end":"2026-07-15T05:24:10.786389Z","steps":["trace[2075512758] 'count revisions from in-memory index tree' (duration: 193.664279ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:24:10.928635Z","caller":"traceutil/trace.go:171","msg":"trace[1178578326] linearizableReadLoop","detail":"{readStateIndex:14186; appliedIndex:14185; }","duration":"125.44902ms","start":"2026-07-15T05:24:10.803158Z","end":"2026-07-15T05:24:10.928607Z","steps":["trace[1178578326] 'read index received' (duration: 125.392959ms)","trace[1178578326] 'applied index is now lower than readState.Index' (duration: 54.301µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:24:10.929053Z","caller":"traceutil/trace.go:171","msg":"trace[1940417214] transaction","detail":"{read_only:false; response_revision:13716; number_of_response:1; }","duration":"138.279024ms","start":"2026-07-15T05:24:10.790731Z","end":"2026-07-15T05:24:10.92901Z","steps":["trace[1940417214] 'process raft request' (duration: 137.680291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:24:10.929133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.968012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gzv8h\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-15T05:24:10.929184Z","caller":"traceutil/trace.go:171","msg":"trace[540579669] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gzv8h; range_end:; response_count:1; response_revision:13716; }","duration":"126.067023ms","start":"2026-07-15T05:24:10.803105Z","end":"2026-07-15T05:24:10.929172Z","steps":["trace[540579669] 'agreement among raft nodes before linearized reading' (duration: 125.89065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:24:10.97816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.934479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T05:24:10.978274Z","caller":"traceutil/trace.go:171","msg":"trace[1531684251] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:13716; }","duration":"143.096403ms","start":"2026-07-15T05:24:10.835156Z","end":"2026-07-15T05:24:10.978253Z","steps":["trace[1531684251] 'agreement among raft nodes before linearized reading' (duration: 142.894449ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:25:15.761214Z","caller":"traceutil/trace.go:171","msg":"trace[1310177178] transaction","detail":"{read_only:false; response_revision:14603; number_of_response:1; }","duration":"155.63926ms","start":"2026-07-15T05:25:15.605549Z","end":"2026-07-15T05:25:15.761188Z","steps":["trace[1310177178] 'process raft request' (duration: 155.474587ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:25:49.695072Z","caller":"traceutil/trace.go:171","msg":"trace[195989987] linearizableReadLoop","detail":"{readStateIndex:15283; appliedIndex:15282; }","duration":"246.505362ms","start":"2026-07-15T05:25:49.448547Z","end":"2026-07-15T05:25:49.695052Z","steps":["trace[195989987] 'read index received' (duration: 246.321768ms)","trace[195989987] 'applied index is now lower than readState.Index' (duration: 182.584µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:25:49.695136Z","caller":"traceutil/trace.go:171","msg":"trace[818304554] transaction","detail":"{read_only:false; response_revision:14780; number_of_response:1; }","duration":"286.060322ms","start":"2026-07-15T05:25:49.409043Z","end":"2026-07-15T05:25:49.695103Z","steps":["trace[818304554] 'process raft request' (duration: 285.873828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:25:49.695275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.718986ms","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-07-15T05:25:49.695318Z","caller":"traceutil/trace.go:171","msg":"trace[1890884299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14780; }","duration":"246.817728ms","start":"2026-07-15T05:25:49.44849Z","end":"2026-07-15T05:25:49.695308Z","steps":["trace[1890884299] 'agreement among raft nodes before linearized reading' (duration: 246.674515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:25:49.992082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.118011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:25:49.992175Z","caller":"traceutil/trace.go:171","msg":"trace[1054752788] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:14781; }","duration":"121.235804ms","start":"2026-07-15T05:25:49.870921Z","end":"2026-07-15T05:25:49.992157Z","steps":["trace[1054752788] 'range keys from in-memory index tree' (duration: 121.077121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:25:49.992462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.433738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-07-15T05:25:49.992542Z","caller":"traceutil/trace.go:171","msg":"trace[195795936] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14781; }","duration":"121.558561ms","start":"2026-07-15T05:25:49.870965Z","end":"2026-07-15T05:25:49.992524Z","steps":["trace[195795936] 'range keys from in-memory index tree' (duration: 121.246384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:25:49.992598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.66533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gzv8h\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-15T05:25:49.992663Z","caller":"traceutil/trace.go:171","msg":"trace[2060518799] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gzv8h; range_end:; response_count:1; response_revision:14781; }","duration":"195.738441ms","start":"2026-07-15T05:25:49.796906Z","end":"2026-07-15T05:25:49.992644Z","steps":["trace[2060518799] 'range keys from in-memory index tree' (duration: 195.552368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:25:49.992989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.58876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-15T05:25:49.993036Z","caller":"traceutil/trace.go:171","msg":"trace[91343964] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14781; }","duration":"118.666121ms","start":"2026-07-15T05:25:49.874359Z","end":"2026-07-15T05:25:49.993025Z","steps":["trace[91343964] 'range keys from in-memory index tree' (duration: 118.473438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:25:49.993215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.484999ms","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-07-15T05:25:49.993261Z","caller":"traceutil/trace.go:171","msg":"trace[768321005] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14781; }","duration":"122.573521ms","start":"2026-07-15T05:25:49.870676Z","end":"2026-07-15T05:25:49.99325Z","steps":["trace[768321005] 'range keys from in-memory index tree' (duration: 122.377497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:25:49.993484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.657003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-07-15T05:25:49.993533Z","caller":"traceutil/trace.go:171","msg":"trace[372036324] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:14781; }","duration":"122.750306ms","start":"2026-07-15T05:25:49.870767Z","end":"2026-07-15T05:25:49.993517Z","steps":["trace[372036324] 'range keys from in-memory index tree' (duration: 122.532701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:25:50.274692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.108846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-15T05:25:50.274836Z","caller":"traceutil/trace.go:171","msg":"trace[928686366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14782; }","duration":"108.249259ms","start":"2026-07-15T05:25:50.166513Z","end":"2026-07-15T05:25:50.274763Z","steps":["trace[928686366] 'range keys from in-memory index tree' (duration: 107.887761ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:25:50.305761Z","caller":"traceutil/trace.go:171","msg":"trace[2023062924] transaction","detail":"{read_only:false; response_revision:14784; number_of_response:1; }","duration":"107.654206ms","start":"2026-07-15T05:25:50.198082Z","end":"2026-07-15T05:25:50.305736Z","steps":["trace[2023062924] 'process raft request' (duration: 107.460472ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:25:50.305952Z","caller":"traceutil/trace.go:171","msg":"trace[1475788396] transaction","detail":"{read_only:false; response_revision:14785; number_of_response:1; }","duration":"102.42681ms","start":"2026-07-15T05:25:50.203504Z","end":"2026-07-15T05:25:50.30593Z","steps":["trace[1475788396] 'process raft request' (duration: 102.189465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:25:50.489472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.179666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-07-15T05:25:50.489556Z","caller":"traceutil/trace.go:171","msg":"trace[505142970] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14786; }","duration":"104.336019ms","start":"2026-07-15T05:25:50.385201Z","end":"2026-07-15T05:25:50.489537Z","steps":["trace[505142970] 'range keys from in-memory index tree' (duration: 103.996162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:25:50.489693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.561543ms","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-07-15T05:25:50.489793Z","caller":"traceutil/trace.go:171","msg":"trace[1537872511] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14786; }","duration":"104.863179ms","start":"2026-07-15T05:25:50.384908Z","end":"2026-07-15T05:25:50.489771Z","steps":["trace[1537872511] 'range keys from in-memory index tree' (duration: 104.279108ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:27:22.259587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13004} {"level":"info","ts":"2026-07-15T05:27:22.342459Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13004,"took":"78.110333ms","hash":4282801858,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":30441472,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-15T05:27:22.342559Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4282801858,"revision":13004,"compact-revision":10960} {"level":"info","ts":"2026-07-15T05:31:09.403101Z","caller":"traceutil/trace.go:171","msg":"trace[5349332] linearizableReadLoop","detail":"{readStateIndex:17792; appliedIndex:17791; }","duration":"102.120225ms","start":"2026-07-15T05:31:09.300949Z","end":"2026-07-15T05:31:09.403069Z","steps":["trace[5349332] 'read index received' (duration: 101.964852ms)","trace[5349332] 'applied index is now lower than readState.Index' (duration: 154.163µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:31:09.403203Z","caller":"traceutil/trace.go:171","msg":"trace[1089231524] transaction","detail":"{read_only:false; response_revision:17209; number_of_response:1; }","duration":"102.266848ms","start":"2026-07-15T05:31:09.300874Z","end":"2026-07-15T05:31:09.403141Z","steps":["trace[1089231524] 'process raft request' (duration: 101.990392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:31:09.403348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.36651ms","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-07-15T05:31:09.403389Z","caller":"traceutil/trace.go:171","msg":"trace[1009500826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17209; }","duration":"102.475611ms","start":"2026-07-15T05:31:09.300902Z","end":"2026-07-15T05:31:09.403378Z","steps":["trace[1009500826] 'agreement among raft nodes before linearized reading' (duration: 102.286938ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:31:09.647353Z","caller":"traceutil/trace.go:171","msg":"trace[2045933317] transaction","detail":"{read_only:false; response_revision:17212; number_of_response:1; }","duration":"240.305318ms","start":"2026-07-15T05:31:09.407016Z","end":"2026-07-15T05:31:09.647321Z","steps":["trace[2045933317] 'process raft request' (duration: 240.060823ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:31:09.647629Z","caller":"traceutil/trace.go:171","msg":"trace[1835217592] transaction","detail":"{read_only:false; response_revision:17213; number_of_response:1; }","duration":"237.40814ms","start":"2026-07-15T05:31:09.41013Z","end":"2026-07-15T05:31:09.647538Z","steps":["trace[1835217592] 'process raft request' (duration: 237.143654ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:32:22.266484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15385} {"level":"info","ts":"2026-07-15T05:32:22.363866Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15385,"took":"93.881874ms","hash":1104835047,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":33832960,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-15T05:32:22.363931Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1104835047,"revision":15385,"compact-revision":13004} {"level":"info","ts":"2026-07-15T05:35:16.548916Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T05:35:16.555711Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-15T05:35:16.555808Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-15T05:36:28.722947Z","caller":"traceutil/trace.go:171","msg":"trace[396998621] linearizableReadLoop","detail":"{readStateIndex:20418; appliedIndex:20416; }","duration":"116.169062ms","start":"2026-07-15T05:36:28.606754Z","end":"2026-07-15T05:36:28.722923Z","steps":["trace[396998621] 'read index received' (duration: 31.343888ms)","trace[396998621] 'applied index is now lower than readState.Index' (duration: 84.823924ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:36:28.723223Z","caller":"traceutil/trace.go:171","msg":"trace[1198193085] transaction","detail":"{read_only:false; response_revision:19752; number_of_response:1; }","duration":"120.280155ms","start":"2026-07-15T05:36:28.602906Z","end":"2026-07-15T05:36:28.723186Z","steps":["trace[1198193085] 'process raft request' (duration: 98.092514ms)","trace[1198193085] 'compare' (duration: 21.744342ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:36:28.723355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.493518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-15T05:36:28.723461Z","caller":"traceutil/trace.go:171","msg":"trace[1711033645] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19752; }","duration":"116.672571ms","start":"2026-07-15T05:36:28.60672Z","end":"2026-07-15T05:36:28.723392Z","steps":["trace[1711033645] 'agreement among raft nodes before linearized reading' (duration: 116.307254ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:36:28.806618Z","caller":"traceutil/trace.go:171","msg":"trace[1535690875] transaction","detail":"{read_only:false; response_revision:19753; number_of_response:1; }","duration":"148.736613ms","start":"2026-07-15T05:36:28.657853Z","end":"2026-07-15T05:36:28.80659Z","steps":["trace[1535690875] 'process raft request' (duration: 148.57132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:36:29.136449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.334775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"warn","ts":"2026-07-15T05:36:29.13648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.633263ms","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-07-15T05:36:29.13654Z","caller":"traceutil/trace.go:171","msg":"trace[492585836] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:19754; }","duration":"306.477479ms","start":"2026-07-15T05:36:28.830043Z","end":"2026-07-15T05:36:29.136521Z","steps":["trace[492585836] 'range keys from in-memory index tree' (duration: 306.122171ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:36:29.136557Z","caller":"traceutil/trace.go:171","msg":"trace[1331221773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19754; }","duration":"311.745846ms","start":"2026-07-15T05:36:28.824784Z","end":"2026-07-15T05:36:29.13653Z","steps":["trace[1331221773] 'range keys from in-memory index tree' (duration: 311.421639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:36:29.136595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:36:28.824766Z","time spent":"311.820677ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-15T05:36:29.136587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:36:28.830024Z","time spent":"306.5532ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-07-15T05:36:29.136504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.873499ms","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-07-15T05:36:29.136686Z","caller":"traceutil/trace.go:171","msg":"trace[1312698158] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:19754; }","duration":"256.133096ms","start":"2026-07-15T05:36:28.880533Z","end":"2026-07-15T05:36:29.136666Z","steps":["trace[1312698158] 'range keys from in-memory index tree' (duration: 255.720647ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:36:29.321989Z","caller":"traceutil/trace.go:171","msg":"trace[1954447820] transaction","detail":"{read_only:false; response_revision:19755; number_of_response:1; }","duration":"181.676533ms","start":"2026-07-15T05:36:29.14028Z","end":"2026-07-15T05:36:29.321957Z","steps":["trace[1954447820] 'process raft request' (duration: 181.473258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:36:29.595978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.564564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:36:29.596099Z","caller":"traceutil/trace.go:171","msg":"trace[1018199543] linearizableReadLoop","detail":"{readStateIndex:20422; appliedIndex:20420; }","duration":"453.259991ms","start":"2026-07-15T05:36:29.142813Z","end":"2026-07-15T05:36:29.596073Z","steps":["trace[1018199543] 'read index received' (duration: 178.974287ms)","trace[1018199543] 'applied index is now lower than readState.Index' (duration: 274.284134ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:36:29.596469Z","caller":"traceutil/trace.go:171","msg":"trace[800109419] transaction","detail":"{read_only:false; response_revision:19756; number_of_response:1; }","duration":"455.320634ms","start":"2026-07-15T05:36:29.141115Z","end":"2026-07-15T05:36:29.596436Z","steps":["trace[800109419] 'process raft request' (duration: 321.208508ms)","trace[800109419] 'compare' (duration: 133.281708ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:36:29.59653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.551809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-15T05:36:29.596521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.6709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-07-15T05:36:29.596592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:36:29.141095Z","time spent":"455.437397ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:36:29.59664Z","caller":"traceutil/trace.go:171","msg":"trace[454746115] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19756; }","duration":"453.825273ms","start":"2026-07-15T05:36:29.14279Z","end":"2026-07-15T05:36:29.596616Z","steps":["trace[454746115] 'agreement among raft nodes before linearized reading' (duration: 453.477216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:36:29.596699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:36:29.142763Z","time spent":"453.925935ms","remote":"127.0.0.1:46510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-15T05:36:29.596571Z","caller":"traceutil/trace.go:171","msg":"trace[231613065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19756; }","duration":"428.618371ms","start":"2026-07-15T05:36:29.167939Z","end":"2026-07-15T05:36:29.596558Z","steps":["trace[231613065] 'agreement among raft nodes before linearized reading' (duration: 428.456628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:36:29.596734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.201652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-15T05:36:29.596772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:36:29.167919Z","time spent":"428.835625ms","remote":"127.0.0.1:46588","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":"info","ts":"2026-07-15T05:36:29.596819Z","caller":"traceutil/trace.go:171","msg":"trace[1763955720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19756; }","duration":"362.361205ms","start":"2026-07-15T05:36:29.234438Z","end":"2026-07-15T05:36:29.596799Z","steps":["trace[1763955720] 'agreement among raft nodes before linearized reading' (duration: 362.09756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:36:29.596861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:36:29.234416Z","time spent":"362.437296ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-15T05:36:29.782204Z","caller":"traceutil/trace.go:171","msg":"trace[1757634772] linearizableReadLoop","detail":"{readStateIndex:20424; appliedIndex:20422; }","duration":"177.456576ms","start":"2026-07-15T05:36:29.604708Z","end":"2026-07-15T05:36:29.782165Z","steps":["trace[1757634772] 'read index received' (duration: 44.121035ms)","trace[1757634772] 'applied index is now lower than readState.Index' (duration: 133.33392ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:36:29.782355Z","caller":"traceutil/trace.go:171","msg":"trace[341965586] transaction","detail":"{read_only:false; response_revision:19758; number_of_response:1; }","duration":"180.288553ms","start":"2026-07-15T05:36:29.602048Z","end":"2026-07-15T05:36:29.782337Z","steps":["trace[341965586] 'process raft request' (duration: 167.150556ms)","trace[341965586] 'compare' (duration: 12.670808ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:36:29.782563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.833033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-15T05:36:29.782651Z","caller":"traceutil/trace.go:171","msg":"trace[1382594157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19758; }","duration":"177.943366ms","start":"2026-07-15T05:36:29.604687Z","end":"2026-07-15T05:36:29.782631Z","steps":["trace[1382594157] 'agreement among raft nodes before linearized reading' (duration: 177.733002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:36:29.782617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.650099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-07-15T05:36:29.782929Z","caller":"traceutil/trace.go:171","msg":"trace[1335995089] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:19758; }","duration":"172.984525ms","start":"2026-07-15T05:36:29.609916Z","end":"2026-07-15T05:36:29.782901Z","steps":["trace[1335995089] 'agreement among raft nodes before linearized reading' (duration: 172.526916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:36:29.782732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.681696ms","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-07-15T05:36:29.783142Z","caller":"traceutil/trace.go:171","msg":"trace[1673282834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19758; }","duration":"102.101104ms","start":"2026-07-15T05:36:29.681009Z","end":"2026-07-15T05:36:29.78311Z","steps":["trace[1673282834] 'agreement among raft nodes before linearized reading' (duration: 101.584104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:36:30.039952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.971306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:36:30.040285Z","caller":"traceutil/trace.go:171","msg":"trace[2102544074] transaction","detail":"{read_only:false; response_revision:19759; number_of_response:1; }","duration":"253.153865ms","start":"2026-07-15T05:36:29.78706Z","end":"2026-07-15T05:36:30.040213Z","steps":["trace[2102544074] 'process raft request' (duration: 107.811051ms)","trace[2102544074] 'compare' (duration: 144.745342ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:36:30.040523Z","caller":"traceutil/trace.go:171","msg":"trace[1998019426] transaction","detail":"{read_only:false; response_revision:19760; number_of_response:1; }","duration":"252.409069ms","start":"2026-07-15T05:36:29.788084Z","end":"2026-07-15T05:36:30.040493Z","steps":["trace[1998019426] 'process raft request' (duration: 251.988071ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:37:22.27253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17990} {"level":"info","ts":"2026-07-15T05:37:22.384037Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17990,"took":"107.246229ms","hash":3744216255,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":30388224,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-15T05:37:22.384092Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3744216255,"revision":17990,"compact-revision":15385} {"level":"info","ts":"2026-07-15T05:40:03.791907Z","caller":"traceutil/trace.go:171","msg":"trace[1643206223] transaction","detail":"{read_only:false; response_revision:21975; number_of_response:1; }","duration":"128.000016ms","start":"2026-07-15T05:40:03.663883Z","end":"2026-07-15T05:40:03.791883Z","steps":["trace[1643206223] 'process raft request' (duration: 127.763881ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:40:03.91795Z","caller":"traceutil/trace.go:171","msg":"trace[1331547737] transaction","detail":"{read_only:false; response_revision:21976; number_of_response:1; }","duration":"240.244411ms","start":"2026-07-15T05:40:03.67767Z","end":"2026-07-15T05:40:03.917915Z","steps":["trace[1331547737] 'process raft request' (duration: 211.346342ms)","trace[1331547737] 'compare' (duration: 28.656054ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:40:03.917917Z","caller":"traceutil/trace.go:171","msg":"trace[2130786945] linearizableReadLoop","detail":"{readStateIndex:22712; appliedIndex:22710; }","duration":"229.635004ms","start":"2026-07-15T05:40:03.688208Z","end":"2026-07-15T05:40:03.917843Z","steps":["trace[2130786945] 'read index received' (duration: 103.394585ms)","trace[2130786945] 'applied index is now lower than readState.Index' (duration: 126.238919ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:40:03.918262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.036182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/orc-system/orc-controller-manager-6cb597b5d4-jvlt9\" ","response":"range_response_count:1 size:4230"} {"level":"info","ts":"2026-07-15T05:40:03.918322Z","caller":"traceutil/trace.go:171","msg":"trace[2003236300] range","detail":"{range_begin:/registry/pods/orc-system/orc-controller-manager-6cb597b5d4-jvlt9; range_end:; response_count:1; response_revision:21976; }","duration":"230.130454ms","start":"2026-07-15T05:40:03.688176Z","end":"2026-07-15T05:40:03.918307Z","steps":["trace[2003236300] 'agreement among raft nodes before linearized reading' (duration: 229.902279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:40:03.918348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.52098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/machines.cluster.x-k8s.io\" ","response":"range_response_count:1 size:58260"} {"level":"info","ts":"2026-07-15T05:40:03.918498Z","caller":"traceutil/trace.go:171","msg":"trace[2118801812] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/machines.cluster.x-k8s.io; range_end:; response_count:1; response_revision:21976; }","duration":"111.736575ms","start":"2026-07-15T05:40:03.806714Z","end":"2026-07-15T05:40:03.918451Z","steps":["trace[2118801812] 'agreement among raft nodes before linearized reading' (duration: 111.394768ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:41:35.290889Z","caller":"traceutil/trace.go:171","msg":"trace[1233260711] transaction","detail":"{read_only:false; response_revision:23029; number_of_response:1; }","duration":"134.903076ms","start":"2026-07-15T05:41:35.155959Z","end":"2026-07-15T05:41:35.290862Z","steps":["trace[1233260711] 'process raft request' (duration: 134.675322ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:42:22.279099Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20294} {"level":"info","ts":"2026-07-15T05:42:22.395676Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20294,"took":"113.449698ms","hash":1799761688,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":44183552,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-15T05:42:22.395728Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1799761688,"revision":20294,"compact-revision":17990} {"level":"info","ts":"2026-07-15T05:43:27.741412Z","caller":"traceutil/trace.go:171","msg":"trace[172963468] linearizableReadLoop","detail":"{readStateIndex:24927; appliedIndex:24926; }","duration":"149.162176ms","start":"2026-07-15T05:43:27.59223Z","end":"2026-07-15T05:43:27.741392Z","steps":["trace[172963468] 'read index received' (duration: 149.111895ms)","trace[172963468] 'applied index is now lower than readState.Index' (duration: 48.951µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:27.741505Z","caller":"traceutil/trace.go:171","msg":"trace[1036989020] transaction","detail":"{read_only:false; response_revision:24136; number_of_response:1; }","duration":"249.840593ms","start":"2026-07-15T05:43:27.491628Z","end":"2026-07-15T05:43:27.741469Z","steps":["trace[1036989020] 'process raft request' (duration: 249.623138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:27.741618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.36718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-15T05:43:27.741678Z","caller":"traceutil/trace.go:171","msg":"trace[2142507780] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24136; }","duration":"149.465433ms","start":"2026-07-15T05:43:27.592198Z","end":"2026-07-15T05:43:27.741663Z","steps":["trace[2142507780] 'agreement among raft nodes before linearized reading' (duration: 149.311949ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:28.047849Z","caller":"traceutil/trace.go:171","msg":"trace[1900937478] transaction","detail":"{read_only:false; response_revision:24141; number_of_response:1; }","duration":"150.754109ms","start":"2026-07-15T05:43:27.897067Z","end":"2026-07-15T05:43:28.047821Z","steps":["trace[1900937478] 'process raft request' (duration: 139.265985ms)","trace[1900937478] 'compare' (duration: 11.368802ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:32.334766Z","caller":"traceutil/trace.go:171","msg":"trace[1900078375] transaction","detail":"{read_only:false; response_revision:24177; number_of_response:1; }","duration":"258.651463ms","start":"2026-07-15T05:43:32.076087Z","end":"2026-07-15T05:43:32.334738Z","steps":["trace[1900078375] 'process raft request' (duration: 189.01669ms)","trace[1900078375] 'compare' (duration: 69.4802ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:32.33484Z","caller":"traceutil/trace.go:171","msg":"trace[1253142287] linearizableReadLoop","detail":"{readStateIndex:24969; appliedIndex:24968; }","duration":"137.09877ms","start":"2026-07-15T05:43:32.197705Z","end":"2026-07-15T05:43:32.334803Z","steps":["trace[1253142287] 'read index received' (duration: 67.411037ms)","trace[1253142287] 'applied index is now lower than readState.Index' (duration: 69.685243ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:32.335094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.368946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-15T05:43:32.335167Z","caller":"traceutil/trace.go:171","msg":"trace[1855043468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24178; }","duration":"137.480148ms","start":"2026-07-15T05:43:32.19767Z","end":"2026-07-15T05:43:32.33515Z","steps":["trace[1855043468] 'agreement among raft nodes before linearized reading' (duration: 137.310565ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:32.460277Z","caller":"traceutil/trace.go:171","msg":"trace[657702722] linearizableReadLoop","detail":"{readStateIndex:24971; appliedIndex:24970; }","duration":"125.220048ms","start":"2026-07-15T05:43:32.335032Z","end":"2026-07-15T05:43:32.460252Z","steps":["trace[657702722] 'read index received' (duration: 121.871529ms)","trace[657702722] 'applied index is now lower than readState.Index' (duration: 3.347479ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:32.460484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.221515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-15T05:43:32.460529Z","caller":"traceutil/trace.go:171","msg":"trace[187704377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24179; }","duration":"259.308067ms","start":"2026-07-15T05:43:32.201206Z","end":"2026-07-15T05:43:32.460514Z","steps":["trace[187704377] 'agreement among raft nodes before linearized reading' (duration: 259.144074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:32.460631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.692498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-15T05:43:32.460709Z","caller":"traceutil/trace.go:171","msg":"trace[1185479963] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24179; }","duration":"150.81088ms","start":"2026-07-15T05:43:32.309878Z","end":"2026-07-15T05:43:32.460689Z","steps":["trace[1185479963] 'agreement among raft nodes before linearized reading' (duration: 150.590346ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:32.461155Z","caller":"traceutil/trace.go:171","msg":"trace[1718973362] transaction","detail":"{read_only:false; response_revision:24179; number_of_response:1; }","duration":"179.531477ms","start":"2026-07-15T05:43:32.281605Z","end":"2026-07-15T05:43:32.461136Z","steps":["trace[1718973362] 'process raft request' (duration: 175.203629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:32.774321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.391273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:43:32.774663Z","caller":"traceutil/trace.go:171","msg":"trace[14195860] transaction","detail":"{read_only:false; response_revision:24180; number_of_response:1; }","duration":"435.023975ms","start":"2026-07-15T05:43:32.33961Z","end":"2026-07-15T05:43:32.774634Z","steps":["trace[14195860] 'process raft request' (duration: 282.217313ms)","trace[14195860] 'compare' (duration: 152.017536ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:32.774695Z","caller":"traceutil/trace.go:171","msg":"trace[321960585] transaction","detail":"{read_only:false; response_revision:24181; number_of_response:1; }","duration":"308.820218ms","start":"2026-07-15T05:43:32.465856Z","end":"2026-07-15T05:43:32.774676Z","steps":["trace[321960585] 'process raft request' (duration: 308.599313ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:32.774695Z","caller":"traceutil/trace.go:171","msg":"trace[1590913928] linearizableReadLoop","detail":"{readStateIndex:24972; appliedIndex:24971; }","duration":"308.827538ms","start":"2026-07-15T05:43:32.465818Z","end":"2026-07-15T05:43:32.774645Z","steps":["trace[1590913928] 'read index received' (duration: 156.021346ms)","trace[1590913928] 'applied index is now lower than readState.Index' (duration: 152.801062ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:32.774819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:32.465841Z","time spent":"308.903419ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:43:32.77484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:32.339582Z","time spent":"435.143578ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:43:32.774916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.064813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:43:32.774938Z","caller":"traceutil/trace.go:171","msg":"trace[1832703389] transaction","detail":"{read_only:false; response_revision:24182; number_of_response:1; }","duration":"309.009821ms","start":"2026-07-15T05:43:32.465897Z","end":"2026-07-15T05:43:32.774907Z","steps":["trace[1832703389] 'process raft request' (duration: 308.737416ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:32.774976Z","caller":"traceutil/trace.go:171","msg":"trace[415889383] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:24182; }","duration":"309.169344ms","start":"2026-07-15T05:43:32.465789Z","end":"2026-07-15T05:43:32.774959Z","steps":["trace[415889383] 'agreement among raft nodes before linearized reading' (duration: 308.96514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:32.775033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:32.465777Z","time spent":"309.246147ms","remote":"127.0.0.1:51264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"info","ts":"2026-07-15T05:43:32.775088Z","caller":"traceutil/trace.go:171","msg":"trace[1860094545] transaction","detail":"{read_only:false; response_revision:24183; number_of_response:1; }","duration":"164.49987ms","start":"2026-07-15T05:43:32.610554Z","end":"2026-07-15T05:43:32.775054Z","steps":["trace[1860094545] 'process raft request' (duration: 164.301726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:32.775128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:32.465881Z","time spent":"309.138773ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:43:32.775146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.054925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:43:32.775239Z","caller":"traceutil/trace.go:171","msg":"trace[1577881107] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:24184; }","duration":"153.189158ms","start":"2026-07-15T05:43:32.622025Z","end":"2026-07-15T05:43:32.775214Z","steps":["trace[1577881107] 'agreement among raft nodes before linearized reading' (duration: 153.014935ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:33.109129Z","caller":"traceutil/trace.go:171","msg":"trace[907740457] linearizableReadLoop","detail":"{readStateIndex:24977; appliedIndex:24976; }","duration":"120.522162ms","start":"2026-07-15T05:43:32.988583Z","end":"2026-07-15T05:43:33.109105Z","steps":["trace[907740457] 'read index received' (duration: 120.44232ms)","trace[907740457] 'applied index is now lower than readState.Index' (duration: 78.652µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:33.109437Z","caller":"traceutil/trace.go:171","msg":"trace[855591478] transaction","detail":"{read_only:false; response_revision:24185; number_of_response:1; }","duration":"210.598782ms","start":"2026-07-15T05:43:32.898799Z","end":"2026-07-15T05:43:33.109398Z","steps":["trace[855591478] 'process raft request' (duration: 210.153382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:33.109495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.88023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:43:33.10957Z","caller":"traceutil/trace.go:171","msg":"trace[1638950566] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:24185; }","duration":"121.011623ms","start":"2026-07-15T05:43:32.988541Z","end":"2026-07-15T05:43:33.109552Z","steps":["trace[1638950566] 'agreement among raft nodes before linearized reading' (duration: 120.793819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:33.461092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.954242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:43:33.461296Z","caller":"traceutil/trace.go:171","msg":"trace[248722892] linearizableReadLoop","detail":"{readStateIndex:24978; appliedIndex:24977; }","duration":"352.054081ms","start":"2026-07-15T05:43:33.109191Z","end":"2026-07-15T05:43:33.461245Z","steps":["trace[248722892] 'read index received' (duration: 86.808253ms)","trace[248722892] 'applied index is now lower than readState.Index' (duration: 265.242097ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:33.4613Z","caller":"traceutil/trace.go:171","msg":"trace[471177520] transaction","detail":"{read_only:false; response_revision:24186; number_of_response:1; }","duration":"434.303211ms","start":"2026-07-15T05:43:33.026952Z","end":"2026-07-15T05:43:33.461256Z","steps":["trace[471177520] 'process raft request' (duration: 169.101154ms)","trace[471177520] 'compare' (duration: 264.772608ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:33.461452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:33.026935Z","time spent":"434.481194ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:43:33.461473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.039931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:33.461555Z","caller":"traceutil/trace.go:171","msg":"trace[1543643380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24186; }","duration":"472.150553ms","start":"2026-07-15T05:43:32.989386Z","end":"2026-07-15T05:43:33.461536Z","steps":["trace[1543643380] 'agreement among raft nodes before linearized reading' (duration: 471.95989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:33.461592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:32.98937Z","time spent":"472.215244ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T05:43:33.462432Z","caller":"traceutil/trace.go:171","msg":"trace[1718870259] transaction","detail":"{read_only:false; response_revision:24187; number_of_response:1; }","duration":"156.232121ms","start":"2026-07-15T05:43:33.306181Z","end":"2026-07-15T05:43:33.462414Z","steps":["trace[1718870259] 'process raft request' (duration: 155.809771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:33.76379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.012039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:33.763876Z","caller":"traceutil/trace.go:171","msg":"trace[1212752747] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24187; }","duration":"164.171312ms","start":"2026-07-15T05:43:33.599684Z","end":"2026-07-15T05:43:33.763855Z","steps":["trace[1212752747] 'count revisions from in-memory index tree' (duration: 163.913458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:33.763889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.092645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-07-15T05:43:33.76397Z","caller":"traceutil/trace.go:171","msg":"trace[1150957670] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24187; }","duration":"197.196227ms","start":"2026-07-15T05:43:33.566753Z","end":"2026-07-15T05:43:33.763949Z","steps":["trace[1150957670] 'range keys from in-memory index tree' (duration: 196.941773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:34.127537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.177675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-07-15T05:43:34.12761Z","caller":"traceutil/trace.go:171","msg":"trace[1760782660] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24189; }","duration":"215.258167ms","start":"2026-07-15T05:43:33.912335Z","end":"2026-07-15T05:43:34.127593Z","steps":["trace[1760782660] 'range keys from in-memory index tree' (duration: 215.037033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:34.127544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.67654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:43:34.127757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.176056ms","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-07-15T05:43:34.127777Z","caller":"traceutil/trace.go:171","msg":"trace[330016045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24189; }","duration":"130.964975ms","start":"2026-07-15T05:43:33.996795Z","end":"2026-07-15T05:43:34.12776Z","steps":["trace[330016045] 'range keys from in-memory index tree' (duration: 130.594337ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:34.127803Z","caller":"traceutil/trace.go:171","msg":"trace[1091669366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24189; }","duration":"194.258848ms","start":"2026-07-15T05:43:33.933529Z","end":"2026-07-15T05:43:34.127788Z","steps":["trace[1091669366] 'range keys from in-memory index tree' (duration: 194.003533ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:34.256772Z","caller":"traceutil/trace.go:171","msg":"trace[1265323555] transaction","detail":"{read_only:false; response_revision:24190; number_of_response:1; }","duration":"125.303ms","start":"2026-07-15T05:43:34.131452Z","end":"2026-07-15T05:43:34.256755Z","steps":["trace[1265323555] 'process raft request' (duration: 124.610876ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:34.559706Z","caller":"traceutil/trace.go:171","msg":"trace[1147434132] transaction","detail":"{read_only:false; response_revision:24191; number_of_response:1; }","duration":"298.743371ms","start":"2026-07-15T05:43:34.260936Z","end":"2026-07-15T05:43:34.559679Z","steps":["trace[1147434132] 'process raft request' (duration: 283.311536ms)","trace[1147434132] 'compare' (duration: 15.327903ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:34.712173Z","caller":"traceutil/trace.go:171","msg":"trace[1615429357] transaction","detail":"{read_only:false; response_revision:24192; number_of_response:1; }","duration":"372.07255ms","start":"2026-07-15T05:43:34.340076Z","end":"2026-07-15T05:43:34.712148Z","steps":["trace[1615429357] 'process raft request' (duration: 345.321123ms)","trace[1615429357] 'compare' (duration: 26.427271ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:34.712336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:34.34002Z","time spent":"372.225343ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:34.71241Z","caller":"traceutil/trace.go:171","msg":"trace[176566101] transaction","detail":"{read_only:false; response_revision:24193; number_of_response:1; }","duration":"371.893527ms","start":"2026-07-15T05:43:34.340491Z","end":"2026-07-15T05:43:34.712384Z","steps":["trace[176566101] 'process raft request' (duration: 371.57476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:34.712541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:34.340467Z","time spent":"372.001998ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:34.712847Z","caller":"traceutil/trace.go:171","msg":"trace[1576500859] linearizableReadLoop","detail":"{readStateIndex:24986; appliedIndex:24982; }","duration":"229.949106ms","start":"2026-07-15T05:43:34.482872Z","end":"2026-07-15T05:43:34.712821Z","steps":["trace[1576500859] 'read index received' (duration: 61.389604ms)","trace[1576500859] 'applied index is now lower than readState.Index' (duration: 168.557412ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:34.712873Z","caller":"traceutil/trace.go:171","msg":"trace[464874900] transaction","detail":"{read_only:false; response_revision:24194; number_of_response:1; }","duration":"247.23092ms","start":"2026-07-15T05:43:34.465604Z","end":"2026-07-15T05:43:34.712834Z","steps":["trace[464874900] 'process raft request' (duration: 246.702639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:34.713019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.12334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:34.713056Z","caller":"traceutil/trace.go:171","msg":"trace[1906885692] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24194; }","duration":"230.183941ms","start":"2026-07-15T05:43:34.48286Z","end":"2026-07-15T05:43:34.713044Z","steps":["trace[1906885692] 'agreement among raft nodes before linearized reading' (duration: 230.079549ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:35.106575Z","caller":"traceutil/trace.go:171","msg":"trace[1135477722] transaction","detail":"{read_only:false; response_revision:24196; number_of_response:1; }","duration":"323.677241ms","start":"2026-07-15T05:43:34.782872Z","end":"2026-07-15T05:43:35.106549Z","steps":["trace[1135477722] 'process raft request' (duration: 302.984968ms)","trace[1135477722] 'compare' (duration: 20.197933ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:35.106719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:34.782859Z","time spent":"323.791793ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:35.106841Z","caller":"traceutil/trace.go:171","msg":"trace[386659165] transaction","detail":"{read_only:false; response_revision:24197; number_of_response:1; }","duration":"323.917706ms","start":"2026-07-15T05:43:34.782889Z","end":"2026-07-15T05:43:35.106806Z","steps":["trace[386659165] 'process raft request' (duration: 323.577478ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:35.107051Z","caller":"traceutil/trace.go:171","msg":"trace[1468182063] linearizableReadLoop","detail":"{readStateIndex:24990; appliedIndex:24987; }","duration":"117.604272ms","start":"2026-07-15T05:43:34.989416Z","end":"2026-07-15T05:43:35.107021Z","steps":["trace[1468182063] 'read index received' (duration: 96.189574ms)","trace[1468182063] 'applied index is now lower than readState.Index' (duration: 21.412888ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:35.10712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:34.78288Z","time spent":"324.06755ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:35.107183Z","caller":"traceutil/trace.go:171","msg":"trace[377159266] transaction","detail":"{read_only:false; response_revision:24198; number_of_response:1; }","duration":"323.977438ms","start":"2026-07-15T05:43:34.783184Z","end":"2026-07-15T05:43:35.107162Z","steps":["trace[377159266] 'process raft request' (duration: 323.497448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:35.107227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.797476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:43:35.10729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:34.783155Z","time spent":"324.08104ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:35.107311Z","caller":"traceutil/trace.go:171","msg":"trace[1119299391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24198; }","duration":"117.925579ms","start":"2026-07-15T05:43:34.989368Z","end":"2026-07-15T05:43:35.107293Z","steps":["trace[1119299391] 'agreement among raft nodes before linearized reading' (duration: 117.782976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:35.863259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.174677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:43:35.863913Z","caller":"traceutil/trace.go:171","msg":"trace[1763572592] linearizableReadLoop","detail":"{readStateIndex:24992; appliedIndex:24990; }","duration":"377.393648ms","start":"2026-07-15T05:43:35.486492Z","end":"2026-07-15T05:43:35.863886Z","steps":["trace[1763572592] 'read index received' (duration: 63.437405ms)","trace[1763572592] 'applied index is now lower than readState.Index' (duration: 313.953113ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:35.864004Z","caller":"traceutil/trace.go:171","msg":"trace[220272758] transaction","detail":"{read_only:false; response_revision:24200; number_of_response:1; }","duration":"396.735364ms","start":"2026-07-15T05:43:35.467198Z","end":"2026-07-15T05:43:35.863934Z","steps":["trace[220272758] 'process raft request' (duration: 222.795151ms)","trace[220272758] 'compare' (duration: 172.734388ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:35.864153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:35.467176Z","time spent":"396.901987ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:43:35.864164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.642674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-15T05:43:35.864256Z","caller":"traceutil/trace.go:171","msg":"trace[1759381892] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:24201; }","duration":"377.745286ms","start":"2026-07-15T05:43:35.486487Z","end":"2026-07-15T05:43:35.864232Z","steps":["trace[1759381892] 'agreement among raft nodes before linearized reading' (duration: 377.478981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:35.864329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:35.486412Z","time spent":"377.903399ms","remote":"127.0.0.1:46344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"info","ts":"2026-07-15T05:43:36.17594Z","caller":"traceutil/trace.go:171","msg":"trace[143527344] linearizableReadLoop","detail":"{readStateIndex:24995; appliedIndex:24993; }","duration":"299.439616ms","start":"2026-07-15T05:43:35.87645Z","end":"2026-07-15T05:43:36.17589Z","steps":["trace[143527344] 'read index received' (duration: 32.595936ms)","trace[143527344] 'applied index is now lower than readState.Index' (duration: 266.84253ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:36.175927Z","caller":"traceutil/trace.go:171","msg":"trace[714162313] transaction","detail":"{read_only:false; response_revision:24202; number_of_response:1; }","duration":"307.845607ms","start":"2026-07-15T05:43:35.868054Z","end":"2026-07-15T05:43:36.175899Z","steps":["trace[714162313] 'process raft request' (duration: 300.952917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:36.17605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.586539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:36.17607Z","caller":"traceutil/trace.go:171","msg":"trace[1676265957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24202; }","duration":"299.645001ms","start":"2026-07-15T05:43:35.876419Z","end":"2026-07-15T05:43:36.176064Z","steps":["trace[1676265957] 'agreement among raft nodes before linearized reading' (duration: 299.5846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:36.176071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:35.868039Z","time spent":"307.96267ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:36.18648Z","caller":"traceutil/trace.go:171","msg":"trace[662321481] transaction","detail":"{read_only:false; response_revision:24203; number_of_response:1; }","duration":"270.054675ms","start":"2026-07-15T05:43:35.916383Z","end":"2026-07-15T05:43:36.186437Z","steps":["trace[662321481] 'process raft request' (duration: 269.872152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:36.1865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.143526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:36.186568Z","caller":"traceutil/trace.go:171","msg":"trace[926120357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24203; }","duration":"196.247869ms","start":"2026-07-15T05:43:35.990305Z","end":"2026-07-15T05:43:36.186553Z","steps":["trace[926120357] 'agreement among raft nodes before linearized reading' (duration: 196.142056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:36.18655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.893851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:43:36.186629Z","caller":"traceutil/trace.go:171","msg":"trace[1809541277] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:24203; }","duration":"241.023123ms","start":"2026-07-15T05:43:35.945587Z","end":"2026-07-15T05:43:36.18661Z","steps":["trace[1809541277] 'agreement among raft nodes before linearized reading' (duration: 240.824679ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:36.379842Z","caller":"traceutil/trace.go:171","msg":"trace[545445575] transaction","detail":"{read_only:false; response_revision:24204; number_of_response:1; }","duration":"113.53394ms","start":"2026-07-15T05:43:36.26624Z","end":"2026-07-15T05:43:36.379774Z","steps":["trace[545445575] 'process raft request' (duration: 112.046769ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:36.670085Z","caller":"traceutil/trace.go:171","msg":"trace[118349963] transaction","detail":"{read_only:false; response_revision:24205; number_of_response:1; }","duration":"101.857801ms","start":"2026-07-15T05:43:36.568204Z","end":"2026-07-15T05:43:36.670062Z","steps":["trace[118349963] 'process raft request' (duration: 101.723059ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:37.08146Z","caller":"traceutil/trace.go:171","msg":"trace[233211617] transaction","detail":"{read_only:false; response_revision:24208; number_of_response:1; }","duration":"363.556875ms","start":"2026-07-15T05:43:36.717867Z","end":"2026-07-15T05:43:37.081424Z","steps":["trace[233211617] 'process raft request' (duration: 363.450673ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:37.081477Z","caller":"traceutil/trace.go:171","msg":"trace[447370591] transaction","detail":"{read_only:false; response_revision:24207; number_of_response:1; }","duration":"363.615116ms","start":"2026-07-15T05:43:36.71782Z","end":"2026-07-15T05:43:37.081435Z","steps":["trace[447370591] 'process raft request' (duration: 282.262924ms)","trace[447370591] 'compare' (duration: 80.813341ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:37.081619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:36.717852Z","time spent":"363.687237ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:43:37.081653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:36.717809Z","time spent":"363.755849ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:37.093732Z","caller":"traceutil/trace.go:171","msg":"trace[346898755] transaction","detail":"{read_only:false; response_revision:24209; number_of_response:1; }","duration":"275.552839ms","start":"2026-07-15T05:43:36.81815Z","end":"2026-07-15T05:43:37.093703Z","steps":["trace[346898755] 'process raft request' (duration: 275.372715ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:37.093886Z","caller":"traceutil/trace.go:171","msg":"trace[1634736419] linearizableReadLoop","detail":"{readStateIndex:25003; appliedIndex:24999; }","duration":"104.3222ms","start":"2026-07-15T05:43:36.989535Z","end":"2026-07-15T05:43:37.093858Z","steps":["trace[1634736419] 'read index received' (duration: 10.505274ms)","trace[1634736419] 'applied index is now lower than readState.Index' (duration: 93.815196ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:37.093908Z","caller":"traceutil/trace.go:171","msg":"trace[952256288] transaction","detail":"{read_only:false; response_revision:24210; number_of_response:1; }","duration":"256.954279ms","start":"2026-07-15T05:43:36.836927Z","end":"2026-07-15T05:43:37.093881Z","steps":["trace[952256288] 'process raft request' (duration: 256.728514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:37.094037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.491444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:37.094075Z","caller":"traceutil/trace.go:171","msg":"trace[1733225564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24210; }","duration":"104.570785ms","start":"2026-07-15T05:43:36.989495Z","end":"2026-07-15T05:43:37.094066Z","steps":["trace[1733225564] 'agreement among raft nodes before linearized reading' (duration: 104.458563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:37.367105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.180011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:43:37.367183Z","caller":"traceutil/trace.go:171","msg":"trace[786917708] transaction","detail":"{read_only:false; response_revision:24211; number_of_response:1; }","duration":"253.418475ms","start":"2026-07-15T05:43:37.113751Z","end":"2026-07-15T05:43:37.36717Z","steps":["trace[786917708] 'process raft request' (duration: 118.107942ms)","trace[786917708] 'compare' (duration: 134.891885ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:37.419482Z","caller":"traceutil/trace.go:171","msg":"trace[566003522] transaction","detail":"{read_only:false; response_revision:24213; number_of_response:1; }","duration":"304.032719ms","start":"2026-07-15T05:43:37.115425Z","end":"2026-07-15T05:43:37.419458Z","steps":["trace[566003522] 'process raft request' (duration: 303.987508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:37.419626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:37.115418Z","time spent":"304.136101ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:37.420032Z","caller":"traceutil/trace.go:171","msg":"trace[861226380] transaction","detail":"{read_only:false; response_revision:24212; number_of_response:1; }","duration":"304.610091ms","start":"2026-07-15T05:43:37.115408Z","end":"2026-07-15T05:43:37.420018Z","steps":["trace[861226380] 'process raft request' (duration: 303.835685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:37.420094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:37.115397Z","time spent":"304.664772ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:38.096319Z","caller":"traceutil/trace.go:171","msg":"trace[1281641798] linearizableReadLoop","detail":"{readStateIndex:25009; appliedIndex:25008; }","duration":"106.72523ms","start":"2026-07-15T05:43:37.989565Z","end":"2026-07-15T05:43:38.09629Z","steps":["trace[1281641798] 'read index received' (duration: 31.131646ms)","trace[1281641798] 'applied index is now lower than readState.Index' (duration: 75.592064ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:38.096448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.871843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:38.096481Z","caller":"traceutil/trace.go:171","msg":"trace[1971282886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24216; }","duration":"106.916624ms","start":"2026-07-15T05:43:37.989554Z","end":"2026-07-15T05:43:38.09647Z","steps":["trace[1971282886] 'agreement among raft nodes before linearized reading' (duration: 106.838462ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:38.096667Z","caller":"traceutil/trace.go:171","msg":"trace[1105071032] transaction","detail":"{read_only:false; response_revision:24216; number_of_response:1; }","duration":"226.946475ms","start":"2026-07-15T05:43:37.869707Z","end":"2026-07-15T05:43:38.096653Z","steps":["trace[1105071032] 'process raft request' (duration: 150.918183ms)","trace[1105071032] 'compare' (duration: 75.491202ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:44.630548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.953282ms","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-07-15T05:43:44.630627Z","caller":"traceutil/trace.go:171","msg":"trace[1047508127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24262; }","duration":"219.082004ms","start":"2026-07-15T05:43:44.411527Z","end":"2026-07-15T05:43:44.630609Z","steps":["trace[1047508127] 'range keys from in-memory index tree' (duration: 218.714607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:45.215886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.362758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:43:45.21613Z","caller":"traceutil/trace.go:171","msg":"trace[378326298] transaction","detail":"{read_only:false; response_revision:24265; number_of_response:1; }","duration":"429.806328ms","start":"2026-07-15T05:43:44.786306Z","end":"2026-07-15T05:43:45.216112Z","steps":["trace[378326298] 'process raft request' (duration: 429.711697ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:45.216459Z","caller":"traceutil/trace.go:171","msg":"trace[1449281774] linearizableReadLoop","detail":"{readStateIndex:25058; appliedIndex:25057; }","duration":"510.489317ms","start":"2026-07-15T05:43:44.705953Z","end":"2026-07-15T05:43:45.216442Z","steps":["trace[1449281774] 'read index received' (duration: 202.492716ms)","trace[1449281774] 'applied index is now lower than readState.Index' (duration: 307.994731ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:45.216599Z","caller":"traceutil/trace.go:171","msg":"trace[1492448922] transaction","detail":"{read_only:false; response_revision:24264; number_of_response:1; }","duration":"581.6734ms","start":"2026-07-15T05:43:44.634807Z","end":"2026-07-15T05:43:45.21648Z","steps":["trace[1492448922] 'process raft request' (duration: 273.628438ms)","trace[1492448922] 'compare' (duration: 306.542542ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:45.216663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.709331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T05:43:45.217111Z","caller":"traceutil/trace.go:171","msg":"trace[599549748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24265; }","duration":"511.197282ms","start":"2026-07-15T05:43:44.705898Z","end":"2026-07-15T05:43:45.217095Z","steps":["trace[599549748] 'agreement among raft nodes before linearized reading' (duration: 510.62946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:45.217151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:44.705876Z","time spent":"511.265553ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T05:43:45.216749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:44.786286Z","time spent":"429.88481ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:43:45.217159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:44.634769Z","time spent":"582.236252ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:45.531379Z","caller":"traceutil/trace.go:171","msg":"trace[1378138732] transaction","detail":"{read_only:false; response_revision:24266; number_of_response:1; }","duration":"422.562341ms","start":"2026-07-15T05:43:45.108795Z","end":"2026-07-15T05:43:45.531357Z","steps":["trace[1378138732] 'process raft request' (duration: 346.330644ms)","trace[1378138732] 'compare' (duration: 76.086024ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:45.531666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.108775Z","time spent":"422.685454ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:45.532012Z","caller":"traceutil/trace.go:171","msg":"trace[403183984] linearizableReadLoop","detail":"{readStateIndex:25062; appliedIndex:25059; }","duration":"315.488684ms","start":"2026-07-15T05:43:45.216499Z","end":"2026-07-15T05:43:45.531987Z","steps":["trace[403183984] 'read index received' (duration: 238.600725ms)","trace[403183984] 'applied index is now lower than readState.Index' (duration: 76.886979ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:45.532024Z","caller":"traceutil/trace.go:171","msg":"trace[1571080515] transaction","detail":"{read_only:false; response_revision:24268; number_of_response:1; }","duration":"408.138896ms","start":"2026-07-15T05:43:45.12385Z","end":"2026-07-15T05:43:45.531989Z","steps":["trace[1571080515] 'process raft request' (duration: 407.83741ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:45.532047Z","caller":"traceutil/trace.go:171","msg":"trace[611772294] transaction","detail":"{read_only:false; response_revision:24267; number_of_response:1; }","duration":"411.384123ms","start":"2026-07-15T05:43:45.12062Z","end":"2026-07-15T05:43:45.532004Z","steps":["trace[611772294] 'process raft request' (duration: 410.698729ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:45.532175Z","caller":"traceutil/trace.go:171","msg":"trace[1759722049] transaction","detail":"{read_only:false; response_revision:24269; number_of_response:1; }","duration":"310.298548ms","start":"2026-07-15T05:43:45.221851Z","end":"2026-07-15T05:43:45.53215Z","steps":["trace[1759722049] 'process raft request' (duration: 310.092264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:45.532182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.123824Z","time spent":"408.2706ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:43:45.532204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.912619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:43:45.532211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.236156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-15T05:43:45.532241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.120604Z","time spent":"411.518805ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:45.532265Z","caller":"traceutil/trace.go:171","msg":"trace[1377468991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24269; }","duration":"541.005371ms","start":"2026-07-15T05:43:44.991241Z","end":"2026-07-15T05:43:45.532246Z","steps":["trace[1377468991] 'agreement among raft nodes before linearized reading' (duration: 540.799146ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:45.53227Z","caller":"traceutil/trace.go:171","msg":"trace[710595542] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:24269; }","duration":"616.325809ms","start":"2026-07-15T05:43:44.915928Z","end":"2026-07-15T05:43:45.532254Z","steps":["trace[710595542] 'agreement among raft nodes before linearized reading' (duration: 616.103914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:45.532278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.221833Z","time spent":"310.39379ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:43:45.532265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.675671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"warn","ts":"2026-07-15T05:43:45.5323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:44.99122Z","time spent":"541.073522ms","remote":"127.0.0.1:46308","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-07-15T05:43:45.532249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.779806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-15T05:43:45.532282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.90863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:45.532351Z","caller":"traceutil/trace.go:171","msg":"trace[513695483] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24269; }","duration":"629.784053ms","start":"2026-07-15T05:43:44.902544Z","end":"2026-07-15T05:43:45.532328Z","steps":["trace[513695483] 'agreement among raft nodes before linearized reading' (duration: 629.513958ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:45.532378Z","caller":"traceutil/trace.go:171","msg":"trace[1412678592] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:24269; }","duration":"635.9735ms","start":"2026-07-15T05:43:44.896375Z","end":"2026-07-15T05:43:45.532349Z","steps":["trace[1412678592] 'agreement among raft nodes before linearized reading' (duration: 635.682954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:45.532392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:44.902537Z","time spent":"629.845834ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-07-15T05:43:45.532436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:44.896354Z","time spent":"636.069032ms","remote":"127.0.0.1:37866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:43:45.532313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:44.915917Z","time spent":"616.38532ms","remote":"127.0.0.1:46582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:43:45.532541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.409873ms","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-07-15T05:43:45.532386Z","caller":"traceutil/trace.go:171","msg":"trace[1361186758] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24269; }","duration":"779.055292ms","start":"2026-07-15T05:43:44.753306Z","end":"2026-07-15T05:43:45.532361Z","steps":["trace[1361186758] 'agreement among raft nodes before linearized reading' (duration: 778.767776ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:45.532599Z","caller":"traceutil/trace.go:171","msg":"trace[396665871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24269; }","duration":"129.506795ms","start":"2026-07-15T05:43:45.403073Z","end":"2026-07-15T05:43:45.53258Z","steps":["trace[396665871] 'agreement among raft nodes before linearized reading' (duration: 129.332872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:45.532636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:44.753288Z","time spent":"779.332518ms","remote":"127.0.0.1:40390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-07-15T05:43:45.53222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.187858ms","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-07-15T05:43:45.532728Z","caller":"traceutil/trace.go:171","msg":"trace[382765534] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24269; }","duration":"246.73496ms","start":"2026-07-15T05:43:45.285968Z","end":"2026-07-15T05:43:45.532703Z","steps":["trace[382765534] 'agreement among raft nodes before linearized reading' (duration: 246.180849ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:46.171124Z","caller":"traceutil/trace.go:171","msg":"trace[1257228425] transaction","detail":"{read_only:false; response_revision:24270; number_of_response:1; }","duration":"633.677423ms","start":"2026-07-15T05:43:45.537412Z","end":"2026-07-15T05:43:46.17109Z","steps":["trace[1257228425] 'process raft request' (duration: 633.338916ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:46.171172Z","caller":"traceutil/trace.go:171","msg":"trace[1026062133] transaction","detail":"{read_only:false; response_revision:24272; number_of_response:1; }","duration":"633.155322ms","start":"2026-07-15T05:43:45.53798Z","end":"2026-07-15T05:43:46.171135Z","steps":["trace[1026062133] 'process raft request' (duration: 633.028909ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:46.171213Z","caller":"traceutil/trace.go:171","msg":"trace[1720471951] transaction","detail":"{read_only:false; response_revision:24273; number_of_response:1; }","duration":"633.161323ms","start":"2026-07-15T05:43:45.53801Z","end":"2026-07-15T05:43:46.171172Z","steps":["trace[1720471951] 'process raft request' (duration: 633.03307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:46.171369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.537391Z","time spent":"633.813945ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:46.171368Z","caller":"traceutil/trace.go:171","msg":"trace[848239383] transaction","detail":"{read_only:false; response_revision:24271; number_of_response:1; }","duration":"633.776065ms","start":"2026-07-15T05:43:45.537564Z","end":"2026-07-15T05:43:46.17134Z","steps":["trace[848239383] 'process raft request' (duration: 633.387037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:46.172063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.537546Z","time spent":"634.444788ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:43:46.17134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.05402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:46.172645Z","caller":"traceutil/trace.go:171","msg":"trace[981265527] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24273; }","duration":"633.385747ms","start":"2026-07-15T05:43:45.539239Z","end":"2026-07-15T05:43:46.172624Z","steps":["trace[981265527] 'agreement among raft nodes before linearized reading' (duration: 631.993818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:46.172706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.539229Z","time spent":"633.459248ms","remote":"127.0.0.1:46518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-07-15T05:43:46.171349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.537966Z","time spent":"633.292295ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:46.171112Z","caller":"traceutil/trace.go:171","msg":"trace[1165108353] linearizableReadLoop","detail":"{readStateIndex:25068; appliedIndex:25063; }","duration":"631.816624ms","start":"2026-07-15T05:43:45.539271Z","end":"2026-07-15T05:43:46.171087Z","steps":["trace[1165108353] 'read index received' (duration: 24.949179ms)","trace[1165108353] 'applied index is now lower than readState.Index' (duration: 606.866455ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:46.171526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.53799Z","time spent":"633.451138ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:43:46.663506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.787946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:43:46.663774Z","caller":"traceutil/trace.go:171","msg":"trace[754571960] linearizableReadLoop","detail":"{readStateIndex:25072; appliedIndex:25068; }","duration":"490.797614ms","start":"2026-07-15T05:43:46.172961Z","end":"2026-07-15T05:43:46.663759Z","steps":["trace[754571960] 'read index received' (duration: 278.717562ms)","trace[754571960] 'applied index is now lower than readState.Index' (duration: 212.078912ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:46.66386Z","caller":"traceutil/trace.go:171","msg":"trace[1745984459] transaction","detail":"{read_only:false; response_revision:24277; number_of_response:1; }","duration":"546.283628ms","start":"2026-07-15T05:43:46.117568Z","end":"2026-07-15T05:43:46.663851Z","steps":["trace[1745984459] 'process raft request' (duration: 546.151925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:46.663922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:46.117537Z","time spent":"546.354779ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:46.664115Z","caller":"traceutil/trace.go:171","msg":"trace[56937850] transaction","detail":"{read_only:false; response_revision:24274; number_of_response:1; }","duration":"1.098686561s","start":"2026-07-15T05:43:45.565412Z","end":"2026-07-15T05:43:46.664098Z","steps":["trace[56937850] 'process raft request' (duration: 886.221691ms)","trace[56937850] 'compare' (duration: 211.573162ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:46.664187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.565398Z","time spent":"1.098756772s","remote":"127.0.0.1:46344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:43:46.664244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.721041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:46.664328Z","caller":"traceutil/trace.go:171","msg":"trace[2065571142] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24277; }","duration":"484.862513ms","start":"2026-07-15T05:43:46.179447Z","end":"2026-07-15T05:43:46.664309Z","steps":["trace[2065571142] 'agreement among raft nodes before linearized reading' (duration: 484.70634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:46.664327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.613119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-07-15T05:43:46.66435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.372268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-15T05:43:46.664381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:46.179427Z","time spent":"484.945275ms","remote":"127.0.0.1:46604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-07-15T05:43:46.663894Z","caller":"traceutil/trace.go:171","msg":"trace[349160262] transaction","detail":"{read_only:false; response_revision:24276; number_of_response:1; }","duration":"707.47267ms","start":"2026-07-15T05:43:45.956384Z","end":"2026-07-15T05:43:46.663857Z","steps":["trace[349160262] 'process raft request' (duration: 707.302176ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:46.664425Z","caller":"traceutil/trace.go:171","msg":"trace[1748510325] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:24277; }","duration":"981.770823ms","start":"2026-07-15T05:43:45.682633Z","end":"2026-07-15T05:43:46.664404Z","steps":["trace[1748510325] 'agreement among raft nodes before linearized reading' (duration: 981.471037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:46.664338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.013459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:46.664433Z","caller":"traceutil/trace.go:171","msg":"trace[1989864644] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:24277; }","duration":"871.49796ms","start":"2026-07-15T05:43:45.792912Z","end":"2026-07-15T05:43:46.66441Z","steps":["trace[1989864644] 'agreement among raft nodes before linearized reading' (duration: 871.294626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:46.664351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.518604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:46.664411Z","caller":"traceutil/trace.go:171","msg":"trace[1955508733] transaction","detail":"{read_only:false; response_revision:24275; number_of_response:1; }","duration":"1.057825885s","start":"2026-07-15T05:43:45.606536Z","end":"2026-07-15T05:43:46.664362Z","steps":["trace[1955508733] 'process raft request' (duration: 1.05706302s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:46.664541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.956358Z","time spent":"708.104913ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:43:46.664556Z","caller":"traceutil/trace.go:171","msg":"trace[390559490] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24277; }","duration":"832.774889ms","start":"2026-07-15T05:43:45.831734Z","end":"2026-07-15T05:43:46.664509Z","steps":["trace[390559490] 'agreement among raft nodes before linearized reading' (duration: 832.459293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:46.664355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.511636ms","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-07-15T05:43:46.664686Z","caller":"traceutil/trace.go:171","msg":"trace[634950274] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24277; }","duration":"179.901974ms","start":"2026-07-15T05:43:46.48477Z","end":"2026-07-15T05:43:46.664672Z","steps":["trace[634950274] 'agreement among raft nodes before linearized reading' (duration: 179.501006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:46.66469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.831717Z","time spent":"832.929463ms","remote":"127.0.0.1:58278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:43:46.664749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.606524Z","time spent":"1.05803263s","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:43:46.664358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.047125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:46.664924Z","caller":"traceutil/trace.go:171","msg":"trace[1266801392] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:24277; }","duration":"458.640947ms","start":"2026-07-15T05:43:46.206254Z","end":"2026-07-15T05:43:46.664895Z","steps":["trace[1266801392] 'agreement among raft nodes before linearized reading' (duration: 458.041555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:46.665015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:46.206237Z","time spent":"458.75365ms","remote":"127.0.0.1:47616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true "} {"level":"info","ts":"2026-07-15T05:43:46.664476Z","caller":"traceutil/trace.go:171","msg":"trace[1346453269] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:24277; }","duration":"396.210512ms","start":"2026-07-15T05:43:46.268254Z","end":"2026-07-15T05:43:46.664465Z","steps":["trace[1346453269] 'agreement among raft nodes before linearized reading' (duration: 395.962087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:46.665104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:46.268239Z","time spent":"396.849236ms","remote":"127.0.0.1:46530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:43:46.664344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.766948ms","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-07-15T05:43:46.665215Z","caller":"traceutil/trace.go:171","msg":"trace[19254737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24277; }","duration":"359.678057ms","start":"2026-07-15T05:43:46.305503Z","end":"2026-07-15T05:43:46.665181Z","steps":["trace[19254737] 'agreement among raft nodes before linearized reading' (duration: 358.706867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:46.665277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:46.305473Z","time spent":"359.787479ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-15T05:43:46.664474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.682565Z","time spent":"981.896695ms","remote":"127.0.0.1:43816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":159,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:43:46.664481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:45.792891Z","time spent":"871.579652ms","remote":"127.0.0.1:46500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":90,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-07-15T05:43:50.778075Z","caller":"traceutil/trace.go:171","msg":"trace[1067720449] transaction","detail":"{read_only:false; response_revision:24361; number_of_response:1; }","duration":"101.81445ms","start":"2026-07-15T05:43:50.676237Z","end":"2026-07-15T05:43:50.778052Z","steps":["trace[1067720449] 'process raft request' (duration: 101.659307ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:50.965647Z","caller":"traceutil/trace.go:171","msg":"trace[1734080523] linearizableReadLoop","detail":"{readStateIndex:25160; appliedIndex:25157; }","duration":"205.09968ms","start":"2026-07-15T05:43:50.760524Z","end":"2026-07-15T05:43:50.965623Z","steps":["trace[1734080523] 'read index received' (duration: 17.463338ms)","trace[1734080523] 'applied index is now lower than readState.Index' (duration: 187.635232ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:50.965747Z","caller":"traceutil/trace.go:171","msg":"trace[2068663719] transaction","detail":"{read_only:false; response_revision:24362; number_of_response:1; }","duration":"287.396541ms","start":"2026-07-15T05:43:50.678321Z","end":"2026-07-15T05:43:50.965717Z","steps":["trace[2068663719] 'process raft request' (duration: 223.315152ms)","trace[2068663719] 'compare' (duration: 63.64009ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:50.965773Z","caller":"traceutil/trace.go:171","msg":"trace[337428561] transaction","detail":"{read_only:false; response_revision:24363; number_of_response:1; }","duration":"287.280278ms","start":"2026-07-15T05:43:50.678481Z","end":"2026-07-15T05:43:50.965761Z","steps":["trace[337428561] 'process raft request' (duration: 287.039103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:50.966127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.58735ms","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-07-15T05:43:50.966184Z","caller":"traceutil/trace.go:171","msg":"trace[616372139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24363; }","duration":"205.682962ms","start":"2026-07-15T05:43:50.760487Z","end":"2026-07-15T05:43:50.96617Z","steps":["trace[616372139] 'agreement among raft nodes before linearized reading' (duration: 205.525219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:50.966447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.545549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:50.966554Z","caller":"traceutil/trace.go:171","msg":"trace[343000664] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:24363; }","duration":"137.695192ms","start":"2026-07-15T05:43:50.82883Z","end":"2026-07-15T05:43:50.966525Z","steps":["trace[343000664] 'agreement among raft nodes before linearized reading' (duration: 136.944317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:51.306272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.558559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:43:51.306601Z","caller":"traceutil/trace.go:171","msg":"trace[1002576335] linearizableReadLoop","detail":"{readStateIndex:25161; appliedIndex:25160; }","duration":"316.408873ms","start":"2026-07-15T05:43:50.990168Z","end":"2026-07-15T05:43:51.306577Z","steps":["trace[1002576335] 'read index received' (duration: 155.423995ms)","trace[1002576335] 'applied index is now lower than readState.Index' (duration: 160.982697ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:51.30661Z","caller":"traceutil/trace.go:171","msg":"trace[1074030880] transaction","detail":"{read_only:false; response_revision:24364; number_of_response:1; }","duration":"336.419402ms","start":"2026-07-15T05:43:50.970162Z","end":"2026-07-15T05:43:51.306581Z","steps":["trace[1074030880] 'process raft request' (duration: 175.462604ms)","trace[1074030880] 'compare' (duration: 160.412177ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:51.306739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.558526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:51.306832Z","caller":"traceutil/trace.go:171","msg":"trace[1378676872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24365; }","duration":"316.684258ms","start":"2026-07-15T05:43:50.990134Z","end":"2026-07-15T05:43:51.306818Z","steps":["trace[1378676872] 'agreement among raft nodes before linearized reading' (duration: 316.527955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:51.306872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:50.990122Z","time spent":"316.742369ms","remote":"127.0.0.1:46308","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-07-15T05:43:51.30696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:50.970137Z","time spent":"336.618375ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:43:51.308026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.756183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a00f8793-9f7e-41ac-8f43-6b0af05055c2\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-15T05:43:51.308123Z","caller":"traceutil/trace.go:171","msg":"trace[1067259847] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a00f8793-9f7e-41ac-8f43-6b0af05055c2; range_end:; response_count:1; response_revision:24365; }","duration":"278.075369ms","start":"2026-07-15T05:43:51.030024Z","end":"2026-07-15T05:43:51.308099Z","steps":["trace[1067259847] 'agreement among raft nodes before linearized reading' (duration: 277.662051ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:51.468464Z","caller":"traceutil/trace.go:171","msg":"trace[6882033] transaction","detail":"{read_only:false; response_revision:24366; number_of_response:1; }","duration":"155.942656ms","start":"2026-07-15T05:43:51.31249Z","end":"2026-07-15T05:43:51.468433Z","steps":["trace[6882033] 'process raft request' (duration: 130.21155ms)","trace[6882033] 'compare' (duration: 25.594473ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:51.479639Z","caller":"traceutil/trace.go:171","msg":"trace[1176164430] transaction","detail":"{read_only:false; response_revision:24367; number_of_response:1; }","duration":"164.02426ms","start":"2026-07-15T05:43:51.315588Z","end":"2026-07-15T05:43:51.479613Z","steps":["trace[1176164430] 'process raft request' (duration: 163.770175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:51.646415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.903329ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-15T05:43:51.646714Z","caller":"traceutil/trace.go:171","msg":"trace[1868934700] linearizableReadLoop","detail":"{readStateIndex:25165; appliedIndex:25162; }","duration":"314.173907ms","start":"2026-07-15T05:43:51.332523Z","end":"2026-07-15T05:43:51.646697Z","steps":["trace[1868934700] 'read index received' (duration: 110.192721ms)","trace[1868934700] 'applied index is now lower than readState.Index' (duration: 203.980206ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:43:51.647169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.636576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:51.647313Z","caller":"traceutil/trace.go:171","msg":"trace[1538658249] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:24367; }","duration":"314.80623ms","start":"2026-07-15T05:43:51.332491Z","end":"2026-07-15T05:43:51.647297Z","steps":["trace[1538658249] 'agreement among raft nodes before linearized reading' (duration: 314.630836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:51.647395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:43:51.332476Z","time spent":"314.902522ms","remote":"127.0.0.1:54692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"info","ts":"2026-07-15T05:43:51.6546Z","caller":"traceutil/trace.go:171","msg":"trace[2078430541] transaction","detail":"{read_only:false; response_revision:24369; number_of_response:1; }","duration":"104.669858ms","start":"2026-07-15T05:43:51.549912Z","end":"2026-07-15T05:43:51.654582Z","steps":["trace[2078430541] 'process raft request' (duration: 103.906252ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:51.654871Z","caller":"traceutil/trace.go:171","msg":"trace[1967444215] transaction","detail":"{read_only:false; response_revision:24370; number_of_response:1; }","duration":"104.540125ms","start":"2026-07-15T05:43:51.550303Z","end":"2026-07-15T05:43:51.654843Z","steps":["trace[1967444215] 'process raft request' (duration: 103.82967ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:53.389247Z","caller":"traceutil/trace.go:171","msg":"trace[1108405022] transaction","detail":"{read_only:false; response_revision:24385; number_of_response:1; }","duration":"181.440906ms","start":"2026-07-15T05:43:53.207767Z","end":"2026-07-15T05:43:53.389208Z","steps":["trace[1108405022] 'process raft request' (duration: 181.060578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:57.310641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.559771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-15T05:43:57.310722Z","caller":"traceutil/trace.go:171","msg":"trace[946340185] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24430; }","duration":"113.649113ms","start":"2026-07-15T05:43:57.197051Z","end":"2026-07-15T05:43:57.3107Z","steps":["trace[946340185] 'range keys from in-memory index tree' (duration: 113.358347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:57.314648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.775902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10359"} {"level":"info","ts":"2026-07-15T05:43:57.3227Z","caller":"traceutil/trace.go:171","msg":"trace[627162782] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24430; }","duration":"160.847807ms","start":"2026-07-15T05:43:57.161825Z","end":"2026-07-15T05:43:57.322673Z","steps":["trace[627162782] 'range keys from in-memory index tree' (duration: 148.531255ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:57.568599Z","caller":"traceutil/trace.go:171","msg":"trace[2093797274] linearizableReadLoop","detail":"{readStateIndex:25230; appliedIndex:25229; }","duration":"227.961089ms","start":"2026-07-15T05:43:57.340609Z","end":"2026-07-15T05:43:57.56857Z","steps":["trace[2093797274] 'read index received' (duration: 227.668363ms)","trace[2093797274] 'applied index is now lower than readState.Index' (duration: 291.556µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:57.568934Z","caller":"traceutil/trace.go:171","msg":"trace[1124741973] transaction","detail":"{read_only:false; response_revision:24431; number_of_response:1; }","duration":"234.219566ms","start":"2026-07-15T05:43:57.334653Z","end":"2026-07-15T05:43:57.568872Z","steps":["trace[1124741973] 'process raft request' (duration: 233.702056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:57.569149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.52085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:43:57.569208Z","caller":"traceutil/trace.go:171","msg":"trace[492219241] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:24431; }","duration":"228.637013ms","start":"2026-07-15T05:43:57.340553Z","end":"2026-07-15T05:43:57.56919Z","steps":["trace[492219241] 'agreement among raft nodes before linearized reading' (duration: 228.535401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:57.578722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.415878ms","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-07-15T05:43:57.57893Z","caller":"traceutil/trace.go:171","msg":"trace[189304825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24432; }","duration":"116.677044ms","start":"2026-07-15T05:43:57.462231Z","end":"2026-07-15T05:43:57.578908Z","steps":["trace[189304825] 'agreement among raft nodes before linearized reading' (duration: 116.336497ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:57.578939Z","caller":"traceutil/trace.go:171","msg":"trace[1861761817] transaction","detail":"{read_only:false; response_revision:24432; number_of_response:1; }","duration":"165.554093ms","start":"2026-07-15T05:43:57.413361Z","end":"2026-07-15T05:43:57.578915Z","steps":["trace[1861761817] 'process raft request' (duration: 165.066223ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:21.88383Z","caller":"traceutil/trace.go:171","msg":"trace[58833240] transaction","detail":"{read_only:false; response_revision:24635; number_of_response:1; }","duration":"125.80226ms","start":"2026-07-15T05:44:21.758Z","end":"2026-07-15T05:44:21.883802Z","steps":["trace[58833240] 'process raft request' (duration: 70.096152ms)","trace[58833240] 'compare' (duration: 55.564115ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:21.945045Z","caller":"traceutil/trace.go:171","msg":"trace[1486225362] transaction","detail":"{read_only:false; response_revision:24636; number_of_response:1; }","duration":"185.626683ms","start":"2026-07-15T05:44:21.759387Z","end":"2026-07-15T05:44:21.945013Z","steps":["trace[1486225362] 'process raft request' (duration: 185.351137ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:21.945041Z","caller":"traceutil/trace.go:171","msg":"trace[620275056] transaction","detail":"{read_only:false; response_revision:24637; number_of_response:1; }","duration":"182.651802ms","start":"2026-07-15T05:44:21.762359Z","end":"2026-07-15T05:44:21.945011Z","steps":["trace[620275056] 'process raft request' (duration: 182.5705ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:21.94522Z","caller":"traceutil/trace.go:171","msg":"trace[11141155] linearizableReadLoop","detail":"{readStateIndex:25440; appliedIndex:25438; }","duration":"183.011159ms","start":"2026-07-15T05:44:21.762187Z","end":"2026-07-15T05:44:21.945198Z","steps":["trace[11141155] 'read index received' (duration: 65.844036ms)","trace[11141155] 'applied index is now lower than readState.Index' (duration: 117.165983ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:21.945346Z","caller":"traceutil/trace.go:171","msg":"trace[853543822] transaction","detail":"{read_only:false; response_revision:24638; number_of_response:1; }","duration":"182.776244ms","start":"2026-07-15T05:44:21.762559Z","end":"2026-07-15T05:44:21.945335Z","steps":["trace[853543822] 'process raft request' (duration: 182.412027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:21.945561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.132245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T05:44:21.945607Z","caller":"traceutil/trace.go:171","msg":"trace[353670107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24638; }","duration":"141.227066ms","start":"2026-07-15T05:44:21.80437Z","end":"2026-07-15T05:44:21.945597Z","steps":["trace[353670107] 'agreement among raft nodes before linearized reading' (duration: 141.018371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:21.945724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.810218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:44:21.945753Z","caller":"traceutil/trace.go:171","msg":"trace[302519193] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:24638; }","duration":"116.839728ms","start":"2026-07-15T05:44:21.828904Z","end":"2026-07-15T05:44:21.945744Z","steps":["trace[302519193] 'agreement among raft nodes before linearized reading' (duration: 116.763547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:21.9458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.596132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:44:21.945846Z","caller":"traceutil/trace.go:171","msg":"trace[988010734] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:24638; }","duration":"183.690274ms","start":"2026-07-15T05:44:21.762145Z","end":"2026-07-15T05:44:21.945835Z","steps":["trace[988010734] 'agreement among raft nodes before linearized reading' (duration: 183.611792ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:22.13366Z","caller":"traceutil/trace.go:171","msg":"trace[1467938349] linearizableReadLoop","detail":"{readStateIndex:25443; appliedIndex:25442; }","duration":"181.286515ms","start":"2026-07-15T05:44:21.952347Z","end":"2026-07-15T05:44:22.133634Z","steps":["trace[1467938349] 'read index received' (duration: 81.689469ms)","trace[1467938349] 'applied index is now lower than readState.Index' (duration: 99.595236ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:22.133978Z","caller":"traceutil/trace.go:171","msg":"trace[1918299321] transaction","detail":"{read_only:false; response_revision:24639; number_of_response:1; }","duration":"183.554921ms","start":"2026-07-15T05:44:21.950408Z","end":"2026-07-15T05:44:22.133963Z","steps":["trace[1918299321] 'process raft request' (duration: 83.67773ms)","trace[1918299321] 'compare' (duration: 99.383241ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:44:22.134273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.910207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-cca5e6b0-5413-44b3-89c1-d2ca360ed81c\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-07-15T05:44:22.134316Z","caller":"traceutil/trace.go:171","msg":"trace[903277255] range","detail":"{range_begin:/registry/persistentvolumes/pvc-cca5e6b0-5413-44b3-89c1-d2ca360ed81c; range_end:; response_count:1; response_revision:24639; }","duration":"182.57838ms","start":"2026-07-15T05:44:21.951726Z","end":"2026-07-15T05:44:22.134305Z","steps":["trace[903277255] 'agreement among raft nodes before linearized reading' (duration: 182.428507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:22.134538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.858577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-07-15T05:44:22.134578Z","caller":"traceutil/trace.go:171","msg":"trace[593447345] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24639; }","duration":"181.916107ms","start":"2026-07-15T05:44:21.952652Z","end":"2026-07-15T05:44:22.134568Z","steps":["trace[593447345] 'agreement among raft nodes before linearized reading' (duration: 181.813455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:22.134839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.918331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:44:22.134876Z","caller":"traceutil/trace.go:171","msg":"trace[1617746212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24639; }","duration":"143.993012ms","start":"2026-07-15T05:44:21.990874Z","end":"2026-07-15T05:44:22.134867Z","steps":["trace[1617746212] 'agreement among raft nodes before linearized reading' (duration: 143.934531ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:22.561318Z","caller":"traceutil/trace.go:171","msg":"trace[1538194164] transaction","detail":"{read_only:false; response_revision:24643; number_of_response:1; }","duration":"212.651306ms","start":"2026-07-15T05:44:22.348634Z","end":"2026-07-15T05:44:22.561286Z","steps":["trace[1538194164] 'process raft request' (duration: 190.497873ms)","trace[1538194164] 'compare' (duration: 21.866627ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:23.879137Z","caller":"traceutil/trace.go:171","msg":"trace[348762794] transaction","detail":"{read_only:false; response_revision:24648; number_of_response:1; }","duration":"183.293816ms","start":"2026-07-15T05:44:23.695814Z","end":"2026-07-15T05:44:23.879107Z","steps":["trace[348762794] 'process raft request' (duration: 182.903728ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:23.87909Z","caller":"traceutil/trace.go:171","msg":"trace[1580911230] linearizableReadLoop","detail":"{readStateIndex:25452; appliedIndex:25451; }","duration":"117.522002ms","start":"2026-07-15T05:44:23.761519Z","end":"2026-07-15T05:44:23.879041Z","steps":["trace[1580911230] 'read index received' (duration: 117.024372ms)","trace[1580911230] 'applied index is now lower than readState.Index' (duration: 495.36µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:44:23.879548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.000601ms","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-07-15T05:44:23.879616Z","caller":"traceutil/trace.go:171","msg":"trace[1757386434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24648; }","duration":"118.135584ms","start":"2026-07-15T05:44:23.761459Z","end":"2026-07-15T05:44:23.879595Z","steps":["trace[1757386434] 'agreement among raft nodes before linearized reading' (duration: 117.823777ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:24.036514Z","caller":"traceutil/trace.go:171","msg":"trace[742134600] linearizableReadLoop","detail":"{readStateIndex:25454; appliedIndex:25452; }","duration":"147.376602ms","start":"2026-07-15T05:44:23.889121Z","end":"2026-07-15T05:44:24.036498Z","steps":["trace[742134600] 'read index received' (duration: 25.318847ms)","trace[742134600] 'applied index is now lower than readState.Index' (duration: 122.057045ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:24.036552Z","caller":"traceutil/trace.go:171","msg":"trace[879614477] transaction","detail":"{read_only:false; response_revision:24650; number_of_response:1; }","duration":"148.018834ms","start":"2026-07-15T05:44:23.888498Z","end":"2026-07-15T05:44:24.036516Z","steps":["trace[879614477] 'process raft request' (duration: 101.082425ms)","trace[879614477] 'compare' (duration: 46.772656ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:24.036567Z","caller":"traceutil/trace.go:171","msg":"trace[1053723831] transaction","detail":"{read_only:false; response_revision:24651; number_of_response:1; }","duration":"145.34713ms","start":"2026-07-15T05:44:23.891192Z","end":"2026-07-15T05:44:24.036539Z","steps":["trace[1053723831] 'process raft request' (duration: 145.263288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:24.036655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.514265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:44:24.036688Z","caller":"traceutil/trace.go:171","msg":"trace[1786414239] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:24651; }","duration":"147.590027ms","start":"2026-07-15T05:44:23.889089Z","end":"2026-07-15T05:44:24.036679Z","steps":["trace[1786414239] 'agreement among raft nodes before linearized reading' (duration: 147.506015ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:24.174471Z","caller":"traceutil/trace.go:171","msg":"trace[1305282564] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"223.294392ms","start":"2026-07-15T05:44:23.95115Z","end":"2026-07-15T05:44:24.174445Z","steps":["trace[1305282564] 'process raft request' (duration: 221.280321ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:24.174847Z","caller":"traceutil/trace.go:171","msg":"trace[414563717] linearizableReadLoop","detail":"{readStateIndex:25457; appliedIndex:25455; }","duration":"138.175972ms","start":"2026-07-15T05:44:24.036569Z","end":"2026-07-15T05:44:24.174745Z","steps":["trace[414563717] 'read index received' (duration: 135.803404ms)","trace[414563717] 'applied index is now lower than readState.Index' (duration: 2.370528ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:24.174828Z","caller":"traceutil/trace.go:171","msg":"trace[1744455117] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"222.638348ms","start":"2026-07-15T05:44:23.952155Z","end":"2026-07-15T05:44:24.174793Z","steps":["trace[1744455117] 'process raft request' (duration: 222.23499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:24.174927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.136292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:44:24.175025Z","caller":"traceutil/trace.go:171","msg":"trace[1273641412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24653; }","duration":"183.258555ms","start":"2026-07-15T05:44:23.991746Z","end":"2026-07-15T05:44:24.175004Z","steps":["trace[1273641412] 'agreement among raft nodes before linearized reading' (duration: 183.066001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:24.175085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.537728ms","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-07-15T05:44:24.175147Z","caller":"traceutil/trace.go:171","msg":"trace[1350822899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24653; }","duration":"225.65069ms","start":"2026-07-15T05:44:23.949478Z","end":"2026-07-15T05:44:24.175129Z","steps":["trace[1350822899] 'agreement among raft nodes before linearized reading' (duration: 225.448596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:24.175317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.474911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:44:24.175356Z","caller":"traceutil/trace.go:171","msg":"trace[1637471444] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:24653; }","duration":"142.546422ms","start":"2026-07-15T05:44:24.032801Z","end":"2026-07-15T05:44:24.175348Z","steps":["trace[1637471444] 'agreement among raft nodes before linearized reading' (duration: 141.99271ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:24.342119Z","caller":"traceutil/trace.go:171","msg":"trace[444384384] transaction","detail":"{read_only:false; response_revision:24655; number_of_response:1; }","duration":"158.750614ms","start":"2026-07-15T05:44:24.183312Z","end":"2026-07-15T05:44:24.342063Z","steps":["trace[444384384] 'process raft request' (duration: 137.380937ms)","trace[444384384] 'compare' (duration: 21.177303ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:24.559866Z","caller":"traceutil/trace.go:171","msg":"trace[2045639707] transaction","detail":"{read_only:false; response_revision:24656; number_of_response:1; }","duration":"140.390089ms","start":"2026-07-15T05:44:24.41945Z","end":"2026-07-15T05:44:24.55984Z","steps":["trace[2045639707] 'process raft request' (duration: 116.729094ms)","trace[2045639707] 'compare' (duration: 23.40441ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:24.604772Z","caller":"traceutil/trace.go:171","msg":"trace[2026319342] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"184.912008ms","start":"2026-07-15T05:44:24.419832Z","end":"2026-07-15T05:44:24.604744Z","steps":["trace[2026319342] 'process raft request' (duration: 184.628132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:25.42741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.613238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:44:25.427704Z","caller":"traceutil/trace.go:171","msg":"trace[553242182] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"235.355739ms","start":"2026-07-15T05:44:25.192307Z","end":"2026-07-15T05:44:25.427663Z","steps":["trace[553242182] 'process raft request' (duration: 83.389763ms)","trace[553242182] 'compare' (duration: 151.497256ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:25.995491Z","caller":"traceutil/trace.go:171","msg":"trace[1484324947] transaction","detail":"{read_only:false; response_revision:24663; number_of_response:1; }","duration":"110.833054ms","start":"2026-07-15T05:44:25.884626Z","end":"2026-07-15T05:44:25.995459Z","steps":["trace[1484324947] 'process raft request' (duration: 110.410896ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:26.334337Z","caller":"traceutil/trace.go:171","msg":"trace[149822576] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"292.114819ms","start":"2026-07-15T05:44:26.042193Z","end":"2026-07-15T05:44:26.334308Z","steps":["trace[149822576] 'process raft request' (duration: 291.953855ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:26.387169Z","caller":"traceutil/trace.go:171","msg":"trace[544267097] linearizableReadLoop","detail":"{readStateIndex:25472; appliedIndex:25469; }","duration":"195.446614ms","start":"2026-07-15T05:44:26.191702Z","end":"2026-07-15T05:44:26.387148Z","steps":["trace[544267097] 'read index received' (duration: 142.455381ms)","trace[544267097] 'applied index is now lower than readState.Index' (duration: 52.990493ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:26.38726Z","caller":"traceutil/trace.go:171","msg":"trace[1615046213] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"203.403587ms","start":"2026-07-15T05:44:26.183845Z","end":"2026-07-15T05:44:26.387248Z","steps":["trace[1615046213] 'process raft request' (duration: 203.270824ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:26.387248Z","caller":"traceutil/trace.go:171","msg":"trace[1903384055] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"206.071072ms","start":"2026-07-15T05:44:26.181128Z","end":"2026-07-15T05:44:26.387199Z","steps":["trace[1903384055] 'process raft request' (duration: 205.859597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:26.387545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.831302ms","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-07-15T05:44:26.387583Z","caller":"traceutil/trace.go:171","msg":"trace[232446146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24667; }","duration":"195.906003ms","start":"2026-07-15T05:44:26.19167Z","end":"2026-07-15T05:44:26.387576Z","steps":["trace[232446146] 'agreement among raft nodes before linearized reading' (duration: 195.74309ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:26.612602Z","caller":"traceutil/trace.go:171","msg":"trace[2002032212] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"219.969234ms","start":"2026-07-15T05:44:26.392591Z","end":"2026-07-15T05:44:26.612561Z","steps":["trace[2002032212] 'process raft request' (duration: 219.861392ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:26.612627Z","caller":"traceutil/trace.go:171","msg":"trace[1677438796] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"220.596757ms","start":"2026-07-15T05:44:26.391978Z","end":"2026-07-15T05:44:26.612575Z","steps":["trace[1677438796] 'process raft request' (duration: 123.923872ms)","trace[1677438796] 'compare' (duration: 96.209285ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:44:26.961977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.848881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:44:26.962203Z","caller":"traceutil/trace.go:171","msg":"trace[1967346122] linearizableReadLoop","detail":"{readStateIndex:25476; appliedIndex:25474; }","duration":"270.434406ms","start":"2026-07-15T05:44:26.691754Z","end":"2026-07-15T05:44:26.962188Z","steps":["trace[1967346122] 'read index received' (duration: 169.29906ms)","trace[1967346122] 'applied index is now lower than readState.Index' (duration: 101.134596ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:26.96229Z","caller":"traceutil/trace.go:171","msg":"trace[1285863290] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"334.786881ms","start":"2026-07-15T05:44:26.627496Z","end":"2026-07-15T05:44:26.962283Z","steps":["trace[1285863290] 'process raft request' (duration: 334.601828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:26.962336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:44:26.627472Z","time spent":"334.836012ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:44:26.962399Z","caller":"traceutil/trace.go:171","msg":"trace[512416476] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"339.865615ms","start":"2026-07-15T05:44:26.622504Z","end":"2026-07-15T05:44:26.962369Z","steps":["trace[512416476] 'process raft request' (duration: 238.529724ms)","trace[512416476] 'compare' (duration: 100.354521ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:44:26.962535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:44:26.622487Z","time spent":"339.974807ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:44:26.962582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.835604ms","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-07-15T05:44:26.962616Z","caller":"traceutil/trace.go:171","msg":"trace[1277913534] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24671; }","duration":"270.914476ms","start":"2026-07-15T05:44:26.691691Z","end":"2026-07-15T05:44:26.962606Z","steps":["trace[1277913534] 'agreement among raft nodes before linearized reading' (duration: 270.810584ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:27.178345Z","caller":"traceutil/trace.go:171","msg":"trace[644031780] linearizableReadLoop","detail":"{readStateIndex:25478; appliedIndex:25477; }","duration":"156.214462ms","start":"2026-07-15T05:44:27.022095Z","end":"2026-07-15T05:44:27.178309Z","steps":["trace[644031780] 'read index received' (duration: 79.30096ms)","trace[644031780] 'applied index is now lower than readState.Index' (duration: 76.912392ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:44:27.17853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.9419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:44:27.178486Z","caller":"traceutil/trace.go:171","msg":"trace[2055038295] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"157.783074ms","start":"2026-07-15T05:44:27.020649Z","end":"2026-07-15T05:44:27.178432Z","steps":["trace[2055038295] 'process raft request' (duration: 80.819302ms)","trace[2055038295] 'compare' (duration: 76.430841ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:27.178584Z","caller":"traceutil/trace.go:171","msg":"trace[2095493604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24673; }","duration":"187.053593ms","start":"2026-07-15T05:44:26.991515Z","end":"2026-07-15T05:44:27.178569Z","steps":["trace[2095493604] 'agreement among raft nodes before linearized reading' (duration: 186.95046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:27.17859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.512958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:44:27.178668Z","caller":"traceutil/trace.go:171","msg":"trace[26405171] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24673; }","duration":"151.623659ms","start":"2026-07-15T05:44:27.027022Z","end":"2026-07-15T05:44:27.178646Z","steps":["trace[26405171] 'agreement among raft nodes before linearized reading' (duration: 151.497487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:27.456908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.801498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:44:27.456991Z","caller":"traceutil/trace.go:171","msg":"trace[682246455] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"222.164489ms","start":"2026-07-15T05:44:27.234813Z","end":"2026-07-15T05:44:27.456978Z","steps":["trace[682246455] 'process raft request' (duration: 79.215158ms)","trace[682246455] 'compare' (duration: 142.188636ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:27.540525Z","caller":"traceutil/trace.go:171","msg":"trace[852591525] transaction","detail":"{read_only:false; response_revision:24675; number_of_response:1; }","duration":"107.235092ms","start":"2026-07-15T05:44:27.433261Z","end":"2026-07-15T05:44:27.540496Z","steps":["trace[852591525] 'process raft request' (duration: 107.013578ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:27.989314Z","caller":"traceutil/trace.go:171","msg":"trace[165007171] transaction","detail":"{read_only:false; response_revision:24676; number_of_response:1; }","duration":"162.96577ms","start":"2026-07-15T05:44:27.826321Z","end":"2026-07-15T05:44:27.989286Z","steps":["trace[165007171] 'process raft request' (duration: 162.788396ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:28.110434Z","caller":"traceutil/trace.go:171","msg":"trace[1161967342] transaction","detail":"{read_only:false; response_revision:24677; number_of_response:1; }","duration":"110.340295ms","start":"2026-07-15T05:44:28.000062Z","end":"2026-07-15T05:44:28.110403Z","steps":["trace[1161967342] 'process raft request' (duration: 82.118368ms)","trace[1161967342] 'compare' (duration: 27.578494ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:28.464941Z","caller":"traceutil/trace.go:171","msg":"trace[158159908] transaction","detail":"{read_only:false; response_revision:24679; number_of_response:1; }","duration":"125.999975ms","start":"2026-07-15T05:44:28.338898Z","end":"2026-07-15T05:44:28.464898Z","steps":["trace[158159908] 'process raft request' (duration: 125.7466ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:28.599246Z","caller":"traceutil/trace.go:171","msg":"trace[1940956018] transaction","detail":"{read_only:false; response_revision:24681; number_of_response:1; }","duration":"205.932929ms","start":"2026-07-15T05:44:28.393288Z","end":"2026-07-15T05:44:28.599221Z","steps":["trace[1940956018] 'process raft request' (duration: 205.824807ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:28.599489Z","caller":"traceutil/trace.go:171","msg":"trace[791143583] transaction","detail":"{read_only:false; response_revision:24682; number_of_response:1; }","duration":"101.647738ms","start":"2026-07-15T05:44:28.49782Z","end":"2026-07-15T05:44:28.599467Z","steps":["trace[791143583] 'process raft request' (duration: 101.341702ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:28.599531Z","caller":"traceutil/trace.go:171","msg":"trace[739061902] transaction","detail":"{read_only:false; response_revision:24680; number_of_response:1; }","duration":"206.427818ms","start":"2026-07-15T05:44:28.393045Z","end":"2026-07-15T05:44:28.599473Z","steps":["trace[739061902] 'process raft request' (duration: 172.532276ms)","trace[739061902] 'compare' (duration: 33.426962ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:29.635018Z","caller":"traceutil/trace.go:171","msg":"trace[1594469956] transaction","detail":"{read_only:false; response_revision:24690; number_of_response:1; }","duration":"166.039073ms","start":"2026-07-15T05:44:29.468951Z","end":"2026-07-15T05:44:29.63499Z","steps":["trace[1594469956] 'process raft request' (duration: 84.621059ms)","trace[1594469956] 'compare' (duration: 80.837892ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:29.659346Z","caller":"traceutil/trace.go:171","msg":"trace[1017497141] transaction","detail":"{read_only:false; response_revision:24691; number_of_response:1; }","duration":"113.052589ms","start":"2026-07-15T05:44:29.546269Z","end":"2026-07-15T05:44:29.659321Z","steps":["trace[1017497141] 'process raft request' (duration: 112.947907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:29.925481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.494282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:44:29.925597Z","caller":"traceutil/trace.go:171","msg":"trace[166442121] linearizableReadLoop","detail":"{readStateIndex:25497; appliedIndex:25496; }","duration":"139.528721ms","start":"2026-07-15T05:44:29.786051Z","end":"2026-07-15T05:44:29.925579Z","steps":["trace[166442121] 'read index received' (duration: 58.671µs)","trace[166442121] 'applied index is now lower than readState.Index' (duration: 139.46862ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:29.925739Z","caller":"traceutil/trace.go:171","msg":"trace[661480113] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"231.632814ms","start":"2026-07-15T05:44:29.694075Z","end":"2026-07-15T05:44:29.925708Z","steps":["trace[661480113] 'process raft request' (duration: 88.825416ms)","trace[661480113] 'compare' (duration: 142.341618ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:44:29.925765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.723406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-15T05:44:29.925886Z","caller":"traceutil/trace.go:171","msg":"trace[1910640756] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24692; }","duration":"139.865828ms","start":"2026-07-15T05:44:29.785994Z","end":"2026-07-15T05:44:29.92586Z","steps":["trace[1910640756] 'agreement among raft nodes before linearized reading' (duration: 139.633693ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:31.347368Z","caller":"traceutil/trace.go:171","msg":"trace[965937021] linearizableReadLoop","detail":"{readStateIndex:25509; appliedIndex:25508; }","duration":"140.414529ms","start":"2026-07-15T05:44:31.206933Z","end":"2026-07-15T05:44:31.347348Z","steps":["trace[965937021] 'read index received' (duration: 140.255926ms)","trace[965937021] 'applied index is now lower than readState.Index' (duration: 157.613µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:31.347538Z","caller":"traceutil/trace.go:171","msg":"trace[845043786] transaction","detail":"{read_only:false; response_revision:24703; number_of_response:1; }","duration":"157.013759ms","start":"2026-07-15T05:44:31.190509Z","end":"2026-07-15T05:44:31.347522Z","steps":["trace[845043786] 'process raft request' (duration: 156.700862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:31.347643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.692704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-15T05:44:31.347723Z","caller":"traceutil/trace.go:171","msg":"trace[803110319] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24703; }","duration":"140.818667ms","start":"2026-07-15T05:44:31.206883Z","end":"2026-07-15T05:44:31.347701Z","steps":["trace[803110319] 'agreement among raft nodes before linearized reading' (duration: 140.593433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:31.638182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.552728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:44:31.638351Z","caller":"traceutil/trace.go:171","msg":"trace[1598931339] transaction","detail":"{read_only:false; response_revision:24705; number_of_response:1; }","duration":"170.485654ms","start":"2026-07-15T05:44:31.467835Z","end":"2026-07-15T05:44:31.638321Z","steps":["trace[1598931339] 'process raft request' (duration: 14.702571ms)","trace[1598931339] 'compare' (duration: 155.404234ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:44:42.295989Z","caller":"traceutil/trace.go:171","msg":"trace[1645522306] transaction","detail":"{read_only:false; response_revision:24790; number_of_response:1; }","duration":"103.629798ms","start":"2026-07-15T05:44:42.192327Z","end":"2026-07-15T05:44:42.295956Z","steps":["trace[1645522306] 'process raft request' (duration: 102.826021ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:45:51.622597Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000670b.wal"} {"level":"info","ts":"2026-07-15T05:47:11.58636Z","caller":"traceutil/trace.go:171","msg":"trace[219898207] transaction","detail":"{read_only:false; response_revision:26284; number_of_response:1; }","duration":"147.695663ms","start":"2026-07-15T05:47:11.438633Z","end":"2026-07-15T05:47:11.586328Z","steps":["trace[219898207] 'process raft request' (duration: 147.370436ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:22.289578Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23466} {"level":"info","ts":"2026-07-15T05:47:22.548915Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23466,"took":"254.881795ms","hash":1910992946,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":38195200,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-15T05:47:22.548986Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1910992946,"revision":23466,"compact-revision":20294} {"level":"info","ts":"2026-07-15T05:47:43.937257Z","caller":"traceutil/trace.go:171","msg":"trace[1242292739] transaction","detail":"{read_only:false; response_revision:26661; number_of_response:1; }","duration":"105.778665ms","start":"2026-07-15T05:47:43.831438Z","end":"2026-07-15T05:47:43.937217Z","steps":["trace[1242292739] 'process raft request' (duration: 62.909528ms)","trace[1242292739] 'compare' (duration: 42.673963ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:44.822625Z","caller":"traceutil/trace.go:171","msg":"trace[1876423098] transaction","detail":"{read_only:false; response_revision:26669; number_of_response:1; }","duration":"187.095888ms","start":"2026-07-15T05:47:44.635487Z","end":"2026-07-15T05:47:44.822583Z","steps":["trace[1876423098] 'process raft request' (duration: 186.563018ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:48:18.633012Z","caller":"traceutil/trace.go:171","msg":"trace[1964323043] transaction","detail":"{read_only:false; response_revision:27023; number_of_response:1; }","duration":"105.963239ms","start":"2026-07-15T05:48:18.527023Z","end":"2026-07-15T05:48:18.632987Z","steps":["trace[1964323043] 'process raft request' (duration: 105.794854ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:22.296579Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26469} {"level":"info","ts":"2026-07-15T05:52:22.434991Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26469,"took":"134.26831ms","hash":1863185761,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":32456704,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T05:52:22.435047Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1863185761,"revision":26469,"compact-revision":23466} {"level":"info","ts":"2026-07-15T05:52:52.880306Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T05:52:52.887026Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-15T05:52:52.887148Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-15T05:57:22.302526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28857} {"level":"info","ts":"2026-07-15T05:57:22.446136Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28857,"took":"138.946965ms","hash":2818042443,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":30298112,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-15T05:57:22.446228Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2818042443,"revision":28857,"compact-revision":26469} {"level":"info","ts":"2026-07-15T06:02:22.309269Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31076} {"level":"info","ts":"2026-07-15T06:02:22.422506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31076,"took":"108.98002ms","hash":2858173329,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":31813632,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T06:02:22.422578Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2858173329,"revision":31076,"compact-revision":28857} {"level":"info","ts":"2026-07-15T06:07:22.314956Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33435} {"level":"info","ts":"2026-07-15T06:07:22.461747Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33435,"took":"143.419523ms","hash":487057498,"current-db-size-bytes":59564032,"current-db-size":"60 MB","current-db-size-in-use-bytes":32567296,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-15T06:07:22.461831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":487057498,"revision":33435,"compact-revision":31076} {"level":"info","ts":"2026-07-15T06:12:22.320138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35732} {"level":"info","ts":"2026-07-15T06:12:22.458604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35732,"took":"134.136012ms","hash":1140079784,"current-db-size-bytes":60411904,"current-db-size":"60 MB","current-db-size-in-use-bytes":31744000,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T06:12:22.458684Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1140079784,"revision":35732,"compact-revision":33435} {"level":"info","ts":"2026-07-15T06:14:10.178798Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T06:14:10.184455Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-15T06:14:10.184551Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}