Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.05.2022 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16037228 kB
MemAvailable 31328560 kB
Buffers 0 kB
Cached 15633480 kB
SwapCached 2440 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-31 22:38:16
DTS_END                         2022-05-31 22:38:16.0690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38404
TRN_ID                          251371
ATT_ID                          665
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: 9822.46 25364 1178695
customer order: creation 180.03 316 21604
customer order: refuse 15.20 309 1824
order to supplier: creation 47.78 1125 5734
order to supplier: removal 3.57 117065 428
invoice (draft): creation 48.19 3190 5783
invoice (draft): removal 55.89 1062 6707
invoice accept: apply 43.66 15998 5239
invoice accept: cancel 50.95 808 6114
customer reserve: creation 4063.06 156 487567
customer reserve: removal 4997.73 64 599727
realization accept: apply 87.76 182 10531
realization accept: cancel 90.28 117 10834
payment from customer: creation 63.12 100 7574
payment from customer: removal 6.21 70 745
payment to supplier: creation 52.41 121 6289
payment to supplier: removal 3.63 60 436
service: total inventory turnovers 4.71 1068 565
service: total monetary turnovers 7.41 47 889
service: aggregate perf. data 0.84 20421 101
service: refresh index statistics 0.03 345001 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-05-2022 22:39:31 to 31-05-2022 22:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3636
TEST_TIME 2 5061
TEST_TIME 3 4452
TEST_TIME 4 4007
TEST_TIME 5 4351
TEST_TIME 6 4604
TEST_TIME 7 4654
TEST_TIME 8 4794
TEST_TIME 9 4690
TEST_TIME 10 5038
TEST_TIME 11 4983
TEST_TIME 12 5309
TEST_TIME 13 5668
TEST_TIME 14 5965
TEST_TIME 15 6627
TEST_TIME 16 6666
TEST_TIME 17 6575
TEST_TIME 18 6936
TEST_TIME 19 7646
TEST_TIME 20 7550
TEST_TIME 21 8436
TEST_TIME 22 8647
TEST_TIME 23 8605
TEST_TIME 24 8666
TEST_TIME 25 8763
TEST_TIME 26 8692
TEST_TIME 27 9171
TEST_TIME 28 9824
TEST_TIME 29 10333
TEST_TIME 30 11990
TEST_TIME 31 11802
TEST_TIME 32 11656
TEST_TIME 33 11815
TEST_TIME 34 11701
TEST_TIME 35 11564
TEST_TIME 36 11396
TEST_TIME 37 11260
TEST_TIME 38 11088
TEST_TIME 39 10861
TEST_TIME 40 11126
TEST_TIME 41 11039
TEST_TIME 42 10863
TEST_TIME 43 10763
TEST_TIME 44 10715
TEST_TIME 45 10673
TEST_TIME 46 10603
TEST_TIME 47 10498
TEST_TIME 48 10567
TEST_TIME 49 10676
TEST_TIME 50 10592
TEST_TIME 51 10477
TEST_TIME 52 10300
TEST_TIME 53 10369
TEST_TIME 54 10268
TEST_TIME 55 10195
TEST_TIME 56 10253
TEST_TIME 57 10213
TEST_TIME 58 10194
TEST_TIME 59 10160
TEST_TIME 60 10311
TEST_TIME 61 10345
TEST_TIME 62 10430
TEST_TIME 63 10412
TEST_TIME 64 10356
TEST_TIME 65 10433
TEST_TIME 66 10484
TEST_TIME 67 10448
TEST_TIME 68 10525
TEST_TIME 69 10525
TEST_TIME 70 10392
TEST_TIME 71 10349
TEST_TIME 72 10328
TEST_TIME 73 10291
TEST_TIME 74 10298
TEST_TIME 75 10232
TEST_TIME 76 10173
TEST_TIME 77 10151
TEST_TIME 78 10100
TEST_TIME 79 10051
TEST_TIME 80 10036
TEST_TIME 81 10086
TEST_TIME 82 10069
TEST_TIME 83 10060
TEST_TIME 84 10032
TEST_TIME 85 10162
TEST_TIME 86 10162
TEST_TIME 87 10134
TEST_TIME 88 10245
TEST_TIME 89 10196
TEST_TIME 90 10261
TEST_TIME 91 10253
TEST_TIME 92 10171
TEST_TIME 93 10128
TEST_TIME 94 10118
TEST_TIME 95 10120
TEST_TIME 96 10078
TEST_TIME 97 10059
TEST_TIME 98 10046
TEST_TIME 99 10066
TEST_TIME 100 10062
TEST_TIME 101 10042
TEST_TIME 102 10041
TEST_TIME 103 10078
TEST_TIME 104 10066
TEST_TIME 105 10117
TEST_TIME 106 10194
TEST_TIME 107 10145
TEST_TIME 108 10221
TEST_TIME 109 10178
TEST_TIME 110 10114
TEST_TIME 111 10091
TEST_TIME 112 10029
TEST_TIME 113 10053
TEST_TIME 114 10001
TEST_TIME 115 9959
TEST_TIME 116 9921
TEST_TIME 117 9891
TEST_TIME 118 9863
TEST_TIME 119 9893
TEST_TIME 120 9824

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-05-2022 22:39:34 to 31-05-2022 22:39:38.

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 1631409 1631409 0 0.00 0 27813 6 0 0
doc_list_biud 1631409 1631409 0 0.00 0 45214 30 0 0
sp_add_invoice_to_stock 5380 5239 141 2.62 39 149545 15999 0 141
sp_cancel_adding_invoice 6114 6114 0 0.00 4 65609 809 0 0
sp_cancel_client_order 1824 1824 0 0.00 4 8080 310 0 0
sp_cancel_customer_reserve 599727 599727 0 0.00 1 45216 64 0 0
sp_cancel_pay_from_customer 745 745 0 0.00 3 4143 70 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 2504 61 0 0
sp_cancel_supplier_invoice 6718 6707 11 0.16 2 27929 1062 0 11
sp_cancel_supplier_order 428 428 0 0.00 7 1313387 117066 0 0
sp_cancel_write_off 10834 10834 0 0.00 1 13778 117 0 0
sp_client_order 21604 21604 0 0.00 3 16774 317 0 0
sp_customer_reserve 487567 487567 0 0.00 0 45929 156 0 0
sp_fill_shopping_cart 33110 33110 0 0.00 1 12429 35 0 0
sp_fill_shopping_cart_clo_res 487563 487563 0 0.00 0 19612 13 0 0
sp_get_clo_for_invoice 5235 5235 0 0.00 0 22013 262 0 0
sp_kill_cost_storno 18129 18129 0 0.00 0 13774 58 0 0
sp_kill_qstorno_ret_qs2qd 1217423 1217423 0 0.00 0 45212 36 0 0
sp_kill_qty_storno 641399 641399 0 0.00 0 45214 76 0 0
sp_lock_selected_doc 21940 21940 0 0.00 0 6320 5 0 0
sp_make_cost_storno 29628 29628 0 0.00 0 27809 57 0 0
sp_multiply_rows_for_pdistr 29628 29628 0 0.00 0 5426 26 0 0
sp_multiply_rows_for_qdistr 21604 21604 0 0.00 0 14067 141 0 0
sp_pay_from_customer 7574 7574 0 0.00 4 10451 101 0 0
sp_pay_to_supplier 6289 6289 0 0.00 5 11655 122 0 0
sp_payment_common 13863 13863 0 0.00 1 10073 24 0 0
sp_qd_handle_on_cancel_clo 1824 1824 0 0.00 0 8075 282 0 0
sp_qd_handle_on_invoice_upd_sts 11348 11348 0 0.00 0 25881 325 0 0
sp_qd_handle_on_reserve_upd_sts 10531 10531 0 0.00 0 15511 59 0 0
sp_reserve_write_off 10531 10531 0 0.00 6 27835 182 0 0
sp_supplier_invoice 5783 5783 0 0.00 7 98519 3191 0 0
sp_supplier_order 5734 5734 0 0.00 6 26139 1126 0 0
srv_aggregate_perf_data 101 101 0 0.00 2321 274887 20422 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1362 110080 40815 0 0
srv_find_qd_qs_mism 1128887 1128887 0 0.00 0 10583 4 0 0
srv_make_invnt_saldo 565 565 0 0.00 35 68014 1069 0 0
srv_make_money_saldo 889 889 0 0.00 1 8361 48 0 0
srv_recalc_idx_stat 4 4 0 0.00 184819 510272 345001 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 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-norm:sp_add_invoice_to_stock 5239 5239 0 0.00 0 13511 131 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 6796 91 0 0
t$perf-norm:sp_cancel_client_order 1824 1824 0 0.00 0 2025 10 0 0
t$perf-norm:sp_cancel_customer_reserve 757 757 0 0.00 0 786 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 745 745 0 0.00 0 2580 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 2600 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 1927 20 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 17933 1622 0 0
t$perf-norm:sp_cancel_write_off 1101 1101 0 0.00 0 1284 7 0 0
t$perf-norm:sp_client_order 21604 21604 0 0.00 0 16551 18 0 0
t$perf-norm:sp_customer_reserve 1048 1048 0 0.00 0 918 5 0 0
t$perf-norm:sp_pay_from_customer 7574 7574 0 0.00 0 5425 9 0 0
t$perf-norm:sp_pay_to_supplier 6289 6289 0 0.00 0 13319 14 0 0
t$perf-norm:sp_reserve_write_off 10531 10531 0 0.00 0 13071 10 0 0
t$perf-norm:sp_supplier_invoice 5783 5783 0 0.00 0 2842 12 0 0
t$perf-norm:sp_supplier_order 5734 5734 0 0.00 0 6713 16 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 291 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 24 1 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 3630 23 0 0
t$perf-norm:srv_make_money_saldo 889 889 0 0.00 0 1781 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 16 5 0 0
v_add_invoice_to_stock 10750 10468 282 2.62 0 9405 22 0 282
v_all_customers 40132 40132 0 0.00 0 27 1 0 0
v_all_suppliers 32164 32164 0 0.00 0 28 1 0 0
v_all_wares 43206 43206 0 0.00 0 40 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 371 5 0 0
v_cancel_client_order 3648 3648 0 0.00 0 2093 4 0 0
v_cancel_customer_prepayment 1490 1490 0 0.00 0 683 3 0 0
v_cancel_customer_reserve 1514 1514 0 0.00 0 1384 6 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 767 3 0 0
v_cancel_supplier_invoice 910 888 22 2.41 0 984 15 0 22
v_cancel_supplier_order 856 856 0 0.00 0 3036 9 0 0
v_cancel_write_off 2202 2202 0 0.00 0 1182 11 0 0
v_max_id_clo_ord 5734 5734 0 0.00 0 422 1 0 0
v_max_id_clo_res 1048 1048 0 0.00 0 4073 16 0 0
v_max_id_ord_sup 5773 5773 0 0.00 0 6759 7 0 0
v_max_non_paid_invoice 1714 1714 0 0.00 0 1192 3 0 0
v_max_non_paid_realizn 3814 3814 0 0.00 0 5269 5 0 0
v_min_id_clo_ord 5734 5734 0 0.00 0 2930 4 0 0
v_min_id_clo_res 1048 1048 0 0.00 0 8559 15 0 0
v_min_id_ord_sup 5773 5773 0 0.00 0 4116 5 0 0
v_min_non_paid_invoice 6289 6289 0 0.00 0 4096 13 0 0
v_min_non_paid_realizn 7574 7574 0 0.00 0 6945 18 0 0
v_our_firm 10594 10594 0 0.00 0 16 1 0 0
v_reserve_write_off 21062 21062 0 0.00 0 9426 7 0 0
x_lock_depdocs_on_canc_invoice 12377 12377 0 0.00 0 19716 116 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 6935 357 0 0
x_make_qstorno_1000_1200 5734 5734 0 0.00 2 26128 1055 0 0
x_make_qstorno_1200_2000 5783 5783 0 0.00 2 98500 3063 0 0
x_make_qstorno_2100_3300 487567 487567 0 0.00 0 45928 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-05-2022 22:39:39 to 31-05-2022 22:39:43.

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:19 25 8192000000 8803827344 8816726016 7915600 0.096 2 2 3 0 8058128 67584 74944
20:09:20 28588 8192000000 9908677568 10220953600 991945504 10.800 100 90 179 1 1022649024 7074192 23629328
20:11:00 16843 8192000000 9945876992 10266943488 1025369728 11.124 100 86 172 1 1056421856 8799504 22252624
20:12:22 18768 8192000000 9983567696 10314752000 1067076864 11.524 100 84 168 0 1098123408 9901744 21144800
20:13:44 10875 8192000000 10026778224 10378059776 1120691696 12.034 100 70 140 1 1148552864 11457600 16403568
20:15:00 6192 8192000000 10088748256 10451238912 1174234896 12.536 100 74 148 1 1203869584 12108256 17526432
20:16:08 3718 8192000000 10132537136 10499063808 1217532176 12.939 100 79 158 1 1248293408 13251376 17509856
20:17:13 2544 8192000000 10164537152 10542120960 1255594800 13.290 100 81 162 0 1286539168 13281008 17663360
20:18:18 1987 8192000000 10198411216 10582155264 1288436544 13.590 100 87 175 2 1319827728 11340480 20050704
20:19:21 12110 8192000000 10191052752 10609405952 1305324336 13.744 100 46 93 0 1326082864 10758064 10000464
20:20:47 2401 8192000000 10206805248 10615398400 1313449248 13.817 100 42 86 1 1329399072 5295344 10654480
20:21:52 8093 8192000000 10207034592 10622771200 1319180800 13.869 100 19 38 1 1329727408 5684032 4862576
20:23:04 15946 8192000000 10215024704 10629054464 1326770144 13.938 100 17 35 0 1335993744 5688480 3535120
20:24:23 25438 8192000000 10215428528 10647220224 1333669072 14.000 100 24 48 0 1345967120 5474160 6823888
20:26:08 9284 8192000000 10225712832 10649923584 1336350160 14.024 100 19 38 1 1346208816 5818240 4040416
20:27:18 5768 8192000000 10238495984 10655846400 1334188384 14.005 100 21 41 1 1352900000 14420800 4290816
20:28:24 7429 8192000000 10201645360 10612793344 1285043808 13.559 98 20 40 1 1298736704 9534592 4158304
20:29:33 29047 8192000000 10132077808 10533023744 1224008832 12.999 94 15 30 0 1232766000 5279008 3478160
20:31:06 4738 8192000000 10156700656 10553860096 1223253472 12.992 97 27 54 2 1235797936 6394096 6150368
20:32:13 7489 8192000000 10152172576 10553286656 1245387104 13.196 99 25 49 2 1256683856 5778640 5518112
20:33:26 47385 8192000000 10157736112 10575605760 1265258944 13.378 99 37 74 0 1279845648 5742528 8844176
20:35:14 7194 8192000000 10186439328 10598457344 1294265648 13.643 100 19 38 1 1304218336 5633936 4318752
20:36:21 9926 8192000000 10199312144 10615500800 1310345792 13.789 100 25 50 0 1321870656 4904640 6620224
20:37:32 22635 8192000000 10212120304 10633207808 1325711152 13.928 100 11 22 0 1334165712 5550288 2904272
20:38:56 62650 8192000000 10243809472 10664271872 1335867840 14.020 99 25 50 1 1347484112 5916560 5699712
20:41:10 26124 8192000000 10287233936 10708357120 1373952384 14.362 100 38 76 2 1399407776 16030944 9424448
20:42:41 10737 8192000000 10299358368 10722283520 1390470016 14.510 100 45 90 2 1417117648 15560160 11087472
20:43:58 11615 8192000000 10319117776 10743484416 1401953936 14.612 100 54 104 1 1431030224 17425296 11650992
20:45:15 11566 8192000000 10328411056 10753183744 1408568336 14.671 100 51 102 2 1441077952 20376880 12132736
20:46:32 9499 8192000000 10347503328 10770169856 1420972192 14.781 100 55 110 2 1456462976 22634144 12856640
20:47:49 9367 8192000000 10352442192 10778243072 1421109344 14.783 100 51 102 1 1458702016 24309168 13283504
20:49:05 5122 8192000000 10368552288 10799800320 1442282672 14.970 100 49 98 1 1480752032 26243664 12225696
20:50:13 6804 8192000000 10354204288 10774409216 1414869392 14.727 100 59 118 3 1463826608 33864560 15092656
20:51:26 4281 8192000000 10379816560 10795778048 1422041440 14.791 100 78 150 0 1480126672 39618048 18467184
20:52:34 2172 8192000000 10363328928 10770210816 1404630848 14.636 100 88 172 3 1469007984 43007520 21369616
20:53:41 8744 8192000000 10336183152 10758606848 1395201088 14.552 100 43 85 1 1447211312 40590928 11419296
20:54:52 6889 8192000000 10364016704 10778640384 1405482208 14.644 100 64 128 1 1473187840 51447232 16258400
20:56:08 2234 8192000000 10386985888 10796490752 1395853632 14.558 99 85 168 1 1484638368 67205840 21578896
20:57:19 1361 8192000000 10396381408 10815057920 1421428032 14.785 100 67 134 0 1497406400 58270192 17708176
20:58:22 3325 8192000000 10389419088 10808954880 1406988096 14.657 100 56 112 0 1487531424 65258000 15285328
20:59:31 11597 8192000000 10359621504 10786897920 1387373536 14.482 100 37 74 2 1458253104 60347184 10532384
21:00:47 9508 8192000000 10379106192 10810281984 1419256880 14.766 100 37 74 0 1483458320 53911600 10289840
21:02:02 20500 8192000000 10370356496 10804830208 1403849040 14.629 100 37 74 0 1476524128 62363712 10311376
21:03:41 14113 8192000000 10366052736 10800340992 1388244464 14.490 100 39 77 3 1474568272 74710576 11613232
21:05:08 22076 8192000000 10377063904 10810916864 1384235952 14.454 100 34 68 0 1486239792 91113344 10890496
21:06:42 13114 8192000000 10328428928 10756554752 1330793616 13.974 98 44 89 1 1414307200 70214048 13299536
21:07:58 23514 8192000000 10309846464 10772426752 1344718672 14.100 99 17 34 0 1418823936 68719184 5386080
21:09:23 32451 8192000000 10281658976 10725396480 1357926448 14.219 99 28 56 0 1376133888 11324528 6882912
21:11:09 87266 8192000000 10296334160 10735964160 1375496960 14.376 100 11 20 2 1392340336 13901408 2941968
21:13:37 39916 8192000000 10296226448 10736054272 1344814752 14.101 97 22 44 2 1360717984 10030128 5873104
21:15:21 85167 8192000000 10297175232 10753527808 1375181008 14.373 99 37 72 1 1395733456 11360736 9191712
21:18:02 94617 8192000000 10299418832 10753286144 1360643344 14.243 98 18 36 1 1378997504 14428560 3925600
21:20:42 21380 8192000000 10293307360 10745372672 1381763312 14.432 100 36 72 1 1398521344 8488544 8269488
21:22:07 37381 8192000000 10303218240 10753540096 1374485488 14.367 99 24 46 0 1387953984 8194912 5273584
21:23:47 11651 8192000000 10315720576 10754715648 1387242160 14.481 100 61 117 1 1412771856 11430912 14098784
21:25:06 46918 8192000000 10312183696 10764795904 1380510384 14.421 99 18 35 1 1407996624 22989904 4496336
21:26:53 41114 8192000000 10292242432 10759577600 1382634816 14.440 99 6 12 0 1393566240 9401552 1529872
21:28:35 110078 8192000000 10292407376 10757672960 1370359184 14.330 98 15 29 1 1382304576 8433200 3512192
21:32:01 29078 8192000000 10288750608 10753990656 1370941392 14.335 99 21 41 1 1392035232 16363824 4730016
21:33:31 38346 8192000000 10282999264 10749014016 1372693120 14.351 99 17 34 0 1392193472 15307104 4193248
21:35:10 42847 8192000000 10298831040 10759151616 1374066192 14.363 99 18 36 1 1388375568 10822288 3487088
21:36:54 38684 8192000000 10292363808 10757308416 1386002720 14.470 100 27 54 0 1406269024 14221824 6044480
21:38:33 53709 8192000000 10290042800 10743996416 1346936208 14.120 97 24 47 1 1360591824 8943408 4712208
21:40:39 24423 8192000000 10251572320 10711781376 1318419296 13.862 97 27 54 1 1334718432 10247136 6052000
21:42:05 16425 8192000000 10255547040 10685972480 1286960368 13.577 96 48 97 0 1307166528 9221872 10984288
21:43:25 62789 8192000000 10263855936 10720874496 1317329744 13.853 97 20 40 0 1331860560 10509968 4020848
21:45:28 29643 8192000000 10286616560 10733477888 1357557968 14.215 99 17 34 0 1371538208 10312368 3667872
21:47:30 103919 8192000000 10275305440 10722443264 1344880528 14.101 98 23 46 0 1362556480 12322160 5353792
21:50:18 68942 8192000000 10277572672 10739490816 1338514240 14.044 98 16 32 0 1360988912 18888496 3586176
21:52:28 54562 8192000000 10250946496 10715127808 1337131120 14.032 98 10 20 1 1347200752 7865008 2204624
21:54:23 46771 8192000000 10267061392 10723483648 1354380688 14.187 99 26 52 0 1371445904 11481472 5583744
21:57:13 73374 8192000000 10257580448 10721132544 1320254064 13.879 96 11 22 0 1339104736 16254000 2596672
21:59:34 37168 8192000000 10276877600 10733953024 1358295440 14.222 99 24 48 0 1374681072 11230432 5155200
22:01:12 27872 8192000000 10277875024 10734829568 1352752464 14.172 98 22 44 0 1364338192 6912464 4673264
22:02:44 76606 8192000000 10314574304 10798481408 1390900464 14.514 100 19 38 0 1423814192 28606400 4307328
22:05:16 14686 8192000000 10315646944 10764648448 1389198464 14.499 99 42 84 0 1407508880 9241040 9069376
22:06:32 62043 8192000000 10316292512 10772267008 1350170928 14.149 96 11 22 0 1367807072 15518320 2117824
22:08:40 44015 8192000000 10316505136 10765328384 1381489440 14.430 98 26 51 0 1394574720 7978768 5106512
22:10:24 84438 8192000000 10309057072 10771947520 1395033120 14.551 99 24 48 0 1408311472 7473072 5805280
22:12:52 61395 8192000000 10309924416 10782982144 1396125504 14.560 99 13 26 0 1411447072 12253968 3067600
22:14:57 27692 8192000000 10318739712 10774757376 1370646672 14.333 97 35 70 0 1391221600 12676016 7898912
22:16:25 81431 8192000000 10288074512 10752946176 1352466032 14.170 97 17 34 0 1366960896 10550464 3944400
22:18:47 78303 8192000000 10303496048 10765058048 1365587888 14.287 98 21 42 1 1383071200 12739264 4744048
22:21:06 81180 8192000000 10276988448 10742583296 1305785024 13.748 95 4 8 0 1317136896 10694640 657232
22:23:47 91653 8192000000 10268206096 10721005568 1281981504 13.531 93 9 18 0 1306260768 22276544 2002720
22:26:19 32138 8192000000 10275619792 10728071168 1356576960 14.207 100 18 36 2 1378069024 17335904 4156160
22:27:52 106365 8192000000 10224494288 10682953728 1268056768 13.404 94 36 72 0 1284867872 8887200 7923904
22:31:33 47113 8192000000 10238999248 10711941120 1346574848 14.117 100 8 16 0 1358163872 9642784 1946240
22:33:22 91550 8192000000 10236859040 10685161472 1254058720 13.275 92 9 18 0 1264628048 8525520 2043808
22:36:56 59725 8192000000 10261697472 10711105536 1322572160 13.900 98 12 24 0 1334814528 9561232 2681136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-05-2022 22:39:45 to 31-05-2022 22:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 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 31-05-2022 22:40:01 to 31-05-2022 22:40:03.

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          250538
MON$OLDEST_ACTIVE               251482
MON$OLDEST_SNAPSHOT             251482
MON$NEXT_TRANSACTION            251482
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                       2066528
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.33597 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33597 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33597 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33597 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 ---
:284: 
:285: mscs-test-161.budzdorov.ru    Tue May 31 22:38:16 2022
:286:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:287: 
--- end of diff output ---

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

31.05.22 22:40:04. 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-31 22:40:06 No severe PSQL-related problems occured
31.05.22 22:40:07. End of report.