Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.07.2022 01:40
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 16119300 kB
MemAvailable 31030160 kB
Buffers 0 kB
Cached 15626036 kB
SwapCached 2512 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-07-31 01:38:26
DTS_END                         2022-07-31 01:38:26.6240
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54580
TRN_ID                          229252
ATT_ID                          692
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: 8869.13 25700 1064296
customer order: creation 160.25 406 19230
customer order: refuse 14.53 430 1743
order to supplier: creation 43.18 1425 5181
order to supplier: removal 3.32 105664 398
invoice (draft): creation 41.80 3551 5016
invoice (draft): removal 51.87 790 6224
invoice accept: apply 39.19 20575 4703
invoice accept: cancel 46.50 688 5580
customer reserve: creation 3576.60 207 429192
customer reserve: removal 4618.90 60 554268
realization accept: apply 77.08 201 9249
realization accept: cancel 78.18 111 9382
payment from customer: creation 53.61 113 6433
payment from customer: removal 5.34 91 641
payment to supplier: creation 44.85 129 5382
payment to supplier: removal 3.11 75 373
service: total inventory turnovers 4.33 1296 520
service: total monetary turnovers 5.83 41 699
service: aggregate perf. data 0.65 29593 78
service: refresh index statistics 0.03 348547 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-07-2022 01:40:38 to 31-07-2022 01:40:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3510
TEST_TIME 2 3027
TEST_TIME 3 3674
TEST_TIME 4 4007
TEST_TIME 5 4219
TEST_TIME 6 4384
TEST_TIME 7 4673
TEST_TIME 8 5001
TEST_TIME 9 5264
TEST_TIME 10 5641
TEST_TIME 11 5933
TEST_TIME 12 6646
TEST_TIME 13 6735
TEST_TIME 14 6855
TEST_TIME 15 7120
TEST_TIME 16 7038
TEST_TIME 17 7434
TEST_TIME 18 7823
TEST_TIME 19 8102
TEST_TIME 20 8181
TEST_TIME 21 8105
TEST_TIME 22 8107
TEST_TIME 23 8597
TEST_TIME 24 8454
TEST_TIME 25 8805
TEST_TIME 26 9657
TEST_TIME 27 10126
TEST_TIME 28 10598
TEST_TIME 29 10351
TEST_TIME 30 10237
TEST_TIME 31 10161
TEST_TIME 32 10012
TEST_TIME 33 9799
TEST_TIME 34 9694
TEST_TIME 35 9574
TEST_TIME 36 9562
TEST_TIME 37 9497
TEST_TIME 38 9487
TEST_TIME 39 9441
TEST_TIME 40 9546
TEST_TIME 41 9445
TEST_TIME 42 9255
TEST_TIME 43 9129
TEST_TIME 44 9188
TEST_TIME 45 9107
TEST_TIME 46 8963
TEST_TIME 47 8941
TEST_TIME 48 9030
TEST_TIME 49 8980
TEST_TIME 50 8892
TEST_TIME 51 8892
TEST_TIME 52 8855
TEST_TIME 53 8823
TEST_TIME 54 8766
TEST_TIME 55 8813
TEST_TIME 56 8776
TEST_TIME 57 8782
TEST_TIME 58 8798
TEST_TIME 59 8850
TEST_TIME 60 8827
TEST_TIME 61 9031
TEST_TIME 62 9065
TEST_TIME 63 9038
TEST_TIME 64 9124
TEST_TIME 65 9188
TEST_TIME 66 9118
TEST_TIME 67 9198
TEST_TIME 68 9295
TEST_TIME 69 9336
TEST_TIME 70 9222
TEST_TIME 71 9123
TEST_TIME 72 9084
TEST_TIME 73 9137
TEST_TIME 74 9059
TEST_TIME 75 9021
TEST_TIME 76 8989
TEST_TIME 77 8969
TEST_TIME 78 8936
TEST_TIME 79 8909
TEST_TIME 80 8927
TEST_TIME 81 8984
TEST_TIME 82 8969
TEST_TIME 83 8964
TEST_TIME 84 9110
TEST_TIME 85 9072
TEST_TIME 86 9162
TEST_TIME 87 9176
TEST_TIME 88 9120
TEST_TIME 89 9159
TEST_TIME 90 9151
TEST_TIME 91 9201
TEST_TIME 92 9115
TEST_TIME 93 9073
TEST_TIME 94 9053
TEST_TIME 95 9022
TEST_TIME 96 8999
TEST_TIME 97 8939
TEST_TIME 98 8893
TEST_TIME 99 8955
TEST_TIME 100 8939
TEST_TIME 101 8976
TEST_TIME 102 8982
TEST_TIME 103 8960
TEST_TIME 104 8999
TEST_TIME 105 9045
TEST_TIME 106 9043
TEST_TIME 107 9118
TEST_TIME 108 9155
TEST_TIME 109 9140
TEST_TIME 110 9139
TEST_TIME 111 9157
TEST_TIME 112 9127
TEST_TIME 113 9072
TEST_TIME 114 9023
TEST_TIME 115 9026
TEST_TIME 116 8969
TEST_TIME 117 8929
TEST_TIME 118 8976
TEST_TIME 119 8946
TEST_TIME 120 8873

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-07-2022 01:40:42 to 31-07-2022 01:40:45.

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 1462651 1462651 0 0.00 0 20777 7 0 0
doc_list_biud 1462651 1462651 0 0.00 0 94995 29 0 0
sp_add_invoice_to_stock 4818 4703 115 2.38 79 189228 20576 0 115
sp_cancel_adding_invoice 5580 5580 0 0.00 3 64765 688 0 0
sp_cancel_client_order 1743 1743 0 0.00 4 22036 431 0 0
sp_cancel_customer_reserve 554268 554268 0 0.00 0 22482 60 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 3 6701 91 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 3 2804 76 0 0
sp_cancel_supplier_invoice 6236 6224 12 0.19 1 95008 790 0 12
sp_cancel_supplier_order 398 398 0 0.00 6 1002578 105665 0 0
sp_cancel_write_off 9382 9382 0 0.00 1 10920 112 0 0
sp_client_order 19230 19230 0 0.00 3 23908 406 0 0
sp_customer_reserve 429192 429192 0 0.00 0 62996 208 0 0
sp_fill_shopping_cart 29417 29417 0 0.00 1 19734 42 0 0
sp_fill_shopping_cart_clo_res 429190 429190 0 0.00 0 17228 15 0 0
sp_get_clo_for_invoice 4700 4700 0 0.00 0 29642 344 0 0
sp_kill_cost_storno 15976 15976 0 0.00 0 7346 54 0 0
sp_kill_qstorno_ret_qs2qd 1124540 1124540 0 0.00 0 94988 32 0 0
sp_kill_qty_storno 591544 591544 0 0.00 0 94993 70 0 0
sp_lock_selected_doc 19430 19430 0 0.00 0 7643 7 0 0
sp_make_cost_storno 25764 25764 0 0.00 0 16524 54 0 0
sp_multiply_rows_for_pdistr 25764 25764 0 0.00 0 7649 29 0 0
sp_multiply_rows_for_qdistr 19230 19230 0 0.00 0 14825 159 0 0
sp_pay_from_customer 6433 6433 0 0.00 4 7208 114 0 0
sp_pay_to_supplier 5382 5382 0 0.00 5 15680 129 0 0
sp_payment_common 11815 11815 0 0.00 1 6815 26 0 0
sp_qd_handle_on_cancel_clo 1743 1743 0 0.00 0 22029 394 0 0
sp_qd_handle_on_invoice_upd_sts 10280 10280 0 0.00 0 22417 331 0 0
sp_qd_handle_on_reserve_upd_sts 9249 9249 0 0.00 0 18058 74 0 0
sp_reserve_write_off 9249 9249 0 0.00 6 18122 202 0 0
sp_supplier_invoice 5016 5016 0 0.00 7 66851 3552 0 0
sp_supplier_order 5181 5181 0 0.00 5 61601 1425 0 0
srv_aggregate_perf_data 78 78 0 0.00 1953 533006 29593 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 676 155683 44944 0 0
srv_find_qd_qs_mism 1020688 1020688 0 0.00 0 20776 6 0 0
srv_make_invnt_saldo 520 520 0 0.00 39 55283 1296 0 0
srv_make_money_saldo 699 699 0 0.00 1 9402 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 295060 491914 348547 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 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 4703 4703 0 0.00 0 13494 201 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 0 2438 86 0 0
t$perf-norm:sp_cancel_client_order 1743 1743 0 0.00 0 5709 16 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 1417 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 1135 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 230 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 387 9 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 28268 1767 0 0
t$perf-norm:sp_cancel_write_off 905 905 0 0.00 0 4371 14 0 0
t$perf-norm:sp_client_order 19230 19230 0 0.00 0 9095 20 0 0
t$perf-norm:sp_customer_reserve 883 883 0 0.00 0 908 6 0 0
t$perf-norm:sp_pay_from_customer 6433 6433 0 0.00 0 11536 13 0 0
t$perf-norm:sp_pay_to_supplier 5382 5382 0 0.00 0 4387 13 0 0
t$perf-norm:sp_reserve_write_off 9249 9249 0 0.00 0 8832 16 0 0
t$perf-norm:sp_supplier_invoice 5016 5016 0 0.00 0 6186 18 0 0
t$perf-norm:sp_supplier_order 5181 5181 0 0.00 0 6016 18 0 0
t$perf-norm:srv_aggregate_perf_data 78 78 0 0.00 0 53 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 191 6 0 0
t$perf-norm:srv_make_invnt_saldo 520 520 0 0.00 0 3034 23 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 2225 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 115 30 0 0
v_add_invoice_to_stock 9630 9400 230 2.38 0 3234 18 0 230
v_all_customers 34556 34556 0 0.00 0 25 1 0 0
v_all_suppliers 28370 28370 0 0.00 0 31 1 0 0
v_all_wares 38460 38460 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 236 6 0 0
v_cancel_client_order 3486 3486 0 0.00 0 2771 6 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 1120 7 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 1858 9 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 1497 8 0 0
v_cancel_supplier_invoice 788 764 24 3.04 0 1827 18 0 24
v_cancel_supplier_order 796 796 0 0.00 0 1768 8 0 0
v_cancel_write_off 1810 1810 0 0.00 0 2112 17 0 0
v_max_id_clo_ord 5181 5181 0 0.00 0 2340 2 0 0
v_max_id_clo_res 883 883 0 0.00 0 3451 22 0 0
v_max_id_ord_sup 5006 5006 0 0.00 0 4385 5 0 0
v_max_non_paid_invoice 1384 1384 0 0.00 0 3540 6 0 0
v_max_non_paid_realizn 3537 3537 0 0.00 0 3620 6 0 0
v_min_id_clo_ord 5181 5181 0 0.00 0 4784 10 0 0
v_min_id_clo_res 883 883 0 0.00 0 6806 15 0 0
v_min_id_ord_sup 5006 5006 0 0.00 0 5915 8 0 0
v_min_non_paid_invoice 5382 5382 0 0.00 0 6813 16 0 0
v_min_non_paid_realizn 6433 6433 0 0.00 0 6773 16 0 0
v_our_firm 9696 9696 0 0.00 0 25 1 0 0
v_reserve_write_off 18498 18498 0 0.00 0 3115 6 0 0
x_lock_depdocs_on_canc_invoice 11422 11422 0 0.00 0 16201 118 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 9311 351 0 0
x_make_qstorno_1000_1200 5181 5181 0 0.00 1 61594 1337 0 0
x_make_qstorno_1200_2000 5016 5016 0 0.00 3 63478 3401 0 0
x_make_qstorno_2100_3300 429192 429192 0 0.00 0 50925 190 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-07-2022 01:40:46 to 31-07-2022 01:40:50.

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:30 14 8192000000 8806706944 8820396032 11716432 0.142 3 3 4 0 11900336 76800 107104
23:09:32 23207 8192000000 9916315200 10227609600 995808080 10.838 100 91 181 0 1025426416 6466144 23152192
23:11:05 13516 8192000000 9940914128 10262601728 1025407360 11.124 100 93 186 0 1058280288 8908576 23964352
23:12:32 11568 8192000000 9984625008 10319925248 1069484912 11.547 100 82 164 0 1099886112 10018576 20382624
23:13:48 7399 8192000000 10034093840 10385653760 1124280992 12.067 100 82 164 0 1155057552 11394960 19381600
23:14:58 4718 8192000000 10077018800 10439135232 1163164144 12.433 100 68 134 0 1190572000 12333904 15073952
23:16:07 3334 8192000000 10120949424 10488885248 1205840784 12.831 100 79 158 0 1236117504 12286992 17989728
23:17:14 2174 8192000000 10143504720 10520588288 1236312736 13.112 100 88 172 1 1268097056 13141184 18643136
23:18:18 1051 8192000000 10188484336 10566213632 1269299984 13.415 100 91 180 1 1304732192 15404592 20027616
23:19:21 1676 8192000000 10214481152 10602704896 1301620464 13.710 100 89 176 0 1333992400 12540464 19831472
23:20:23 14025 8192000000 10216511056 10618863616 1314229952 13.824 100 58 116 0 1335710288 8500384 12979952
23:21:38 7796 8192000000 10216272256 10634149888 1327867776 13.948 100 22 44 0 1339624528 7279920 4476832
23:22:46 7797 8192000000 10212340032 10635145216 1330636960 13.973 100 18 36 0 1340813520 5530272 4646288
23:23:55 69468 8192000000 10237548240 10661044224 1345914880 14.111 100 30 61 0 1358842512 5474880 7452752
23:26:05 7658 8192000000 10241136608 10661863424 1347039504 14.121 100 24 49 0 1358029456 5518704 5471248
23:27:15 6903 8192000000 10240025920 10658910208 1333592720 14.000 99 22 44 1 1344404080 6082704 4728656
23:28:23 74413 8192000000 10219463280 10639212544 1292181088 13.624 96 24 48 1 1302638560 4890672 5566800
23:30:41 6664 8192000000 10164338448 10574094336 1256427456 13.297 98 39 79 0 1273101888 7572816 9101616
23:31:48 6156 8192000000 10124138528 10519064576 1220897328 12.970 99 21 42 1 1230229936 4492000 4840608
23:32:59 15663 8192000000 10145743936 10558226432 1262296256 13.351 100 36 73 0 1276971440 5114320 9560864
23:34:21 7340 8192000000 10159061424 10565140480 1260805376 13.337 99 18 36 0 1270505792 5593824 4106592
23:35:31 5289 8192000000 10187705232 10603188224 1293166592 13.633 100 36 72 1 1311529744 9781984 8581168
23:36:37 10375 8192000000 10199621248 10612944896 1304989408 13.741 100 33 66 0 1319353632 7091312 7272912
23:37:49 6604 8192000000 10199024496 10618527744 1312438384 13.808 100 17 34 1 1321372624 5189040 3745200
23:38:56 18738 8192000000 10218381344 10639060992 1328164096 13.951 100 24 48 0 1338861040 4337392 6359552
23:40:23 23776 8192000000 10248364272 10668695552 1347198800 14.122 100 48 96 0 1368472944 10821440 10452704
23:41:59 32825 8192000000 10271510752 10691186688 1360383344 14.241 100 37 73 2 1378978944 9919568 8676032
23:43:51 12002 8192000000 10297662704 10719387648 1384121344 14.453 100 50 99 0 1410744896 15003568 11619984
23:45:08 5388 8192000000 10313732720 10731462656 1396238912 14.561 100 60 118 1 1430108752 19938032 13931808
23:46:22 673 8192000000 10322906256 10731483136 1393379056 14.536 100 71 142 0 1429458720 19578720 16500944
23:47:30 3460 8192000000 10342982720 10759401472 1406896672 14.656 100 72 141 1 1448478112 25118064 16463376
23:48:40 7461 8192000000 10339577408 10760151040 1399171200 14.588 99 61 120 0 1441598368 27667712 14759456
23:49:53 11197 8192000000 10336069504 10752581632 1384532944 14.457 99 67 133 0 1440790032 40093040 16164048
23:51:11 3898 8192000000 10342394448 10761834496 1400733728 14.602 100 63 124 1 1452317760 36383808 15200224
23:52:23 2167 8192000000 10329963328 10739412992 1361521632 14.251 97 77 151 1 1420029536 39388240 19119664
23:53:33 2372 8192000000 10333763872 10746032128 1377195328 14.391 100 70 139 0 1441403872 46134592 18073952
23:54:39 1718 8192000000 10328789584 10736451584 1360810192 14.245 99 77 154 0 1427631712 47579888 19241632
23:55:45 8426 8192000000 10345256992 10757226496 1378172912 14.400 100 64 128 0 1447310176 53051072 16086192
23:56:59 2295 8192000000 10354138768 10757947392 1382796432 14.442 100 70 136 2 1447826384 49018512 16011440
23:58:05 17313 8192000000 10332399792 10754338816 1382979968 14.443 100 46 90 1 1440652464 45553904 12118592
23:59:27 24121 8192000000 10331190960 10757152768 1376874432 14.389 99 33 61 0 1432501392 47062816 8564144
00:01:22 22587 8192000000 10339052752 10777505792 1392123904 14.525 100 33 67 1 1453056896 51646288 9286704
00:03:11 23030 8192000000 10329954448 10759667712 1348864128 14.137 98 30 60 0 1413905136 56374336 8666672
00:04:35 47150 8192000000 10291830912 10734862336 1328516512 13.954 97 20 40 0 1383466384 49239360 5710512
00:06:25 3258 8192000000 10304233888 10735865856 1362174368 14.257 100 58 117 2 1403903776 27592640 14136768
00:07:32 102179 8192000000 10259404016 10706317312 1325304624 13.925 97 17 34 0 1341308848 11857024 4147200
00:10:34 43638 8192000000 10276831744 10716291072 1367140304 14.301 100 25 51 0 1380720608 7903936 5676368
00:12:30 32910 8192000000 10285585728 10724356096 1351401296 14.160 98 36 73 0 1369424080 9369808 8652976
00:14:04 40164 8192000000 10304437104 10746961920 1382716752 14.441 100 35 67 0 1408130832 17266304 8147776
00:15:47 52374 8192000000 10294918720 10753540096 1381845728 14.433 100 15 29 0 1403055120 18061376 3148016
00:17:49 66567 8192000000 10280536560 10744573952 1384326080 14.455 100 10 21 1 1401404240 14900784 2177376
00:19:56 58648 8192000000 10287218704 10736074752 1367248592 14.302 99 14 28 1 1381401664 10896640 3256432
00:22:11 56495 8192000000 10285580128 10742132736 1378367872 14.402 100 21 42 0 1397125616 14114800 4642944
00:24:17 27702 8192000000 10294035040 10740039680 1391643776 14.521 100 34 68 0 1408695376 9072896 7978704
00:25:58 84539 8192000000 10296331472 10759614464 1397135856 14.569 100 14 28 0 1412375136 11823152 3416128
00:28:23 47473 8192000000 10299263248 10763735040 1399939312 14.594 100 15 31 0 1417555920 13812608 3804000
00:30:10 63065 8192000000 10301955584 10769174528 1401564480 14.609 100 22 44 0 1420433680 13733584 5135616
00:32:16 62690 8192000000 10282377312 10748432384 1368440128 14.313 98 11 20 1 1382096112 11093680 2562304
00:34:59 68684 8192000000 10292247104 10747568128 1372471648 14.349 99 16 32 0 1387639440 11572128 3595664
00:37:08 12476 8192000000 10295453888 10743169024 1348799600 14.137 97 53 95 1 1378120400 18576000 10744800
00:38:27 84906 8192000000 10285834864 10754007040 1341258464 14.069 97 9 18 0 1362548944 19661328 1629152
00:40:52 52903 8192000000 10287403392 10734501888 1341350912 14.070 97 18 36 0 1354706448 9549456 3806080
00:42:47 66015 8192000000 10269670128 10720141312 1262225456 13.350 91 31 62 0 1280704304 10901808 7577040
00:44:57 25190 8192000000 10296627440 10739351552 1373510224 14.358 100 33 66 0 1395805360 14899904 7395232
00:47:10 155683 8192000000 10273905488 10713878528 1301840656 13.712 94 36 72 0 1317196224 7179152 8176416
00:50:46 39136 8192000000 10264784080 10712301568 1340690320 14.064 98 42 81 1 1359015680 8105088 10220272
00:52:36 50358 8192000000 10254759904 10719281152 1361280304 14.249 100 8 16 0 1371999200 8994000 1724896
00:54:41 72142 8192000000 10256839152 10708680704 1326735424 13.938 96 16 32 0 1341622496 10982288 3904784
00:56:53 49363 8192000000 10279715440 10729209856 1362477568 14.260 99 17 34 0 1381198816 14512960 4208288
00:58:43 63639 8192000000 10271934176 10731515904 1339189616 14.050 97 16 32 1 1360716752 17707824 3819312
01:00:49 76135 8192000000 10280808224 10735026176 1314334384 13.825 95 28 56 0 1338211424 17555104 6321936
01:03:49 45695 8192000000 10279968448 10733699072 1369256784 14.320 100 34 67 1 1392606512 15451568 7898160
01:05:53 35047 8192000000 10280952800 10712276992 1330504352 13.972 97 26 52 0 1345692528 9689024 5499152
01:07:28 33853 8192000000 10285882368 10724802560 1364087728 14.274 100 39 78 0 1388038000 15392112 8558160
01:09:05 27250 8192000000 10298461104 10727567360 1359727744 14.235 99 52 102 0 1385697232 15219312 10750176
01:10:42 77937 8192000000 10256919008 10694877184 1317084128 13.850 96 32 64 0 1332572320 8429024 7059168
01:13:05 68049 8192000000 10255936736 10718412800 1361928288 14.255 100 9 18 0 1373765952 9603376 2234288
01:15:16 52715 8192000000 10292418448 10740490240 1377140752 14.391 100 18 35 1 1390598432 9759664 3698016
01:17:15 106565 8192000000 10288571872 10752528384 1368440624 14.313 99 11 22 0 1389593808 18535440 2617744
01:20:02 81931 8192000000 10297908240 10758090752 1393603040 14.538 100 14 26 1 1410942400 14634016 2705344
01:22:28 85583 8192000000 10303231888 10764038144 1382559872 14.439 99 11 22 0 1399472032 14472096 2440064
01:24:54 148359 8192000000 10293374064 10766483456 1353371488 14.178 97 11 22 1 1375904000 19681152 2851360
01:28:23 62330 8192000000 10284760256 10730070016 1314990944 13.831 95 28 56 0 1336272800 15121744 6160112
01:30:25 63984 8192000000 10276961728 10730442752 1353124256 14.176 98 19 38 1 1365583680 8451584 4007840
01:32:46 29619 8192000000 10264694448 10712281088 1345967968 14.111 99 28 56 0 1363537696 11083920 6485808
01:34:16 54803 8192000000 10248930176 10705457152 1327782736 13.947 97 11 22 0 1340611952 10422768 2406448
01:36:16 58102 8192000000 10258401024 10710278144 1342682208 14.082 99 12 21 0 1355097488 9505328 2909952
01:38:22 15559 8192000000 9105381584 9347100672 278090752 3.283 21 2 4 0 283672608 5220096 361760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 31-07-2022 01:40:52 to 31-07-2022 01:41:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 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 31-07-2022 01:41:07 to 31-07-2022 01:41:09.

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          230958
MON$OLDEST_ACTIVE               230959
MON$OLDEST_SNAPSHOT             230959
MON$NEXT_TRANSACTION            230959
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                       2019520
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.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:557: 
:558: mscs-test-161.budzdorov.ru    Sun Jul 31 01:38:45 2022
:559:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:560: 
--- end of diff output ---

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

31.07.22 01:41:10. 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-07-31 01:41:13 No severe PSQL-related problems occured
31.07.22 01:41:14. End of report.