Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2021 01: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 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 15879420 kB
MemAvailable 30668948 kB
Buffers 0 kB
Cached 15841648 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-18 01:37:49
DTS_END                         2021-10-18 01:37:49.4190
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/36074
TRN_ID                          241501
ATT_ID                          691
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: 9462.67 21305 1135520
customer order: creation 172.86 326 20743
customer order: refuse 15.00 332 1800
order to supplier: creation 45.98 1247 5518
order to supplier: removal 3.43 103856 412
invoice (draft): creation 45.24 3268 5429
invoice (draft): removal 52.88 821 6346
invoice accept: apply 42.53 18355 5103
invoice accept: cancel 48.68 750 5841
customer reserve: creation 3956.68 181 474801
customer reserve: removal 4777.76 59 573331
realization accept: apply 85.02 155 10202
realization accept: cancel 86.28 117 10354
payment from customer: creation 59.85 99 7182
payment from customer: removal 5.76 61 691
payment to supplier: creation 48.88 113 5865
payment to supplier: removal 3.43 77 411
service: total inventory turnovers 4.61 1332 553
service: total monetary turnovers 6.88 33 826
service: aggregate perf. data 0.90 20886 108
service: refresh index statistics 0.03 274023 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-10-2021 01:40:14 to 18-10-2021 01:40:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1127
TEST_TIME 2 1635
TEST_TIME 3 2771
TEST_TIME 4 3771
TEST_TIME 5 3836
TEST_TIME 6 4030
TEST_TIME 7 4413
TEST_TIME 8 4663
TEST_TIME 9 4937
TEST_TIME 10 5082
TEST_TIME 11 5178
TEST_TIME 12 5623
TEST_TIME 13 6116
TEST_TIME 14 6387
TEST_TIME 15 6404
TEST_TIME 16 6482
TEST_TIME 17 7004
TEST_TIME 18 7319
TEST_TIME 19 7470
TEST_TIME 20 7852
TEST_TIME 21 8583
TEST_TIME 22 9074
TEST_TIME 23 9167
TEST_TIME 24 9560
TEST_TIME 25 9615
TEST_TIME 26 9850
TEST_TIME 27 10131
TEST_TIME 28 10364
TEST_TIME 29 11125
TEST_TIME 30 10853
TEST_TIME 31 10908
TEST_TIME 32 10960
TEST_TIME 33 10783
TEST_TIME 34 10558
TEST_TIME 35 10511
TEST_TIME 36 10315
TEST_TIME 37 10203
TEST_TIME 38 10232
TEST_TIME 39 10152
TEST_TIME 40 10086
TEST_TIME 41 10108
TEST_TIME 42 10236
TEST_TIME 43 10208
TEST_TIME 44 10138
TEST_TIME 45 10090
TEST_TIME 46 9963
TEST_TIME 47 9897
TEST_TIME 48 9839
TEST_TIME 49 9821
TEST_TIME 50 9843
TEST_TIME 51 9741
TEST_TIME 52 9645
TEST_TIME 53 9578
TEST_TIME 54 9701
TEST_TIME 55 9651
TEST_TIME 56 9674
TEST_TIME 57 9683
TEST_TIME 58 9633
TEST_TIME 59 9729
TEST_TIME 60 9673
TEST_TIME 61 9738
TEST_TIME 62 9947
TEST_TIME 63 9932
TEST_TIME 64 9998
TEST_TIME 65 10040
TEST_TIME 66 10016
TEST_TIME 67 10024
TEST_TIME 68 9985
TEST_TIME 69 9871
TEST_TIME 70 9858
TEST_TIME 71 9816
TEST_TIME 72 9799
TEST_TIME 73 9794
TEST_TIME 74 9744
TEST_TIME 75 9768
TEST_TIME 76 9779
TEST_TIME 77 9702
TEST_TIME 78 9744
TEST_TIME 79 9655
TEST_TIME 80 9672
TEST_TIME 81 9654
TEST_TIME 82 9673
TEST_TIME 83 9646
TEST_TIME 84 9707
TEST_TIME 85 9730
TEST_TIME 86 9677
TEST_TIME 87 9689
TEST_TIME 88 9660
TEST_TIME 89 9646
TEST_TIME 90 9754
TEST_TIME 91 9751
TEST_TIME 92 9716
TEST_TIME 93 9756
TEST_TIME 94 9669
TEST_TIME 95 9631
TEST_TIME 96 9583
TEST_TIME 97 9594
TEST_TIME 98 9559
TEST_TIME 99 9520
TEST_TIME 100 9490
TEST_TIME 101 9443
TEST_TIME 102 9442
TEST_TIME 103 9460
TEST_TIME 104 9428
TEST_TIME 105 9470
TEST_TIME 106 9456
TEST_TIME 107 9465
TEST_TIME 108 9450
TEST_TIME 109 9494
TEST_TIME 110 9521
TEST_TIME 111 9513
TEST_TIME 112 9476
TEST_TIME 113 9525
TEST_TIME 114 9606
TEST_TIME 115 9604
TEST_TIME 116 9606
TEST_TIME 117 9529
TEST_TIME 118 9463
TEST_TIME 119 9487
TEST_TIME 120 9467

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-10-2021 01:40:18 to 18-10-2021 01:40:21.

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 1573932 1573932 0 0.00 0 12322 6 0 0
doc_list_biud 1573932 1573932 0 0.00 0 81281 27 0 0
sp_add_invoice_to_stock 5272 5103 169 3.20 40 214873 18356 0 169
sp_cancel_adding_invoice 5841 5841 0 0.00 4 66302 750 0 0
sp_cancel_client_order 1800 1800 0 0.00 4 12557 333 0 0
sp_cancel_customer_reserve 573331 573331 0 0.00 1 23130 59 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 2692 62 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 3235 77 0 0
sp_cancel_supplier_invoice 6363 6346 17 0.26 1 81287 821 0 17
sp_cancel_supplier_order 412 412 0 0.00 8 1041034 103857 0 0
sp_cancel_write_off 10354 10354 0 0.00 1 10769 117 0 0
sp_client_order 20743 20743 0 0.00 3 26008 327 0 0
sp_customer_reserve 474801 474801 0 0.00 0 86617 182 0 0
sp_fill_shopping_cart 31674 31674 0 0.00 1 13682 38 0 0
sp_fill_shopping_cart_clo_res 474786 474786 0 0.00 0 44608 14 0 0
sp_get_clo_for_invoice 5088 5088 0 0.00 0 41996 288 0 0
sp_kill_cost_storno 17297 17297 0 0.00 0 8528 54 0 0
sp_kill_qstorno_ret_qs2qd 1163774 1163774 0 0.00 0 81278 32 0 0
sp_kill_qty_storno 613374 613374 0 0.00 0 81280 69 0 0
sp_lock_selected_doc 21200 21200 0 0.00 0 9181 5 0 0
sp_make_cost_storno 28337 28337 0 0.00 0 12299 44 0 0
sp_multiply_rows_for_pdistr 28337 28337 0 0.00 0 7667 27 0 0
sp_multiply_rows_for_qdistr 20743 20743 0 0.00 0 21746 151 0 0
sp_pay_from_customer 7182 7182 0 0.00 4 13991 100 0 0
sp_pay_to_supplier 5865 5865 0 0.00 4 13858 114 0 0
sp_payment_common 13047 13047 0 0.00 1 13985 22 0 0
sp_qd_handle_on_cancel_clo 1800 1800 0 0.00 0 6526 302 0 0
sp_qd_handle_on_invoice_upd_sts 10929 10929 0 0.00 0 13541 315 0 0
sp_qd_handle_on_reserve_upd_sts 10202 10202 0 0.00 0 12117 54 0 0
sp_reserve_write_off 10202 10202 0 0.00 7 14359 156 0 0
sp_supplier_invoice 5429 5429 0 0.00 7 83672 3268 0 0
sp_supplier_order 5518 5518 0 0.00 5 37565 1247 0 0
srv_aggregate_perf_data 108 108 0 0.00 2869 227873 20887 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1525 133701 47975 0 0
srv_find_qd_qs_mism 1086587 1086587 0 0.00 0 8937 5 0 0
srv_make_invnt_saldo 553 553 0 0.00 38 68629 1332 0 0
srv_make_money_saldo 826 826 0 0.00 1 8776 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 146083 437304 274023 0 0
t$perf-abend:sp_add_invoice_to_stock 169 169 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5103 5103 0 0.00 0 34612 190 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 1 2752 78 0 0
t$perf-norm:sp_cancel_client_order 1800 1800 0 0.00 0 3536 14 0 0
t$perf-norm:sp_cancel_customer_reserve 738 738 0 0.00 0 12040 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 135 1 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 534 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 257 7 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 23612 1714 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 4027 8 0 0
t$perf-norm:sp_client_order 20744 20744 0 0.00 0 18146 20 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 2340 6 0 0
t$perf-norm:sp_pay_from_customer 7182 7182 0 0.00 0 10248 13 0 0
t$perf-norm:sp_pay_to_supplier 5865 5865 0 0.00 0 17043 19 0 0
t$perf-norm:sp_reserve_write_off 10202 10202 0 0.00 0 11550 14 0 0
t$perf-norm:sp_supplier_invoice 5429 5429 0 0.00 0 8209 17 0 0
t$perf-norm:sp_supplier_order 5518 5518 0 0.00 0 13203 22 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 171 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 249 8 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 643 19 0 0
t$perf-norm:srv_make_money_saldo 826 826 0 0.00 0 9877 32 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 16 6 0 0
v_add_invoice_to_stock 10514 10176 338 3.21 0 5247 19 0 338
v_all_customers 38026 38026 0 0.00 0 105 1 0 0
v_all_suppliers 30344 30344 0 0.00 0 15 1 0 0
v_all_wares 41486 41486 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 499 6 0 0
v_cancel_client_order 3600 3600 0 0.00 0 12288 9 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 2681 7 0 0
v_cancel_customer_reserve 1476 1476 0 0.00 0 7116 10 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 522 3 0 0
v_cancel_supplier_invoice 842 808 34 4.03 0 1432 18 0 34
v_cancel_supplier_order 824 824 0 0.00 0 2460 11 0 0
v_cancel_write_off 2018 2018 0 0.00 0 4589 19 0 0
v_max_id_clo_ord 5518 5518 0 0.00 0 2280 2 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 2988 17 0 0
v_max_id_ord_sup 5413 5413 0 0.00 0 2068 3 0 0
v_max_non_paid_invoice 1624 1624 0 0.00 0 1058 3 0 0
v_max_non_paid_realizn 3768 3768 0 0.00 0 460 3 0 0
v_min_id_clo_ord 5518 5518 0 0.00 0 2356 7 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 1270 4 0 0
v_min_id_ord_sup 5413 5413 0 0.00 0 4547 5 0 0
v_min_non_paid_invoice 5865 5865 0 0.00 0 5954 13 0 0
v_min_non_paid_realizn 7182 7182 0 0.00 0 13980 18 0 0
v_our_firm 10288 10288 0 0.00 0 51 1 0 0
v_reserve_write_off 20404 20404 0 0.00 0 14187 7 0 0
x_lock_depdocs_on_canc_invoice 11783 11783 0 0.00 0 19315 116 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 6496 331 0 0
x_make_qstorno_1000_1200 5518 5518 0 0.00 1 37541 1166 0 0
x_make_qstorno_1200_2000 5429 5429 0 0.00 4 80354 3132 0 0
x_make_qstorno_2100_3300 474801 474801 0 0.00 0 86615 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-10-2021 01:40:22 to 18-10-2021 01:40:26.

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:07:53 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:08:54 22423 8192000000 9897357680 10206822400 982189184 10.706 100 88 176 0 1011302080 6060752 23052144
23:10:24 26718 8192000000 9947022672 10268270592 1025593088 11.126 100 88 176 0 1056950880 8467200 22890592
23:12:00 10704 8192000000 9977328944 10308345856 1062953136 11.485 100 88 176 0 1094782048 9335264 22493648
23:13:23 8398 8192000000 10039152096 10386321408 1123061200 12.056 100 79 157 1 1152565920 11116064 18388656
23:14:36 12284 8192000000 10090557040 10453819392 1177007936 12.562 100 85 169 1 1209198208 13450832 18739440
23:15:52 5355 8192000000 10130559856 10501189632 1219541088 12.957 100 89 177 2 1251854144 12850272 19462784
23:16:59 2375 8192000000 10162170912 10538672128 1247904608 13.219 100 88 174 1 1282012256 14096784 20010864
23:18:02 1851 8192000000 10203480256 10585432064 1287767712 13.584 100 98 199 2 1324193120 14061952 22363456
23:19:05 15746 8192000000 10217724432 10610806784 1306161104 13.751 100 71 141 2 1331315200 9385024 15769072
23:20:21 5318 8192000000 10207450784 10619572224 1318985152 13.868 100 39 78 1 1334472880 6445040 9042688
23:21:30 6385 8192000000 10212850416 10630144000 1325232912 13.924 100 33 67 3 1339868240 7235840 7399488
23:22:41 6326 8192000000 10230554096 10648621056 1339106240 14.049 100 25 52 1 1350812112 6287328 5418544
23:23:51 18534 8192000000 10222769824 10649395200 1338997520 14.048 100 23 46 0 1349657824 5482224 5178080
23:25:14 8404 8192000000 10237258832 10658496512 1333273008 13.997 99 32 64 1 1347435120 6769008 7393104
23:26:25 6464 8192000000 10239037536 10661679104 1347290304 14.123 100 32 64 1 1366429744 10845744 8293696
23:27:32 65573 8192000000 10209359712 10631446528 1294544832 13.646 97 24 47 3 1309643584 9503040 5595712
23:29:39 7965 8192000000 10182603040 10587013120 1264972144 13.376 98 29 58 0 1278664304 6003488 7688672
23:30:48 3789 8192000000 10128874704 10528608256 1194390032 12.724 95 27 54 0 1206696320 5617312 6688976
23:31:52 28188 8192000000 10143786416 10549456896 1222017296 12.980 97 29 58 0 1234060944 5695440 6348208
23:33:21 13360 8192000000 10152887968 10556547072 1249590656 13.234 99 33 66 1 1263504144 6697264 7216224
23:34:37 9963 8192000000 10183144464 10588704768 1284829664 13.557 100 27 54 1 1297371008 6169280 6372064
23:35:50 7996 8192000000 10180498704 10596896768 1294993984 13.650 100 14 28 0 1303954288 5833888 3126416
23:36:58 129563 8192000000 10227727168 10640650240 1315893776 13.840 100 29 58 1 1328350496 5499424 6957296
23:40:12 20837 8192000000 10257474432 10670522368 1346657616 14.117 100 53 106 0 1376266128 17906352 11702160
23:41:40 30823 8192000000 10271491488 10693742592 1358863568 14.227 100 36 71 0 1386109120 18668752 8576800
23:43:27 17411 8192000000 10299984512 10721447936 1378778320 14.406 100 41 82 1 1413855408 25419216 9657872
23:45:13 3863 8192000000 10325433600 10745245696 1393286528 14.535 100 60 118 1 1433827216 26196512 14344176
23:46:19 2230 8192000000 10349893232 10764029952 1405895952 14.647 100 77 154 2 1457456128 32576256 18983920
23:47:25 5749 8192000000 10344557856 10770247680 1410706272 14.690 100 55 110 1 1459085376 34337392 14041712
23:48:40 4908 8192000000 10360791392 10782367744 1415280432 14.731 100 63 127 1 1470802496 40061216 15460848
23:49:50 14727 8192000000 10347882272 10765705216 1391776528 14.522 98 70 140 0 1448525248 39920240 16828480
23:51:11 1523 8192000000 10362783504 10772385792 1404426864 14.634 100 83 168 1 1465492112 40706368 20358880
23:52:17 1926 8192000000 10357913024 10767585280 1396506880 14.564 100 89 175 2 1457637008 40191680 20938448
23:53:25 2186 8192000000 10383249856 10786222080 1406892928 14.656 100 94 187 3 1479468000 49883040 22692032
23:54:31 4460 8192000000 10342484592 10754928640 1361838336 14.254 98 61 122 1 1428558032 51079248 15640448
23:55:39 11771 8192000000 10332329536 10755141632 1369434240 14.322 99 37 74 1 1432524016 52772832 10316944
23:57:02 8340 8192000000 10335976880 10750099456 1364944640 14.282 99 66 129 0 1436913472 54084912 17883920
23:58:15 12590 8192000000 10317500608 10741813248 1356492656 14.206 99 42 82 2 1420091744 52516400 11082688
23:59:43 12992 8192000000 10312813648 10745548800 1367465968 14.304 100 30 60 0 1421990240 45815392 8708880
00:00:59 23021 8192000000 10298472832 10733256704 1335954144 14.021 98 26 52 1 1387127536 43965648 7207744
00:02:30 11137 8192000000 10291088896 10728890368 1352210256 14.167 100 29 58 1 1403821520 43470880 8140384
00:03:49 39526 8192000000 10296046608 10738814976 1347461328 14.125 99 21 42 1 1392094928 38425552 6208048
00:05:30 26181 8192000000 10263359216 10705420288 1313864752 13.821 98 16 32 1 1359278928 40870224 4543952
00:06:57 38692 8192000000 10267368096 10694725632 1318129696 13.860 97 34 69 1 1344623968 18486672 8007600
00:08:40 85756 8192000000 10284921696 10741706752 1373872672 14.362 100 14 28 0 1395150656 18672048 2605936
00:11:06 55672 8192000000 10295800944 10729476096 1366637008 14.297 100 51 102 2 1388960144 10482944 11840192
00:13:06 133699 8192000000 10280909600 10733301760 1342164528 14.077 97 26 52 2 1360584624 11935360 6484736
00:16:26 64204 8192000000 10303472912 10750947328 1370017680 14.327 99 29 57 2 1395634608 18368240 7248688
00:18:31 48313 8192000000 10281427360 10753064960 1381863216 14.433 100 17 34 0 1406440784 20245488 4332080
00:20:20 81096 8192000000 10285329904 10751549440 1386816368 14.477 100 7 14 0 1397238400 8974432 1447600
00:22:50 59972 8192000000 10284785424 10740871168 1376389648 14.384 99 10 20 1 1393333312 14752208 2191456
00:24:51 66870 8192000000 10295601216 10767323136 1384865920 14.460 99 14 28 2 1408097744 19432880 3798944
00:27:02 95549 8192000000 10300618800 10762969088 1398647312 14.583 100 18 37 0 1412807200 10483568 3676320
00:29:39 91803 8192000000 10317304416 10792525824 1409662928 14.681 100 12 24 0 1432098096 19918432 2516736
00:32:11 41985 8192000000 10316007056 10777313280 1411884256 14.701 100 18 34 0 1427963168 12287552 3791360
00:33:53 55565 8192000000 10312221664 10770415616 1381712240 14.432 98 14 28 1 1396519008 11707568 3099200
00:35:49 42123 8192000000 10328364528 10788278272 1401304896 14.607 100 23 46 1 1423782800 16503728 5974176
00:37:49 79671 8192000000 10323279344 10794004480 1396292800 14.562 99 15 30 1 1414179280 14671184 3215296
00:40:10 72995 8192000000 10310032480 10763374592 1321660912 13.892 94 11 22 0 1335222464 11279648 2281904
00:43:07 73534 8192000000 10288354912 10722590720 1211783584 12.886 86 39 75 1 1232220144 11814912 8621648
00:45:24 47236 8192000000 10269009040 10711539712 1342764160 14.082 98 29 58 1 1362733792 13683712 6285920
00:47:12 119806 8192000000 10250412720 10704953344 1267510576 13.399 92 21 42 1 1287491872 14739984 5241312
00:50:21 19978 8192000000 10273908272 10718707712 1358590512 14.225 100 59 117 2 1391452928 19255232 13607184
00:52:36 73793 8192000000 10236152192 10688090112 1270162144 13.423 93 16 33 0 1289854384 15613504 4078736
00:54:56 53588 8192000000 10248165904 10706407424 1333973216 14.003 99 10 20 0 1353456640 16975920 2507504
00:56:50 45012 8192000000 10249967872 10709299200 1326023376 13.931 98 15 30 0 1339390896 10263408 3104112
00:58:36 116149 8192000000 10286848272 10739400704 1356245008 14.204 99 13 26 1 1371110912 11861040 3004864
01:01:44 74587 8192000000 10278586784 10750582784 1353813760 14.182 98 6 12 0 1370260768 15121344 1325664
01:04:00 45695 8192000000 10289905712 10733977600 1352782480 14.173 98 36 72 0 1371924672 11526560 7615632
01:05:48 13968 8192000000 10290662768 10724470784 1350846320 14.155 98 37 74 1 1371778032 12740592 8191120
01:07:03 46906 8192000000 10281709728 10737057792 1350479216 14.152 98 20 40 0 1370086656 15436688 4170752
01:08:54 40305 8192000000 10303905440 10744750080 1367090000 14.301 98 24 48 1 1379742192 7381280 5270912
01:10:36 119832 8192000000 10288925952 10749292544 1379112400 14.409 99 26 51 1 1395025408 9250848 6662160
01:13:37 2283 8192000000 10302427024 10751766528 1395119424 14.552 100 48 93 2 1413457936 7758976 10579536
01:14:41 79493 8192000000 10291577280 10763112448 1394272240 14.544 100 9 18 1 1405140480 9033440 1834800
01:17:09 77785 8192000000 10294089024 10757521408 1377823424 14.397 98 14 26 1 1388752112 7643296 3285392
01:19:59 60889 8192000000 10299880176 10771316736 1378678128 14.405 98 17 34 1 1392813536 10061008 4074400
01:22:00 40369 8192000000 10301996512 10775703552 1401390720 14.607 100 41 80 1 1421927296 11260208 9276368
01:23:43 58740 8192000000 10298920064 10759446528 1365250816 14.284 97 18 36 0 1379309264 10017072 4041376
01:25:42 74923 8192000000 10277043856 10725462016 1308214176 13.770 94 15 30 2 1321523200 10027856 3281168
01:28:03 35664 8192000000 10254116848 10703900672 1308345776 13.771 96 18 36 2 1320600640 8490512 3764352
01:29:39 67091 8192000000 10275136720 10725695488 1318737552 13.865 97 30 60 0 1346904784 21426064 6741168
01:31:57 56893 8192000000 10268806960 10719277056 1320926672 13.885 97 15 30 1 1333599200 9562192 3110336
01:35:44 23281 8192000000 10270667376 10701365248 1344755760 14.100 99 59 118 3 1369867680 11358432 13753488
01:37:07 54810 8192000000 9967869936 10389413888 1075108064 11.601 79 6 11 1 1087168368 11128656 931648

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-10-2021 01:40:28 to 18-10-2021 01:40:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 169 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-10-2021 01:40:44 to 18-10-2021 01:40:46.

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          241077
MON$OLDEST_ACTIVE               241742
MON$OLDEST_SNAPSHOT             241742
MON$NEXT_TRANSACTION            241742
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                       2033952
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:509: 
:510: mscs-test-161.budzdorov.ru    Mon Oct 18 01:38:26 2021
:511:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:512: 
--- end of diff output ---

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

18.10.21 01:40:47. 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-18 01:40:49 No severe PSQL-related problems occured
18.10.21 01:40:50. End of report.