Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.06.2022 16: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 16228880 kB
MemAvailable 31284320 kB
Buffers 0 kB
Cached 15512456 kB
SwapCached 2460 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-06-18 16:38:27
DTS_END                         2022-06-18 16:38:27.4450
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/57278
TRN_ID                          239158
ATT_ID                          662
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9320.36 24522 1118443
customer order: creation 168.72 364 20246
customer order: refuse 14.58 331 1749
order to supplier: creation 44.92 1267 5390
order to supplier: removal 3.48 108709 417
invoice (draft): creation 45.85 3570 5502
invoice (draft): removal 53.24 792 6389
invoice accept: apply 42.21 18250 5065
invoice accept: cancel 47.75 833 5730
customer reserve: creation 3894.33 180 467320
customer reserve: removal 4713.52 63 565623
realization accept: apply 82.74 206 9929
realization accept: cancel 81.25 114 9750
payment from customer: creation 58.39 137 7007
payment from customer: removal 5.69 86 683
payment to supplier: creation 47.98 138 5757
payment to supplier: removal 3.32 71 398
service: total inventory turnovers 5.01 973 601
service: total monetary turnovers 6.68 35 801
service: aggregate perf. data 0.68 27738 82
service: refresh index statistics 0.03 326592 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-06-2022 16:39:54 to 18-06-2022 16:39:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4922
TEST_TIME 2 3845
TEST_TIME 3 4726
TEST_TIME 4 4531
TEST_TIME 5 4677
TEST_TIME 6 5363
TEST_TIME 7 5425
TEST_TIME 8 5600
TEST_TIME 9 5576
TEST_TIME 10 5594
TEST_TIME 11 5701
TEST_TIME 12 6113
TEST_TIME 13 6206
TEST_TIME 14 6110
TEST_TIME 15 6297
TEST_TIME 16 6375
TEST_TIME 17 6803
TEST_TIME 18 7060
TEST_TIME 19 7304
TEST_TIME 20 7380
TEST_TIME 21 7670
TEST_TIME 22 8274
TEST_TIME 23 8792
TEST_TIME 24 8780
TEST_TIME 25 9006
TEST_TIME 26 9504
TEST_TIME 27 9596
TEST_TIME 28 10051
TEST_TIME 29 10740
TEST_TIME 30 10653
TEST_TIME 31 10699
TEST_TIME 32 10493
TEST_TIME 33 10224
TEST_TIME 34 10292
TEST_TIME 35 10322
TEST_TIME 36 10288
TEST_TIME 37 10148
TEST_TIME 38 10025
TEST_TIME 39 9935
TEST_TIME 40 9887
TEST_TIME 41 9892
TEST_TIME 42 9963
TEST_TIME 43 9850
TEST_TIME 44 9778
TEST_TIME 45 9779
TEST_TIME 46 9774
TEST_TIME 47 9635
TEST_TIME 48 9581
TEST_TIME 49 9682
TEST_TIME 50 9627
TEST_TIME 51 9586
TEST_TIME 52 9534
TEST_TIME 53 9538
TEST_TIME 54 9577
TEST_TIME 55 9654
TEST_TIME 56 9601
TEST_TIME 57 9620
TEST_TIME 58 9545
TEST_TIME 59 9557
TEST_TIME 60 9745
TEST_TIME 61 9806
TEST_TIME 62 9821
TEST_TIME 63 9820
TEST_TIME 64 9674
TEST_TIME 65 9609
TEST_TIME 66 9554
TEST_TIME 67 9441
TEST_TIME 68 9347
TEST_TIME 69 9297
TEST_TIME 70 9380
TEST_TIME 71 9318
TEST_TIME 72 9289
TEST_TIME 73 9199
TEST_TIME 74 9231
TEST_TIME 75 9191
TEST_TIME 76 9167
TEST_TIME 77 9259
TEST_TIME 78 9238
TEST_TIME 79 9229
TEST_TIME 80 9263
TEST_TIME 81 9342
TEST_TIME 82 9327
TEST_TIME 83 9329
TEST_TIME 84 9356
TEST_TIME 85 9467
TEST_TIME 86 9431
TEST_TIME 87 9392
TEST_TIME 88 9330
TEST_TIME 89 9392
TEST_TIME 90 9393
TEST_TIME 91 9359
TEST_TIME 92 9327
TEST_TIME 93 9317
TEST_TIME 94 9339
TEST_TIME 95 9362
TEST_TIME 96 9357
TEST_TIME 97 9330
TEST_TIME 98 9378
TEST_TIME 99 9438
TEST_TIME 100 9419
TEST_TIME 101 9457
TEST_TIME 102 9473
TEST_TIME 103 9495
TEST_TIME 104 9482
TEST_TIME 105 9482
TEST_TIME 106 9577
TEST_TIME 107 9567
TEST_TIME 108 9556
TEST_TIME 109 9475
TEST_TIME 110 9499
TEST_TIME 111 9474
TEST_TIME 112 9471
TEST_TIME 113 9451
TEST_TIME 114 9419
TEST_TIME 115 9399
TEST_TIME 116 9391
TEST_TIME 117 9353
TEST_TIME 118 9337
TEST_TIME 119 9347
TEST_TIME 120 9325

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-06-2022 16:39:58 to 18-06-2022 16:40:01.

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 1550502 1550502 0 0.00 0 22965 6 0 0
doc_list_biud 1550502 1550502 0 0.00 0 77266 29 0 0
sp_add_invoice_to_stock 5171 5065 106 2.04 51 164332 18250 0 106
sp_cancel_adding_invoice 5730 5730 0 0.00 4 92079 833 0 0
sp_cancel_client_order 1749 1749 0 0.00 4 17045 332 0 0
sp_cancel_customer_reserve 565623 565623 0 0.00 1 77267 64 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 2 5932 86 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 2532 72 0 0
sp_cancel_supplier_invoice 6403 6389 14 0.21 1 69329 793 0 14
sp_cancel_supplier_order 417 417 0 0.00 10 1141781 108709 0 0
sp_cancel_write_off 9750 9750 0 0.00 1 22558 114 0 0
sp_client_order 20246 20246 0 0.00 3 76746 365 0 0
sp_customer_reserve 467320 467320 0 0.00 0 94158 180 0 0
sp_fill_shopping_cart 31133 31133 0 0.00 1 16683 38 0 0
sp_fill_shopping_cart_clo_res 467316 467316 0 0.00 0 18525 14 0 0
sp_get_clo_for_invoice 5061 5061 0 0.00 0 35183 300 0 0
sp_kill_cost_storno 16561 16561 0 0.00 0 11244 60 0 0
sp_kill_qstorno_ret_qs2qd 1147802 1147802 0 0.00 0 77264 34 0 0
sp_kill_qty_storno 604648 604648 0 0.00 0 77266 74 0 0
sp_lock_selected_doc 20734 20734 0 0.00 0 9121 5 0 0
sp_make_cost_storno 27754 27754 0 0.00 0 22946 78 0 0
sp_multiply_rows_for_pdistr 27754 27754 0 0.00 0 10068 27 0 0
sp_multiply_rows_for_qdistr 20246 20246 0 0.00 0 76736 152 0 0
sp_pay_from_customer 7007 7007 0 0.00 4 19588 138 0 0
sp_pay_to_supplier 5757 5757 0 0.00 4 18598 138 0 0
sp_payment_common 12764 12764 0 0.00 1 13842 31 0 0
sp_qd_handle_on_cancel_clo 1749 1749 0 0.00 0 17038 306 0 0
sp_qd_handle_on_invoice_upd_sts 10791 10791 0 0.00 0 28285 359 0 0
sp_qd_handle_on_reserve_upd_sts 9929 9929 0 0.00 0 14229 65 0 0
sp_reserve_write_off 9929 9929 0 0.00 5 19899 206 0 0
sp_supplier_invoice 5502 5502 0 0.00 7 113661 3570 0 0
sp_supplier_order 5390 5390 0 0.00 5 45115 1267 0 0
srv_aggregate_perf_data 82 82 0 0.00 2979 389132 27738 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1742 143714 51244 0 0
srv_find_qd_qs_mism 1070655 1070655 0 0.00 0 20341 5 0 0
srv_make_invnt_saldo 601 601 0 0.00 48 37498 973 0 0
srv_make_money_saldo 801 801 0 0.00 1 5632 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 234376 419581 326592 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5065 5065 0 0.00 0 27529 209 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 0 4991 102 0 0
t$perf-norm:sp_cancel_client_order 1749 1749 0 0.00 0 7090 16 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 2488 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 865 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 6746 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 453 453 0 0.00 0 2306 16 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 37434 1845 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 2016 8 0 0
t$perf-norm:sp_client_order 20246 20246 0 0.00 0 15272 21 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 6425 11 0 0
t$perf-norm:sp_pay_from_customer 7007 7007 0 0.00 0 13805 16 0 0
t$perf-norm:sp_pay_to_supplier 5757 5757 0 0.00 0 5987 10 0 0
t$perf-norm:sp_reserve_write_off 9929 9929 0 0.00 0 7174 12 0 0
t$perf-norm:sp_supplier_invoice 5502 5502 0 0.00 0 8691 27 0 0
t$perf-norm:sp_supplier_order 5390 5390 0 0.00 0 5124 17 0 0
t$perf-norm:srv_aggregate_perf_data 82 82 0 0.00 0 461 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 601 601 0 0.00 0 2369 34 0 0
t$perf-norm:srv_make_money_saldo 801 801 0 0.00 0 3944 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 1551 393 0 0
v_add_invoice_to_stock 10334 10122 212 2.05 0 2688 14 0 212
v_all_customers 36722 36722 0 0.00 0 34 1 0 0
v_all_suppliers 29860 29860 0 0.00 0 15 1 0 0
v_all_wares 40492 40492 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 1115 9 0 0
v_cancel_client_order 3498 3498 0 0.00 0 4120 6 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 5924 13 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 2204 8 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 2485 10 0 0
v_cancel_supplier_invoice 934 906 28 2.99 0 4832 19 0 28
v_cancel_supplier_order 834 834 0 0.00 0 6672 14 0 0
v_cancel_write_off 1972 1972 0 0.00 0 1866 13 0 0
v_max_id_clo_ord 5390 5390 0 0.00 0 304 1 0 0
v_max_id_clo_res 984 984 0 0.00 0 7902 21 0 0
v_max_id_ord_sup 5497 5497 0 0.00 0 4594 3 0 0
v_max_non_paid_invoice 1714 1714 0 0.00 0 5297 7 0 0
v_max_non_paid_realizn 3821 3821 0 0.00 0 3667 7 0 0
v_min_id_clo_ord 5390 5390 0 0.00 0 1235 5 0 0
v_min_id_clo_res 984 984 0 0.00 0 2439 15 0 0
v_min_id_ord_sup 5497 5497 0 0.00 0 11412 13 0 0
v_min_non_paid_invoice 5757 5757 0 0.00 0 13836 18 0 0
v_min_non_paid_realizn 7007 7007 0 0.00 1 12323 25 0 0
v_our_firm 10142 10142 0 0.00 0 136 1 0 0
v_reserve_write_off 19858 19858 0 0.00 0 5692 7 0 0
x_lock_depdocs_on_canc_invoice 11666 11666 0 0.00 0 22061 129 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 19095 390 0 0
x_make_qstorno_1000_1200 5390 5390 0 0.00 1 44893 1189 0 0
x_make_qstorno_1200_2000 5502 5502 0 0.00 4 99122 3434 0 0
x_make_qstorno_2100_3300 467320 467320 0 0.00 0 94157 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-06-2022 16:40:02 to 18-06-2022 16:40:06.

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
14:08:31 3 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:32 33151 8192000000 9910374176 10220523520 982586480 10.709 100 87 175 0 1011483168 5789856 23106832
14:11:14 22563 8192000000 9945632752 10266558464 1025459456 11.125 100 93 186 0 1057836928 8212080 24165392
14:12:40 14429 8192000000 9981479968 10314649600 1066972688 11.523 100 77 154 1 1095071968 9420224 18679056
14:14:16 11131 8192000000 10052583152 10403975168 1134827584 12.167 100 78 156 1 1163335312 10483168 18024560
14:15:29 7654 8192000000 10108750544 10474409984 1193345360 12.714 100 82 164 0 1225331776 13623472 18362944
14:16:40 5873 8192000000 10145907696 10516291584 1239375248 13.140 100 84 169 1 1272371424 13690112 19306064
14:17:48 8683 8192000000 10172923920 10550026240 1259755360 13.328 100 76 149 2 1288611520 12534672 16321488
14:19:00 1788 8192000000 10199075696 10585128960 1291263824 13.616 100 83 166 2 1323019072 13373328 18381920
14:20:04 12973 8192000000 10209629552 10599702528 1303636096 13.728 100 61 120 3 1326921984 10090528 13195360
14:21:18 2128 8192000000 10208479728 10606825472 1309610336 13.783 100 48 92 2 1328459040 8617200 10231504
14:22:21 10007 8192000000 10192455680 10607235072 1310696592 13.792 100 20 40 0 1321598400 5961744 4940064
14:23:32 12260 8192000000 10212441312 10625953792 1324484656 13.917 100 32 65 0 1339509776 5436624 9588496
14:24:45 28471 8192000000 10211953312 10633711616 1326223072 13.933 100 25 50 2 1338068160 5603360 6241728
14:26:15 4111 8192000000 10227916272 10645798912 1335247520 14.015 100 32 64 0 1349416112 6129552 8039040
14:27:20 9935 8192000000 10225652048 10641383424 1325180432 13.924 99 22 44 0 1340327040 10489632 4656976
14:28:31 7978 8192000000 10206920160 10627649536 1312351088 13.807 99 20 39 2 1323383056 6533392 4498576
14:29:41 27051 8192000000 10171065040 10583736320 1263221712 13.360 97 45 91 0 1279831488 5541600 11068176
14:31:08 5832 8192000000 10143611312 10538811392 1192070048 12.703 94 26 52 3 1203563920 6001456 5492416
14:32:16 8729 8192000000 10138308928 10535231488 1216819680 12.932 97 29 58 0 1229278688 4628848 7830160
14:33:30 20500 8192000000 10151653072 10559578112 1233223760 13.084 97 25 49 1 1243734768 5395136 5115872
14:35:30 9486 8192000000 10170330736 10580406272 1281560672 13.527 100 23 46 0 1292603360 5861456 5181232
14:36:40 11853 8192000000 10178928832 10593841152 1290802016 13.612 100 19 38 1 1300650208 5273728 4574464
14:37:53 12722 8192000000 10199240672 10611494912 1302475520 13.718 100 14 27 0 1310475328 4785296 3214512
14:39:10 124982 8192000000 10238231872 10658201600 1337116880 14.031 100 44 88 2 1354556176 7995648 9443648
14:42:18 30497 8192000000 10269345568 10690351104 1358816176 14.227 100 41 80 1 1383970800 15951360 9203264
14:43:53 9632 8192000000 10298841040 10720858112 1378592608 14.404 100 53 105 1 1411907008 20742480 12571920
14:45:06 5877 8192000000 10308926624 10728321024 1384521760 14.457 100 48 94 0 1421437632 25757536 11158336
14:46:14 13846 8192000000 10314868048 10737676288 1378176672 14.400 99 63 125 0 1420267280 26754608 15336000
14:47:29 10213 8192000000 10324328112 10746986496 1396797664 14.566 100 49 98 1 1435947696 27526224 11623808
14:48:47 8760 8192000000 10332803760 10750984192 1386900032 14.478 99 65 128 0 1433152288 30678240 15574016
14:50:02 6097 8192000000 10343946448 10769739776 1409961312 14.684 100 52 104 2 1457490480 34184208 13344960
14:51:15 4533 8192000000 10329637440 10738724864 1382089104 14.435 100 75 149 1 1435367648 35265504 18013040
14:52:31 5725 8192000000 10330704800 10737643520 1366863952 14.299 99 80 159 0 1425634752 39736640 19034160
14:53:43 3757 8192000000 10317569248 10713866240 1359084192 14.229 98 79 158 1 1419587264 41602976 18900096
14:54:52 2592 8192000000 10334921072 10731532288 1364903696 14.281 99 86 172 1 1436214448 49821072 21489680
14:56:00 1742 8192000000 10348071040 10749403136 1363958880 14.273 99 75 147 2 1439022640 55657024 19406736
14:57:05 2985 8192000000 10355772528 10757988352 1370545184 14.332 100 78 155 2 1450812848 59858448 20409216
14:58:14 5242 8192000000 10346226400 10751541248 1364852448 14.281 99 81 161 2 1439382576 53551072 20979056
14:59:25 3349 8192000000 10349500560 10754166784 1370365312 14.330 100 82 165 3 1451058032 58665680 22027040
15:00:34 4527 8192000000 10351304624 10762678272 1388162960 14.489 100 66 132 1 1452397904 46918672 17316272
15:01:59 5819 8192000000 10346029392 10772619264 1395114240 14.551 100 48 96 0 1450119600 42248528 12756832
15:03:15 19159 8192000000 10332734752 10775437312 1381744352 14.432 99 29 58 2 1433707648 43800512 8162784
15:04:46 24115 8192000000 10336138672 10785726464 1383029376 14.444 100 23 46 0 1439484672 49876048 6579248
15:06:18 75959 8192000000 10292219440 10757197824 1366617376 14.297 99 13 26 1 1417383904 47056608 3709920
15:08:36 70906 8192000000 10300167616 10747764736 1377901136 14.398 100 13 26 0 1404989968 23920832 3168000
15:10:48 138873 8192000000 10296504496 10744741888 1363796224 14.271 98 28 56 0 1383852000 13353120 6702656
15:14:13 55473 8192000000 10304678224 10752004096 1385921408 14.469 99 18 36 0 1398486304 8000320 4564576
15:16:11 101811 8192000000 10327247840 10769674240 1395415808 14.554 99 27 54 1 1410869616 9609600 5844208
15:19:20 28457 8192000000 10304654912 10757148672 1392115920 14.525 100 37 70 0 1416727936 15777920 8834096
15:20:54 92576 8192000000 10294004160 10753859584 1349033136 14.139 96 17 34 0 1366721872 13685040 4003696
15:24:08 73656 8192000000 10305521024 10781523968 1402463936 14.617 100 11 22 0 1424522800 19349136 2709728
15:26:29 91050 8192000000 10302913536 10780983296 1398708800 14.583 99 16 32 1 1419974608 17332736 3933072
15:29:07 48317 8192000000 10288051712 10750562304 1368686016 14.315 98 20 40 0 1382303376 8811632 4805728
15:31:11 45928 8192000000 10311846448 10765176832 1389542592 14.502 99 19 39 1 1402283152 8404592 4335968
15:33:00 70963 8192000000 10303754400 10756300800 1385247472 14.463 99 16 32 0 1404195168 14989968 3957728
15:35:36 104744 8192000000 10304441104 10762723328 1358367424 14.223 97 20 40 1 1381904176 18790176 4746576
15:38:38 56114 8192000000 10308978384 10754191360 1360882640 14.245 97 13 26 0 1379680384 16458992 2338752
15:40:36 143714 8192000000 10237580176 10677071872 1201121312 12.787 86 17 34 0 1214176576 9617408 3437856
15:44:07 61641 8192000000 10264990560 10727686144 1346252352 14.114 98 10 20 1 1359744720 11439616 2052752
15:46:10 44979 8192000000 10271430528 10727518208 1362786800 14.262 99 8 16 0 1380095456 15392416 1916240
15:48:05 64694 8192000000 10238476608 10692546560 1251232800 13.250 92 12 23 2 1266988800 13125312 2630688
15:50:21 72626 8192000000 10241636416 10698428416 1327469664 13.944 98 15 30 0 1344042032 13389824 3182544
15:52:34 73124 8192000000 10265195216 10714501120 1345593328 14.108 99 20 40 0 1357185136 7338320 4253488
15:54:47 18551 8192000000 10271710256 10711474176 1345749680 14.109 99 37 74 0 1366455696 12811456 7894560
15:56:17 51224 8192000000 10278132064 10712027136 1330436192 13.971 98 33 66 1 1351213232 13343040 7434000
15:58:13 47657 8192000000 10275532912 10717442048 1347642512 14.126 99 19 38 0 1365959472 13900432 4416528
16:00:01 43966 8192000000 10279002608 10721320960 1359281104 14.231 99 40 80 1 1377447856 8775744 9391008
16:01:47 121657 8192000000 10274261952 10742358016 1330533392 13.972 97 20 40 0 1355522448 20585712 4403344
16:04:50 36872 8192000000 10294745168 10724425728 1365771744 14.289 100 40 80 0 1386250848 10411472 10067632
16:06:29 115315 8192000000 10291150192 10745278464 1343651360 14.090 98 24 48 1 1364677168 15249328 5776480
16:09:25 62798 8192000000 10287183040 10755518464 1374774704 14.370 100 9 18 0 1402106304 25691600 1640000
16:11:33 59484 8192000000 10277604512 10735599616 1356024576 14.202 98 10 18 0 1368905248 11110960 1769712
16:13:33 41432 8192000000 10294466544 10749526016 1383528352 14.448 100 25 52 0 1397483488 8300672 5654464
16:15:15 34142 8192000000 10306257376 10750238720 1370510240 14.332 98 22 44 0 1386477200 11088080 4878880
16:16:51 61776 8192000000 10285131312 10746355712 1362867680 14.263 98 9 18 1 1376403072 11796848 1738544
16:18:53 57664 8192000000 10294234608 10747215872 1364171728 14.275 98 23 46 1 1380141616 10988800 4981088
16:20:51 78607 8192000000 10282829760 10736517120 1346605696 14.117 97 13 26 0 1358642848 9512432 2524720
16:23:10 25759 8192000000 10315384128 10757623808 1375943632 14.380 99 49 98 2 1401138592 14186416 11008544
16:24:40 99850 8192000000 10276360304 10730160128 1274542016 13.463 92 3 6 1 1289554208 14382640 629552
16:27:20 120177 8192000000 10259278000 10709938176 1292268544 13.625 94 18 36 0 1308995456 13058000 3668912
16:30:34 25195 8192000000 10262915248 10716659712 1361809296 14.254 100 13 26 0 1370631520 6222016 2600208
16:32:12 76368 8192000000 10278710672 10730422272 1321532016 13.891 96 13 26 0 1338175616 13877968 2765632
16:34:30 72915 8192000000 10269627184 10728771584 1339816784 14.056 98 15 30 1 1356818784 13210208 3791792
16:36:43 65342 8192000000 10252441376 10724331520 1335252352 14.015 98 13 26 2 1351236352 12648448 3335552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-06-2022 16:40:08 to 18-06-2022 16:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-06-2022 16:40:24 to 18-06-2022 16:40:26.

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          238545
MON$OLDEST_ACTIVE               239307
MON$OLDEST_SNAPSHOT             239307
MON$NEXT_TRANSACTION            239307
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                       2040736
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:54: 
:55: mscs-test-161.budzdorov.ru    Sat Jun 18 16:38:27 2022
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Sat Jun 18 16:38:30 2022
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:61: 
--- end of diff output ---

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

18.06.22 16:40:27. 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-06-18 16:40:29 No severe PSQL-related problems occured
18.06.22 16:40:30. End of report.