Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.01.2023 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 15446948 kB
MemAvailable 30402556 kB
Buffers 0 kB
Cached 16257776 kB
SwapCached 4412 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 2023-01-10 22:38:34
DTS_END                         2023-01-10 22:38:34.5160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43608
TRN_ID                          239281
ATT_ID                          684
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: 9356.33 22576 1122760
customer order: creation 172.14 347 20657
customer order: refuse 14.53 334 1744
order to supplier: creation 46.53 1224 5583
order to supplier: removal 3.51 95885 421
invoice (draft): creation 45.04 3137 5405
invoice (draft): removal 52.83 722 6339
invoice accept: apply 42.86 17547 5143
invoice accept: cancel 47.72 755 5726
customer reserve: creation 3927.64 175 471317
customer reserve: removal 4708.48 56 565018
realization accept: apply 84.53 155 10144
realization accept: cancel 81.18 98 9741
payment from customer: creation 58.28 113 6993
payment from customer: removal 5.83 79 700
payment to supplier: creation 49.61 127 5953
payment to supplier: removal 3.52 89 422
service: total inventory turnovers 4.81 993 577
service: total monetary turnovers 6.38 24 766
service: aggregate perf. data 0.89 18382 107
service: refresh index statistics 0.03 311277 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-01-2023 22:39:48 to 10-01-2023 22:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6794
TEST_TIME 2 5918
TEST_TIME 3 6886
TEST_TIME 4 6593
TEST_TIME 5 6153
TEST_TIME 6 6044
TEST_TIME 7 5906
TEST_TIME 8 5735
TEST_TIME 9 5983
TEST_TIME 10 6106
TEST_TIME 11 6445
TEST_TIME 12 6419
TEST_TIME 13 6859
TEST_TIME 14 6846
TEST_TIME 15 7009
TEST_TIME 16 7341
TEST_TIME 17 7510
TEST_TIME 18 8095
TEST_TIME 19 8128
TEST_TIME 20 8849
TEST_TIME 21 9290
TEST_TIME 22 9254
TEST_TIME 23 9316
TEST_TIME 24 9512
TEST_TIME 25 9592
TEST_TIME 26 9546
TEST_TIME 27 9883
TEST_TIME 28 10776
TEST_TIME 29 10738
TEST_TIME 30 10694
TEST_TIME 31 10452
TEST_TIME 32 10226
TEST_TIME 33 10017
TEST_TIME 34 9878
TEST_TIME 35 9894
TEST_TIME 36 9926
TEST_TIME 37 10044
TEST_TIME 38 9976
TEST_TIME 39 9826
TEST_TIME 40 9752
TEST_TIME 41 9605
TEST_TIME 42 9516
TEST_TIME 43 9436
TEST_TIME 44 9660
TEST_TIME 45 9509
TEST_TIME 46 9548
TEST_TIME 47 9488
TEST_TIME 48 9580
TEST_TIME 49 9561
TEST_TIME 50 9501
TEST_TIME 51 9452
TEST_TIME 52 9545
TEST_TIME 53 9612
TEST_TIME 54 9648
TEST_TIME 55 9748
TEST_TIME 56 9776
TEST_TIME 57 9704
TEST_TIME 58 9787
TEST_TIME 59 9847
TEST_TIME 60 9891
TEST_TIME 61 9796
TEST_TIME 62 9689
TEST_TIME 63 9624
TEST_TIME 64 9618
TEST_TIME 65 9558
TEST_TIME 66 9570
TEST_TIME 67 9465
TEST_TIME 68 9431
TEST_TIME 69 9389
TEST_TIME 70 9452
TEST_TIME 71 9513
TEST_TIME 72 9519
TEST_TIME 73 9527
TEST_TIME 74 9563
TEST_TIME 75 9652
TEST_TIME 76 9682
TEST_TIME 77 9649
TEST_TIME 78 9680
TEST_TIME 79 9718
TEST_TIME 80 9724
TEST_TIME 81 9774
TEST_TIME 82 9823
TEST_TIME 83 9727
TEST_TIME 84 9659
TEST_TIME 85 9647
TEST_TIME 86 9628
TEST_TIME 87 9590
TEST_TIME 88 9537
TEST_TIME 89 9526
TEST_TIME 90 9555
TEST_TIME 91 9483
TEST_TIME 92 9440
TEST_TIME 93 9482
TEST_TIME 94 9490
TEST_TIME 95 9496
TEST_TIME 96 9537
TEST_TIME 97 9511
TEST_TIME 98 9570
TEST_TIME 99 9555
TEST_TIME 100 9551
TEST_TIME 101 9534
TEST_TIME 102 9553
TEST_TIME 103 9589
TEST_TIME 104 9635
TEST_TIME 105 9625
TEST_TIME 106 9616
TEST_TIME 107 9633
TEST_TIME 108 9553
TEST_TIME 109 9541
TEST_TIME 110 9501
TEST_TIME 111 9526
TEST_TIME 112 9477
TEST_TIME 113 9456
TEST_TIME 114 9424
TEST_TIME 115 9424
TEST_TIME 116 9388
TEST_TIME 117 9407
TEST_TIME 118 9414
TEST_TIME 119 9414
TEST_TIME 120 9363

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-01-2023 22:39:52 to 10-01-2023 22:39:55.

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 1559790 1559790 0 0.00 0 20360 6 0 0
doc_list_biud 1559789 1559789 0 0.00 0 48848 26 0 0
sp_add_invoice_to_stock 5260 5143 117 2.22 35 234088 17547 0 117
sp_cancel_adding_invoice 5726 5726 0 0.00 5 50197 756 0 0
sp_cancel_client_order 1744 1744 0 0.00 4 9470 335 0 0
sp_cancel_customer_reserve 565018 565018 0 0.00 0 48850 57 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 3 6520 79 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 6665 90 0 0
sp_cancel_supplier_invoice 6350 6339 11 0.17 1 21218 722 0 11
sp_cancel_supplier_order 421 421 0 0.00 12 872867 95886 0 0
sp_cancel_write_off 9741 9741 0 0.00 1 9651 99 0 0
sp_client_order 20657 20657 0 0.00 3 19707 347 0 0
sp_customer_reserve 471317 471317 0 0.00 0 80291 176 0 0
sp_fill_shopping_cart 31630 31630 0 0.00 1 11270 32 0 0
sp_fill_shopping_cart_clo_res 471304 471304 0 0.00 0 17488 13 0 0
sp_get_clo_for_invoice 5130 5130 0 0.00 0 36766 308 0 0
sp_kill_cost_storno 16589 16589 0 0.00 0 9195 51 0 0
sp_kill_qstorno_ret_qs2qd 1146537 1146537 0 0.00 0 48846 30 0 0
sp_kill_qty_storno 604262 604262 0 0.00 0 48848 65 0 0
sp_lock_selected_doc 21043 21043 0 0.00 0 9684 7 0 0
sp_make_cost_storno 28220 28220 0 0.00 0 17405 50 0 0
sp_multiply_rows_for_pdistr 28219 28219 0 0.00 0 7241 24 0 0
sp_multiply_rows_for_qdistr 20657 20657 0 0.00 0 10836 157 0 0
sp_pay_from_customer 6993 6993 0 0.00 4 36577 114 0 0
sp_pay_to_supplier 5953 5953 0 0.00 5 18671 128 0 0
sp_payment_common 12946 12946 0 0.00 1 36523 36 0 0
sp_qd_handle_on_cancel_clo 1744 1744 0 0.00 0 9429 316 0 0
sp_qd_handle_on_invoice_upd_sts 10855 10855 0 0.00 0 24990 294 0 0
sp_qd_handle_on_reserve_upd_sts 10144 10144 0 0.00 0 12776 47 0 0
sp_reserve_write_off 10144 10144 0 0.00 6 17453 155 0 0
sp_supplier_invoice 5405 5405 0 0.00 5 113193 3138 0 0
sp_supplier_order 5583 5583 0 0.00 4 32525 1224 0 0
srv_aggregate_perf_data 108 107 1 0.92 1974 333823 18382 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 2361 127724 45527 0 0
srv_find_qd_qs_mism 1074999 1074999 0 0.00 0 20073 5 0 0
srv_make_invnt_saldo 577 577 0 0.00 29 86637 994 0 0
srv_make_money_saldo 766 766 0 0.00 1 1966 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 168323 413309 311277 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5144 5144 0 0.00 0 24759 175 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 5261 98 0 0
t$perf-norm:sp_cancel_client_order 1744 1744 0 0.00 0 1598 9 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 448 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 3712 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 1919 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 2716 21 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 40573 1708 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 1725 11 0 0
t$perf-norm:sp_client_order 20658 20658 0 0.00 0 12735 20 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 2368 8 0 0
t$perf-norm:sp_pay_from_customer 6993 6993 0 0.00 0 14035 14 0 0
t$perf-norm:sp_pay_to_supplier 5953 5953 0 0.00 0 16676 19 0 0
t$perf-norm:sp_reserve_write_off 10145 10145 0 0.00 0 4866 10 0 0
t$perf-norm:sp_supplier_invoice 5405 5405 0 0.00 0 6988 19 0 0
t$perf-norm:sp_supplier_order 5583 5583 0 0.00 0 14406 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 154 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 56 1 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 3341 30 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 976 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10492 10258 234 2.23 0 9516 19 0 234
v_all_customers 37310 37310 0 0.00 0 16 0 0 0
v_all_suppliers 30588 30588 0 0.00 0 112 1 0 0
v_all_wares 41314 41314 0 0.00 0 72 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 1877 9 0 0
v_cancel_client_order 3488 3488 0 0.00 0 1090 3 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 4329 8 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 369 3 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 3707 11 0 0
v_cancel_supplier_invoice 832 810 22 2.64 0 4971 30 0 22
v_cancel_supplier_order 842 842 0 0.00 0 3258 10 0 0
v_cancel_write_off 1936 1936 0 0.00 0 2637 19 0 0
v_max_id_clo_ord 5583 5583 0 0.00 0 1143 1 0 0
v_max_id_clo_res 975 975 0 0.00 0 5234 25 0 0
v_max_id_ord_sup 5390 5390 0 0.00 0 4583 4 0 0
v_max_non_paid_invoice 1632 1632 0 0.00 0 855 3 0 0
v_max_non_paid_realizn 3814 3814 0 0.00 0 3817 6 0 0
v_min_id_clo_ord 5583 5583 0 0.00 0 3308 5 0 0
v_min_id_clo_res 975 975 0 0.00 0 4954 13 0 0
v_min_id_ord_sup 5390 5390 0 0.00 0 6693 5 0 0
v_min_non_paid_invoice 5953 5953 0 0.00 0 11194 22 0 0
v_min_non_paid_realizn 6993 6993 0 0.00 0 36520 32 0 0
v_our_firm 10362 10362 0 0.00 0 125 1 0 0
v_reserve_write_off 20288 20288 0 0.00 0 3610 6 0 0
x_lock_depdocs_on_canc_invoice 11660 11660 0 0.00 0 20710 128 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 10103 347 0 0
x_make_qstorno_1000_1200 5583 5583 0 0.00 1 32514 1156 0 0
x_make_qstorno_1200_2000 5405 5405 0 0.00 2 113182 3009 0 0
x_make_qstorno_2100_3300 471317 471317 0 0.00 0 80289 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-01-2023 22:39:56 to 10-01-2023 22:40:00.

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:38 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:39 23576 8192000000 9912114112 10223910912 994440128 10.825 100 86 173 0 1022647120 5673872 22533120
20:11:09 19257 8192000000 9962153408 10285027328 1037584224 11.241 100 88 176 0 1067408432 7510032 22314176
20:12:38 17747 8192000000 9982176816 10314964992 1068108368 11.534 100 77 154 0 1095884592 8960896 18815328
20:14:02 10652 8192000000 10032196800 10378260480 1122285968 12.049 100 79 156 0 1150699664 10305296 18108400
20:15:16 6517 8192000000 10084182848 10443980800 1171903152 12.515 100 80 160 0 1200932464 10908448 18120864
20:16:28 3768 8192000000 10140829632 10510020608 1226306096 13.020 100 81 161 0 1256667120 12406560 17954464
20:17:32 1615 8192000000 10160182096 10538090496 1249178672 13.231 100 79 156 2 1278674624 12267776 17228176
20:18:36 1596 8192000000 10194051504 10578558976 1283828992 13.548 100 81 161 4 1313787424 12318720 17639712
20:19:38 1756 8192000000 10200457008 10595221504 1297535824 13.673 100 79 156 4 1326888688 11662464 17690400
20:20:41 10503 8192000000 10190789152 10602852352 1302768624 13.720 100 36 70 0 1318623120 8390960 7463536
20:21:52 8201 8192000000 10204219280 10617090048 1313035696 13.814 100 34 67 3 1327752496 7100000 7616800
20:23:01 7188 8192000000 10204363984 10624835584 1320012288 13.877 100 24 46 1 1335115104 10233760 4869056
20:24:10 13113 8192000000 10233206752 10637754368 1321389760 13.889 100 58 116 0 1342084800 6640496 14054544
20:25:26 6793 8192000000 10219171216 10639294464 1328798288 13.956 100 36 72 1 1342779264 5982944 7998032
20:26:33 7475 8192000000 10225204528 10648506368 1336949600 14.030 100 26 54 0 1348927696 5624560 6353536
20:27:41 50941 8192000000 10222223392 10651934720 1336168032 14.023 100 36 72 1 1350423536 5313248 8942256
20:29:33 9909 8192000000 10185184656 10598207488 1267250160 13.396 96 15 30 1 1275990288 5168928 3571200
20:30:44 15348 8192000000 10133891856 10533756928 1152993424 12.338 91 22 42 2 1163178432 4718080 5466928
20:32:00 9762 8192000000 10121670640 10518118400 1176833040 12.561 95 16 31 0 1185357760 4904368 3620352
20:33:10 47352 8192000000 10149559232 10555699200 1261668240 13.345 100 32 63 0 1274416448 5588640 7159568
20:35:02 7973 8192000000 10183773856 10589224960 1284064656 13.550 100 34 69 2 1297374368 5555744 7753968
20:36:15 11040 8192000000 10197236656 10608840704 1302284496 13.716 100 22 44 0 1312564480 5321312 4958672
20:37:30 18759 8192000000 10209963088 10621292544 1315124512 13.833 100 22 44 2 1326664944 5902064 5638368
20:38:53 8450 8192000000 10227241664 10635931648 1326772416 13.938 100 47 95 2 1344298528 6060608 11465504
20:40:09 3346 8192000000 10260952992 10681143296 1358669664 14.225 100 50 98 1 1380077936 9838256 11570016
20:41:14 10447 8192000000 10280359456 10695757824 1369649824 14.324 100 56 112 0 1394851200 12223088 12978288
20:42:29 6383 8192000000 10298941184 10717724672 1380939456 14.425 100 58 115 0 1406936608 13555552 12441600
20:43:47 42842 8192000000 10295222528 10724634624 1390251216 14.508 100 29 58 0 1409892368 12343248 7297904
20:45:34 17788 8192000000 10302741904 10728046592 1385333232 14.464 99 43 83 0 1410203248 14801824 10068192
20:47:07 11071 8192000000 10331636352 10755198976 1406155920 14.650 100 46 92 0 1441060272 23795808 11108544
20:48:24 3581 8192000000 10329219072 10753093632 1400677328 14.601 99 59 117 0 1435345312 20438064 14229920
20:49:30 2598 8192000000 10347293680 10773360640 1422123632 14.792 100 52 103 1 1458867952 24187360 12556960
20:50:36 5136 8192000000 10331542080 10756632576 1405338368 14.643 99 53 106 0 1445697040 26850896 13507776
20:51:51 4657 8192000000 10348152608 10772975616 1415306960 14.731 100 60 120 0 1455017456 24721792 14988704
20:53:04 3389 8192000000 10342341584 10761510912 1401249632 14.606 100 59 116 0 1446202432 30077584 14875216
20:54:14 5744 8192000000 10348335744 10772852736 1408108944 14.667 100 54 105 1 1457330128 35487472 13733712
20:55:25 5425 8192000000 10317973968 10729934848 1375749200 14.379 100 67 132 0 1423222272 30165360 17307712
20:56:36 14654 8192000000 10319797232 10742054912 1379810512 14.415 100 45 89 2 1423160624 31613120 11736992
20:57:56 2361 8192000000 10334967856 10740617216 1367085024 14.301 99 85 170 1 1429302272 41273504 20943744
20:59:07 15212 8192000000 10310390080 10734034944 1352280768 14.168 98 42 81 0 1395161232 32575680 10304784
21:00:27 16342 8192000000 10309733232 10742038528 1378285952 14.401 100 35 70 0 1416251184 29120384 8844848
21:01:56 21006 8192000000 10312763776 10745671680 1377327376 14.393 100 24 47 1 1415918192 31956304 6634512
21:03:23 21679 8192000000 10289895344 10718912512 1354844384 14.191 99 22 44 1 1387603776 26812912 5946480
21:04:48 19327 8192000000 10279729392 10712707072 1322255168 13.897 98 30 59 1 1369662144 39281312 8125664
21:06:08 35119 8192000000 10265995104 10708140032 1324061440 13.913 98 20 41 0 1360707456 31000544 5645472
21:07:45 66677 8192000000 10246257056 10678861824 1271449424 13.435 94 19 39 1 1287585008 11429152 4706432
21:09:52 104459 8192000000 10271975936 10708930560 1349327024 14.141 99 35 71 0 1365776160 9029440 7419696
21:12:46 31612 8192000000 10281522544 10715213824 1356205216 14.203 100 30 60 1 1378753104 16007568 6540320
21:14:29 65349 8192000000 10267338176 10733461504 1355503376 14.197 99 18 37 0 1381768736 21976544 4288816
21:16:37 58741 8192000000 10269951184 10718314496 1363775120 14.271 99 26 52 0 1377802240 8090160 5936960
21:18:42 28048 8192000000 10281379424 10736525312 1367728880 14.307 99 18 37 0 1385880768 13681248 4470640
21:20:11 75021 8192000000 10286599632 10743926784 1382863504 14.442 100 12 24 0 1403868720 17884576 3120640
21:22:42 68812 8192000000 10276422256 10724032512 1367001584 14.300 99 16 32 0 1390450096 19796784 3651728
21:24:53 56904 8192000000 10298739616 10752528384 1392606752 14.529 100 11 22 0 1404470320 9416480 2447088
21:27:19 65282 8192000000 10301514688 10756857856 1390098368 14.507 99 25 51 2 1408044832 12438672 5507792
21:29:25 43891 8192000000 10334515568 10778734592 1411220000 14.695 100 46 92 0 1438710560 16869296 10621264
21:31:10 44642 8192000000 10326292384 10778599424 1400857216 14.603 99 21 42 0 1417564080 12104048 4602816
21:33:06 50144 8192000000 10298540368 10753908736 1376032816 14.381 98 15 30 0 1393563616 14267760 3263040
21:35:03 32735 8192000000 10325799952 10771025920 1410131104 14.685 100 29 57 0 1428034800 11871936 6031760
21:36:36 75011 8192000000 10322817184 10782699520 1406488192 14.653 100 23 44 1 1426368304 14781616 5098496
21:38:51 97071 8192000000 10278672128 10730029056 1337287952 14.033 95 28 54 1 1351218032 7626272 6303808
21:41:29 44583 8192000000 10275113488 10719924224 1343737152 14.091 97 28 54 1 1358602448 8816112 6049184
21:43:18 49680 8192000000 10261814144 10712145920 1326220544 13.933 96 19 38 0 1340703392 10350256 4132592
21:45:08 51500 8192000000 10263774544 10715668480 1347571248 14.126 98 8 16 0 1363068080 13405984 2090848
21:47:00 105151 8192000000 10264733504 10731167744 1324993952 13.922 97 11 22 0 1347186800 19175584 3017264
21:49:48 45193 8192000000 10269639424 10727133184 1318494928 13.863 96 18 36 0 1340172992 17379440 4298624
21:51:35 56077 8192000000 10261737680 10707873792 1311160224 13.797 96 26 51 1 1329716704 12500000 6056480
21:53:32 84382 8192000000 10281443952 10711318528 1334039584 14.004 98 23 46 0 1351521984 12092240 5390160
21:55:59 44826 8192000000 10272869328 10715734016 1327987024 13.949 98 38 76 0 1351969200 15771632 8210544
21:57:47 50667 8192000000 10273773824 10715607040 1341066320 14.067 99 15 30 0 1353516896 9265216 3185360
21:59:45 98189 8192000000 10250958944 10691862528 1281305840 13.525 94 8 16 0 1300510160 17312048 1892272
22:02:26 56922 8192000000 10258458864 10709098496 1343110208 14.085 99 38 75 0 1361478896 9518672 8850016
22:04:55 38750 8192000000 10261286336 10708324352 1348448496 14.134 99 20 41 0 1361117456 7374384 5294576
22:06:38 83714 8192000000 10249514752 10707701760 1334699376 14.010 97 14 28 0 1348590608 10337520 3553712
22:09:13 64774 8192000000 10258322256 10702233600 1326528608 13.936 96 16 32 0 1341647984 11199520 3919856
22:11:22 94700 8192000000 10265316624 10723442688 1364415840 14.277 100 11 21 1 1378941824 12207440 2318544
22:13:57 69448 8192000000 10279717552 10737360896 1382148672 14.436 100 13 26 0 1393764096 9282912 2332512
22:16:14 12257 8192000000 10305133632 10750414848 1391418512 14.519 100 60 120 1 1417200672 12304976 13477184
22:17:45 127722 8192000000 10315731728 10772090880 1394929520 14.550 99 16 32 1 1411474480 13237136 3307824
22:20:54 27390 8192000000 10285776208 10735284224 1339837984 14.056 96 23 44 0 1353279216 8496512 4944720
22:22:27 89789 8192000000 10301287952 10757193728 1368033872 14.309 98 9 18 0 1380549504 10720368 1795264
22:25:02 108446 8192000000 10290991552 10747035648 1345891248 14.110 97 18 36 0 1363209856 13574416 3744192
22:27:59 45559 8192000000 10281321376 10746273792 1360828288 14.245 98 10 18 0 1376284256 13592848 1863120
22:29:45 62416 8192000000 10280060656 10740645888 1376716560 14.387 99 9 18 0 1388038160 9408016 1913584
22:31:47 43188 8192000000 10264628176 10724835328 1340737984 14.064 97 16 32 0 1354653792 10273888 3641920
22:33:36 57658 8192000000 10258096576 10712203264 1310188928 13.788 95 8 16 0 1324505408 12487392 1829088
22:35:51 61879 8192000000 10287789152 10733813760 1372428320 14.349 100 27 54 0 1391455024 12346416 6680288
22:37:54 43287 8192000000 9633710272 9978826752 760348480 8.493 56 19 38 0 775614864 11394416 3871968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-01-2023 22:40:02 to 10-01-2023 22:40:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 117 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-01-2023 22:40:18 to 10-01-2023 22:40:20.

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          239585
MON$OLDEST_ACTIVE               239586
MON$OLDEST_SNAPSHOT             239586
MON$NEXT_TRANSACTION            239586
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                       2016832
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.33652 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33652 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33652 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 ---
:308: 
:309: mscs-test-161.budzdorov.ru    Tue Jan 10 22:38:57 2023
:310:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:311: 
--- end of diff output ---

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

10.01.23 22:40:22. 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
2023-01-10 22:40:24 No severe PSQL-related problems occured
10.01.23 22:40:25. End of report.