Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.04.2022 13: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 16400692 kB
MemAvailable 31580860 kB
Buffers 0 kB
Cached 15337840 kB
SwapCached 2280 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-04-08 13:37:15
DTS_END                         2022-04-08 13:37:15.3960
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/58898
TRN_ID                          216943
ATT_ID                          643
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: 8951.78 20686 1074214
customer order: creation 165.77 467 19892
customer order: refuse 13.83 438 1660
order to supplier: creation 44.53 1415 5344
order to supplier: removal 3.54 159153 425
invoice (draft): creation 43.63 2955 5236
invoice (draft): removal 50.77 1033 6092
invoice accept: apply 40.85 27969 4902
invoice accept: cancel 45.93 1184 5512
customer reserve: creation 3767.13 269 452056
customer reserve: removal 4493.97 98 539276
realization accept: apply 79.93 282 9591
realization accept: cancel 77.36 158 9283
payment from customer: creation 57.12 170 6854
payment from customer: removal 5.54 115 665
payment to supplier: creation 46.17 199 5540
payment to supplier: removal 3.13 114 375
service: total inventory turnovers 3.85 3755 462
service: total monetary turnovers 7.72 302 926
service: aggregate perf. data 0.98 13524 118
service: refresh index statistics 0.04 200121 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-04-2022 13:39:06 to 08-04-2022 13:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8457
TEST_TIME 2 7250
TEST_TIME 3 6516
TEST_TIME 4 5693
TEST_TIME 5 5549
TEST_TIME 6 5403
TEST_TIME 7 5177
TEST_TIME 8 5433
TEST_TIME 9 5984
TEST_TIME 10 6461
TEST_TIME 11 6433
TEST_TIME 12 6689
TEST_TIME 13 6762
TEST_TIME 14 6961
TEST_TIME 15 7198
TEST_TIME 16 7288
TEST_TIME 17 7364
TEST_TIME 18 7416
TEST_TIME 19 7583
TEST_TIME 20 7822
TEST_TIME 21 7932
TEST_TIME 22 8235
TEST_TIME 23 8244
TEST_TIME 24 8611
TEST_TIME 25 8772
TEST_TIME 26 9307
TEST_TIME 27 9674
TEST_TIME 28 9683
TEST_TIME 29 9947
TEST_TIME 30 10124
TEST_TIME 31 10655
TEST_TIME 32 10728
TEST_TIME 33 10999
TEST_TIME 34 10966
TEST_TIME 35 10879
TEST_TIME 36 10914
TEST_TIME 37 10826
TEST_TIME 38 10667
TEST_TIME 39 10422
TEST_TIME 40 10328
TEST_TIME 41 10364
TEST_TIME 42 10335
TEST_TIME 43 10195
TEST_TIME 44 10163
TEST_TIME 45 10094
TEST_TIME 46 9959
TEST_TIME 47 9998
TEST_TIME 48 9974
TEST_TIME 49 9817
TEST_TIME 50 9739
TEST_TIME 51 9727
TEST_TIME 52 9667
TEST_TIME 53 9615
TEST_TIME 54 9805
TEST_TIME 55 9791
TEST_TIME 56 9733
TEST_TIME 57 9756
TEST_TIME 58 9640
TEST_TIME 59 9755
TEST_TIME 60 9817
TEST_TIME 61 9765
TEST_TIME 62 9864
TEST_TIME 63 9871
TEST_TIME 64 9816
TEST_TIME 65 9852
TEST_TIME 66 9833
TEST_TIME 67 9833
TEST_TIME 68 9867
TEST_TIME 69 9854
TEST_TIME 70 9740
TEST_TIME 71 9755
TEST_TIME 72 9709
TEST_TIME 73 9694
TEST_TIME 74 9613
TEST_TIME 75 9670
TEST_TIME 76 9649
TEST_TIME 77 9592
TEST_TIME 78 9544
TEST_TIME 79 9496
TEST_TIME 80 9425
TEST_TIME 81 9352
TEST_TIME 82 9338
TEST_TIME 83 9395
TEST_TIME 84 9349
TEST_TIME 85 9309
TEST_TIME 86 9304
TEST_TIME 87 9257
TEST_TIME 88 9225
TEST_TIME 89 9244
TEST_TIME 90 9252
TEST_TIME 91 9389
TEST_TIME 92 9364
TEST_TIME 93 9407
TEST_TIME 94 9426
TEST_TIME 95 9399
TEST_TIME 96 9374
TEST_TIME 97 9339
TEST_TIME 98 9277
TEST_TIME 99 9270
TEST_TIME 100 9207
TEST_TIME 101 9185
TEST_TIME 102 9158
TEST_TIME 103 9130
TEST_TIME 104 9152
TEST_TIME 105 9104
TEST_TIME 106 9099
TEST_TIME 107 9085
TEST_TIME 108 9069
TEST_TIME 109 9052
TEST_TIME 110 9032
TEST_TIME 111 9051
TEST_TIME 112 9141
TEST_TIME 113 9190
TEST_TIME 114 9206
TEST_TIME 115 9162
TEST_TIME 116 9101
TEST_TIME 117 9121
TEST_TIME 118 9095
TEST_TIME 119 9036
TEST_TIME 120 8969

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-04-2022 13:39:10 to 08-04-2022 13:39:13.

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 1491068 1491068 0 0.00 0 13537 14 0 0
doc_list_biud 1491068 1491068 0 0.00 0 49064 42 0 0
sp_add_invoice_to_stock 5077 4902 175 3.44 22 230195 27969 0 175
sp_cancel_adding_invoice 5512 5512 0 0.00 7 87424 1185 0 0
sp_cancel_client_order 1660 1660 0 0.00 6 10581 438 0 0
sp_cancel_customer_reserve 539276 539276 0 0.00 1 49091 98 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 4 3377 116 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 4 3168 114 0 0
sp_cancel_supplier_invoice 6104 6092 12 0.19 4 27415 1034 0 12
sp_cancel_supplier_order 425 425 0 0.00 13 1549915 159154 0 0
sp_cancel_write_off 9283 9283 0 0.00 2 10104 159 0 0
sp_client_order 19892 19892 0 0.00 6 52594 468 0 0
sp_customer_reserve 452056 452056 0 0.00 1 128447 270 0 0
sp_fill_shopping_cart 30468 30468 0 0.00 1 9829 80 0 0
sp_fill_shopping_cart_clo_res 452021 452021 0 0.00 0 17553 22 0 0
sp_get_clo_for_invoice 4860 4860 0 0.00 0 13099 276 0 0
sp_kill_cost_storno 15835 15835 0 0.00 0 8102 69 0 0
sp_kill_qstorno_ret_qs2qd 1094352 1094352 0 0.00 0 34475 43 0 0
sp_kill_qty_storno 576697 576697 0 0.00 0 49063 100 0 0
sp_lock_selected_doc 19982 19982 0 0.00 0 10037 7 0 0
sp_make_cost_storno 26843 26843 0 0.00 0 11868 82 0 0
sp_multiply_rows_for_pdistr 26843 26843 0 0.00 0 10031 37 0 0
sp_multiply_rows_for_qdistr 19892 19892 0 0.00 0 13949 178 0 0
sp_pay_from_customer 6854 6854 0 0.00 6 24923 170 0 0
sp_pay_to_supplier 5540 5540 0 0.00 7 11845 200 0 0
sp_payment_common 12394 12394 0 0.00 2 24891 49 0 0
sp_qd_handle_on_cancel_clo 1660 1660 0 0.00 0 10574 368 0 0
sp_qd_handle_on_invoice_upd_sts 10370 10370 0 0.00 0 41998 529 0 0
sp_qd_handle_on_reserve_upd_sts 9591 9591 0 0.00 0 28227 74 0 0
sp_reserve_write_off 9591 9591 0 0.00 9 28260 282 0 0
sp_supplier_invoice 5236 5236 0 0.00 8 96648 2955 0 0
sp_supplier_order 5344 5344 0 0.00 8 64123 1415 0 0
srv_aggregate_perf_data 118 118 0 0.00 2648 140233 13524 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 661 116605 34977 0 0
srv_find_qd_qs_mism 1027185 1027185 0 0.00 0 13535 11 0 0
srv_make_invnt_saldo 462 462 0 0.00 60 74552 3755 0 0
srv_make_money_saldo 926 926 0 0.00 1 12330 302 0 0
srv_recalc_idx_stat 5 5 0 0.00 51440 438427 200121 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 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4902 4902 0 0.00 1 11993 951 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 7814 419 0 0
t$perf-norm:sp_cancel_client_order 1660 1660 0 0.00 0 2703 28 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 742 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 1102 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 5704 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 1295 33 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 112664 6915 0 0
t$perf-norm:sp_cancel_write_off 953 953 0 0.00 0 6790 26 0 0
t$perf-norm:sp_client_order 19892 19892 0 0.00 0 9518 38 0 0
t$perf-norm:sp_customer_reserve 943 943 0 0.00 0 2177 16 0 0
t$perf-norm:sp_pay_from_customer 6854 6854 0 0.00 0 7589 23 0 0
t$perf-norm:sp_pay_to_supplier 5540 5540 0 0.00 0 6786 19 0 0
t$perf-norm:sp_reserve_write_off 9591 9591 0 0.00 0 7633 26 0 0
t$perf-norm:sp_supplier_invoice 5236 5236 0 0.00 0 7654 32 0 0
t$perf-norm:sp_supplier_order 5344 5344 0 0.00 0 5365 33 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 458 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 178 8 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 610 18 0 0
t$perf-norm:srv_make_money_saldo 926 926 0 0.00 0 1502 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 205 84 0 0
v_add_invoice_to_stock 10066 9716 350 3.47 0 6643 25 0 350
v_all_customers 36412 36412 0 0.00 0 183 4 0 0
v_all_suppliers 29362 29362 0 0.00 0 73 4 0 0
v_all_wares 39784 39784 0 0.00 0 179 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1542 16 0 0
v_cancel_client_order 3320 3320 0 0.00 0 2400 9 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 3342 17 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 2935 12 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 1635 13 0 0
v_cancel_supplier_invoice 762 738 24 3.14 0 2430 32 0 24
v_cancel_supplier_order 850 850 0 0.00 0 2343 12 0 0
v_cancel_write_off 1906 1906 0 0.00 0 3901 22 0 0
v_max_id_clo_ord 5344 5344 0 0.00 0 1436 5 0 0
v_max_id_clo_res 943 943 0 0.00 0 3387 15 0 0
v_max_id_ord_sup 5232 5232 0 0.00 0 4492 7 0 0
v_max_non_paid_invoice 1435 1435 0 0.00 0 621 8 0 0
v_max_non_paid_realizn 3579 3579 0 0.00 0 1498 8 0 0
v_min_id_clo_ord 5344 5344 0 0.00 0 1474 8 0 0
v_min_id_clo_res 943 943 0 0.00 0 607 8 0 0
v_min_id_ord_sup 5232 5232 0 0.00 0 2085 7 0 0
v_min_non_paid_invoice 5540 5540 0 0.00 0 5791 19 0 0
v_min_non_paid_realizn 6854 6854 0 0.00 0 6909 17 0 0
v_our_firm 9922 9922 0 0.00 0 186 4 0 0
v_reserve_write_off 19182 19182 0 0.00 0 3792 10 0 0
x_lock_depdocs_on_canc_invoice 11235 11235 0 0.00 0 16487 117 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 14235 422 0 0
x_make_qstorno_1000_1200 5344 5344 0 0.00 3 64105 1244 0 0
x_make_qstorno_1200_2000 5236 5236 0 0.00 4 89547 2751 0 0
x_make_qstorno_2100_3300 452056 452056 0 0.00 0 128444 236 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-04-2022 13:39:14 to 08-04-2022 13:39:18.

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:19 4 8192000000 8801486352 8813797376 6315888 0.077 2 2 3 0 6481152 103904 61360
11:08:19 33573 8192000000 9718937680 10019676160 791598832 8.811 100 90 180 0 821735232 7904064 22232336
11:09:55 17500 8192000000 9769236096 10078826496 841494048 9.315 100 82 165 1 871954208 10795344 19664816
11:11:18 18457 8192000000 9806701744 10124484608 870123392 9.601 100 86 172 0 912309280 22280608 19905280
11:12:42 14899 8192000000 9866737808 10202497024 918296128 10.079 100 68 135 0 968300000 35437936 14565936
11:14:05 2611 8192000000 9924027168 10269618176 960462960 10.494 100 90 172 0 1036180672 58345840 17371872
11:15:09 5009 8192000000 9953180528 10310340608 993368096 10.814 100 83 163 0 1080863472 70319280 17176096
11:16:18 2276 8192000000 9999222864 10365296640 1025040240 11.121 100 90 180 0 1121573536 78828688 17704608
11:17:22 1088 8192000000 10016867520 10388344832 1043309504 11.296 100 89 168 0 1137866432 77361712 17195216
11:18:24 1223 8192000000 10050653648 10420801536 1052995792 11.389 100 97 190 1 1166635264 95943776 17695696
11:19:27 1246 8192000000 10042312096 10416500736 1060492928 11.461 100 91 181 2 1161077088 81966416 18617744
11:20:30 5001 8192000000 10050422736 10430586880 1065871472 11.513 100 88 175 1 1167193968 83787184 17535312
11:21:36 1233 8192000000 10054180624 10441138176 1071490704 11.566 100 92 164 6 1173805616 85818144 16496768
11:22:39 1105 8192000000 10048386048 10433351680 1084621968 11.691 100 100 190 0 1172974928 68921744 19431216
11:23:41 1219 8192000000 10048427488 10434551808 1086899568 11.713 100 95 179 1 1168722176 62653392 19169216
11:24:43 1516 8192000000 10042078528 10430341120 1088193952 11.725 100 77 150 0 1167936528 64619056 15123520
11:25:46 1278 8192000000 10063540128 10448715776 1094947344 11.790 100 98 190 0 1184146624 70229984 18969296
11:26:48 1300 8192000000 10059651136 10444840960 1095700672 11.797 100 91 172 0 1181427152 68266704 17459776
11:27:50 1243 8192000000 10039417424 10424119296 1092923696 11.770 100 96 189 3 1160845760 47710992 20211072
11:28:53 1197 8192000000 10058994080 10444419072 1081450128 11.661 100 95 184 0 1182029488 81993872 18585488
11:29:55 2069 8192000000 10030237936 10418069504 1083663552 11.682 100 90 171 2 1156921840 55464608 17793680
11:30:57 878 8192000000 10039293408 10422403072 1053712256 11.396 98 90 177 0 1158971056 88336880 16921920
11:32:00 1252 8192000000 9998892848 10370695168 1048174992 11.343 99 92 178 0 1119547712 51811856 19560864
11:33:01 2574 8192000000 10032398896 10413195264 1056184272 11.420 100 67 126 0 1150106544 80309808 13612464
11:34:05 1399 8192000000 10004774000 10387615744 1062385440 11.479 100 78 146 4 1126803040 48293376 16124224
11:35:07 1398 8192000000 9987307584 10363494400 1025077984 11.121 99 76 149 0 1105782720 66290992 14413744
11:36:10 1241 8192000000 9980909472 10353913856 1025192512 11.122 99 81 151 4 1103435616 61779360 16463744
11:37:12 19775 8192000000 9996000912 10383106048 1054452288 11.403 100 56 106 0 1116225056 51730000 10042768
11:38:40 4426 8192000000 10027654576 10408300544 1056609152 11.424 99 75 147 1 1147024192 75235824 15179216
11:39:54 1132 8192000000 10051656848 10437771264 1082541040 11.672 100 89 169 2 1172332048 72803616 16987392
11:41:00 975 8192000000 10072104816 10456723456 1096981056 11.809 100 84 159 1 1193409008 78334416 18093536
11:42:10 9495 8192000000 10100048928 10492076032 1101165328 11.849 100 67 124 2 1222312976 107452864 13694784
11:43:26 8599 8192000000 10127549776 10527174656 1120945728 12.036 100 65 124 2 1243770864 108580336 14244800
11:44:38 10813 8192000000 10200925984 10597679104 1131623536 12.137 100 69 138 0 1306649216 159589200 15436480
11:45:56 3559 8192000000 10242355824 10645815296 1150603392 12.315 100 77 146 1 1359188800 191306752 17278656
11:47:03 853 8192000000 10266621568 10666991616 1159989888 12.403 100 94 184 1 1379428304 197642912 21795504
11:48:07 1268 8192000000 10290751072 10690551808 1170178752 12.499 100 94 181 1 1402978560 210430832 22368976
11:49:13 6016 8192000000 10349729392 10753290240 1177213600 12.564 100 79 156 0 1458691136 263168432 18309104
11:50:22 984 8192000000 10357513008 10769666048 1178256240 12.574 100 96 187 1 1464443376 265725120 20462016
11:51:26 1195 8192000000 10399994544 10807541760 1185445936 12.641 100 98 180 2 1508094912 302692720 19956256
11:52:31 979 8192000000 10409966384 10816299008 1192058976 12.703 100 96 189 1 1515794000 302706480 21028544
11:53:36 964 8192000000 10461720432 10875387904 1201468256 12.790 100 98 191 0 1569697728 346310016 21919456
11:54:41 998 8192000000 10486919936 10905747456 1200971744 12.785 100 90 171 1 1594013856 373767744 19274368
11:55:46 1923 8192000000 10520304320 10939981824 1200088624 12.777 100 89 170 0 1627843360 408269520 19485216
11:56:50 1070 8192000000 10500867280 10935197696 1184625168 12.633 99 91 177 0 1608394640 402897024 20872448
11:57:54 654 8192000000 10499655696 10943004672 1185507888 12.642 100 95 188 1 1604506288 395556032 23442368
11:58:59 916 8192000000 10521654832 10978086912 1189579616 12.679 100 99 191 2 1627648912 413498160 24571136
12:00:03 4745 8192000000 10509189808 10943889408 1166047392 12.460 100 50 100 0 1601669520 422689872 12932256
12:01:29 6305 8192000000 10447601424 10925580288 1167043936 12.469 100 48 94 1 1555338528 376771696 11522896
12:02:38 16380 8192000000 10448333808 10917511168 1138712768 12.203 99 42 84 0 1535376624 385080208 11583648
12:03:57 11939 8192000000 10323129232 10796920832 1153851552 12.346 100 32 64 1 1468002592 306239680 7911360
12:05:11 13392 8192000000 10312508384 10765115392 1145307616 12.265 100 40 78 1 1419091808 264987984 8796208
12:06:30 8308 8192000000 10253280144 10750226432 1143028960 12.244 100 56 110 0 1364839392 209573008 12237424
12:07:45 6295 8192000000 10241967824 10669350912 1135636864 12.174 99 55 103 3 1328685776 181708832 11340080
12:09:08 108581 8192000000 10093405072 10636390400 1106935328 11.903 96 14 28 0 1246126272 136210400 2980544
12:11:58 84375 8192000000 10096432224 10555039744 1102949840 11.866 96 17 30 0 1140302000 34642720 2709440
12:14:33 14063 8192000000 10092849200 10522914816 1137565216 12.193 100 48 90 3 1180982784 32200128 11217440
12:15:51 116604 8192000000 10169372144 10632474624 1148020528 12.291 100 49 92 2 1258534064 99804384 10709152
12:18:59 45188 8192000000 10101547424 10564059136 1136308800 12.181 99 17 34 1 1191757712 52022912 3426000
12:20:46 45428 8192000000 10094128304 10528944128 1128785504 12.110 98 39 78 3 1171754240 33915008 9053728
12:22:33 47538 8192000000 10106731552 10548252672 1137862064 12.195 99 29 56 2 1195120608 51345616 5912928
12:24:26 21232 8192000000 10170496592 10619043840 1158632192 12.390 100 49 96 0 1257668224 88080944 10955088
12:25:48 107470 8192000000 10199496448 10648059904 1132639248 12.146 97 17 32 0 1219160816 82935216 3586352
12:28:38 69014 8192000000 10194848704 10650497024 1162670960 12.428 100 18 37 0 1311077888 144181408 4225520
12:30:47 70205 8192000000 10124852912 10672365568 1143119680 12.245 99 24 48 0 1264712720 115881152 5711888
12:33:06 56392 8192000000 10084571184 10553028608 1128444512 12.107 99 30 58 2 1183979904 49356736 6178656
12:35:02 35240 8192000000 10166168512 10594811904 1134027840 12.159 99 48 83 1 1217933136 75819136 8086160
12:36:42 83268 8192000000 10095308400 10562531328 1112327664 11.954 97 21 42 0 1172332768 56043952 3961152
12:39:07 105678 8192000000 10096924592 10569048064 1122141264 12.047 98 14 28 0 1200225856 75333392 2751200
12:42:40 28647 8192000000 10112352752 10555224064 1138080656 12.197 99 35 68 1 1189260272 44381536 6798080
12:44:09 25575 8192000000 10154828480 10592219136 1124265392 12.067 98 32 64 2 1222919120 91851600 6802128
12:45:36 34142 8192000000 10113651552 10577063936 1137045264 12.188 99 27 54 0 1223540848 80386752 6108832
12:47:11 109549 8192000000 10077307440 10523717632 1113449616 11.965 98 22 43 1 1190956976 72858256 4649104
12:50:01 76240 8192000000 10127582144 10623426560 1125686768 12.081 98 32 64 2 1236566736 104001152 6878816
12:52:18 45541 8192000000 10083257056 10588598272 1107375152 11.908 97 15 30 0 1165980240 55428816 3176272
12:54:04 41237 8192000000 10082058928 10517741568 1104661568 11.882 97 47 92 2 1153757456 39176240 9919648
12:55:52 62559 8192000000 10087250784 10548858880 1131820576 12.139 99 8 16 0 1176871472 43556304 1494592
12:57:55 87715 8192000000 10114409328 10609356800 1150356096 12.313 100 11 22 2 1249512016 96581744 2574176
13:00:23 55565 8192000000 10060287888 10523877376 1127099760 12.094 99 14 28 0 1165462880 35502704 2860416
13:02:20 40298 8192000000 10100820848 10555998208 1117008112 11.999 97 39 74 2 1176596768 52168528 7420128
13:04:00 22195 8192000000 10110603824 10549526528 1109798016 11.931 97 35 70 2 1177967408 60826192 7343200
13:05:24 43760 8192000000 10169793024 10612887552 1119220064 12.020 98 30 60 1 1233106144 106918976 6967104
13:07:15 49243 8192000000 10149442896 10619248640 1119064640 12.018 98 30 60 2 1234951872 109852656 6034576
13:09:07 23303 8192000000 10119214176 10542247936 1113757136 11.968 98 45 88 0 1182545152 59971952 8816064
13:10:32 28223 8192000000 10122452112 10555596800 1123024960 12.056 99 36 70 1 1199034400 69101696 6907744
13:12:01 13109 8192000000 10112725968 10541092864 1121154736 12.038 99 55 110 0 1184310160 52479712 10675712
13:13:14 10002 8192000000 10111402544 10546356224 1122246016 12.048 99 54 108 1 1192161632 59515136 10400480
13:14:28 64553 8192000000 10100366864 10574405632 1123665616 12.062 99 31 62 1 1212325792 82239136 6421040
13:16:35 54011 8192000000 10092318000 10594369536 1141570208 12.230 100 6 12 0 1211185424 68146848 1468368
13:18:29 65795 8192000000 10081745296 10571120640 1127269568 12.096 99 19 38 0 1186210800 54453664 4487568
13:20:36 33012 8192000000 10098592128 10558582784 1140016464 12.216 100 29 56 0 1192705360 46778832 5910064
13:22:25 32353 8192000000 10100888896 10543874048 1139105296 12.207 100 32 61 1 1178030800 31614912 7310592
13:24:05 28313 8192000000 10084190704 10527182848 1122395328 12.050 98 26 52 0 1179600768 51255328 5950112
13:25:34 24291 8192000000 10146463808 10584023040 1133239264 12.152 99 40 78 0 1231672288 89511648 8921376
13:27:03 44400 8192000000 10203326000 10648997888 1138073968 12.197 98 37 74 0 1285232384 138747696 8410720
13:28:55 60487 8192000000 10121835808 10635583488 1119997072 12.027 99 24 47 1 1281603648 156049856 5556720
13:31:03 65944 8192000000 10087691504 10526543872 1091852384 11.760 97 26 52 0 1142589680 45511888 5225408
13:33:13 39944 8192000000 10116295520 10541449216 1117092912 12.000 100 59 112 5 1215601040 86693936 11814192
13:34:53 78212 8192000000 10054057216 10572234752 1115190128 11.982 100 3 6 1 1180032224 64197952 644144
13:37:12 10638 8192000000 8972063136 9193537536 138797504 1.666 13 3 6 0 142427888 3286368 344016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 08-04-2022 13:39:20 to 08-04-2022 13:39:37.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-04-2022 13:39:38 to 08-04-2022 13:39:40.

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          217505
MON$OLDEST_ACTIVE               217506
MON$OLDEST_SNAPSHOT             217506
MON$NEXT_TRANSACTION            217506
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                       2068384
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

08.04.22 13:39:41. 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-04-08 13:39:43 No severe PSQL-related problems occured
08.04.22 13:39:44. End of report.