Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.02.2023 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15259244 kB
MemAvailable 30451132 kB
Buffers 0 kB
Cached 16468948 kB
SwapCached 5064 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-10 10:37:26
DTS_END                         2023-02-10 10:37:26.7370
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45294
TRN_ID                          222485
ATT_ID                          578
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8852.27 23659 1062272
customer order: creation 170.20 458 20424
customer order: refuse 14.70 404 1764
order to supplier: creation 44.88 1321 5385
order to supplier: removal 3.44 149429 413
invoice (draft): creation 45.62 3137 5474
invoice (draft): removal 49.18 862 5901
invoice accept: apply 41.22 27655 4946
invoice accept: cancel 44.39 1212 5327
customer reserve: creation 3807.40 266 456888
customer reserve: removal 4336.86 95 520423
realization accept: apply 83.10 262 9972
realization accept: cancel 80.63 150 9675
payment from customer: creation 58.38 177 7006
payment from customer: removal 6.01 111 721
payment to supplier: creation 49.99 180 5999
payment to supplier: removal 3.45 118 414
service: total inventory turnovers 4.02 3772 482
service: total monetary turnovers 7.84 339 941
service: aggregate perf. data 0.93 15138 112
service: refresh index statistics 0.04 268095 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-02-2023 10:38:49 to 10-02-2023 10:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5382
TEST_TIME 2 6245
TEST_TIME 3 6581
TEST_TIME 4 6790
TEST_TIME 5 6491
TEST_TIME 6 6401
TEST_TIME 7 6390
TEST_TIME 8 6423
TEST_TIME 9 6546
TEST_TIME 10 6634
TEST_TIME 11 6787
TEST_TIME 12 7081
TEST_TIME 13 7131
TEST_TIME 14 7318
TEST_TIME 15 7218
TEST_TIME 16 7344
TEST_TIME 17 7378
TEST_TIME 18 7527
TEST_TIME 19 7677
TEST_TIME 20 7872
TEST_TIME 21 8161
TEST_TIME 22 8281
TEST_TIME 23 8525
TEST_TIME 24 8866
TEST_TIME 25 8958
TEST_TIME 26 8974
TEST_TIME 27 9269
TEST_TIME 28 9375
TEST_TIME 29 9527
TEST_TIME 30 10189
TEST_TIME 31 10649
TEST_TIME 32 11194
TEST_TIME 33 10946
TEST_TIME 34 10833
TEST_TIME 35 10605
TEST_TIME 36 10471
TEST_TIME 37 10516
TEST_TIME 38 10439
TEST_TIME 39 10428
TEST_TIME 40 10330
TEST_TIME 41 10270
TEST_TIME 42 10155
TEST_TIME 43 10119
TEST_TIME 44 10018
TEST_TIME 45 9996
TEST_TIME 46 9963
TEST_TIME 47 9918
TEST_TIME 48 10017
TEST_TIME 49 9909
TEST_TIME 50 9811
TEST_TIME 51 9764
TEST_TIME 52 9626
TEST_TIME 53 9562
TEST_TIME 54 9480
TEST_TIME 55 9564
TEST_TIME 56 9531
TEST_TIME 57 9531
TEST_TIME 58 9506
TEST_TIME 59 9467
TEST_TIME 60 9515
TEST_TIME 61 9524
TEST_TIME 62 9520
TEST_TIME 63 9678
TEST_TIME 64 9613
TEST_TIME 65 9624
TEST_TIME 66 9592
TEST_TIME 67 9637
TEST_TIME 68 9550
TEST_TIME 69 9440
TEST_TIME 70 9457
TEST_TIME 71 9436
TEST_TIME 72 9353
TEST_TIME 73 9370
TEST_TIME 74 9342
TEST_TIME 75 9317
TEST_TIME 76 9292
TEST_TIME 77 9260
TEST_TIME 78 9266
TEST_TIME 79 9288
TEST_TIME 80 9236
TEST_TIME 81 9334
TEST_TIME 82 9373
TEST_TIME 83 9367
TEST_TIME 84 9362
TEST_TIME 85 9297
TEST_TIME 86 9387
TEST_TIME 87 9339
TEST_TIME 88 9315
TEST_TIME 89 9335
TEST_TIME 90 9274
TEST_TIME 91 9247
TEST_TIME 92 9201
TEST_TIME 93 9140
TEST_TIME 94 9124
TEST_TIME 95 9083
TEST_TIME 96 9042
TEST_TIME 97 9078
TEST_TIME 98 9052
TEST_TIME 99 9063
TEST_TIME 100 9044
TEST_TIME 101 9044
TEST_TIME 102 9026
TEST_TIME 103 8979
TEST_TIME 104 9075
TEST_TIME 105 9148
TEST_TIME 106 9116
TEST_TIME 107 9103
TEST_TIME 108 9116
TEST_TIME 109 9177
TEST_TIME 110 9180
TEST_TIME 111 9124
TEST_TIME 112 9068
TEST_TIME 113 9064
TEST_TIME 114 9004
TEST_TIME 115 8989
TEST_TIME 116 8935
TEST_TIME 117 8951
TEST_TIME 118 8941
TEST_TIME 119 8916
TEST_TIME 120 8880

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-02-2023 10:38:53 to 10-02-2023 10:38:56.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1483147 1483147 0 0.00 0 15769 14 0 0
doc_list_biud 1483128 1483128 0 0.00 0 62013 39 0 0
sp_add_invoice_to_stock 5121 4946 175 3.41 54 196862 27655 0 175
sp_cancel_adding_invoice 5327 5327 0 0.00 6 124700 1212 0 0
sp_cancel_client_order 1764 1764 0 0.00 5 24299 404 0 0
sp_cancel_customer_reserve 520423 520423 0 0.00 1 62016 95 0 0
sp_cancel_pay_from_customer 721 721 0 0.00 4 3789 111 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 4351 118 0 0
sp_cancel_supplier_invoice 5907 5901 6 0.10 3 19955 862 0 6
sp_cancel_supplier_order 413 413 0 0.00 13 1583924 149429 0 0
sp_cancel_write_off 9675 9675 0 0.00 2 22594 150 0 0
sp_client_order 20424 20424 0 0.00 6 27509 459 0 0
sp_customer_reserve 456888 456888 0 0.00 0 109864 267 0 0
sp_fill_shopping_cart 31277 31277 0 0.00 1 16533 78 0 0
sp_fill_shopping_cart_clo_res 456859 456859 0 0.00 0 18671 20 0 0
sp_get_clo_for_invoice 4908 4908 0 0.00 0 31377 294 0 0
sp_kill_cost_storno 16137 16137 0 0.00 0 15757 62 0 0
sp_kill_qstorno_ret_qs2qd 1056835 1056835 0 0.00 0 62003 41 0 0
sp_kill_qty_storno 558379 558379 0 0.00 0 62013 94 0 0
sp_lock_selected_doc 20667 20667 0 0.00 0 10442 8 0 0
sp_make_cost_storno 27885 27885 0 0.00 0 12986 68 0 0
sp_multiply_rows_for_pdistr 27880 27880 0 0.00 0 8099 35 0 0
sp_multiply_rows_for_qdistr 20424 20424 0 0.00 0 19235 189 0 0
sp_pay_from_customer 7006 7006 0 0.00 5 23233 178 0 0
sp_pay_to_supplier 5999 5999 0 0.00 6 14150 181 0 0
sp_payment_common 13003 13003 0 0.00 2 23215 47 0 0
sp_qd_handle_on_cancel_clo 1764 1764 0 0.00 0 24283 333 0 0
sp_qd_handle_on_invoice_upd_sts 10233 10233 0 0.00 0 16776 477 0 0
sp_qd_handle_on_reserve_upd_sts 9969 9969 0 0.00 0 14409 62 0 0
sp_reserve_write_off 9972 9972 0 0.00 8 14501 263 0 0
sp_supplier_invoice 5474 5474 0 0.00 7 104832 3137 0 0
sp_supplier_order 5385 5385 0 0.00 8 26242 1322 0 0
srv_aggregate_perf_data 112 112 0 0.00 2447 225147 15139 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 807 130716 36536 0 0
srv_find_qd_qs_mism 1013694 1013694 0 0.00 0 10258 12 0 0
srv_make_invnt_saldo 482 482 0 0.00 40 147205 3772 0 0
srv_make_money_saldo 941 941 0 0.00 1 19712 340 0 0
srv_recalc_idx_stat 5 5 0 0.00 114713 470431 268096 0 0
t$perf-abend:sp_add_invoice_to_stock 175 175 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4953 4953 0 0.00 1 23040 891 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 7845 467 0 0
t$perf-norm:sp_cancel_client_order 1764 1764 0 0.00 0 7608 34 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 1450 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 721 721 0 0.00 0 24374 48 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 3226 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 2827 50 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 95164 5894 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 525 15 0 0
t$perf-norm:sp_client_order 20425 20425 0 0.00 0 35860 39 0 0
t$perf-norm:sp_customer_reserve 1087 1087 0 0.00 0 2235 16 0 0
t$perf-norm:sp_pay_from_customer 7006 7006 0 0.00 0 24212 25 0 0
t$perf-norm:sp_pay_to_supplier 5999 5999 0 0.00 0 3185 21 0 0
t$perf-norm:sp_reserve_write_off 9972 9972 0 0.00 0 10601 23 0 0
t$perf-norm:sp_supplier_invoice 5474 5474 0 0.00 0 20841 46 0 0
t$perf-norm:sp_supplier_order 5386 5386 0 0.00 0 8121 35 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 665 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 41 3 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 5291 26 0 0
t$perf-norm:srv_make_money_saldo 941 941 0 0.00 0 2154 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 179 68 0 0
v_add_invoice_to_stock 10156 9806 350 3.44 0 4792 24 0 350
v_all_customers 37679 37679 0 0.00 0 189 4 0 0
v_all_suppliers 30583 30583 0 0.00 0 56 4 0 0
v_all_wares 40846 40846 0 0.00 0 278 4 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 208 14 0 0
v_cancel_client_order 3528 3528 0 0.00 0 4518 12 0 0
v_cancel_customer_prepayment 1442 1442 0 0.00 0 3590 13 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 1 2104 15 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 373 10 0 0
v_cancel_supplier_invoice 776 764 12 1.54 0 5361 28 0 12
v_cancel_supplier_order 826 826 0 0.00 0 306 8 0 0
v_cancel_write_off 1994 1994 0 0.00 0 3408 24 0 0
v_max_id_clo_ord 5384 5384 0 0.00 0 507 5 0 0
v_max_id_clo_res 1086 1086 0 0.00 0 1878 11 0 0
v_max_id_ord_sup 5469 5469 0 0.00 0 2446 6 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 65 8 0 0
v_max_non_paid_realizn 3590 3590 0 0.00 0 23207 13 0 0
v_min_id_clo_ord 5384 5384 0 0.00 0 3441 9 0 0
v_min_id_clo_res 1086 1086 0 0.00 0 5112 12 0 0
v_min_id_ord_sup 5469 5469 0 0.00 0 5977 10 0 0
v_min_non_paid_invoice 5999 5999 0 0.00 0 5750 16 0 0
v_min_non_paid_realizn 7004 7004 0 0.00 0 5751 18 0 0
v_our_firm 9990 9990 0 0.00 0 80 4 0 0
v_reserve_write_off 19938 19938 0 0.00 0 8658 11 0 0
x_lock_depdocs_on_canc_invoice 10846 10846 0 0.00 0 33726 130 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 3955 327 0 0
x_make_qstorno_1000_1200 5385 5385 0 0.00 2 26227 1162 0 0
x_make_qstorno_1200_2000 5474 5474 0 0.00 3 104790 2922 0 0
x_make_qstorno_2100_3300 456886 456886 0 0.00 0 109862 234 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-02-2023 10:38:57 to 10-02-2023 10:39:02.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:30 21 8192000000 8809354512 8824545280 10709728 0.130 6 2 2 0 10818352 72848 35776
08:08:31 21240 8192000000 9705762448 10002763776 785997248 8.754 100 88 176 0 815984112 7864640 22122224
08:10:05 12605 8192000000 9750118896 10055143424 826969536 9.169 100 90 180 0 860924256 11860384 22094336
08:11:30 3764 8192000000 9789514608 10104774656 857994832 9.480 100 92 184 0 902622352 22877744 21749776
08:12:46 7474 8192000000 9843424848 10177462272 901081504 9.909 100 71 137 0 959170352 42828336 15260512
08:13:57 4024 8192000000 9920109088 10260586496 955273744 10.443 100 87 172 1 1035198736 61889792 18035200
08:15:04 1972 8192000000 9954694416 10306404352 987892672 10.761 100 87 170 0 1073213312 68419728 16900912
08:16:09 1384 8192000000 9980602048 10341294080 1010204416 10.977 100 83 158 0 1101990288 76641232 15144640
08:17:13 990 8192000000 10013823776 10383282176 1035190096 11.218 100 95 186 2 1131791712 78031232 18570384
08:18:16 1255 8192000000 10029668928 10403368960 1052522256 11.385 100 92 184 0 1147706768 76603568 18580944
08:19:20 1008 8192000000 10036174160 10415079424 1063088048 11.486 100 95 185 1 1157083552 75854080 18141424
08:20:24 1671 8192000000 10042043968 10423959552 1068687920 11.540 100 95 180 2 1163250512 76619872 17942720
08:21:28 1214 8192000000 10051617936 10433859584 1076487728 11.614 100 94 184 0 1172362080 77535520 18338832
08:22:31 1407 8192000000 10065075872 10451009536 1078441136 11.633 100 96 180 1 1186768144 89396768 18930240
08:23:33 1375 8192000000 10039256272 10420776960 1084819360 11.693 100 93 173 5 1157213856 54858784 17535712
08:24:36 1587 8192000000 10058548704 10445623296 1088186656 11.725 100 95 178 1 1181544288 73552304 19805328
08:25:39 1108 8192000000 10066554256 10451189760 1094920464 11.789 100 93 179 2 1184684048 71210640 18552944
08:26:41 1209 8192000000 10076924064 10461532160 1096283248 11.802 100 97 187 0 1191179200 76578144 18317808
08:27:43 1544 8192000000 10080433712 10469830656 1099828224 11.836 100 97 191 1 1203571408 85513984 18229200
08:28:45 1227 8192000000 10071867040 10459873280 1093577968 11.777 100 96 178 2 1193255888 81002240 18675680
08:29:48 1363 8192000000 10027469312 10409869312 1061626816 11.472 99 88 167 2 1153085584 74856400 16602368
08:30:52 1040 8192000000 10031315712 10411573248 1072209552 11.573 99 92 176 2 1154960864 64159680 18591632
08:31:54 1055 8192000000 10020752576 10396848128 1055776544 11.416 100 88 168 2 1142590880 69507424 17306912
08:32:55 1284 8192000000 10024907632 10408648704 1055274816 11.411 100 76 135 4 1145874192 75406096 15193280
08:33:58 1146 8192000000 9989213744 10362970112 1042127968 11.285 99 90 169 1 1113501792 52924032 18449792
08:35:01 4647 8192000000 9968647248 10347909120 1020808496 11.080 98 70 136 4 1084341040 50266320 13266224
08:36:09 1174 8192000000 9992659728 10363953152 1039605616 11.261 100 91 178 1 1113546656 55415728 18525312
08:37:12 6452 8192000000 9945965392 10315108352 1008317152 10.959 98 66 127 0 1060199616 39484480 12397984
08:38:24 23105 8192000000 10001233184 10384785408 1047843632 11.340 100 63 120 0 1130273184 70415072 12014480
08:39:51 7748 8192000000 10042075632 10420203520 1071031680 11.562 100 80 147 1 1160623024 75263456 14327888
08:41:03 1667 8192000000 10056236752 10441592832 1088216000 11.726 100 79 153 0 1176545360 73826672 14502688
08:42:07 2434 8192000000 10097934736 10489159680 1097723984 11.816 100 77 147 3 1210595712 97334880 15536848
08:43:15 1119 8192000000 10126505328 10514268160 1108564992 11.919 100 94 185 1 1250768304 121522160 20681152
08:44:21 1652 8192000000 10177183088 10581667840 1122239696 12.048 100 88 174 0 1299949504 157984368 19725440
08:45:30 7673 8192000000 10205733968 10603614208 1131465552 12.135 100 77 154 2 1317617728 169499824 16652352
08:46:44 969 8192000000 10251415584 10653011968 1150286688 12.312 100 95 183 0 1368650896 198270096 20094112
08:47:48 4124 8192000000 10269901888 10684362752 1153958880 12.347 100 70 140 0 1385352080 215896672 15496528
08:49:00 1945 8192000000 10313035440 10719969280 1169531696 12.492 100 91 176 1 1428125792 238711808 19882288
08:50:04 847 8192000000 10316204448 10718969856 1169977936 12.497 100 97 189 1 1426016416 235020320 21018160
08:51:09 1025 8192000000 10335369600 10745380864 1178448192 12.576 100 95 186 1 1446587984 246400080 21739712
08:52:12 888 8192000000 10362407440 10764640256 1181491408 12.604 100 97 183 5 1471297952 269362960 20443584
08:53:18 1167 8192000000 10387046640 10801250304 1171590176 12.512 99 95 176 3 1498573424 306808752 20174496
08:54:22 1348 8192000000 10434729712 10846756864 1196671280 12.745 100 95 186 0 1536838864 318166736 22000848
08:55:26 963 8192000000 10464540320 10884337664 1202545184 12.800 100 96 191 0 1571748288 347106928 22096176
08:56:30 1273 8192000000 10464129232 10902118400 1184787232 12.635 100 96 192 0 1571502672 364009744 22705696
08:57:33 888 8192000000 10486224048 10929836032 1183984016 12.627 99 96 184 0 1595926800 389643952 22298832
08:58:36 801 8192000000 10486673024 10905444352 1182608640 12.615 100 95 178 0 1594776208 390314832 21852736
08:59:39 1824 8192000000 10510140640 10947297280 1181379584 12.603 100 69 136 0 1613887792 416264608 16243600
09:00:43 3424 8192000000 10461727136 10927394816 1172044944 12.516 100 60 115 0 1564612784 377432544 15135296
09:01:50 2343 8192000000 10461611664 10923544576 1170347920 12.500 100 62 123 0 1570761344 385403088 15010336
09:03:04 14086 8192000000 10482925232 10932928512 1179340720 12.584 100 36 72 0 1597455600 408369936 9744944
09:04:28 22272 8192000000 10460168112 10921746432 1162893360 12.430 99 33 66 0 1586151792 414465280 8793152
09:06:38 18499 8192000000 10387159408 10929381376 1171828160 12.514 100 33 66 0 1542244784 361490208 8926416
09:09:00 44162 8192000000 10071531952 10622488576 1098644240 11.825 97 12 23 0 1259328400 157988160 2696000
09:11:11 93302 8192000000 10068267520 10530385920 1096944864 11.809 98 29 58 0 1161852080 58721088 6186128
09:13:49 130716 8192000000 10090790128 10515566592 1087083936 11.715 96 30 60 0 1167561200 74195568 6281696
09:17:02 91927 8192000000 10075365360 10511192064 1125982880 12.083 100 29 54 0 1160499408 28843472 5673056
09:19:36 8129 8192000000 10091284576 10517704704 1130004896 12.121 99 64 123 0 1190651216 47118208 13528112
09:20:46 9642 8192000000 10120962144 10561748992 1121451440 12.041 99 48 94 1 1215643968 84130944 10061584
09:21:57 51970 8192000000 10100478096 10547335168 1114869488 11.978 98 15 30 0 1200325328 81480224 3975616
09:23:57 49108 8192000000 10124478608 10579890176 1119619696 12.023 98 20 41 0 1210206400 85881360 4705344
09:25:46 78696 8192000000 10060674544 10515329024 1122671616 12.052 99 18 37 0 1162275296 35455968 4147712
09:28:06 18095 8192000000 10081481616 10530164736 1141831664 12.233 100 31 63 3 1186007824 38040624 6135536
09:29:28 86563 8192000000 10109935600 10597322752 1143952192 12.253 100 16 32 0 1231388240 83979008 3457040
09:32:02 57336 8192000000 10073935472 10526871552 1145481232 12.267 100 15 30 0 1204309456 55620000 3208224
09:34:06 27513 8192000000 10073835088 10525753344 1120961936 12.036 98 29 58 0 1156680240 30080784 5637520
09:35:34 14963 8192000000 10125259968 10570608640 1147802992 12.289 100 35 70 0 1212098736 57259376 7036368
09:36:52 43093 8192000000 10145477296 10595061760 1112042944 11.952 97 36 68 1 1233687056 113771760 7872352
09:38:41 60289 8192000000 10169999728 10611720192 1117820544 12.006 98 33 66 0 1216271120 91436320 7014256
09:40:43 23817 8192000000 10065359552 10505830400 1134459920 12.163 100 30 60 0 1164550176 22947776 7142480
09:42:14 20621 8192000000 10105965936 10565971968 1124794752 12.072 99 42 84 0 1188074528 54223184 9056592
09:43:39 28982 8192000000 10127161376 10574733312 1131901312 12.139 100 41 80 1 1221885616 81985376 7998928
09:45:10 113195 8192000000 10077533216 10542563328 1132510800 12.145 99 35 70 0 1204804736 64687312 7606624
09:48:11 47743 8192000000 10051310032 10540904448 1117484544 12.003 98 7 14 0 1153546576 34653072 1408960
09:50:20 57143 8192000000 10066654736 10552705024 1123142496 12.057 99 6 12 0 1171178528 47058048 977984
09:52:18 11623 8192000000 10033010096 10471145472 1105831264 11.893 98 42 81 0 1130853632 16283792 8738576
09:53:35 7837 8192000000 10097102272 10528911360 1120904832 12.036 99 57 109 0 1184868512 53137296 10826384
09:54:53 26814 8192000000 10102162368 10548064256 1112926128 11.960 99 53 106 1 1201947792 77662624 11359040
09:56:25 49867 8192000000 10117461376 10556469248 1131481408 12.135 100 42 80 0 1217016032 76597312 8937312
09:58:20 119777 8192000000 10062142720 10548928512 1064122496 11.496 94 21 42 1 1138457392 70213568 4121328
10:01:23 42631 8192000000 10063075232 10489274368 1111244656 11.944 98 23 42 0 1143802848 28091264 4466928
10:03:07 85789 8192000000 10059509472 10496286720 1084050048 11.686 96 27 54 0 1148266672 58864192 5352432
10:05:38 61903 8192000000 10102548688 10583674880 1104443264 11.880 98 40 80 0 1204783232 91920640 8419328
10:08:09 43149 8192000000 10045847440 10517012480 1095630656 11.796 97 33 61 0 1127967568 26694528 5642384
10:09:55 83862 8192000000 10082010672 10531790848 1096911440 11.808 97 18 36 0 1137908224 37076976 3919808
10:12:45 25823 8192000000 10046921872 10497097728 1090534384 11.748 98 11 22 0 1144263184 51704704 2024096
10:14:22 58096 8192000000 10057699152 10491232256 1106418992 11.899 99 27 45 2 1157605040 45984784 5201264
10:16:26 39326 8192000000 10083754016 10517929984 1111743664 11.949 99 29 58 0 1174976688 57133248 6099776
10:18:05 33722 8192000000 10112279968 10548625408 1099757040 11.835 98 41 78 0 1189682096 81301488 8623568
10:19:41 54307 8192000000 10162857520 10600329216 1112202944 11.953 99 24 48 0 1228169120 110708656 5257520
10:21:43 97075 8192000000 10059277008 10508636160 1108595424 11.919 98 16 32 0 1177651936 66246240 2810272
10:24:29 49283 8192000000 10109474112 10552721408 1099019168 11.828 97 44 88 0 1189441184 81407328 9014688
10:26:21 50745 8192000000 10113138448 10578558976 1117742112 12.006 99 32 64 0 1239606080 115461552 6402416
10:28:14 81447 8192000000 10091187648 10543628288 1133829456 12.157 100 32 59 1 1192186736 51209424 7147856
10:30:40 69978 8192000000 10080214752 10553663488 1126083344 12.084 99 12 22 0 1162550288 33520464 2946480
10:33:06 92502 8192000000 10072971680 10566021120 1085264144 11.698 96 7 14 0 1135446304 48757152 1425008
10:35:47 54114 8192000000 10103623328 10564141056 1128048400 12.103 100 28 54 0 1174428928 41113536 5266992

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-02-2023 10:39:04 to 10-02-2023 10:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 175 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-02-2023 10:39:21 to 10-02-2023 10:39:23.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          220591
MON$OLDEST_ACTIVE               222594
MON$OLDEST_SNAPSHOT             222594
MON$NEXT_TRANSACTION            222594
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2040800
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2899 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2899 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:75: 
:76: mscs-test-161.budzdorov.ru    Fri Feb 10 10:37:30 2023
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:78: 
:79: 
:80: mscs-test-161.budzdorov.ru    Fri Feb 10 10:37:31 2023
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:82: 
:83: 
:84: mscs-test-161.budzdorov.ru    Fri Feb 10 10:37:31 2023
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
:87: 
:88: mscs-test-161.budzdorov.ru    Fri Feb 10 10:37:31 2023
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
:91: 
:92: mscs-test-161.budzdorov.ru    Fri Feb 10 10:37:32 2023
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:94: 
:95: 
:96: mscs-test-161.budzdorov.ru    Fri Feb 10 10:37:33 2023
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:98: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

10.02.23 10:39:24. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-10 10:39:27 No severe PSQL-related problems occured
10.02.23 10:39:28. End of report.