{"level":"warn","ts":"2026-09-11T00:42:12.580969Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-11T00:42:12.581098Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.111:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.111:2380","--initial-cluster=instance=https://162.253.55.111:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.111:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.111:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-11T00:42:12.581196Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-11T00:42:12.581219Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.111:2380"]} {"level":"info","ts":"2026-09-11T00:42:12.581247Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-11T00:42:12.582343Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"]} {"level":"info","ts":"2026-09-11T00:42:12.582486Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.111:2380"],"listen-peer-urls":["https://162.253.55.111:2380"],"advertise-client-urls":["https://162.253.55.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.111:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-11T00:42:12.586771Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.876921ms"} {"level":"info","ts":"2026-09-11T00:42:12.593204Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"299bf3744060cbd","cluster-id":"83d94f04ddaf7996"} {"level":"info","ts":"2026-09-11T00:42:12.593297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=()"} {"level":"info","ts":"2026-09-11T00:42:12.593332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became follower at term 0"} {"level":"info","ts":"2026-09-11T00:42:12.593352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 299bf3744060cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-11T00:42:12.593374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became follower at term 1"} {"level":"info","ts":"2026-09-11T00:42:12.593426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=(187391103597939901)"} {"level":"warn","ts":"2026-09-11T00:42:12.598838Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-11T00:42:12.600752Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-11T00:42:12.602392Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-11T00:42:12.605602Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"299bf3744060cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-11T00:42:12.605791Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"299bf3744060cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-11T00:42:12.606033Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-11T00:42:12.606188Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-11T00:42:12.606213Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-11T00:42:12.607606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=(187391103597939901)"} {"level":"info","ts":"2026-09-11T00:42:12.607853Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"83d94f04ddaf7996","local-member-id":"299bf3744060cbd","added-peer-id":"299bf3744060cbd","added-peer-peer-urls":["https://162.253.55.111:2380"]} {"level":"info","ts":"2026-09-11T00:42:12.6111Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-11T00:42:12.611246Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.111:2380"} {"level":"info","ts":"2026-09-11T00:42:12.611311Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.111:2380"} {"level":"info","ts":"2026-09-11T00:42:12.611417Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"299bf3744060cbd","initial-advertise-peer-urls":["https://162.253.55.111:2380"],"listen-peer-urls":["https://162.253.55.111:2380"],"advertise-client-urls":["https://162.253.55.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-11T00:42:12.611455Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-11T00:42:12.694559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd is starting a new election at term 1"} {"level":"info","ts":"2026-09-11T00:42:12.694693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-09-11T00:42:12.694725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd received MsgPreVoteResp from 299bf3744060cbd at term 1"} {"level":"info","ts":"2026-09-11T00:42:12.69475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became candidate at term 2"} {"level":"info","ts":"2026-09-11T00:42:12.694765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd received MsgVoteResp from 299bf3744060cbd at term 2"} {"level":"info","ts":"2026-09-11T00:42:12.694827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became leader at term 2"} {"level":"info","ts":"2026-09-11T00:42:12.694856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 299bf3744060cbd elected leader 299bf3744060cbd at term 2"} {"level":"info","ts":"2026-09-11T00:42:12.696356Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"299bf3744060cbd","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.111:2379]}","request-path":"/0/members/299bf3744060cbd/attributes","cluster-id":"83d94f04ddaf7996","publish-timeout":"7s"} {"level":"info","ts":"2026-09-11T00:42:12.696708Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T00:42:12.696747Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T00:42:12.6968Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-11T00:42:12.696844Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-11T00:42:12.696871Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:42:12.697845Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"83d94f04ddaf7996","local-member-id":"299bf3744060cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:42:12.698012Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:42:12.698072Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:42:12.707035Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-11T00:42:12.708026Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.111:2379"} {"level":"info","ts":"2026-09-11T00:42:35.340733Z","caller":"traceutil/trace.go:171","msg":"trace[1995381472] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"206.936297ms","start":"2026-09-11T00:42:35.133765Z","end":"2026-09-11T00:42:35.340701Z","steps":["trace[1995381472] 'process raft request' (duration: 206.60435ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:42:40.682739Z","caller":"traceutil/trace.go:171","msg":"trace[727589792] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"143.277987ms","start":"2026-09-11T00:42:40.53913Z","end":"2026-09-11T00:42:40.682408Z","steps":["trace[727589792] 'process raft request' (duration: 143.102773ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:42:52.022091Z","caller":"traceutil/trace.go:171","msg":"trace[265656550] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"474.517091ms","start":"2026-09-11T00:42:51.547532Z","end":"2026-09-11T00:42:52.022049Z","steps":["trace[265656550] 'process raft request' (duration: 399.868169ms)","trace[265656550] 'compare' (duration: 74.451557ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:42:52.02272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:51.547501Z","time spent":"474.708124ms","remote":"127.0.0.1:33674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T00:42:52.039217Z","caller":"traceutil/trace.go:171","msg":"trace[184966658] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"468.615587ms","start":"2026-09-11T00:42:51.570564Z","end":"2026-09-11T00:42:52.03918Z","steps":["trace[184966658] 'process raft request' (duration: 468.459773ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:42:52.039128Z","caller":"traceutil/trace.go:171","msg":"trace[2085431738] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"482.942768ms","start":"2026-09-11T00:42:51.556146Z","end":"2026-09-11T00:42:52.039088Z","steps":["trace[2085431738] 'process raft request' (duration: 482.755254ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:42:52.039451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:51.570539Z","time spent":"468.801011ms","remote":"127.0.0.1:33674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T00:42:52.039475Z","caller":"traceutil/trace.go:171","msg":"trace[1802828603] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"469.867963ms","start":"2026-09-11T00:42:51.569573Z","end":"2026-09-11T00:42:52.039441Z","steps":["trace[1802828603] 'process raft request' (duration: 469.393093ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:42:52.039055Z","caller":"traceutil/trace.go:171","msg":"trace[1446675109] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"483.02081ms","start":"2026-09-11T00:42:51.556016Z","end":"2026-09-11T00:42:52.039036Z","steps":["trace[1446675109] 'process raft request' (duration: 482.768634ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:42:52.039643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:51.569548Z","time spent":"470.004335ms","remote":"127.0.0.1:33674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T00:42:52.039734Z","caller":"traceutil/trace.go:171","msg":"trace[1185995533] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"467.551764ms","start":"2026-09-11T00:42:51.572139Z","end":"2026-09-11T00:42:52.039691Z","steps":["trace[1185995533] 'process raft request' (duration: 467.038484ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:42:52.039787Z","caller":"traceutil/trace.go:171","msg":"trace[1680157980] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"468.921954ms","start":"2026-09-11T00:42:51.570844Z","end":"2026-09-11T00:42:52.039766Z","steps":["trace[1680157980] 'process raft request' (duration: 468.23787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:42:52.039766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:51.555995Z","time spent":"483.668994ms","remote":"127.0.0.1:33674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T00:42:52.039756Z","caller":"traceutil/trace.go:171","msg":"trace[2052847048] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"407.600822ms","start":"2026-09-11T00:42:51.632141Z","end":"2026-09-11T00:42:52.039742Z","steps":["trace[2052847048] 'process raft request' (duration: 407.177863ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:42:52.039833Z","caller":"traceutil/trace.go:171","msg":"trace[717492659] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"468.488863ms","start":"2026-09-11T00:42:51.57133Z","end":"2026-09-11T00:42:52.039819Z","steps":["trace[717492659] 'process raft request' (duration: 467.799129ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:42:52.039875Z","caller":"traceutil/trace.go:171","msg":"trace[271656188] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"443.443355ms","start":"2026-09-11T00:42:51.59635Z","end":"2026-09-11T00:42:52.039794Z","steps":["trace[271656188] 'process raft request' (duration: 442.871693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:42:52.03993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:51.570829Z","time spent":"468.997905ms","remote":"127.0.0.1:33674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T00:42:52.039823Z","caller":"traceutil/trace.go:171","msg":"trace[139605203] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"430.492424ms","start":"2026-09-11T00:42:51.609235Z","end":"2026-09-11T00:42:52.039727Z","steps":["trace[139605203] 'process raft request' (duration: 430.034604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:42:52.039914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:51.556115Z","time spent":"483.359957ms","remote":"127.0.0.1:33674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-11T00:42:52.040067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:51.596325Z","time spent":"443.65284ms","remote":"127.0.0.1:33674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-11T00:42:52.040105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:51.60921Z","time spent":"430.841471ms","remote":"127.0.0.1:33674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-11T00:42:52.040155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:51.571316Z","time spent":"468.617176ms","remote":"127.0.0.1:33674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-11T00:42:52.040131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:51.572117Z","time spent":"467.665806ms","remote":"127.0.0.1:33674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-11T00:42:52.04023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:51.632105Z","time spent":"408.052631ms","remote":"127.0.0.1:33674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T00:42:52.090594Z","caller":"traceutil/trace.go:171","msg":"trace[1672256214] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"362.341819ms","start":"2026-09-11T00:42:51.728197Z","end":"2026-09-11T00:42:52.090539Z","steps":["trace[1672256214] 'process raft request' (duration: 361.755486ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:42:52.090736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:51.728165Z","time spent":"362.502492ms","remote":"127.0.0.1:33674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T00:42:52.354748Z","caller":"traceutil/trace.go:171","msg":"trace[709019947] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"175.837501ms","start":"2026-09-11T00:42:52.178876Z","end":"2026-09-11T00:42:52.354714Z","steps":["trace[709019947] 'process raft request' (duration: 144.569143ms)","trace[709019947] 'compare' (duration: 31.107484ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:42:52.3671Z","caller":"traceutil/trace.go:171","msg":"trace[1956108690] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"185.544637ms","start":"2026-09-11T00:42:52.181532Z","end":"2026-09-11T00:42:52.367076Z","steps":["trace[1956108690] 'process raft request' (duration: 185.348833ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:42:52.367371Z","caller":"traceutil/trace.go:171","msg":"trace[2105191733] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"184.490404ms","start":"2026-09-11T00:42:52.182842Z","end":"2026-09-11T00:42:52.367332Z","steps":["trace[2105191733] 'process raft request' (duration: 184.195638ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:42:52.367459Z","caller":"traceutil/trace.go:171","msg":"trace[1446086114] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"150.182292ms","start":"2026-09-11T00:42:52.217257Z","end":"2026-09-11T00:42:52.367439Z","steps":["trace[1446086114] 'process raft request' (duration: 150.035509ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:42:53.065017Z","caller":"traceutil/trace.go:171","msg":"trace[161939384] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"103.113261ms","start":"2026-09-11T00:42:52.961878Z","end":"2026-09-11T00:42:53.064991Z","steps":["trace[161939384] 'process raft request' (duration: 59.588434ms)","trace[161939384] 'compare' (duration: 43.302332ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:42:59.385414Z","caller":"traceutil/trace.go:171","msg":"trace[964265397] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"321.339038ms","start":"2026-09-11T00:42:59.064051Z","end":"2026-09-11T00:42:59.38539Z","steps":["trace[964265397] 'read index received' (duration: 320.460418ms)","trace[964265397] 'applied index is now lower than readState.Index' (duration: 877.66µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:42:59.38555Z","caller":"traceutil/trace.go:171","msg":"trace[1910118597] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"351.41953ms","start":"2026-09-11T00:42:59.034101Z","end":"2026-09-11T00:42:59.385521Z","steps":["trace[1910118597] 'process raft request' (duration: 350.654753ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:42:59.385629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.557972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-56cd4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-11T00:42:59.385794Z","caller":"traceutil/trace.go:171","msg":"trace[287159894] range","detail":"{range_begin:/registry/pods/kube-system/cilium-56cd4; range_end:; response_count:1; response_revision:599; }","duration":"321.784097ms","start":"2026-09-11T00:42:59.063993Z","end":"2026-09-11T00:42:59.385777Z","steps":["trace[287159894] 'agreement among raft nodes before linearized reading' (duration: 321.492581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:42:59.385912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:59.034065Z","time spent":"351.588264ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T00:42:59.386059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:59.063972Z","time spent":"321.849029ms","remote":"127.0.0.1:33798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-56cd4\" "} {"level":"info","ts":"2026-09-11T00:42:59.406814Z","caller":"traceutil/trace.go:171","msg":"trace[729446934] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"259.662237ms","start":"2026-09-11T00:42:59.147126Z","end":"2026-09-11T00:42:59.406788Z","steps":["trace[729446934] 'process raft request' (duration: 259.524284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:42:59.961616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.16481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T00:42:59.961714Z","caller":"traceutil/trace.go:171","msg":"trace[1254747682] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"558.179216ms","start":"2026-09-11T00:42:59.403517Z","end":"2026-09-11T00:42:59.961697Z","steps":["trace[1254747682] 'process raft request' (duration: 182.682829ms)","trace[1254747682] 'compare' (duration: 374.833472ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:42:59.961771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:59.403497Z","time spent":"558.246348ms","remote":"127.0.0.1:33798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T00:43:00.013399Z","caller":"traceutil/trace.go:171","msg":"trace[382526116] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"523.240962ms","start":"2026-09-11T00:42:59.490129Z","end":"2026-09-11T00:43:00.01337Z","steps":["trace[382526116] 'read index received' (duration: 96.078333ms)","trace[382526116] 'applied index is now lower than readState.Index' (duration: 427.161299ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:43:00.013451Z","caller":"traceutil/trace.go:171","msg":"trace[281002352] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"533.267899ms","start":"2026-09-11T00:42:59.480152Z","end":"2026-09-11T00:43:00.01342Z","steps":["trace[281002352] 'process raft request' (duration: 533.088585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:43:00.013543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:59.480127Z","time spent":"533.376472ms","remote":"127.0.0.1:34146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-11T00:43:00.013719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.57372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-11T00:43:00.013788Z","caller":"traceutil/trace.go:171","msg":"trace[1629495602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"523.698432ms","start":"2026-09-11T00:42:59.490073Z","end":"2026-09-11T00:43:00.013771Z","steps":["trace[1629495602] 'agreement among raft nodes before linearized reading' (duration: 523.442897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:43:00.013854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:59.490053Z","time spent":"523.771105ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-11T00:43:00.013719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.975774ms","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-09-11T00:43:00.013916Z","caller":"traceutil/trace.go:171","msg":"trace[2139192797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"489.193418ms","start":"2026-09-11T00:42:59.524705Z","end":"2026-09-11T00:43:00.013898Z","steps":["trace[2139192797] 'agreement among raft nodes before linearized reading' (duration: 488.852441ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:43:00.014033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:42:59.524695Z","time spent":"489.323311ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-11T00:43:15.812531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.730386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-11T00:43:15.81268Z","caller":"traceutil/trace.go:171","msg":"trace[741665555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"107.914561ms","start":"2026-09-11T00:43:15.704728Z","end":"2026-09-11T00:43:15.812643Z","steps":["trace[741665555] 'range keys from in-memory index tree' (duration: 107.4928ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:43:44.765757Z","caller":"traceutil/trace.go:171","msg":"trace[1180108587] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"116.889374ms","start":"2026-09-11T00:43:44.64883Z","end":"2026-09-11T00:43:44.76572Z","steps":["trace[1180108587] 'process raft request' (duration: 116.665219ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:33.728665Z","caller":"traceutil/trace.go:171","msg":"trace[1154608500] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"180.230013ms","start":"2026-09-11T00:44:33.548415Z","end":"2026-09-11T00:44:33.728645Z","steps":["trace[1154608500] 'read index received' (duration: 94.314182ms)","trace[1154608500] 'applied index is now lower than readState.Index' (duration: 85.914741ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:44:33.728785Z","caller":"traceutil/trace.go:171","msg":"trace[1430001434] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"245.418983ms","start":"2026-09-11T00:44:33.483316Z","end":"2026-09-11T00:44:33.728735Z","steps":["trace[1430001434] 'process raft request' (duration: 159.517282ms)","trace[1430001434] 'compare' (duration: 85.692306ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:44:33.728891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.462458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d2fe43c3-76b0-4574-a63a-df676e8c5aba\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-11T00:44:33.728954Z","caller":"traceutil/trace.go:171","msg":"trace[1990160047] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d2fe43c3-76b0-4574-a63a-df676e8c5aba; range_end:; response_count:1; response_revision:1837; }","duration":"180.577721ms","start":"2026-09-11T00:44:33.54836Z","end":"2026-09-11T00:44:33.728938Z","steps":["trace[1990160047] 'agreement among raft nodes before linearized reading' (duration: 180.399287ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:34.151814Z","caller":"traceutil/trace.go:171","msg":"trace[780516978] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1912; }","duration":"195.793316ms","start":"2026-09-11T00:44:33.955995Z","end":"2026-09-11T00:44:34.151789Z","steps":["trace[780516978] 'read index received' (duration: 195.646053ms)","trace[780516978] 'applied index is now lower than readState.Index' (duration: 146.013µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:44:34.151929Z","caller":"traceutil/trace.go:171","msg":"trace[1887556200] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"414.771669ms","start":"2026-09-11T00:44:33.737146Z","end":"2026-09-11T00:44:34.151917Z","steps":["trace[1887556200] 'process raft request' (duration: 414.490393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:44:34.152058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:44:33.737125Z","time spent":"414.83653ms","remote":"127.0.0.1:33798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T00:44:34.152161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.157274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:44:34.15225Z","caller":"traceutil/trace.go:171","msg":"trace[1696397195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1838; }","duration":"196.291307ms","start":"2026-09-11T00:44:33.955938Z","end":"2026-09-11T00:44:34.152229Z","steps":["trace[1696397195] 'agreement among raft nodes before linearized reading' (duration: 196.159664ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:34.163406Z","caller":"traceutil/trace.go:171","msg":"trace[1000804937] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"201.6626ms","start":"2026-09-11T00:44:33.961724Z","end":"2026-09-11T00:44:34.163387Z","steps":["trace[1000804937] 'process raft request' (duration: 201.407384ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:34.163389Z","caller":"traceutil/trace.go:171","msg":"trace[1584223491] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"204.953524ms","start":"2026-09-11T00:44:33.958369Z","end":"2026-09-11T00:44:34.163323Z","steps":["trace[1584223491] 'process raft request' (duration: 204.622947ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:47.906683Z","caller":"traceutil/trace.go:171","msg":"trace[1036025178] linearizableReadLoop","detail":"{readStateIndex:2033; appliedIndex:2032; }","duration":"113.918988ms","start":"2026-09-11T00:44:47.792732Z","end":"2026-09-11T00:44:47.906651Z","steps":["trace[1036025178] 'read index received' (duration: 113.652622ms)","trace[1036025178] 'applied index is now lower than readState.Index' (duration: 257.976µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:44:47.906705Z","caller":"traceutil/trace.go:171","msg":"trace[1773484276] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"292.712658ms","start":"2026-09-11T00:44:47.613962Z","end":"2026-09-11T00:44:47.906675Z","steps":["trace[1773484276] 'process raft request' (duration: 292.416751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:44:47.90692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.170693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-11T00:44:47.90698Z","caller":"traceutil/trace.go:171","msg":"trace[434830241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1955; }","duration":"114.286556ms","start":"2026-09-11T00:44:47.792679Z","end":"2026-09-11T00:44:47.906966Z","steps":["trace[434830241] 'agreement among raft nodes before linearized reading' (duration: 114.093662ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:13.05474Z","caller":"traceutil/trace.go:171","msg":"trace[1037266859] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"211.440232ms","start":"2026-09-11T00:45:12.843274Z","end":"2026-09-11T00:45:13.054714Z","steps":["trace[1037266859] 'process raft request' (duration: 211.298829ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:13.084769Z","caller":"traceutil/trace.go:171","msg":"trace[457957352] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2193; }","duration":"218.761608ms","start":"2026-09-11T00:45:12.865987Z","end":"2026-09-11T00:45:13.084749Z","steps":["trace[457957352] 'read index received' (duration: 188.603714ms)","trace[457957352] 'applied index is now lower than readState.Index' (duration: 30.157264ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:45:13.084841Z","caller":"traceutil/trace.go:171","msg":"trace[1088358628] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"167.106894ms","start":"2026-09-11T00:45:12.9177Z","end":"2026-09-11T00:45:13.084807Z","steps":["trace[1088358628] 'process raft request' (duration: 167.015092ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:13.084827Z","caller":"traceutil/trace.go:171","msg":"trace[592160071] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"240.45943ms","start":"2026-09-11T00:45:12.844335Z","end":"2026-09-11T00:45:13.084794Z","steps":["trace[592160071] 'process raft request' (duration: 240.273676ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:45:13.085002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.653644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-11T00:45:13.084999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.996753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-11T00:45:13.085063Z","caller":"traceutil/trace.go:171","msg":"trace[1807497920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2113; }","duration":"129.745706ms","start":"2026-09-11T00:45:12.955302Z","end":"2026-09-11T00:45:13.085048Z","steps":["trace[1807497920] 'agreement among raft nodes before linearized reading' (duration: 129.651104ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:13.085076Z","caller":"traceutil/trace.go:171","msg":"trace[1503113126] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2113; }","duration":"219.113096ms","start":"2026-09-11T00:45:12.865944Z","end":"2026-09-11T00:45:13.085057Z","steps":["trace[1503113126] 'agreement among raft nodes before linearized reading' (duration: 218.967792ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:18.50874Z","caller":"traceutil/trace.go:171","msg":"trace[1383393392] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"134.403822ms","start":"2026-09-11T00:45:18.374304Z","end":"2026-09-11T00:45:18.508708Z","steps":["trace[1383393392] 'process raft request' (duration: 134.112444ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:24.671808Z","caller":"traceutil/trace.go:171","msg":"trace[1520600827] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"233.699277ms","start":"2026-09-11T00:45:24.438081Z","end":"2026-09-11T00:45:24.67178Z","steps":["trace[1520600827] 'process raft request' (duration: 233.524933ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:24.71343Z","caller":"traceutil/trace.go:171","msg":"trace[1092048275] linearizableReadLoop","detail":"{readStateIndex:2258; appliedIndex:2256; }","duration":"159.848379ms","start":"2026-09-11T00:45:24.553566Z","end":"2026-09-11T00:45:24.713414Z","steps":["trace[1092048275] 'read index received' (duration: 118.080452ms)","trace[1092048275] 'applied index is now lower than readState.Index' (duration: 41.767287ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:45:24.713539Z","caller":"traceutil/trace.go:171","msg":"trace[778222522] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"181.321287ms","start":"2026-09-11T00:45:24.53216Z","end":"2026-09-11T00:45:24.713481Z","steps":["trace[778222522] 'process raft request' (duration: 177.302566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:45:24.713669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.075035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-11T00:45:24.713738Z","caller":"traceutil/trace.go:171","msg":"trace[2041927808] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2172; }","duration":"160.205988ms","start":"2026-09-11T00:45:24.553514Z","end":"2026-09-11T00:45:24.71372Z","steps":["trace[2041927808] 'agreement among raft nodes before linearized reading' (duration: 159.994673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:45:24.713674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.311848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-11T00:45:24.713855Z","caller":"traceutil/trace.go:171","msg":"trace[135834005] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2172; }","duration":"144.547693ms","start":"2026-09-11T00:45:24.569286Z","end":"2026-09-11T00:45:24.713833Z","steps":["trace[135834005] 'agreement among raft nodes before linearized reading' (duration: 144.295487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:45:25.133399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.455697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:45:25.13349Z","caller":"traceutil/trace.go:171","msg":"trace[1128165315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2173; }","duration":"176.60927ms","start":"2026-09-11T00:45:24.95686Z","end":"2026-09-11T00:45:25.133469Z","steps":["trace[1128165315] 'range keys from in-memory index tree' (duration: 176.359894ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:25.318083Z","caller":"traceutil/trace.go:171","msg":"trace[1737226833] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"181.579434ms","start":"2026-09-11T00:45:25.136472Z","end":"2026-09-11T00:45:25.318051Z","steps":["trace[1737226833] 'process raft request' (duration: 181.3889ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:25.450143Z","caller":"traceutil/trace.go:171","msg":"trace[1375966028] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"312.21925ms","start":"2026-09-11T00:45:25.137901Z","end":"2026-09-11T00:45:25.45012Z","steps":["trace[1375966028] 'process raft request' (duration: 312.008295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:45:25.450344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:45:25.137877Z","time spent":"312.339802ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T00:45:25.450407Z","caller":"traceutil/trace.go:171","msg":"trace[214094092] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2259; }","duration":"309.11219ms","start":"2026-09-11T00:45:25.141228Z","end":"2026-09-11T00:45:25.45034Z","steps":["trace[214094092] 'read index received' (duration: 176.622451ms)","trace[214094092] 'applied index is now lower than readState.Index' (duration: 132.487708ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:45:25.450453Z","caller":"traceutil/trace.go:171","msg":"trace[1138433695] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"239.190672ms","start":"2026-09-11T00:45:25.211209Z","end":"2026-09-11T00:45:25.450399Z","steps":["trace[1138433695] 'process raft request' (duration: 238.834033ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:45:25.450894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.53739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-11T00:45:25.450986Z","caller":"traceutil/trace.go:171","msg":"trace[474443146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2176; }","duration":"309.816036ms","start":"2026-09-11T00:45:25.141151Z","end":"2026-09-11T00:45:25.450967Z","steps":["trace[474443146] 'agreement among raft nodes before linearized reading' (duration: 309.417567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:45:25.451032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:45:25.141137Z","time spent":"309.882587ms","remote":"127.0.0.1:33864","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-09-11T00:46:17.625172Z","caller":"traceutil/trace.go:171","msg":"trace[662184957] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"105.079715ms","start":"2026-09-11T00:46:17.520068Z","end":"2026-09-11T00:46:17.625148Z","steps":["trace[662184957] 'process raft request' (duration: 104.936891ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:46:49.987776Z","caller":"traceutil/trace.go:171","msg":"trace[2108314193] linearizableReadLoop","detail":"{readStateIndex:2935; appliedIndex:2934; }","duration":"123.645977ms","start":"2026-09-11T00:46:49.864099Z","end":"2026-09-11T00:46:49.987745Z","steps":["trace[2108314193] 'read index received' (duration: 95.859747ms)","trace[2108314193] 'applied index is now lower than readState.Index' (duration: 27.78501ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:46:49.987932Z","caller":"traceutil/trace.go:171","msg":"trace[1536484425] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"133.539671ms","start":"2026-09-11T00:46:49.854374Z","end":"2026-09-11T00:46:49.987913Z","steps":["trace[1536484425] 'process raft request' (duration: 105.598387ms)","trace[1536484425] 'compare' (duration: 27.467784ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:46:49.988254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.137238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-11T00:46:49.988329Z","caller":"traceutil/trace.go:171","msg":"trace[362449421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2825; }","duration":"124.251011ms","start":"2026-09-11T00:46:49.864056Z","end":"2026-09-11T00:46:49.988307Z","steps":["trace[362449421] 'agreement among raft nodes before linearized reading' (duration: 124.033586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:46:49.988521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.058016ms","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-09-11T00:46:49.988635Z","caller":"traceutil/trace.go:171","msg":"trace[1258858588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2825; }","duration":"109.143377ms","start":"2026-09-11T00:46:49.879431Z","end":"2026-09-11T00:46:49.988574Z","steps":["trace[1258858588] 'agreement among raft nodes before linearized reading' (duration: 108.593305ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:46:55.279691Z","caller":"traceutil/trace.go:171","msg":"trace[890358682] transaction","detail":"{read_only:false; response_revision:2856; number_of_response:1; }","duration":"125.381847ms","start":"2026-09-11T00:46:55.154279Z","end":"2026-09-11T00:46:55.279661Z","steps":["trace[890358682] 'process raft request' (duration: 125.175262ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:46:55.312009Z","caller":"traceutil/trace.go:171","msg":"trace[1256245512] transaction","detail":"{read_only:false; response_revision:2857; number_of_response:1; }","duration":"132.933087ms","start":"2026-09-11T00:46:55.179042Z","end":"2026-09-11T00:46:55.311975Z","steps":["trace[1256245512] 'process raft request' (duration: 132.750753ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:49:26.575879Z","caller":"traceutil/trace.go:171","msg":"trace[1626634844] transaction","detail":"{read_only:false; response_revision:3807; number_of_response:1; }","duration":"108.928585ms","start":"2026-09-11T00:49:26.466923Z","end":"2026-09-11T00:49:26.575852Z","steps":["trace[1626634844] 'process raft request' (duration: 108.640968ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:49:26.575984Z","caller":"traceutil/trace.go:171","msg":"trace[1357699813] transaction","detail":"{read_only:false; response_revision:3808; number_of_response:1; }","duration":"108.401553ms","start":"2026-09-11T00:49:26.467555Z","end":"2026-09-11T00:49:26.575956Z","steps":["trace[1357699813] 'process raft request' (duration: 108.221939ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:51:22.742417Z","caller":"traceutil/trace.go:171","msg":"trace[1952171753] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"100.234788ms","start":"2026-09-11T00:51:22.642142Z","end":"2026-09-11T00:51:22.742377Z","steps":["trace[1952171753] 'process raft request' (duration: 87.393764ms)","trace[1952171753] 'compare' (duration: 12.515697ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:51:28.944407Z","caller":"traceutil/trace.go:171","msg":"trace[108538598] linearizableReadLoop","detail":"{readStateIndex:5302; appliedIndex:5301; }","duration":"113.123743ms","start":"2026-09-11T00:51:28.831263Z","end":"2026-09-11T00:51:28.944387Z","steps":["trace[108538598] 'read index received' (duration: 59.205297ms)","trace[108538598] 'applied index is now lower than readState.Index' (duration: 53.917316ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:51:28.944528Z","caller":"traceutil/trace.go:171","msg":"trace[439757546] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"113.673495ms","start":"2026-09-11T00:51:28.830827Z","end":"2026-09-11T00:51:28.944501Z","steps":["trace[439757546] 'process raft request' (duration: 59.627496ms)","trace[439757546] 'compare' (duration: 53.591459ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:51:28.944609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.326757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-11T00:51:28.944644Z","caller":"traceutil/trace.go:171","msg":"trace[48484480] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5102; }","duration":"113.388589ms","start":"2026-09-11T00:51:28.831243Z","end":"2026-09-11T00:51:28.944632Z","steps":["trace[48484480] 'agreement among raft nodes before linearized reading' (duration: 113.269947ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:51:30.063019Z","caller":"traceutil/trace.go:171","msg":"trace[531511894] linearizableReadLoop","detail":"{readStateIndex:5342; appliedIndex:5339; }","duration":"109.420318ms","start":"2026-09-11T00:51:29.953581Z","end":"2026-09-11T00:51:30.063001Z","steps":["trace[531511894] 'read index received' (duration: 53.60901ms)","trace[531511894] 'applied index is now lower than readState.Index' (duration: 55.810358ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:51:30.063012Z","caller":"traceutil/trace.go:171","msg":"trace[442628236] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"116.06007ms","start":"2026-09-11T00:51:29.94692Z","end":"2026-09-11T00:51:30.06298Z","steps":["trace[442628236] 'process raft request' (duration: 60.246741ms)","trace[442628236] 'compare' (duration: 55.622984ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:51:30.063108Z","caller":"traceutil/trace.go:171","msg":"trace[947058722] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"112.778696ms","start":"2026-09-11T00:51:29.9503Z","end":"2026-09-11T00:51:30.063079Z","steps":["trace[947058722] 'process raft request' (duration: 112.666812ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:51:30.063101Z","caller":"traceutil/trace.go:171","msg":"trace[2068595568] transaction","detail":"{read_only:false; response_revision:5140; number_of_response:1; }","duration":"115.623812ms","start":"2026-09-11T00:51:29.94744Z","end":"2026-09-11T00:51:30.063064Z","steps":["trace[2068595568] 'process raft request' (duration: 115.476047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:51:30.063183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.581053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:51:30.063243Z","caller":"traceutil/trace.go:171","msg":"trace[716119870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5141; }","duration":"109.677265ms","start":"2026-09-11T00:51:29.953552Z","end":"2026-09-11T00:51:30.063229Z","steps":["trace[716119870] 'agreement among raft nodes before linearized reading' (duration: 109.527762ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:51:32.930638Z","caller":"traceutil/trace.go:171","msg":"trace[1439390121] linearizableReadLoop","detail":"{readStateIndex:5447; appliedIndex:5446; }","duration":"192.684108ms","start":"2026-09-11T00:51:32.737929Z","end":"2026-09-11T00:51:32.930613Z","steps":["trace[1439390121] 'read index received' (duration: 192.439692ms)","trace[1439390121] 'applied index is now lower than readState.Index' (duration: 243.286µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:51:32.931191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.2389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787144"} {"level":"info","ts":"2026-09-11T00:51:32.931267Z","caller":"traceutil/trace.go:171","msg":"trace[311196005] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5243; }","duration":"193.350132ms","start":"2026-09-11T00:51:32.737901Z","end":"2026-09-11T00:51:32.931251Z","steps":["trace[311196005] 'agreement among raft nodes before linearized reading' (duration: 192.845271ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:51:32.931331Z","caller":"traceutil/trace.go:171","msg":"trace[797486225] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"197.312984ms","start":"2026-09-11T00:51:32.733378Z","end":"2026-09-11T00:51:32.930691Z","steps":["trace[797486225] 'process raft request' (duration: 196.989626ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:51:33.136857Z","caller":"traceutil/trace.go:171","msg":"trace[2061291261] linearizableReadLoop","detail":"{readStateIndex:5449; appliedIndex:5447; }","duration":"206.098785ms","start":"2026-09-11T00:51:32.930731Z","end":"2026-09-11T00:51:33.13683Z","steps":["trace[2061291261] 'read index received' (duration: 143.335436ms)","trace[2061291261] 'applied index is now lower than readState.Index' (duration: 62.762389ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:51:33.137006Z","caller":"traceutil/trace.go:171","msg":"trace[119162343] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"300.165652ms","start":"2026-09-11T00:51:32.836788Z","end":"2026-09-11T00:51:33.136954Z","steps":["trace[119162343] 'process raft request' (duration: 299.978508ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:51:33.137006Z","caller":"traceutil/trace.go:171","msg":"trace[757666030] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"312.193428ms","start":"2026-09-11T00:51:32.824764Z","end":"2026-09-11T00:51:33.136957Z","steps":["trace[757666030] 'process raft request' (duration: 249.380237ms)","trace[757666030] 'compare' (duration: 62.307419ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:51:33.137105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.809682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-09-11T00:51:33.137148Z","caller":"traceutil/trace.go:171","msg":"trace[1774527346] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5245; }","duration":"271.895153ms","start":"2026-09-11T00:51:32.865241Z","end":"2026-09-11T00:51:33.137137Z","steps":["trace[1774527346] 'agreement among raft nodes before linearized reading' (duration: 271.733639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:51:33.137194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:51:32.836764Z","time spent":"300.324005ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T00:51:33.137182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.46974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-82ee8c0a-5ad9-4e73-aae8-52cb444a51ea\" ","response":"range_response_count:1 size:3721"} {"level":"warn","ts":"2026-09-11T00:51:33.137219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:51:32.824739Z","time spent":"312.360832ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T00:51:33.137277Z","caller":"traceutil/trace.go:171","msg":"trace[1277118903] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-82ee8c0a-5ad9-4e73-aae8-52cb444a51ea; range_end:; response_count:1; response_revision:5245; }","duration":"311.609844ms","start":"2026-09-11T00:51:32.825642Z","end":"2026-09-11T00:51:33.137252Z","steps":["trace[1277118903] 'agreement among raft nodes before linearized reading' (duration: 311.378078ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:51:33.137333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:51:32.825626Z","time spent":"311.696685ms","remote":"127.0.0.1:33798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3744,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-82ee8c0a-5ad9-4e73-aae8-52cb444a51ea\" "} {"level":"warn","ts":"2026-09-11T00:51:33.137413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.433911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-11T00:51:33.137472Z","caller":"traceutil/trace.go:171","msg":"trace[1590187152] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5245; }","duration":"178.533493ms","start":"2026-09-11T00:51:32.958924Z","end":"2026-09-11T00:51:33.137458Z","steps":["trace[1590187152] 'agreement among raft nodes before linearized reading' (duration: 178.100073ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:51:47.743678Z","caller":"traceutil/trace.go:171","msg":"trace[2062533064] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"155.423684ms","start":"2026-09-11T00:51:47.588226Z","end":"2026-09-11T00:51:47.74365Z","steps":["trace[2062533064] 'process raft request' (duration: 155.22146ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:51:47.862089Z","caller":"traceutil/trace.go:171","msg":"trace[205902754] linearizableReadLoop","detail":"{readStateIndex:5715; appliedIndex:5714; }","duration":"113.548293ms","start":"2026-09-11T00:51:47.748519Z","end":"2026-09-11T00:51:47.862067Z","steps":["trace[205902754] 'read index received' (duration: 74.052778ms)","trace[205902754] 'applied index is now lower than readState.Index' (duration: 39.494605ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:51:47.862178Z","caller":"traceutil/trace.go:171","msg":"trace[72318470] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"113.974963ms","start":"2026-09-11T00:51:47.748126Z","end":"2026-09-11T00:51:47.862101Z","steps":["trace[72318470] 'process raft request' (duration: 74.433456ms)","trace[72318470] 'compare' (duration: 39.403333ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:51:47.862367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.829819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70794"} {"level":"info","ts":"2026-09-11T00:51:47.862683Z","caller":"traceutil/trace.go:171","msg":"trace[1136474257] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5503; }","duration":"114.154396ms","start":"2026-09-11T00:51:47.748496Z","end":"2026-09-11T00:51:47.86265Z","steps":["trace[1136474257] 'agreement among raft nodes before linearized reading' (duration: 113.667265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:03.583864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.187454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T00:52:03.584115Z","caller":"traceutil/trace.go:171","msg":"trace[825676289] linearizableReadLoop","detail":"{readStateIndex:5991; appliedIndex:5989; }","duration":"242.507829ms","start":"2026-09-11T00:52:03.341586Z","end":"2026-09-11T00:52:03.584094Z","steps":["trace[825676289] 'read index received' (duration: 54.032348ms)","trace[825676289] 'applied index is now lower than readState.Index' (duration: 188.474191ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:52:03.584227Z","caller":"traceutil/trace.go:171","msg":"trace[264493172] transaction","detail":"{read_only:false; response_revision:5773; number_of_response:1; }","duration":"281.891561ms","start":"2026-09-11T00:52:03.302321Z","end":"2026-09-11T00:52:03.584212Z","steps":["trace[264493172] 'process raft request' (duration: 281.668906ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:52:03.58421Z","caller":"traceutil/trace.go:171","msg":"trace[1586369178] transaction","detail":"{read_only:false; response_revision:5772; number_of_response:1; }","duration":"284.014131ms","start":"2026-09-11T00:52:03.300139Z","end":"2026-09-11T00:52:03.584153Z","steps":["trace[1586369178] 'process raft request' (duration: 95.451888ms)","trace[1586369178] 'compare' (duration: 187.889888ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:52:03.584782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.147715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-11T00:52:03.584882Z","caller":"traceutil/trace.go:171","msg":"trace[710441004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5773; }","duration":"243.322289ms","start":"2026-09-11T00:52:03.341537Z","end":"2026-09-11T00:52:03.584859Z","steps":["trace[710441004] 'agreement among raft nodes before linearized reading' (duration: 242.839398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:03.584807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.2921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-72635cf7-b887-4eb7-b7e0-9bc9cc18d22f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-11T00:52:03.584999Z","caller":"traceutil/trace.go:171","msg":"trace[971210931] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-72635cf7-b887-4eb7-b7e0-9bc9cc18d22f; range_end:; response_count:1; response_revision:5773; }","duration":"141.531106ms","start":"2026-09-11T00:52:03.443448Z","end":"2026-09-11T00:52:03.584979Z","steps":["trace[971210931] 'agreement among raft nodes before linearized reading' (duration: 140.895161ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:52:03.898488Z","caller":"traceutil/trace.go:171","msg":"trace[1000793352] transaction","detail":"{read_only:false; response_revision:5774; number_of_response:1; }","duration":"309.043335ms","start":"2026-09-11T00:52:03.589409Z","end":"2026-09-11T00:52:03.898452Z","steps":["trace[1000793352] 'process raft request' (duration: 263.798278ms)","trace[1000793352] 'compare' (duration: 44.951441ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:52:03.898795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:52:03.589386Z","time spent":"309.21949ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T00:52:12.92633Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2954} {"level":"info","ts":"2026-09-11T00:52:13.010878Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2954,"took":"83.105504ms","hash":2821481793,"current-db-size-bytes":57671680,"current-db-size":"58 MB","current-db-size-in-use-bytes":32448512,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-11T00:52:13.010942Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2821481793,"revision":2954,"compact-revision":-1} {"level":"warn","ts":"2026-09-11T00:52:47.665013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.511687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:52:47.665112Z","caller":"traceutil/trace.go:171","msg":"trace[800617782] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:6173; }","duration":"127.664281ms","start":"2026-09-11T00:52:47.537427Z","end":"2026-09-11T00:52:47.665092Z","steps":["trace[800617782] 'range keys from in-memory index tree' (duration: 127.409876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:47.665152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.06005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:52:47.665213Z","caller":"traceutil/trace.go:171","msg":"trace[2126098223] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:6173; }","duration":"128.153772ms","start":"2026-09-11T00:52:47.537043Z","end":"2026-09-11T00:52:47.665197Z","steps":["trace[2126098223] 'range keys from in-memory index tree' (duration: 127.994658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:47.665219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.28133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"info","ts":"2026-09-11T00:52:47.665371Z","caller":"traceutil/trace.go:171","msg":"trace[1530025264] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:6173; }","duration":"123.469475ms","start":"2026-09-11T00:52:47.541876Z","end":"2026-09-11T00:52:47.665345Z","steps":["trace[1530025264] 'range keys from in-memory index tree' (duration: 123.148558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:47.665274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.184011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-09-11T00:52:47.665534Z","caller":"traceutil/trace.go:171","msg":"trace[301260254] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:6173; }","duration":"142.499588ms","start":"2026-09-11T00:52:47.523007Z","end":"2026-09-11T00:52:47.665507Z","steps":["trace[301260254] 'range keys from in-memory index tree' (duration: 142.000276ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:56:47.025536Z","caller":"traceutil/trace.go:171","msg":"trace[971183162] transaction","detail":"{read_only:false; response_revision:7827; number_of_response:1; }","duration":"183.983953ms","start":"2026-09-11T00:56:46.84153Z","end":"2026-09-11T00:56:47.025514Z","steps":["trace[971183162] 'process raft request' (duration: 134.428844ms)","trace[971183162] 'compare' (duration: 49.476117ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:57:12.932619Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5856} {"level":"info","ts":"2026-09-11T00:57:13.026798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5856,"took":"92.487468ms","hash":3606252555,"current-db-size-bytes":57671680,"current-db-size":"58 MB","current-db-size-in-use-bytes":26238976,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-11T00:57:13.02687Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3606252555,"revision":5856,"compact-revision":2954} {"level":"info","ts":"2026-09-11T00:58:29.006242Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002300.wal"} {"level":"warn","ts":"2026-09-11T00:58:31.784053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.086035ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-11T00:58:31.869639Z","caller":"traceutil/trace.go:171","msg":"trace[286570842] transaction","detail":"{read_only:false; response_revision:8657; number_of_response:1; }","duration":"499.327009ms","start":"2026-09-11T00:58:31.370275Z","end":"2026-09-11T00:58:31.869602Z","steps":["trace[286570842] 'process raft request' (duration: 499.087274ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:58:31.869824Z","caller":"traceutil/trace.go:171","msg":"trace[2102196068] linearizableReadLoop","detail":"{readStateIndex:8971; appliedIndex:8969; }","duration":"209.405043ms","start":"2026-09-11T00:58:31.660397Z","end":"2026-09-11T00:58:31.869802Z","steps":["trace[2102196068] 'read index received' (duration: 208.923722ms)","trace[2102196068] 'applied index is now lower than readState.Index' (duration: 479.391µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:58:31.869839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:58:31.370242Z","time spent":"499.492422ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T00:58:31.869952Z","caller":"traceutil/trace.go:171","msg":"trace[256625217] transaction","detail":"{read_only:false; response_revision:8658; number_of_response:1; }","duration":"379.179211ms","start":"2026-09-11T00:58:31.490708Z","end":"2026-09-11T00:58:31.869887Z","steps":["trace[256625217] 'process raft request' (duration: 378.833043ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:58:31.869981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.563346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-11T00:58:31.869995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.840194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:58:31.870064Z","caller":"traceutil/trace.go:171","msg":"trace[1264742396] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:8658; }","duration":"209.706299ms","start":"2026-09-11T00:58:31.660336Z","end":"2026-09-11T00:58:31.870042Z","steps":["trace[1264742396] 'agreement among raft nodes before linearized reading' (duration: 209.538945ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:58:31.87009Z","caller":"traceutil/trace.go:171","msg":"trace[1611594756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8658; }","duration":"181.976517ms","start":"2026-09-11T00:58:31.688099Z","end":"2026-09-11T00:58:31.870075Z","steps":["trace[1611594756] 'agreement among raft nodes before linearized reading' (duration: 181.842944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:58:31.870107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:58:31.490686Z","time spent":"379.347945ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T00:58:44.166968Z","caller":"traceutil/trace.go:171","msg":"trace[668084544] transaction","detail":"{read_only:false; response_revision:8719; number_of_response:1; }","duration":"255.920153ms","start":"2026-09-11T00:58:43.911022Z","end":"2026-09-11T00:58:44.166942Z","steps":["trace[668084544] 'process raft request' (duration: 255.719938ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:58:44.185006Z","caller":"traceutil/trace.go:171","msg":"trace[41563728] linearizableReadLoop","detail":"{readStateIndex:9035; appliedIndex:9033; }","duration":"182.746134ms","start":"2026-09-11T00:58:44.002228Z","end":"2026-09-11T00:58:44.184975Z","steps":["trace[41563728] 'read index received' (duration: 164.55607ms)","trace[41563728] 'applied index is now lower than readState.Index' (duration: 18.188514ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:58:44.185072Z","caller":"traceutil/trace.go:171","msg":"trace[1811484531] transaction","detail":"{read_only:false; response_revision:8720; number_of_response:1; }","duration":"255.778529ms","start":"2026-09-11T00:58:43.929267Z","end":"2026-09-11T00:58:44.185046Z","steps":["trace[1811484531] 'process raft request' (duration: 255.39264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:58:44.185313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.055921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-11T00:58:44.1854Z","caller":"traceutil/trace.go:171","msg":"trace[1872048933] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8721; }","duration":"183.208174ms","start":"2026-09-11T00:58:44.002169Z","end":"2026-09-11T00:58:44.185377Z","steps":["trace[1872048933] 'agreement among raft nodes before linearized reading' (duration: 182.919758ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:58:44.192658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.995959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-11T00:58:44.192729Z","caller":"traceutil/trace.go:171","msg":"trace[2079980354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8722; }","duration":"150.118211ms","start":"2026-09-11T00:58:44.042597Z","end":"2026-09-11T00:58:44.192715Z","steps":["trace[2079980354] 'agreement among raft nodes before linearized reading' (duration: 149.920966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:58:44.192894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.016399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6d98b6c979-vgvv4.18d41e5562c5f0a7\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-09-11T00:58:44.193012Z","caller":"traceutil/trace.go:171","msg":"trace[654944666] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6d98b6c979-vgvv4.18d41e5562c5f0a7; range_end:; response_count:1; response_revision:8722; }","duration":"139.194283ms","start":"2026-09-11T00:58:44.053793Z","end":"2026-09-11T00:58:44.192987Z","steps":["trace[654944666] 'agreement among raft nodes before linearized reading' (duration: 138.870446ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:58:45.053984Z","caller":"traceutil/trace.go:171","msg":"trace[1472021796] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"241.865221ms","start":"2026-09-11T00:58:44.812091Z","end":"2026-09-11T00:58:45.053956Z","steps":["trace[1472021796] 'process raft request' (duration: 241.596405ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:14.334281Z","caller":"traceutil/trace.go:171","msg":"trace[345796645] transaction","detail":"{read_only:false; response_revision:8933; number_of_response:1; }","duration":"278.451025ms","start":"2026-09-11T00:59:14.055805Z","end":"2026-09-11T00:59:14.334256Z","steps":["trace[345796645] 'process raft request' (duration: 278.291302ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:17.132931Z","caller":"traceutil/trace.go:171","msg":"trace[708901497] linearizableReadLoop","detail":"{readStateIndex:9277; appliedIndex:9276; }","duration":"188.23426ms","start":"2026-09-11T00:59:16.944649Z","end":"2026-09-11T00:59:17.132883Z","steps":["trace[708901497] 'read index received' (duration: 188.033305ms)","trace[708901497] 'applied index is now lower than readState.Index' (duration: 200.095µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:59:17.133225Z","caller":"traceutil/trace.go:171","msg":"trace[1851382485] transaction","detail":"{read_only:false; response_revision:8952; number_of_response:1; }","duration":"258.250756ms","start":"2026-09-11T00:59:16.874956Z","end":"2026-09-11T00:59:17.133207Z","steps":["trace[1851382485] 'process raft request' (duration: 257.790455ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:59:17.133561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.894425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-11T00:59:17.133571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.749424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:59:17.133605Z","caller":"traceutil/trace.go:171","msg":"trace[380522275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8952; }","duration":"188.977547ms","start":"2026-09-11T00:59:16.944616Z","end":"2026-09-11T00:59:17.133593Z","steps":["trace[380522275] 'agreement among raft nodes before linearized reading' (duration: 188.825284ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:17.133619Z","caller":"traceutil/trace.go:171","msg":"trace[986044015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8952; }","duration":"178.839096ms","start":"2026-09-11T00:59:16.954765Z","end":"2026-09-11T00:59:17.133604Z","steps":["trace[986044015] 'agreement among raft nodes before linearized reading' (duration: 178.762884ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:18.460739Z","caller":"traceutil/trace.go:171","msg":"trace[313540030] transaction","detail":"{read_only:false; response_revision:8954; number_of_response:1; }","duration":"107.864807ms","start":"2026-09-11T00:59:18.35285Z","end":"2026-09-11T00:59:18.460715Z","steps":["trace[313540030] 'process raft request' (duration: 107.728394ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:18.46854Z","caller":"traceutil/trace.go:171","msg":"trace[829296747] transaction","detail":"{read_only:false; response_revision:8956; number_of_response:1; }","duration":"103.357125ms","start":"2026-09-11T00:59:18.365167Z","end":"2026-09-11T00:59:18.468524Z","steps":["trace[829296747] 'process raft request' (duration: 103.290154ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:18.468612Z","caller":"traceutil/trace.go:171","msg":"trace[1604553295] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"104.126663ms","start":"2026-09-11T00:59:18.364452Z","end":"2026-09-11T00:59:18.468578Z","steps":["trace[1604553295] 'process raft request' (duration: 103.920848ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:26.589657Z","caller":"traceutil/trace.go:171","msg":"trace[644349576] transaction","detail":"{read_only:false; response_revision:8998; number_of_response:1; }","duration":"252.094745ms","start":"2026-09-11T00:59:26.337531Z","end":"2026-09-11T00:59:26.589625Z","steps":["trace[644349576] 'process raft request' (duration: 174.892056ms)","trace[644349576] 'compare' (duration: 76.751219ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:59:26.662403Z","caller":"traceutil/trace.go:171","msg":"trace[2018976030] transaction","detail":"{read_only:false; response_revision:8999; number_of_response:1; }","duration":"167.325743ms","start":"2026-09-11T00:59:26.495045Z","end":"2026-09-11T00:59:26.662371Z","steps":["trace[2018976030] 'process raft request' (duration: 167.19345ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:26.672144Z","caller":"traceutil/trace.go:171","msg":"trace[30417025] linearizableReadLoop","detail":"{readStateIndex:9329; appliedIndex:9326; }","duration":"121.305305ms","start":"2026-09-11T00:59:26.55081Z","end":"2026-09-11T00:59:26.672115Z","steps":["trace[30417025] 'read index received' (duration: 111.797248ms)","trace[30417025] 'applied index is now lower than readState.Index' (duration: 9.506917ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:59:26.67247Z","caller":"traceutil/trace.go:171","msg":"trace[2028696318] transaction","detail":"{read_only:false; response_revision:9000; number_of_response:1; }","duration":"129.392158ms","start":"2026-09-11T00:59:26.543064Z","end":"2026-09-11T00:59:26.672456Z","steps":["trace[2028696318] 'process raft request' (duration: 128.823535ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:26.672653Z","caller":"traceutil/trace.go:171","msg":"trace[779437792] transaction","detail":"{read_only:false; response_revision:9001; number_of_response:1; }","duration":"128.921477ms","start":"2026-09-11T00:59:26.543722Z","end":"2026-09-11T00:59:26.672644Z","steps":["trace[779437792] 'process raft request' (duration: 128.318964ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:26.672823Z","caller":"traceutil/trace.go:171","msg":"trace[1227128267] transaction","detail":"{read_only:false; response_revision:9002; number_of_response:1; }","duration":"127.313691ms","start":"2026-09-11T00:59:26.545496Z","end":"2026-09-11T00:59:26.67281Z","steps":["trace[1227128267] 'process raft request' (duration: 126.582095ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:59:26.673194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.353308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-11T00:59:26.673296Z","caller":"traceutil/trace.go:171","msg":"trace[2066460397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9002; }","duration":"122.502241ms","start":"2026-09-11T00:59:26.550766Z","end":"2026-09-11T00:59:26.673268Z","steps":["trace[2066460397] 'agreement among raft nodes before linearized reading' (duration: 122.210575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:59:27.403323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.469546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T00:59:27.403437Z","caller":"traceutil/trace.go:171","msg":"trace[405148718] transaction","detail":"{read_only:false; response_revision:9008; number_of_response:1; }","duration":"183.026152ms","start":"2026-09-11T00:59:27.22039Z","end":"2026-09-11T00:59:27.403416Z","steps":["trace[405148718] 'process raft request' (duration: 80.377243ms)","trace[405148718] 'compare' (duration: 102.344372ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:59:40.098327Z","caller":"traceutil/trace.go:171","msg":"trace[1443493390] transaction","detail":"{read_only:false; response_revision:9149; number_of_response:1; }","duration":"114.684644ms","start":"2026-09-11T00:59:39.983617Z","end":"2026-09-11T00:59:40.098301Z","steps":["trace[1443493390] 'process raft request' (duration: 114.53238ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:00:29.724883Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"299bf3744060cbd","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-11T01:00:29.735409Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-11T01:00:29.735491Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-11T01:00:43.889591Z","caller":"traceutil/trace.go:171","msg":"trace[1793843776] transaction","detail":"{read_only:false; response_revision:9794; number_of_response:1; }","duration":"105.550035ms","start":"2026-09-11T01:00:43.784017Z","end":"2026-09-11T01:00:43.889567Z","steps":["trace[1793843776] 'process raft request' (duration: 105.380772ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:02:12.943237Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8010} {"level":"info","ts":"2026-09-11T01:02:13.018065Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8010,"took":"72.519401ms","hash":1376092945,"current-db-size-bytes":57671680,"current-db-size":"58 MB","current-db-size-in-use-bytes":28278784,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-11T01:02:13.01816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1376092945,"revision":8010,"compact-revision":5856} {"level":"warn","ts":"2026-09-11T01:06:16.799916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.625033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T01:06:16.800159Z","caller":"traceutil/trace.go:171","msg":"trace[1681644126] linearizableReadLoop","detail":"{readStateIndex:12620; appliedIndex:12618; }","duration":"364.088085ms","start":"2026-09-11T01:06:16.436053Z","end":"2026-09-11T01:06:16.800141Z","steps":["trace[1681644126] 'read index received' (duration: 76.141355ms)","trace[1681644126] 'applied index is now lower than readState.Index' (duration: 287.94579ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:06:16.800243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.192167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T01:06:16.800276Z","caller":"traceutil/trace.go:171","msg":"trace[549098714] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:12194; }","duration":"364.29102ms","start":"2026-09-11T01:06:16.435976Z","end":"2026-09-11T01:06:16.800267Z","steps":["trace[549098714] 'agreement among raft nodes before linearized reading' (duration: 364.214188ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:06:16.800246Z","caller":"traceutil/trace.go:171","msg":"trace[1263253357] transaction","detail":"{read_only:false; response_revision:12193; number_of_response:1; }","duration":"486.904811ms","start":"2026-09-11T01:06:16.313299Z","end":"2026-09-11T01:06:16.800203Z","steps":["trace[1263253357] 'process raft request' (duration: 198.887349ms)","trace[1263253357] 'compare' (duration: 287.291525ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:06:16.800316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:06:16.435958Z","time spent":"364.348161ms","remote":"127.0.0.1:33758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-09-11T01:06:16.800446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:06:16.313275Z","time spent":"487.076004ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T01:06:16.800274Z","caller":"traceutil/trace.go:171","msg":"trace[323263183] transaction","detail":"{read_only:false; response_revision:12194; number_of_response:1; }","duration":"413.536829ms","start":"2026-09-11T01:06:16.386707Z","end":"2026-09-11T01:06:16.800244Z","steps":["trace[323263183] 'process raft request' (duration: 413.349284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:06:16.800665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:06:16.386682Z","time spent":"413.947208ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T01:06:37.090342Z","caller":"traceutil/trace.go:171","msg":"trace[1140657746] transaction","detail":"{read_only:false; response_revision:12307; number_of_response:1; }","duration":"222.92418ms","start":"2026-09-11T01:06:36.867389Z","end":"2026-09-11T01:06:37.090313Z","steps":["trace[1140657746] 'process raft request' (duration: 222.746836ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:06:37.163946Z","caller":"traceutil/trace.go:171","msg":"trace[1660851845] transaction","detail":"{read_only:false; response_revision:12308; number_of_response:1; }","duration":"284.818248ms","start":"2026-09-11T01:06:36.879103Z","end":"2026-09-11T01:06:37.163921Z","steps":["trace[1660851845] 'process raft request' (duration: 284.635454ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:06:37.334027Z","caller":"traceutil/trace.go:171","msg":"trace[1603154727] transaction","detail":"{read_only:false; response_revision:12311; number_of_response:1; }","duration":"122.227562ms","start":"2026-09-11T01:06:37.21173Z","end":"2026-09-11T01:06:37.333958Z","steps":["trace[1603154727] 'process raft request' (duration: 64.277433ms)","trace[1603154727] 'compare' (duration: 57.822646ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:07:12.952903Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10379} {"level":"info","ts":"2026-09-11T01:07:13.074454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10379,"took":"119.192552ms","hash":759567663,"current-db-size-bytes":57671680,"current-db-size":"58 MB","current-db-size-in-use-bytes":25980928,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-11T01:07:13.074574Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":759567663,"revision":10379,"compact-revision":8010} {"level":"info","ts":"2026-09-11T01:12:12.95984Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12563} {"level":"info","ts":"2026-09-11T01:12:13.04915Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12563,"took":"86.740746ms","hash":3358574806,"current-db-size-bytes":57671680,"current-db-size":"58 MB","current-db-size-in-use-bytes":27586560,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-11T01:12:13.049207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3358574806,"revision":12563,"compact-revision":10379} {"level":"warn","ts":"2026-09-11T01:13:11.976202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.352036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-11T01:13:11.976216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.18691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-11T01:13:11.976303Z","caller":"traceutil/trace.go:171","msg":"trace[507295420] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:14934; }","duration":"185.493039ms","start":"2026-09-11T01:13:11.79079Z","end":"2026-09-11T01:13:11.976283Z","steps":["trace[507295420] 'count revisions from in-memory index tree' (duration: 185.272364ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:13:11.976321Z","caller":"traceutil/trace.go:171","msg":"trace[1668854735] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14934; }","duration":"206.340664ms","start":"2026-09-11T01:13:11.769955Z","end":"2026-09-11T01:13:11.976296Z","steps":["trace[1668854735] 'range keys from in-memory index tree' (duration: 205.993745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:13:28.480881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.091484ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T01:13:28.481004Z","caller":"traceutil/trace.go:171","msg":"trace[1716941155] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15037; }","duration":"126.233017ms","start":"2026-09-11T01:13:28.354751Z","end":"2026-09-11T01:13:28.480984Z","steps":["trace[1716941155] 'range keys from in-memory index tree' (duration: 126.074824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:13:28.481285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.00252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-hmzkv\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-09-11T01:13:28.48143Z","caller":"traceutil/trace.go:171","msg":"trace[231115779] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-hmzkv; range_end:; response_count:1; response_revision:15037; }","duration":"104.226985ms","start":"2026-09-11T01:13:28.377175Z","end":"2026-09-11T01:13:28.481402Z","steps":["trace[231115779] 'range keys from in-memory index tree' (duration: 103.653813ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:13:29.489852Z","caller":"traceutil/trace.go:171","msg":"trace[1219425845] transaction","detail":"{read_only:false; response_revision:15041; number_of_response:1; }","duration":"358.370848ms","start":"2026-09-11T01:13:29.131453Z","end":"2026-09-11T01:13:29.489824Z","steps":["trace[1219425845] 'process raft request' (duration: 358.057581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:13:29.490058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:13:29.131421Z","time spent":"358.524062ms","remote":"127.0.0.1:59940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":416359,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T01:13:29.490555Z","caller":"traceutil/trace.go:171","msg":"trace[1592647140] linearizableReadLoop","detail":"{readStateIndex:15569; appliedIndex:15567; }","duration":"102.044556ms","start":"2026-09-11T01:13:29.38845Z","end":"2026-09-11T01:13:29.490494Z","steps":["trace[1592647140] 'read index received' (duration: 100.986781ms)","trace[1592647140] 'applied index is now lower than readState.Index' (duration: 1.056035ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:13:29.490638Z","caller":"traceutil/trace.go:171","msg":"trace[80900361] transaction","detail":"{read_only:false; response_revision:15042; number_of_response:1; }","duration":"157.375987ms","start":"2026-09-11T01:13:29.333229Z","end":"2026-09-11T01:13:29.490605Z","steps":["trace[80900361] 'process raft request' (duration: 157.084951ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:13:29.490873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.398725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cv9mf\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-11T01:13:29.490938Z","caller":"traceutil/trace.go:171","msg":"trace[1773018316] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cv9mf; range_end:; response_count:1; response_revision:15042; }","duration":"102.519017ms","start":"2026-09-11T01:13:29.388402Z","end":"2026-09-11T01:13:29.490921Z","steps":["trace[1773018316] 'agreement among raft nodes before linearized reading' (duration: 102.276072ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:13:40.106315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.828045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T01:13:40.106438Z","caller":"traceutil/trace.go:171","msg":"trace[994029108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15219; }","duration":"149.991768ms","start":"2026-09-11T01:13:39.956424Z","end":"2026-09-11T01:13:40.106416Z","steps":["trace[994029108] 'range keys from in-memory index tree' (duration: 149.730552ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:13:40.268204Z","caller":"traceutil/trace.go:171","msg":"trace[1379267659] transaction","detail":"{read_only:false; response_revision:15220; number_of_response:1; }","duration":"167.427087ms","start":"2026-09-11T01:13:40.100751Z","end":"2026-09-11T01:13:40.268178Z","steps":["trace[1379267659] 'process raft request' (duration: 167.246733ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:13:40.348998Z","caller":"traceutil/trace.go:171","msg":"trace[364390974] linearizableReadLoop","detail":"{readStateIndex:15752; appliedIndex:15749; }","duration":"229.966042ms","start":"2026-09-11T01:13:40.119008Z","end":"2026-09-11T01:13:40.348974Z","steps":["trace[364390974] 'read index received' (duration: 148.983166ms)","trace[364390974] 'applied index is now lower than readState.Index' (duration: 80.981876ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:13:40.349127Z","caller":"traceutil/trace.go:171","msg":"trace[1227495064] transaction","detail":"{read_only:false; response_revision:15222; number_of_response:1; }","duration":"239.998811ms","start":"2026-09-11T01:13:40.109082Z","end":"2026-09-11T01:13:40.349081Z","steps":["trace[1227495064] 'process raft request' (duration: 239.828187ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:13:40.349161Z","caller":"traceutil/trace.go:171","msg":"trace[1540994610] transaction","detail":"{read_only:false; response_revision:15221; number_of_response:1; }","duration":"243.198794ms","start":"2026-09-11T01:13:40.105911Z","end":"2026-09-11T01:13:40.349109Z","steps":["trace[1540994610] 'process raft request' (duration: 205.919663ms)","trace[1540994610] 'compare' (duration: 36.960033ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:13:40.349219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.192416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-11T01:13:40.349259Z","caller":"traceutil/trace.go:171","msg":"trace[798722610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15222; }","duration":"230.294369ms","start":"2026-09-11T01:13:40.118954Z","end":"2026-09-11T01:13:40.349248Z","steps":["trace[798722610] 'agreement among raft nodes before linearized reading' (duration: 230.131615ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:14:48.128624Z","caller":"traceutil/trace.go:171","msg":"trace[36893502] transaction","detail":"{read_only:false; response_revision:16132; number_of_response:1; }","duration":"151.336168ms","start":"2026-09-11T01:14:47.977264Z","end":"2026-09-11T01:14:48.1286Z","steps":["trace[36893502] 'process raft request' (duration: 151.182404ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:14:48.128848Z","caller":"traceutil/trace.go:171","msg":"trace[1607647868] linearizableReadLoop","detail":"{readStateIndex:16691; appliedIndex:16690; }","duration":"135.682501ms","start":"2026-09-11T01:14:47.993141Z","end":"2026-09-11T01:14:48.128824Z","steps":["trace[1607647868] 'read index received' (duration: 135.222061ms)","trace[1607647868] 'applied index is now lower than readState.Index' (duration: 459.29µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:14:48.129125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.959768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-11T01:14:48.129208Z","caller":"traceutil/trace.go:171","msg":"trace[1777464552] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16132; }","duration":"136.0732ms","start":"2026-09-11T01:14:47.993114Z","end":"2026-09-11T01:14:48.129187Z","steps":["trace[1777464552] 'agreement among raft nodes before linearized reading' (duration: 135.807414ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:14:48.129528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.361486ms","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-09-11T01:14:48.129577Z","caller":"traceutil/trace.go:171","msg":"trace[1230803144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16132; }","duration":"136.442699ms","start":"2026-09-11T01:14:47.993122Z","end":"2026-09-11T01:14:48.129564Z","steps":["trace[1230803144] 'agreement among raft nodes before linearized reading' (duration: 135.997759ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:15:22.403825Z","caller":"traceutil/trace.go:171","msg":"trace[2071203604] transaction","detail":"{read_only:false; response_revision:16310; number_of_response:1; }","duration":"270.931384ms","start":"2026-09-11T01:15:22.13287Z","end":"2026-09-11T01:15:22.403801Z","steps":["trace[2071203604] 'process raft request' (duration: 270.76736ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:15:22.403993Z","caller":"traceutil/trace.go:171","msg":"trace[1940953557] linearizableReadLoop","detail":"{readStateIndex:16876; appliedIndex:16875; }","duration":"182.738094ms","start":"2026-09-11T01:15:22.221243Z","end":"2026-09-11T01:15:22.403981Z","steps":["trace[1940953557] 'read index received' (duration: 182.369156ms)","trace[1940953557] 'applied index is now lower than readState.Index' (duration: 156.703µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:15:22.404126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.864387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-11T01:15:22.404228Z","caller":"traceutil/trace.go:171","msg":"trace[894813192] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16310; }","duration":"183.01182ms","start":"2026-09-11T01:15:22.221191Z","end":"2026-09-11T01:15:22.404203Z","steps":["trace[894813192] 'agreement among raft nodes before linearized reading' (duration: 182.728364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:15:22.404389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.625824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-11T01:15:22.4044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.290711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-11T01:15:22.40443Z","caller":"traceutil/trace.go:171","msg":"trace[292020735] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16310; }","duration":"158.730166ms","start":"2026-09-11T01:15:22.245685Z","end":"2026-09-11T01:15:22.404415Z","steps":["trace[292020735] 'agreement among raft nodes before linearized reading' (duration: 158.668584ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:15:22.404433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.599596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-11T01:15:22.40447Z","caller":"traceutil/trace.go:171","msg":"trace[517762884] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16310; }","duration":"138.420133ms","start":"2026-09-11T01:15:22.266025Z","end":"2026-09-11T01:15:22.404445Z","steps":["trace[517762884] 'agreement among raft nodes before linearized reading' (duration: 138.208989ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:15:22.404488Z","caller":"traceutil/trace.go:171","msg":"trace[1373916368] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16310; }","duration":"148.701008ms","start":"2026-09-11T01:15:22.255772Z","end":"2026-09-11T01:15:22.404473Z","steps":["trace[1373916368] 'agreement among raft nodes before linearized reading' (duration: 148.511724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:15:22.404656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.321765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-11T01:15:22.404681Z","caller":"traceutil/trace.go:171","msg":"trace[1571565020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16310; }","duration":"125.367936ms","start":"2026-09-11T01:15:22.279304Z","end":"2026-09-11T01:15:22.404672Z","steps":["trace[1571565020] 'agreement among raft nodes before linearized reading' (duration: 125.290554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:15:22.404826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.91209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-11T01:15:22.404932Z","caller":"traceutil/trace.go:171","msg":"trace[961259704] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16310; }","duration":"159.107815ms","start":"2026-09-11T01:15:22.245747Z","end":"2026-09-11T01:15:22.404855Z","steps":["trace[961259704] 'agreement among raft nodes before linearized reading' (duration: 158.358568ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:15:22.753767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.023588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-11T01:15:22.753819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.560077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-11T01:15:22.753887Z","caller":"traceutil/trace.go:171","msg":"trace[1886241425] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16314; }","duration":"164.154351ms","start":"2026-09-11T01:15:22.589671Z","end":"2026-09-11T01:15:22.753825Z","steps":["trace[1886241425] 'range keys from in-memory index tree' (duration: 163.924226ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:15:22.753902Z","caller":"traceutil/trace.go:171","msg":"trace[1646300698] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16314; }","duration":"156.69806ms","start":"2026-09-11T01:15:22.597183Z","end":"2026-09-11T01:15:22.753881Z","steps":["trace[1646300698] 'range keys from in-memory index tree' (duration: 156.402544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:15:22.754002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.180872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-11T01:15:22.754079Z","caller":"traceutil/trace.go:171","msg":"trace[547083089] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16314; }","duration":"164.291824ms","start":"2026-09-11T01:15:22.589773Z","end":"2026-09-11T01:15:22.754065Z","steps":["trace[547083089] 'range keys from in-memory index tree' (duration: 164.042579ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:17:12.967481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14553} {"level":"info","ts":"2026-09-11T01:17:13.061863Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14553,"took":"92.391135ms","hash":4108864772,"current-db-size-bytes":57671680,"current-db-size":"58 MB","current-db-size-in-use-bytes":31641600,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-11T01:17:13.061938Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4108864772,"revision":14553,"compact-revision":12563} {"level":"info","ts":"2026-09-11T01:18:29.16658Z","caller":"traceutil/trace.go:171","msg":"trace[1798554983] transaction","detail":"{read_only:false; response_revision:17775; number_of_response:1; }","duration":"130.33632ms","start":"2026-09-11T01:18:29.036219Z","end":"2026-09-11T01:18:29.166555Z","steps":["trace[1798554983] 'process raft request' (duration: 129.977631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:21:04.687743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.441104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-11T01:21:04.687868Z","caller":"traceutil/trace.go:171","msg":"trace[719354858] transaction","detail":"{read_only:false; response_revision:18818; number_of_response:1; }","duration":"224.020285ms","start":"2026-09-11T01:21:04.463818Z","end":"2026-09-11T01:21:04.687838Z","steps":["trace[719354858] 'process raft request' (duration: 110.394606ms)","trace[719354858] 'compare' (duration: 113.313802ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:21:04.731137Z","caller":"traceutil/trace.go:171","msg":"trace[1136261778] transaction","detail":"{read_only:false; response_revision:18819; number_of_response:1; }","duration":"257.419796ms","start":"2026-09-11T01:21:04.473683Z","end":"2026-09-11T01:21:04.731103Z","steps":["trace[1136261778] 'process raft request' (duration: 256.453464ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:21:05.246114Z","caller":"traceutil/trace.go:171","msg":"trace[1406154852] linearizableReadLoop","detail":"{readStateIndex:19472; appliedIndex:19471; }","duration":"363.444922ms","start":"2026-09-11T01:21:04.882599Z","end":"2026-09-11T01:21:05.246044Z","steps":["trace[1406154852] 'read index received' (duration: 363.052943ms)","trace[1406154852] 'applied index is now lower than readState.Index' (duration: 390.279µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:21:05.246329Z","caller":"traceutil/trace.go:171","msg":"trace[1464140712] transaction","detail":"{read_only:false; response_revision:18822; number_of_response:1; }","duration":"375.405654ms","start":"2026-09-11T01:21:04.870904Z","end":"2026-09-11T01:21:05.24631Z","steps":["trace[1464140712] 'process raft request' (duration: 374.865992ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:21:05.246555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:21:04.870892Z","time spent":"375.484216ms","remote":"127.0.0.1:33798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T01:21:05.246894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.295062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-jbrbf\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-09-11T01:21:05.246955Z","caller":"traceutil/trace.go:171","msg":"trace[1716995216] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-jbrbf; range_end:; response_count:1; response_revision:18822; }","duration":"364.472765ms","start":"2026-09-11T01:21:04.882467Z","end":"2026-09-11T01:21:05.24694Z","steps":["trace[1716995216] 'agreement among raft nodes before linearized reading' (duration: 364.25861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:21:05.247007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:21:04.882444Z","time spent":"364.552407ms","remote":"127.0.0.1:33798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22117,"request content":"key:\"/registry/pods/openstack/nova-compute-default-jbrbf\" "} {"level":"warn","ts":"2026-09-11T01:21:05.247217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.74566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-09-11T01:21:05.247317Z","caller":"traceutil/trace.go:171","msg":"trace[1650117717] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18822; }","duration":"281.901724ms","start":"2026-09-11T01:21:04.965391Z","end":"2026-09-11T01:21:05.247293Z","steps":["trace[1650117717] 'agreement among raft nodes before linearized reading' (duration: 281.615678ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:21:05.247554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.117018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-11T01:21:05.247594Z","caller":"traceutil/trace.go:171","msg":"trace[429631099] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:18822; }","duration":"203.197459ms","start":"2026-09-11T01:21:05.044383Z","end":"2026-09-11T01:21:05.247581Z","steps":["trace[429631099] 'agreement among raft nodes before linearized reading' (duration: 203.133888ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:21:05.247638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.173956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-09-11T01:21:05.247701Z","caller":"traceutil/trace.go:171","msg":"trace[497085766] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18822; }","duration":"281.269969ms","start":"2026-09-11T01:21:04.966416Z","end":"2026-09-11T01:21:05.247686Z","steps":["trace[497085766] 'agreement among raft nodes before linearized reading' (duration: 281.109445ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:21:05.628989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.2092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-x22gc\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-09-11T01:21:05.629238Z","caller":"traceutil/trace.go:171","msg":"trace[1102684084] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-x22gc; range_end:; response_count:1; response_revision:18822; }","duration":"203.482296ms","start":"2026-09-11T01:21:05.425709Z","end":"2026-09-11T01:21:05.629191Z","steps":["trace[1102684084] 'range keys from in-memory index tree' (duration: 203.038726ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:21:56.951606Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"299bf3744060cbd","local-member-applied-index":20004,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-11T01:21:56.958855Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20004} {"level":"info","ts":"2026-09-11T01:21:56.958954Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15004} {"level":"info","ts":"2026-09-11T01:22:12.974555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17010} {"level":"info","ts":"2026-09-11T01:22:13.083495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17010,"took":"104.284956ms","hash":2393294354,"current-db-size-bytes":57671680,"current-db-size":"58 MB","current-db-size-in-use-bytes":33374208,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-11T01:22:13.083638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2393294354,"revision":17010,"compact-revision":14553} {"level":"warn","ts":"2026-09-11T01:27:13.251562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.632939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-11T01:27:13.251997Z","caller":"traceutil/trace.go:171","msg":"trace[1097344594] linearizableReadLoop","detail":"{readStateIndex:22391; appliedIndex:22390; }","duration":"250.806643ms","start":"2026-09-11T01:27:13.001173Z","end":"2026-09-11T01:27:13.25198Z","steps":["trace[1097344594] 'read index received' (duration: 143.610291ms)","trace[1097344594] 'applied index is now lower than readState.Index' (duration: 107.194462ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:27:13.252028Z","caller":"traceutil/trace.go:171","msg":"trace[623699486] transaction","detail":"{read_only:false; response_revision:21648; number_of_response:1; }","duration":"275.542585ms","start":"2026-09-11T01:27:12.97644Z","end":"2026-09-11T01:27:13.251983Z","steps":["trace[623699486] 'process raft request' (duration: 168.413905ms)","trace[623699486] 'compare' (duration: 106.443434ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:27:13.252207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.407981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-11T01:27:13.252282Z","caller":"traceutil/trace.go:171","msg":"trace[1633130427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21648; }","duration":"296.513313ms","start":"2026-09-11T01:27:12.95575Z","end":"2026-09-11T01:27:13.252264Z","steps":["trace[1633130427] 'agreement among raft nodes before linearized reading' (duration: 296.288748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:13.252313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.707597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-11T01:27:13.252386Z","caller":"traceutil/trace.go:171","msg":"trace[648289799] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:21648; }","duration":"112.841499ms","start":"2026-09-11T01:27:13.139527Z","end":"2026-09-11T01:27:13.252368Z","steps":["trace[648289799] 'agreement among raft nodes before linearized reading' (duration: 112.551593ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:13.277141Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19605} {"level":"info","ts":"2026-09-11T01:27:13.401533Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19605,"took":"120.76326ms","hash":969700901,"current-db-size-bytes":57671680,"current-db-size":"58 MB","current-db-size-in-use-bytes":30412800,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-11T01:27:13.401589Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":969700901,"revision":19605,"compact-revision":17010} {"level":"info","ts":"2026-09-11T01:27:29.454959Z","caller":"traceutil/trace.go:171","msg":"trace[958829247] linearizableReadLoop","detail":"{readStateIndex:22476; appliedIndex:22475; }","duration":"499.906436ms","start":"2026-09-11T01:27:28.95502Z","end":"2026-09-11T01:27:29.454926Z","steps":["trace[958829247] 'read index received' (duration: 499.575518ms)","trace[958829247] 'applied index is now lower than readState.Index' (duration: 306.907µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:27:29.455152Z","caller":"traceutil/trace.go:171","msg":"trace[922143655] transaction","detail":"{read_only:false; response_revision:21729; number_of_response:1; }","duration":"514.878516ms","start":"2026-09-11T01:27:28.940251Z","end":"2026-09-11T01:27:29.45513Z","steps":["trace[922143655] 'process raft request' (duration: 514.478687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:29.455457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.428868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-11T01:27:29.455541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.275106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-11T01:27:29.455582Z","caller":"traceutil/trace.go:171","msg":"trace[478521058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21729; }","duration":"500.592591ms","start":"2026-09-11T01:27:28.954969Z","end":"2026-09-11T01:27:29.455561Z","steps":["trace[478521058] 'agreement among raft nodes before linearized reading' (duration: 500.407837ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:29.45561Z","caller":"traceutil/trace.go:171","msg":"trace[1555859413] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:21729; }","duration":"191.400879ms","start":"2026-09-11T01:27:29.264194Z","end":"2026-09-11T01:27:29.455595Z","steps":["trace[1555859413] 'agreement among raft nodes before linearized reading' (duration: 191.106592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:29.455641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:28.954948Z","time spent":"500.679094ms","remote":"127.0.0.1:33638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-11T01:27:29.455649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.693742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-11T01:27:29.455732Z","caller":"traceutil/trace.go:171","msg":"trace[303498984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21729; }","duration":"377.826635ms","start":"2026-09-11T01:27:29.077886Z","end":"2026-09-11T01:27:29.455713Z","steps":["trace[303498984] 'agreement among raft nodes before linearized reading' (duration: 377.57151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:29.455491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:28.940226Z","time spent":"514.975629ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T01:27:29.455784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:29.077869Z","time spent":"377.905127ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-11T01:27:29.456064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.504386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-11T01:27:29.456182Z","caller":"traceutil/trace.go:171","msg":"trace[423584317] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:21729; }","duration":"488.669969ms","start":"2026-09-11T01:27:28.967488Z","end":"2026-09-11T01:27:29.456158Z","steps":["trace[423584317] 'agreement among raft nodes before linearized reading' (duration: 488.389413ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:29.456224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:28.967469Z","time spent":"488.746401ms","remote":"127.0.0.1:33718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-09-11T01:27:30.242951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.421072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-11T01:27:30.243127Z","caller":"traceutil/trace.go:171","msg":"trace[919997572] transaction","detail":"{read_only:false; response_revision:21730; number_of_response:1; }","duration":"782.288136ms","start":"2026-09-11T01:27:29.460774Z","end":"2026-09-11T01:27:30.243062Z","steps":["trace[919997572] 'process raft request' (duration: 380.669829ms)","trace[919997572] 'compare' (duration: 400.818559ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:27:30.243221Z","caller":"traceutil/trace.go:171","msg":"trace[987470436] transaction","detail":"{read_only:false; response_revision:21731; number_of_response:1; }","duration":"573.255465ms","start":"2026-09-11T01:27:29.669944Z","end":"2026-09-11T01:27:30.2432Z","steps":["trace[987470436] 'process raft request' (duration: 573.180933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:30.243284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.111178ms","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":"warn","ts":"2026-09-11T01:27:30.2435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:29.460752Z","time spent":"782.46412ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T01:27:30.243502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:29.66991Z","time spent":"573.360698ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T01:27:30.24353Z","caller":"traceutil/trace.go:171","msg":"trace[1521761641] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:21731; }","duration":"683.501937ms","start":"2026-09-11T01:27:29.560015Z","end":"2026-09-11T01:27:30.243517Z","steps":["trace[1521761641] 'agreement among raft nodes before linearized reading' (duration: 683.17144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:30.243562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:29.559999Z","time spent":"683.554698ms","remote":"127.0.0.1:51814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"info","ts":"2026-09-11T01:27:30.243148Z","caller":"traceutil/trace.go:171","msg":"trace[2044608458] linearizableReadLoop","detail":"{readStateIndex:22477; appliedIndex:22476; }","duration":"682.958504ms","start":"2026-09-11T01:27:29.560169Z","end":"2026-09-11T01:27:30.243128Z","steps":["trace[2044608458] 'read index received' (duration: 281.245166ms)","trace[2044608458] 'applied index is now lower than readState.Index' (duration: 401.711008ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:27:30.253975Z","caller":"traceutil/trace.go:171","msg":"trace[1649736863] transaction","detail":"{read_only:false; response_revision:21732; number_of_response:1; }","duration":"380.366833ms","start":"2026-09-11T01:27:29.873545Z","end":"2026-09-11T01:27:30.253912Z","steps":["trace[1649736863] 'process raft request' (duration: 380.264251ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:30.254134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:29.873522Z","time spent":"380.512846ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T01:27:31.235008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.759083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-11T01:27:31.235295Z","caller":"traceutil/trace.go:171","msg":"trace[2109819768] linearizableReadLoop","detail":"{readStateIndex:22481; appliedIndex:22478; }","duration":"991.646904ms","start":"2026-09-11T01:27:30.243627Z","end":"2026-09-11T01:27:31.235274Z","steps":["trace[2109819768] 'read index received' (duration: 10.211472ms)","trace[2109819768] 'applied index is now lower than readState.Index' (duration: 981.434252ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:27:31.235373Z","caller":"traceutil/trace.go:171","msg":"trace[677910881] transaction","detail":"{read_only:false; response_revision:21734; number_of_response:1; }","duration":"1.009346918s","start":"2026-09-11T01:27:30.225994Z","end":"2026-09-11T01:27:31.235341Z","steps":["trace[677910881] 'process raft request' (duration: 1.009161554s)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:31.235442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279984081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-11T01:27:31.235483Z","caller":"traceutil/trace.go:171","msg":"trace[160351050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21734; }","duration":"1.280059953s","start":"2026-09-11T01:27:29.95541Z","end":"2026-09-11T01:27:31.23547Z","steps":["trace[160351050] 'agreement among raft nodes before linearized reading' (duration: 1.279983241s)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:31.235527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:29.955393Z","time spent":"1.280122584s","remote":"127.0.0.1:33638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-11T01:27:31.235529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:30.225976Z","time spent":"1.00946995s","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T01:27:31.235566Z","caller":"traceutil/trace.go:171","msg":"trace[375914020] transaction","detail":"{read_only:false; response_revision:21733; number_of_response:1; }","duration":"1.05030761s","start":"2026-09-11T01:27:30.185222Z","end":"2026-09-11T01:27:31.23553Z","steps":["trace[375914020] 'process raft request' (duration: 390.916743ms)","trace[375914020] 'compare' (duration: 657.924024ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:27:31.235709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:30.185206Z","time spent":"1.050427313s","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T01:27:31.669517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.82275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-11T01:27:31.669784Z","caller":"traceutil/trace.go:171","msg":"trace[2126013262] linearizableReadLoop","detail":"{readStateIndex:22482; appliedIndex:22481; }","duration":"434.394783ms","start":"2026-09-11T01:27:31.23536Z","end":"2026-09-11T01:27:31.669755Z","steps":["trace[2126013262] 'read index received' (duration: 234.123713ms)","trace[2126013262] 'applied index is now lower than readState.Index' (duration: 200.26789ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:27:31.669788Z","caller":"traceutil/trace.go:171","msg":"trace[1014240419] transaction","detail":"{read_only:false; response_revision:21735; number_of_response:1; }","duration":"1.006766148s","start":"2026-09-11T01:27:30.662992Z","end":"2026-09-11T01:27:31.669758Z","steps":["trace[1014240419] 'process raft request' (duration: 806.548879ms)","trace[1014240419] 'compare' (duration: 199.252588ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:27:31.669936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:30.662966Z","time spent":"1.006896123s","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T01:27:31.775988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.328291ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-11T01:27:31.776112Z","caller":"traceutil/trace.go:171","msg":"trace[461401515] linearizableReadLoop","detail":"{readStateIndex:22483; appliedIndex:22482; }","duration":"106.219359ms","start":"2026-09-11T01:27:31.669849Z","end":"2026-09-11T01:27:31.776068Z","steps":["trace[461401515] 'read index received' (duration: 60.752µs)","trace[461401515] 'applied index is now lower than readState.Index' (duration: 106.157257ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:27:31.776126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255018703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-11T01:27:31.776185Z","caller":"traceutil/trace.go:171","msg":"trace[1322249735] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21735; }","duration":"1.255109885s","start":"2026-09-11T01:27:30.521054Z","end":"2026-09-11T01:27:31.776164Z","steps":["trace[1322249735] 'agreement among raft nodes before linearized reading' (duration: 1.148773994s)","trace[1322249735] 'range keys from in-memory index tree' (duration: 106.110216ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:27:31.776188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221011108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-09-11T01:27:31.77623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:30.520998Z","time spent":"1.255222737s","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-11T01:27:31.77627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.739446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-09-11T01:27:31.77631Z","caller":"traceutil/trace.go:171","msg":"trace[346319747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21735; }","duration":"1.221165172s","start":"2026-09-11T01:27:30.555076Z","end":"2026-09-11T01:27:31.776242Z","steps":["trace[346319747] 'agreement among raft nodes before linearized reading' (duration: 1.114703766s)","trace[346319747] 'range keys from in-memory index tree' (duration: 106.181708ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:27:31.776361Z","caller":"traceutil/trace.go:171","msg":"trace[1063732575] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:21735; }","duration":"760.874489ms","start":"2026-09-11T01:27:31.015458Z","end":"2026-09-11T01:27:31.776333Z","steps":["trace[1063732575] 'agreement among raft nodes before linearized reading' (duration: 654.365623ms)","trace[1063732575] 'range keys from in-memory index tree' (duration: 106.29671ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:27:31.776399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:30.555055Z","time spent":"1.221296515s","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-11T01:27:31.776359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40064789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-11T01:27:31.776487Z","caller":"traceutil/trace.go:171","msg":"trace[1707304997] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21735; }","duration":"1.400835834s","start":"2026-09-11T01:27:30.375616Z","end":"2026-09-11T01:27:31.776452Z","steps":["trace[1707304997] 'agreement among raft nodes before linearized reading' (duration: 1.294227826s)","trace[1707304997] 'range keys from in-memory index tree' (duration: 106.27522ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:27:31.776537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.751915ms","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-09-11T01:27:31.776556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.843051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-09-11T01:27:31.776412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:31.015432Z","time spent":"760.966591ms","remote":"127.0.0.1:33774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"info","ts":"2026-09-11T01:27:31.776594Z","caller":"traceutil/trace.go:171","msg":"trace[1481231313] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21735; }","duration":"404.919642ms","start":"2026-09-11T01:27:31.371664Z","end":"2026-09-11T01:27:31.776584Z","steps":["trace[1481231313] 'agreement among raft nodes before linearized reading' (duration: 404.83565ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:31.776596Z","caller":"traceutil/trace.go:171","msg":"trace[1550228464] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:21735; }","duration":"291.857728ms","start":"2026-09-11T01:27:31.484716Z","end":"2026-09-11T01:27:31.776574Z","steps":["trace[1550228464] 'agreement among raft nodes before linearized reading' (duration: 291.766126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:31.776622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:31.371645Z","time spent":"404.971064ms","remote":"127.0.0.1:33798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-11T01:27:31.776627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.686971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-11T01:27:31.776554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:30.375597Z","time spent":"1.400946876s","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-11T01:27:31.776774Z","caller":"traceutil/trace.go:171","msg":"trace[1785355022] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:21735; }","duration":"762.950307ms","start":"2026-09-11T01:27:31.013796Z","end":"2026-09-11T01:27:31.776747Z","steps":["trace[1785355022] 'agreement among raft nodes before linearized reading' (duration: 656.039372ms)","trace[1785355022] 'count revisions from in-memory index tree' (duration: 106.603798ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:27:31.77704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:31.013777Z","time spent":"763.151151ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-09-11T01:27:31.776472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.302873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-11T01:27:31.777265Z","caller":"traceutil/trace.go:171","msg":"trace[862775592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21735; }","duration":"540.184273ms","start":"2026-09-11T01:27:31.237048Z","end":"2026-09-11T01:27:31.777233Z","steps":["trace[862775592] 'agreement among raft nodes before linearized reading' (duration: 539.248912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:31.777346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:31.237031Z","time spent":"540.300325ms","remote":"127.0.0.1:33640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-11T01:27:31.777666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.226390721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-11T01:27:31.777756Z","caller":"traceutil/trace.go:171","msg":"trace[1803033363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21735; }","duration":"1.226525704s","start":"2026-09-11T01:27:30.551207Z","end":"2026-09-11T01:27:31.777733Z","steps":["trace[1803033363] 'agreement among raft nodes before linearized reading' (duration: 1.118598086s)","trace[1803033363] 'range keys from in-memory index tree' (duration: 106.485225ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:27:31.777849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:27:30.551192Z","time spent":"1.226646256s","remote":"127.0.0.1:33864","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":"info","ts":"2026-09-11T01:31:44.396435Z","caller":"traceutil/trace.go:171","msg":"trace[26676483] transaction","detail":"{read_only:false; response_revision:24292; number_of_response:1; }","duration":"137.814019ms","start":"2026-09-11T01:31:44.258591Z","end":"2026-09-11T01:31:44.396405Z","steps":["trace[26676483] 'process raft request' (duration: 137.653265ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:31:44.794046Z","caller":"traceutil/trace.go:171","msg":"trace[1458882645] transaction","detail":"{read_only:false; response_revision:24295; number_of_response:1; }","duration":"323.950228ms","start":"2026-09-11T01:31:44.470071Z","end":"2026-09-11T01:31:44.794021Z","steps":["trace[1458882645] 'process raft request' (duration: 317.4565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:31:44.794216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:31:44.470039Z","time spent":"324.097421ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T01:32:13.284123Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21648} {"level":"info","ts":"2026-09-11T01:32:13.394788Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21648,"took":"107.370474ms","hash":1520173508,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":42352640,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-11T01:32:13.394859Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1520173508,"revision":21648,"compact-revision":19605} {"level":"info","ts":"2026-09-11T01:33:05.288556Z","caller":"traceutil/trace.go:171","msg":"trace[47171279] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"113.828803ms","start":"2026-09-11T01:33:05.1747Z","end":"2026-09-11T01:33:05.288529Z","steps":["trace[47171279] 'process raft request' (duration: 113.68791ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:33:36.942227Z","caller":"traceutil/trace.go:171","msg":"trace[2100510559] linearizableReadLoop","detail":"{readStateIndex:26276; appliedIndex:26274; }","duration":"120.278759ms","start":"2026-09-11T01:33:36.821919Z","end":"2026-09-11T01:33:36.942198Z","steps":["trace[2100510559] 'read index received' (duration: 8.759219ms)","trace[2100510559] 'applied index is now lower than readState.Index' (duration: 111.5182ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:33:36.94246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.514305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-11T01:33:36.942597Z","caller":"traceutil/trace.go:171","msg":"trace[70348604] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25422; }","duration":"120.674898ms","start":"2026-09-11T01:33:36.821887Z","end":"2026-09-11T01:33:36.942562Z","steps":["trace[70348604] 'agreement among raft nodes before linearized reading' (duration: 120.500444ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:33:36.942817Z","caller":"traceutil/trace.go:171","msg":"trace[2029339975] transaction","detail":"{read_only:false; response_revision:25422; number_of_response:1; }","duration":"124.833273ms","start":"2026-09-11T01:33:36.817971Z","end":"2026-09-11T01:33:36.942805Z","steps":["trace[2029339975] 'process raft request' (duration: 119.319987ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:35:42.511479Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a7c.wal"} {"level":"info","ts":"2026-09-11T01:37:13.291477Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24597} {"level":"info","ts":"2026-09-11T01:37:13.443816Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24597,"took":"148.258727ms","hash":3781844764,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":32677888,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-11T01:37:13.44388Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3781844764,"revision":24597,"compact-revision":21648} {"level":"info","ts":"2026-09-11T01:41:42.752181Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"299bf3744060cbd","local-member-applied-index":30005,"local-member-snapshot-index":20004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-11T01:41:42.76121Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30005} {"level":"info","ts":"2026-09-11T01:41:42.761406Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25005}