Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.08.2022 13:37
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 15098568 kB
MemAvailable 30958416 kB
Buffers 0 kB
Cached 16557488 kB
SwapCached 2512 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-08-02 13:37:17
DTS_END                         2022-08-02 13:37:17.8310
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/51242
TRN_ID                          222438
ATT_ID                          677
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: 9032.05 23837 1083846
customer order: creation 174.89 507 20987
customer order: refuse 14.80 490 1776
order to supplier: creation 45.11 1508 5413
order to supplier: removal 3.58 173838 429
invoice (draft): creation 45.45 3561 5454
invoice (draft): removal 51.13 1046 6135
invoice accept: apply 41.52 26513 4982
invoice accept: cancel 46.33 1318 5560
customer reserve: creation 3817.67 253 458120
customer reserve: removal 4491.43 108 538971
realization accept: apply 84.56 306 10147
realization accept: cancel 82.43 176 9892
payment from customer: creation 61.35 182 7362
payment from customer: removal 6.10 139 732
payment to supplier: creation 49.19 220 5903
payment to supplier: removal 4.02 137 482
service: total inventory turnovers 3.83 4728 459
service: total monetary turnovers 7.73 457 927
service: aggregate perf. data 0.93 18319 111
service: refresh index statistics 0.03 242925 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-08-2022 13:37:54 to 02-08-2022 13:37:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4775
TEST_TIME 2 4773
TEST_TIME 3 5485
TEST_TIME 4 6215
TEST_TIME 5 6324
TEST_TIME 6 6397
TEST_TIME 7 6377
TEST_TIME 8 6489
TEST_TIME 9 6699
TEST_TIME 10 6663
TEST_TIME 11 6808
TEST_TIME 12 6999
TEST_TIME 13 6902
TEST_TIME 14 6942
TEST_TIME 15 6923
TEST_TIME 16 6958
TEST_TIME 17 6896
TEST_TIME 18 6823
TEST_TIME 19 6831
TEST_TIME 20 6949
TEST_TIME 21 7006
TEST_TIME 22 7426
TEST_TIME 23 7453
TEST_TIME 24 7700
TEST_TIME 25 7839
TEST_TIME 26 7928
TEST_TIME 27 8048
TEST_TIME 28 8199
TEST_TIME 29 8674
TEST_TIME 30 8912
TEST_TIME 31 9037
TEST_TIME 32 9143
TEST_TIME 33 9340
TEST_TIME 34 9597
TEST_TIME 35 9802
TEST_TIME 36 10021
TEST_TIME 37 10358
TEST_TIME 38 10285
TEST_TIME 39 10442
TEST_TIME 40 10463
TEST_TIME 41 10763
TEST_TIME 42 10888
TEST_TIME 43 10671
TEST_TIME 44 10700
TEST_TIME 45 10495
TEST_TIME 46 10381
TEST_TIME 47 10318
TEST_TIME 48 10159
TEST_TIME 49 10065
TEST_TIME 50 10179
TEST_TIME 51 10129
TEST_TIME 52 10058
TEST_TIME 53 9936
TEST_TIME 54 9862
TEST_TIME 55 9757
TEST_TIME 56 9777
TEST_TIME 57 9724
TEST_TIME 58 9661
TEST_TIME 59 9656
TEST_TIME 60 9554
TEST_TIME 61 9513
TEST_TIME 62 9427
TEST_TIME 63 9449
TEST_TIME 64 9388
TEST_TIME 65 9363
TEST_TIME 66 9300
TEST_TIME 67 9368
TEST_TIME 68 9343
TEST_TIME 69 9367
TEST_TIME 70 9462
TEST_TIME 71 9399
TEST_TIME 72 9581
TEST_TIME 73 9606
TEST_TIME 74 9580
TEST_TIME 75 9583
TEST_TIME 76 9490
TEST_TIME 77 9472
TEST_TIME 78 9408
TEST_TIME 79 9343
TEST_TIME 80 9399
TEST_TIME 81 9364
TEST_TIME 82 9291
TEST_TIME 83 9240
TEST_TIME 84 9162
TEST_TIME 85 9087
TEST_TIME 86 9111
TEST_TIME 87 9149
TEST_TIME 88 9146
TEST_TIME 89 9152
TEST_TIME 90 9136
TEST_TIME 91 9151
TEST_TIME 92 9166
TEST_TIME 93 9165
TEST_TIME 94 9135
TEST_TIME 95 9246
TEST_TIME 96 9270
TEST_TIME 97 9276
TEST_TIME 98 9299
TEST_TIME 99 9333
TEST_TIME 100 9333
TEST_TIME 101 9248
TEST_TIME 102 9211
TEST_TIME 103 9197
TEST_TIME 104 9194
TEST_TIME 105 9151
TEST_TIME 106 9112
TEST_TIME 107 9089
TEST_TIME 108 9053
TEST_TIME 109 9044
TEST_TIME 110 9024
TEST_TIME 111 9005
TEST_TIME 112 8995
TEST_TIME 113 9043
TEST_TIME 114 9033
TEST_TIME 115 9017
TEST_TIME 116 9028
TEST_TIME 117 9021
TEST_TIME 118 9064
TEST_TIME 119 9116
TEST_TIME 120 9047

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-08-2022 13:37:57 to 02-08-2022 13:38:01.

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 1505841 1505841 0 0.00 0 25968 14 0 0
doc_list_biud 1505841 1505841 0 0.00 0 35286 45 0 0
sp_add_invoice_to_stock 5145 4982 163 3.16 28 265260 26513 0 163
sp_cancel_adding_invoice 5560 5560 0 0.00 4 74994 1318 0 0
sp_cancel_client_order 1776 1776 0 0.00 6 20421 491 0 0
sp_cancel_customer_reserve 538971 538971 0 0.00 1 18699 108 0 0
sp_cancel_pay_from_customer 732 732 0 0.00 4 5410 140 0 0
sp_cancel_pay_to_supplier 482 482 0 0.00 3 4503 137 0 0
sp_cancel_supplier_invoice 6146 6135 11 0.17 2 26557 1046 0 11
sp_cancel_supplier_order 429 429 0 0.00 11 1774732 173839 0 0
sp_cancel_write_off 9892 9892 0 0.00 2 21717 176 0 0
sp_client_order 20987 20987 0 0.00 5 40525 508 0 0
sp_customer_reserve 458120 458120 0 0.00 0 55700 253 0 0
sp_fill_shopping_cart 31844 31844 0 0.00 1 34381 92 0 0
sp_fill_shopping_cart_clo_res 458102 458102 0 0.00 0 20744 19 0 0
sp_get_clo_for_invoice 4952 4952 0 0.00 0 20669 266 0 0
sp_kill_cost_storno 16666 16666 0 0.00 0 22569 79 0 0
sp_kill_qstorno_ret_qs2qd 1094398 1094398 0 0.00 0 26548 47 0 0
sp_kill_qty_storno 577862 577862 0 0.00 0 35286 108 0 0
sp_lock_selected_doc 20990 20990 0 0.00 0 9921 7 0 0
sp_make_cost_storno 28364 28364 0 0.00 0 14403 86 0 0
sp_multiply_rows_for_pdistr 28364 28364 0 0.00 0 25942 42 0 0
sp_multiply_rows_for_qdistr 20987 20987 0 0.00 0 15064 194 0 0
sp_pay_from_customer 7362 7362 0 0.00 5 17711 182 0 0
sp_pay_to_supplier 5903 5903 0 0.00 7 15705 220 0 0
sp_payment_common 13265 13265 0 0.00 2 11122 51 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 1 20361 415 0 0
sp_qd_handle_on_invoice_upd_sts 10512 10512 0 0.00 0 35285 599 0 0
sp_qd_handle_on_reserve_upd_sts 10147 10147 0 0.00 0 16797 68 0 0
sp_reserve_write_off 10147 10147 0 0.00 8 25976 307 0 0
sp_supplier_invoice 5454 5454 0 0.00 8 120927 3561 0 0
sp_supplier_order 5413 5413 0 0.00 7 77071 1508 0 0
srv_aggregate_perf_data 111 111 0 0.00 2592 326781 18320 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 771 105178 32426 0 0
srv_find_qd_qs_mism 1034625 1034625 0 0.00 0 15857 10 0 0
srv_make_invnt_saldo 459 459 0 0.00 71 116152 4728 0 0
srv_make_money_saldo 927 927 0 0.00 1 42452 458 0 0
srv_recalc_idx_stat 4 4 0 0.00 96396 391499 242926 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4982 4982 0 0.00 1 13178 1026 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 1 5881 513 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 5750 33 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 5853 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 732 732 0 0.00 0 3934 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 482 482 0 0.00 0 3818 32 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 884 39 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 95183 7960 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 3740 25 0 0
t$perf-norm:sp_client_order 20987 20987 0 0.00 0 11549 41 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 3914 19 0 0
t$perf-norm:sp_pay_from_customer 7362 7362 0 0.00 0 12345 22 0 0
t$perf-norm:sp_pay_to_supplier 5903 5903 0 0.00 0 10460 25 0 0
t$perf-norm:sp_reserve_write_off 10147 10147 0 0.00 0 5903 25 0 0
t$perf-norm:sp_supplier_invoice 5454 5454 0 0.00 0 10642 41 0 0
t$perf-norm:sp_supplier_order 5413 5413 0 0.00 0 5663 36 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 522 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 47 4 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 1418 23 0 0
t$perf-norm:srv_make_money_saldo 927 927 0 0.00 0 1944 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 43 186 85 0 0
v_add_invoice_to_stock 10228 9902 326 3.18 0 4235 28 0 326
v_all_customers 38732 38732 0 0.00 0 97 4 0 0
v_all_suppliers 30312 30312 0 0.00 0 74 5 0 0
v_all_wares 41974 41974 0 0.00 0 75 4 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 937 16 0 0
v_cancel_client_order 3552 3552 0 0.00 0 2908 12 0 0
v_cancel_customer_prepayment 1464 1464 0 0.00 0 442 10 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 1935 12 0 0
v_cancel_payment_to_supplier 964 964 0 0.00 0 4164 16 0 0
v_cancel_supplier_invoice 794 772 22 2.77 0 1179 31 0 22
v_cancel_supplier_order 858 858 0 0.00 0 1329 12 0 0
v_cancel_write_off 1994 1994 0 0.00 0 2747 26 0 0
v_max_id_clo_ord 5413 5413 0 0.00 0 404 5 0 0
v_max_id_clo_res 975 975 0 0.00 0 1704 18 0 0
v_max_id_ord_sup 5444 5444 0 0.00 0 1027 6 0 0
v_max_non_paid_invoice 1604 1604 0 0.00 0 3016 10 0 0
v_max_non_paid_realizn 3798 3798 0 0.00 0 8949 13 0 0
v_min_id_clo_ord 5413 5413 0 0.00 0 4334 11 0 0
v_min_id_clo_res 975 975 0 0.00 0 2522 17 0 0
v_min_id_ord_sup 5444 5444 0 0.00 0 7280 13 0 0
v_min_non_paid_invoice 5903 5903 0 0.00 0 4903 16 0 0
v_min_non_paid_realizn 7362 7362 0 0.00 0 11112 20 0 0
v_our_firm 10370 10370 0 0.00 0 58 5 0 0
v_reserve_write_off 20294 20294 0 0.00 0 4533 11 0 0
x_lock_depdocs_on_canc_invoice 11309 11309 0 0.00 0 10264 132 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 25237 448 0 0
x_make_qstorno_1000_1200 5413 5413 0 0.00 3 69132 1316 0 0
x_make_qstorno_1200_2000 5454 5454 0 0.00 4 120818 3308 0 0
x_make_qstorno_2100_3300 458120 458120 0 0.00 0 55695 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-08-2022 13:38:02 to 02-08-2022 13:38:06.

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:21 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:23 21498 8192000000 9726309072 10029043712 811263856 9.010 100 86 170 0 840044896 8217072 20563968
11:09:49 21045 8192000000 9770785680 10081808384 843637632 9.336 100 81 162 0 874718416 12114992 18965792
11:11:11 8598 8192000000 9804539424 10122956800 873618704 9.636 100 85 168 0 913927872 20832848 19476320
11:12:40 11918 8192000000 9849264752 10180403200 905686000 9.955 100 78 154 1 959719296 36550320 17482976
11:13:58 4755 8192000000 9918595280 10263834624 969180224 10.579 100 87 173 0 1037293056 49817456 18295376
11:15:10 2642 8192000000 9976867968 10332930048 998259904 10.862 100 92 175 0 1096448992 80869328 17319760
11:16:15 1556 8192000000 10001116544 10360881152 1021003152 11.082 100 92 182 1 1119269296 80808896 17457248
11:17:18 1595 8192000000 10003064464 10369175552 1035854192 11.225 100 89 173 1 1120786432 68312384 16619856
11:18:21 1132 8192000000 10032389776 10406559744 1060952640 11.466 100 95 185 1 1155101360 75914272 18234448
11:19:24 838 8192000000 10029143136 10405756928 1064536464 11.500 100 95 183 2 1148882992 65642976 18703552
11:20:27 862 8192000000 10055001376 10440540160 1066648432 11.520 100 95 188 0 1175449536 89949120 18851984
11:21:29 1040 8192000000 10026557728 10413268992 1076180128 11.611 100 81 154 2 1149268960 57522064 15566768
11:22:31 1053 8192000000 10052511440 10440175616 1076833824 11.617 100 88 167 4 1176297984 83039392 16424768
11:23:34 1322 8192000000 10057933552 10444931072 1083964304 11.685 100 90 176 1 1179417504 77341472 18111728
11:24:37 970 8192000000 10052409504 10438561792 1090145328 11.744 100 92 175 1 1173771232 65000848 18625056
11:25:39 1179 8192000000 10059817264 10448433152 1091454384 11.756 100 99 192 4 1181207696 70678432 19074880
11:26:42 1232 8192000000 10061256464 10446671872 1094136848 11.782 100 94 181 0 1185505984 73467968 17901168
11:27:44 1098 8192000000 10074809008 10462588928 1089542992 11.738 100 96 180 1 1193103936 86783312 16777632
11:28:47 1050 8192000000 10038762128 10429894656 1089200784 11.735 100 81 156 1 1159549632 53548448 16800400
11:29:50 983 8192000000 10026574512 10410209280 1058175248 11.439 100 89 173 0 1149744144 73192800 18376096
11:30:53 1098 8192000000 10042927872 10424176640 1077281152 11.622 100 96 176 0 1164748464 68867904 18599408
11:31:56 1112 8192000000 10031872336 10415509504 1073842064 11.589 100 97 189 3 1156148560 63408960 18897536
11:32:58 1066 8192000000 10033776768 10415919104 1057760080 11.435 100 98 189 2 1157407744 79821744 19825920
11:33:59 1103 8192000000 10030459120 10412072960 1049938048 11.360 100 95 183 3 1154844720 86331808 18574864
11:35:02 1302 8192000000 10004367328 10379878400 1040973936 11.274 100 96 177 3 1134837392 74868848 18994608
11:36:04 1256 8192000000 9986559440 10356989952 1026642704 11.136 100 91 175 1 1111318880 66225872 18450304
11:37:06 1274 8192000000 9987931952 10368376832 1053894240 11.398 100 86 153 1 1111270624 40785808 16590576
11:38:13 10696 8192000000 9969527200 10350694400 1034575088 11.212 98 58 102 2 1076061552 31180704 10305760
11:39:28 9702 8192000000 10012508096 10398326784 1047895760 11.340 99 65 127 1 1128858368 68709968 12252640
11:40:51 2746 8192000000 10074633456 10453970944 1083960624 11.685 100 88 164 0 1191557344 90811200 16785520
11:41:58 11311 8192000000 10110214016 10506694656 1101990384 11.857 100 75 148 0 1219416064 102264368 15161312
11:43:18 2908 8192000000 10138229232 10532081664 1117498544 12.003 100 78 152 1 1254335968 120706512 16130912
11:44:28 833 8192000000 10148882672 10544799744 1125984144 12.083 100 94 180 1 1261586720 116774320 18828256
11:45:34 2350 8192000000 10160179936 10556837888 1138167712 12.198 100 90 166 0 1278711280 121082016 19461552
11:46:46 7598 8192000000 10188987328 10591358976 1146500112 12.277 100 66 128 1 1303541072 142140512 14900448
11:47:58 5254 8192000000 10227955376 10631770112 1148774176 12.298 100 82 158 1 1335505024 168469520 18261328
11:49:07 2011 8192000000 10271653120 10679898112 1166540000 12.464 100 86 164 0 1377061152 191930800 18590352
11:50:16 3731 8192000000 10271030448 10681073664 1175743264 12.550 100 66 127 0 1380558832 189595360 15220208
11:51:22 1722 8192000000 10323662960 10732187648 1172144880 12.517 100 95 184 0 1428472352 235302464 21025008
11:52:28 944 8192000000 10353509776 10756853760 1188396800 12.668 100 98 188 0 1464864544 254372608 22095136
11:53:33 1738 8192000000 10401138976 10803695616 1191321600 12.696 100 93 181 1 1508189328 293970464 22897264
11:54:38 1045 8192000000 10456443712 10862108672 1202733808 12.802 100 99 191 2 1564298944 339614896 21950240
11:55:42 1143 8192000000 10481712416 10888605696 1198396368 12.761 100 87 170 0 1592833600 374447968 19989264
11:56:49 1966 8192000000 10516439552 10942484480 1208056736 12.851 100 90 179 1 1621723744 391530704 22136304
11:57:54 766 8192000000 10564746576 10976968704 1186576880 12.651 100 92 178 3 1663834960 456104448 21153632
11:58:59 834 8192000000 10540123776 11001229312 1190969968 12.692 100 94 181 0 1645558416 431711248 22877200
12:00:02 1334 8192000000 10544895792 10982936576 1199592064 12.773 100 94 187 0 1647239776 425445552 22202160
12:01:07 882 8192000000 10533060656 10983174144 1183916896 12.627 100 94 178 1 1634027056 429239296 20870864
12:02:10 1418 8192000000 10574588848 11006865408 1195001040 12.730 100 93 180 0 1684713856 467623184 22089632
12:03:14 1496 8192000000 10592134640 11028504576 1189467680 12.678 100 76 152 0 1695735408 487949072 18318656
12:04:22 1673 8192000000 10565543408 11020013568 1186720720 12.653 100 80 150 0 1668798848 463714048 18364080
12:05:28 1197 8192000000 10543451840 11040219136 1170940000 12.506 100 77 149 1 1646649184 456994752 18714432
12:06:31 15785 8192000000 10503676944 10962984960 1163365360 12.435 99 47 93 0 1604429488 428314112 12750016
12:07:49 26567 8192000000 10445424544 10906542080 1151654528 12.325 98 33 66 0 1543775760 383511552 8609680
12:09:23 13436 8192000000 10447066352 10920878080 1169142496 12.489 100 37 74 0 1549675904 371369872 9163536
12:10:47 41118 8192000000 10400149920 10925314048 1166107680 12.460 100 24 48 0 1553974656 381799056 6067920
12:12:29 58590 8192000000 10238211184 10768175104 1136635680 12.184 97 29 56 1 1407486704 264256464 6594560
12:14:31 6102 8192000000 10223656672 10664460288 1156043776 12.366 100 40 77 1 1323838736 159070384 8724576
12:15:41 36703 8192000000 10232127328 10740441088 1163369024 12.435 100 25 50 0 1384118976 214940224 5809728
12:17:41 64716 8192000000 10199811280 10700939264 1146328880 12.275 99 23 44 0 1304496624 152815328 5352416
12:19:47 105177 8192000000 10109528320 10570035200 1140326912 12.219 99 46 87 0 1245881904 95083280 10471712
12:22:33 57172 8192000000 10090210352 10537201664 1124731760 12.072 98 41 80 0 1162105776 29370528 8003488
12:24:33 26007 8192000000 10101595888 10572062720 1136598400 12.184 99 23 46 1 1194716192 53553136 4564656
12:26:07 58472 8192000000 10136743408 10614493184 1133706336 12.156 99 43 84 0 1220217872 77426080 9085456
12:28:12 37343 8192000000 10057872288 10518138880 1107366944 11.907 98 18 36 0 1151107664 40455040 3285680
12:30:00 30858 8192000000 10064210288 10515636224 1122159360 12.047 99 27 53 0 1151289424 23271184 5858880
12:31:37 40055 8192000000 10120068576 10584330240 1133342448 12.153 99 17 34 1 1215754832 78830992 3581392
12:33:19 54653 8192000000 10099869664 10611671040 1123200688 12.057 99 17 32 0 1209476032 82007760 4267584
12:35:14 99410 8192000000 10034850272 10517209088 1093609664 11.777 97 10 20 0 1128019680 32258432 2151584
12:38:07 33392 8192000000 10074401264 10546335744 1131458640 12.135 100 38 70 1 1197492064 59030400 7003024
12:39:41 50592 8192000000 10088883904 10566950912 1127737216 12.100 99 6 12 0 1188340240 59527232 1075792
12:41:37 36399 8192000000 10098004704 10553446400 1100500656 11.842 96 28 55 0 1156573152 50029104 6043392
12:43:37 49137 8192000000 10152561360 10627358720 1118409456 12.012 98 28 58 0 1244872336 119993536 6469344
12:45:30 77288 8192000000 10148293616 10641190912 1131689472 12.137 100 20 40 1 1274914480 138760896 4464112
12:47:48 26930 8192000000 10126445024 10555228160 1135362896 12.172 100 52 99 0 1215972096 70347008 10262192
12:49:15 42576 8192000000 10103214608 10641088512 1123413328 12.059 100 19 38 0 1224424288 96689808 4321152
12:51:01 38363 8192000000 10105198784 10549551104 1128529152 12.107 100 32 62 0 1187613248 52147936 6936160
12:52:41 99545 8192000000 10098821104 10554630144 1090677808 11.749 96 50 99 0 1168204032 67738912 9787312
12:55:36 42060 8192000000 10075627200 10499764224 1085436688 11.699 97 46 90 0 1153595520 59269536 8889296
12:57:19 35714 8192000000 10038734816 10494369792 1104565792 11.881 99 22 42 0 1151836480 43041488 4229200
12:58:56 33349 8192000000 10048872608 10494062592 1116987968 11.999 99 29 58 0 1131952288 9126928 5837392
13:00:34 83272 8192000000 10088863360 10547695616 1117410912 12.003 99 20 41 0 1171571120 49872208 4288000
13:03:01 44839 8192000000 10102159744 10582794240 1119583968 12.023 99 21 42 0 1201843536 77719280 4540288
13:04:46 94853 8192000000 10082079696 10541203456 1098713616 11.825 97 23 45 1 1134598192 31007568 4877008
13:07:31 17288 8192000000 10121089584 10563440640 1135478128 12.173 100 40 78 0 1217631552 74526496 7626928
13:08:52 48084 8192000000 10091332896 10536452096 1130301216 12.124 99 26 53 0 1211219328 75562448 5355664
13:10:40 43576 8192000000 10148113120 10588708864 1134949760 12.168 99 30 60 0 1222543440 81138896 6454784
13:12:39 51744 8192000000 10087635008 10526347264 1058108992 11.438 93 16 32 0 1117634960 56131040 3394928
13:14:34 49112 8192000000 10102651728 10537398272 1107042640 11.904 98 38 76 0 1185023728 70720704 7260384
13:16:24 39879 8192000000 10088696240 10558046208 1127616720 12.099 100 30 60 0 1223337584 89085504 6635360
13:18:08 85944 8192000000 10087854096 10536665088 1132076016 12.141 100 30 60 0 1163139472 25233088 5830368
13:20:34 53746 8192000000 10067414528 10575994880 1141805056 12.233 100 18 36 0 1201661104 56471584 3384464
13:22:29 53363 8192000000 10068465072 10532507648 1136167952 12.179 100 17 33 0 1181372480 41511760 3692768
13:24:26 69424 8192000000 10082806544 10567610368 1144017984 12.253 100 11 22 0 1195745568 49462528 2265056
13:26:38 39125 8192000000 10062362640 10532888576 1100953488 11.847 97 19 34 1 1147680032 42827840 3898704
13:28:18 25919 8192000000 10099724176 10550628352 1129077744 12.113 100 30 60 1 1190728432 55255712 6394976
13:29:58 50953 8192000000 10088722464 10575515648 1120471984 12.031 99 26 51 0 1182795808 56678608 5645216
13:31:49 19562 8192000000 10101069088 10547675136 1139530976 12.211 100 34 64 0 1199522368 53038896 6952496
13:33:14 25791 8192000000 10109620224 10547286016 1113982256 11.970 98 38 74 0 1192701504 71013312 7705936
13:34:41 49316 8192000000 10094356160 10545303552 1079757072 11.645 96 37 74 0 1177405040 89834528 7813440
13:36:31 38653 8192000000 10058900992 10493505536 1102592608 11.862 98 39 76 1 1137866528 27660992 7612928

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 02-08-2022 13:38:07 to 02-08-2022 13:38:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

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

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          221035
MON$OLDEST_ACTIVE               222788
MON$OLDEST_SNAPSHOT             222788
MON$NEXT_TRANSACTION            222788
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                       2135680
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2812 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2812 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2812 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2812 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 ---
:36: 
:37: mscs-test-161.budzdorov.ru    Tue Aug  2 13:37:18 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Tue Aug  2 13:37:18 2022
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Tue Aug  2 13:37:19 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Tue Aug  2 13:37:23 2022
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Tue Aug  2 13:37:25 2022
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:55: 
:56: 
:57: mscs-test-161.budzdorov.ru    Tue Aug  2 13:37:26 2022
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:59: 
--- end of diff output ---

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

02.08.22 13:38:28. 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-08-02 13:38:30 No severe PSQL-related problems occured
02.08.22 13:38:31. End of report.