Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.05.2022 07: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 16113560 kB
MemAvailable 31395948 kB
Buffers 0 kB
Cached 15551444 kB
SwapCached 2632 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-18 07:37:18
DTS_END                         2022-05-18 07:37:18.3820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42016
TRN_ID                          210568
ATT_ID                          617
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: 9089.61 23353 1090753
customer order: creation 165.53 498 19863
customer order: refuse 13.70 475 1644
order to supplier: creation 43.73 1500 5247
order to supplier: removal 3.38 191070 405
invoice (draft): creation 44.01 3716 5281
invoice (draft): removal 52.34 1136 6281
invoice accept: apply 40.31 28746 4837
invoice accept: cancel 47.28 1446 5674
customer reserve: creation 3777.42 269 453290
customer reserve: removal 4614.01 109 553681
realization accept: apply 79.19 333 9503
realization accept: cancel 84.68 172 10161
payment from customer: creation 56.76 198 6811
payment from customer: removal 5.44 157 653
payment to supplier: creation 46.18 225 5541
payment to supplier: removal 3.30 137 396
service: total inventory turnovers 3.73 4929 447
service: total monetary turnovers 7.68 385 921
service: aggregate perf. data 0.93 16337 112
service: refresh index statistics 0.04 215213 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-05-2022 07:38:29 to 18-05-2022 07:38:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8268
TEST_TIME 2 7030
TEST_TIME 3 5898
TEST_TIME 4 6576
TEST_TIME 5 6385
TEST_TIME 6 6125
TEST_TIME 7 6106
TEST_TIME 8 5935
TEST_TIME 9 6250
TEST_TIME 10 6681
TEST_TIME 11 6706
TEST_TIME 12 6688
TEST_TIME 13 6881
TEST_TIME 14 6918
TEST_TIME 15 7031
TEST_TIME 16 7078
TEST_TIME 17 7433
TEST_TIME 18 7553
TEST_TIME 19 7749
TEST_TIME 20 7769
TEST_TIME 21 7949
TEST_TIME 22 8075
TEST_TIME 23 8313
TEST_TIME 24 8312
TEST_TIME 25 8658
TEST_TIME 26 8647
TEST_TIME 27 8884
TEST_TIME 28 8922
TEST_TIME 29 9145
TEST_TIME 30 9035
TEST_TIME 31 9141
TEST_TIME 32 9600
TEST_TIME 33 9593
TEST_TIME 34 9707
TEST_TIME 35 9669
TEST_TIME 36 10290
TEST_TIME 37 10302
TEST_TIME 38 10752
TEST_TIME 39 10609
TEST_TIME 40 10446
TEST_TIME 41 10410
TEST_TIME 42 10457
TEST_TIME 43 10463
TEST_TIME 44 10304
TEST_TIME 45 10231
TEST_TIME 46 10046
TEST_TIME 47 10029
TEST_TIME 48 9958
TEST_TIME 49 9837
TEST_TIME 50 9728
TEST_TIME 51 9732
TEST_TIME 52 9679
TEST_TIME 53 9713
TEST_TIME 54 9677
TEST_TIME 55 9616
TEST_TIME 56 9501
TEST_TIME 57 9476
TEST_TIME 58 9464
TEST_TIME 59 9428
TEST_TIME 60 9600
TEST_TIME 61 9668
TEST_TIME 62 9738
TEST_TIME 63 9733
TEST_TIME 64 9792
TEST_TIME 65 9758
TEST_TIME 66 9795
TEST_TIME 67 9697
TEST_TIME 68 9677
TEST_TIME 69 9732
TEST_TIME 70 9775
TEST_TIME 71 9807
TEST_TIME 72 9775
TEST_TIME 73 9758
TEST_TIME 74 9700
TEST_TIME 75 9661
TEST_TIME 76 9623
TEST_TIME 77 9586
TEST_TIME 78 9507
TEST_TIME 79 9474
TEST_TIME 80 9419
TEST_TIME 81 9395
TEST_TIME 82 9409
TEST_TIME 83 9421
TEST_TIME 84 9364
TEST_TIME 85 9391
TEST_TIME 86 9354
TEST_TIME 87 9392
TEST_TIME 88 9392
TEST_TIME 89 9390
TEST_TIME 90 9396
TEST_TIME 91 9444
TEST_TIME 92 9389
TEST_TIME 93 9410
TEST_TIME 94 9508
TEST_TIME 95 9481
TEST_TIME 96 9535
TEST_TIME 97 9535
TEST_TIME 98 9502
TEST_TIME 99 9506
TEST_TIME 100 9469
TEST_TIME 101 9422
TEST_TIME 102 9426
TEST_TIME 103 9421
TEST_TIME 104 9372
TEST_TIME 105 9376
TEST_TIME 106 9325
TEST_TIME 107 9274
TEST_TIME 108 9260
TEST_TIME 109 9194
TEST_TIME 110 9178
TEST_TIME 111 9193
TEST_TIME 112 9170
TEST_TIME 113 9192
TEST_TIME 114 9180
TEST_TIME 115 9157
TEST_TIME 116 9136
TEST_TIME 117 9143
TEST_TIME 118 9150
TEST_TIME 119 9172
TEST_TIME 120 9116

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-05-2022 07:38:32 to 18-05-2022 07:38:36.

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 1506771 1506771 0 0.00 0 33432 15 0 0
doc_list_biud 1506760 1506760 0 0.00 0 32146 47 0 0
sp_add_invoice_to_stock 4957 4837 120 2.42 44 175987 28747 0 120
sp_cancel_adding_invoice 5674 5674 0 0.00 6 95084 1446 0 0
sp_cancel_client_order 1644 1644 0 0.00 6 11117 476 0 0
sp_cancel_customer_reserve 553681 553681 0 0.00 1 32160 110 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 4 5117 157 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 4 3391 137 0 0
sp_cancel_supplier_invoice 6289 6281 8 0.12 2 26447 1136 0 8
sp_cancel_supplier_order 405 405 0 0.00 10 1467165 191070 0 0
sp_cancel_write_off 10161 10161 0 0.00 2 17900 172 0 0
sp_client_order 19863 19863 0 0.00 5 22217 499 0 0
sp_customer_reserve 453290 453290 0 0.00 1 96941 269 0 0
sp_fill_shopping_cart 30383 30383 0 0.00 1 9724 90 0 0
sp_fill_shopping_cart_clo_res 453251 453251 0 0.00 0 17313 19 0 0
sp_get_clo_for_invoice 4792 4792 0 0.00 0 13840 260 0 0
sp_kill_cost_storno 16884 16884 0 0.00 0 24905 87 0 0
sp_kill_qstorno_ret_qs2qd 1124209 1124209 0 0.00 0 32137 48 0 0
sp_kill_qty_storno 592140 592140 0 0.00 0 32145 110 0 0
sp_lock_selected_doc 19783 19783 0 0.00 0 8009 7 0 0
sp_make_cost_storno 26646 26646 0 0.00 0 32360 108 0 0
sp_multiply_rows_for_pdistr 26646 26646 0 0.00 0 22037 45 0 0
sp_multiply_rows_for_qdistr 19863 19863 0 0.00 0 14688 182 0 0
sp_pay_from_customer 6811 6811 0 0.00 6 35182 199 0 0
sp_pay_to_supplier 5541 5541 0 0.00 7 33441 225 0 0
sp_payment_common 12352 12352 0 0.00 2 9891 51 0 0
sp_qd_handle_on_cancel_clo 1644 1644 0 0.00 1 11111 393 0 0
sp_qd_handle_on_invoice_upd_sts 10465 10465 0 0.00 0 14330 594 0 0
sp_qd_handle_on_reserve_upd_sts 9503 9503 0 0.00 0 14201 68 0 0
sp_reserve_write_off 9503 9503 0 0.00 9 29970 333 0 0
sp_supplier_invoice 5281 5281 0 0.00 9 103021 3717 0 0
sp_supplier_order 5247 5247 0 0.00 8 35877 1501 0 0
srv_aggregate_perf_data 112 112 0 0.00 2674 144777 16338 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 676 143583 34138 0 0
srv_find_qd_qs_mism 1042766 1042766 0 0.00 0 25239 11 0 0
srv_make_invnt_saldo 447 447 0 0.00 68 65831 4930 0 0
srv_make_money_saldo 921 921 0 0.00 1 12188 386 0 0
srv_recalc_idx_stat 5 5 0 0.00 62997 410834 215214 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 4840 4840 0 0.00 1 34797 1159 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 2 7036 699 0 0
t$perf-norm:sp_cancel_client_order 1644 1644 0 0.00 0 2858 32 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 3764 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 1786 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 852 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 843 35 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 86788 8958 0 0
t$perf-norm:sp_cancel_write_off 945 945 0 0.00 0 981 21 0 0
t$perf-norm:sp_client_order 19863 19863 0 0.00 0 8057 44 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 1491 16 0 0
t$perf-norm:sp_pay_from_customer 6811 6811 0 0.00 0 4986 22 0 0
t$perf-norm:sp_pay_to_supplier 5541 5541 0 0.00 0 5692 24 0 0
t$perf-norm:sp_reserve_write_off 9503 9503 0 0.00 0 4283 27 0 0
t$perf-norm:sp_supplier_invoice 5282 5282 0 0.00 0 7481 39 0 0
t$perf-norm:sp_supplier_order 5247 5247 0 0.00 0 5418 40 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 690 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 170 7 0 0
t$perf-norm:srv_make_invnt_saldo 447 447 0 0.00 0 985 17 0 0
t$perf-norm:srv_make_money_saldo 921 921 0 0.00 0 990 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 459 160 0 0
v_add_invoice_to_stock 9822 9582 240 2.44 0 3788 29 0 240
v_all_customers 36174 36174 0 0.00 0 176 4 0 0
v_all_suppliers 29560 29560 0 0.00 0 73 5 0 0
v_all_wares 39726 39726 0 0.00 0 68 4 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 1033 18 0 0
v_cancel_client_order 3288 3288 0 0.00 0 1309 11 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 3792 19 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 2254 16 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 3321 18 0 0
v_cancel_supplier_invoice 772 756 16 2.07 0 3242 30 0 16
v_cancel_supplier_order 810 810 0 0.00 0 3034 20 0 0
v_cancel_write_off 1890 1890 0 0.00 0 7543 32 0 0
v_max_id_clo_ord 5247 5247 0 0.00 0 495 6 0 0
v_max_id_clo_res 985 985 0 0.00 0 3189 17 0 0
v_max_id_ord_sup 5273 5273 0 0.00 0 4609 8 0 0
v_max_non_paid_invoice 1280 1280 0 0.00 0 1609 11 0 0
v_max_non_paid_realizn 3538 3538 0 0.00 0 3799 10 0 0
v_min_id_clo_ord 5247 5247 0 0.00 0 2488 10 0 0
v_min_id_clo_res 985 985 0 0.00 0 1013 8 0 0
v_min_id_ord_sup 5273 5273 0 0.00 0 3078 7 0 0
v_min_non_paid_invoice 5541 5541 0 0.00 0 6395 16 0 0
v_min_non_paid_realizn 6811 6811 0 0.00 0 6008 19 0 0
v_our_firm 10098 10098 0 0.00 0 63 5 0 0
v_reserve_write_off 19004 19004 0 0.00 0 3936 15 0 0
x_lock_depdocs_on_canc_invoice 11577 11577 0 0.00 0 12687 138 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 25900 448 0 0
x_make_qstorno_1000_1200 5247 5247 0 0.00 2 35854 1304 0 0
x_make_qstorno_1200_2000 5281 5281 0 0.00 3 102869 3480 0 0
x_make_qstorno_2100_3300 453290 453290 0 0.00 0 96939 236 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-05-2022 07:38:37 to 18-05-2022 07:38:41.

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
05:07:22 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:22 35312 8192000000 9754001760 10058731520 815546704 9.054 100 87 174 1 844486656 7910800 21029152
05:10:05 17878 8192000000 9774230752 10087067648 848576240 9.386 100 86 172 0 882917840 14331488 20010112
05:11:31 13195 8192000000 9819580576 10141945856 879904224 9.699 100 84 168 1 926680384 27149488 19626672
05:12:47 13495 8192000000 9858333552 10187694080 905915200 9.957 100 85 170 0 964425216 39676432 18833584
05:14:02 4005 8192000000 9910896784 10253881344 949795248 10.389 100 91 177 0 1032787696 64625664 18366784
05:15:12 2504 8192000000 9944055568 10296201216 977900544 10.664 100 87 172 1 1067836800 71398192 18538064
05:16:18 2346 8192000000 9991930544 10353238016 1010055184 10.976 100 88 172 0 1105857344 78443792 17358368
05:17:22 1272 8192000000 10003108976 10370985984 1034789024 11.215 100 89 172 1 1120580464 68664576 17126864
05:18:25 800 8192000000 10030616256 10407972864 1055994512 11.418 100 98 189 1 1153189200 78559280 18635408
05:19:27 1121 8192000000 10037426336 10415681536 1066928240 11.523 100 96 185 1 1159975456 73816288 19230928
05:20:30 1049 8192000000 10039266416 10413985792 1070250128 11.554 100 94 182 0 1158420080 68667696 19502256
05:21:34 1642 8192000000 10025468192 10407956480 1075299472 11.603 100 94 174 0 1148656528 54946416 18410640
05:22:39 1013 8192000000 10074044224 10462842880 1084546880 11.691 100 90 173 3 1191566912 89337296 17682736
05:23:42 1321 8192000000 10060517744 10452168704 1091884208 11.761 100 90 177 1 1190052336 79128448 19039680
05:24:54 1416 8192000000 10065951920 10450907136 1091115984 11.753 100 92 183 2 1187242160 76940176 19186000
05:25:57 1195 8192000000 10058536704 10448609280 1097238176 11.811 100 92 164 2 1181511552 66095760 18177616
05:27:01 844 8192000000 10076866528 10466119680 1096101056 11.801 100 94 183 1 1195756960 82072672 17583232
05:28:03 1085 8192000000 10047023744 10437283840 1090314672 11.746 100 86 169 2 1169983792 62676560 16992560
05:29:06 1268 8192000000 10060656512 10450898944 1095370064 11.794 100 84 157 2 1185964608 74393904 16200640
05:30:08 752 8192000000 10032864352 10419630080 1076489904 11.614 100 87 173 2 1151888912 58175792 17223216
05:31:11 1231 8192000000 10047494384 10429599744 1084100208 11.687 100 95 179 1 1168985408 65695424 19189776
05:32:14 1099 8192000000 10037215408 10420359168 1081190320 11.659 100 91 171 1 1161735680 62484128 18061232
05:33:16 3588 8192000000 9967200224 10345381888 1026830448 11.138 98 64 117 2 1081574032 42280240 12463344
05:34:21 3913 8192000000 10000838384 10380771328 1038693456 11.252 99 74 137 0 1113314688 62429664 12191568
05:35:25 1279 8192000000 9958117936 10324963328 1002298432 10.901 97 91 170 1 1083302416 62903920 18100064
05:36:27 1164 8192000000 9977268800 10348072960 1021824976 11.090 100 93 174 0 1096705696 58268096 16612624
05:37:32 1631 8192000000 10004455008 10379083776 1048958496 11.351 100 91 172 1 1124081968 56525376 18598096
05:38:58 6735 8192000000 10032099904 10414415872 1075199232 11.602 100 75 141 0 1152985984 63759584 14027168
05:40:10 3853 8192000000 10026878416 10412843008 1079462240 11.642 100 80 137 1 1149146496 55170080 14514176
05:41:20 1183 8192000000 10087215136 10466787328 1088278464 11.726 100 85 164 3 1208864352 102377744 18208144
05:42:25 4096 8192000000 10098094608 10488156160 1103207872 11.868 100 63 123 0 1219161824 103034416 12919536
05:43:37 1753 8192000000 10115645280 10505945088 1111373408 11.945 100 70 133 0 1237388720 112756528 13258784
05:44:43 7001 8192000000 10159838736 10557517824 1124646272 12.071 100 79 149 0 1277851120 137920960 15283888
05:45:56 1277 8192000000 10202365088 10593509376 1131921360 12.139 100 93 177 0 1313740640 162913216 18906064
05:47:00 2818 8192000000 10206924704 10624651264 1137856592 12.195 100 83 166 0 1322568896 166544768 18167536
05:48:08 3124 8192000000 10211533424 10612875264 1151001248 12.319 100 72 138 3 1326724320 160116208 15606864
05:49:15 1294 8192000000 10257511936 10662871040 1153696592 12.344 100 95 184 0 1372906544 199373968 19835984
05:50:20 1958 8192000000 10289388560 10683101184 1158577680 12.390 100 91 168 1 1396480080 218209744 19692656
05:51:27 2868 8192000000 10322636592 10730442752 1163684272 12.438 100 95 178 2 1435079248 250631712 20763264
05:52:34 1090 8192000000 10344749856 10749992960 1171144784 12.508 100 99 192 1 1452688400 259318912 22224704
05:53:37 672 8192000000 10354157552 10797240320 1176715440 12.560 100 96 185 0 1463723520 263920064 23088016
05:54:42 952 8192000000 10381693008 10804883456 1183262816 12.621 100 83 163 0 1496305824 294282944 18760064
05:55:47 1227 8192000000 10401842640 10820456448 1189143696 12.675 100 96 191 0 1508411184 296589136 22678352
05:56:51 1675 8192000000 10406504256 10829037568 1186029840 12.646 100 97 180 2 1511180112 304079584 21070688
05:57:56 2619 8192000000 10421538512 10852192256 1184521472 12.632 100 92 182 0 1532182336 326544848 21116016
05:59:02 1114 8192000000 10445301440 10870042624 1184996080 12.637 100 100 193 1 1550246928 343311008 21939840
06:00:06 1072 8192000000 10433890720 10873356288 1182469216 12.613 100 97 193 1 1536460112 330839792 23151104
06:01:11 943 8192000000 10425642272 10854281216 1196586912 12.745 100 99 192 0 1535839936 316585360 22667664
06:02:17 997 8192000000 10444701984 10892189696 1184154080 12.629 100 97 186 0 1547624960 341943488 21527392
06:03:22 912 8192000000 10422004208 10844168192 1169778864 12.495 100 95 188 0 1526598912 334573488 22246560
06:04:26 3371 8192000000 10419306032 10854150144 1159789040 12.401 100 54 108 0 1531300496 357602368 13909088
06:05:34 1827 8192000000 10430982912 10893623296 1177546336 12.567 100 84 164 0 1527606080 330208592 19851152
06:06:40 9601 8192000000 10413295632 10850893824 1170629968 12.503 100 54 103 0 1534937312 350433904 13873440
06:07:54 2029 8192000000 10466547984 10919239680 1174014272 12.534 100 63 127 0 1574477792 383863680 16599840
06:08:59 3135 8192000000 10447769552 10923040768 1185797776 12.644 100 63 126 1 1547621088 346619760 15203552
06:10:12 11856 8192000000 10391323168 10854092800 1164864960 12.449 99 41 82 0 1509731616 333465104 11401552
06:12:23 23760 8192000000 10371238384 10844397568 1135812160 12.176 99 31 63 3 1463728464 320183904 7732400
06:14:31 33499 8192000000 10163858816 10649702400 1141165584 12.226 100 30 62 0 1302294528 154194608 6934336
06:16:24 30393 8192000000 10070768784 10492665856 1131948976 12.140 100 39 77 2 1180453024 38542400 9961648
06:17:55 65878 8192000000 10129438352 10571403264 1127817040 12.101 99 27 54 0 1232382048 98318400 6246608
06:20:04 72912 8192000000 10045710320 10488451072 1108958032 11.923 98 26 50 1 1148853136 33346800 6548304
06:22:21 88931 8192000000 10061108176 10494337024 1103036464 11.866 98 40 80 0 1138095568 26042256 9016848
06:24:53 12985 8192000000 10077073312 10507436032 1117554912 12.004 99 48 85 0 1160662352 34747552 8359888
06:26:08 82091 8192000000 10101826944 10548916224 1134900656 12.168 100 36 73 1 1207759472 65328304 7530512
06:28:32 7330 8192000000 10171425856 10598572032 1130289216 12.124 100 53 105 3 1276990592 135436528 11264848
06:29:44 21306 8192000000 10177742784 10596651008 1129305360 12.115 99 58 116 2 1254158880 111452896 13400624
06:31:11 11111 8192000000 10179068240 10635640832 1149294416 12.303 100 36 72 1 1288928000 130677776 8955808
06:32:23 81073 8192000000 10229225904 10669989888 1147013888 12.281 100 23 47 0 1298894928 145756944 6124096
06:35:05 69867 8192000000 10230939840 10682208256 1134349712 12.162 99 20 40 0 1320810336 181034320 5426304
06:37:25 98752 8192000000 10140939120 10663845888 1107054064 11.905 98 12 23 0 1245470992 135802208 2614720
06:40:05 75160 8192000000 10117963760 10549927936 1109511280 11.928 98 28 56 1 1164341616 49810000 5020336
06:42:26 75772 8192000000 10099511664 10566287360 1127274096 12.096 99 23 44 1 1198712288 66552320 4885872
06:44:42 21055 8192000000 10171816384 10607685632 1153458944 12.342 100 50 98 0 1230577536 67217040 9901552
06:46:09 40967 8192000000 10133176624 10623889408 1138656720 12.203 99 32 64 0 1247817296 102235696 6924880
06:47:53 45875 8192000000 10103837056 10573135872 1126876464 12.092 98 26 52 0 1165206928 33427776 4902688
06:49:41 68787 8192000000 10118250848 10565382144 1156554400 12.371 100 21 40 4 1193846112 33149072 4142640
06:51:58 90334 8192000000 10088127824 10561155072 1147945104 12.290 99 48 82 1 1187215888 31035616 8235168
06:54:52 67952 8192000000 10114108400 10566017024 1156906448 12.374 99 35 64 0 1205407968 42350112 6151408
06:57:00 43978 8192000000 10172576432 10613841920 1147886272 12.290 98 38 76 0 1229799312 73470816 8442224
06:58:56 41172 8192000000 10122279168 10604044288 1123270272 12.058 97 27 54 1 1239501952 110531808 5699872
07:00:41 57718 8192000000 10125946960 10624921600 1124749328 12.072 98 8 16 0 1216581184 90014576 1817280
07:03:18 93314 8192000000 10111595712 10584977408 1117231392 12.001 97 11 22 0 1216886432 97141936 2513104
07:05:54 49960 8192000000 10088626864 10517970944 1092157376 11.763 95 39 69 3 1127499872 26448576 8893920
07:07:53 40929 8192000000 10100076496 10556178432 1114123360 11.971 98 28 55 1 1182398832 62822896 5452576
07:10:15 72926 8192000000 10135658272 10614677504 1142293088 12.237 100 15 30 0 1244045680 98794752 2957840
07:12:37 82021 8192000000 10109911888 10618306560 1101814528 11.855 96 22 44 0 1160019008 53701872 4502608
07:15:54 12228 8192000000 10133021312 10561810432 1135179360 12.170 100 54 108 0 1215294864 69504960 10610544
07:17:07 143583 8192000000 10110959792 10627919872 1140423824 12.220 100 28 56 1 1271522544 124855568 6243152
07:20:31 23363 8192000000 10085737984 10539573248 1135609568 12.174 99 29 57 4 1175000704 34180224 5210912
07:21:58 84747 8192000000 10069732496 10529198080 1080847456 11.656 95 11 22 0 1139868352 56856544 2164352
07:24:23 80458 8192000000 10098401632 10564149248 1086076768 11.705 95 20 41 0 1178074496 87122640 4875088
07:26:43 70341 8192000000 10107353392 10574585856 1097438640 11.813 97 25 50 0 1215323568 111952288 5932640
07:29:42 101022 8192000000 10063845216 10564165632 1098950896 11.828 97 8 16 1 1167062992 66432944 1679152
07:32:25 52997 8192000000 10098289056 10530897920 1124121568 12.066 99 38 74 3 1176235280 44200576 7913136
07:34:18 40556 8192000000 10115153552 10578599936 1109615792 11.929 98 29 58 0 1200329344 84446784 6266768
07:36:25 27518 8192000000 10123696352 10571370496 1123695568 12.062 99 36 72 0 1222260928 91283056 7282304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 18-05-2022 07:38:43 to 18-05-2022 07:38:59.

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 18-05-2022 07:39:00 to 18-05-2022 07:39:02.

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          207042
MON$OLDEST_ACTIVE               212599
MON$OLDEST_SNAPSHOT             212599
MON$NEXT_TRANSACTION            212599
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                       2075488
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
:278: 
:279: mscs-test-161.budzdorov.ru    Wed May 18 07:37:18 2022
:280:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:281: 
:282: 
:283: mscs-test-161.budzdorov.ru    Wed May 18 07:37:19 2022
:284:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:285: 
:286: 
:287: mscs-test-161.budzdorov.ru    Wed May 18 07:37:29 2022
:288:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:289: 
:290: 
:291: mscs-test-161.budzdorov.ru    Wed May 18 07:37:31 2022
:292:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:293: 
:294: 
:295: mscs-test-161.budzdorov.ru    Wed May 18 07:37:31 2022
:296:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:297: 
:298: 
:299: mscs-test-161.budzdorov.ru    Wed May 18 07:37:31 2022
:300:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:301: 
:302: 
:303: mscs-test-161.budzdorov.ru    Wed May 18 07:37:31 2022
:304:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:305: 
--- end of diff output ---

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

18.05.22 07:39:03. 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-18 07:39:05 No severe PSQL-related problems occured
18.05.22 07:39:07. End of report.