Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.09.2021 19: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 16137264 kB
MemAvailable 30870320 kB
Buffers 0 kB
Cached 15527008 kB
SwapCached 336 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-09-03 19:37:50
DTS_END                         2021-09-03 19:37:50.2590
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/51324
TRN_ID                          248765
ATT_ID                          709
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: 9586.45 18974 1150374
customer order: creation 183.75 352 22050
customer order: refuse 15.49 314 1859
order to supplier: creation 49.03 1238 5884
order to supplier: removal 3.88 89549 466
invoice (draft): creation 48.68 3087 5842
invoice (draft): removal 54.55 753 6546
invoice accept: apply 43.97 18025 5276
invoice accept: cancel 49.63 737 5956
customer reserve: creation 4013.36 181 481603
customer reserve: removal 4811.31 57 577357
realization accept: apply 88.68 159 10642
realization accept: cancel 87.19 112 10463
payment from customer: creation 62.18 102 7462
payment from customer: removal 5.98 77 718
payment to supplier: creation 52.32 104 6278
payment to supplier: removal 3.57 57 428
service: total inventory turnovers 5.00 1085 600
service: total monetary turnovers 6.83 24 820
service: aggregate perf. data 1.00 15105 120
service: refresh index statistics 0.03 248352 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-09-2021 19:38:26 to 03-09-2021 19:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3358
TEST_TIME 2 4425
TEST_TIME 3 4557
TEST_TIME 4 4952
TEST_TIME 5 5614
TEST_TIME 6 6380
TEST_TIME 7 6491
TEST_TIME 8 6501
TEST_TIME 9 6716
TEST_TIME 10 6736
TEST_TIME 11 6922
TEST_TIME 12 6936
TEST_TIME 13 7037
TEST_TIME 14 7471
TEST_TIME 15 7515
TEST_TIME 16 7555
TEST_TIME 17 7823
TEST_TIME 18 7857
TEST_TIME 19 8381
TEST_TIME 20 8453
TEST_TIME 21 9312
TEST_TIME 22 9508
TEST_TIME 23 9643
TEST_TIME 24 10120
TEST_TIME 25 10004
TEST_TIME 26 11254
TEST_TIME 27 11197
TEST_TIME 28 11013
TEST_TIME 29 10739
TEST_TIME 30 10571
TEST_TIME 31 10473
TEST_TIME 32 10429
TEST_TIME 33 10624
TEST_TIME 34 10541
TEST_TIME 35 10372
TEST_TIME 36 10448
TEST_TIME 37 10318
TEST_TIME 38 10201
TEST_TIME 39 10299
TEST_TIME 40 10371
TEST_TIME 41 10269
TEST_TIME 42 10154
TEST_TIME 43 10221
TEST_TIME 44 10227
TEST_TIME 45 10151
TEST_TIME 46 10069
TEST_TIME 47 10012
TEST_TIME 48 10159
TEST_TIME 49 10103
TEST_TIME 50 10135
TEST_TIME 51 10248
TEST_TIME 52 10200
TEST_TIME 53 10142
TEST_TIME 54 10240
TEST_TIME 55 10276
TEST_TIME 56 10334
TEST_TIME 57 10343
TEST_TIME 58 10218
TEST_TIME 59 10133
TEST_TIME 60 10063
TEST_TIME 61 9980
TEST_TIME 62 9954
TEST_TIME 63 9946
TEST_TIME 64 9862
TEST_TIME 65 9827
TEST_TIME 66 9774
TEST_TIME 67 9745
TEST_TIME 68 9700
TEST_TIME 69 9837
TEST_TIME 70 9820
TEST_TIME 71 9769
TEST_TIME 72 9870
TEST_TIME 73 9872
TEST_TIME 74 9863
TEST_TIME 75 9841
TEST_TIME 76 9899
TEST_TIME 77 10004
TEST_TIME 78 9999
TEST_TIME 79 9948
TEST_TIME 80 9848
TEST_TIME 81 9825
TEST_TIME 82 9815
TEST_TIME 83 9757
TEST_TIME 84 9717
TEST_TIME 85 9712
TEST_TIME 86 9672
TEST_TIME 87 9650
TEST_TIME 88 9650
TEST_TIME 89 9672
TEST_TIME 90 9633
TEST_TIME 91 9614
TEST_TIME 92 9727
TEST_TIME 93 9728
TEST_TIME 94 9756
TEST_TIME 95 9760
TEST_TIME 96 9798
TEST_TIME 97 9773
TEST_TIME 98 9755
TEST_TIME 99 9844
TEST_TIME 100 9845
TEST_TIME 101 9810
TEST_TIME 102 9768
TEST_TIME 103 9721
TEST_TIME 104 9695
TEST_TIME 105 9677
TEST_TIME 106 9607
TEST_TIME 107 9599
TEST_TIME 108 9566
TEST_TIME 109 9589
TEST_TIME 110 9543
TEST_TIME 111 9542
TEST_TIME 112 9587
TEST_TIME 113 9593
TEST_TIME 114 9586
TEST_TIME 115 9565
TEST_TIME 116 9552
TEST_TIME 117 9554
TEST_TIME 118 9595
TEST_TIME 119 9595
TEST_TIME 120 9593

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-09-2021 19:38:29 to 03-09-2021 19:38:33.

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 1597043 1597043 0 0.00 0 17917 6 0 0
doc_list_biud 1597043 1597043 0 0.00 0 19309 26 0 0
sp_add_invoice_to_stock 5414 5276 138 2.54 31 211310 18026 0 138
sp_cancel_adding_invoice 5956 5956 0 0.00 3 49195 737 0 0
sp_cancel_client_order 1859 1859 0 0.00 5 9359 314 0 0
sp_cancel_customer_reserve 577357 577357 0 0.00 1 19312 57 0 0
sp_cancel_pay_from_customer 718 718 0 0.00 2 4231 77 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 4525 58 0 0
sp_cancel_supplier_invoice 6555 6546 9 0.13 2 17320 754 0 9
sp_cancel_supplier_order 466 466 0 0.00 5 1090178 89549 0 0
sp_cancel_write_off 10463 10463 0 0.00 1 7955 113 0 0
sp_client_order 22050 22050 0 0.00 3 21943 353 0 0
sp_customer_reserve 481603 481603 0 0.00 0 186276 181 0 0
sp_fill_shopping_cart 33771 33771 0 0.00 1 12983 36 0 0
sp_fill_shopping_cart_clo_res 481587 481587 0 0.00 0 17175 14 0 0
sp_get_clo_for_invoice 5253 5253 0 0.00 0 24986 268 0 0
sp_kill_cost_storno 17565 17565 0 0.00 0 9265 51 0 0
sp_kill_qstorno_ret_qs2qd 1172189 1172189 0 0.00 0 19290 31 0 0
sp_kill_qty_storno 618540 618540 0 0.00 0 19309 66 0 0
sp_lock_selected_doc 22027 22027 0 0.00 0 6685 5 0 0
sp_make_cost_storno 29633 29633 0 0.00 0 13722 46 0 0
sp_multiply_rows_for_pdistr 29633 29633 0 0.00 0 7798 27 0 0
sp_multiply_rows_for_qdistr 22050 22050 0 0.00 0 12448 159 0 0
sp_pay_from_customer 7462 7462 0 0.00 4 8669 102 0 0
sp_pay_to_supplier 6278 6278 0 0.00 5 7572 104 0 0
sp_payment_common 13740 13740 0 0.00 1 8659 24 0 0
sp_qd_handle_on_cancel_clo 1859 1859 0 0.00 0 8909 290 0 0
sp_qd_handle_on_invoice_upd_sts 11207 11207 0 0.00 0 11591 295 0 0
sp_qd_handle_on_reserve_upd_sts 10642 10642 0 0.00 0 9680 51 0 0
sp_reserve_write_off 10642 10642 0 0.00 7 9697 159 0 0
sp_supplier_invoice 5842 5842 0 0.00 6 66084 3087 0 0
sp_supplier_order 5884 5884 0 0.00 5 38649 1239 0 0
srv_aggregate_perf_data 120 120 0 0.00 3201 149156 15105 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1273 151204 44800 0 0
srv_find_qd_qs_mism 1100353 1100353 0 0.00 0 17915 6 0 0
srv_make_invnt_saldo 600 600 0 0.00 56 44977 1085 0 0
srv_make_money_saldo 820 820 0 0.00 1 1443 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 172588 351008 248352 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5276 5276 0 0.00 0 22168 156 0 0
t$perf-norm:sp_cancel_adding_invoice 451 451 0 0.00 0 4815 92 0 0
t$perf-norm:sp_cancel_client_order 1859 1859 0 0.00 0 2557 11 0 0
t$perf-norm:sp_cancel_customer_reserve 765 765 0 0.00 0 4078 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 718 718 0 0.00 0 3535 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 3584 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 569 15 0 0
t$perf-norm:sp_cancel_supplier_order 466 466 0 0.00 0 25543 1332 0 0
t$perf-norm:sp_cancel_write_off 1040 1040 0 0.00 0 5247 13 0 0
t$perf-norm:sp_client_order 22050 22050 0 0.00 0 14866 17 0 0
t$perf-norm:sp_customer_reserve 1040 1040 0 0.00 0 2514 9 0 0
t$perf-norm:sp_pay_from_customer 7462 7462 0 0.00 0 18293 15 0 0
t$perf-norm:sp_pay_to_supplier 6278 6278 0 0.00 0 5190 9 0 0
t$perf-norm:sp_reserve_write_off 10642 10642 0 0.00 0 22395 13 0 0
t$perf-norm:sp_supplier_invoice 5842 5842 0 0.00 0 6268 20 0 0
t$perf-norm:sp_supplier_order 5884 5884 0 0.00 0 7957 17 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 1756 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1090 17 0 0
t$perf-norm:srv_make_invnt_saldo 600 600 0 0.00 0 793 19 0 0
t$perf-norm:srv_make_money_saldo 820 820 0 0.00 0 1030 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10778 10502 276 2.56 0 8745 20 0 276
v_all_customers 39852 39852 0 0.00 0 125 1 0 0
v_all_suppliers 32608 32608 0 0.00 0 67 1 0 0
v_all_wares 44100 44100 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 902 902 0 0.00 0 3252 13 0 0
v_cancel_client_order 3718 3718 0 0.00 0 8369 6 0 0
v_cancel_customer_prepayment 1436 1436 0 0.00 0 2652 9 0 0
v_cancel_customer_reserve 1530 1530 0 0.00 0 6958 8 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 4494 10 0 0
v_cancel_supplier_invoice 832 814 18 2.16 0 1731 16 0 18
v_cancel_supplier_order 932 932 0 0.00 0 569 4 0 0
v_cancel_write_off 2080 2080 0 0.00 0 6991 24 0 0
v_max_id_clo_ord 5884 5884 0 0.00 0 2147 2 0 0
v_max_id_clo_res 1040 1040 0 0.00 0 1298 11 0 0
v_max_id_ord_sup 5837 5837 0 0.00 0 9691 5 0 0
v_max_non_paid_invoice 1695 1695 0 0.00 0 231 2 0 0
v_max_non_paid_realizn 4087 4087 0 0.00 0 7596 6 0 0
v_min_id_clo_ord 5884 5884 0 0.00 0 3104 6 0 0
v_min_id_clo_res 1040 1040 0 0.00 0 461 3 0 0
v_min_id_ord_sup 5837 5837 0 0.00 0 7360 7 0 0
v_min_non_paid_invoice 6278 6278 0 0.00 0 3085 13 0 0
v_min_non_paid_realizn 7462 7462 0 0.00 0 8654 15 0 0
v_our_firm 10998 10998 0 0.00 0 24 1 0 0
v_reserve_write_off 21284 21284 0 0.00 0 8372 6 0 0
x_lock_depdocs_on_canc_invoice 12095 12095 0 0.00 0 11787 106 0 0
x_lock_depdocs_on_canc_sup_ord 466 466 0 0.00 0 4531 256 0 0
x_make_qstorno_1000_1200 5884 5884 0 0.00 2 38634 1161 0 0
x_make_qstorno_1200_2000 5842 5842 0 0.00 3 66076 2962 0 0
x_make_qstorno_2100_3300 481603 481603 0 0.00 0 186275 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-09-2021 19:38:34 to 03-09-2021 19:38:38.

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
17:07:54 57 8192000000 8845683696 8868700160 32729648 0.397 9 3 2 1 32992512 83376 179488
17:08:55 14884 8192000000 10354706384 10670948352 1412418528 14.705 100 90 180 0 1447361888 5645104 29298256
17:10:20 21451 8192000000 10424025232 10755874816 1477013216 15.275 100 85 170 0 1512416624 7743312 27660096
17:11:56 23659 8192000000 10487103376 10826878976 1542473376 15.845 100 81 162 0 1577024688 9383664 25167648
17:13:24 16259 8192000000 10556192944 10915835904 1616463568 16.480 100 79 158 0 1651224016 10877776 23882672
17:14:44 11621 8192000000 10659192256 11024527360 1706472864 17.239 100 91 183 1 1746796192 13534320 26789008
17:16:00 5604 8192000000 10703209328 11080720384 1761423008 17.696 100 77 153 1 1796090432 13100960 21566464
17:17:07 2516 8192000000 10751813936 11135795200 1812666272 18.118 100 81 161 2 1848203536 13632352 21904912
17:18:11 2006 8192000000 10802417776 11189440512 1859718624 18.501 100 91 178 3 1896849520 12840592 24290304
17:19:14 1691 8192000000 10820849824 11215982592 1886896544 18.721 100 87 174 1 1924410656 12749408 24764704
17:20:16 10495 8192000000 10784670112 11217477632 1881170192 18.675 100 29 57 3 1900152624 11040624 7941808
17:21:30 7083 8192000000 10797871264 11227553792 1891508864 18.758 100 26 52 1 1905137632 6344320 7284448
17:22:38 15592 8192000000 10806232064 11238883328 1898758176 18.816 100 24 46 1 1910806496 5639616 6408704
17:23:55 12856 8192000000 10824504240 11253719040 1913773792 18.937 100 27 54 0 1927246736 4936048 8536896
17:25:11 7061 8192000000 10864232880 11269238784 1921072672 18.995 100 59 119 0 1944421728 6042944 17306112
17:26:19 4819 8192000000 10841396192 11271426048 1922754208 19.009 100 35 70 1 1939686096 6620432 10311456
17:27:28 8899 8192000000 10801904976 11230801920 1867302608 18.562 99 19 38 1 1878645136 6174496 5168032
17:28:40 42087 8192000000 10759417360 11183493120 1781021248 17.858 94 37 73 0 1797400768 5696064 10683456
17:30:22 6166 8192000000 10719685136 11119226880 1762201712 17.703 97 31 62 0 1777151168 5376656 9572800
17:31:30 6931 8192000000 10691373408 11108880384 1762610800 17.706 98 19 38 1 1774039600 5666192 5762608
17:32:37 63319 8192000000 10725157984 11157008384 1805411904 18.058 99 24 48 1 1818063200 5813536 6837760
17:34:42 6358 8192000000 10770151152 11196055552 1856427232 18.474 100 42 84 3 1875266448 6574336 12264880
17:35:50 10669 8192000000 10790561072 11213955072 1869863840 18.583 100 28 52 2 1882869040 5583792 7421408
17:37:03 60312 8192000000 10825128976 11244470272 1893060304 18.770 100 43 86 4 1911891312 6609248 12221760
17:39:09 12599 8192000000 10867167600 11285147648 1924726928 19.025 100 54 108 1 1951033456 11091456 15215072
17:40:25 10216 8192000000 10887059856 11305099264 1944830752 19.185 100 59 117 1 1976089216 13828592 17429872
17:41:41 21769 8192000000 10896135280 11336032256 1958253040 19.292 100 39 78 2 1989146016 19544768 11348208
17:43:13 4875 8192000000 10909487776 11346145280 1970079792 19.386 100 48 96 3 2003114000 18442112 14592096
17:44:23 7692 8192000000 10927919728 11358867456 1984412816 19.500 100 55 109 4 2018667232 18014512 16239904
17:45:35 6771 8192000000 10947253424 11374395392 1990059072 19.544 100 64 128 0 2036408928 27455616 18894240
17:46:45 6742 8192000000 10960796512 11390246912 1998706464 19.613 100 64 126 0 2044842144 28167072 17968608
17:47:57 4216 8192000000 10971046128 11396456448 2005486176 19.666 100 81 161 0 2061334384 32514608 23333600
17:49:06 3829 8192000000 10969998544 11398877184 2002962704 19.646 100 63 127 1 2060591424 38034912 19593808
17:50:28 5142 8192000000 10958455040 11380412416 1960547552 19.310 98 61 122 2 2023879920 43701360 19631008
17:51:37 3624 8192000000 10970835936 11393978368 1995034032 19.584 100 78 158 0 2061457472 42216096 24207344
17:52:45 14879 8192000000 10918502992 11343908864 1922330064 19.006 98 68 133 1 1986838496 43241552 21266880
17:54:07 1272 8192000000 10952631072 11365470208 1960193952 19.308 100 82 162 2 2031733984 45801200 25738832
17:55:15 4263 8192000000 10945433696 11363053568 1943401312 19.174 99 64 129 1 2011704368 47805040 20498016
17:56:24 8973 8192000000 10925480208 11357708288 1946819136 19.201 100 53 106 1 2013072432 48718832 17534464
17:57:41 2270 8192000000 10941738800 11359150080 1940313312 19.149 100 75 150 0 2023679088 59132128 24233648
17:58:47 19365 8192000000 10901742608 11346219008 1918271616 18.973 99 34 67 1 1982032976 52075984 11685376
18:00:08 21935 8192000000 10925596784 11379687424 1961459600 19.318 100 26 51 1 2012120368 41925264 8735504
18:01:33 28849 8192000000 10903991840 11373727744 1936495536 19.119 99 22 43 2 1987134432 43322960 7315936
18:03:02 20512 8192000000 10890824576 11357933568 1917281616 18.965 99 19 38 0 1969303200 44790384 7231200
18:04:24 27072 8192000000 10870223424 11336015872 1931958720 19.083 99 12 24 1 1944028720 8452128 3617872
18:05:51 95430 8192000000 10888228864 11339915264 1928908880 19.058 99 21 40 0 1942051568 7661744 5480944
18:08:30 31824 8192000000 10923049392 11372740608 1959768624 19.304 99 30 59 2 1977006272 9521168 7716480
18:10:02 88927 8192000000 10941558432 11405406208 1986873648 19.519 100 15 30 2 2005488000 14273456 4340896
18:12:35 151203 8192000000 10930774592 11369459712 1874708656 18.622 94 26 52 0 1898279856 16208288 7362912
18:16:09 37339 8192000000 10917388800 11394887680 1984107600 19.497 100 19 38 0 2014424912 24733248 5584064
18:18:35 23917 8192000000 10905162240 11379687424 1964460768 19.341 99 16 29 2 1984100048 15188368 4450912
18:22:01 69619 8192000000 10913808976 11386630144 1987340048 19.523 100 11 22 0 2003376880 12941968 3094864
18:24:13 43703 8192000000 10928311168 11401011200 1980273136 19.467 99 19 37 1 2003723408 18008800 5441472
18:26:12 54984 8192000000 10946056304 11404591104 1974793920 19.423 98 26 52 0 1995095904 12481232 7820752
18:28:08 13085 8192000000 10929284096 11388370944 1978315488 19.451 99 48 95 1 2000031840 9131264 12585088
18:29:33 76080 8192000000 10926140144 11391299584 1953351936 19.253 98 17 34 1 1969939536 11490464 5097136
18:31:49 96392 8192000000 10927626000 11391741952 1931150624 19.076 97 13 26 0 1963359808 28245488 3963696
18:34:31 115319 8192000000 10903182592 11379818496 1978255344 19.451 100 17 34 0 1993341440 10403200 4682896
18:37:27 56275 8192000000 10873733248 11319599104 1855644208 18.468 94 27 52 0 1870303488 7594576 7064704
18:39:23 48150 8192000000 10837699360 11306860544 1874018192 18.617 97 19 38 1 1888309024 9070416 5220416
18:41:13 34247 8192000000 10817475936 11292282880 1884538384 18.702 99 12 24 0 1899415968 11533648 3343936
18:42:51 61136 8192000000 10857962432 11337191424 1940776384 19.153 100 7 14 0 1950867184 8242992 1847808
18:45:38 61001 8192000000 10867344608 11344531456 1922500672 19.007 98 24 48 1 1943493568 13807312 7185584
18:47:42 61634 8192000000 10864838608 11332849664 1903248640 18.852 98 15 30 0 1918957760 11549792 4159328
18:49:48 33000 8192000000 10877586736 11328851968 1931395536 19.078 100 18 36 0 1947439632 11438064 4606032
18:51:22 34574 8192000000 10901370128 11357605888 1937368208 19.126 99 24 48 1 1953716144 9917728 6430208
18:53:06 61444 8192000000 10887834112 11370594304 1936688784 19.120 99 15 30 0 1962184496 21007776 4487936
18:55:12 32799 8192000000 10869764736 11334979584 1924021952 19.019 99 23 47 0 1939397296 9131728 6243616
18:56:45 86448 8192000000 10883138560 11341795328 1952142352 19.244 100 41 82 2 1971381120 6930784 12307984
18:59:13 18905 8192000000 10891634208 11356889088 1948403888 19.214 99 35 70 3 1968712528 10761488 9547152
19:00:38 63500 8192000000 10879127728 11364564992 1960864352 19.313 100 4 8 0 1974824656 12945888 1014416
19:02:42 36282 8192000000 10858836544 11334307840 1921398400 18.998 98 13 23 0 1934050912 9279200 3373312
19:04:49 75434 8192000000 10889869888 11372404736 1941777536 19.161 99 10 19 0 1963269632 18160528 3331568
19:07:05 37535 8192000000 10923187824 11391123456 1987480160 19.524 100 25 50 1 2006296656 11924608 6891888
19:08:43 89821 8192000000 10925590560 11394932736 1941249504 19.157 97 18 34 2 1964627728 18115344 5262880
19:11:14 59419 8192000000 10913664496 11395383296 1958224320 19.292 98 15 30 1 1971034704 9064288 3746096
19:13:13 76671 8192000000 10923510256 11382788096 1914611696 18.944 96 10 19 0 1929047744 11662752 2773296
19:15:38 17711 8192000000 10930520656 11388723200 1966297888 19.356 99 43 86 1 1993636064 15050624 12287552
19:17:22 108847 8192000000 10916737120 11387932672 1951006064 19.234 98 7 14 0 1966290224 13280512 2003648
19:20:14 34677 8192000000 10908467648 11386667008 1978458256 19.452 100 23 46 0 2000595712 15663136 6474320
19:21:55 40391 8192000000 10901029744 11387297792 1981829136 19.479 100 8 16 1 1998690400 14843296 2017968
19:23:37 71654 8192000000 10886586944 11354693632 1884046400 18.698 95 12 24 0 1899152304 11019104 4086800
19:25:55 97455 8192000000 10880770160 11365965824 1883662592 18.695 96 10 20 0 1899124368 12370496 3091280
19:28:33 72888 8192000000 10875449632 11350626304 1833930896 18.291 93 18 36 1 1854530384 15202832 5396656
19:30:47 53907 8192000000 10868523968 11324190720 1855064000 18.463 95 21 42 0 1868880688 8458128 5358560
19:32:41 19753 8192000000 10870198112 11329601536 1905974496 18.874 98 28 56 0 1923252800 9290384 7987920
19:34:05 140735 8192000000 10879862176 11363241984 1896323232 18.797 97 18 36 2 1918179840 17067328 4789280
19:37:26 30627 8192000000 9419708160 9714782208 563513392 6.436 29 7 14 1 571220624 6189392 1517840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-09-2021 19:38:40 to 03-09-2021 19:38:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-09-2021 19:38:56 to 03-09-2021 19:38:58.

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          248074
MON$OLDEST_ACTIVE               249713
MON$OLDEST_SNAPSHOT             249713
MON$NEXT_TRANSACTION            249713
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                       2029408
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.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 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 ---
:99: 
:100: mscs-test-161.budzdorov.ru    Fri Sep  3 19:37:59 2021
:101:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:102: 
:103: 
:104: mscs-test-161.budzdorov.ru    Fri Sep  3 19:38:01 2021
:105:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:106: 
--- end of diff output ---

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

03.09.21 19:38:59. 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-09-03 19:39:01 No severe PSQL-related problems occured
03.09.21 19:39:02. End of report.