Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.2021 22: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15769536 kB
MemAvailable 30642732 kB
Buffers 0 kB
Cached 15938876 kB
SwapCached 3704 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.8
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 2021-10-21 22:37:50
DTS_END                         2021-10-21 22:37:50.1830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40264
TRN_ID                          249348
ATT_ID                          657
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: 9507.58 18566 1140909
customer order: creation 185.06 302 22207
customer order: refuse 15.49 349 1859
order to supplier: creation 48.56 1162 5827
order to supplier: removal 3.62 87606 434
invoice (draft): creation 48.33 3038 5800
invoice (draft): removal 52.92 713 6350
invoice accept: apply 44.17 16801 5300
invoice accept: cancel 48.28 755 5794
customer reserve: creation 4067.04 168 488045
customer reserve: removal 4672.44 53 560693
realization accept: apply 87.53 158 10503
realization accept: cancel 95.18 95 11421
payment from customer: creation 63.83 85 7659
payment from customer: removal 6.48 67 777
payment to supplier: creation 52.51 109 6301
payment to supplier: removal 3.45 97 414
service: total inventory turnovers 4.95 1006 594
service: total monetary turnovers 6.77 36 812
service: aggregate perf. data 0.96 16911 115
service: refresh index statistics 0.03 241805 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-10-2021 22:39:17 to 21-10-2021 22:39:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1129
TEST_TIME 2 2226
TEST_TIME 3 2455
TEST_TIME 4 2856
TEST_TIME 5 4571
TEST_TIME 6 5408
TEST_TIME 7 5638
TEST_TIME 8 5645
TEST_TIME 9 6360
TEST_TIME 10 6210
TEST_TIME 11 6151
TEST_TIME 12 6053
TEST_TIME 13 6666
TEST_TIME 14 6885
TEST_TIME 15 6806
TEST_TIME 16 6909
TEST_TIME 17 6885
TEST_TIME 18 6889
TEST_TIME 19 7129
TEST_TIME 20 7332
TEST_TIME 21 7477
TEST_TIME 22 7753
TEST_TIME 23 8094
TEST_TIME 24 8465
TEST_TIME 25 8781
TEST_TIME 26 9311
TEST_TIME 27 9163
TEST_TIME 28 9360
TEST_TIME 29 10130
TEST_TIME 30 9984
TEST_TIME 31 9948
TEST_TIME 32 9814
TEST_TIME 33 9912
TEST_TIME 34 9878
TEST_TIME 35 9770
TEST_TIME 36 10062
TEST_TIME 37 10030
TEST_TIME 38 9992
TEST_TIME 39 9914
TEST_TIME 40 10157
TEST_TIME 41 10166
TEST_TIME 42 9964
TEST_TIME 43 9820
TEST_TIME 44 9694
TEST_TIME 45 9807
TEST_TIME 46 9655
TEST_TIME 47 9713
TEST_TIME 48 9654
TEST_TIME 49 9692
TEST_TIME 50 9684
TEST_TIME 51 9699
TEST_TIME 52 9681
TEST_TIME 53 9595
TEST_TIME 54 9621
TEST_TIME 55 9620
TEST_TIME 56 9547
TEST_TIME 57 9651
TEST_TIME 58 9642
TEST_TIME 59 9711
TEST_TIME 60 9761
TEST_TIME 61 9813
TEST_TIME 62 9857
TEST_TIME 63 9842
TEST_TIME 64 9894
TEST_TIME 65 9901
TEST_TIME 66 9917
TEST_TIME 67 9897
TEST_TIME 68 9980
TEST_TIME 69 10001
TEST_TIME 70 9900
TEST_TIME 71 9788
TEST_TIME 72 9831
TEST_TIME 73 9745
TEST_TIME 74 9698
TEST_TIME 75 9682
TEST_TIME 76 9654
TEST_TIME 77 9670
TEST_TIME 78 9617
TEST_TIME 79 9556
TEST_TIME 80 9477
TEST_TIME 81 9448
TEST_TIME 82 9495
TEST_TIME 83 9455
TEST_TIME 84 9493
TEST_TIME 85 9466
TEST_TIME 86 9573
TEST_TIME 87 9598
TEST_TIME 88 9565
TEST_TIME 89 9594
TEST_TIME 90 9602
TEST_TIME 91 9659
TEST_TIME 92 9666
TEST_TIME 93 9666
TEST_TIME 94 9762
TEST_TIME 95 9762
TEST_TIME 96 9828
TEST_TIME 97 9737
TEST_TIME 98 9675
TEST_TIME 99 9656
TEST_TIME 100 9612
TEST_TIME 101 9599
TEST_TIME 102 9541
TEST_TIME 103 9496
TEST_TIME 104 9523
TEST_TIME 105 9488
TEST_TIME 106 9467
TEST_TIME 107 9451
TEST_TIME 108 9483
TEST_TIME 109 9474
TEST_TIME 110 9510
TEST_TIME 111 9497
TEST_TIME 112 9518
TEST_TIME 113 9522
TEST_TIME 114 9525
TEST_TIME 115 9538
TEST_TIME 116 9547
TEST_TIME 117 9562
TEST_TIME 118 9605
TEST_TIME 119 9559
TEST_TIME 120 9509

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-10-2021 22:39:20 to 21-10-2021 22: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 1593489 1593489 0 0.00 0 17765 6 0 0
doc_list_biud 1593488 1593488 0 0.00 0 47696 24 0 0
sp_add_invoice_to_stock 5409 5300 109 2.01 37 214535 16802 0 109
sp_cancel_adding_invoice 5794 5794 0 0.00 5 41966 755 0 0
sp_cancel_client_order 1859 1859 0 0.00 4 11489 349 0 0
sp_cancel_customer_reserve 560693 560693 0 0.00 1 16114 53 0 0
sp_cancel_pay_from_customer 777 777 0 0.00 3 5473 67 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 6022 98 0 0
sp_cancel_supplier_invoice 6360 6350 10 0.15 2 15896 713 0 10
sp_cancel_supplier_order 434 434 0 0.00 9 929889 87606 0 0
sp_cancel_write_off 11421 11421 0 0.00 1 19354 96 0 0
sp_client_order 22207 22207 0 0.00 3 18452 302 0 0
sp_customer_reserve 488045 488045 0 0.00 0 114493 168 0 0
sp_fill_shopping_cart 33827 33827 0 0.00 1 19124 37 0 0
sp_fill_shopping_cart_clo_res 488043 488043 0 0.00 0 19757 11 0 0
sp_get_clo_for_invoice 5294 5294 0 0.00 0 26196 275 0 0
sp_kill_cost_storno 18406 18406 0 0.00 0 5513 47 0 0
sp_kill_qstorno_ret_qs2qd 1139591 1139591 0 0.00 0 19314 29 0 0
sp_kill_qty_storno 602348 602348 0 0.00 0 47695 63 0 0
sp_lock_selected_doc 21994 21994 0 0.00 0 7772 4 0 0
sp_make_cost_storno 29757 29757 0 0.00 0 17757 43 0 0
sp_multiply_rows_for_pdistr 29757 29757 0 0.00 0 8903 22 0 0
sp_multiply_rows_for_qdistr 22207 22207 0 0.00 0 11485 137 0 0
sp_pay_from_customer 7659 7659 0 0.00 4 7739 86 0 0
sp_pay_to_supplier 6301 6301 0 0.00 5 25265 110 0 0
sp_payment_common 13960 13960 0 0.00 1 25190 22 0 0
sp_qd_handle_on_cancel_clo 1859 1859 0 0.00 0 11477 311 0 0
sp_qd_handle_on_invoice_upd_sts 11088 11088 0 0.00 0 47694 314 0 0
sp_qd_handle_on_reserve_upd_sts 10503 10503 0 0.00 0 15125 58 0 0
sp_reserve_write_off 10503 10503 0 0.00 6 15227 158 0 0
sp_supplier_invoice 5800 5800 0 0.00 6 83492 3039 0 0
sp_supplier_order 5827 5827 0 0.00 5 61674 1162 0 0
srv_aggregate_perf_data 116 115 1 0.86 2732 162688 16912 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 2539 176166 48266 0 0
srv_find_qd_qs_mism 1090346 1090346 0 0.00 0 12363 5 0 0
srv_make_invnt_saldo 594 594 0 0.00 34 57474 1006 0 0
srv_make_money_saldo 812 812 0 0.00 1 12889 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 163858 331433 241805 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5300 5300 0 0.00 0 18002 142 0 0
t$perf-norm:sp_cancel_adding_invoice 467 467 0 0.00 0 5123 116 0 0
t$perf-norm:sp_cancel_client_order 1859 1859 0 0.00 0 8581 23 0 0
t$perf-norm:sp_cancel_customer_reserve 740 740 0 0.00 0 3978 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 777 777 0 0.00 0 1219 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 1324 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 716 10 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 17054 1409 0 0
t$perf-norm:sp_cancel_write_off 1067 1067 0 0.00 0 1691 10 0 0
t$perf-norm:sp_client_order 22207 22207 0 0.00 0 4975 17 0 0
t$perf-norm:sp_customer_reserve 1096 1096 0 0.00 0 3031 7 0 0
t$perf-norm:sp_pay_from_customer 7659 7659 0 0.00 0 6828 10 0 0
t$perf-norm:sp_pay_to_supplier 6301 6301 0 0.00 0 5484 10 0 0
t$perf-norm:sp_reserve_write_off 10503 10503 0 0.00 0 6373 11 0 0
t$perf-norm:sp_supplier_invoice 5800 5800 0 0.00 0 5435 15 0 0
t$perf-norm:sp_supplier_order 5827 5827 0 0.00 0 11660 16 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 377 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 48 1 0 0
t$perf-norm:srv_make_invnt_saldo 594 594 0 0.00 0 2235 19 0 0
t$perf-norm:srv_make_money_saldo 812 812 0 0.00 0 2309 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10806 10588 218 2.01 0 7389 19 0 218
v_all_customers 40678 40678 0 0.00 0 47 0 0 0
v_all_suppliers 32518 32518 0 0.00 0 13 1 0 0
v_all_wares 44414 44414 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 934 934 0 0.00 0 217 5 0 0
v_cancel_client_order 3718 3718 0 0.00 0 4860 5 0 0
v_cancel_customer_prepayment 1554 1554 0 0.00 0 5436 10 0 0
v_cancel_customer_reserve 1480 1480 0 0.00 0 5886 8 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 5892 17 0 0
v_cancel_supplier_invoice 898 878 20 2.22 0 886 15 0 20
v_cancel_supplier_order 868 868 0 0.00 0 391 3 0 0
v_cancel_write_off 2134 2134 0 0.00 0 5934 17 0 0
v_max_id_clo_ord 5825 5825 0 0.00 0 661 1 0 0
v_max_id_clo_res 1096 1096 0 0.00 0 1915 7 0 0
v_max_id_ord_sup 5793 5793 0 0.00 0 4561 6 0 0
v_max_non_paid_invoice 1659 1659 0 0.00 0 378 2 0 0
v_max_non_paid_realizn 3939 3939 0 0.00 0 2092 5 0 0
v_min_id_clo_ord 5825 5825 0 0.00 0 2314 4 0 0
v_min_id_clo_res 1096 1096 0 0.00 0 5709 11 0 0
v_min_id_ord_sup 5793 5793 0 0.00 0 19110 13 0 0
v_min_non_paid_invoice 6301 6301 0 0.00 0 25185 15 0 0
v_min_non_paid_realizn 7659 7659 0 0.00 0 7388 15 0 0
v_our_firm 11176 11176 0 0.00 0 14 1 0 0
v_reserve_write_off 21006 21006 0 0.00 0 5731 6 0 0
x_lock_depdocs_on_canc_invoice 11705 11705 0 0.00 0 30153 104 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 3946 265 0 0
x_make_qstorno_1000_1200 5827 5827 0 0.00 2 61668 1087 0 0
x_make_qstorno_1200_2000 5800 5800 0 0.00 3 80366 2892 0 0
x_make_qstorno_2100_3300 488045 488045 0 0.00 0 114491 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-10-2021 22:39:25 to 21-10-2021 22:39:29.

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:07:54 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:08:54 35695 8192000000 9901888992 10213212160 977511728 10.660 100 85 170 0 1006036160 6072816 22451616
20:10:34 21507 8192000000 9945862528 10270035968 1029519088 11.164 100 90 180 0 1061122672 8663936 22939648
20:12:02 21490 8192000000 9982006112 10314944512 1064274704 11.497 100 85 170 0 1094782816 9767120 20740992
20:13:28 17453 8192000000 10029604496 10377605120 1115318096 11.983 100 80 160 0 1144935392 10621648 18995648
20:14:48 9398 8192000000 10087491904 10451124224 1178006912 12.572 100 79 158 1 1207052320 10647952 18397456
20:15:59 3711 8192000000 10137336992 10509885440 1224553136 13.004 100 87 174 1 1255347104 11879216 18914752
20:17:06 3030 8192000000 10170841520 10550804480 1256913328 13.302 100 78 155 3 1287151664 12706416 17531920
20:18:11 2537 8192000000 10198925312 10581893120 1281819168 13.530 100 93 186 3 1316138528 13191488 21127872
20:19:15 1346 8192000000 10219957776 10603864064 1296966752 13.668 100 96 193 2 1332040720 13279920 21794048
20:20:17 2475 8192000000 10193047200 10600308736 1306119472 13.751 100 45 90 2 1322398496 5650848 10628176
20:21:21 6066 8192000000 10197118400 10611720192 1310636208 13.792 100 23 46 0 1322218560 5917520 5664832
20:22:29 9964 8192000000 10203933984 10617561088 1316037856 13.841 100 20 39 2 1325991504 5748032 4205616
20:23:43 56367 8192000000 10201555952 10627907584 1317449440 13.854 100 28 55 1 1329543488 5837984 6256064
20:25:40 12621 8192000000 10215623328 10639929344 1324069440 13.914 100 25 50 1 1340476304 10311184 6095680
20:26:53 7501 8192000000 10211742560 10640330752 1328700880 13.955 100 27 54 2 1341117504 6091904 6324720
20:28:01 71539 8192000000 10183255120 10601459712 1252999280 13.266 95 34 67 1 1266758064 5767904 7990880
20:30:13 6361 8192000000 10164049152 10567397376 1253239792 13.268 99 34 68 2 1271822464 10558160 8024512
20:31:19 7209 8192000000 10140861216 10539859968 1246541248 13.206 100 33 66 0 1261693984 7043008 8109728
20:32:27 11043 8192000000 10124038560 10523037696 1173773728 12.532 94 23 44 1 1185325760 5901456 5650576
20:33:38 26749 8192000000 10142830576 10550136832 1242812800 13.172 99 11 22 1 1250780880 5151760 2816320
20:35:08 8066 8192000000 10170583776 10582695936 1283852704 13.548 100 13 27 0 1292357600 5380880 3124016
20:36:16 8191 8192000000 10183200464 10597470208 1293386048 13.635 100 15 30 0 1302021744 4828992 3806704
20:37:29 59666 8192000000 10214746672 10625900544 1315538720 13.836 100 19 38 1 1324318352 4811200 3968432
20:39:53 5373 8192000000 10243542448 10658271232 1342097328 14.076 100 40 78 1 1359273344 8185552 8990464
20:41:10 29073 8192000000 10273716768 10685145088 1358517152 14.224 100 53 106 0 1384222336 13489920 12215264
20:43:07 7335 8192000000 10290150304 10705080320 1370637088 14.333 100 65 129 2 1398717216 12935520 15144608
20:44:22 5368 8192000000 10305026368 10722377728 1384590304 14.458 100 59 117 2 1410229664 11501520 14137840
20:45:31 19946 8192000000 10308714096 10732269568 1389634096 14.503 100 49 96 2 1418205040 17319872 11251072
20:46:57 2681 8192000000 10318504832 10737000448 1390644496 14.512 100 63 124 2 1423706848 18152768 14909584
20:48:02 6014 8192000000 10317788832 10744700928 1398596432 14.582 100 62 123 0 1433947392 20542544 14808416
20:49:13 10888 8192000000 10329120336 10754871296 1400714400 14.601 100 58 116 1 1442515840 27240176 14561264
20:50:30 7159 8192000000 10347059920 10769997824 1404279328 14.633 100 78 154 0 1454524448 31361504 18883616
20:51:44 7131 8192000000 10346947280 10771529728 1408831680 14.674 100 64 126 1 1453949056 29829584 15287792
20:52:55 4379 8192000000 10353929040 10774028288 1412081616 14.702 100 69 138 1 1459561024 30262016 17217392
20:54:04 3515 8192000000 10363746736 10790633472 1423090992 14.800 100 60 120 1 1469745584 32121376 14533216
20:55:15 3538 8192000000 10357392288 10779770880 1408263680 14.669 99 59 117 0 1460238448 37650352 14324416
20:56:25 2530 8192000000 10377111696 10796490752 1419142608 14.765 100 68 135 2 1480101520 43932960 17025952
20:57:32 7317 8192000000 10326441120 10749796352 1376602784 14.386 100 43 83 0 1429871856 42209472 11059600
20:58:44 4322 8192000000 10308978352 10716798976 1354165360 14.185 100 58 116 3 1413957040 44360288 15431392
20:59:54 4623 8192000000 10340917072 10758709248 1377855376 14.397 100 64 128 0 1445425136 50833888 16735872
21:01:01 26865 8192000000 10309348000 10734776320 1339026240 14.049 98 36 72 0 1404636112 55534208 10075664
21:02:30 24077 8192000000 10299467232 10728697856 1342871600 14.083 99 29 58 1 1395969504 44754384 8343520
21:03:57 20092 8192000000 10262842880 10685431808 1311216928 13.797 98 51 103 0 1353941392 27869152 14855312
21:05:19 7223 8192000000 10286370736 10714669056 1343001872 14.084 100 33 66 0 1387571920 36316768 8253280
21:06:30 44065 8192000000 10266919888 10706579456 1351440528 14.160 100 15 28 2 1367150800 12473408 3236864
21:08:19 41802 8192000000 10273438640 10715717632 1347845440 14.128 98 19 40 1 1359180672 7351152 3984080
21:10:01 36585 8192000000 10274035248 10706681856 1363826720 14.272 100 37 75 2 1379192272 6227008 9138544
21:11:39 36153 8192000000 10285888064 10712735744 1333868032 14.002 97 23 46 1 1355578304 15711168 5999104
21:13:19 84589 8192000000 10286671936 10732187648 1377045200 14.390 100 12 24 2 1393053840 13002944 3005696
21:16:03 15902 8192000000 10314276528 10761363456 1400844864 14.603 100 37 74 0 1424251520 14686336 8720320
21:17:22 73869 8192000000 10305175216 10753519616 1384977712 14.461 99 7 14 1 1401358176 15039936 1340528
21:19:55 89594 8192000000 10330629808 10772635648 1390889360 14.514 99 47 93 2 1412950992 10985696 11075936
21:22:28 94342 8192000000 10330291840 10781093888 1409932960 14.683 100 26 49 3 1431853216 15972208 5948048
21:25:03 47999 8192000000 10329492976 10782633984 1404983152 14.639 99 21 41 2 1420867152 11060320 4823680
21:26:52 32334 8192000000 10326908192 10795196416 1419137088 14.765 100 29 58 1 1443213376 16312336 7763952
21:28:26 176166 8192000000 10327188928 10803073024 1420857232 14.780 100 9 16 1 1438930352 16573488 1499632
21:32:45 33866 8192000000 10327467120 10788818944 1411232288 14.695 99 21 42 2 1428149248 12227936 4689024
21:34:26 66395 8192000000 10320176976 10788806656 1375172208 14.373 97 12 24 0 1391775760 13684128 2919424
21:36:33 61466 8192000000 10283168240 10743898112 1328568576 13.954 95 12 24 0 1341715872 10515712 2631584
21:38:37 61984 8192000000 10292582880 10751008768 1344814208 14.101 97 15 30 2 1356622896 8584960 3223728
21:40:41 51625 8192000000 10298459920 10764447744 1377265504 14.392 99 7 14 0 1388086368 9713536 1107328
21:42:33 61118 8192000000 10288499328 10741379072 1329490160 13.963 96 22 44 1 1347282192 13115888 4676144
21:44:35 26972 8192000000 10272010736 10711220224 1337999456 14.039 99 38 76 1 1362946512 16437440 8509616
21:46:02 80223 8192000000 10237077344 10687205376 1280478544 13.517 95 11 22 0 1295781696 13129760 2173392
21:48:36 92187 8192000000 10231622496 10681163776 1236222512 13.111 92 26 52 0 1251448224 9908336 5317376
21:51:10 15044 8192000000 10254086816 10704228352 1349976256 14.147 100 32 63 1 1369949584 12761168 7212160
21:52:25 58275 8192000000 10258031632 10712629248 1345285456 14.105 99 18 36 2 1364678096 15467472 3925168
21:54:29 47372 8192000000 10261303440 10710364160 1322966512 13.904 97 25 48 0 1336511152 7910656 5633984
21:56:16 68577 8192000000 10254860512 10715140096 1356725664 14.208 100 11 22 0 1371846080 12673488 2446928
21:58:39 83014 8192000000 10263119264 10723221504 1342817904 14.083 98 17 34 1 1356150896 9338464 3994528
22:01:03 79516 8192000000 10293762432 10757783552 1382813968 14.442 100 20 40 0 1404437056 16910128 4712960
22:03:45 66391 8192000000 10290313712 10760060928 1363172768 14.266 98 9 19 0 1377304784 11966352 2165664
22:05:52 63151 8192000000 10295949600 10754949120 1368015216 14.309 98 19 38 0 1382160896 10139552 4006128
22:07:58 75088 8192000000 10304752544 10772086784 1376002720 14.381 98 10 20 2 1391776848 13763664 2010464
22:10:13 94677 8192000000 10314924256 10783154176 1399519680 14.591 99 11 22 0 1412706224 10792672 2393872
22:12:48 117934 8192000000 10322878944 10793377792 1388639248 14.494 98 10 19 0 1405801728 15318208 1844272
22:15:56 56427 8192000000 10312892336 10779959296 1392327888 14.527 98 35 70 2 1408235200 8125712 7781600
22:18:02 70973 8192000000 10295451648 10766127104 1360799056 14.245 96 7 14 0 1370123296 7817344 1506896
22:20:13 116905 8192000000 10290973792 10764337152 1350968192 14.156 96 10 20 0 1365279392 11762720 2548480
22:23:17 86449 8192000000 10293167408 10761175040 1355783776 14.199 97 11 22 1 1367661600 9315920 2561904
22:25:47 44489 8192000000 10277463568 10730745856 1319211008 13.870 95 19 38 0 1334920464 10860688 4848768
22:27:41 64476 8192000000 10275411328 10732724224 1343386080 14.088 98 20 40 1 1361041568 13019056 4636432
22:29:51 62657 8192000000 10262966064 10715852800 1323559136 13.909 96 15 30 0 1335166560 8512752 3094672
22:31:54 76085 8192000000 10240853424 10679980032 1255967152 13.293 93 35 70 1 1273869232 10301648 7600432
22:34:12 58570 8192000000 10246120960 10688151552 1268760976 13.410 95 31 62 2 1293926000 18600576 6564448
22:36:12 104528 8192000000 8991494016 9188978688 165501040 1.980 13 4 8 0 167214512 1358656 354816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-10-2021 22:39:31 to 21-10-2021 22:39:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 109 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-10-2021 22:39:46 to 21-10-2021 22:39:48.

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          249643
MON$OLDEST_ACTIVE               249644
MON$OLDEST_SNAPSHOT             249644
MON$NEXT_TRANSACTION            249644
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                       2044064
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33522 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33522 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33522 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33522 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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Thu Oct 21 22:37:59 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Thu Oct 21 22:37:59 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:27: 
--- end of diff output ---

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

21.10.21 22:39:49. 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
2021-10-21 22:39:52 No severe PSQL-related problems occured
21.10.21 22:39:53. End of report.