Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.09.2022 22: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 15254036 kB
MemAvailable 30606228 kB
Buffers 0 kB
Cached 16481180 kB
SwapCached 2060 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.11
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-09-29 22:38:29
DTS_END                         2022-09-29 22:38:29.4770
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(57:17)
IP                              ::1/39458
TRN_ID                          248285
ATT_ID                          653
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: 9185.87 26472 1102304
customer order: creation 175.75 330 21090
customer order: refuse 15.68 398 1881
order to supplier: creation 47.66 1194 5719
order to supplier: removal 3.43 112280 412
invoice (draft): creation 47.36 3512 5683
invoice (draft): removal 52.88 936 6345
invoice accept: apply 42.85 16507 5142
invoice accept: cancel 47.65 845 5718
customer reserve: creation 3889.50 166 466740
customer reserve: removal 4551.12 65 546134
realization accept: apply 87.19 174 10463
realization accept: cancel 91.66 107 10999
payment from customer: creation 59.90 113 7188
payment from customer: removal 5.98 94 718
payment to supplier: creation 51.11 130 6133
payment to supplier: removal 3.68 100 441
service: total inventory turnovers 4.62 1394 554
service: total monetary turnovers 6.98 36 838
service: aggregate perf. data 0.85 21157 102
service: refresh index statistics 0.03 369893 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-09-2022 22:40:11 to 29-09-2022 22:40:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3308
TEST_TIME 2 4548
TEST_TIME 3 4987
TEST_TIME 4 4490
TEST_TIME 5 4847
TEST_TIME 6 5248
TEST_TIME 7 5491
TEST_TIME 8 5354
TEST_TIME 9 5597
TEST_TIME 10 5662
TEST_TIME 11 6252
TEST_TIME 12 6232
TEST_TIME 13 6871
TEST_TIME 14 7248
TEST_TIME 15 8092
TEST_TIME 16 8123
TEST_TIME 17 7936
TEST_TIME 18 8012
TEST_TIME 19 8144
TEST_TIME 20 8077
TEST_TIME 21 8355
TEST_TIME 22 8521
TEST_TIME 23 8414
TEST_TIME 24 8513
TEST_TIME 25 8434
TEST_TIME 26 8679
TEST_TIME 27 8946
TEST_TIME 28 9152
TEST_TIME 29 9894
TEST_TIME 30 11206
TEST_TIME 31 11519
TEST_TIME 32 11214
TEST_TIME 33 11106
TEST_TIME 34 10990
TEST_TIME 35 10866
TEST_TIME 36 10718
TEST_TIME 37 10719
TEST_TIME 38 10584
TEST_TIME 39 10454
TEST_TIME 40 10279
TEST_TIME 41 10196
TEST_TIME 42 10203
TEST_TIME 43 10122
TEST_TIME 44 9989
TEST_TIME 45 10136
TEST_TIME 46 10167
TEST_TIME 47 10089
TEST_TIME 48 9996
TEST_TIME 49 10000
TEST_TIME 50 10147
TEST_TIME 51 10029
TEST_TIME 52 9917
TEST_TIME 53 9834
TEST_TIME 54 9831
TEST_TIME 55 9693
TEST_TIME 56 9569
TEST_TIME 57 9543
TEST_TIME 58 9558
TEST_TIME 59 9510
TEST_TIME 60 9625
TEST_TIME 61 9662
TEST_TIME 62 9583
TEST_TIME 63 9507
TEST_TIME 64 9399
TEST_TIME 65 9472
TEST_TIME 66 9454
TEST_TIME 67 9468
TEST_TIME 68 9466
TEST_TIME 69 9470
TEST_TIME 70 9437
TEST_TIME 71 9461
TEST_TIME 72 9422
TEST_TIME 73 9440
TEST_TIME 74 9526
TEST_TIME 75 9457
TEST_TIME 76 9469
TEST_TIME 77 9478
TEST_TIME 78 9608
TEST_TIME 79 9696
TEST_TIME 80 9687
TEST_TIME 81 9578
TEST_TIME 82 9560
TEST_TIME 83 9580
TEST_TIME 84 9506
TEST_TIME 85 9480
TEST_TIME 86 9430
TEST_TIME 87 9380
TEST_TIME 88 9337
TEST_TIME 89 9281
TEST_TIME 90 9300
TEST_TIME 91 9347
TEST_TIME 92 9326
TEST_TIME 93 9371
TEST_TIME 94 9363
TEST_TIME 95 9353
TEST_TIME 96 9370
TEST_TIME 97 9406
TEST_TIME 98 9457
TEST_TIME 99 9429
TEST_TIME 100 9417
TEST_TIME 101 9424
TEST_TIME 102 9478
TEST_TIME 103 9486
TEST_TIME 104 9489
TEST_TIME 105 9516
TEST_TIME 106 9509
TEST_TIME 107 9450
TEST_TIME 108 9395
TEST_TIME 109 9400
TEST_TIME 110 9367
TEST_TIME 111 9395
TEST_TIME 112 9347
TEST_TIME 113 9333
TEST_TIME 114 9302
TEST_TIME 115 9272
TEST_TIME 116 9208
TEST_TIME 117 9225
TEST_TIME 118 9279
TEST_TIME 119 9250
TEST_TIME 120 9191

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-09-2022 22:40:14 to 29-09-2022 22:40:18.

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 1534616 1534616 0 0.00 0 13025 6 0 0
doc_list_biud 1534616 1534616 0 0.00 0 87980 30 0 0
sp_add_invoice_to_stock 5256 5142 114 2.16 24 181814 16507 0 114
sp_cancel_adding_invoice 5718 5718 0 0.00 3 50292 846 0 0
sp_cancel_client_order 1881 1881 0 0.00 4 15918 399 0 0
sp_cancel_customer_reserve 546134 546134 0 0.00 0 58477 65 0 0
sp_cancel_pay_from_customer 718 718 0 0.00 3 7287 95 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 2 5313 101 0 0
sp_cancel_supplier_invoice 6355 6345 10 0.15 1 87989 936 0 10
sp_cancel_supplier_order 412 412 0 0.00 10 1174984 112280 0 0
sp_cancel_write_off 10999 10999 0 0.00 1 8857 108 0 0
sp_client_order 21090 21090 0 0.00 3 32563 330 0 0
sp_customer_reserve 466740 466740 0 0.00 0 122460 167 0 0
sp_fill_shopping_cart 32482 32482 0 0.00 1 22345 44 0 0
sp_fill_shopping_cart_clo_res 466731 466731 0 0.00 0 12990 13 0 0
sp_get_clo_for_invoice 5133 5133 0 0.00 0 35591 298 0 0
sp_kill_cost_storno 17876 17876 0 0.00 0 8542 56 0 0
sp_kill_qstorno_ret_qs2qd 1110024 1110024 0 0.00 0 87977 36 0 0
sp_kill_qty_storno 587085 587085 0 0.00 0 87979 77 0 0
sp_lock_selected_doc 21667 21667 0 0.00 0 7278 5 0 0
sp_make_cost_storno 28917 28917 0 0.00 0 8850 53 0 0
sp_multiply_rows_for_pdistr 28917 28917 0 0.00 0 7753 24 0 0
sp_multiply_rows_for_qdistr 21090 21090 0 0.00 0 32551 135 0 0
sp_pay_from_customer 7188 7188 0 0.00 4 17798 113 0 0
sp_pay_to_supplier 6133 6133 0 0.00 5 12334 131 0 0
sp_payment_common 13321 13321 0 0.00 2 17794 29 0 0
sp_qd_handle_on_cancel_clo 1881 1881 0 0.00 0 15905 372 0 0
sp_qd_handle_on_invoice_upd_sts 10851 10851 0 0.00 0 40636 344 0 0
sp_qd_handle_on_reserve_upd_sts 10463 10463 0 0.00 0 17649 56 0 0
sp_reserve_write_off 10463 10463 0 0.00 6 17670 175 0 0
sp_supplier_invoice 5683 5683 0 0.00 6 98591 3512 0 0
sp_supplier_order 5719 5719 0 0.00 5 45095 1195 0 0
srv_aggregate_perf_data 102 102 0 0.00 2230 333579 21157 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1079 243142 47824 0 0
srv_find_qd_qs_mism 1053620 1053620 0 0.00 0 13017 5 0 0
srv_make_invnt_saldo 554 554 0 0.00 44 160121 1395 0 0
srv_make_money_saldo 838 838 0 0.00 1 5932 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 252482 502043 369894 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5143 5143 0 0.00 0 9296 171 0 0
t$perf-norm:sp_cancel_adding_invoice 468 468 0 0.00 0 3962 103 0 0
t$perf-norm:sp_cancel_client_order 1881 1881 0 0.00 0 1678 11 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 3617 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 718 718 0 0.00 0 13307 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 3056 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 2749 23 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 26768 1976 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 2733 10 0 0
t$perf-norm:sp_client_order 21090 21090 0 0.00 0 9745 16 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 9096 14 0 0
t$perf-norm:sp_pay_from_customer 7188 7188 0 0.00 0 3489 10 0 0
t$perf-norm:sp_pay_to_supplier 6133 6133 0 0.00 0 7476 12 0 0
t$perf-norm:sp_reserve_write_off 10463 10463 0 0.00 0 16496 14 0 0
t$perf-norm:sp_supplier_invoice 5684 5684 0 0.00 0 8123 18 0 0
t$perf-norm:sp_supplier_order 5719 5719 0 0.00 0 9745 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 164 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 8 0 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 964 22 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 1057 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10494 10266 228 2.17 0 10936 18 0 228
v_all_customers 37898 37898 0 0.00 0 29 1 0 0
v_all_suppliers 31386 31386 0 0.00 0 86 1 0 0
v_all_wares 42180 42180 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 936 936 0 0.00 0 3900 11 0 0
v_cancel_client_order 3762 3762 0 0.00 0 3046 5 0 0
v_cancel_customer_prepayment 1436 1436 0 0.00 0 3129 14 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 1386 6 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 5291 17 0 0
v_cancel_supplier_invoice 870 850 20 2.29 0 2705 16 0 20
v_cancel_supplier_order 824 824 0 0.00 0 4348 13 0 0
v_cancel_write_off 2072 2072 0 0.00 0 4908 22 0 0
v_max_id_clo_ord 5719 5719 0 0.00 0 3606 3 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 1735 13 0 0
v_max_id_ord_sup 5673 5673 0 0.00 0 5495 4 0 0
v_max_non_paid_invoice 1832 1832 0 0.00 0 515 3 0 0
v_max_non_paid_realizn 3957 3957 0 0.00 0 5306 6 0 0
v_min_id_clo_ord 5719 5719 0 0.00 0 11951 8 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 2104 7 0 0
v_min_id_ord_sup 5673 5673 0 0.00 0 8417 12 0 0
v_min_non_paid_invoice 6133 6133 0 0.00 0 5117 17 0 0
v_min_non_paid_realizn 7188 7188 0 0.00 0 17790 24 0 0
v_our_firm 10744 10744 0 0.00 0 22 1 0 0
v_reserve_write_off 20926 20926 0 0.00 0 7180 8 0 0
x_lock_depdocs_on_canc_invoice 11638 11638 0 0.00 0 20898 145 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 16600 455 0 0
x_make_qstorno_1000_1200 5719 5719 0 0.00 1 45081 1109 0 0
x_make_qstorno_1200_2000 5683 5683 0 0.00 3 96024 3352 0 0
x_make_qstorno_2100_3300 466740 466740 0 0.00 0 122459 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-09-2022 22:40:19 to 29-09-2022 22:40:23.

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
20:08:33 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:34 21846 8192000000 9904478720 10212913152 986635520 10.749 100 89 178 0 1015742448 5861984 23244944
20:11:01 20073 8192000000 9943938304 10265116672 1029970688 11.168 100 92 184 0 1062550816 8168128 24412000
20:12:30 13652 8192000000 9966926256 10297823232 1051164048 11.372 100 80 160 0 1081238208 9415456 20658704
20:13:50 10528 8192000000 10013159712 10355417088 1100528528 11.843 100 84 164 0 1130705104 10097136 20079440
20:15:08 8433 8192000000 10070414320 10424934400 1155448352 12.361 100 89 176 0 1188268736 12602496 20217888
20:16:19 4704 8192000000 10110548112 10475278336 1199622272 12.773 100 75 145 0 1228210704 12453376 16135056
20:17:29 1775 8192000000 10163112176 10538422272 1247786560 13.218 100 80 158 1 1276954544 12293856 16874128
20:18:33 2059 8192000000 10200207744 10578436096 1280724288 13.520 100 85 172 1 1313379216 13779600 18875328
20:19:36 820 8192000000 10219903888 10604830720 1305495632 13.745 100 90 181 1 1338925824 13141584 20288608
20:20:39 26121 8192000000 10192038144 10606641152 1308906448 13.776 100 27 54 1 1322121232 7060272 6154512
20:22:07 9640 8192000000 10206371888 10624720896 1320593104 13.882 100 13 26 0 1328646496 5278608 2774784
20:23:17 4826 8192000000 10208130336 10627575808 1326870400 13.939 100 25 48 2 1337290960 5524160 4896400
20:24:27 5495 8192000000 10215473056 10634903552 1329559152 13.963 100 27 54 0 1346157376 10472880 6125344
20:25:33 50093 8192000000 10246025360 10648915968 1334050256 14.004 100 55 112 0 1354037936 5527104 14460576
20:27:23 3996 8192000000 10244805744 10662043648 1341277072 14.069 100 39 79 2 1356902160 6724224 8900864
20:28:28 4578 8192000000 10194967952 10610438144 1297090016 13.669 99 22 45 0 1307586160 5316080 5180064
20:29:33 32639 8192000000 10164859728 10575581184 1241620096 13.161 95 29 58 0 1253636400 5767936 6248368
20:31:07 6045 8192000000 10133241952 10532540416 1209280464 12.862 96 33 66 1 1228310160 11590880 7438816
20:32:13 7974 8192000000 10141267312 10537607168 1233442400 13.086 98 12 24 0 1241096272 4835280 2818592
20:33:22 18530 8192000000 10141187376 10538582016 1220687856 12.968 96 29 58 0 1233774000 5313568 7772576
20:34:42 13850 8192000000 10172073696 10573676544 1275411920 13.471 100 30 61 0 1287982288 5299632 7270736
20:36:02 7305 8192000000 10189538272 10600873984 1296973200 13.668 100 25 46 0 1307631120 5138480 5519440
20:37:11 7151 8192000000 10203538848 10617749504 1308861408 13.776 100 17 34 0 1318443312 6090144 3491760
20:38:21 17966 8192000000 10222354688 10637705216 1327605152 13.946 100 25 48 0 1338064576 5433024 5026400
20:39:50 49446 8192000000 10245347984 10668404736 1346641152 14.117 100 41 82 0 1367849136 11244032 9963952
20:41:43 36865 8192000000 10269079008 10689499136 1364334192 14.276 100 27 54 0 1379276256 8992256 5949808
20:43:33 7266 8192000000 10300497616 10720866304 1385567936 14.466 100 58 115 1 1413567024 14823984 13175104
20:44:45 6638 8192000000 10309559504 10732761088 1398248480 14.579 100 46 89 1 1421737808 13432176 10057152
20:45:54 4842 8192000000 10299764896 10724040704 1386505728 14.475 100 53 104 0 1413849424 14961168 12382528
20:47:04 8407 8192000000 10328124128 10751995904 1407494464 14.662 100 45 90 0 1435653824 17443632 10715728
20:48:17 10140 8192000000 10334210096 10755551232 1410319088 14.687 100 63 123 0 1446850880 21970944 14560848
20:49:30 1079 8192000000 10323639984 10735153152 1387804896 14.486 99 79 156 2 1430324240 24467328 18052016
20:50:36 14752 8192000000 10311184304 10733400064 1379284352 14.410 99 47 90 1 1424863312 34003632 11575328
20:51:59 5825 8192000000 10295505696 10708713472 1335967088 14.021 98 62 124 1 1385308784 33793456 15548240
20:53:08 3467 8192000000 10306592848 10711494656 1356422272 14.205 100 74 145 0 1410979744 36774096 17783376
20:54:17 10672 8192000000 10308931104 10728771584 1376141136 14.382 100 53 105 2 1419730080 30685888 12903056
20:55:33 5851 8192000000 10306144880 10719744000 1365888832 14.290 100 62 122 1 1413537024 31400128 16248064
20:56:45 3348 8192000000 10334892528 10751803392 1380382272 14.420 100 57 114 0 1443815008 48519872 14912864
20:57:56 1920 8192000000 10357468944 10770649088 1394415040 14.545 100 69 136 3 1461926432 50095296 17416096
20:59:06 5310 8192000000 10360546256 10785902592 1399615840 14.592 100 53 102 0 1465331152 51604160 14111152
21:00:18 2418 8192000000 10356117664 10769879040 1387496416 14.484 100 60 116 1 1456310912 54099200 14715296
21:01:28 4317 8192000000 10366644736 10776158208 1396962608 14.568 100 67 132 1 1464787232 50522304 17302320
21:02:36 7838 8192000000 10360489728 10785263616 1405194416 14.641 100 37 74 0 1468437440 53392576 9850448
21:03:48 7090 8192000000 10376853936 10800046080 1404304240 14.633 100 48 91 0 1479313936 61532384 13477312
21:05:02 16325 8192000000 10362691824 10802425856 1400614256 14.600 100 31 62 0 1474912608 65508448 8789904
21:06:34 23251 8192000000 10359291360 10795503616 1370116368 14.328 99 35 70 0 1456701568 75967440 10617760
21:08:03 40011 8192000000 10305085376 10760331264 1376901712 14.389 100 19 38 0 1441069088 58522224 5645152
21:09:44 94400 8192000000 10290179376 10739462144 1366024624 14.291 98 19 38 1 1383856080 13525280 4306176
21:12:20 54506 8192000000 10309328400 10757066752 1395820272 14.558 100 21 41 0 1407694400 7444256 4429872
21:14:14 68549 8192000000 10314709104 10771742720 1398274352 14.580 99 15 30 0 1409898384 8387280 3236752
21:16:24 53894 8192000000 10311086368 10765217792 1406161056 14.650 100 51 98 1 1423284208 6367824 10755328
21:18:30 66634 8192000000 10325115488 10777026560 1391681008 14.521 98 26 51 1 1411979520 14365520 5932992
21:21:46 76138 8192000000 10319286688 10770599936 1372606400 14.350 97 17 34 0 1395629616 18762672 4260544
21:24:05 139731 8192000000 10321612640 10772697088 1347906832 14.129 96 7 13 0 1359980416 10392688 1680896
21:27:31 105016 8192000000 10312882944 10765602816 1361021296 14.247 97 12 23 0 1379485616 16248304 2216016
21:30:19 123351 8192000000 10288094224 10742861824 1343891952 14.092 97 16 32 0 1355634944 8077456 3665536
21:33:28 39944 8192000000 10309637216 10748252160 1378222048 14.401 99 45 87 1 1398267616 10568416 9477152
21:35:12 13955 8192000000 10306331792 10751578112 1392279104 14.526 100 42 82 0 1413638640 11878192 9481344
21:36:28 24664 8192000000 10280185712 10731442176 1355394416 14.196 97 18 36 1 1372447152 12834816 4217920
21:37:54 45510 8192000000 10283167280 10728271872 1333070160 13.995 97 40 81 0 1357459040 14344448 10044432
21:40:01 243142 8192000000 10267909648 10744471552 1317030800 13.850 95 8 16 0 1331134848 12411936 1692112
21:45:07 39525 8192000000 10277847584 10729005056 1372224448 14.347 100 18 36 1 1384710480 8479216 4006816
21:46:50 18371 8192000000 10246924432 10698526720 1327763040 13.947 98 21 42 1 1338500128 6179344 4557744
21:48:18 39535 8192000000 10272918400 10716819456 1351151344 14.158 99 34 68 0 1367434240 9016384 7266512
21:50:01 63580 8192000000 10271211184 10718982144 1330389472 13.971 97 19 39 0 1345484880 10681584 4413824
21:52:16 52629 8192000000 10262696464 10704965632 1322354864 13.898 97 20 40 0 1335527200 8508560 4663776
21:54:10 36672 8192000000 10296662176 10735767552 1363153088 14.266 99 44 88 0 1388032896 15002256 9877552
21:55:56 63916 8192000000 10278248672 10745139200 1343741024 14.091 98 10 20 0 1365979680 20088304 2150352
21:58:00 126125 8192000000 10277598976 10724974592 1337200208 14.032 97 20 40 1 1351208560 8775328 5233024
22:01:16 73267 8192000000 10277955520 10742796288 1370941664 14.335 100 23 45 0 1393954000 18027792 4984544
22:03:35 53118 8192000000 10263621952 10712449024 1341739280 14.073 97 20 39 0 1352845808 6830336 4276192
22:05:34 48214 8192000000 10292020032 10747334656 1392485184 14.528 100 14 30 0 1407030512 11121648 3423680
22:07:22 68033 8192000000 10298959264 10768642048 1383748304 14.450 99 19 39 0 1404817056 16186080 4882672
22:10:03 152675 8192000000 10285800288 10747056128 1348756288 14.136 97 11 23 0 1366834240 15966000 2111952
22:13:37 39621 8192000000 10287696016 10739154944 1381254688 14.428 99 21 40 1 1396122448 10552880 4314880
22:15:17 89826 8192000000 10297827824 10761052160 1341221296 14.068 97 8 16 0 1355301808 12383920 1696592
22:17:48 16679 8192000000 10297988912 10745335808 1375493072 14.376 99 40 80 1 1393432224 8577472 9361680
22:19:12 45175 8192000000 10299173392 10751795200 1377167296 14.391 99 28 56 0 1395979728 12456384 6356048
22:21:09 115493 8192000000 10264255168 10705776640 1240904592 13.155 90 16 32 1 1258034928 14191584 2938752
22:24:06 114808 8192000000 10281145120 10740092928 1364489232 14.278 99 20 40 1 1381957520 12889216 4579072
22:27:52 5287 8192000000 10271689360 10719645696 1367284176 14.303 100 30 59 0 1381190112 7686032 6219904
22:29:09 58513 8192000000 10279371920 10744442880 1374246816 14.365 100 9 16 0 1390276512 14212416 1817280
22:31:08 46606 8192000000 10250668640 10711891968 1327237968 13.942 97 13 24 1 1339492512 8700144 3554400
22:32:55 99391 8192000000 10272711968 10721427456 1362747936 14.262 100 18 36 0 1378135136 11123856 4263344
22:35:35 52035 8192000000 10244864752 10698956800 1299722880 13.693 96 14 28 1 1315531248 12638672 3169696
22:37:27 49283 8192000000 10250839296 10695086080 1310818992 13.794 97 16 33 0 1325449808 11255776 3375040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-09-2022 22:40:25 to 29-09-2022 22:40:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-09-2022 22:40:41 to 29-09-2022 22:40:43.

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          246284
MON$OLDEST_ACTIVE               248656
MON$OLDEST_SNAPSHOT             248656
MON$NEXT_TRANSACTION            248656
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                       2066080
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 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 ---
:252: 
:253: mscs-test-161.budzdorov.ru    Thu Sep 29 22:38:35 2022
:254:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:255: 
:256: 
:257: mscs-test-161.budzdorov.ru    Thu Sep 29 22:38:39 2022
:258:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:259: 
:260: 
:261: mscs-test-161.budzdorov.ru    Thu Sep 29 22:38:42 2022
:262:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:263: 
--- end of diff output ---

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

29.09.22 22:40:44. 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-09-29 22:40:47 No severe PSQL-related problems occured
29.09.22 22:40:48. End of report.