{"level":"warn","ts":"2026-10-04T01:12:33.569757Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T01:12:33.569881Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.239: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.239:2380","--initial-cluster=instance=https://162.253.55.239:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.239:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.239: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-10-04T01:12:33.569979Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T01:12:33.569996Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.239:2380"]} {"level":"info","ts":"2026-10-04T01:12:33.570022Z","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-10-04T01:12:33.570973Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.239:2379"]} {"level":"info","ts":"2026-10-04T01:12:33.571098Z","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.239:2380"],"listen-peer-urls":["https://162.253.55.239:2380"],"advertise-client-urls":["https://162.253.55.239:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.239:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.239: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-10-04T01:12:33.575716Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.285484ms"} {"level":"info","ts":"2026-10-04T01:12:33.581864Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"548b318b3528a14c","cluster-id":"8bf5e58b9331730a"} {"level":"info","ts":"2026-10-04T01:12:33.581944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T01:12:33.581971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became follower at term 0"} {"level":"info","ts":"2026-10-04T01:12:33.581987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 548b318b3528a14c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T01:12:33.581995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became follower at term 1"} {"level":"info","ts":"2026-10-04T01:12:33.582041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c switched to configuration voters=(6092017394910798156)"} {"level":"warn","ts":"2026-10-04T01:12:33.589636Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T01:12:33.591414Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T01:12:33.593356Z","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-10-04T01:12:33.596132Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"548b318b3528a14c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T01:12:33.596768Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"548b318b3528a14c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T01:12:33.598151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c switched to configuration voters=(6092017394910798156)"} {"level":"info","ts":"2026-10-04T01:12:33.598397Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8bf5e58b9331730a","local-member-id":"548b318b3528a14c","added-peer-id":"548b318b3528a14c","added-peer-peer-urls":["https://162.253.55.239:2380"]} {"level":"info","ts":"2026-10-04T01:12:33.596388Z","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-10-04T01:12:33.598649Z","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-10-04T01:12:33.598673Z","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-10-04T01:12:33.60104Z","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-10-04T01:12:33.601209Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.239:2380"} {"level":"info","ts":"2026-10-04T01:12:33.601248Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.239:2380"} {"level":"info","ts":"2026-10-04T01:12:33.601492Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"548b318b3528a14c","initial-advertise-peer-urls":["https://162.253.55.239:2380"],"listen-peer-urls":["https://162.253.55.239:2380"],"advertise-client-urls":["https://162.253.55.239:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.239:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T01:12:33.601529Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T01:12:34.482802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T01:12:34.482877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T01:12:34.482924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c received MsgPreVoteResp from 548b318b3528a14c at term 1"} {"level":"info","ts":"2026-10-04T01:12:34.482943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became candidate at term 2"} {"level":"info","ts":"2026-10-04T01:12:34.48296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c received MsgVoteResp from 548b318b3528a14c at term 2"} {"level":"info","ts":"2026-10-04T01:12:34.482984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"548b318b3528a14c became leader at term 2"} {"level":"info","ts":"2026-10-04T01:12:34.482997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 548b318b3528a14c elected leader 548b318b3528a14c at term 2"} {"level":"info","ts":"2026-10-04T01:12:34.484048Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T01:12:34.484922Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"548b318b3528a14c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.239:2379]}","request-path":"/0/members/548b318b3528a14c/attributes","cluster-id":"8bf5e58b9331730a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T01:12:34.485096Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T01:12:34.485175Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T01:12:34.485697Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T01:12:34.485727Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T01:12:34.485671Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8bf5e58b9331730a","local-member-id":"548b318b3528a14c","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T01:12:34.48592Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T01:12:34.485977Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T01:12:34.488283Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.239:2379"} {"level":"info","ts":"2026-10-04T01:12:34.488781Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-04T01:12:48.468276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.663497ms","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-10-04T01:12:48.468378Z","caller":"traceutil/trace.go:171","msg":"trace[1714062044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:364; }","duration":"255.827449ms","start":"2026-10-04T01:12:48.212533Z","end":"2026-10-04T01:12:48.46836Z","steps":["trace[1714062044] 'range keys from in-memory index tree' (duration: 255.550494ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:12:48.615913Z","caller":"traceutil/trace.go:171","msg":"trace[395568135] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"143.413001ms","start":"2026-10-04T01:12:48.472453Z","end":"2026-10-04T01:12:48.615866Z","steps":["trace[395568135] 'process raft request' (duration: 143.070335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:12:48.835736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.357555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T01:12:48.835801Z","caller":"traceutil/trace.go:171","msg":"trace[1398561675] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:376; }","duration":"213.54212ms","start":"2026-10-04T01:12:48.622249Z","end":"2026-10-04T01:12:48.835791Z","steps":["trace[1398561675] 'read index received' (duration: 111.687446ms)","trace[1398561675] 'applied index is now lower than readState.Index' (duration: 101.853774ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:12:48.835935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.670762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-10-04T01:12:48.836083Z","caller":"traceutil/trace.go:171","msg":"trace[368283296] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"215.27994ms","start":"2026-10-04T01:12:48.620797Z","end":"2026-10-04T01:12:48.836077Z","steps":["trace[368283296] 'process raft request' (duration: 113.248183ms)","trace[368283296] 'compare' (duration: 101.276403ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:12:48.836044Z","caller":"traceutil/trace.go:171","msg":"trace[1601225741] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:366; }","duration":"213.822904ms","start":"2026-10-04T01:12:48.622206Z","end":"2026-10-04T01:12:48.836028Z","steps":["trace[1601225741] 'agreement among raft nodes before linearized reading' (duration: 213.640531ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.6397Z","caller":"traceutil/trace.go:171","msg":"trace[619409019] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"168.463961ms","start":"2026-10-04T01:13:12.471205Z","end":"2026-10-04T01:13:12.639669Z","steps":["trace[619409019] 'process raft request' (duration: 168.298109ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.718478Z","caller":"traceutil/trace.go:171","msg":"trace[49636950] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"246.547968ms","start":"2026-10-04T01:13:12.471902Z","end":"2026-10-04T01:13:12.71845Z","steps":["trace[49636950] 'process raft request' (duration: 246.272764ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.718518Z","caller":"traceutil/trace.go:171","msg":"trace[1588688776] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"238.127369ms","start":"2026-10-04T01:13:12.480359Z","end":"2026-10-04T01:13:12.718487Z","steps":["trace[1588688776] 'process raft request' (duration: 238.008718ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.71886Z","caller":"traceutil/trace.go:171","msg":"trace[727070082] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"212.601232ms","start":"2026-10-04T01:13:12.506249Z","end":"2026-10-04T01:13:12.71885Z","steps":["trace[727070082] 'process raft request' (duration: 212.355378ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.71894Z","caller":"traceutil/trace.go:171","msg":"trace[1976874483] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"212.657243ms","start":"2026-10-04T01:13:12.506271Z","end":"2026-10-04T01:13:12.718929Z","steps":["trace[1976874483] 'process raft request' (duration: 212.46174ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.718656Z","caller":"traceutil/trace.go:171","msg":"trace[939417797] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"234.83837ms","start":"2026-10-04T01:13:12.483805Z","end":"2026-10-04T01:13:12.718643Z","steps":["trace[939417797] 'process raft request' (duration: 234.598116ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.718696Z","caller":"traceutil/trace.go:171","msg":"trace[514887211] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"243.834547ms","start":"2026-10-04T01:13:12.474854Z","end":"2026-10-04T01:13:12.718688Z","steps":["trace[514887211] 'process raft request' (duration: 243.453211ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.718706Z","caller":"traceutil/trace.go:171","msg":"trace[1874643772] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"233.265506ms","start":"2026-10-04T01:13:12.485398Z","end":"2026-10-04T01:13:12.718663Z","steps":["trace[1874643772] 'process raft request' (duration: 233.078813ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.718673Z","caller":"traceutil/trace.go:171","msg":"trace[293700576] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"233.203035ms","start":"2026-10-04T01:13:12.485432Z","end":"2026-10-04T01:13:12.718635Z","steps":["trace[293700576] 'process raft request' (duration: 233.009042ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.718777Z","caller":"traceutil/trace.go:171","msg":"trace[1062720484] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"228.782588ms","start":"2026-10-04T01:13:12.489916Z","end":"2026-10-04T01:13:12.718699Z","steps":["trace[1062720484] 'process raft request' (duration: 228.640446ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.71946Z","caller":"traceutil/trace.go:171","msg":"trace[2073762217] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"195.077646ms","start":"2026-10-04T01:13:12.524352Z","end":"2026-10-04T01:13:12.71943Z","steps":["trace[2073762217] 'process raft request' (duration: 194.595978ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.899993Z","caller":"traceutil/trace.go:171","msg":"trace[1087151571] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"141.313938ms","start":"2026-10-04T01:13:12.758652Z","end":"2026-10-04T01:13:12.899966Z","steps":["trace[1087151571] 'process raft request' (duration: 67.937203ms)","trace[1087151571] 'compare' (duration: 73.03433ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:13:12.900408Z","caller":"traceutil/trace.go:171","msg":"trace[266568796] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"140.011069ms","start":"2026-10-04T01:13:12.760366Z","end":"2026-10-04T01:13:12.900377Z","steps":["trace[266568796] 'process raft request' (duration: 139.502191ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.900901Z","caller":"traceutil/trace.go:171","msg":"trace[1446376372] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"138.733349ms","start":"2026-10-04T01:13:12.762143Z","end":"2026-10-04T01:13:12.900876Z","steps":["trace[1446376372] 'process raft request' (duration: 138.169711ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.90105Z","caller":"traceutil/trace.go:171","msg":"trace[1032073049] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"124.861529ms","start":"2026-10-04T01:13:12.776162Z","end":"2026-10-04T01:13:12.901023Z","steps":["trace[1032073049] 'process raft request' (duration: 124.484843ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.901303Z","caller":"traceutil/trace.go:171","msg":"trace[1382631577] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"105.113668ms","start":"2026-10-04T01:13:12.796178Z","end":"2026-10-04T01:13:12.901291Z","steps":["trace[1382631577] 'process raft request' (duration: 104.786923ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:12.958896Z","caller":"traceutil/trace.go:171","msg":"trace[71760118] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"117.692349ms","start":"2026-10-04T01:13:12.841189Z","end":"2026-10-04T01:13:12.958882Z","steps":["trace[71760118] 'process raft request' (duration: 117.552627ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:13.087871Z","caller":"traceutil/trace.go:171","msg":"trace[823726278] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"127.932078ms","start":"2026-10-04T01:13:12.959915Z","end":"2026-10-04T01:13:13.087847Z","steps":["trace[823726278] 'process raft request' (duration: 127.840757ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:13.087992Z","caller":"traceutil/trace.go:171","msg":"trace[1863113543] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"129.288659ms","start":"2026-10-04T01:13:12.958669Z","end":"2026-10-04T01:13:13.087958Z","steps":["trace[1863113543] 'process raft request' (duration: 96.863148ms)","trace[1863113543] 'compare' (duration: 32.075865ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:13:13.095061Z","caller":"traceutil/trace.go:171","msg":"trace[1536603088] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"113.785493ms","start":"2026-10-04T01:13:12.98125Z","end":"2026-10-04T01:13:13.095036Z","steps":["trace[1536603088] 'process raft request' (duration: 108.444672ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:13.095801Z","caller":"traceutil/trace.go:171","msg":"trace[884116796] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"107.609568ms","start":"2026-10-04T01:13:12.988175Z","end":"2026-10-04T01:13:13.095785Z","steps":["trace[884116796] 'process raft request' (duration: 102.087165ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:19.141266Z","caller":"traceutil/trace.go:171","msg":"trace[1437136883] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"202.433236ms","start":"2026-10-04T01:13:18.938809Z","end":"2026-10-04T01:13:19.141242Z","steps":["trace[1437136883] 'read index received' (duration: 202.316124ms)","trace[1437136883] 'applied index is now lower than readState.Index' (duration: 116.272µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:13:19.141445Z","caller":"traceutil/trace.go:171","msg":"trace[488309576] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"208.413874ms","start":"2026-10-04T01:13:18.933004Z","end":"2026-10-04T01:13:19.141418Z","steps":["trace[488309576] 'process raft request' (duration: 208.1037ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:13:19.141554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.734431ms","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-10-04T01:13:19.141631Z","caller":"traceutil/trace.go:171","msg":"trace[740270493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"202.885463ms","start":"2026-10-04T01:13:18.938733Z","end":"2026-10-04T01:13:19.141618Z","steps":["trace[740270493] 'agreement among raft nodes before linearized reading' (duration: 202.715051ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:19.14522Z","caller":"traceutil/trace.go:171","msg":"trace[575195660] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"172.132749ms","start":"2026-10-04T01:13:18.973068Z","end":"2026-10-04T01:13:19.145201Z","steps":["trace[575195660] 'process raft request' (duration: 171.868335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:13:19.806972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.384135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T01:13:19.807088Z","caller":"traceutil/trace.go:171","msg":"trace[1906673790] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:614; }","duration":"649.154909ms","start":"2026-10-04T01:13:19.157915Z","end":"2026-10-04T01:13:19.80707Z","steps":["trace[1906673790] 'read index received' (duration: 7.872415ms)","trace[1906673790] 'applied index is now lower than readState.Index' (duration: 641.281364ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:13:19.807164Z","caller":"traceutil/trace.go:171","msg":"trace[1568074515] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"655.935298ms","start":"2026-10-04T01:13:19.151218Z","end":"2026-10-04T01:13:19.807153Z","steps":["trace[1568074515] 'process raft request' (duration: 262.289849ms)","trace[1568074515] 'compare' (duration: 393.201272ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:13:19.807235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:13:19.151206Z","time spent":"655.986209ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T01:13:19.807265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.65445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T01:13:19.807344Z","caller":"traceutil/trace.go:171","msg":"trace[354742083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"639.776991ms","start":"2026-10-04T01:13:19.167542Z","end":"2026-10-04T01:13:19.807319Z","steps":["trace[354742083] 'agreement among raft nodes before linearized reading' (duration: 639.648519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:13:19.807388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:13:19.167524Z","time spent":"639.856202ms","remote":"127.0.0.1:56968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T01:13:19.807638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.723987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T01:13:19.807655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.660794ms","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-10-04T01:13:19.80769Z","caller":"traceutil/trace.go:171","msg":"trace[502574679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"236.740946ms","start":"2026-10-04T01:13:19.570939Z","end":"2026-10-04T01:13:19.80768Z","steps":["trace[502574679] 'agreement among raft nodes before linearized reading' (duration: 236.623634ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:13:19.807687Z","caller":"traceutil/trace.go:171","msg":"trace[1038675342] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:597; }","duration":"649.775197ms","start":"2026-10-04T01:13:19.157899Z","end":"2026-10-04T01:13:19.807674Z","steps":["trace[1038675342] 'agreement among raft nodes before linearized reading' (duration: 649.687146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:13:19.807723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:13:19.157849Z","time spent":"649.864759ms","remote":"127.0.0.1:57280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-10-04T01:13:19.98204Z","caller":"traceutil/trace.go:171","msg":"trace[315355809] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"169.165753ms","start":"2026-10-04T01:13:19.812842Z","end":"2026-10-04T01:13:19.982008Z","steps":["trace[315355809] 'process raft request' (duration: 134.260391ms)","trace[315355809] 'compare' (duration: 34.71927ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:13:36.069339Z","caller":"traceutil/trace.go:171","msg":"trace[875070587] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"173.247946ms","start":"2026-10-04T01:13:35.896069Z","end":"2026-10-04T01:13:36.069317Z","steps":["trace[875070587] 'process raft request' (duration: 173.112974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:14:54.965846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.970707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-10-04T01:14:54.965938Z","caller":"traceutil/trace.go:171","msg":"trace[1150904440] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1830; }","duration":"615.120103ms","start":"2026-10-04T01:14:54.350795Z","end":"2026-10-04T01:14:54.965915Z","steps":["trace[1150904440] 'range keys from in-memory index tree' (duration: 614.783001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:14:54.966013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:14:54.350775Z","time spent":"615.225437ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-04T01:14:54.966019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.166887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T01:14:54.966086Z","caller":"traceutil/trace.go:171","msg":"trace[1592126369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1830; }","duration":"799.275681ms","start":"2026-10-04T01:14:54.166792Z","end":"2026-10-04T01:14:54.966068Z","steps":["trace[1592126369] 'range keys from in-memory index tree' (duration: 799.054863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:14:54.966123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:14:54.166774Z","time spent":"799.338874ms","remote":"127.0.0.1:56968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T01:14:54.966344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000930502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-04T01:14:54.967385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.911765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-04T01:14:54.967441Z","caller":"traceutil/trace.go:171","msg":"trace[907944090] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1830; }","duration":"902.012898ms","start":"2026-10-04T01:14:54.065414Z","end":"2026-10-04T01:14:54.967427Z","steps":["trace[907944090] 'range keys from in-memory index tree' (duration: 900.530784ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:14:54.967481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:14:54.065394Z","time spent":"902.077691ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-04T01:14:54.966434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001222293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b019051c-7405-4ade-8a03-c6731acc0aba\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-04T01:14:54.967417Z","caller":"traceutil/trace.go:171","msg":"trace[183750680] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1830; }","duration":"1.002020593s","start":"2026-10-04T01:14:53.965366Z","end":"2026-10-04T01:14:54.967387Z","steps":["trace[183750680] 'range keys from in-memory index tree' (duration: 1.000750865s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:14:54.967651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:14:53.96536Z","time spent":"1.002273882s","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-04T01:14:54.967549Z","caller":"traceutil/trace.go:171","msg":"trace[2058487448] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b019051c-7405-4ade-8a03-c6731acc0aba; range_end:; response_count:1; response_revision:1830; }","duration":"1.002355065s","start":"2026-10-04T01:14:53.965169Z","end":"2026-10-04T01:14:54.967524Z","steps":["trace[2058487448] 'range keys from in-memory index tree' (duration: 1.001100328s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:14:54.966639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.496675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b019051c-7405-4ade-8a03-c6731acc0aba\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-04T01:14:54.967915Z","caller":"traceutil/trace.go:171","msg":"trace[258125686] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b019051c-7405-4ade-8a03-c6731acc0aba; range_end:; response_count:1; response_revision:1830; }","duration":"572.817504ms","start":"2026-10-04T01:14:54.395082Z","end":"2026-10-04T01:14:54.9679Z","steps":["trace[258125686] 'range keys from in-memory index tree' (duration: 571.289148ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:14:54.968015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:14:54.395059Z","time spent":"572.941568ms","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b019051c-7405-4ade-8a03-c6731acc0aba\" "} {"level":"warn","ts":"2026-10-04T01:14:54.967868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:14:53.96514Z","time spent":"1.002682887s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b019051c-7405-4ade-8a03-c6731acc0aba\" "} {"level":"warn","ts":"2026-10-04T01:14:54.966648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.667347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T01:14:54.966772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.938721ms","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-10-04T01:14:54.968307Z","caller":"traceutil/trace.go:171","msg":"trace[544858455] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1830; }","duration":"412.38583ms","start":"2026-10-04T01:14:54.5559Z","end":"2026-10-04T01:14:54.968286Z","steps":["trace[544858455] 'count revisions from in-memory index tree' (duration: 410.583014ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:14:54.968439Z","caller":"traceutil/trace.go:171","msg":"trace[1994161049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1830; }","duration":"366.616773ms","start":"2026-10-04T01:14:54.601809Z","end":"2026-10-04T01:14:54.968426Z","steps":["trace[1994161049] 'range keys from in-memory index tree' (duration: 364.861668ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:14:54.968483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:14:54.6018Z","time spent":"366.672785ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-04T01:14:54.968467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:14:54.555892Z","time spent":"412.553727ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-10-04T01:14:54.966855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.742792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-04T01:14:54.968825Z","caller":"traceutil/trace.go:171","msg":"trace[315405744] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1830; }","duration":"590.731514ms","start":"2026-10-04T01:14:54.378075Z","end":"2026-10-04T01:14:54.968806Z","steps":["trace[315405744] 'range keys from in-memory index tree' (duration: 587.658931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:14:54.966576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.753911ms","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-10-04T01:14:54.970303Z","caller":"traceutil/trace.go:171","msg":"trace[1956401642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1830; }","duration":"420.537921ms","start":"2026-10-04T01:14:54.54975Z","end":"2026-10-04T01:14:54.970288Z","steps":["trace[1956401642] 'range keys from in-memory index tree' (duration: 416.518692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:14:54.970485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:14:54.549733Z","time spent":"420.696827ms","remote":"127.0.0.1:57306","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-10-04T01:14:54.970336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:14:54.378058Z","time spent":"592.198549ms","remote":"127.0.0.1:39584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-04T01:14:55.018243Z","caller":"traceutil/trace.go:171","msg":"trace[1768163794] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"367.96311ms","start":"2026-10-04T01:14:54.65026Z","end":"2026-10-04T01:14:55.018223Z","steps":["trace[1768163794] 'process raft request' (duration: 367.828925ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:14:55.018343Z","caller":"traceutil/trace.go:171","msg":"trace[1483695938] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1899; }","duration":"324.983575ms","start":"2026-10-04T01:14:54.69334Z","end":"2026-10-04T01:14:55.018323Z","steps":["trace[1483695938] 'read index received' (duration: 324.975205ms)","trace[1483695938] 'applied index is now lower than readState.Index' (duration: 7.14µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:14:55.018369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:14:54.650242Z","time spent":"368.057533ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T01:14:55.018451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.1025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T01:14:55.018507Z","caller":"traceutil/trace.go:171","msg":"trace[1844219815] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1831; }","duration":"325.196474ms","start":"2026-10-04T01:14:54.693298Z","end":"2026-10-04T01:14:55.018494Z","steps":["trace[1844219815] 'agreement among raft nodes before linearized reading' (duration: 325.10324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:14:55.018581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:14:54.693287Z","time spent":"325.279306ms","remote":"127.0.0.1:55106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-10-04T01:14:55.186582Z","caller":"traceutil/trace.go:171","msg":"trace[607573800] linearizableReadLoop","detail":"{readStateIndex:1905; appliedIndex:1899; }","duration":"168.181549ms","start":"2026-10-04T01:14:55.018379Z","end":"2026-10-04T01:14:55.18656Z","steps":["trace[607573800] 'read index received' (duration: 167.859447ms)","trace[607573800] 'applied index is now lower than readState.Index' (duration: 321.162µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:14:55.186725Z","caller":"traceutil/trace.go:171","msg":"trace[1520467139] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"212.445577ms","start":"2026-10-04T01:14:54.974256Z","end":"2026-10-04T01:14:55.186701Z","steps":["trace[1520467139] 'process raft request' (duration: 212.191718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:14:55.186771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.404473ms","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-10-04T01:14:55.186801Z","caller":"traceutil/trace.go:171","msg":"trace[374432022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1837; }","duration":"474.487345ms","start":"2026-10-04T01:14:54.712304Z","end":"2026-10-04T01:14:55.186792Z","steps":["trace[374432022] 'agreement among raft nodes before linearized reading' (duration: 474.33589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:14:55.186835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:14:54.712283Z","time spent":"474.544098ms","remote":"127.0.0.1:57306","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-10-04T01:14:55.186924Z","caller":"traceutil/trace.go:171","msg":"trace[992328404] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"210.115391ms","start":"2026-10-04T01:14:54.9768Z","end":"2026-10-04T01:14:55.186915Z","steps":["trace[992328404] 'process raft request' (duration: 209.716186ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:14:55.187004Z","caller":"traceutil/trace.go:171","msg":"trace[508338691] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"212.619704ms","start":"2026-10-04T01:14:54.974325Z","end":"2026-10-04T01:14:55.186945Z","steps":["trace[508338691] 'process raft request' (duration: 212.152847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:14:55.187116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.506537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T01:14:55.187147Z","caller":"traceutil/trace.go:171","msg":"trace[586675684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1837; }","duration":"219.613131ms","start":"2026-10-04T01:14:54.967523Z","end":"2026-10-04T01:14:55.187136Z","steps":["trace[586675684] 'agreement among raft nodes before linearized reading' (duration: 219.568379ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:14:55.187198Z","caller":"traceutil/trace.go:171","msg":"trace[1396554754] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"214.478812ms","start":"2026-10-04T01:14:54.972703Z","end":"2026-10-04T01:14:55.187182Z","steps":["trace[1396554754] 'process raft request' (duration: 213.546818ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:14:55.187345Z","caller":"traceutil/trace.go:171","msg":"trace[1937127266] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"214.589107ms","start":"2026-10-04T01:14:54.972723Z","end":"2026-10-04T01:14:55.187312Z","steps":["trace[1937127266] 'process raft request' (duration: 213.648692ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:14:55.187475Z","caller":"traceutil/trace.go:171","msg":"trace[1762527441] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"214.69544ms","start":"2026-10-04T01:14:54.972767Z","end":"2026-10-04T01:14:55.187462Z","steps":["trace[1762527441] 'process raft request' (duration: 213.645282ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:15:43.637957Z","caller":"traceutil/trace.go:171","msg":"trace[1287451993] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"121.866367ms","start":"2026-10-04T01:15:43.516068Z","end":"2026-10-04T01:15:43.637934Z","steps":["trace[1287451993] 'process raft request' (duration: 121.715093ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:15:43.638119Z","caller":"traceutil/trace.go:171","msg":"trace[1149742478] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2220; }","duration":"120.478537ms","start":"2026-10-04T01:15:43.517567Z","end":"2026-10-04T01:15:43.638045Z","steps":["trace[1149742478] 'read index received' (duration: 120.468327ms)","trace[1149742478] 'applied index is now lower than readState.Index' (duration: 8.17µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:15:43.638304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.724434ms","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-10-04T01:15:43.638343Z","caller":"traceutil/trace.go:171","msg":"trace[994165743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2141; }","duration":"120.818767ms","start":"2026-10-04T01:15:43.517512Z","end":"2026-10-04T01:15:43.638331Z","steps":["trace[994165743] 'agreement among raft nodes before linearized reading' (duration: 120.673722ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:15:43.959063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.660369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T01:15:43.959234Z","caller":"traceutil/trace.go:171","msg":"trace[1410403090] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"316.928493ms","start":"2026-10-04T01:15:43.642288Z","end":"2026-10-04T01:15:43.959216Z","steps":["trace[1410403090] 'process raft request' (duration: 316.873461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:15:43.959298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:15:43.642272Z","time spent":"316.995966ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T01:15:43.959493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.873592ms","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-10-04T01:15:43.959571Z","caller":"traceutil/trace.go:171","msg":"trace[885635394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2143; }","duration":"409.001036ms","start":"2026-10-04T01:15:43.550548Z","end":"2026-10-04T01:15:43.959549Z","steps":["trace[885635394] 'agreement among raft nodes before linearized reading' (duration: 408.720898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:15:43.959611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:15:43.550531Z","time spent":"409.068858ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-04T01:15:43.959717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.437426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-04T01:15:43.959773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.167269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-04T01:15:43.959686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.06129ms","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-10-04T01:15:43.959793Z","caller":"traceutil/trace.go:171","msg":"trace[1019111994] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2143; }","duration":"383.58505ms","start":"2026-10-04T01:15:43.576186Z","end":"2026-10-04T01:15:43.959771Z","steps":["trace[1019111994] 'agreement among raft nodes before linearized reading' (duration: 383.363184ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:15:43.959809Z","caller":"traceutil/trace.go:171","msg":"trace[1762234879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2143; }","duration":"381.20289ms","start":"2026-10-04T01:15:43.578595Z","end":"2026-10-04T01:15:43.959798Z","steps":["trace[1762234879] 'agreement among raft nodes before linearized reading' (duration: 381.088357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:15:43.959834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:15:43.576172Z","time spent":"383.653572ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-04T01:15:43.959838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:15:43.578555Z","time spent":"381.277642ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-04T01:15:43.960146Z","caller":"traceutil/trace.go:171","msg":"trace[1994766728] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"321.104065ms","start":"2026-10-04T01:15:43.63816Z","end":"2026-10-04T01:15:43.959264Z","steps":["trace[1994766728] 'read index received' (duration: 148.168899ms)","trace[1994766728] 'applied index is now lower than readState.Index' (duration: 172.932046ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:15:43.9595Z","caller":"traceutil/trace.go:171","msg":"trace[60386245] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"385.374222ms","start":"2026-10-04T01:15:43.573879Z","end":"2026-10-04T01:15:43.959254Z","steps":["trace[60386245] 'process raft request' (duration: 212.435766ms)","trace[60386245] 'compare' (duration: 172.505813ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:15:43.960666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:15:43.573861Z","time spent":"386.64818ms","remote":"127.0.0.1:57306","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-10-04T01:15:43.959822Z","caller":"traceutil/trace.go:171","msg":"trace[1871165500] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2143; }","duration":"404.225645ms","start":"2026-10-04T01:15:43.555582Z","end":"2026-10-04T01:15:43.959807Z","steps":["trace[1871165500] 'agreement among raft nodes before linearized reading' (duration: 403.973548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:15:43.961026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:15:43.555566Z","time spent":"405.424431ms","remote":"127.0.0.1:57306","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-10-04T01:15:44.103039Z","caller":"traceutil/trace.go:171","msg":"trace[1922934430] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2222; }","duration":"142.82812ms","start":"2026-10-04T01:15:43.960189Z","end":"2026-10-04T01:15:44.103017Z","steps":["trace[1922934430] 'read index received' (duration: 142.773768ms)","trace[1922934430] 'applied index is now lower than readState.Index' (duration: 53.232µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:15:44.103131Z","caller":"traceutil/trace.go:171","msg":"trace[224005046] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"311.732899ms","start":"2026-10-04T01:15:43.791376Z","end":"2026-10-04T01:15:44.103109Z","steps":["trace[224005046] 'process raft request' (duration: 311.514122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:15:44.103247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.322046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-04T01:15:44.103284Z","caller":"traceutil/trace.go:171","msg":"trace[140047919] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2144; }","duration":"342.418359ms","start":"2026-10-04T01:15:43.760856Z","end":"2026-10-04T01:15:44.103274Z","steps":["trace[140047919] 'agreement among raft nodes before linearized reading' (duration: 342.238094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:15:44.103289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:15:43.791349Z","time spent":"311.864802ms","remote":"127.0.0.1:57202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T01:15:44.103319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:15:43.760834Z","time spent":"342.476941ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-10-04T01:15:44.193596Z","caller":"traceutil/trace.go:171","msg":"trace[81794159] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"229.142372ms","start":"2026-10-04T01:15:43.964434Z","end":"2026-10-04T01:15:44.193576Z","steps":["trace[81794159] 'process raft request' (duration: 229.07853ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:15:44.193818Z","caller":"traceutil/trace.go:171","msg":"trace[153335745] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"228.945517ms","start":"2026-10-04T01:15:43.964858Z","end":"2026-10-04T01:15:44.193804Z","steps":["trace[153335745] 'process raft request' (duration: 228.685729ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:15:44.194047Z","caller":"traceutil/trace.go:171","msg":"trace[189248073] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"230.378419ms","start":"2026-10-04T01:15:43.963655Z","end":"2026-10-04T01:15:44.194034Z","steps":["trace[189248073] 'process raft request' (duration: 229.683758ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:15:44.194072Z","caller":"traceutil/trace.go:171","msg":"trace[1195771113] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"175.398057ms","start":"2026-10-04T01:15:44.018663Z","end":"2026-10-04T01:15:44.194061Z","steps":["trace[1195771113] 'process raft request' (duration: 175.090208ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:15:44.194191Z","caller":"traceutil/trace.go:171","msg":"trace[2146107429] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"115.090774ms","start":"2026-10-04T01:15:44.079092Z","end":"2026-10-04T01:15:44.194182Z","steps":["trace[2146107429] 'process raft request' (duration: 114.931199ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:15:44.194164Z","caller":"traceutil/trace.go:171","msg":"trace[1211285327] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"230.023838ms","start":"2026-10-04T01:15:43.9641Z","end":"2026-10-04T01:15:44.194123Z","steps":["trace[1211285327] 'process raft request' (duration: 229.375399ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:16:24.510857Z","caller":"traceutil/trace.go:171","msg":"trace[731488968] linearizableReadLoop","detail":"{readStateIndex:2553; appliedIndex:2552; }","duration":"131.440949ms","start":"2026-10-04T01:16:24.3794Z","end":"2026-10-04T01:16:24.510841Z","steps":["trace[731488968] 'read index received' (duration: 130.779693ms)","trace[731488968] 'applied index is now lower than readState.Index' (duration: 660.386µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:16:24.510955Z","caller":"traceutil/trace.go:171","msg":"trace[210635305] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"179.299413ms","start":"2026-10-04T01:16:24.331647Z","end":"2026-10-04T01:16:24.510947Z","steps":["trace[210635305] 'process raft request' (duration: 178.578175ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:16:24.511096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.46717ms","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-10-04T01:16:24.511143Z","caller":"traceutil/trace.go:171","msg":"trace[539228675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2465; }","duration":"123.521112ms","start":"2026-10-04T01:16:24.387607Z","end":"2026-10-04T01:16:24.511128Z","steps":["trace[539228675] 'agreement among raft nodes before linearized reading' (duration: 123.382858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:16:24.511253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.850199ms","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-10-04T01:16:24.511273Z","caller":"traceutil/trace.go:171","msg":"trace[1733007988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2465; }","duration":"131.895251ms","start":"2026-10-04T01:16:24.379372Z","end":"2026-10-04T01:16:24.511267Z","steps":["trace[1733007988] 'agreement among raft nodes before linearized reading' (duration: 131.84344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:16:56.948965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.256014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T01:16:56.949123Z","caller":"traceutil/trace.go:171","msg":"trace[1718107415] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"214.901808ms","start":"2026-10-04T01:16:56.734204Z","end":"2026-10-04T01:16:56.949106Z","steps":["trace[1718107415] 'process raft request' (duration: 88.423188ms)","trace[1718107415] 'compare' (duration: 126.149622ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:16:56.94931Z","caller":"traceutil/trace.go:171","msg":"trace[1491246298] linearizableReadLoop","detail":"{readStateIndex:2743; appliedIndex:2741; }","duration":"138.122391ms","start":"2026-10-04T01:16:56.811161Z","end":"2026-10-04T01:16:56.949283Z","steps":["trace[1491246298] 'read index received' (duration: 11.460107ms)","trace[1491246298] 'applied index is now lower than readState.Index' (duration: 126.661254ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:16:56.949472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.295214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T01:16:56.94957Z","caller":"traceutil/trace.go:171","msg":"trace[1846343042] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2648; }","duration":"138.419917ms","start":"2026-10-04T01:16:56.811109Z","end":"2026-10-04T01:16:56.949529Z","steps":["trace[1846343042] 'agreement among raft nodes before linearized reading' (duration: 138.293164ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:16:56.949512Z","caller":"traceutil/trace.go:171","msg":"trace[1575718918] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"212.042184ms","start":"2026-10-04T01:16:56.737437Z","end":"2026-10-04T01:16:56.949479Z","steps":["trace[1575718918] 'process raft request' (duration: 211.623795ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:20:47.314725Z","caller":"traceutil/trace.go:171","msg":"trace[233359011] transaction","detail":"{read_only:false; response_revision:3989; number_of_response:1; }","duration":"112.470716ms","start":"2026-10-04T01:20:47.202237Z","end":"2026-10-04T01:20:47.314707Z","steps":["trace[233359011] 'process raft request' (duration: 86.3777ms)","trace[233359011] 'compare' (duration: 26.007035ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:21:38.01209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.687987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-04T01:21:38.012702Z","caller":"traceutil/trace.go:171","msg":"trace[1806745006] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4793; }","duration":"111.314216ms","start":"2026-10-04T01:21:37.901347Z","end":"2026-10-04T01:21:38.012661Z","steps":["trace[1806745006] 'agreement among raft nodes before linearized reading' (duration: 110.620566ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:21:38.012142Z","caller":"traceutil/trace.go:171","msg":"trace[1455086267] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"109.327127ms","start":"2026-10-04T01:21:37.902793Z","end":"2026-10-04T01:21:38.01212Z","steps":["trace[1455086267] 'process raft request' (duration: 94.850029ms)","trace[1455086267] 'compare' (duration: 14.004351ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:21:45.424402Z","caller":"traceutil/trace.go:171","msg":"trace[458062494] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"108.110403ms","start":"2026-10-04T01:21:45.316257Z","end":"2026-10-04T01:21:45.424368Z","steps":["trace[458062494] 'process raft request' (duration: 107.826559ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:21:45.471243Z","caller":"traceutil/trace.go:171","msg":"trace[91676027] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"115.967675ms","start":"2026-10-04T01:21:45.355248Z","end":"2026-10-04T01:21:45.471216Z","steps":["trace[91676027] 'process raft request' (duration: 104.996278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:21:45.471318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.132526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3de362fd-a583-45bc-bc18-ebaf235eaa0d\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-04T01:21:45.471384Z","caller":"traceutil/trace.go:171","msg":"trace[1900418263] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3de362fd-a583-45bc-bc18-ebaf235eaa0d; range_end:; response_count:1; response_revision:4925; }","duration":"104.248418ms","start":"2026-10-04T01:21:45.367117Z","end":"2026-10-04T01:21:45.471366Z","steps":["trace[1900418263] 'agreement among raft nodes before linearized reading' (duration: 104.042225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:21:45.830509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.597784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T01:21:45.830648Z","caller":"traceutil/trace.go:171","msg":"trace[1881429771] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"209.384579ms","start":"2026-10-04T01:21:45.621245Z","end":"2026-10-04T01:21:45.83063Z","steps":["trace[1881429771] 'process raft request' (duration: 106.588782ms)","trace[1881429771] 'compare' (duration: 102.466892ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:21:46.059042Z","caller":"traceutil/trace.go:171","msg":"trace[1902913440] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"141.732512ms","start":"2026-10-04T01:21:45.917263Z","end":"2026-10-04T01:21:46.058996Z","steps":["trace[1902913440] 'process raft request' (duration: 71.443219ms)","trace[1902913440] 'compare' (duration: 70.003259ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:21:57.946882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.738426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T01:21:57.946975Z","caller":"traceutil/trace.go:171","msg":"trace[1144264615] transaction","detail":"{read_only:false; response_revision:5082; number_of_response:1; }","duration":"459.378268ms","start":"2026-10-04T01:21:57.48758Z","end":"2026-10-04T01:21:57.946958Z","steps":["trace[1144264615] 'process raft request' (duration: 175.47731ms)","trace[1144264615] 'compare' (duration: 283.611774ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:21:57.947023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:21:57.487556Z","time spent":"459.44411ms","remote":"127.0.0.1:57306","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-10-04T01:21:58.065727Z","caller":"traceutil/trace.go:171","msg":"trace[1665249982] linearizableReadLoop","detail":"{readStateIndex:5270; appliedIndex:5268; }","duration":"502.146614ms","start":"2026-10-04T01:21:57.563558Z","end":"2026-10-04T01:21:58.065705Z","steps":["trace[1665249982] 'read index received' (duration: 99.480672ms)","trace[1665249982] 'applied index is now lower than readState.Index' (duration: 402.665022ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:21:58.06594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.373777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-04T01:21:58.065981Z","caller":"traceutil/trace.go:171","msg":"trace[605426186] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5083; }","duration":"502.482489ms","start":"2026-10-04T01:21:57.563485Z","end":"2026-10-04T01:21:58.065968Z","steps":["trace[605426186] 'agreement among raft nodes before linearized reading' (duration: 502.326527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:21:58.066012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:21:57.563463Z","time spent":"502.540539ms","remote":"127.0.0.1:57180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-10-04T01:21:58.06592Z","caller":"traceutil/trace.go:171","msg":"trace[2002547435] transaction","detail":"{read_only:false; response_revision:5083; number_of_response:1; }","duration":"566.806672ms","start":"2026-10-04T01:21:57.499069Z","end":"2026-10-04T01:21:58.065875Z","steps":["trace[2002547435] 'process raft request' (duration: 552.871754ms)","trace[2002547435] 'compare' (duration: 13.513762ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:21:58.066203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:21:57.499056Z","time spent":"567.053186ms","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T01:21:58.084063Z","caller":"traceutil/trace.go:171","msg":"trace[343627296] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"348.579075ms","start":"2026-10-04T01:21:57.735438Z","end":"2026-10-04T01:21:58.084017Z","steps":["trace[343627296] 'process raft request' (duration: 348.361822ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:21:58.084696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.661475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-04T01:21:58.084738Z","caller":"traceutil/trace.go:171","msg":"trace[1383014725] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5086; }","duration":"467.719126ms","start":"2026-10-04T01:21:57.617001Z","end":"2026-10-04T01:21:58.08472Z","steps":["trace[1383014725] 'agreement among raft nodes before linearized reading' (duration: 467.637235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:21:58.084771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:21:57.616988Z","time spent":"467.776527ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-10-04T01:21:58.084203Z","caller":"traceutil/trace.go:171","msg":"trace[89999240] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"136.647918ms","start":"2026-10-04T01:21:57.947544Z","end":"2026-10-04T01:21:58.084192Z","steps":["trace[89999240] 'process raft request' (duration: 136.383375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:21:58.084603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.675734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.239\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-04T01:21:58.08554Z","caller":"traceutil/trace.go:171","msg":"trace[1122101108] range","detail":"{range_begin:/registry/masterleases/162.253.55.239; range_end:; response_count:1; response_revision:5086; }","duration":"217.642908ms","start":"2026-10-04T01:21:57.867833Z","end":"2026-10-04T01:21:58.085476Z","steps":["trace[1122101108] 'agreement among raft nodes before linearized reading' (duration: 216.593073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:21:58.085803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:21:57.735407Z","time spent":"349.290486ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T01:21:58.230733Z","caller":"traceutil/trace.go:171","msg":"trace[477030783] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"100.043769ms","start":"2026-10-04T01:21:58.130655Z","end":"2026-10-04T01:21:58.230699Z","steps":["trace[477030783] 'process raft request' (duration: 58.672812ms)","trace[477030783] 'compare' (duration: 41.066093ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:22:34.462345Z","caller":"traceutil/trace.go:171","msg":"trace[1406051834] linearizableReadLoop","detail":"{readStateIndex:5618; appliedIndex:5617; }","duration":"144.980996ms","start":"2026-10-04T01:22:34.317339Z","end":"2026-10-04T01:22:34.46232Z","steps":["trace[1406051834] 'read index received' (duration: 115.076249ms)","trace[1406051834] 'applied index is now lower than readState.Index' (duration: 29.903837ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:22:34.462417Z","caller":"traceutil/trace.go:171","msg":"trace[1046550305] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"152.158096ms","start":"2026-10-04T01:22:34.310235Z","end":"2026-10-04T01:22:34.462393Z","steps":["trace[1046550305] 'process raft request' (duration: 122.234328ms)","trace[1046550305] 'compare' (duration: 29.709935ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:22:34.462799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.435382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-04T01:22:34.462956Z","caller":"traceutil/trace.go:171","msg":"trace[1500907782] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5422; }","duration":"145.638675ms","start":"2026-10-04T01:22:34.317293Z","end":"2026-10-04T01:22:34.462932Z","steps":["trace[1500907782] 'agreement among raft nodes before linearized reading' (duration: 145.116757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:22:34.462514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.934658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T01:22:34.463045Z","caller":"traceutil/trace.go:171","msg":"trace[1472011019] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5422; }","duration":"138.500485ms","start":"2026-10-04T01:22:34.32453Z","end":"2026-10-04T01:22:34.463031Z","steps":["trace[1472011019] 'agreement among raft nodes before linearized reading' (duration: 137.932267ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:22:34.593627Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2843} {"level":"warn","ts":"2026-10-04T01:22:34.723173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.485902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T01:22:34.723293Z","caller":"traceutil/trace.go:171","msg":"trace[744102374] transaction","detail":"{read_only:false; response_revision:5428; number_of_response:1; }","duration":"124.48875ms","start":"2026-10-04T01:22:34.598779Z","end":"2026-10-04T01:22:34.723268Z","steps":["trace[744102374] 'compare' (duration: 98.523707ms)","trace[744102374] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:416; } (duration: 18.824213ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:22:34.840677Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2843,"took":"244.870592ms","hash":3086094218,"current-db-size-bytes":53366784,"current-db-size":"53 MB","current-db-size-in-use-bytes":28692480,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-04T01:22:34.840746Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3086094218,"revision":2843,"compact-revision":-1} {"level":"info","ts":"2026-10-04T01:27:34.600015Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5426} {"level":"info","ts":"2026-10-04T01:27:34.679235Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5426,"took":"77.838726ms","hash":92584056,"current-db-size-bytes":53366784,"current-db-size":"53 MB","current-db-size-in-use-bytes":18620416,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-04T01:27:34.6793Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":92584056,"revision":5426,"compact-revision":2843}