{"level":"warn","ts":"2026-09-22T03:44:41.999117Z","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-22T03:44:41.999211Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.121: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.121:2380","--initial-cluster=instance=https://162.253.55.121:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.121:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.121: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-22T03:44:41.999288Z","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-22T03:44:41.999306Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.121:2380"]} {"level":"info","ts":"2026-09-22T03:44:41.99933Z","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-22T03:44:42.000062Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.121:2379"]} {"level":"info","ts":"2026-09-22T03:44:42.000156Z","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":8,"max-cpu-available":8,"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.121:2380"],"listen-peer-urls":["https://162.253.55.121:2380"],"advertise-client-urls":["https://162.253.55.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.121: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-22T03:44:42.006541Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.157136ms"} {"level":"info","ts":"2026-09-22T03:44:42.012227Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e064f6438c9a029d","cluster-id":"46d59d02273d6950"} {"level":"info","ts":"2026-09-22T03:44:42.012418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T03:44:42.012473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d became follower at term 0"} {"level":"info","ts":"2026-09-22T03:44:42.012489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e064f6438c9a029d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T03:44:42.012524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d became follower at term 1"} {"level":"info","ts":"2026-09-22T03:44:42.012617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d switched to configuration voters=(16169319332149068445)"} {"level":"warn","ts":"2026-09-22T03:44:42.022768Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T03:44:42.025848Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T03:44:42.026924Z","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-22T03:44:42.028789Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e064f6438c9a029d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T03:44:42.028901Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e064f6438c9a029d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T03:44:42.029219Z","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-22T03:44:42.029314Z","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-22T03:44:42.029322Z","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-22T03:44:42.032437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d switched to configuration voters=(16169319332149068445)"} {"level":"info","ts":"2026-09-22T03:44:42.032617Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"46d59d02273d6950","local-member-id":"e064f6438c9a029d","added-peer-id":"e064f6438c9a029d","added-peer-peer-urls":["https://162.253.55.121:2380"]} {"level":"info","ts":"2026-09-22T03:44:42.036519Z","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-22T03:44:42.036652Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.121:2380"} {"level":"info","ts":"2026-09-22T03:44:42.036728Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.121:2380"} {"level":"info","ts":"2026-09-22T03:44:42.03784Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e064f6438c9a029d","initial-advertise-peer-urls":["https://162.253.55.121:2380"],"listen-peer-urls":["https://162.253.55.121:2380"],"advertise-client-urls":["https://162.253.55.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T03:44:42.037876Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T03:44:42.314354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T03:44:42.314449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T03:44:42.314496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d received MsgPreVoteResp from e064f6438c9a029d at term 1"} {"level":"info","ts":"2026-09-22T03:44:42.314518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d became candidate at term 2"} {"level":"info","ts":"2026-09-22T03:44:42.31453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d received MsgVoteResp from e064f6438c9a029d at term 2"} {"level":"info","ts":"2026-09-22T03:44:42.314547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e064f6438c9a029d became leader at term 2"} {"level":"info","ts":"2026-09-22T03:44:42.314566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e064f6438c9a029d elected leader e064f6438c9a029d at term 2"} {"level":"info","ts":"2026-09-22T03:44:42.315496Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e064f6438c9a029d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.121:2379]}","request-path":"/0/members/e064f6438c9a029d/attributes","cluster-id":"46d59d02273d6950","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T03:44:42.315544Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T03:44:42.315522Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:44:42.315627Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T03:44:42.316856Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T03:44:42.316876Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T03:44:42.318584Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"46d59d02273d6950","local-member-id":"e064f6438c9a029d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:44:42.318678Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:44:42.318706Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:44:42.319036Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.121:2379"} {"level":"info","ts":"2026-09-22T03:44:42.319235Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-22T03:45:22.881252Z","caller":"traceutil/trace.go:171","msg":"trace[747967464] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"176.067691ms","start":"2026-09-22T03:45:22.705136Z","end":"2026-09-22T03:45:22.881204Z","steps":["trace[747967464] 'process raft request' (duration: 175.883857ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:22.891024Z","caller":"traceutil/trace.go:171","msg":"trace[698087818] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"117.561624ms","start":"2026-09-22T03:45:22.773445Z","end":"2026-09-22T03:45:22.891007Z","steps":["trace[698087818] 'process raft request' (duration: 117.518083ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:22.891258Z","caller":"traceutil/trace.go:171","msg":"trace[221361928] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"172.810529ms","start":"2026-09-22T03:45:22.718436Z","end":"2026-09-22T03:45:22.891247Z","steps":["trace[221361928] 'process raft request' (duration: 172.260446ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:22.891582Z","caller":"traceutil/trace.go:171","msg":"trace[559487231] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"185.928091ms","start":"2026-09-22T03:45:22.705575Z","end":"2026-09-22T03:45:22.891503Z","steps":["trace[559487231] 'process raft request' (duration: 184.921129ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:22.891658Z","caller":"traceutil/trace.go:171","msg":"trace[1872743089] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"164.449872ms","start":"2026-09-22T03:45:22.727134Z","end":"2026-09-22T03:45:22.891583Z","steps":["trace[1872743089] 'process raft request' (duration: 163.674475ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:22.89174Z","caller":"traceutil/trace.go:171","msg":"trace[1209173722] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"167.07528ms","start":"2026-09-22T03:45:22.724617Z","end":"2026-09-22T03:45:22.891692Z","steps":["trace[1209173722] 'process raft request' (duration: 166.14873ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:22.891857Z","caller":"traceutil/trace.go:171","msg":"trace[101002013] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"164.078882ms","start":"2026-09-22T03:45:22.727768Z","end":"2026-09-22T03:45:22.891847Z","steps":["trace[101002013] 'process raft request' (duration: 163.085391ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:22.891818Z","caller":"traceutil/trace.go:171","msg":"trace[97547237] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"170.770782ms","start":"2026-09-22T03:45:22.720716Z","end":"2026-09-22T03:45:22.891487Z","steps":["trace[97547237] 'process raft request' (duration: 170.018195ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:22.891642Z","caller":"traceutil/trace.go:171","msg":"trace[1324105719] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"178.331121ms","start":"2026-09-22T03:45:22.7133Z","end":"2026-09-22T03:45:22.891632Z","steps":["trace[1324105719] 'process raft request' (duration: 177.358709ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:22.892176Z","caller":"traceutil/trace.go:171","msg":"trace[898722102] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"182.838762ms","start":"2026-09-22T03:45:22.708707Z","end":"2026-09-22T03:45:22.891546Z","steps":["trace[898722102] 'process raft request' (duration: 181.86447ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:22.892186Z","caller":"traceutil/trace.go:171","msg":"trace[999841529] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"154.598551ms","start":"2026-09-22T03:45:22.7375Z","end":"2026-09-22T03:45:22.892099Z","steps":["trace[999841529] 'process raft request' (duration: 153.403935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:29.281139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.285101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:45:29.281256Z","caller":"traceutil/trace.go:171","msg":"trace[873709917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"232.449615ms","start":"2026-09-22T03:45:29.048779Z","end":"2026-09-22T03:45:29.281229Z","steps":["trace[873709917] 'range keys from in-memory index tree' (duration: 232.172179ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:29.627002Z","caller":"traceutil/trace.go:171","msg":"trace[1513425664] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"147.184984ms","start":"2026-09-22T03:45:29.479782Z","end":"2026-09-22T03:45:29.626967Z","steps":["trace[1513425664] 'read index received' (duration: 145.997829ms)","trace[1513425664] 'applied index is now lower than readState.Index' (duration: 1.186095ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:29.627114Z","caller":"traceutil/trace.go:171","msg":"trace[865615128] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"285.059101ms","start":"2026-09-22T03:45:29.341971Z","end":"2026-09-22T03:45:29.62703Z","steps":["trace[865615128] 'process raft request' (duration: 284.125731ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:29.627259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.447699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gkhph\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-22T03:45:29.627317Z","caller":"traceutil/trace.go:171","msg":"trace[506488710] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gkhph; range_end:; response_count:1; response_revision:642; }","duration":"147.578332ms","start":"2026-09-22T03:45:29.479718Z","end":"2026-09-22T03:45:29.627296Z","steps":["trace[506488710] 'agreement among raft nodes before linearized reading' (duration: 147.370168ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:45:30.180019Z","caller":"traceutil/trace.go:171","msg":"trace[1367929365] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"523.167622ms","start":"2026-09-22T03:45:29.656745Z","end":"2026-09-22T03:45:30.179913Z","steps":["trace[1367929365] 'process raft request' (duration: 488.827016ms)","trace[1367929365] 'compare' (duration: 34.130962ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:45:30.182591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:29.656716Z","time spent":"523.391387ms","remote":"127.0.0.1:60890","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-22T03:45:30.187704Z","caller":"traceutil/trace.go:171","msg":"trace[949031405] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:667; }","duration":"137.536836ms","start":"2026-09-22T03:45:30.050131Z","end":"2026-09-22T03:45:30.187668Z","steps":["trace[949031405] 'read index received' (duration: 95.413398ms)","trace[949031405] 'applied index is now lower than readState.Index' (duration: 42.121848ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:45:30.187759Z","caller":"traceutil/trace.go:171","msg":"trace[1566408271] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"473.777213ms","start":"2026-09-22T03:45:29.713941Z","end":"2026-09-22T03:45:30.187718Z","steps":["trace[1566408271] 'process raft request' (duration: 473.469886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:45:30.188042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:45:29.713887Z","time spent":"473.963666ms","remote":"127.0.0.1:32980","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-22T03:45:30.187892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.731751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:45:30.188334Z","caller":"traceutil/trace.go:171","msg":"trace[1447662863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"138.23615ms","start":"2026-09-22T03:45:30.050073Z","end":"2026-09-22T03:45:30.18831Z","steps":["trace[1447662863] 'agreement among raft nodes before linearized reading' (duration: 137.719749ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:46:00.444931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.59003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T03:46:00.445038Z","caller":"traceutil/trace.go:171","msg":"trace[513647518] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:851; }","duration":"261.798084ms","start":"2026-09-22T03:46:00.18322Z","end":"2026-09-22T03:46:00.445018Z","steps":["trace[513647518] 'count revisions from in-memory index tree' (duration: 261.404197ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:46:13.186739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.12435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:46:13.186855Z","caller":"traceutil/trace.go:171","msg":"trace[588210695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"137.295663ms","start":"2026-09-22T03:46:13.049534Z","end":"2026-09-22T03:46:13.18683Z","steps":["trace[588210695] 'range keys from in-memory index tree' (duration: 136.984048ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:46:29.987521Z","caller":"traceutil/trace.go:171","msg":"trace[872868111] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"106.09782ms","start":"2026-09-22T03:46:29.881378Z","end":"2026-09-22T03:46:29.987476Z","steps":["trace[872868111] 'process raft request' (duration: 105.958788ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:46:55.254418Z","caller":"traceutil/trace.go:171","msg":"trace[651529097] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"124.972923ms","start":"2026-09-22T03:46:55.129421Z","end":"2026-09-22T03:46:55.254394Z","steps":["trace[651529097] 'process raft request' (duration: 124.7935ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:21.359702Z","caller":"traceutil/trace.go:171","msg":"trace[662419156] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2007; }","duration":"222.987467ms","start":"2026-09-22T03:47:21.136689Z","end":"2026-09-22T03:47:21.359676Z","steps":["trace[662419156] 'read index received' (duration: 222.926236ms)","trace[662419156] 'applied index is now lower than readState.Index' (duration: 60.111µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:47:21.35985Z","caller":"traceutil/trace.go:171","msg":"trace[1171378969] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"249.603919ms","start":"2026-09-22T03:47:21.11021Z","end":"2026-09-22T03:47:21.359814Z","steps":["trace[1171378969] 'process raft request' (duration: 249.267125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:21.360232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.514593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-22T03:47:21.360308Z","caller":"traceutil/trace.go:171","msg":"trace[856345069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1932; }","duration":"223.627864ms","start":"2026-09-22T03:47:21.136664Z","end":"2026-09-22T03:47:21.360291Z","steps":["trace[856345069] 'agreement among raft nodes before linearized reading' (duration: 223.151289ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:21.370252Z","caller":"traceutil/trace.go:171","msg":"trace[8365943] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"223.295772ms","start":"2026-09-22T03:47:21.146933Z","end":"2026-09-22T03:47:21.370229Z","steps":["trace[8365943] 'process raft request' (duration: 223.0983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:21.393027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.809128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:47:21.393152Z","caller":"traceutil/trace.go:171","msg":"trace[1351252317] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1933; }","duration":"135.990439ms","start":"2026-09-22T03:47:21.257139Z","end":"2026-09-22T03:47:21.39313Z","steps":["trace[1351252317] 'agreement among raft nodes before linearized reading' (duration: 135.799027ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:21.393159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.762031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-22T03:47:21.393208Z","caller":"traceutil/trace.go:171","msg":"trace[1219672793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1933; }","duration":"238.860213ms","start":"2026-09-22T03:47:21.154333Z","end":"2026-09-22T03:47:21.393193Z","steps":["trace[1219672793] 'agreement among raft nodes before linearized reading' (duration: 238.659081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:21.393537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.451878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-22T03:47:21.393598Z","caller":"traceutil/trace.go:171","msg":"trace[716558347] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1933; }","duration":"244.550279ms","start":"2026-09-22T03:47:21.149031Z","end":"2026-09-22T03:47:21.393582Z","steps":["trace[716558347] 'agreement among raft nodes before linearized reading' (duration: 244.308516ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:21.393513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.152238ms","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":"warn","ts":"2026-09-22T03:47:21.393689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.776852ms","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-22T03:47:21.393793Z","caller":"traceutil/trace.go:171","msg":"trace[1509923913] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1933; }","duration":"120.976765ms","start":"2026-09-22T03:47:21.272792Z","end":"2026-09-22T03:47:21.393769Z","steps":["trace[1509923913] 'agreement among raft nodes before linearized reading' (duration: 120.511579ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:21.393938Z","caller":"traceutil/trace.go:171","msg":"trace[785824620] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1933; }","duration":"110.543102ms","start":"2026-09-22T03:47:21.283312Z","end":"2026-09-22T03:47:21.393856Z","steps":["trace[785824620] 'agreement among raft nodes before linearized reading' (duration: 109.918165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:21.393117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.497526ms","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-22T03:47:21.394376Z","caller":"traceutil/trace.go:171","msg":"trace[850409127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1933; }","duration":"208.814701ms","start":"2026-09-22T03:47:21.185544Z","end":"2026-09-22T03:47:21.394358Z","steps":["trace[850409127] 'agreement among raft nodes before linearized reading' (duration: 207.389714ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:21.817941Z","caller":"traceutil/trace.go:171","msg":"trace[1531427975] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"419.702047ms","start":"2026-09-22T03:47:21.398217Z","end":"2026-09-22T03:47:21.817919Z","steps":["trace[1531427975] 'process raft request' (duration: 419.618476ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:21.818172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:21.398199Z","time spent":"419.89808ms","remote":"127.0.0.1:60956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:47:21.818231Z","caller":"traceutil/trace.go:171","msg":"trace[2132920189] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"419.041441ms","start":"2026-09-22T03:47:21.399179Z","end":"2026-09-22T03:47:21.81822Z","steps":["trace[2132920189] 'process raft request' (duration: 418.707797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:21.818302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:21.399165Z","time spent":"419.101011ms","remote":"127.0.0.1:60956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:47:21.817988Z","caller":"traceutil/trace.go:171","msg":"trace[1440626457] linearizableReadLoop","detail":"{readStateIndex:2018; appliedIndex:2015; }","duration":"401.352884ms","start":"2026-09-22T03:47:21.416612Z","end":"2026-09-22T03:47:21.817965Z","steps":["trace[1440626457] 'read index received' (duration: 322.565592ms)","trace[1440626457] 'applied index is now lower than readState.Index' (duration: 78.786162ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:47:21.818192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.602758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-22T03:47:21.818464Z","caller":"traceutil/trace.go:171","msg":"trace[1183999055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1941; }","duration":"402.937891ms","start":"2026-09-22T03:47:21.415514Z","end":"2026-09-22T03:47:21.818451Z","steps":["trace[1183999055] 'agreement among raft nodes before linearized reading' (duration: 402.509767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:21.818529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:21.415498Z","time spent":"403.018672ms","remote":"127.0.0.1:60956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-22T03:47:21.818691Z","caller":"traceutil/trace.go:171","msg":"trace[1217285641] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"420.507667ms","start":"2026-09-22T03:47:21.397496Z","end":"2026-09-22T03:47:21.818004Z","steps":["trace[1217285641] 'process raft request' (duration: 341.698785ms)","trace[1217285641] 'compare' (duration: 78.375037ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:47:21.819253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:21.397474Z","time spent":"421.476747ms","remote":"127.0.0.1:60956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:47:21.862588Z","caller":"traceutil/trace.go:171","msg":"trace[1973795462] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"435.745846ms","start":"2026-09-22T03:47:21.426808Z","end":"2026-09-22T03:47:21.862554Z","steps":["trace[1973795462] 'process raft request' (duration: 435.544314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:21.862874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:47:21.426784Z","time spent":"435.904138ms","remote":"127.0.0.1:60890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:47:34.033016Z","caller":"traceutil/trace.go:171","msg":"trace[486368068] linearizableReadLoop","detail":"{readStateIndex:2120; appliedIndex:2119; }","duration":"148.513147ms","start":"2026-09-22T03:47:33.884483Z","end":"2026-09-22T03:47:34.032996Z","steps":["trace[486368068] 'read index received' (duration: 148.304584ms)","trace[486368068] 'applied index is now lower than readState.Index' (duration: 207.713µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:47:34.03313Z","caller":"traceutil/trace.go:171","msg":"trace[346209106] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"159.39853ms","start":"2026-09-22T03:47:33.87372Z","end":"2026-09-22T03:47:34.033119Z","steps":["trace[346209106] 'process raft request' (duration: 159.091617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:34.0334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.890749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-22T03:47:34.033545Z","caller":"traceutil/trace.go:171","msg":"trace[618731733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2040; }","duration":"111.064501ms","start":"2026-09-22T03:47:33.922451Z","end":"2026-09-22T03:47:34.033515Z","steps":["trace[618731733] 'agreement among raft nodes before linearized reading' (duration: 110.774777ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:47:34.033351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.83397ms","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-22T03:47:34.034012Z","caller":"traceutil/trace.go:171","msg":"trace[2120601056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2040; }","duration":"149.537878ms","start":"2026-09-22T03:47:33.884446Z","end":"2026-09-22T03:47:34.033984Z","steps":["trace[2120601056] 'agreement among raft nodes before linearized reading' (duration: 148.7859ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:37.676371Z","caller":"traceutil/trace.go:171","msg":"trace[1004253017] linearizableReadLoop","detail":"{readStateIndex:2151; appliedIndex:2150; }","duration":"162.784804ms","start":"2026-09-22T03:47:37.513552Z","end":"2026-09-22T03:47:37.676337Z","steps":["trace[1004253017] 'read index received' (duration: 159.924592ms)","trace[1004253017] 'applied index is now lower than readState.Index' (duration: 2.857332ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:47:37.676546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.976866ms","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-22T03:47:37.676572Z","caller":"traceutil/trace.go:171","msg":"trace[719777699] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2070; }","duration":"163.075787ms","start":"2026-09-22T03:47:37.513488Z","end":"2026-09-22T03:47:37.676564Z","steps":["trace[719777699] 'agreement among raft nodes before linearized reading' (duration: 162.920915ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:37.676297Z","caller":"traceutil/trace.go:171","msg":"trace[1182944786] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"176.69788ms","start":"2026-09-22T03:47:37.499527Z","end":"2026-09-22T03:47:37.676225Z","steps":["trace[1182944786] 'process raft request' (duration: 173.923709ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:48:00.491727Z","caller":"traceutil/trace.go:171","msg":"trace[1374462256] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"118.442891ms","start":"2026-09-22T03:48:00.373255Z","end":"2026-09-22T03:48:00.491698Z","steps":["trace[1374462256] 'process raft request' (duration: 112.884271ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:48:01.716131Z","caller":"traceutil/trace.go:171","msg":"trace[565818033] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"148.964487ms","start":"2026-09-22T03:48:01.567125Z","end":"2026-09-22T03:48:01.716089Z","steps":["trace[565818033] 'process raft request' (duration: 148.724115ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:48:01.720858Z","caller":"traceutil/trace.go:171","msg":"trace[2061765442] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"108.989476ms","start":"2026-09-22T03:48:01.611847Z","end":"2026-09-22T03:48:01.720836Z","steps":["trace[2061765442] 'process raft request' (duration: 108.883705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:48:01.894311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.572257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-22T03:48:01.894401Z","caller":"traceutil/trace.go:171","msg":"trace[688743156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2204; }","duration":"101.671868ms","start":"2026-09-22T03:48:01.792709Z","end":"2026-09-22T03:48:01.894381Z","steps":["trace[688743156] 'range keys from in-memory index tree' (duration: 101.405614ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:49:30.167773Z","caller":"traceutil/trace.go:171","msg":"trace[2028216180] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"115.930631ms","start":"2026-09-22T03:49:30.051823Z","end":"2026-09-22T03:49:30.167754Z","steps":["trace[2028216180] 'process raft request' (duration: 32.308908ms)","trace[2028216180] 'compare' (duration: 83.553691ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:53:58.262061Z","caller":"traceutil/trace.go:171","msg":"trace[2120573297] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"108.200846ms","start":"2026-09-22T03:53:58.153844Z","end":"2026-09-22T03:53:58.262045Z","steps":["trace[2120573297] 'process raft request' (duration: 108.103225ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:53:58.378166Z","caller":"traceutil/trace.go:171","msg":"trace[1089177963] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"113.006054ms","start":"2026-09-22T03:53:58.265136Z","end":"2026-09-22T03:53:58.378142Z","steps":["trace[1089177963] 'process raft request' (duration: 112.784791ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:53:58.379272Z","caller":"traceutil/trace.go:171","msg":"trace[2076437761] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"107.043317ms","start":"2026-09-22T03:53:58.27219Z","end":"2026-09-22T03:53:58.379233Z","steps":["trace[2076437761] 'process raft request' (duration: 106.922915ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:53:58.379296Z","caller":"traceutil/trace.go:171","msg":"trace[1269511874] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"107.095727ms","start":"2026-09-22T03:53:58.272147Z","end":"2026-09-22T03:53:58.379243Z","steps":["trace[1269511874] 'process raft request' (duration: 106.784672ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:53:58.379563Z","caller":"traceutil/trace.go:171","msg":"trace[407412453] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"106.529548ms","start":"2026-09-22T03:53:58.273023Z","end":"2026-09-22T03:53:58.379552Z","steps":["trace[407412453] 'process raft request' (duration: 106.149252ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:00.405953Z","caller":"traceutil/trace.go:171","msg":"trace[1167819824] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"220.808544ms","start":"2026-09-22T03:54:00.185116Z","end":"2026-09-22T03:54:00.405925Z","steps":["trace[1167819824] 'process raft request' (duration: 220.57238ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:06.62693Z","caller":"traceutil/trace.go:171","msg":"trace[337866534] linearizableReadLoop","detail":"{readStateIndex:5153; appliedIndex:5152; }","duration":"193.208019ms","start":"2026-09-22T03:54:06.433687Z","end":"2026-09-22T03:54:06.626895Z","steps":["trace[337866534] 'read index received' (duration: 192.629849ms)","trace[337866534] 'applied index is now lower than readState.Index' (duration: 577.17µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:54:06.627114Z","caller":"traceutil/trace.go:171","msg":"trace[429646459] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"220.235469ms","start":"2026-09-22T03:54:06.40686Z","end":"2026-09-22T03:54:06.627095Z","steps":["trace[429646459] 'process raft request' (duration: 219.555618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:06.627527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.421094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-22T03:54:06.627597Z","caller":"traceutil/trace.go:171","msg":"trace[397119281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4963; }","duration":"187.562247ms","start":"2026-09-22T03:54:06.440018Z","end":"2026-09-22T03:54:06.62758Z","steps":["trace[397119281] 'agreement among raft nodes before linearized reading' (duration: 187.350563ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:06.627733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.055542ms","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-22T03:54:06.627748Z","caller":"traceutil/trace.go:171","msg":"trace[348315577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4963; }","duration":"194.111973ms","start":"2026-09-22T03:54:06.433631Z","end":"2026-09-22T03:54:06.627743Z","steps":["trace[348315577] 'agreement among raft nodes before linearized reading' (duration: 194.060412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:06.628018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.75933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-22T03:54:06.628045Z","caller":"traceutil/trace.go:171","msg":"trace[809667684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4963; }","duration":"187.829292ms","start":"2026-09-22T03:54:06.440208Z","end":"2026-09-22T03:54:06.628037Z","steps":["trace[809667684] 'agreement among raft nodes before linearized reading' (duration: 187.703299ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:07.227551Z","caller":"traceutil/trace.go:171","msg":"trace[848177602] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"120.000857ms","start":"2026-09-22T03:54:07.10746Z","end":"2026-09-22T03:54:07.227461Z","steps":["trace[848177602] 'process raft request' (duration: 26.470659ms)","trace[848177602] 'compare' (duration: 93.202023ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:54:07.227813Z","caller":"traceutil/trace.go:171","msg":"trace[1999837197] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"119.496489ms","start":"2026-09-22T03:54:07.108301Z","end":"2026-09-22T03:54:07.227797Z","steps":["trace[1999837197] 'process raft request' (duration: 119.114572ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:07.228117Z","caller":"traceutil/trace.go:171","msg":"trace[2099150106] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"118.083056ms","start":"2026-09-22T03:54:07.110007Z","end":"2026-09-22T03:54:07.22809Z","steps":["trace[2099150106] 'process raft request' (duration: 117.461306ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:07.230198Z","caller":"traceutil/trace.go:171","msg":"trace[643554980] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"119.589531ms","start":"2026-09-22T03:54:07.10811Z","end":"2026-09-22T03:54:07.2277Z","steps":["trace[643554980] 'process raft request' (duration: 119.249115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:21.205561Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":188483536905325619,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-22T03:54:21.213604Z","caller":"traceutil/trace.go:171","msg":"trace[1064913657] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"542.112106ms","start":"2026-09-22T03:54:20.671468Z","end":"2026-09-22T03:54:21.21358Z","steps":["trace[1064913657] 'process raft request' (duration: 541.963674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:21.213737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:54:20.671451Z","time spent":"542.206647ms","remote":"127.0.0.1:60890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11652,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T03:54:21.356965Z","caller":"traceutil/trace.go:171","msg":"trace[156409749] linearizableReadLoop","detail":"{readStateIndex:5385; appliedIndex:5383; }","duration":"652.290296ms","start":"2026-09-22T03:54:20.70465Z","end":"2026-09-22T03:54:21.356941Z","steps":["trace[156409749] 'read index received' (duration: 508.884184ms)","trace[156409749] 'applied index is now lower than readState.Index' (duration: 143.404992ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:54:21.35706Z","caller":"traceutil/trace.go:171","msg":"trace[2097643529] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"231.483538ms","start":"2026-09-22T03:54:21.125532Z","end":"2026-09-22T03:54:21.357016Z","steps":["trace[2097643529] 'process raft request' (duration: 231.264334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:21.357204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.5362ms","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-22T03:54:21.357243Z","caller":"traceutil/trace.go:171","msg":"trace[392967481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5189; }","duration":"652.645062ms","start":"2026-09-22T03:54:20.704586Z","end":"2026-09-22T03:54:21.357231Z","steps":["trace[392967481] 'agreement among raft nodes before linearized reading' (duration: 652.465129ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:21.357275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:54:20.704557Z","time spent":"652.710322ms","remote":"127.0.0.1:60956","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":"info","ts":"2026-09-22T03:54:21.371073Z","caller":"traceutil/trace.go:171","msg":"trace[1785575460] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"149.388137ms","start":"2026-09-22T03:54:21.22165Z","end":"2026-09-22T03:54:21.371038Z","steps":["trace[1785575460] 'process raft request' (duration: 149.118473ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:21.371125Z","caller":"traceutil/trace.go:171","msg":"trace[945714212] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"144.195014ms","start":"2026-09-22T03:54:21.226907Z","end":"2026-09-22T03:54:21.371102Z","steps":["trace[945714212] 'process raft request' (duration: 144.100483ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:21.371463Z","caller":"traceutil/trace.go:171","msg":"trace[18971392] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"149.778664ms","start":"2026-09-22T03:54:21.221673Z","end":"2026-09-22T03:54:21.371451Z","steps":["trace[18971392] 'process raft request' (duration: 149.256795ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:21.446945Z","caller":"traceutil/trace.go:171","msg":"trace[891079490] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"115.291472ms","start":"2026-09-22T03:54:21.331616Z","end":"2026-09-22T03:54:21.446907Z","steps":["trace[891079490] 'process raft request' (duration: 114.902536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:21.447241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.296784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-22T03:54:21.447265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.097041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-22T03:54:21.447321Z","caller":"traceutil/trace.go:171","msg":"trace[354113575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5194; }","duration":"677.455067ms","start":"2026-09-22T03:54:20.769849Z","end":"2026-09-22T03:54:21.447304Z","steps":["trace[354113575] 'agreement among raft nodes before linearized reading' (duration: 677.088161ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:21.447379Z","caller":"traceutil/trace.go:171","msg":"trace[1720367106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5194; }","duration":"682.246744ms","start":"2026-09-22T03:54:20.765082Z","end":"2026-09-22T03:54:21.447329Z","steps":["trace[1720367106] 'agreement among raft nodes before linearized reading' (duration: 681.871408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:21.447405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:54:20.769815Z","time spent":"677.573389ms","remote":"127.0.0.1:60956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-22T03:54:21.447428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:54:20.765053Z","time spent":"682.364905ms","remote":"127.0.0.1:60956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-22T03:54:21.447551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.780346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-09-22T03:54:21.447582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.618387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-09-22T03:54:21.447616Z","caller":"traceutil/trace.go:171","msg":"trace[250251256] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5194; }","duration":"130.702808ms","start":"2026-09-22T03:54:21.316904Z","end":"2026-09-22T03:54:21.447607Z","steps":["trace[250251256] 'agreement among raft nodes before linearized reading' (duration: 130.056188ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:21.447631Z","caller":"traceutil/trace.go:171","msg":"trace[1128323489] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5194; }","duration":"676.874648ms","start":"2026-09-22T03:54:20.770731Z","end":"2026-09-22T03:54:21.447605Z","steps":["trace[1128323489] 'agreement among raft nodes before linearized reading' (duration: 676.244048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:21.447686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:54:20.770718Z","time spent":"676.949679ms","remote":"127.0.0.1:60956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-22T03:54:21.447919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.735923ms","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-22T03:54:21.448027Z","caller":"traceutil/trace.go:171","msg":"trace[1706024677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5194; }","duration":"704.935886ms","start":"2026-09-22T03:54:20.743071Z","end":"2026-09-22T03:54:21.448007Z","steps":["trace[1706024677] 'agreement among raft nodes before linearized reading' (duration: 703.89635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:21.448074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:54:20.74305Z","time spent":"705.010977ms","remote":"127.0.0.1:60956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-22T03:54:21.666505Z","caller":"traceutil/trace.go:171","msg":"trace[1828494333] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"115.512485ms","start":"2026-09-22T03:54:21.550952Z","end":"2026-09-22T03:54:21.666464Z","steps":["trace[1828494333] 'process raft request' (duration: 64.141885ms)","trace[1828494333] 'compare' (duration: 51.007605ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:54:21.666582Z","caller":"traceutil/trace.go:171","msg":"trace[1344017005] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"112.791241ms","start":"2026-09-22T03:54:21.553765Z","end":"2026-09-22T03:54:21.666556Z","steps":["trace[1344017005] 'process raft request' (duration: 112.73675ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:21.666861Z","caller":"traceutil/trace.go:171","msg":"trace[1429243005] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"113.269199ms","start":"2026-09-22T03:54:21.553574Z","end":"2026-09-22T03:54:21.666844Z","steps":["trace[1429243005] 'process raft request' (duration: 112.798851ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:42.457523Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2856} {"level":"info","ts":"2026-09-22T03:54:42.539419Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2856,"took":"80.661333ms","hash":3491979852,"current-db-size-bytes":52203520,"current-db-size":"52 MB","current-db-size-in-use-bytes":27627520,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T03:54:42.539484Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3491979852,"revision":2856,"compact-revision":-1} {"level":"info","ts":"2026-09-22T03:55:00.259629Z","caller":"traceutil/trace.go:171","msg":"trace[950132871] linearizableReadLoop","detail":"{readStateIndex:5744; appliedIndex:5743; }","duration":"106.089544ms","start":"2026-09-22T03:55:00.153493Z","end":"2026-09-22T03:55:00.259582Z","steps":["trace[950132871] 'read index received' (duration: 75.241048ms)","trace[950132871] 'applied index is now lower than readState.Index' (duration: 30.846166ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:55:00.259862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.346398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-22T03:55:00.259989Z","caller":"traceutil/trace.go:171","msg":"trace[1123189452] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5538; }","duration":"106.53661ms","start":"2026-09-22T03:55:00.153412Z","end":"2026-09-22T03:55:00.259948Z","steps":["trace[1123189452] 'agreement among raft nodes before linearized reading' (duration: 106.308817ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:55:00.259733Z","caller":"traceutil/trace.go:171","msg":"trace[1467149484] transaction","detail":"{read_only:false; response_revision:5538; number_of_response:1; }","duration":"153.509884ms","start":"2026-09-22T03:55:00.106156Z","end":"2026-09-22T03:55:00.259666Z","steps":["trace[1467149484] 'process raft request' (duration: 122.71895ms)","trace[1467149484] 'compare' (duration: 30.5238ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:58:02.941005Z","caller":"traceutil/trace.go:171","msg":"trace[1169068229] transaction","detail":"{read_only:false; response_revision:6800; number_of_response:1; }","duration":"100.811207ms","start":"2026-09-22T03:58:02.840163Z","end":"2026-09-22T03:58:02.940974Z","steps":["trace[1169068229] 'process raft request' (duration: 38.12504ms)","trace[1169068229] 'compare' (duration: 62.137743ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:58:02.940995Z","caller":"traceutil/trace.go:171","msg":"trace[975369064] transaction","detail":"{read_only:false; response_revision:6801; number_of_response:1; }","duration":"100.311726ms","start":"2026-09-22T03:58:02.840655Z","end":"2026-09-22T03:58:02.940966Z","steps":["trace[975369064] 'process raft request' (duration: 99.941151ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:59:42.464679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5344} {"level":"info","ts":"2026-09-22T03:59:42.576172Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5344,"took":"109.313343ms","hash":120674561,"current-db-size-bytes":52203520,"current-db-size":"52 MB","current-db-size-in-use-bytes":18759680,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-22T03:59:42.576219Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":120674561,"revision":5344,"compact-revision":2856}