Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.12.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 14931520 kB
MemAvailable 30135164 kB
Buffers 0 kB
Cached 16796752 kB
SwapCached 3264 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-12-09 01:38:35
DTS_END                         2022-12-09 01:38:35.0710
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/50374
TRN_ID                          246214
ATT_ID                          697
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: 9339.33 24916 1120720
customer order: creation 172.61 359 20713
customer order: refuse 14.07 372 1688
order to supplier: creation 45.74 1202 5489
order to supplier: removal 3.44 92424 413
invoice (draft): creation 45.15 3512 5418
invoice (draft): removal 53.93 774 6471
invoice accept: apply 41.85 17860 5022
invoice accept: cancel 49.02 780 5882
customer reserve: creation 3836.25 178 460350
customer reserve: removal 4779.48 51 573538
realization accept: apply 83.71 180 10045
realization accept: cancel 83.86 104 10063
payment from customer: creation 59.56 114 7147
payment from customer: removal 6.21 84 745
payment to supplier: creation 49.33 137 5920
payment to supplier: removal 3.38 91 405
service: total inventory turnovers 4.64 1052 557
service: total monetary turnovers 6.36 50 763
service: aggregate perf. data 0.73 26680 87
service: refresh index statistics 0.03 352312 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-12-2022 01:40:06 to 09-12-2022 01:40:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2122
TEST_TIME 2 3383
TEST_TIME 3 4109
TEST_TIME 4 4472
TEST_TIME 5 4731
TEST_TIME 6 4663
TEST_TIME 7 4867
TEST_TIME 8 5098
TEST_TIME 9 5264
TEST_TIME 10 5477
TEST_TIME 11 5763
TEST_TIME 12 5825
TEST_TIME 13 5781
TEST_TIME 14 6154
TEST_TIME 15 6176
TEST_TIME 16 6507
TEST_TIME 17 6763
TEST_TIME 18 7475
TEST_TIME 19 7645
TEST_TIME 20 8267
TEST_TIME 21 9307
TEST_TIME 22 9280
TEST_TIME 23 9444
TEST_TIME 24 9255
TEST_TIME 25 9313
TEST_TIME 26 10135
TEST_TIME 27 10562
TEST_TIME 28 10580
TEST_TIME 29 10589
TEST_TIME 30 10470
TEST_TIME 31 10829
TEST_TIME 32 10629
TEST_TIME 33 10403
TEST_TIME 34 10178
TEST_TIME 35 10238
TEST_TIME 36 10312
TEST_TIME 37 10126
TEST_TIME 38 10079
TEST_TIME 39 9940
TEST_TIME 40 10103
TEST_TIME 41 10026
TEST_TIME 42 10034
TEST_TIME 43 9925
TEST_TIME 44 9881
TEST_TIME 45 9831
TEST_TIME 46 9719
TEST_TIME 47 9680
TEST_TIME 48 9722
TEST_TIME 49 9741
TEST_TIME 50 9599
TEST_TIME 51 9547
TEST_TIME 52 9522
TEST_TIME 53 9516
TEST_TIME 54 9434
TEST_TIME 55 9478
TEST_TIME 56 9728
TEST_TIME 57 9678
TEST_TIME 58 9799
TEST_TIME 59 9780
TEST_TIME 60 9759
TEST_TIME 61 9837
TEST_TIME 62 9916
TEST_TIME 63 9947
TEST_TIME 64 9900
TEST_TIME 65 9763
TEST_TIME 66 9688
TEST_TIME 67 9684
TEST_TIME 68 9625
TEST_TIME 69 9571
TEST_TIME 70 9507
TEST_TIME 71 9460
TEST_TIME 72 9414
TEST_TIME 73 9368
TEST_TIME 74 9391
TEST_TIME 75 9418
TEST_TIME 76 9396
TEST_TIME 77 9425
TEST_TIME 78 9501
TEST_TIME 79 9518
TEST_TIME 80 9467
TEST_TIME 81 9549
TEST_TIME 82 9543
TEST_TIME 83 9545
TEST_TIME 84 9618
TEST_TIME 85 9585
TEST_TIME 86 9669
TEST_TIME 87 9633
TEST_TIME 88 9557
TEST_TIME 89 9536
TEST_TIME 90 9576
TEST_TIME 91 9500
TEST_TIME 92 9425
TEST_TIME 93 9382
TEST_TIME 94 9489
TEST_TIME 95 9432
TEST_TIME 96 9456
TEST_TIME 97 9464
TEST_TIME 98 9440
TEST_TIME 99 9490
TEST_TIME 100 9491
TEST_TIME 101 9537
TEST_TIME 102 9548
TEST_TIME 103 9546
TEST_TIME 104 9494
TEST_TIME 105 9563
TEST_TIME 106 9584
TEST_TIME 107 9616
TEST_TIME 108 9604
TEST_TIME 109 9597
TEST_TIME 110 9549
TEST_TIME 111 9515
TEST_TIME 112 9519
TEST_TIME 113 9524
TEST_TIME 114 9475
TEST_TIME 115 9456
TEST_TIME 116 9431
TEST_TIME 117 9402
TEST_TIME 118 9387
TEST_TIME 119 9380
TEST_TIME 120 9340

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-12-2022 01:40:10 to 09-12-2022 01:40:13.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1548619 1548619 0 0.00 0 22008 6 0 0
doc_list_biud 1548589 1548589 0 0.00 0 48806 25 0 0
sp_add_invoice_to_stock 5104 5022 82 1.60 24 177307 17861 0 82
sp_cancel_adding_invoice 5882 5882 0 0.00 4 68447 781 0 0
sp_cancel_client_order 1688 1688 0 0.00 4 10521 372 0 0
sp_cancel_customer_reserve 573538 573538 0 0.00 0 48807 51 0 0
sp_cancel_pay_from_customer 745 745 0 0.00 3 4315 84 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 2 7737 91 0 0
sp_cancel_supplier_invoice 6474 6471 3 0.04 1 14030 775 0 3
sp_cancel_supplier_order 413 413 0 0.00 4 1140095 92424 0 0
sp_cancel_write_off 10063 10063 0 0.00 1 25845 105 0 0
sp_client_order 20713 20713 0 0.00 3 47020 359 0 0
sp_customer_reserve 460350 460350 0 0.00 0 41022 179 0 0
sp_fill_shopping_cart 31616 31616 0 0.00 1 16537 37 0 0
sp_fill_shopping_cart_clo_res 460347 460347 0 0.00 0 19190 15 0 0
sp_get_clo_for_invoice 5019 5019 0 0.00 0 20476 298 0 0
sp_kill_cost_storno 17095 17095 0 0.00 0 9330 49 0 0
sp_kill_qstorno_ret_qs2qd 1164023 1164023 0 0.00 0 48800 28 0 0
sp_kill_qty_storno 613098 613098 0 0.00 0 48805 62 0 0
sp_lock_selected_doc 20855 20855 0 0.00 0 6745 5 0 0
sp_make_cost_storno 28130 28130 0 0.00 0 16264 58 0 0
sp_multiply_rows_for_pdistr 28101 28101 0 0.00 0 8047 25 0 0
sp_multiply_rows_for_qdistr 20713 20713 0 0.00 0 47005 164 0 0
sp_pay_from_customer 7147 7147 0 0.00 4 11399 114 0 0
sp_pay_to_supplier 5920 5920 0 0.00 4 13760 138 0 0
sp_payment_common 13057 13057 0 0.00 1 5083 25 0 0
sp_qd_handle_on_cancel_clo 1688 1688 0 0.00 0 10498 333 0 0
sp_qd_handle_on_invoice_upd_sts 10891 10891 0 0.00 0 15856 334 0 0
sp_qd_handle_on_reserve_upd_sts 10034 10034 0 0.00 0 12705 45 0 0
sp_reserve_write_off 10045 10045 0 0.00 6 16276 181 0 0
sp_supplier_invoice 5418 5418 0 0.00 6 100289 3512 0 0
sp_supplier_order 5489 5489 0 0.00 4 29480 1202 0 0
srv_aggregate_perf_data 87 87 0 0.00 2461 698792 26680 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1027 166995 44159 0 0
srv_find_qd_qs_mism 1073744 1073744 0 0.00 0 22006 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 44 44848 1053 0 0
srv_make_money_saldo 763 763 0 0.00 1 8063 51 0 0
srv_recalc_idx_stat 4 4 0 0.00 164090 555135 352312 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5022 5022 0 0.00 0 13979 183 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 5261 90 0 0
t$perf-norm:sp_cancel_client_order 1688 1688 0 0.00 0 1632 14 0 0
t$perf-norm:sp_cancel_customer_reserve 748 748 0 0.00 0 13004 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 745 745 0 0.00 0 366 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 2568 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 523 9 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 25769 1496 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 2278 12 0 0
t$perf-norm:sp_client_order 20713 20713 0 0.00 0 6747 16 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 4041 11 0 0
t$perf-norm:sp_pay_from_customer 7147 7147 0 0.00 0 19988 12 0 0
t$perf-norm:sp_pay_to_supplier 5920 5920 0 0.00 0 6100 13 0 0
t$perf-norm:sp_reserve_write_off 10045 10045 0 0.00 0 6368 12 0 0
t$perf-norm:sp_supplier_invoice 5418 5418 0 0.00 0 4457 19 0 0
t$perf-norm:sp_supplier_order 5489 5489 0 0.00 0 3609 13 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 281 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 5839 95 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 3210 34 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 1127 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 21 6 0 0
v_add_invoice_to_stock 10180 10016 164 1.61 0 3911 18 0 164
v_all_customers 37662 37662 0 0.00 0 51 1 0 0
v_all_suppliers 30325 30325 0 0.00 0 53 1 0 0
v_all_wares 41423 41423 0 0.00 0 69 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 2521 9 0 0
v_cancel_client_order 3376 3376 0 0.00 0 9955 9 0 0
v_cancel_customer_prepayment 1490 1490 0 0.00 0 4307 14 0 0
v_cancel_customer_reserve 1496 1496 0 0.00 0 1137 6 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 4059 10 0 0
v_cancel_supplier_invoice 808 802 6 0.74 0 2523 23 0 6
v_cancel_supplier_order 826 826 0 0.00 0 2495 7 0 0
v_cancel_write_off 1986 1986 0 0.00 0 3966 18 0 0
v_max_id_clo_ord 5487 5487 0 0.00 0 4002 3 0 0
v_max_id_clo_res 968 968 0 0.00 0 4188 15 0 0
v_max_id_ord_sup 5417 5417 0 0.00 0 4866 5 0 0
v_max_non_paid_invoice 1656 1656 0 0.00 0 3177 4 0 0
v_max_non_paid_realizn 3886 3886 0 0.00 0 1746 4 0 0
v_min_id_clo_ord 5487 5487 0 0.00 0 4010 8 0 0
v_min_id_clo_res 967 967 0 0.00 0 6094 16 0 0
v_min_id_ord_sup 5417 5417 0 0.00 0 6024 6 0 0
v_min_non_paid_invoice 5914 5914 0 0.00 0 4776 18 0 0
v_min_non_paid_realizn 7142 7142 0 0.00 0 5080 17 0 0
v_our_firm 10272 10272 0 0.00 0 22 1 0 0
v_reserve_write_off 20068 20068 0 0.00 0 16113 12 0 0
x_lock_depdocs_on_canc_invoice 11952 11952 0 0.00 0 22397 116 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 8061 329 0 0
x_make_qstorno_1000_1200 5489 5489 0 0.00 1 29439 1126 0 0
x_make_qstorno_1200_2000 5418 5418 0 0.00 3 100270 3379 0 0
x_make_qstorno_2100_3300 460350 460350 0 0.00 0 38383 161 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 09-12-2022 01:40:14 to 09-12-2022 01:40:19.

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:39 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:40 22390 8192000000 9885130608 10189402112 963462320 10.523 100 86 172 0 992206976 5852576 22892080
23:11:18 6032 8192000000 9916252688 10229379072 1001008688 10.888 100 91 182 0 1032759888 7876944 23874256
23:12:31 17778 8192000000 9939531440 10267041792 1029610048 11.165 100 78 156 0 1058826256 9628976 19587232
23:13:52 9993 8192000000 10002949072 10345930752 1096172544 11.801 100 77 154 0 1125352448 10621024 18558880
23:15:06 7162 8192000000 10077172080 10434502656 1161196848 12.414 100 71 140 0 1187820704 10822816 15801040
23:16:22 1491 8192000000 10123738608 10491285504 1211059184 12.879 100 82 164 1 1243044816 13955616 18030016
23:17:25 1836 8192000000 10164334640 10536550400 1251176496 13.249 100 81 160 1 1282767392 13177904 18412992
23:18:29 1960 8192000000 10187982960 10572120064 1271346096 13.434 100 72 144 0 1300371744 13542864 15482784
23:19:32 49364 8192000000 10199166496 10601947136 1301081440 13.705 100 51 102 1 1321016112 7938704 11995968
23:21:22 3342 8192000000 10191226080 10604101632 1305984944 13.750 100 24 46 0 1317579664 6458688 5136032
23:22:26 8115 8192000000 10195713968 10615873536 1314323152 13.825 100 17 35 0 1328234208 9504496 4406560
23:23:35 9772 8192000000 10211412672 10626449408 1321171104 13.887 100 24 48 0 1332548016 5876400 5500512
23:24:47 69342 8192000000 10234595328 10644058112 1330199104 13.969 100 42 84 0 1346881488 6063136 10619248
23:26:57 5891 8192000000 10222772928 10642366464 1324460720 13.917 99 23 49 0 1334859792 5200256 5198816
23:28:05 7063 8192000000 10202291712 10618888192 1296380688 13.662 98 21 42 0 1306608528 5175152 5052688
23:29:12 53705 8192000000 10078469328 10470555648 1082247872 11.669 85 23 46 1 1092094176 5030864 4815440
23:31:07 4752 8192000000 10141139312 10540113920 1224966736 13.008 97 25 50 0 1236846784 6296288 5583760
23:32:13 6073 8192000000 10141559168 10540675072 1236195696 13.111 99 23 47 0 1247041328 5666720 5178912
23:33:22 22915 8192000000 10168459952 10576736256 1267738976 13.401 99 20 40 0 1278324480 4791648 5793856
23:34:45 14307 8192000000 10175043808 10591657984 1290219648 13.606 100 15 30 0 1299508944 6063200 3226096
23:36:05 8020 8192000000 10189592288 10604601344 1303769536 13.730 100 16 32 0 1312999264 4911744 4317984
23:37:13 12189 8192000000 10197467040 10615922688 1311008416 13.795 100 17 36 0 1319994624 4812912 4173296
23:38:28 14597 8192000000 10241559776 10645442560 1336369280 14.025 100 46 86 1 1352156960 5405456 10382224
23:39:49 64859 8192000000 10256015424 10676514816 1353816032 14.182 100 43 84 1 1377125760 12704880 10604848
23:42:02 15486 8192000000 10294300720 10715365376 1377059856 14.390 100 46 92 1 1404781104 17317664 10403584
23:43:25 12617 8192000000 10301651280 10727669760 1388725536 14.494 100 47 89 0 1418099296 19627632 9746128
23:44:42 16862 8192000000 10310700000 10737467392 1385023936 14.461 99 38 74 0 1419269232 25351968 8893328
23:46:03 6805 8192000000 10325226976 10749067264 1389858528 14.505 99 50 101 1 1427376640 25701168 11816944
23:47:13 7303 8192000000 10311066224 10732998656 1376601232 14.386 99 51 100 0 1416147952 27947776 11598944
23:48:24 6688 8192000000 10326576256 10746363904 1385884224 14.469 99 57 114 0 1429126064 29818864 13422976
23:49:40 11671 8192000000 10325937856 10748649472 1377948224 14.398 98 56 110 0 1423991168 31829888 14213056
23:50:57 2972 8192000000 10314570656 10723962880 1362507952 14.260 99 71 139 0 1413665136 34357392 16799792
23:52:05 4591 8192000000 10301318560 10705252352 1346215408 14.113 99 73 145 1 1397880064 33718464 17946192
23:53:17 2324 8192000000 10309690288 10711146496 1354337280 14.186 100 76 153 1 1412806880 38351648 20117952
23:54:28 1520 8192000000 10323812368 10728308736 1367500512 14.305 100 73 136 1 1429367984 44788224 17079248
23:55:35 1024 8192000000 10332095760 10731618304 1365440144 14.286 100 77 150 0 1434719632 50280336 18999152
23:56:43 12165 8192000000 10311577488 10730684416 1350319344 14.150 99 41 83 0 1418903232 56582832 12001056
23:57:57 4137 8192000000 10320998448 10722463744 1343546976 14.089 98 67 130 1 1417880624 56464080 17869568
23:59:04 4317 8192000000 10346957872 10770657280 1380020720 14.417 100 62 125 0 1451727872 56535200 15171952
00:00:14 23749 8192000000 10301923056 10736373760 1361767728 14.253 99 22 44 1 1397429472 29521184 6140560
00:01:46 42358 8192000000 10318781872 10756235264 1374489088 14.367 99 17 34 0 1414565504 35394320 4682096
00:03:31 13819 8192000000 10320709168 10747600896 1357074608 14.211 99 40 81 1 1407584864 39648064 10862192
00:05:27 18158 8192000000 10311799328 10750689280 1373103936 14.355 100 38 73 1 1413160736 30052848 10003952
00:06:48 9776 8192000000 10300840912 10737545216 1382260048 14.437 100 31 62 0 1401626000 12172608 7193344
00:07:58 45951 8192000000 10330174080 10760691712 1380102720 14.417 99 32 64 0 1407542320 19799584 7640016
00:09:55 79628 8192000000 10321057680 10765348864 1400729424 14.601 100 20 40 0 1415106192 10137536 4239232
00:12:18 96776 8192000000 10306216624 10765766656 1362996160 14.264 97 21 42 1 1386270000 18381040 4892800
00:14:59 41640 8192000000 10301575232 10758328320 1388157776 14.489 99 13 26 0 1404670384 13804144 2708464
00:16:43 59267 8192000000 10319417680 10769539072 1401848736 14.611 100 23 46 0 1421630976 14746784 5035456
00:18:48 18336 8192000000 10309199312 10763911168 1392973536 14.532 99 37 74 0 1415219952 14149632 8096784
00:20:18 43374 8192000000 10292544032 10760171520 1386836448 14.478 99 5 8 1 1397821504 10121680 863376
00:22:22 35956 8192000000 10303752016 10769502208 1404462512 14.635 100 10 18 0 1418194576 11588336 2143728
00:24:20 90949 8192000000 10298037872 10761650176 1393412640 14.536 99 25 50 0 1411121104 11676896 6031568
00:26:52 41683 8192000000 10292483312 10747604992 1369572960 14.323 98 28 56 1 1391333008 14799344 6960704
00:28:34 47240 8192000000 10278681216 10749542400 1383838192 14.451 100 6 12 0 1394897856 9861072 1198592
00:30:23 48674 8192000000 10291239696 10753462272 1376709136 14.387 98 17 34 0 1394909488 14120880 4079472
00:32:17 45318 8192000000 10320679248 10760445952 1363017248 14.264 97 39 76 1 1390186912 17443008 9726656
00:34:08 77808 8192000000 10261169264 10708119552 1264278624 13.369 92 15 28 1 1278905744 11285728 3341392
00:36:31 65709 8192000000 10224688928 10675621888 1240537328 13.151 92 17 32 0 1260981744 16628336 3816080
00:39:00 76579 8192000000 10259166400 10701197312 1280243104 13.515 95 12 24 0 1292322944 9649952 2429888
00:41:17 57979 8192000000 10241743248 10686078976 1288055488 13.587 96 8 16 0 1301974960 12395952 1523520
00:43:57 31917 8192000000 10263704256 10706571264 1350263536 14.150 100 39 78 0 1368806880 9623056 8920288
00:45:29 36644 8192000000 10250207200 10710564864 1348972128 14.138 99 29 56 0 1364660992 9519984 6168880
00:47:06 30337 8192000000 10257025184 10716819456 1348893264 14.138 99 11 21 0 1363500016 12450736 2156016
00:48:41 55392 8192000000 10243349600 10704662528 1345473088 14.107 99 16 33 0 1355722624 6608080 3641456
00:50:38 64173 8192000000 10259474784 10725113856 1360474848 14.242 99 13 26 0 1373012016 9418288 3118880
00:52:48 63339 8192000000 10285036432 10748231680 1374312688 14.366 100 15 30 0 1388184480 9559200 4312592
00:54:55 34867 8192000000 10270195392 10730147840 1367295584 14.303 100 19 38 0 1384241952 12453520 4492848
00:56:36 91896 8192000000 10277896784 10729295872 1332865184 13.993 97 14 29 0 1355079936 18894112 3320640
00:59:08 55957 8192000000 10287949680 10749968384 1364629456 14.279 99 26 52 0 1386640176 15924160 6086560
01:01:22 42688 8192000000 10299593440 10751094784 1365835968 14.290 99 31 62 0 1388696400 16189872 6670560
01:03:16 29117 8192000000 10292034272 10733596672 1365659840 14.288 99 29 58 0 1382559648 10746096 6153712
01:04:46 60042 8192000000 10292098144 10765758464 1388736144 14.495 100 11 22 0 1407265232 16109760 2419328
01:07:06 72345 8192000000 10290146656 10762309632 1385770272 14.468 100 21 42 0 1408082000 18042816 4268912
01:10:01 67857 8192000000 10305788208 10769616896 1394392816 14.545 100 8 16 0 1416223792 20101248 1729728
01:12:18 81175 8192000000 10310603264 10790309888 1405655744 14.645 100 9 18 0 1424316448 16800208 1860496
01:14:39 94066 8192000000 10299068736 10760654848 1350009472 14.148 96 14 28 0 1365791968 12868208 2914288
01:17:14 41030 8192000000 10291184112 10737070080 1355523968 14.197 98 24 44 1 1367816768 7650880 4641920
01:18:56 82603 8192000000 10284826400 10736668672 1345719168 14.109 97 15 30 0 1361749424 12781440 3248816
01:21:28 54832 8192000000 10301504608 10733322240 1326817392 13.938 96 41 81 0 1344464432 8370864 9276176
01:23:26 166967 8192000000 10279944128 10747219968 1238332736 13.131 90 13 26 0 1254438432 13287552 2818144
01:27:13 101485 8192000000 10273992160 10726440960 1336574032 14.027 98 26 51 0 1357290560 15268176 5448352
01:30:01 31302 8192000000 10247339056 10707898368 1322079136 13.896 97 19 38 0 1342313296 15864160 4370000
01:31:35 34656 8192000000 10245388144 10708930560 1344343184 14.097 99 3 6 0 1352306928 7086320 877424
01:33:10 67172 8192000000 10266230064 10720079872 1340500848 14.062 98 24 47 0 1353377952 7630544 5246560
01:35:24 48420 8192000000 10269944992 10725744640 1358651456 14.225 99 16 32 0 1371428448 8601648 4175344
01:37:21 59284 8192000000 10260049264 10721259520 1340736704 14.064 98 12 24 0 1356195696 12732864 2726128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-12-2022 01:40:20 to 09-12-2022 01:40:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 09-12-2022 01:40:37 to 09-12-2022 01:40:38.

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          244686
MON$OLDEST_ACTIVE               246343
MON$OLDEST_SNAPSHOT             246343
MON$NEXT_TRANSACTION            246343
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                       2047968
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.33646 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33646 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33646 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33646 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 ---
:161: 
:162: mscs-test-161.budzdorov.ru    Fri Dec  9 01:38:39 2022
:163:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:164: 
:165: 
:166: mscs-test-161.budzdorov.ru    Fri Dec  9 01:38:40 2022
:167:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:168: 
:169: 
:170: mscs-test-161.budzdorov.ru    Fri Dec  9 01:38:40 2022
:171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:172: 
--- end of diff output ---

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

09.12.22 01:40:40. 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-12-09 01:40:42 No severe PSQL-related problems occured
09.12.22 01:40:43. End of report.