Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.05.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 16287284 kB
MemAvailable 31490124 kB
Buffers 0 kB
Cached 15450316 kB
SwapCached 2668 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-15 01:38:12
DTS_END                         2022-05-15 01:38:12.4140
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/34158
TRN_ID                          240517
ATT_ID                          662
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: 9683.61 20267 1162033
customer order: creation 172.05 358 20646
customer order: refuse 14.93 311 1792
order to supplier: creation 46.13 1212 5535
order to supplier: removal 3.65 110179 438
invoice (draft): creation 45.60 3467 5472
invoice (draft): removal 55.99 895 6719
invoice accept: apply 41.51 17603 4981
invoice accept: cancel 50.98 701 6117
customer reserve: creation 3931.00 169 471720
customer reserve: removal 5014.63 62 601756
realization accept: apply 84.93 153 10192
realization accept: cancel 93.73 100 11247
payment from customer: creation 59.31 112 7117
payment from customer: removal 5.37 65 644
payment to supplier: creation 48.91 114 5869
payment to supplier: removal 3.34 68 401
service: total inventory turnovers 4.45 1464 534
service: total monetary turnovers 6.37 29 764
service: aggregate perf. data 0.71 22306 85
service: refresh index statistics 0.03 245964 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-05-2022 01:40:01 to 15-05-2022 01:40:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2446
TEST_TIME 2 3497
TEST_TIME 3 4385
TEST_TIME 4 5329
TEST_TIME 5 5737
TEST_TIME 6 5418
TEST_TIME 7 5798
TEST_TIME 8 5664
TEST_TIME 9 5693
TEST_TIME 10 5592
TEST_TIME 11 5647
TEST_TIME 12 5871
TEST_TIME 13 6414
TEST_TIME 14 6495
TEST_TIME 15 7257
TEST_TIME 16 7382
TEST_TIME 17 7315
TEST_TIME 18 7591
TEST_TIME 19 7688
TEST_TIME 20 7892
TEST_TIME 21 8169
TEST_TIME 22 8220
TEST_TIME 23 8838
TEST_TIME 24 9053
TEST_TIME 25 9118
TEST_TIME 26 9590
TEST_TIME 27 10832
TEST_TIME 28 11474
TEST_TIME 29 11881
TEST_TIME 30 11644
TEST_TIME 31 11660
TEST_TIME 32 11464
TEST_TIME 33 11331
TEST_TIME 34 11148
TEST_TIME 35 10939
TEST_TIME 36 10813
TEST_TIME 37 10853
TEST_TIME 38 10832
TEST_TIME 39 10694
TEST_TIME 40 10715
TEST_TIME 41 10595
TEST_TIME 42 10414
TEST_TIME 43 10351
TEST_TIME 44 10332
TEST_TIME 45 10201
TEST_TIME 46 10578
TEST_TIME 47 10460
TEST_TIME 48 10380
TEST_TIME 49 10387
TEST_TIME 50 10350
TEST_TIME 51 10183
TEST_TIME 52 10080
TEST_TIME 53 10016
TEST_TIME 54 9981
TEST_TIME 55 9919
TEST_TIME 56 9839
TEST_TIME 57 9745
TEST_TIME 58 9765
TEST_TIME 59 9729
TEST_TIME 60 9754
TEST_TIME 61 9760
TEST_TIME 62 9790
TEST_TIME 63 9935
TEST_TIME 64 9918
TEST_TIME 65 9905
TEST_TIME 66 9963
TEST_TIME 67 9994
TEST_TIME 68 10079
TEST_TIME 69 10021
TEST_TIME 70 10031
TEST_TIME 71 10090
TEST_TIME 72 10141
TEST_TIME 73 10030
TEST_TIME 74 9939
TEST_TIME 75 9921
TEST_TIME 76 9909
TEST_TIME 77 9884
TEST_TIME 78 9804
TEST_TIME 79 9835
TEST_TIME 80 9764
TEST_TIME 81 9745
TEST_TIME 82 9739
TEST_TIME 83 9668
TEST_TIME 84 9742
TEST_TIME 85 9792
TEST_TIME 86 9878
TEST_TIME 87 9850
TEST_TIME 88 9838
TEST_TIME 89 9833
TEST_TIME 90 9876
TEST_TIME 91 9886
TEST_TIME 92 9975
TEST_TIME 93 9956
TEST_TIME 94 9971
TEST_TIME 95 9920
TEST_TIME 96 9850
TEST_TIME 97 9879
TEST_TIME 98 9808
TEST_TIME 99 9758
TEST_TIME 100 9721
TEST_TIME 101 9651
TEST_TIME 102 9636
TEST_TIME 103 9648
TEST_TIME 104 9608
TEST_TIME 105 9640
TEST_TIME 106 9630
TEST_TIME 107 9685
TEST_TIME 108 9664
TEST_TIME 109 9651
TEST_TIME 110 9650
TEST_TIME 111 9609
TEST_TIME 112 9726
TEST_TIME 113 9773
TEST_TIME 114 9806
TEST_TIME 115 9815
TEST_TIME 116 9857
TEST_TIME 117 9834
TEST_TIME 118 9764
TEST_TIME 119 9753
TEST_TIME 120 9685

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-05-2022 01:40:05 to 15-05-2022 01:40:08.

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 1597107 1597107 0 0.00 0 28264 6 0 0
doc_list_biud 1597107 1597107 0 0.00 0 58682 30 0 0
sp_add_invoice_to_stock 5121 4981 140 2.73 35 192804 17603 0 140
sp_cancel_adding_invoice 6117 6117 0 0.00 5 64068 702 0 0
sp_cancel_client_order 1792 1792 0 0.00 4 7795 312 0 0
sp_cancel_customer_reserve 601756 601756 0 0.00 1 49389 63 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 3 5691 65 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 4287 68 0 0
sp_cancel_supplier_invoice 6731 6719 12 0.17 2 58696 895 0 12
sp_cancel_supplier_order 438 438 0 0.00 7 1214049 110180 0 0
sp_cancel_write_off 11247 11247 0 0.00 1 8906 101 0 0
sp_client_order 20646 20646 0 0.00 3 42061 359 0 0
sp_customer_reserve 471720 471720 0 0.00 0 87687 170 0 0
sp_fill_shopping_cart 31639 31639 0 0.00 1 12625 35 0 0
sp_fill_shopping_cart_clo_res 471718 471718 0 0.00 0 17910 15 0 0
sp_get_clo_for_invoice 4979 4979 0 0.00 0 30164 362 0 0
sp_kill_cost_storno 18409 18409 0 0.00 0 8426 51 0 0
sp_kill_qstorno_ret_qs2qd 1221916 1221916 0 0.00 0 58676 34 0 0
sp_kill_qty_storno 643240 643240 0 0.00 0 58679 73 0 0
sp_lock_selected_doc 20954 20954 0 0.00 0 7553 5 0 0
sp_make_cost_storno 28157 28157 0 0.00 0 28262 53 0 0
sp_multiply_rows_for_pdistr 28157 28157 0 0.00 0 9113 29 0 0
sp_multiply_rows_for_qdistr 20646 20646 0 0.00 0 34700 184 0 0
sp_pay_from_customer 7117 7117 0 0.00 4 28267 112 0 0
sp_pay_to_supplier 5869 5869 0 0.00 4 14871 114 0 0
sp_payment_common 12986 12986 0 0.00 1 10213 22 0 0
sp_qd_handle_on_cancel_clo 1792 1792 0 0.00 0 6330 284 0 0
sp_qd_handle_on_invoice_upd_sts 11096 11096 0 0.00 0 27199 307 0 0
sp_qd_handle_on_reserve_upd_sts 10192 10192 0 0.00 0 11717 47 0 0
sp_reserve_write_off 10192 10192 0 0.00 6 13844 154 0 0
sp_supplier_invoice 5472 5472 0 0.00 6 131623 3468 0 0
sp_supplier_order 5535 5535 0 0.00 5 40424 1212 0 0
srv_aggregate_perf_data 85 85 0 0.00 2634 154371 22307 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2207 137425 49212 0 0
srv_find_qd_qs_mism 1113166 1113166 0 0.00 0 10722 4 0 0
srv_make_invnt_saldo 534 534 0 0.00 48 138503 1464 0 0
srv_make_money_saldo 764 764 0 0.00 0 5354 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 145983 352652 245964 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4981 4981 0 0.00 0 20202 179 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 8198 116 0 0
t$perf-norm:sp_cancel_client_order 1792 1792 0 0.00 0 3005 9 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 4675 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 4841 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 432 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 3488 20 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 35274 1503 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 2751 13 0 0
t$perf-norm:sp_client_order 20646 20646 0 0.00 0 10556 15 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 2430 7 0 0
t$perf-norm:sp_pay_from_customer 7117 7117 0 0.00 0 8540 11 0 0
t$perf-norm:sp_pay_to_supplier 5869 5869 0 0.00 0 6796 10 0 0
t$perf-norm:sp_reserve_write_off 10192 10192 0 0.00 0 17610 12 0 0
t$perf-norm:sp_supplier_invoice 5472 5472 0 0.00 0 18839 24 0 0
t$perf-norm:sp_supplier_order 5535 5535 0 0.00 0 8081 17 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 84 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1152 35 0 0
t$perf-norm:srv_make_invnt_saldo 534 534 0 0.00 0 599 14 0 0
t$perf-norm:srv_make_money_saldo 764 764 0 0.00 0 494 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10236 9956 280 2.73 0 6136 17 0 280
v_all_customers 37592 37592 0 0.00 0 81 0 0 0
v_all_suppliers 30508 30508 0 0.00 0 14 1 0 0
v_all_wares 41292 41292 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 1998 9 0 0
v_cancel_client_order 3584 3584 0 0.00 0 1319 3 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 2664 7 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 0 1861 6 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 946 3 0 0
v_cancel_supplier_invoice 808 784 24 2.97 0 5831 18 0 24
v_cancel_supplier_order 876 876 0 0.00 0 1884 8 0 0
v_cancel_write_off 1988 1988 0 0.00 0 1006 10 0 0
v_max_id_clo_ord 5535 5535 0 0.00 0 4159 3 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 6280 37 0 0
v_max_id_ord_sup 5458 5458 0 0.00 0 7226 8 0 0
v_max_non_paid_invoice 1608 1608 0 0.00 0 811 2 0 0
v_max_non_paid_realizn 3776 3776 0 0.00 0 5014 5 0 0
v_min_id_clo_ord 5535 5535 0 0.00 0 1970 5 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 2072 8 0 0
v_min_id_ord_sup 5458 5458 0 0.00 0 5888 5 0 0
v_min_non_paid_invoice 5869 5869 0 0.00 0 4200 12 0 0
v_min_non_paid_realizn 7117 7117 0 0.00 0 8525 17 0 0
v_our_firm 10382 10382 0 0.00 0 111 1 0 0
v_reserve_write_off 20384 20384 0 0.00 0 2530 4 0 0
x_lock_depdocs_on_canc_invoice 12444 12444 0 0.00 0 28335 110 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 6796 337 0 0
x_make_qstorno_1000_1200 5535 5535 0 0.00 1 40416 1143 0 0
x_make_qstorno_1200_2000 5472 5472 0 0.00 3 131605 3330 0 0
x_make_qstorno_2100_3300 471720 471720 0 0.00 0 87687 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-05-2022 01:40:09 to 15-05-2022 01:40:13.

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:16 3 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:17 34007 8192000000 9901865200 10209513472 979262672 10.677 100 88 176 0 1008595840 5782800 23550368
23:10:58 19443 8192000000 9938826528 10258206720 1019356096 11.066 100 84 168 1 1049321184 7773920 22191168
23:12:36 16740 8192000000 9978684224 10310692864 1065698976 11.511 100 80 160 0 1094747616 8871600 20177040
23:14:11 11534 8192000000 10052893712 10403590144 1138325264 12.200 100 80 160 0 1168266640 10819216 19122160
23:15:24 8183 8192000000 10111827552 10476355584 1195797584 12.737 100 78 156 1 1226324608 12878096 17648928
23:16:34 5125 8192000000 10141796336 10515677184 1227689744 13.033 100 75 149 1 1256423936 12399024 16335168
23:17:45 2905 8192000000 10185724768 10564550656 1266704848 13.391 100 85 170 2 1298488752 13456656 18327248
23:18:49 1195 8192000000 10211060368 10592374784 1288619376 13.592 100 96 191 2 1324515312 14782112 21113824
23:19:50 7759 8192000000 10192213952 10601381888 1301513040 13.709 100 46 92 0 1322295952 10781312 10001600
23:21:00 6555 8192000000 10192152064 10605166592 1309148608 13.778 100 23 46 2 1321267696 7010080 5109008
23:22:08 10137 8192000000 10209698512 10626306048 1323735792 13.911 100 18 36 2 1334026064 6270976 4019296
23:23:20 16160 8192000000 10222110064 10639761408 1335272752 14.015 100 24 48 1 1347132960 6687184 5173024
23:24:43 28434 8192000000 10237771152 10657828864 1340022304 14.058 100 23 46 2 1351964080 6730112 5211664
23:26:12 7284 8192000000 10236169888 10658406400 1342733792 14.082 100 22 44 0 1353576160 5857504 4984864
23:27:20 11277 8192000000 10227604672 10645024768 1334182720 14.005 100 23 42 2 1344550624 5519248 4848656
23:28:34 66225 8192000000 10164223376 10559586304 1203635488 12.810 91 37 73 3 1216592848 4162048 8795312
23:30:40 4947 8192000000 10144828992 10538971136 1213054912 12.897 96 41 78 4 1233794752 12118240 8621600
23:31:47 4420 8192000000 10131671104 10521944064 1186307152 12.649 95 31 60 2 1199344160 5530224 7506784
23:32:53 15007 8192000000 10152248768 10556346368 1258072960 13.312 100 34 67 3 1271613104 5098320 8441824
23:34:24 8150 8192000000 10168549856 10574950400 1276057072 13.477 100 25 49 0 1288404848 6607264 5740512
23:35:34 9875 8192000000 10178148720 10592157696 1288988880 13.595 100 28 56 1 1302457568 7418720 6049968
23:36:45 4891 8192000000 10185061472 10601046016 1297511472 13.673 100 25 50 2 1309326848 5721520 6093856
23:37:54 116292 8192000000 10225474272 10641801216 1321473360 13.890 100 30 60 0 1336192096 7626416 7092320
23:40:53 6800 8192000000 10270608160 10681700352 1357304912 14.213 100 58 116 1 1388594256 17607056 13682288
23:42:03 13048 8192000000 10295044640 10707984384 1372395056 14.349 100 60 120 0 1408909888 21909088 14605744
23:43:18 21580 8192000000 10295461744 10715291648 1376307856 14.384 100 45 90 1 1409612112 22377984 10926272
23:44:43 8781 8192000000 10321710496 10737762304 1390275632 14.508 100 61 122 1 1427941552 23125808 14540112
23:45:54 13530 8192000000 10317531472 10741424128 1390582320 14.511 99 49 98 0 1424952560 22803088 11567152
23:47:13 14363 8192000000 10343310208 10765086720 1415403104 14.732 100 53 106 0 1455879856 27695056 12781696
23:48:34 5493 8192000000 10360163264 10781261824 1422797968 14.798 100 59 117 0 1467516720 30668320 14050432
23:49:44 6922 8192000000 10364424288 10781782016 1407731280 14.664 99 55 108 2 1456783824 35096896 13955648
23:50:55 5546 8192000000 10353809104 10769879040 1393513488 14.537 99 56 109 0 1446527840 38396272 14618080
23:52:03 6318 8192000000 10330584464 10748112896 1377372736 14.393 99 60 120 0 1434831616 41966272 15492608
23:53:15 4889 8192000000 10359876448 10772426752 1395424672 14.554 100 76 152 1 1463066736 47774976 19867088
23:54:25 2734 8192000000 10359294784 10778484736 1406870896 14.656 100 71 140 2 1463111504 38055136 18185472
23:55:33 5451 8192000000 10347925184 10758094848 1388938128 14.496 100 67 133 0 1452180816 45838064 17404624
23:56:45 3719 8192000000 10383566144 10793893888 1407693600 14.663 100 85 172 3 1485036160 54497504 22845056
23:57:53 2207 8192000000 10398503488 10812489728 1424870624 14.816 100 72 144 1 1498844048 55219776 18753648
23:59:02 6779 8192000000 10371340880 10787229696 1401266832 14.606 100 58 116 0 1473928592 56835440 15826320
00:00:14 18151 8192000000 10356859488 10776051712 1385302624 14.464 99 46 91 2 1454877184 56989600 12584960
00:01:34 13022 8192000000 10361896368 10797748224 1397678416 14.574 100 38 76 1 1471971232 63129232 11163584
00:03:34 19143 8192000000 10336772224 10772910080 1368537696 14.314 100 34 68 1 1452179840 72510448 11131696
00:04:55 22241 8192000000 10307091056 10745188352 1332604640 13.991 99 31 62 0 1417187136 74956192 9626304
00:06:18 34370 8192000000 10281840576 10740334592 1350502160 14.152 99 18 36 0 1384683888 29814992 4366736
00:07:53 75768 8192000000 10285238240 10728943616 1321373152 13.889 96 6 12 0 1335928416 13430128 1125136
00:10:09 54937 8192000000 10291351792 10728144896 1374735200 14.369 100 25 52 1 1390318832 8302496 7281136
00:12:11 137425 8192000000 10281881568 10725908480 1319925264 13.876 96 27 54 1 1334140816 7583392 6632160
00:16:03 99000 8192000000 10305280160 10751954944 1391834448 14.522 100 24 48 0 1412812144 15734992 5242704
00:18:43 108413 8192000000 10320379840 10762141696 1379653280 14.413 99 12 24 1 1394743344 12227792 2862272
00:21:36 47381 8192000000 10322834496 10771038208 1397815712 14.576 100 17 34 1 1419795232 17494960 4484560
00:23:24 26638 8192000000 10329315472 10772815872 1393171072 14.534 100 41 80 1 1426282800 23140576 9971152
00:24:53 58796 8192000000 10306253456 10768113664 1383895856 14.451 99 8 16 0 1399253968 13552448 1805664
00:27:05 22078 8192000000 10286953456 10754379776 1377129104 14.391 99 4 8 0 1391355568 13224160 1002304
00:28:44 69491 8192000000 10292522864 10744741888 1378174512 14.400 99 8 16 0 1385980400 6231824 1574064
00:31:08 78947 8192000000 10294798192 10758168576 1386242624 14.472 100 22 43 0 1403976464 13145056 4588784
00:33:28 63037 8192000000 10274757904 10736558080 1348679168 14.136 97 9 18 0 1360525376 9761648 2084560
00:35:31 71718 8192000000 10267454720 10736529408 1342545824 14.080 97 18 36 1 1357174608 10193856 4434928
00:37:45 42885 8192000000 10269077008 10725892096 1348684112 14.136 98 16 32 0 1360900640 8303040 3913488
00:39:30 25751 8192000000 10282697264 10731438080 1356075424 14.202 99 26 52 1 1374955056 13428448 5451184
00:40:56 45921 8192000000 10277703728 10735415296 1352340240 14.169 98 13 26 1 1364666656 9409600 2916816
00:42:44 21895 8192000000 10305082672 10744111104 1374376960 14.366 99 36 71 2 1389842944 7944624 7521360
00:44:07 29546 8192000000 10285416112 10733694976 1352473632 14.170 98 25 50 0 1371242512 13304656 5464224
00:45:38 64437 8192000000 10249603152 10702237696 1276220256 13.478 94 10 19 0 1287184160 8975168 1988736
00:47:50 25974 8192000000 10266278784 10699276288 1323378720 13.907 98 31 62 1 1340828528 10582976 6866832
00:49:16 103023 8192000000 10240560816 10692784128 1263620208 13.363 94 25 50 0 1280248464 11419392 5208864
00:52:01 120470 8192000000 10256578560 10712735744 1335439808 14.016 99 24 48 0 1351245216 10387504 5417904
00:55:19 57341 8192000000 10244823296 10706771968 1339987696 14.057 99 7 14 0 1350968384 9456368 1524320
00:57:16 48288 8192000000 10249586752 10717057024 1356945920 14.210 100 9 19 1 1369835312 10577296 2312096
00:59:06 86231 8192000000 10268299008 10734497792 1326600592 13.936 97 15 30 1 1348566848 18507936 3458320
01:01:32 65039 8192000000 10289560480 10738847744 1352323840 14.168 99 21 42 0 1371437632 14190736 4923056
01:03:51 96811 8192000000 10283609456 10742665216 1342093920 14.076 98 11 22 1 1361935728 17271040 2570768
01:06:28 41121 8192000000 10292949488 10736930816 1361683552 14.252 99 20 40 1 1378983936 12842240 4458144
01:08:13 70821 8192000000 10297741456 10744881152 1366429488 14.295 99 32 64 2 1383420848 10293392 6697968
01:10:24 97852 8192000000 10294121376 10750713856 1344617024 14.099 97 21 42 0 1360444800 11195456 4632320
01:13:02 89439 8192000000 10306819424 10756923392 1367205488 14.302 98 41 82 0 1390957232 14204176 9547568
01:15:52 52028 8192000000 10277671968 10751102976 1377258768 14.392 100 6 12 1 1392930960 14207392 1464800
01:17:50 84310 8192000000 10284096560 10749136896 1372215040 14.347 99 15 30 0 1383014880 7500736 3299104
01:20:14 62207 8192000000 10275649728 10745364480 1361085008 14.247 98 14 28 0 1387132416 22732304 3315104
01:22:24 38805 8192000000 10310392736 10757419008 1385055360 14.462 99 29 60 2 1400085424 8414688 6615376
01:24:13 81122 8192000000 10295116800 10763231232 1369715648 14.324 98 9 18 2 1383875808 12156992 2003168
01:26:43 37064 8192000000 10279285600 10733199360 1345375872 14.106 97 24 48 0 1359615456 8862496 5377088
01:28:27 55262 8192000000 10302553152 10739105792 1348228416 14.132 98 31 62 1 1366539408 11810816 6500176
01:31:03 133307 8192000000 10276536464 10723348480 1312168528 13.806 95 18 35 0 1337133152 21486352 3478272
01:34:23 73673 8192000000 10280045424 10738294784 1339132272 14.050 98 13 27 0 1358795296 16788016 2875008
01:36:44 84700 8192000000 10250100928 10700402688 1311693520 13.801 96 22 44 0 1323460128 7199760 4566848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-05-2022 01:40:15 to 15-05-2022 01:40:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-05-2022 01:40:31 to 15-05-2022 01:40:33.

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          239330
MON$OLDEST_ACTIVE               240768
MON$OLDEST_SNAPSHOT             240768
MON$NEXT_TRANSACTION            240768
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                       2056928
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
:321: 
:322: mscs-test-161.budzdorov.ru    Sun May 15 01:38:14 2022
:323:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:324: 
:325: 
:326: mscs-test-161.budzdorov.ru    Sun May 15 01:38:19 2022
:327:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:328: 
:329: 
:330: mscs-test-161.budzdorov.ru    Sun May 15 01:38:26 2022
:331:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:332: 
--- end of diff output ---

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

15.05.22 01:40:34. 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-05-15 01:40:36 No severe PSQL-related problems occured
15.05.22 01:40:37. End of report.