Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.10.2022 01:38
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 15502160 kB
MemAvailable 30447592 kB
Buffers 0 kB
Cached 16203000 kB
SwapCached 2108 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-10-24 01:38:33
DTS_END                         2022-10-24 01:38:33.4310
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/41080
TRN_ID                          235944
ATT_ID                          615
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: 9104.34 20557 1092521
customer order: creation 167.43 420 20091
customer order: refuse 14.03 387 1684
order to supplier: creation 45.53 1326 5464
order to supplier: removal 3.53 100724 424
invoice (draft): creation 45.00 3529 5400
invoice (draft): removal 52.44 809 6293
invoice accept: apply 39.81 19033 4777
invoice accept: cancel 47.37 824 5684
customer reserve: creation 3708.40 186 445008
customer reserve: removal 4683.33 59 562000
realization accept: apply 83.83 197 10060
realization accept: cancel 87.13 107 10455
payment from customer: creation 57.55 143 6906
payment from customer: removal 5.29 115 635
payment to supplier: creation 48.14 171 5777
payment to supplier: removal 3.43 86 412
service: total inventory turnovers 4.68 1074 561
service: total monetary turnovers 6.51 31 781
service: aggregate perf. data 0.88 20257 105
service: refresh index statistics 0.03 261671 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-10-2022 01:39:17 to 24-10-2022 01:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2835
TEST_TIME 2 2773
TEST_TIME 3 3323
TEST_TIME 4 3059
TEST_TIME 5 4556
TEST_TIME 6 4909
TEST_TIME 7 4779
TEST_TIME 8 4995
TEST_TIME 9 5523
TEST_TIME 10 5499
TEST_TIME 11 5936
TEST_TIME 12 6561
TEST_TIME 13 6513
TEST_TIME 14 6461
TEST_TIME 15 6753
TEST_TIME 16 6779
TEST_TIME 17 7171
TEST_TIME 18 7657
TEST_TIME 19 8340
TEST_TIME 20 8259
TEST_TIME 21 8246
TEST_TIME 22 8310
TEST_TIME 23 8448
TEST_TIME 24 8509
TEST_TIME 25 8781
TEST_TIME 26 9045
TEST_TIME 27 9078
TEST_TIME 28 9972
TEST_TIME 29 10409
TEST_TIME 30 10529
TEST_TIME 31 10324
TEST_TIME 32 10290
TEST_TIME 33 10112
TEST_TIME 34 9986
TEST_TIME 35 9810
TEST_TIME 36 9652
TEST_TIME 37 9803
TEST_TIME 38 9829
TEST_TIME 39 9719
TEST_TIME 40 9683
TEST_TIME 41 9474
TEST_TIME 42 9345
TEST_TIME 43 9375
TEST_TIME 44 9377
TEST_TIME 45 9374
TEST_TIME 46 9386
TEST_TIME 47 9252
TEST_TIME 48 9236
TEST_TIME 49 9161
TEST_TIME 50 9148
TEST_TIME 51 9213
TEST_TIME 52 9130
TEST_TIME 53 9122
TEST_TIME 54 9156
TEST_TIME 55 9217
TEST_TIME 56 9202
TEST_TIME 57 9218
TEST_TIME 58 9186
TEST_TIME 59 9189
TEST_TIME 60 9280
TEST_TIME 61 9294
TEST_TIME 62 9218
TEST_TIME 63 9253
TEST_TIME 64 9340
TEST_TIME 65 9500
TEST_TIME 66 9497
TEST_TIME 67 9441
TEST_TIME 68 9555
TEST_TIME 69 9498
TEST_TIME 70 9371
TEST_TIME 71 9351
TEST_TIME 72 9385
TEST_TIME 73 9301
TEST_TIME 74 9248
TEST_TIME 75 9344
TEST_TIME 76 9277
TEST_TIME 77 9227
TEST_TIME 78 9240
TEST_TIME 79 9198
TEST_TIME 80 9164
TEST_TIME 81 9089
TEST_TIME 82 9063
TEST_TIME 83 9024
TEST_TIME 84 9045
TEST_TIME 85 9041
TEST_TIME 86 9044
TEST_TIME 87 9055
TEST_TIME 88 9175
TEST_TIME 89 9142
TEST_TIME 90 9155
TEST_TIME 91 9239
TEST_TIME 92 9215
TEST_TIME 93 9341
TEST_TIME 94 9368
TEST_TIME 95 9374
TEST_TIME 96 9344
TEST_TIME 97 9338
TEST_TIME 98 9271
TEST_TIME 99 9232
TEST_TIME 100 9194
TEST_TIME 101 9252
TEST_TIME 102 9235
TEST_TIME 103 9202
TEST_TIME 104 9156
TEST_TIME 105 9163
TEST_TIME 106 9114
TEST_TIME 107 9070
TEST_TIME 108 9074
TEST_TIME 109 9065
TEST_TIME 110 9031
TEST_TIME 111 9065
TEST_TIME 112 9046
TEST_TIME 113 9101
TEST_TIME 114 9106
TEST_TIME 115 9102
TEST_TIME 116 9107
TEST_TIME 117 9099
TEST_TIME 118 9099
TEST_TIME 119 9133
TEST_TIME 120 9106

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-10-2022 01:39:21 to 24-10-2022 01:39:24.

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 1506014 1506014 0 0.00 0 25257 7 0 0
doc_list_biud 1506009 1506009 0 0.00 0 32815 28 0 0
sp_add_invoice_to_stock 4865 4777 88 1.80 32 205154 19033 0 88
sp_cancel_adding_invoice 5684 5684 0 0.00 3 81395 824 0 0
sp_cancel_client_order 1684 1684 0 0.00 3 12262 388 0 0
sp_cancel_customer_reserve 562000 562000 0 0.00 0 32817 60 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 2 10107 116 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 9868 86 0 0
sp_cancel_supplier_invoice 6298 6293 5 0.07 2 11992 810 0 5
sp_cancel_supplier_order 424 424 0 0.00 5 1111096 100724 0 0
sp_cancel_write_off 10455 10455 0 0.00 1 9439 107 0 0
sp_client_order 20091 20091 0 0.00 3 42933 421 0 0
sp_customer_reserve 445008 445008 0 0.00 0 121068 187 0 0
sp_fill_shopping_cart 30949 30949 0 0.00 1 20333 45 0 0
sp_fill_shopping_cart_clo_res 445003 445003 0 0.00 0 16319 16 0 0
sp_get_clo_for_invoice 4772 4772 0 0.00 0 55487 411 0 0
sp_kill_cost_storno 17186 17186 0 0.00 0 11364 57 0 0
sp_kill_qstorno_ret_qs2qd 1141172 1141172 0 0.00 0 32812 32 0 0
sp_kill_qty_storno 601372 601372 0 0.00 0 32814 70 0 0
sp_lock_selected_doc 20343 20343 0 0.00 0 10096 4 0 0
sp_make_cost_storno 27515 27515 0 0.00 0 25235 62 0 0
sp_multiply_rows_for_pdistr 27515 27515 0 0.00 0 18516 31 0 0
sp_multiply_rows_for_qdistr 20091 20091 0 0.00 0 31390 171 0 0
sp_pay_from_customer 6906 6906 0 0.00 4 16819 143 0 0
sp_pay_to_supplier 5777 5777 0 0.00 4 25423 172 0 0
sp_payment_common 12682 12682 0 0.00 1 19595 42 0 0
sp_qd_handle_on_cancel_clo 1684 1684 0 0.00 0 12224 357 0 0
sp_qd_handle_on_invoice_upd_sts 10456 10456 0 0.00 0 20797 337 0 0
sp_qd_handle_on_reserve_upd_sts 10060 10060 0 0.00 0 12889 56 0 0
sp_reserve_write_off 10060 10060 0 0.00 6 21623 197 0 0
sp_supplier_invoice 5400 5400 0 0.00 6 86972 3530 0 0
sp_supplier_order 5464 5464 0 0.00 5 55290 1326 0 0
srv_aggregate_perf_data 105 105 0 0.00 3003 185494 20257 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 962 156386 47775 0 0
srv_find_qd_qs_mism 1046831 1046831 0 0.00 0 25256 6 0 0
srv_make_invnt_saldo 561 561 0 0.00 35 25916 1074 0 0
srv_make_money_saldo 781 781 0 0.00 0 3253 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 186995 327688 261671 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4777 4777 0 0.00 0 16502 188 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 3783 87 0 0
t$perf-norm:sp_cancel_client_order 1684 1684 0 0.00 0 3975 14 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 4842 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 531 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 1541 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 1743 23 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 21208 1601 0 0
t$perf-norm:sp_cancel_write_off 928 928 0 0.00 0 4902 16 0 0
t$perf-norm:sp_client_order 20091 20091 0 0.00 0 18092 21 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 1137 5 0 0
t$perf-norm:sp_pay_from_customer 6906 6906 0 0.00 0 11647 15 0 0
t$perf-norm:sp_pay_to_supplier 5777 5777 0 0.00 0 6065 10 0 0
t$perf-norm:sp_reserve_write_off 10060 10060 0 0.00 0 8000 11 0 0
t$perf-norm:sp_supplier_invoice 5400 5400 0 0.00 0 6268 23 0 0
t$perf-norm:sp_supplier_order 5464 5464 0 0.00 0 8197 20 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 582 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 67 3 0 0
t$perf-norm:srv_make_invnt_saldo 561 561 0 0.00 0 2993 26 0 0
t$perf-norm:srv_make_money_saldo 781 781 0 0.00 0 2169 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 9718 9542 176 1.81 0 14929 27 0 176
v_all_customers 36946 36946 0 0.00 0 72 1 0 0
v_all_suppliers 30128 30128 0 0.00 0 25 1 0 0
v_all_wares 40180 40180 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 1609 11 0 0
v_cancel_client_order 3368 3368 0 0.00 0 4303 8 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 4120 16 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 2719 6 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 3819 12 0 0
v_cancel_supplier_invoice 806 796 10 1.24 0 3414 24 0 10
v_cancel_supplier_order 848 848 0 0.00 0 4514 12 0 0
v_cancel_write_off 1856 1856 0 0.00 0 6233 26 0 0
v_max_id_clo_ord 5464 5464 0 0.00 0 336 1 0 0
v_max_id_clo_res 996 996 0 0.00 0 2437 22 0 0
v_max_id_ord_sup 5394 5394 0 0.00 0 7972 8 0 0
v_max_non_paid_invoice 1571 1571 0 0.00 0 2564 7 0 0
v_max_non_paid_realizn 3586 3586 0 0.00 0 4457 8 0 0
v_min_id_clo_ord 5464 5464 0 0.00 0 9196 8 0 0
v_min_id_clo_res 996 996 0 0.00 0 7824 13 0 0
v_min_id_ord_sup 5394 5394 0 0.00 0 10281 12 0 0
v_min_non_paid_invoice 5777 5777 0 0.00 0 19588 37 0 0
v_min_non_paid_realizn 6905 6905 0 0.00 0 16324 30 0 0
v_our_firm 9872 9872 0 0.00 0 19 1 0 0
v_reserve_write_off 20120 20120 0 0.00 0 15173 11 0 0
x_lock_depdocs_on_canc_invoice 11579 11579 0 0.00 0 20775 129 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 28433 399 0 0
x_make_qstorno_1000_1200 5464 5464 0 0.00 1 53495 1244 0 0
x_make_qstorno_1200_2000 5400 5400 0 0.00 3 77786 3359 0 0
x_make_qstorno_2100_3300 445008 445008 0 0.00 0 121063 168 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 24-10-2022 01:39:25 to 24-10-2022 01:39:30.

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:37 2 8192000000 8803754032 8816791552 9129616 0.111 4 1 2 0 9231936 59536 42784
23:09:39 25743 8192000000 9913685152 10226241536 992619600 10.807 100 85 170 0 1021150320 5810896 22719824
23:11:07 11873 8192000000 9953598112 10274942976 1034593712 11.213 100 89 178 0 1064767056 7638160 22535184
23:12:24 13254 8192000000 9987208848 10319712256 1070143952 11.553 100 84 168 1 1100408608 9452288 20812368
23:13:44 12337 8192000000 10024000592 10371256320 1112862608 11.960 100 79 154 1 1141283056 10019888 18400560
23:15:01 6070 8192000000 10059234480 10413989888 1150563632 12.315 100 79 158 0 1180971216 11917856 18489728
23:16:10 5901 8192000000 10105885264 10468675584 1190086800 12.684 100 79 158 0 1221002912 13160640 17755472
23:17:18 3686 8192000000 10136795216 10508656640 1223148992 12.991 100 83 160 1 1254239184 13228768 17861424
23:18:24 2486 8192000000 10158510096 10535333888 1249201760 13.231 100 82 163 0 1278867536 12394864 17270912
23:19:28 1306 8192000000 10192911152 10574565376 1279549744 13.509 100 91 182 0 1313362496 14007232 19805520
23:20:31 1295 8192000000 10216028176 10598658048 1297508640 13.673 100 96 195 0 1331597696 13044992 21044064
23:21:33 14073 8192000000 10198959584 10606804992 1308268592 13.770 100 40 78 3 1323324976 5611024 9445360
23:22:48 9494 8192000000 10203091472 10612842496 1311697776 13.801 100 11 22 0 1319672640 5647792 2327072
23:23:58 9810 8192000000 10219754416 10633920512 1325309600 13.925 100 19 38 0 1335549392 6027328 4212464
23:25:13 57295 8192000000 10230633872 10646822912 1336611600 14.027 100 21 41 0 1347263792 4630720 6021472
23:27:26 5093 8192000000 10229682544 10653855744 1339375248 14.052 100 31 62 1 1351758480 5047408 7335824
23:28:33 10074 8192000000 10221772816 10642575360 1330124224 13.968 100 14 28 0 1339188912 6066848 2997840
23:29:43 8336 8192000000 10183068080 10593206272 1262479968 13.353 96 20 41 1 1272834032 5278640 5075424
23:30:53 20652 8192000000 10152115760 10559303680 1200474880 12.781 93 16 32 0 1208862960 4327808 4060272
23:32:16 4235 8192000000 10158789616 10556731392 1258477184 13.316 100 39 78 0 1274740176 7386992 8876000
23:33:22 9867 8192000000 10141194064 10544910336 1230706720 13.061 98 22 42 1 1241338336 5395904 5235712
23:34:37 9155 8192000000 10174164192 10576285696 1266221680 13.387 99 30 59 0 1279016240 5034128 7760432
23:35:47 25557 8192000000 10177536416 10587086848 1285258944 13.561 100 23 46 0 1296530112 6479504 4791664
23:37:15 6829 8192000000 10182220192 10600890368 1296648512 13.665 100 21 42 2 1307286976 4997280 5641184
23:38:24 8610 8192000000 10212550384 10625814528 1319186464 13.869 100 19 39 0 1328024512 4983168 3854880
23:40:02 34036 8192000000 10253846048 10666979328 1347394656 14.124 100 38 76 0 1365244752 9087408 8762688
23:41:42 87786 8192000000 10267061216 10691072000 1361341696 14.249 100 35 70 2 1384511008 14556128 8613184
23:44:22 14147 8192000000 10269314928 10702225408 1372124480 14.346 100 34 67 1 1393708208 13673712 7910016
23:45:42 14434 8192000000 10304378400 10727882752 1387143360 14.480 100 41 80 1 1409583360 13025456 9414544
23:47:00 16242 8192000000 10328096064 10748551168 1400004784 14.595 100 60 120 0 1434044160 20478736 13560640
23:48:23 8946 8192000000 10351018144 10772287488 1418212592 14.757 100 68 132 0 1457947824 24794256 14940976
23:49:39 2411 8192000000 10355131904 10772103168 1414779104 14.726 100 71 141 0 1460534768 29122352 16633312
23:50:50 1650 8192000000 10350951552 10763051008 1402848096 14.620 100 84 167 0 1455880960 32631056 20401808
23:51:55 5789 8192000000 10362378288 10783563776 1421300400 14.784 100 64 127 0 1469063056 32322064 15440592
23:53:06 1529 8192000000 10376094592 10788098048 1420859568 14.780 100 80 159 1 1478918672 38195600 19863504
23:54:11 959 8192000000 10356154736 10764988416 1402151440 14.614 100 85 168 1 1464259936 40736560 21371936
23:55:16 2543 8192000000 10350715776 10760351744 1378463248 14.403 99 75 149 0 1448127792 49403408 20261136
23:56:27 2149 8192000000 10343478608 10750439424 1369093872 14.319 99 80 155 1 1439009520 49905824 20009824
23:57:35 11059 8192000000 10329653424 10754195456 1384757392 14.459 100 49 96 0 1437009760 39438992 12813376
23:58:53 10868 8192000000 10327979072 10759897088 1388116224 14.489 100 33 66 1 1438949408 42136176 8697008
00:00:08 17783 8192000000 10335840512 10769055744 1385733136 14.468 100 31 62 0 1443097440 48480528 8883776
00:01:33 25206 8192000000 10340758480 10769117184 1374423600 14.367 99 34 68 0 1440211488 56445456 9342432
00:03:08 10520 8192000000 10340240256 10768035840 1380030960 14.417 100 28 56 0 1439942128 51676768 8234400
00:04:24 21499 8192000000 10307013744 10735767552 1346249920 14.114 98 28 56 0 1405616272 51554176 7812176
00:06:07 34161 8192000000 10305413792 10754109440 1361668032 14.252 100 26 51 0 1423835632 54184016 7983584
00:07:51 58874 8192000000 10265541472 10701631488 1321675696 13.892 97 18 36 1 1345900832 20000736 4224400
00:09:52 16941 8192000000 10295646528 10728943616 1380039488 14.417 100 53 93 2 1399687360 8954688 10693184
00:11:12 132607 8192000000 10275617648 10725208064 1340824960 14.065 97 16 32 0 1356227520 10558752 4843808
00:14:33 31578 8192000000 10298075328 10745114624 1383719568 14.450 100 18 35 0 1405270256 18085792 3464896
00:16:06 115840 8192000000 10282107424 10733912064 1346695680 14.118 97 20 41 0 1359924144 8975728 4252736
00:19:03 100926 8192000000 10302467552 10742411264 1362803856 14.263 98 44 86 2 1382932912 10137600 9991456
00:21:54 34589 8192000000 10276649776 10725715968 1357740880 14.217 98 20 38 0 1372062736 9314496 5007360
00:23:51 156386 8192000000 10307073136 10746839040 1361419104 14.250 98 43 86 0 1382621312 10895216 10306992
00:27:28 37688 8192000000 10326503488 10770911232 1401663536 14.610 100 31 62 1 1428030688 18913280 7453872
00:29:06 48850 8192000000 10304803968 10777325568 1390186960 14.508 99 9 18 0 1415571264 23366720 2017584
00:30:56 51799 8192000000 10311131552 10782650368 1399865376 14.594 100 15 30 0 1418646512 15391104 3390032
00:32:48 72843 8192000000 10310041344 10769457152 1377931040 14.398 98 17 34 0 1390800256 8634336 4234880
00:35:26 27214 8192000000 10313853248 10776109056 1399437440 14.590 99 47 94 0 1423264064 12024576 11802048
00:37:04 44891 8192000000 10308408720 10769768448 1397881440 14.576 99 17 34 0 1411610832 10077328 3652064
00:38:54 83135 8192000000 10313657776 10765905920 1350157664 14.149 95 22 43 1 1366783744 12030224 4595856
00:41:22 25628 8192000000 10337178128 10784796672 1413752368 14.717 100 26 52 0 1430233536 10882944 5598224
00:42:48 54049 8192000000 10307336736 10780979200 1353466736 14.179 96 6 12 0 1376539280 21753136 1319408
00:45:09 33372 8192000000 10327074752 10763165696 1378882256 14.407 98 28 57 0 1397836064 12713520 6240288
00:46:48 137597 8192000000 10305017552 10745905152 1362473696 14.260 97 24 46 0 1381182064 13340160 5368208
00:50:27 67999 8192000000 10268355856 10728316928 1335883952 14.020 97 17 34 0 1350540208 10712400 3943856
00:52:36 71791 8192000000 10282097536 10746593280 1376221440 14.383 100 20 40 1 1392822976 12143056 4458480
00:54:48 23694 8192000000 10241999200 10687700992 1306468256 13.754 95 13 27 0 1316268576 6888080 2912240
00:56:26 47647 8192000000 10255292896 10707165184 1327082144 13.941 97 13 26 0 1336862944 6930096 2850704
00:58:15 71435 8192000000 10249793280 10702647296 1328834304 13.957 97 9 18 0 1338342560 7382432 2125824
01:00:27 62032 8192000000 10256344336 10715414528 1360978144 14.246 100 29 56 0 1376137168 8447424 6711600
01:02:58 33445 8192000000 10276999168 10732146688 1359770336 14.235 99 19 38 0 1375804864 10857824 5176704
01:04:42 62065 8192000000 10281382224 10732744704 1345080288 14.103 98 17 34 1 1366746608 17619584 4046736
01:06:46 40369 8192000000 10275660160 10719625216 1361957376 14.255 100 16 32 0 1375973456 10228496 3787584
01:08:28 68122 8192000000 10280907056 10736754688 1351999872 14.165 98 22 44 0 1372739872 15681504 5058496
01:10:47 17997 8192000000 10294851408 10739621888 1371015616 14.336 99 30 60 0 1389722864 12129456 6577792
01:12:12 144171 8192000000 10302787168 10757513216 1357262400 14.213 98 13 26 0 1373556576 13289168 3005008
01:15:45 127269 8192000000 10287235088 10741854208 1380897488 14.425 99 29 58 1 1396737440 8956912 6883040
01:19:10 63176 8192000000 10282704576 10755264512 1383072608 14.444 99 21 42 0 1401955712 13134784 5748320
01:21:21 74824 8192000000 10290330672 10749157376 1369403392 14.322 98 14 28 1 1379508432 6942160 3162880
01:23:36 78312 8192000000 10281001248 10744537088 1366507936 14.296 98 19 39 0 1378682608 6761696 5412976
01:26:05 86768 8192000000 10281193488 10749177856 1375924336 14.380 99 15 30 0 1387122608 8196576 3001696
01:28:32 32040 8192000000 10293864112 10746073088 1355593760 14.198 97 26 52 0 1373425136 11501664 6329712
01:30:06 26140 8192000000 10308816304 10753568768 1379101584 14.409 99 22 44 0 1393596496 9272688 5222224
01:31:34 43570 8192000000 10309243008 10769305600 1398202608 14.579 100 18 37 1 1416829440 14572224 4054608
01:33:25 52067 8192000000 10291789488 10735742976 1328549984 13.954 95 31 62 0 1346013392 10392256 7071152
01:35:22 31673 8192000000 10294660528 10736930816 1359040688 14.229 98 32 64 0 1375516256 9625600 6849968
01:36:56 46878 8192000000 10275987632 10722975744 1340029856 14.058 98 24 48 0 1355624144 10556064 5038224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-10-2022 01:39:31 to 24-10-2022 01:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-10-2022 01:39:47 to 24-10-2022 01:39:49.

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          233315
MON$OLDEST_ACTIVE               236209
MON$OLDEST_SNAPSHOT             236209
MON$NEXT_TRANSACTION            236209
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                       2020096
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.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 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 ---
:145: 
:146: mscs-test-161.budzdorov.ru    Mon Oct 24 01:38:33 2022
:147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:148: 
:149: 
:150: mscs-test-161.budzdorov.ru    Mon Oct 24 01:38:35 2022
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:152: 
:153: 
:154: mscs-test-161.budzdorov.ru    Mon Oct 24 01:38:41 2022
:155:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:156: 
--- end of diff output ---

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

24.10.22 01:39:51. 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-10-24 01:39:53 No severe PSQL-related problems occured
24.10.22 01:39:54. End of report.