Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.05.2022 10: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 16203264 kB
MemAvailable 31445244 kB
Buffers 0 kB
Cached 15538076 kB
SwapCached 2588 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.2
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-05-22 10:37:17
DTS_END                         2022-05-22 10:37:17.7710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48654
TRN_ID                          221364
ATT_ID                          628
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: 8945.05 25167 1073406
customer order: creation 168.92 463 20270
customer order: refuse 15.09 413 1811
order to supplier: creation 45.18 1340 5422
order to supplier: removal 3.38 152928 406
invoice (draft): creation 44.93 3153 5391
invoice (draft): removal 50.35 926 6042
invoice accept: apply 40.83 27619 4899
invoice accept: cancel 44.98 1183 5398
customer reserve: creation 3839.51 261 460741
customer reserve: removal 4405.72 92 528686
realization accept: apply 82.23 314 9868
realization accept: cancel 74.62 177 8954
payment from customer: creation 58.70 196 7044
payment from customer: removal 5.77 147 692
payment to supplier: creation 49.62 215 5954
payment to supplier: removal 3.25 129 390
service: total inventory turnovers 3.83 3798 459
service: total monetary turnovers 7.30 360 876
service: aggregate perf. data 0.82 17168 98
service: refresh index statistics 0.04 292463 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-05-2022 10:39:50 to 22-05-2022 10:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7572
TEST_TIME 2 5361
TEST_TIME 3 5972
TEST_TIME 4 6346
TEST_TIME 5 6413
TEST_TIME 6 6134
TEST_TIME 7 5741
TEST_TIME 8 6011
TEST_TIME 9 6198
TEST_TIME 10 6783
TEST_TIME 11 6637
TEST_TIME 12 6895
TEST_TIME 13 6990
TEST_TIME 14 7137
TEST_TIME 15 7110
TEST_TIME 16 7143
TEST_TIME 17 7374
TEST_TIME 18 7276
TEST_TIME 19 7244
TEST_TIME 20 7517
TEST_TIME 21 7622
TEST_TIME 22 7824
TEST_TIME 23 8065
TEST_TIME 24 8220
TEST_TIME 25 8729
TEST_TIME 26 8743
TEST_TIME 27 9134
TEST_TIME 28 9236
TEST_TIME 29 9407
TEST_TIME 30 9548
TEST_TIME 31 10345
TEST_TIME 32 10427
TEST_TIME 33 10760
TEST_TIME 34 10774
TEST_TIME 35 10582
TEST_TIME 36 10390
TEST_TIME 37 10453
TEST_TIME 38 10476
TEST_TIME 39 10444
TEST_TIME 40 10486
TEST_TIME 41 10431
TEST_TIME 42 10404
TEST_TIME 43 10243
TEST_TIME 44 10076
TEST_TIME 45 10019
TEST_TIME 46 10090
TEST_TIME 47 9985
TEST_TIME 48 9840
TEST_TIME 49 9861
TEST_TIME 50 9770
TEST_TIME 51 9697
TEST_TIME 52 9735
TEST_TIME 53 9687
TEST_TIME 54 9676
TEST_TIME 55 9610
TEST_TIME 56 9592
TEST_TIME 57 9545
TEST_TIME 58 9424
TEST_TIME 59 9414
TEST_TIME 60 9464
TEST_TIME 61 9612
TEST_TIME 62 9688
TEST_TIME 63 9676
TEST_TIME 64 9599
TEST_TIME 65 9690
TEST_TIME 66 9684
TEST_TIME 67 9697
TEST_TIME 68 9649
TEST_TIME 69 9702
TEST_TIME 70 9724
TEST_TIME 71 9824
TEST_TIME 72 9747
TEST_TIME 73 9786
TEST_TIME 74 9670
TEST_TIME 75 9622
TEST_TIME 76 9556
TEST_TIME 77 9524
TEST_TIME 78 9506
TEST_TIME 79 9454
TEST_TIME 80 9442
TEST_TIME 81 9408
TEST_TIME 82 9376
TEST_TIME 83 9322
TEST_TIME 84 9320
TEST_TIME 85 9281
TEST_TIME 86 9231
TEST_TIME 87 9318
TEST_TIME 88 9294
TEST_TIME 89 9307
TEST_TIME 90 9349
TEST_TIME 91 9312
TEST_TIME 92 9336
TEST_TIME 93 9389
TEST_TIME 94 9362
TEST_TIME 95 9377
TEST_TIME 96 9499
TEST_TIME 97 9416
TEST_TIME 98 9333
TEST_TIME 99 9291
TEST_TIME 100 9269
TEST_TIME 101 9234
TEST_TIME 102 9203
TEST_TIME 103 9177
TEST_TIME 104 9212
TEST_TIME 105 9207
TEST_TIME 106 9169
TEST_TIME 107 9202
TEST_TIME 108 9175
TEST_TIME 109 9144
TEST_TIME 110 9209
TEST_TIME 111 9176
TEST_TIME 112 9142
TEST_TIME 113 9205
TEST_TIME 114 9212
TEST_TIME 115 9187
TEST_TIME 116 9132
TEST_TIME 117 9107
TEST_TIME 118 9091
TEST_TIME 119 9033
TEST_TIME 120 8969

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-05-2022 10:39:54 to 22-05-2022 10:39:58.

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 1498877 1498877 0 0.00 0 20661 14 0 0
doc_list_biud 1498872 1498872 0 0.00 0 67007 38 0 0
sp_add_invoice_to_stock 5019 4899 120 2.39 71 227077 27620 0 120
sp_cancel_adding_invoice 5398 5398 0 0.00 6 140350 1184 0 0
sp_cancel_client_order 1811 1811 0 0.00 5 10273 413 0 0
sp_cancel_customer_reserve 528686 528686 0 0.00 1 67009 92 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 4 6688 147 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 4 2660 129 0 0
sp_cancel_supplier_invoice 6050 6042 8 0.13 3 23855 926 0 8
sp_cancel_supplier_order 406 406 0 0.00 14 1441855 152929 0 0
sp_cancel_write_off 8954 8954 0 0.00 2 10489 177 0 0
sp_client_order 20270 20270 0 0.00 6 33526 464 0 0
sp_customer_reserve 460741 460741 0 0.00 1 56923 262 0 0
sp_fill_shopping_cart 31079 31079 0 0.00 1 18777 80 0 0
sp_fill_shopping_cart_clo_res 460710 460710 0 0.00 0 13113 18 0 0
sp_get_clo_for_invoice 4858 4858 0 0.00 0 21762 280 0 0
sp_kill_cost_storno 15434 15434 0 0.00 0 9273 76 0 0
sp_kill_qstorno_ret_qs2qd 1072774 1072774 0 0.00 0 67002 41 0 0
sp_kill_qty_storno 566023 566023 0 0.00 0 67006 94 0 0
sp_lock_selected_doc 20423 20423 0 0.00 0 10460 9 0 0
sp_make_cost_storno 27724 27724 0 0.00 0 17528 92 0 0
sp_multiply_rows_for_pdistr 27724 27724 0 0.00 0 12379 41 0 0
sp_multiply_rows_for_qdistr 20270 20270 0 0.00 0 23605 181 0 0
sp_pay_from_customer 7044 7044 0 0.00 5 20383 196 0 0
sp_pay_to_supplier 5954 5954 0 0.00 7 19655 216 0 0
sp_payment_common 12998 12998 0 0.00 2 13881 49 0 0
sp_qd_handle_on_cancel_clo 1811 1811 0 0.00 1 10250 326 0 0
sp_qd_handle_on_invoice_upd_sts 10256 10256 0 0.00 0 31126 510 0 0
sp_qd_handle_on_reserve_upd_sts 9868 9868 0 0.00 0 13267 85 0 0
sp_reserve_write_off 9868 9868 0 0.00 9 20151 314 0 0
sp_supplier_invoice 5391 5391 0 0.00 8 100643 3154 0 0
sp_supplier_order 5422 5422 0 0.00 7 30495 1341 0 0
srv_aggregate_perf_data 98 98 0 0.00 2576 188842 17168 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 825 101284 35140 0 0
srv_find_qd_qs_mism 1025419 1025419 0 0.00 0 15780 11 0 0
srv_make_invnt_saldo 459 459 0 0.00 59 95294 3798 0 0
srv_make_money_saldo 876 876 0 0.00 1 16303 361 0 0
srv_recalc_idx_stat 5 5 0 0.00 99612 511194 292463 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4903 4903 0 0.00 1 12745 927 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 2 10228 482 0 0
t$perf-norm:sp_cancel_client_order 1811 1811 0 0.00 0 5854 35 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 1077 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 438 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 254 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 491 26 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 91181 6792 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 397 16 0 0
t$perf-norm:sp_client_order 20270 20270 0 0.00 0 10559 37 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 2150 16 0 0
t$perf-norm:sp_pay_from_customer 7044 7044 0 0.00 0 4212 20 0 0
t$perf-norm:sp_pay_to_supplier 5954 5954 0 0.00 0 4586 20 0 0
t$perf-norm:sp_reserve_write_off 9868 9868 0 0.00 0 10859 22 0 0
t$perf-norm:sp_supplier_invoice 5391 5391 0 0.00 0 2561 29 0 0
t$perf-norm:sp_supplier_order 5422 5422 0 0.00 0 4506 31 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 512 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 283 7 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 1393 21 0 0
t$perf-norm:srv_make_money_saldo 876 876 0 0.00 0 2977 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 193 48 0 0
v_add_invoice_to_stock 9954 9714 240 2.41 0 8821 25 0 240
v_all_customers 37112 37112 0 0.00 0 90 4 0 0
v_all_suppliers 30576 30576 0 0.00 0 234 4 0 0
v_all_wares 40540 40540 0 0.00 0 164 4 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 3742 23 0 0
v_cancel_client_order 3622 3622 0 0.00 0 3192 15 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 3563 19 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 2739 13 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 2612 18 0 0
v_cancel_supplier_invoice 788 772 16 2.03 0 331 21 0 16
v_cancel_supplier_order 812 812 0 0.00 0 438 9 0 0
v_cancel_write_off 1896 1896 0 0.00 0 3639 29 0 0
v_max_id_clo_ord 5422 5422 0 0.00 0 414 5 0 0
v_max_id_clo_res 984 984 0 0.00 0 1473 10 0 0
v_max_id_ord_sup 5387 5387 0 0.00 0 3776 8 0 0
v_max_non_paid_invoice 1475 1475 0 0.00 0 275 7 0 0
v_max_non_paid_realizn 3750 3750 0 0.00 0 1933 8 0 0
v_min_id_clo_ord 5422 5422 0 0.00 0 838 7 0 0
v_min_id_clo_res 984 984 0 0.00 0 5207 18 0 0
v_min_id_ord_sup 5387 5387 0 0.00 0 9497 15 0 0
v_min_non_paid_invoice 5954 5954 0 0.00 0 8733 17 0 0
v_min_non_paid_realizn 7044 7044 0 0.00 0 13466 24 0 0
v_our_firm 10014 10014 0 0.00 0 231 4 0 0
v_reserve_write_off 19736 19736 0 0.00 0 7278 12 0 0
x_lock_depdocs_on_canc_invoice 11054 11054 0 0.00 0 14952 117 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 15065 371 0 0
x_make_qstorno_1000_1200 5422 5422 0 0.00 2 30466 1179 0 0
x_make_qstorno_1200_2000 5391 5391 0 0.00 4 92932 2934 0 0
x_make_qstorno_2100_3300 460741 460741 0 0.00 0 56920 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-05-2022 10:39:59 to 22-05-2022 10:40:03.

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:21 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:22 32131 8192000000 9732060448 10034360320 812145776 9.019 100 86 172 0 841330256 7777792 21406688
08:10:01 22998 8192000000 9771860016 10082607104 849141056 9.391 100 92 184 0 884982336 13867856 21973424
08:11:38 14371 8192000000 9819275888 10141782016 877527632 9.675 100 83 165 0 923759712 27472560 18759520
08:12:56 11778 8192000000 9874103328 10207756288 921850944 10.114 100 86 172 0 984794928 44016784 18927200
08:14:13 3877 8192000000 9928774096 10272104448 967883680 10.566 100 88 170 3 1041951552 55710224 18357648
08:15:21 1473 8192000000 9989496784 10345648128 1002164768 10.900 100 84 160 1 1102344096 84566912 15612416
08:16:26 1161 8192000000 9990166480 10358710272 1026019376 11.130 100 93 173 1 1109821136 65643152 18158608
08:17:29 940 8192000000 10025574592 10399961088 1050586736 11.366 100 92 178 1 1147795152 79746480 17461936
08:18:32 1036 8192000000 10028489344 10404122624 1059255888 11.449 100 95 183 1 1150239200 70624240 20359072
08:19:36 1113 8192000000 10051819600 10432532480 1073541808 11.586 100 94 178 0 1173230256 81488784 18199664
08:20:39 1267 8192000000 10057858640 10441756672 1073905184 11.589 100 95 181 4 1180025728 88630736 17489808
08:21:41 1018 8192000000 10067509536 10457870336 1080742560 11.655 100 86 170 1 1189718624 91544704 17431360
08:22:43 2367 8192000000 10058538928 10441601024 1085014640 11.695 100 81 158 1 1180066608 79100800 15951168
08:23:51 1564 8192000000 10036697136 10428092416 1094137216 11.782 100 88 162 2 1163702768 52461680 17103872
08:24:53 1217 8192000000 10061530416 10449235968 1096683344 11.806 100 89 170 0 1179637840 64735088 18219408
08:25:55 1317 8192000000 10057158656 10446626816 1089955392 11.742 100 86 158 3 1177785152 70522688 17307072
08:26:57 3075 8192000000 10041510464 10440040448 1101366336 11.851 100 65 121 1 1166480304 53339744 11774224
08:28:01 1015 8192000000 10070109712 10459406336 1109612672 11.929 100 95 183 1 1193911760 66438112 17860976
08:29:03 654 8192000000 10032163328 10413359104 1077677616 11.625 100 91 177 1 1152590496 56990624 17922256
08:30:04 3197 8192000000 10022119696 10416840704 1077253552 11.621 100 74 142 0 1152204992 60796480 14154960
08:31:09 1249 8192000000 10048776176 10436505600 1081867056 11.665 100 87 172 2 1172451936 73470512 17114368
08:32:12 1096 8192000000 10017391936 10391470080 1036296160 11.229 100 94 185 0 1139345616 84542672 18506784
08:33:13 1043 8192000000 10035587696 10409996288 1048501248 11.346 100 93 179 1 1158876800 91695040 18680512
08:34:16 3676 8192000000 9960234896 10337349632 1034633776 11.213 100 67 126 3 1086245984 38882912 12729296
08:35:20 1151 8192000000 9982707488 10358386688 1031315248 11.181 99 85 162 3 1108269504 59481088 17473168
08:36:21 1169 8192000000 9985843088 10371772416 1047158336 11.333 100 73 129 1 1113531936 51943264 14430336
08:37:27 28171 8192000000 10010422912 10396585984 1065908976 11.513 100 60 108 0 1125106448 49246960 9950512
08:39:03 7302 8192000000 10025764944 10409775104 1080432592 11.652 100 77 150 0 1144472672 48923232 15116848
08:40:16 2341 8192000000 10061036160 10447499264 1077049392 11.619 100 78 148 1 1184793088 92174400 15569296
08:41:24 5857 8192000000 10061430752 10449534976 1097915440 11.818 100 80 157 3 1182701664 69082672 15703552
08:42:32 2981 8192000000 10112504688 10502361088 1113680672 11.967 100 84 156 2 1229306240 100836048 14789520
08:43:42 8691 8192000000 10129313392 10529968128 1124914352 12.073 100 65 118 1 1249580928 111074096 13592480
08:44:57 2520 8192000000 10170375264 10571792384 1133135056 12.151 100 73 136 2 1292121040 143662432 15323552
08:46:03 936 8192000000 10240340016 10634723328 1138381152 12.200 100 100 198 0 1353845936 194055360 21409424
08:47:08 943 8192000000 10226624784 10644529152 1149074416 12.301 100 96 191 0 1339496480 168903904 21518160
08:48:12 1172 8192000000 10253636832 10649776128 1156989136 12.375 100 90 176 1 1366640304 190528976 19122192
08:49:15 1113 8192000000 10241188208 10656301056 1168067488 12.479 100 87 166 1 1359000256 172024752 18908016
08:50:21 927 8192000000 10295467520 10699517952 1178644768 12.578 100 94 170 4 1406612448 209434784 18532896
08:51:24 823 8192000000 10328229312 10730352640 1170568144 12.502 99 97 187 1 1438673536 246694688 21410704
08:52:29 1644 8192000000 10344738336 10756558848 1178903744 12.580 100 90 165 1 1458525984 259698192 19924048
08:53:34 940 8192000000 10367204784 10802872320 1182319216 12.612 100 87 165 0 1478277936 277893040 18065680
08:54:40 1170 8192000000 10367713472 10781634560 1175386128 12.547 100 93 181 3 1474196592 277250896 21559568
08:55:46 1703 8192000000 10403577648 10828705792 1192732640 12.709 100 79 158 0 1514369920 301765488 19871792
08:56:52 1308 8192000000 10450647344 10900770816 1204754928 12.820 100 93 182 1 1559603728 331707168 23141632
08:57:57 1793 8192000000 10469080560 10901057536 1209118368 12.861 100 95 186 1 1578967488 347706096 22143024
08:59:02 885 8192000000 10482169008 10912686080 1187075552 12.656 100 98 196 1 1592869184 381068000 24725632
09:00:05 6597 8192000000 10481908720 10931650560 1182984352 12.618 100 70 139 0 1601073280 400903072 17185856
09:01:16 3577 8192000000 10480231472 10896359424 1169978208 12.497 99 70 140 0 1580669776 392983216 17708352
09:02:24 21250 8192000000 10416650528 10856824832 1167581424 12.474 99 44 87 1 1520089152 341087264 11420464
09:03:50 10511 8192000000 10429871760 10903846912 1169516192 12.492 100 42 84 0 1542732576 362579856 10636528
09:05:04 17904 8192000000 10371967744 10807910400 1149073808 12.301 100 26 52 1 1463161408 307337664 6749936
09:06:30 25738 8192000000 10336529248 10798133248 1126802624 12.091 99 29 56 2 1431736416 297388656 7545136
09:08:06 1927 8192000000 10245883120 10773295104 1152300496 12.331 100 65 129 1 1350810752 183925872 14584384
09:09:09 40640 8192000000 10198405296 10702733312 1146260352 12.274 99 34 69 0 1307784224 153398384 8125488
09:10:58 61808 8192000000 10086660160 10564931584 1116110272 11.990 98 7 14 0 1169505840 52340928 1054640
09:13:01 36627 8192000000 10111371552 10535579648 1130737968 12.128 99 42 71 1 1199664672 61754288 7172416
09:14:54 65463 8192000000 10088106880 10525401088 1101103600 11.848 97 25 47 1 1167382624 61158992 5120032
09:17:05 84790 8192000000 10077206448 10502713344 1107716416 11.911 98 23 40 0 1127767168 16013760 4036992
09:19:33 86592 8192000000 10103530656 10561236992 1090295344 11.745 96 13 27 0 1158867408 65783520 2788544
09:22:02 70399 8192000000 10112316576 10555232256 1117178160 12.000 99 27 53 0 1201042384 77968800 5895424
09:24:14 87972 8192000000 10078216448 10506862592 1114896016 11.979 99 18 36 0 1154472560 35877264 3699280
09:26:42 38415 8192000000 10079993408 10528129024 1126036704 12.084 100 26 52 3 1197902384 65826400 6039280
09:28:23 2330 8192000000 10145170000 10564763648 1135151760 12.170 100 83 151 2 1232912624 80748912 17011952
09:29:26 35895 8192000000 10124754416 10598670336 1113779536 11.968 98 24 46 1 1211732912 92994640 4958736
09:32:14 51148 8192000000 10113786816 10557779968 1117948560 12.008 98 15 30 1 1209399760 87480192 3971008
09:34:11 72792 8192000000 10150320240 10626641920 1104684976 11.882 98 13 26 0 1209577568 101963920 2928672
09:36:29 57375 8192000000 10190686144 10639400960 1116832880 11.997 99 19 38 1 1278685760 156910352 4942528
09:38:27 60844 8192000000 10074901152 10574389248 1134681472 12.165 100 28 56 0 1225195456 84173152 6340832
09:40:30 24465 8192000000 10090538032 10530721792 1139192176 12.208 100 35 70 1 1176150528 29896656 7061696
09:41:55 66254 8192000000 10123279840 10617389056 1138124176 12.198 99 35 70 0 1239487520 94422560 6940784
09:44:03 50388 8192000000 10077085760 10526318592 1125639088 12.080 98 15 30 3 1158945616 30473376 2833152
09:45:53 27886 8192000000 10139207488 10606215168 1123883408 12.064 98 21 42 0 1212671056 84127920 4659728
09:47:29 64038 8192000000 10119692560 10596118528 1106465280 11.899 97 16 32 0 1205890640 96089824 3335536
09:49:33 48665 8192000000 10104345328 10589257728 1099717344 11.835 97 40 80 0 1166494224 58799056 7977824
09:51:23 76952 8192000000 10120248976 10576171008 1129044672 12.112 100 26 52 0 1202519616 67785152 5689792
09:53:59 35210 8192000000 10099727408 10537033728 1107572976 11.909 97 26 52 0 1182289120 68834416 5881728
09:55:36 68562 8192000000 10095673168 10559520768 1128604944 12.108 99 14 28 0 1200602160 69154240 2842976
09:57:45 66791 8192000000 10081177440 10561867776 1131465728 12.135 100 9 18 0 1187881872 54732512 1683632
09:59:53 7273 8192000000 10065897840 10533486592 1125785184 12.082 100 24 46 3 1170463232 39053504 5624544
10:01:10 80976 8192000000 10098903312 10559479808 1086496336 11.709 96 13 24 0 1156721712 67641360 2584016
10:03:32 17096 8192000000 10136725792 10573049856 1121516976 12.041 100 40 78 3 1219814640 89837872 8459792
10:04:53 46100 8192000000 10107112080 10543788032 1120744800 12.034 99 18 36 0 1170199328 45729088 3725440
10:06:39 45318 8192000000 10134037376 10576859136 1105826864 11.893 98 21 42 1 1196668624 85602048 5239712
10:08:25 30917 8192000000 10119636080 10558767104 1122429664 12.050 99 40 81 1 1206700640 75432912 8838064
10:10:02 35797 8192000000 10086094304 10544422912 1092264000 11.764 97 37 74 0 1155839392 56530464 7044928
10:11:39 27874 8192000000 10158450224 10578132992 1123922160 12.064 99 49 97 2 1227398944 93026016 10450768
10:13:11 56699 8192000000 10061984816 10547585024 1118043520 12.009 99 16 30 1 1220153216 98279984 3829712
10:15:08 50651 8192000000 10038229520 10474819584 1114125824 11.971 99 34 65 0 1135463296 14795792 6541680
10:16:59 101284 8192000000 10063784272 10535149568 1090732352 11.750 97 24 48 1 1182788928 86793008 5263568
10:19:46 91542 8192000000 10121899280 10581450752 1111379776 11.945 98 16 33 1 1185693168 70702544 3610848
10:22:19 49100 8192000000 10075685680 10515263488 1114829648 11.978 98 32 63 1 1155773136 33938016 7005472
10:24:21 31786 8192000000 10090451424 10525777920 1118342288 12.011 99 30 60 0 1178467952 54257520 5868144
10:25:53 48494 8192000000 10144043584 10580348928 1117369616 12.002 98 38 76 1 1193222384 68235856 7616912
10:27:47 51358 8192000000 10072320192 10512596992 1116684880 11.996 99 26 52 0 1160450912 38344336 5421696
10:29:44 17136 8192000000 10120647104 10554896384 1117817728 12.006 99 49 96 1 1205127040 77314640 9994672
10:31:04 49203 8192000000 10084179760 10549276672 1106106016 11.896 98 20 40 0 1163222144 53226224 3889904
10:32:57 70351 8192000000 10050602400 10493497344 1101944288 11.856 98 19 38 2 1159147312 53391120 3811904
10:35:13 77887 8192000000 10099350960 10585112576 1132375840 12.144 100 8 16 0 1197192000 63008432 1807728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 22-05-2022 10:40:04 to 22-05-2022 10:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-05-2022 10:40:22 to 22-05-2022 10:40:24.

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          219036
MON$OLDEST_ACTIVE               221947
MON$OLDEST_SNAPSHOT             221947
MON$NEXT_TRANSACTION            221947
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                       2066016
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2767 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2767 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2767 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2767 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 ---
--- end of diff output ---

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

22.05.22 10:40:25. 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-05-22 10:40:27 No severe PSQL-related problems occured
22.05.22 10:40:28. End of report.