Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.12.2022 13:39
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 14778696 kB
MemAvailable 30118572 kB
Buffers 0 kB
Cached 16949668 kB
SwapCached 3204 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 2022-12-24 13:37:23
DTS_END                         2022-12-24 13:37:23.1460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45330
TRN_ID                          218253
ATT_ID                          582
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: 9262.71 28471 1111525
customer order: creation 161.03 435 19323
customer order: refuse 14.72 389 1766
order to supplier: creation 44.34 1255 5321
order to supplier: removal 3.62 151758 434
invoice (draft): creation 43.59 2742 5231
invoice (draft): removal 54.32 962 6518
invoice accept: apply 40.63 25729 4875
invoice accept: cancel 48.90 1008 5868
customer reserve: creation 3808.88 245 457066
customer reserve: removal 4749.75 90 569970
realization accept: apply 81.56 258 9787
realization accept: cancel 86.13 154 10336
payment from customer: creation 57.11 152 6853
payment from customer: removal 5.68 120 681
payment to supplier: creation 47.39 176 5687
payment to supplier: removal 3.26 95 391
service: total inventory turnovers 3.98 3752 477
service: total monetary turnovers 6.98 382 837
service: aggregate perf. data 0.83 16426 99
service: refresh index statistics 0.04 363301 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-12-2022 13:40:02 to 24-12-2022 13:40:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5890
TEST_TIME 2 7061
TEST_TIME 3 6378
TEST_TIME 4 5805
TEST_TIME 5 5606
TEST_TIME 6 5433
TEST_TIME 7 5369
TEST_TIME 8 5622
TEST_TIME 9 5603
TEST_TIME 10 5751
TEST_TIME 11 6144
TEST_TIME 12 6439
TEST_TIME 13 6899
TEST_TIME 14 6849
TEST_TIME 15 6937
TEST_TIME 16 7177
TEST_TIME 17 7402
TEST_TIME 18 7627
TEST_TIME 19 7782
TEST_TIME 20 7967
TEST_TIME 21 7892
TEST_TIME 22 7885
TEST_TIME 23 7969
TEST_TIME 24 8110
TEST_TIME 25 8874
TEST_TIME 26 9210
TEST_TIME 27 10033
TEST_TIME 28 10274
TEST_TIME 29 10484
TEST_TIME 30 10957
TEST_TIME 31 10829
TEST_TIME 32 10765
TEST_TIME 33 11054
TEST_TIME 34 11115
TEST_TIME 35 11079
TEST_TIME 36 11010
TEST_TIME 37 10797
TEST_TIME 38 10560
TEST_TIME 39 10808
TEST_TIME 40 10685
TEST_TIME 41 10810
TEST_TIME 42 10771
TEST_TIME 43 10580
TEST_TIME 44 10428
TEST_TIME 45 10346
TEST_TIME 46 10274
TEST_TIME 47 10307
TEST_TIME 48 10435
TEST_TIME 49 10340
TEST_TIME 50 10317
TEST_TIME 51 10213
TEST_TIME 52 10076
TEST_TIME 53 10012
TEST_TIME 54 10135
TEST_TIME 55 10048
TEST_TIME 56 10144
TEST_TIME 57 10101
TEST_TIME 58 10074
TEST_TIME 59 9994
TEST_TIME 60 10049
TEST_TIME 61 10095
TEST_TIME 62 10031
TEST_TIME 63 10136
TEST_TIME 64 10060
TEST_TIME 65 10132
TEST_TIME 66 10021
TEST_TIME 67 9952
TEST_TIME 68 9946
TEST_TIME 69 9844
TEST_TIME 70 9813
TEST_TIME 71 9800
TEST_TIME 72 9759
TEST_TIME 73 9730
TEST_TIME 74 9680
TEST_TIME 75 9639
TEST_TIME 76 9622
TEST_TIME 77 9581
TEST_TIME 78 9613
TEST_TIME 79 9592
TEST_TIME 80 9542
TEST_TIME 81 9613
TEST_TIME 82 9636
TEST_TIME 83 9673
TEST_TIME 84 9601
TEST_TIME 85 9663
TEST_TIME 86 9659
TEST_TIME 87 9694
TEST_TIME 88 9727
TEST_TIME 89 9685
TEST_TIME 90 9647
TEST_TIME 91 9587
TEST_TIME 92 9552
TEST_TIME 93 9524
TEST_TIME 94 9511
TEST_TIME 95 9467
TEST_TIME 96 9437
TEST_TIME 97 9363
TEST_TIME 98 9299
TEST_TIME 99 9283
TEST_TIME 100 9337
TEST_TIME 101 9305
TEST_TIME 102 9306
TEST_TIME 103 9330
TEST_TIME 104 9302
TEST_TIME 105 9327
TEST_TIME 106 9291
TEST_TIME 107 9324
TEST_TIME 108 9317
TEST_TIME 109 9353
TEST_TIME 110 9417
TEST_TIME 111 9466
TEST_TIME 112 9493
TEST_TIME 113 9534
TEST_TIME 114 9562
TEST_TIME 115 9481
TEST_TIME 116 9439
TEST_TIME 117 9424
TEST_TIME 118 9373
TEST_TIME 119 9354
TEST_TIME 120 9287

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-12-2022 13:40:05 to 24-12-2022 13:40:09.

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 1532014 1532014 0 0.00 0 13378 12 0 0
doc_list_biud 1532014 1532014 0 0.00 0 68259 39 0 0
sp_add_invoice_to_stock 5007 4875 132 2.63 61 234513 25729 0 132
sp_cancel_adding_invoice 5868 5868 0 0.00 6 101696 1009 0 0
sp_cancel_client_order 1766 1766 0 0.00 6 16570 390 0 0
sp_cancel_customer_reserve 569970 569970 0 0.00 1 26695 91 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 6087 121 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 4 3769 95 0 0
sp_cancel_supplier_invoice 6528 6518 10 0.15 3 68272 962 0 10
sp_cancel_supplier_order 434 434 0 0.00 8 1431637 151759 0 0
sp_cancel_write_off 10336 10336 0 0.00 2 17934 154 0 0
sp_client_order 19323 19323 0 0.00 5 14911 436 0 0
sp_customer_reserve 457066 457066 0 0.00 1 102831 246 0 0
sp_fill_shopping_cart 29863 29863 0 0.00 1 16531 70 0 0
sp_fill_shopping_cart_clo_res 457022 457022 0 0.00 0 18367 17 0 0
sp_get_clo_for_invoice 4831 4831 0 0.00 0 15829 233 0 0
sp_kill_cost_storno 17276 17276 0 0.00 0 10303 63 0 0
sp_kill_qstorno_ret_qs2qd 1157228 1157228 0 0.00 0 68254 41 0 0
sp_kill_qty_storno 609510 609510 0 0.00 0 68257 92 0 0
sp_lock_selected_doc 20354 20354 0 0.00 0 9431 6 0 0
sp_make_cost_storno 27158 27158 0 0.00 0 13327 73 0 0
sp_multiply_rows_for_pdistr 27158 27158 0 0.00 0 8418 35 0 0
sp_multiply_rows_for_qdistr 19323 19323 0 0.00 0 12354 168 0 0
sp_pay_from_customer 6853 6853 0 0.00 5 7294 153 0 0
sp_pay_to_supplier 5687 5687 0 0.00 6 11872 176 0 0
sp_payment_common 12540 12540 0 0.00 2 6570 39 0 0
sp_qd_handle_on_cancel_clo 1766 1766 0 0.00 1 16559 330 0 0
sp_qd_handle_on_invoice_upd_sts 10699 10699 0 0.00 0 21018 461 0 0
sp_qd_handle_on_reserve_upd_sts 9787 9787 0 0.00 0 15653 67 0 0
sp_reserve_write_off 9787 9787 0 0.00 7 22560 259 0 0
sp_supplier_invoice 5231 5231 0 0.00 10 50388 2743 0 0
sp_supplier_order 5321 5321 0 0.00 8 40525 1256 0 0
srv_aggregate_perf_data 99 99 0 0.00 2652 160723 16426 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 678 153393 36805 0 0
srv_find_qd_qs_mism 1063984 1063984 0 0.00 0 12846 10 0 0
srv_make_invnt_saldo 477 477 0 0.00 67 81551 3753 0 0
srv_make_money_saldo 837 837 0 0.00 1 35315 383 0 0
srv_recalc_idx_stat 5 5 0 0.00 255079 538092 363301 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4875 4875 0 0.00 0 12621 765 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 2 6148 388 0 0
t$perf-norm:sp_cancel_client_order 1766 1766 0 0.00 0 2917 24 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 1676 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 2354 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 232 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 820 34 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 83932 5611 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 3004 17 0 0
t$perf-norm:sp_client_order 19323 19323 0 0.00 0 7378 30 0 0
t$perf-norm:sp_customer_reserve 941 941 0 0.00 0 5662 25 0 0
t$perf-norm:sp_pay_from_customer 6853 6853 0 0.00 0 14795 23 0 0
t$perf-norm:sp_pay_to_supplier 5687 5687 0 0.00 0 7092 19 0 0
t$perf-norm:sp_reserve_write_off 9787 9787 0 0.00 0 9617 23 0 0
t$perf-norm:sp_supplier_invoice 5231 5231 0 0.00 0 3912 25 0 0
t$perf-norm:sp_supplier_order 5321 5321 0 0.00 0 1599 25 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 317 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 166 5 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 362 10 0 0
t$perf-norm:srv_make_money_saldo 837 837 0 0.00 0 696 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 244 54 0 0
v_add_invoice_to_stock 9924 9660 264 2.66 0 7295 28 0 264
v_all_customers 35800 35800 0 0.00 0 191 3 0 0
v_all_suppliers 30086 30086 0 0.00 0 120 4 0 0
v_all_wares 38634 38634 0 0.00 0 69 3 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 793 13 0 0
v_cancel_client_order 3532 3532 0 0.00 0 3269 11 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 976 10 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 348 8 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 3700 13 0 0
v_cancel_supplier_invoice 782 762 20 2.55 0 2843 30 0 20
v_cancel_supplier_order 868 868 0 0.00 0 669 10 0 0
v_cancel_write_off 2020 2020 0 0.00 0 7087 25 0 0
v_max_id_clo_ord 5317 5317 0 0.00 0 1805 5 0 0
v_max_id_clo_res 941 941 0 0.00 0 3503 22 0 0
v_max_id_ord_sup 5229 5229 0 0.00 0 2732 6 0 0
v_max_non_paid_invoice 1190 1190 0 0.00 0 125 7 0 0
v_max_non_paid_realizn 3412 3412 0 0.00 0 2513 7 0 0
v_min_id_clo_ord 5317 5317 0 0.00 0 2426 7 0 0
v_min_id_clo_res 941 941 0 0.00 0 2295 10 0 0
v_min_id_ord_sup 5229 5229 0 0.00 0 5046 10 0 0
v_min_non_paid_invoice 5687 5687 0 0.00 0 6565 15 0 0
v_min_non_paid_realizn 6853 6853 0 0.00 0 5498 15 0 0
v_our_firm 9716 9716 0 0.00 0 192 4 0 0
v_reserve_write_off 19570 19570 0 0.00 0 7250 11 0 0
x_lock_depdocs_on_canc_invoice 12005 12005 0 0.00 0 16076 113 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 3141 264 0 0
x_make_qstorno_1000_1200 5321 5321 0 0.00 2 40509 1114 0 0
x_make_qstorno_1200_2000 5231 5231 0 0.00 5 40799 2550 0 0
x_make_qstorno_2100_3300 457066 457066 0 0.00 0 102824 218 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 24-12-2022 13:40:09 to 24-12-2022 13:40:14.

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
11:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:27 27225 8192000000 9721405568 10017538048 797437696 8.870 100 88 176 0 826896336 7888080 21570560
11:10:00 20320 8192000000 9763368528 10070528000 835058688 9.250 100 89 178 0 869780544 13250304 21471552
11:11:29 17309 8192000000 9811963520 10131533824 873111792 9.631 100 84 168 0 916895120 24683168 19100160
11:12:52 7828 8192000000 9863767328 10196107264 917861744 10.075 100 80 158 0 979418368 43955136 17601488
11:14:03 4328 8192000000 9923666672 10265833472 952427456 10.415 100 85 165 1 1036523728 66316672 17779600
11:15:10 2258 8192000000 9942513376 10292408320 989955328 10.781 100 88 169 2 1062251152 55475616 16820208
11:16:17 1761 8192000000 10007511008 10370887680 1024027024 11.111 100 88 174 0 1121260576 78591728 18641824
11:17:19 1274 8192000000 10031940272 10398838784 1037608032 11.242 100 95 188 1 1146104912 88972000 19524880
11:18:22 1137 8192000000 10038204192 10411065344 1056781472 11.426 100 94 183 3 1158160880 83345792 18033616
11:19:25 4766 8192000000 10035301328 10412240896 1065570256 11.510 100 78 146 0 1163523728 84291616 13661856
11:20:30 947 8192000000 10039629408 10416447488 1071496464 11.566 100 90 180 0 1158895152 68702032 18696656
11:21:32 1433 8192000000 10057103984 10436050944 1076178256 11.611 100 86 163 2 1172550928 80871200 15501472
11:22:35 1076 8192000000 10044396320 10428387328 1080267280 11.650 100 94 183 0 1165616928 66591520 18758128
11:23:37 1057 8192000000 10051565152 10440601600 1085467856 11.700 100 86 155 1 1174021632 72693648 15860128
11:24:39 1197 8192000000 10054136064 10441236480 1087929344 11.723 100 97 187 1 1172505184 64933856 19641984
11:25:42 1055 8192000000 10064238768 10449608704 1093988912 11.781 100 96 184 1 1184773248 71171376 19612960
11:26:44 982 8192000000 10067508880 10456641536 1094157552 11.782 100 92 179 1 1185856640 73775568 17923520
11:27:46 3219 8192000000 10042011024 10431823872 1083807552 11.684 100 67 131 1 1162318624 65606992 12904080
11:28:49 2720 8192000000 10043533936 10437824512 1088406320 11.728 100 69 137 1 1173208000 70615104 14186576
11:29:54 5449 8192000000 10026994800 10410422272 1075769808 11.607 100 67 129 3 1149279376 60709120 12800448
11:31:00 899 8192000000 9988554032 10371874816 1067990848 11.533 100 83 145 2 1111148576 29017568 14140160
11:32:02 980 8192000000 10048462032 10431168512 1066308928 11.517 100 94 181 1 1170705904 84377376 20019600
11:33:04 1135 8192000000 10006961568 10383212544 1049350064 11.354 100 96 180 2 1129739840 62414944 17974832
11:34:06 2186 8192000000 9964566576 10335977472 1021773024 11.089 98 79 145 3 1082580480 44497648 16309808
11:35:09 1374 8192000000 10015206112 10393804800 1050733040 11.368 100 90 170 3 1134315120 66778112 16803968
11:36:11 4337 8192000000 9989239936 10374545408 1052447952 11.384 100 59 113 3 1098015312 33764560 11802800
11:37:18 8043 8192000000 9990322544 10370076672 1033892560 11.206 98 57 107 3 1112995584 68968928 10134096
11:38:35 12117 8192000000 10024686432 10407247872 1062080352 11.476 100 72 134 1 1143047744 66298608 14668784
11:39:52 7798 8192000000 10060963184 10446581760 1081707712 11.664 100 68 125 0 1181683984 87598048 12378224
11:41:05 10446 8192000000 10077818992 10474258432 1099497888 11.833 100 54 104 2 1200400096 90415072 10487136
11:42:24 3021 8192000000 10117256272 10502582272 1116873632 11.997 100 80 150 1 1235031904 101715344 16442928
11:43:42 3935 8192000000 10172213712 10565238784 1121041744 12.037 100 66 126 2 1285403392 150129520 14232128
11:44:52 5595 8192000000 10206557712 10610786304 1130015088 12.122 100 63 114 1 1324493168 181028784 13449296
11:46:04 884 8192000000 10242728912 10637344768 1147263808 12.284 100 96 191 1 1359466448 191953568 20249072
11:47:10 1184 8192000000 10317484064 10713903104 1159882832 12.402 100 95 178 1 1431015360 251445552 19686976
11:48:15 1660 8192000000 10297729264 10697424896 1167920544 12.477 100 84 151 1 1405052416 219787328 17344544
11:49:20 1202 8192000000 10307412480 10736300032 1180812160 12.598 100 93 179 1 1422344128 221176832 20355136
11:50:24 732 8192000000 10344316256 10753032192 1192327264 12.705 100 98 184 2 1455720800 241815728 21577808
11:51:27 658 8192000000 10391347072 10792722432 1200596896 12.782 100 99 191 1 1498327024 275116496 22613632
11:52:32 2466 8192000000 10395673792 10809114624 1192299152 12.705 100 83 163 1 1508465104 296934672 19231280
11:53:38 1694 8192000000 10445826592 10866978816 1195502608 12.735 100 99 187 3 1549772336 331666992 22602736
11:54:42 1228 8192000000 10467454048 10916188160 1209065264 12.860 99 95 184 1 1571735632 341530448 21139920
11:55:45 767 8192000000 10499122896 10921721856 1209041088 12.860 100 96 190 0 1603531744 370949152 23541504
11:56:49 9346 8192000000 10483016528 10939842560 1202674160 12.801 100 47 94 0 1601334320 386269248 12390912
11:58:10 3595 8192000000 10502215568 10919546880 1206729648 12.839 100 68 133 0 1607237728 384448816 16059264
11:59:21 11750 8192000000 10538561648 10994135040 1188849920 12.673 99 47 92 0 1637058208 436157056 12051232
12:00:35 18756 8192000000 10556757472 11007225856 1184527920 12.632 100 37 74 0 1658401936 463555616 10318400
12:02:12 8834 8192000000 10495958880 11020668928 1175302304 12.546 100 42 82 0 1618787776 432306048 11179424
12:03:27 18415 8192000000 10376982976 10872074240 1149649440 12.306 99 28 54 0 1477623696 320270384 7703872
12:04:53 39376 8192000000 10240272400 10690793472 1153882592 12.346 98 30 61 0 1348752048 188364992 6504464
12:06:34 18589 8192000000 10173592512 10650595328 1142571376 12.240 99 28 54 0 1257329696 109024784 5733536
12:07:57 9924 8192000000 10127077024 10571001856 1147686864 12.288 100 30 58 0 1231730912 77553536 6490512
12:09:42 49977 8192000000 10140677280 10652577792 1141244688 12.227 100 16 32 1 1258924608 113876096 3803824
12:11:45 55216 8192000000 10134513168 10578608128 1133966272 12.159 99 18 30 0 1214981056 77994384 3020400
12:13:45 118286 8192000000 10145184512 10579816448 1096142448 11.801 97 30 60 0 1206913792 104857184 5914160
12:16:47 153393 8192000000 10093710592 10572857344 1107752736 11.911 97 32 63 0 1218008256 103313264 6942256
12:20:27 61500 8192000000 10135882496 10566733824 1133996368 12.159 99 64 109 0 1220578832 74765808 11816656
12:22:30 5366 8192000000 10160148528 10596417536 1133904944 12.158 100 76 143 1 1258408816 107795888 16707984
12:23:41 11000 8192000000 10211459440 10650992640 1150042608 12.310 100 49 96 0 1301759200 141818576 9898016
12:24:55 27386 8192000000 10124445840 10614280192 1138750576 12.204 100 15 30 0 1268846288 126370400 3725312
12:26:45 34783 8192000000 10053340912 10523832320 1119572368 12.023 99 13 26 1 1188810656 66426224 2812064
12:28:20 68741 8192000000 10080438848 10551586816 1123200912 12.057 99 16 32 0 1170465024 43495088 3769024
12:30:29 49184 8192000000 10054195024 10561994752 1107047680 11.904 98 14 28 0 1210131984 99969360 3114944
12:32:19 26286 8192000000 10111352000 10551132160 1140358112 12.219 100 50 98 0 1195387600 44675408 10354080
12:33:46 40307 8192000000 10114285968 10577887232 1133763168 12.157 99 35 70 0 1188733248 47630144 7339936
12:35:34 29747 8192000000 10114260368 10553737216 1119085280 12.018 98 26 52 1 1192148480 67851600 5211600
12:37:08 37205 8192000000 10112848320 10547691520 1129798880 12.119 99 36 70 0 1201716064 64978608 6938576
12:38:47 104926 8192000000 10154201168 10605690880 1078105296 11.629 95 15 30 1 1165350208 84145856 3099056
12:41:39 51035 8192000000 10088512096 10562248704 1113438464 11.965 98 39 72 1 1190675248 69836128 7400656
12:43:47 120633 8192000000 10069875008 10512412672 1101717056 11.854 98 39 77 1 1166897376 57202016 7978304
12:46:51 23774 8192000000 10116478304 10545229824 1121455712 12.041 99 48 93 2 1209369456 78159936 9753808
12:48:17 70045 8192000000 10059265584 10552758272 1120805024 12.035 99 23 42 0 1199297952 73479952 5012976
12:50:35 68321 8192000000 10098696544 10552369152 1133203792 12.152 100 23 46 1 1204055216 66007664 4843760
12:52:47 29980 8192000000 10059651792 10495176704 1115456080 11.984 99 27 42 3 1156150176 37428192 3265904
12:54:18 48135 8192000000 10083730272 10529103872 1136241744 12.180 100 13 26 0 1187153184 48512272 2399168
12:56:06 33178 8192000000 10085402672 10543927296 1139495664 12.211 100 18 36 0 1183058448 39304096 4258688
12:57:46 40887 8192000000 10134582016 10603192320 1120988592 12.036 98 38 76 0 1231530640 102324480 8217568
12:59:31 65532 8192000000 10110293584 10552348672 1094930864 11.790 96 15 30 0 1155239184 57339728 2968592
13:01:51 32975 8192000000 10104652144 10589208576 1139103744 12.207 100 35 69 0 1233824032 87282160 7438128
13:03:27 7916 8192000000 10124506032 10563084288 1130960688 12.130 99 49 96 1 1209268320 68128944 10178688
13:04:42 49530 8192000000 10117418160 10557906944 1122853360 12.054 99 24 48 0 1199740848 71559568 5327920
13:06:32 8945 8192000000 10099796752 10514550784 1140420448 12.219 100 64 126 0 1191056224 37830544 12805232
13:07:44 87631 8192000000 10116210688 10568568832 1131206160 12.133 99 58 115 0 1229541568 86326896 12008512
13:10:18 10874 8192000000 10066306768 10505920512 1131065200 12.131 100 27 52 0 1163669536 27926448 4677888
13:11:31 16533 8192000000 10065761696 10502307840 1116308320 11.992 98 32 64 1 1164312416 41527792 6476304
13:12:48 104936 8192000000 10091873840 10544865280 1123224800 12.057 98 16 33 0 1152911104 26427888 3258416
13:15:34 108679 8192000000 10127497520 10620866560 1135887904 12.177 99 18 34 0 1237729744 97695136 4146704
13:18:30 26413 8192000000 10102677168 10552623104 1121100128 12.037 98 37 74 0 1184444064 55589968 7753968
13:20:02 30547 8192000000 10075660720 10511818752 1115051904 11.980 98 29 58 0 1206345920 85267568 6026448
13:21:35 22945 8192000000 10150212864 10586640384 1138600800 12.202 99 39 78 0 1240727344 93969872 8156672
13:23:01 25183 8192000000 10147140624 10579955712 1124533568 12.070 98 41 80 0 1234291152 101198928 8558656
13:24:32 65214 8192000000 10186897024 10651738112 1113625200 11.967 97 33 66 0 1247409168 126514720 7269248
13:26:54 63530 8192000000 10182271632 10690568192 1155303744 12.359 100 21 42 1 1283270912 123340288 4626880
13:28:58 58070 8192000000 10155789408 10641715200 1115456848 11.984 97 16 30 0 1197177696 78866304 2854544
13:30:58 134543 8192000000 10082052464 10529984512 1130598480 12.127 99 22 41 0 1180137184 44944512 4594192
13:34:29 80363 8192000000 10091521328 10549207040 1113187664 11.963 97 5 10 0 1151946240 38006960 751616
13:36:51 51192 8192000000 9195807024 9515663360 342226320 4.010 30 6 12 0 386539152 42939392 1373440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-12-2022 13:40:16 to 24-12-2022 13:40:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-12-2022 13:40:33 to 24-12-2022 13:40:35.

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          220643
MON$OLDEST_ACTIVE               220644
MON$OLDEST_SNAPSHOT             220644
MON$NEXT_TRANSACTION            220644
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                       2058976
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.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:432: 
:433: mscs-test-161.budzdorov.ru    Sat Dec 24 13:37:52 2022
:434:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:435: 
--- end of diff output ---

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

24.12.22 13:40:36. 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
2022-12-24 13:40:38 No severe PSQL-related problems occured
24.12.22 13:40:40. End of report.