Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.06.2022 01:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16338996 kB
MemAvailable 31189916 kB
Buffers 0 kB
Cached 15364652 kB
SwapCached 2504 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 3.0.10
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-06-27 01:38:19
DTS_END                         2022-06-27 01:38:19.5410
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(57:17)
IP                              ::1/44456
TRN_ID                          238049
ATT_ID                          644
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: 8967.88 25499 1076145
customer order: creation 168.98 370 20278
customer order: refuse 14.95 408 1794
order to supplier: creation 44.08 1389 5290
order to supplier: removal 3.10 103781 372
invoice (draft): creation 43.98 3989 5278
invoice (draft): removal 50.10 754 6012
invoice accept: apply 42.51 17961 5101
invoice accept: cancel 44.95 718 5394
customer reserve: creation 3840.95 181 460914
customer reserve: removal 4424.58 57 530949
realization accept: apply 81.83 192 9819
realization accept: cancel 81.33 117 9759
payment from customer: creation 56.78 113 6813
payment from customer: removal 5.91 75 709
payment to supplier: creation 48.37 146 5804
payment to supplier: removal 3.20 79 384
service: total inventory turnovers 5.03 934 603
service: total monetary turnovers 6.50 27 780
service: aggregate perf. data 0.73 26969 88
service: refresh index statistics 0.03 351715 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-06-2022 01:39:34 to 27-06-2022 01:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6544
TEST_TIME 2 5955
TEST_TIME 3 5457
TEST_TIME 4 5854
TEST_TIME 5 5572
TEST_TIME 6 5535
TEST_TIME 7 5681
TEST_TIME 8 5600
TEST_TIME 9 5597
TEST_TIME 10 5481
TEST_TIME 11 5606
TEST_TIME 12 5688
TEST_TIME 13 6216
TEST_TIME 14 6341
TEST_TIME 15 6421
TEST_TIME 16 7251
TEST_TIME 17 7148
TEST_TIME 18 7548
TEST_TIME 19 7897
TEST_TIME 20 7910
TEST_TIME 21 8087
TEST_TIME 22 8193
TEST_TIME 23 8328
TEST_TIME 24 8961
TEST_TIME 25 9168
TEST_TIME 26 9453
TEST_TIME 27 9804
TEST_TIME 28 10039
TEST_TIME 29 9889
TEST_TIME 30 9582
TEST_TIME 31 9734
TEST_TIME 32 9550
TEST_TIME 33 9765
TEST_TIME 34 9556
TEST_TIME 35 9421
TEST_TIME 36 9450
TEST_TIME 37 9300
TEST_TIME 38 9135
TEST_TIME 39 9025
TEST_TIME 40 9070
TEST_TIME 41 8958
TEST_TIME 42 8998
TEST_TIME 43 8900
TEST_TIME 44 8824
TEST_TIME 45 8783
TEST_TIME 46 8850
TEST_TIME 47 8731
TEST_TIME 48 8781
TEST_TIME 49 8975
TEST_TIME 50 9051
TEST_TIME 51 9056
TEST_TIME 52 9105
TEST_TIME 53 9154
TEST_TIME 54 9176
TEST_TIME 55 9285
TEST_TIME 56 9232
TEST_TIME 57 9168
TEST_TIME 58 9276
TEST_TIME 59 9374
TEST_TIME 60 9415
TEST_TIME 61 9315
TEST_TIME 62 9240
TEST_TIME 63 9141
TEST_TIME 64 9135
TEST_TIME 65 9105
TEST_TIME 66 9064
TEST_TIME 67 9020
TEST_TIME 68 8968
TEST_TIME 69 8937
TEST_TIME 70 8940
TEST_TIME 71 8928
TEST_TIME 72 8925
TEST_TIME 73 8976
TEST_TIME 74 8921
TEST_TIME 75 8925
TEST_TIME 76 8951
TEST_TIME 77 8944
TEST_TIME 78 8893
TEST_TIME 79 8963
TEST_TIME 80 9088
TEST_TIME 81 9030
TEST_TIME 82 9101
TEST_TIME 83 9135
TEST_TIME 84 9040
TEST_TIME 85 8964
TEST_TIME 86 8948
TEST_TIME 87 8952
TEST_TIME 88 8918
TEST_TIME 89 8898
TEST_TIME 90 8866
TEST_TIME 91 8884
TEST_TIME 92 8871
TEST_TIME 93 8858
TEST_TIME 94 8816
TEST_TIME 95 8940
TEST_TIME 96 9020
TEST_TIME 97 8991
TEST_TIME 98 9005
TEST_TIME 99 9042
TEST_TIME 100 9102
TEST_TIME 101 9078
TEST_TIME 102 9044
TEST_TIME 103 9075
TEST_TIME 104 9146
TEST_TIME 105 9141
TEST_TIME 106 9072
TEST_TIME 107 9015
TEST_TIME 108 9013
TEST_TIME 109 8987
TEST_TIME 110 8924
TEST_TIME 111 8903
TEST_TIME 112 8933
TEST_TIME 113 8906
TEST_TIME 114 8929
TEST_TIME 115 8943
TEST_TIME 116 8928
TEST_TIME 117 8969
TEST_TIME 118 8972
TEST_TIME 119 9017
TEST_TIME 120 8970

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-06-2022 01:39:38 to 27-06-2022 01:39:41.

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 1501254 1501254 0 0.00 0 22162 6 0 0
doc_list_biud 1501254 1501254 0 0.00 0 30987 26 0 0
sp_add_invoice_to_stock 5222 5101 121 2.31 40 192380 17962 0 121
sp_cancel_adding_invoice 5394 5394 0 0.00 4 50963 718 0 0
sp_cancel_client_order 1794 1794 0 0.00 4 16640 409 0 0
sp_cancel_customer_reserve 530949 530949 0 0.00 1 24115 57 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 3 3693 76 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 2 3275 79 0 0
sp_cancel_supplier_invoice 6031 6012 19 0.31 2 20506 755 0 19
sp_cancel_supplier_order 372 372 0 0.00 12 819865 103781 0 0
sp_cancel_write_off 9759 9759 0 0.00 1 31003 117 0 0
sp_client_order 20278 20278 0 0.00 4 69854 371 0 0
sp_customer_reserve 460914 460914 0 0.00 0 47777 182 0 0
sp_fill_shopping_cart 30836 30836 0 0.00 1 21085 44 0 0
sp_fill_shopping_cart_clo_res 460910 460910 0 0.00 0 21377 16 0 0
sp_get_clo_for_invoice 5097 5097 0 0.00 0 18811 356 0 0
sp_kill_cost_storno 16246 16246 0 0.00 0 6576 56 0 0
sp_kill_qstorno_ret_qs2qd 1078041 1078041 0 0.00 0 30985 31 0 0
sp_kill_qty_storno 569196 569196 0 0.00 0 30986 68 0 0
sp_lock_selected_doc 20665 20665 0 0.00 0 5624 3 0 0
sp_make_cost_storno 27533 27533 0 0.00 0 22157 57 0 0
sp_multiply_rows_for_pdistr 27533 27533 0 0.00 0 7026 28 0 0
sp_multiply_rows_for_qdistr 20278 20278 0 0.00 0 69846 160 0 0
sp_pay_from_customer 6813 6813 0 0.00 4 9224 113 0 0
sp_pay_to_supplier 5804 5804 0 0.00 5 10779 146 0 0
sp_payment_common 12617 12617 0 0.00 1 8339 24 0 0
sp_qd_handle_on_cancel_clo 1794 1794 0 0.00 0 16634 376 0 0
sp_qd_handle_on_invoice_upd_sts 10491 10491 0 0.00 0 29359 329 0 0
sp_qd_handle_on_reserve_upd_sts 9819 9819 0 0.00 0 24344 78 0 0
sp_reserve_write_off 9819 9819 0 0.00 6 24382 193 0 0
sp_supplier_invoice 5278 5278 0 0.00 6 87064 3989 0 0
sp_supplier_order 5290 5290 0 0.00 5 30746 1390 0 0
srv_aggregate_perf_data 88 88 0 0.00 2940 605285 26970 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1240 187417 47749 0 0
srv_find_qd_qs_mism 1028376 1028376 0 0.00 0 17314 6 0 0
srv_make_invnt_saldo 603 603 0 0.00 40 33098 934 0 0
srv_make_money_saldo 780 780 0 0.00 1 2887 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 170206 489393 351715 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5101 5101 0 0.00 0 9066 154 0 0
t$perf-norm:sp_cancel_adding_invoice 358 358 0 0.00 1 5584 118 0 0
t$perf-norm:sp_cancel_client_order 1794 1794 0 0.00 0 2033 13 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 5719 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 4303 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 3749 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 1303 25 0 0
t$perf-norm:sp_cancel_supplier_order 372 372 0 0.00 0 24195 1607 0 0
t$perf-norm:sp_cancel_write_off 1034 1034 0 0.00 0 2140 11 0 0
t$perf-norm:sp_client_order 20278 20278 0 0.00 0 10098 18 0 0
t$perf-norm:sp_customer_reserve 908 908 0 0.00 0 6107 9 0 0
t$perf-norm:sp_pay_from_customer 6813 6813 0 0.00 0 8471 11 0 0
t$perf-norm:sp_pay_to_supplier 5804 5804 0 0.00 0 17405 9 0 0
t$perf-norm:sp_reserve_write_off 9819 9819 0 0.00 0 9498 12 0 0
t$perf-norm:sp_supplier_invoice 5278 5278 0 0.00 0 5258 16 0 0
t$perf-norm:sp_supplier_order 5290 5290 0 0.00 0 4783 19 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 152 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 28 1 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 1102 25 0 0
t$perf-norm:srv_make_money_saldo 780 780 0 0.00 0 3411 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 40 13 0 0
v_add_invoice_to_stock 10434 10192 242 2.31 0 6210 20 0 242
v_all_customers 36348 36348 0 0.00 0 13 1 0 0
v_all_suppliers 28984 28984 0 0.00 0 97 1 0 0
v_all_wares 40554 40554 0 0.00 0 257 1 0 0
v_cancel_adding_invoice 716 716 0 0.00 0 9679 23 0 0
v_cancel_client_order 3588 3588 0 0.00 0 6216 8 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 3603 6 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 5186 13 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 3261 11 0 0
v_cancel_supplier_invoice 864 826 38 4.39 0 6358 32 0 38
v_cancel_supplier_order 744 744 0 0.00 0 2274 9 0 0
v_cancel_write_off 2068 2068 0 0.00 0 2045 11 0 0
v_max_id_clo_ord 5288 5288 0 0.00 0 2061 2 0 0
v_max_id_clo_res 908 908 0 0.00 0 2009 14 0 0
v_max_id_ord_sup 5271 5271 0 0.00 0 6877 6 0 0
v_max_non_paid_invoice 1871 1871 0 0.00 0 5206 5 0 0
v_max_non_paid_realizn 3888 3888 0 0.00 0 1224 4 0 0
v_min_id_clo_ord 5288 5288 0 0.00 0 5472 6 0 0
v_min_id_clo_res 908 908 0 0.00 0 2048 12 0 0
v_min_id_ord_sup 5271 5271 0 0.00 0 7502 7 0 0
v_min_non_paid_invoice 5804 5804 0 0.00 0 6879 17 0 0
v_min_non_paid_realizn 6813 6813 0 0.00 0 8334 18 0 0
v_our_firm 10056 10056 0 0.00 0 25 1 0 0
v_reserve_write_off 19638 19638 0 0.00 0 14161 7 0 0
x_lock_depdocs_on_canc_invoice 10993 10993 0 0.00 0 11512 106 0 0
x_lock_depdocs_on_canc_sup_ord 372 372 0 0.00 0 10064 378 0 0
x_make_qstorno_1000_1200 5290 5290 0 0.00 2 30726 1306 0 0
x_make_qstorno_1200_2000 5278 5278 0 0.00 3 87053 3820 0 0
x_make_qstorno_2100_3300 460914 460914 0 0.00 0 47776 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-06-2022 01:39:42 to 27-06-2022 01:39:46.

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
23:08:23 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:24 27165 8192000000 9891959120 10203406336 975699696 10.642 100 86 172 0 1004240512 5808560 22732256
23:10:56 11278 8192000000 9942381904 10264752128 1026797776 11.138 100 93 186 0 1059392976 8088896 24506304
23:12:13 20392 8192000000 9989443568 10320453632 1068276512 11.536 100 86 171 1 1098656192 9607488 20772192
23:13:39 14333 8192000000 10022213136 10367913984 1111593344 11.948 100 81 163 0 1141410240 10516288 19300608
23:14:57 9937 8192000000 10083963152 10439852032 1159824608 12.402 100 77 154 1 1189859024 12364608 17669808
23:16:09 6150 8192000000 10122387360 10488385536 1211052000 12.879 100 76 152 3 1240900512 12890832 16957680
23:17:17 3313 8192000000 10158244944 10529271808 1241711392 13.162 100 91 180 0 1273788336 12703280 19373664
23:18:23 2148 8192000000 10187584848 10567200768 1270389312 13.425 100 94 188 1 1304797040 13352912 21054816
23:19:26 8925 8192000000 10201201664 10589880320 1289980864 13.604 100 74 147 1 1316209904 9582240 16646800
23:20:38 6767 8192000000 10174168560 10585976832 1293769312 13.639 100 28 56 1 1307292192 6957488 6565392
23:21:51 6801 8192000000 10191045760 10601414656 1303381376 13.726 100 34 68 0 1318689840 7330288 7978176
23:23:05 9798 8192000000 10194389792 10610020352 1312907504 13.812 100 22 44 1 1323550528 5225088 5417936
23:24:15 67235 8192000000 10207327856 10632024064 1320951872 13.885 100 37 75 2 1335717936 5688896 9077168
23:26:26 4615 8192000000 10212656304 10627928064 1317729072 13.856 100 25 50 0 1329829744 6730272 5370400
23:27:33 8561 8192000000 10216747152 10636611584 1315346976 13.835 99 21 42 1 1325269424 5756384 4166064
23:28:44 14621 8192000000 10197527984 10600681472 1259029856 13.321 96 32 61 3 1272555248 5057104 8468288
23:30:00 7413 8192000000 10164621120 10576789504 1273010368 13.449 98 29 58 0 1285713600 6254128 6449104
23:31:08 4150 8192000000 10147342656 10542710784 1212248368 12.890 96 34 68 1 1225296976 4951104 8097504
23:32:12 5589 8192000000 10139117440 10537603072 1225956304 13.017 98 24 48 1 1237259296 5764000 5538992
23:33:19 20912 8192000000 10163068560 10560004096 1249229696 13.231 98 30 60 0 1266245920 9557232 7458992
23:34:41 26005 8192000000 10183653088 10592722944 1289678240 13.601 100 30 60 1 1302816000 6778048 6359712
23:36:08 7451 8192000000 10193622208 10606821376 1300387104 13.699 100 26 52 0 1312531712 6038928 6105680
23:37:16 9707 8192000000 10197446528 10615291904 1308116176 13.769 100 18 36 1 1317663888 5642576 3905136
23:38:34 9996 8192000000 10247529216 10653081600 1338869664 14.047 100 52 103 1 1356924528 6591280 11463584
23:39:53 30340 8192000000 10243065072 10662887424 1337010208 14.030 99 37 74 0 1351901728 6589168 8302352
23:41:28 43055 8192000000 10275685808 10698182656 1357925504 14.219 100 41 83 1 1380610288 13216480 9468304
23:43:14 12075 8192000000 10286538352 10711961600 1378619168 14.404 100 43 86 1 1402567520 13248848 10699504
23:44:30 18923 8192000000 10310502512 10735583232 1392713264 14.530 100 48 97 1 1421489136 17004928 11770944
23:46:01 6042 8192000000 10322461488 10742128640 1399895808 14.594 100 62 122 3 1431611568 17110512 14605248
23:47:20 36855 8192000000 10320558048 10756190208 1406942880 14.657 100 25 50 1 1429837360 16865296 6029184
23:49:06 5875 8192000000 10332169776 10750984192 1396239776 14.562 99 61 122 0 1431955760 21272992 14442992
23:50:19 4376 8192000000 10341408000 10757042176 1404364400 14.634 100 79 158 1 1448635856 25412432 18859024
23:51:27 5403 8192000000 10328820432 10746294272 1394796176 14.549 100 69 138 1 1436551776 24569632 17185968
23:52:37 4361 8192000000 10340471648 10758938624 1397106416 14.569 100 71 142 1 1442815024 28488368 17220240
23:53:47 3010 8192000000 10333569408 10746601472 1385391648 14.465 100 69 137 1 1439729984 36698736 17639600
23:54:58 5042 8192000000 10343494832 10757115904 1389349872 14.500 100 82 164 3 1436521664 27597536 19574256
23:56:09 4458 8192000000 10344590832 10755231744 1385596064 14.467 100 86 172 0 1448564400 41230352 21737984
23:57:19 1240 8192000000 10314049024 10715791360 1356007328 14.201 99 88 174 1 1412851744 35131760 21712656
23:58:26 9104 8192000000 10307864720 10726203392 1371416704 14.340 100 55 110 2 1417733744 32753584 13563456
23:59:42 4885 8192000000 10325551056 10735456256 1371648256 14.342 100 76 148 0 1427072544 36940560 18483728
00:00:50 12716 8192000000 10327920128 10744913920 1381280496 14.428 100 76 148 0 1433746576 33740592 18725488
00:02:07 5481 8192000000 10297381744 10729955328 1353953568 14.183 100 30 60 1 1408896544 46378224 8564752
00:03:27 25056 8192000000 10280156960 10718687232 1348703120 14.136 100 25 47 2 1387808048 32853296 6251632
00:04:53 44614 8192000000 10251336880 10695155712 1304021120 13.732 97 8 16 1 1338695184 32397776 2276288
00:07:02 63574 8192000000 10277647408 10714423296 1360677232 14.243 99 32 64 1 1381324000 13330464 7316304
00:09:17 22699 8192000000 10283751504 10727157760 1372051504 14.345 100 31 60 1 1395997296 16586016 7359776
00:10:41 29980 8192000000 10266195008 10720063488 1358360816 14.223 99 17 34 0 1376984272 14802752 3820704
00:12:11 62639 8192000000 10268249744 10715615232 1351154416 14.158 98 41 81 2 1367587888 7126432 9307040
00:14:31 74969 8192000000 10284076624 10735484928 1381043904 14.426 100 7 14 0 1390237680 7853840 1339936
00:16:48 47029 8192000000 10294017856 10749800448 1393965920 14.541 100 12 24 0 1407613088 10703232 2943936
00:19:59 101118 8192000000 10297060000 10756657152 1382115392 14.435 99 12 24 0 1400879760 15680016 3084352
00:22:54 58744 8192000000 10326049472 10782695424 1407421728 14.661 100 43 86 0 1434280800 15656768 11202304
00:24:56 36961 8192000000 10333131024 10787917824 1415924208 14.737 100 14 28 0 1447800048 28533824 3342016
00:26:39 56186 8192000000 10329779904 10809675776 1412402288 14.705 100 14 28 1 1442218320 26416784 3399248
00:28:36 97818 8192000000 10314875216 10778546176 1378662304 14.405 98 7 14 0 1402289600 22132288 1495008
00:31:37 81419 8192000000 10316663776 10779500544 1381912048 14.434 98 16 32 0 1401918240 16032832 3973360
00:33:59 60635 8192000000 10318262832 10762608640 1375890768 14.380 98 22 42 2 1390517136 9881984 4744384
00:36:00 40687 8192000000 10316240496 10770952192 1373628144 14.360 98 38 74 2 1397737312 16155712 7953456
00:37:49 176804 8192000000 10265017328 10723414016 1291223424 13.615 93 10 20 1 1310059264 16549200 2286640
00:42:20 39550 8192000000 10268279760 10721312768 1370241488 14.329 100 25 50 0 1385869552 10511120 5116944
00:44:01 69287 8192000000 10243614208 10697805824 1339914080 14.057 98 11 22 0 1349724896 8007520 1803296
00:46:11 68491 8192000000 10251839024 10713100288 1337104000 14.031 98 13 25 2 1348640864 8560384 2976480
00:48:43 52364 8192000000 10273449664 10717487104 1332321088 13.988 97 22 44 2 1345199184 8162112 4715984
00:50:36 64485 8192000000 10260449568 10713976832 1346129712 14.113 99 25 50 1 1366186640 13867168 6189760
00:52:52 23060 8192000000 10249359280 10682953728 1319446528 13.872 99 45 90 2 1343872368 14869328 9556512
00:54:30 85506 8192000000 10235987440 10676490240 1214017536 12.906 90 17 34 1 1227702528 10053136 3631856
00:57:07 74327 8192000000 10252877056 10710745088 1324201936 13.915 98 24 48 0 1345653520 15969360 5482224
01:00:01 109668 8192000000 10249851696 10702536704 1304784112 13.739 96 17 34 0 1322685600 14349168 3552320
01:02:57 62461 8192000000 10277528352 10712981504 1360781808 14.244 100 36 72 1 1376965712 7459440 8724464
01:05:00 66170 8192000000 10263934400 10726678528 1357734624 14.217 99 10 20 1 1373943600 13550576 2658400
01:07:07 30672 8192000000 10253847776 10720169984 1350162544 14.149 99 7 14 0 1359949904 8232528 1554832
01:08:43 77008 8192000000 10270378320 10729897984 1353904816 14.183 98 20 40 0 1368118640 9761040 4452784
01:11:13 55403 8192000000 10286443520 10741583872 1374720512 14.369 99 18 36 0 1391974208 13169792 4083904
01:13:24 81503 8192000000 10298766240 10774716416 1396445840 14.563 100 14 28 2 1419496896 19648256 3402800
01:16:26 71411 8192000000 10286105696 10746830848 1365468528 14.286 98 17 33 1 1385000320 15750368 3781424
01:19:55 23553 8192000000 10300149184 10738204672 1373982304 14.363 99 28 56 2 1391824896 11878016 5964576
01:21:20 32222 8192000000 10312358880 10745327616 1367743280 14.307 98 43 86 2 1387330864 10749808 8837776
01:23:00 187417 8192000000 10292106320 10742317056 1351642192 14.162 97 21 42 1 1365679856 9499088 4538576
01:27:07 75663 8192000000 10288746688 10752417792 1376152000 14.382 99 6 12 1 1388753216 11244016 1357200
01:29:23 61224 8192000000 10265591984 10726060032 1316790672 13.848 95 7 14 0 1334689088 16033824 1864592
01:31:26 56794 8192000000 10269208160 10724483072 1339078592 14.049 98 15 30 0 1363262464 20498192 3685680
01:33:48 63962 8192000000 10275404224 10731319296 1361402352 14.250 99 24 48 0 1378893968 11880240 5611376
01:36:03 28509 8192000000 10277207184 10732142592 1358915152 14.228 98 19 39 0 1371292480 7743392 4633936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-06-2022 01:39:48 to 27-06-2022 01:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-06-2022 01:40:04 to 27-06-2022 01:40:06.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237073
MON$OLDEST_ACTIVE               238229
MON$OLDEST_SNAPSHOT             238229
MON$NEXT_TRANSACTION            238229
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-03 16:41:55.3470
MON$PAGES                       2019328
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:485: 
:486: mscs-test-161.budzdorov.ru    Mon Jun 27 01:38:22 2022
:487:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:488: 
:489: 
:490: mscs-test-161.budzdorov.ru    Mon Jun 27 01:38:22 2022
:491:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:492: 
:493: 
:494: mscs-test-161.budzdorov.ru    Mon Jun 27 01:38:23 2022
:495:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:496: 
:497: 
:498: mscs-test-161.budzdorov.ru    Mon Jun 27 01:38:26 2022
:499:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:500: 
--- end of diff output ---

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

27.06.22 01:40:07. There are 3 logs in /var/tmp/logs-oltp30 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-06-27 01:40:09 No severe PSQL-related problems occured
27.06.22 01:40:10. End of report.