Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.10.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 15695252 kB
MemAvailable 30661808 kB
Buffers 0 kB
Cached 15971672 kB
SwapCached 3872 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-11 19:37:52
DTS_END                         2021-10-11 19:37:52.7540
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/51404
TRN_ID                          244955
ATT_ID                          723
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: 9791.49 20473 1174979
customer order: creation 178.33 337 21400
customer order: refuse 15.50 355 1860
order to supplier: creation 48.58 1301 5829
order to supplier: removal 3.48 109841 418
invoice (draft): creation 48.35 3359 5802
invoice (draft): removal 56.92 864 6830
invoice accept: apply 42.36 17700 5083
invoice accept: cancel 51.19 845 6143
customer reserve: creation 3954.08 172 474489
customer reserve: removal 5081.63 58 609795
realization accept: apply 85.26 168 10231
realization accept: cancel 89.86 111 10783
payment from customer: creation 62.41 121 7489
payment from customer: removal 5.60 93 672
payment to supplier: creation 51.55 123 6186
payment to supplier: removal 3.66 68 439
service: total inventory turnovers 4.96 1040 595
service: total monetary turnovers 6.96 41 835
service: aggregate perf. data 0.80 25384 96
service: refresh index statistics 0.03 247486 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-10-2021 19:38:37 to 11-10-2021 19:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3336
TEST_TIME 2 4198
TEST_TIME 3 4403
TEST_TIME 4 4841
TEST_TIME 5 5481
TEST_TIME 6 5731
TEST_TIME 7 6085
TEST_TIME 8 6272
TEST_TIME 9 6188
TEST_TIME 10 6197
TEST_TIME 11 6473
TEST_TIME 12 6559
TEST_TIME 13 7282
TEST_TIME 14 7180
TEST_TIME 15 7343
TEST_TIME 16 7380
TEST_TIME 17 7488
TEST_TIME 18 7817
TEST_TIME 19 8141
TEST_TIME 20 8151
TEST_TIME 21 8624
TEST_TIME 22 8514
TEST_TIME 23 8693
TEST_TIME 24 9108
TEST_TIME 25 9311
TEST_TIME 26 9087
TEST_TIME 27 9639
TEST_TIME 28 10380
TEST_TIME 29 11151
TEST_TIME 30 11113
TEST_TIME 31 11347
TEST_TIME 32 11235
TEST_TIME 33 10975
TEST_TIME 34 10878
TEST_TIME 35 11101
TEST_TIME 36 11188
TEST_TIME 37 11164
TEST_TIME 38 10888
TEST_TIME 39 10736
TEST_TIME 40 10892
TEST_TIME 41 10745
TEST_TIME 42 10563
TEST_TIME 43 10468
TEST_TIME 44 10519
TEST_TIME 45 10413
TEST_TIME 46 10468
TEST_TIME 47 10570
TEST_TIME 48 10460
TEST_TIME 49 10334
TEST_TIME 50 10259
TEST_TIME 51 10294
TEST_TIME 52 10226
TEST_TIME 53 10246
TEST_TIME 54 10162
TEST_TIME 55 10058
TEST_TIME 56 9987
TEST_TIME 57 9974
TEST_TIME 58 9859
TEST_TIME 59 9990
TEST_TIME 60 9956
TEST_TIME 61 9925
TEST_TIME 62 10053
TEST_TIME 63 10044
TEST_TIME 64 10112
TEST_TIME 65 10075
TEST_TIME 66 10114
TEST_TIME 67 10235
TEST_TIME 68 10197
TEST_TIME 69 10164
TEST_TIME 70 10131
TEST_TIME 71 10251
TEST_TIME 72 10277
TEST_TIME 73 10265
TEST_TIME 74 10131
TEST_TIME 75 10074
TEST_TIME 76 10067
TEST_TIME 77 10081
TEST_TIME 78 10027
TEST_TIME 79 9950
TEST_TIME 80 9981
TEST_TIME 81 9923
TEST_TIME 82 9866
TEST_TIME 83 9800
TEST_TIME 84 9786
TEST_TIME 85 9715
TEST_TIME 86 9658
TEST_TIME 87 9705
TEST_TIME 88 9661
TEST_TIME 89 9702
TEST_TIME 90 9706
TEST_TIME 91 9756
TEST_TIME 92 9741
TEST_TIME 93 9809
TEST_TIME 94 9799
TEST_TIME 95 9792
TEST_TIME 96 9855
TEST_TIME 97 9882
TEST_TIME 98 9851
TEST_TIME 99 9881
TEST_TIME 100 9940
TEST_TIME 101 9915
TEST_TIME 102 9880
TEST_TIME 103 9819
TEST_TIME 104 9826
TEST_TIME 105 9802
TEST_TIME 106 9754
TEST_TIME 107 9715
TEST_TIME 108 9735
TEST_TIME 109 9684
TEST_TIME 110 9650
TEST_TIME 111 9711
TEST_TIME 112 9730
TEST_TIME 113 9703
TEST_TIME 114 9701
TEST_TIME 115 9735
TEST_TIME 116 9738
TEST_TIME 117 9755
TEST_TIME 118 9763
TEST_TIME 119 9763
TEST_TIME 120 9799

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-10-2021 19:38:41 to 11-10-2021 19:38:45.

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 1615816 1615816 0 0.00 0 16910 6 0 0
doc_list_biud 1615816 1615816 0 0.00 0 78330 28 0 0
sp_add_invoice_to_stock 5188 5083 105 2.02 23 202504 17700 0 105
sp_cancel_adding_invoice 6143 6143 0 0.00 5 67834 846 0 0
sp_cancel_client_order 1860 1860 0 0.00 4 10925 356 0 0
sp_cancel_customer_reserve 609795 609795 0 0.00 1 44350 59 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 3 10213 94 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 3481 69 0 0
sp_cancel_supplier_invoice 6834 6830 4 0.05 1 78340 865 0 4
sp_cancel_supplier_order 418 418 0 0.00 9 1004326 109842 0 0
sp_cancel_write_off 10783 10783 0 0.00 1 12344 112 0 0
sp_client_order 21400 21400 0 0.00 4 19165 337 0 0
sp_customer_reserve 474489 474489 0 0.00 0 85233 173 0 0
sp_fill_shopping_cart 33008 33008 0 0.00 1 12921 37 0 0
sp_fill_shopping_cart_clo_res 474467 474467 0 0.00 0 12534 12 0 0
sp_get_clo_for_invoice 5061 5061 0 0.00 0 28786 272 0 0
sp_kill_cost_storno 18037 18037 0 0.00 0 16905 59 0 0
sp_kill_qstorno_ret_qs2qd 1237621 1237621 0 0.00 0 78325 32 0 0
sp_kill_qty_storno 651121 651121 0 0.00 0 78328 69 0 0
sp_lock_selected_doc 21220 21220 0 0.00 0 10204 6 0 0
sp_make_cost_storno 28967 28967 0 0.00 0 16287 54 0 0
sp_multiply_rows_for_pdistr 28967 28967 0 0.00 0 14343 31 0 0
sp_multiply_rows_for_qdistr 21400 21400 0 0.00 0 19156 155 0 0
sp_pay_from_customer 7489 7489 0 0.00 4 11033 122 0 0
sp_pay_to_supplier 6186 6186 0 0.00 5 14355 123 0 0
sp_payment_common 13675 13675 0 0.00 1 9013 28 0 0
sp_qd_handle_on_cancel_clo 1860 1860 0 0.00 0 10915 319 0 0
sp_qd_handle_on_invoice_upd_sts 11204 11204 0 0.00 0 14501 328 0 0
sp_qd_handle_on_reserve_upd_sts 10231 10231 0 0.00 0 14243 53 0 0
sp_reserve_write_off 10231 10231 0 0.00 6 15917 168 0 0
sp_supplier_invoice 5802 5802 0 0.00 7 85055 3360 0 0
sp_supplier_order 5829 5829 0 0.00 6 78636 1302 0 0
srv_aggregate_perf_data 96 96 0 0.00 3014 626470 25385 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2686 140262 46942 0 0
srv_find_qd_qs_mism 1126095 1126095 0 0.00 0 9022 5 0 0
srv_make_invnt_saldo 595 595 0 0.00 31 43855 1040 0 0
srv_make_money_saldo 835 835 0 0.00 0 10607 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 162445 385525 247487 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5083 5083 0 0.00 0 12951 179 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 1 3946 105 0 0
t$perf-norm:sp_cancel_client_order 1860 1860 0 0.00 0 1758 11 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 10421 42 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 5501 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 1377 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 2689 25 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 19942 1749 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 3463 10 0 0
t$perf-norm:sp_client_order 21400 21400 0 0.00 0 11222 20 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 1104 5 0 0
t$perf-norm:sp_pay_from_customer 7489 7489 0 0.00 0 10497 12 0 0
t$perf-norm:sp_pay_to_supplier 6186 6186 0 0.00 0 9766 12 0 0
t$perf-norm:sp_reserve_write_off 10231 10231 0 0.00 0 4841 11 0 0
t$perf-norm:sp_supplier_invoice 5802 5802 0 0.00 0 10080 19 0 0
t$perf-norm:sp_supplier_order 5829 5829 0 0.00 0 9807 21 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 7 0 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1421 25 0 0
t$perf-norm:srv_make_invnt_saldo 595 595 0 0.00 0 5601 33 0 0
t$perf-norm:srv_make_money_saldo 835 835 0 0.00 0 3905 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 19 7 0 0
v_add_invoice_to_stock 10332 10122 210 2.03 0 4593 16 0 210
v_all_customers 39576 39576 0 0.00 0 59 1 0 0
v_all_suppliers 32332 32332 0 0.00 0 18 1 0 0
v_all_wares 42800 42800 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 1091 7 0 0
v_cancel_client_order 3720 3720 0 0.00 0 4694 3 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 346 3 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 454 4 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 3467 10 0 0
v_cancel_supplier_invoice 852 844 8 0.93 0 7664 23 0 8
v_cancel_supplier_order 836 836 0 0.00 0 422 3 0 0
v_cancel_write_off 2046 2046 0 0.00 0 5990 14 0 0
v_max_id_clo_ord 5829 5829 0 0.00 0 141 1 0 0
v_max_id_clo_res 1055 1055 0 0.00 0 2948 12 0 0
v_max_id_ord_sup 5779 5779 0 0.00 0 4629 5 0 0
v_max_non_paid_invoice 1628 1628 0 0.00 0 1457 4 0 0
v_max_non_paid_realizn 3740 3740 0 0.00 0 1111 4 0 0
v_min_id_clo_ord 5829 5829 0 0.00 0 765 4 0 0
v_min_id_clo_res 1055 1055 0 0.00 0 754 4 0 0
v_min_id_ord_sup 5779 5779 0 0.00 0 6832 8 0 0
v_min_non_paid_invoice 6186 6186 0 0.00 0 8262 17 0 0
v_min_non_paid_realizn 7489 7489 0 0.00 0 8255 24 0 0
v_our_firm 10722 10722 0 0.00 0 16 1 0 0
v_reserve_write_off 20462 20462 0 0.00 0 5854 5 0 0
x_lock_depdocs_on_canc_invoice 12551 12551 0 0.00 0 16265 115 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 12381 364 0 0
x_make_qstorno_1000_1200 5829 5829 0 0.00 1 78619 1216 0 0
x_make_qstorno_1200_2000 5802 5802 0 0.00 4 79440 3233 0 0
x_make_qstorno_2100_3300 474489 474489 0 0.00 0 85232 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-10-2021 19:38:46 to 11-10-2021 19:38:50.

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:56 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:57 41308 8192000000 9915487872 10232061952 989542240 10.777 100 86 172 1 1018015520 6344496 22128784
17:10:44 16239 8192000000 9957108400 10283401216 1040902240 11.273 100 87 174 0 1072373232 9425008 22045984
17:12:09 15368 8192000000 9993500736 10330329088 1074719120 11.597 100 87 174 0 1107793680 11656224 21418336
17:13:28 14087 8192000000 10041634496 10391330816 1122053760 12.046 100 80 160 2 1152595808 11513856 19028192
17:14:47 9714 8192000000 10096501472 10460160000 1179201520 12.583 100 74 148 1 1208158480 11886352 17070608
17:15:59 3865 8192000000 10143502448 10514194432 1228814016 13.043 100 82 165 1 1259832144 12909392 18108736
17:17:05 4206 8192000000 10173414240 10553327616 1261530464 13.344 100 84 168 0 1293358272 13581136 18246672
17:18:12 2077 8192000000 10210338512 10596306944 1295195760 13.652 100 85 170 3 1327972304 13473952 19302592
17:19:16 32547 8192000000 10215469984 10614181888 1309465712 13.781 100 59 115 2 1335760080 13851200 12443168
17:20:49 3058 8192000000 10220452528 10626449408 1318755600 13.865 100 41 83 2 1339067024 11239248 9072176
17:21:56 11803 8192000000 10204443632 10629095424 1324805232 13.920 100 13 26 0 1333770928 5813360 3152336
17:23:11 13075 8192000000 10224445344 10645082112 1338019584 14.040 100 20 39 2 1348101648 5564960 4517104
17:24:27 10442 8192000000 10263659568 10665979904 1342206128 14.077 100 81 163 1 1368918096 6631840 20080128
17:25:37 5473 8192000000 10243863808 10664202240 1346218688 14.113 100 32 64 1 1358644064 5559344 6866032
17:26:44 9479 8192000000 10244189568 10667364352 1331936304 13.985 99 16 32 1 1349339408 13998608 3404496
17:27:56 62306 8192000000 10155486368 10568200192 1205680128 12.829 92 28 54 1 1215841344 4466608 5694608
17:29:59 8525 8192000000 10139306272 10544066560 1225248208 13.010 96 22 44 1 1235234864 5057584 4929072
17:31:09 10973 8192000000 10132004688 10526830592 1189594352 12.680 94 25 50 2 1201301184 5954080 5752752
17:32:20 67269 8192000000 10155852832 10566553600 1252215984 13.259 98 25 50 0 1263566064 4970240 6379840
17:34:29 4603 8192000000 10198810224 10611015680 1304699968 13.738 100 40 80 3 1321327568 6951392 9676208
17:35:37 6890 8192000000 10202442592 10617446400 1310385568 13.790 100 11 22 1 1317993360 5030368 2577424
17:36:45 6805 8192000000 10213506672 10625748992 1317723904 13.856 100 31 63 1 1330881296 5730144 7427248
17:37:53 21105 8192000000 10243880384 10648023040 1331976208 13.985 100 61 120 1 1353192496 6259744 14956544
17:39:19 9941 8192000000 10256901408 10672590848 1355204000 14.194 100 51 99 0 1375926112 9069296 11652816
17:40:32 7636 8192000000 10278961584 10699317248 1373768448 14.361 100 48 97 1 1396141792 11616768 10756576
17:41:45 42664 8192000000 10280997488 10716721152 1382533680 14.439 100 37 74 1 1404987280 14150624 8302976
17:43:38 30321 8192000000 10310386928 10740961280 1396425440 14.563 100 42 84 1 1423604224 16987088 10191696
17:45:12 4489 8192000000 10330620576 10756259840 1412511744 14.706 100 53 106 0 1441732352 16816816 12403792
17:46:21 10066 8192000000 10342633840 10770522112 1423339280 14.802 100 51 100 3 1452423200 16683056 12400864
17:47:35 2685 8192000000 10350261200 10774380544 1422236784 14.793 100 64 128 2 1456382240 19465712 14679744
17:48:49 3261 8192000000 10340423136 10754252800 1407146576 14.659 100 81 162 1 1449123712 22492224 19484912
17:49:55 6852 8192000000 10331866064 10755067904 1401373504 14.607 100 55 110 3 1442119856 26923600 13822752
17:51:06 3008 8192000000 10345036880 10763259904 1406985568 14.657 100 68 136 1 1447620416 24026848 16608000
17:52:15 3364 8192000000 10354285616 10759258112 1398466000 14.581 100 90 176 2 1455526992 35278064 21782928
17:53:23 5095 8192000000 10351306592 10766286848 1400898016 14.603 100 70 140 1 1455636000 37091936 17646048
17:54:35 7069 8192000000 10353166864 10764038144 1377447632 14.394 98 75 149 1 1439899184 42943088 19508464
17:55:47 4568 8192000000 10369553024 10781810688 1405840912 14.647 100 81 161 1 1470837376 44502864 20493600
17:56:57 5054 8192000000 10355750672 10773778432 1386317952 14.473 99 61 121 2 1445165504 43229984 15617568
17:58:08 4897 8192000000 10376718608 10794909696 1406948352 14.657 99 73 145 1 1472252832 47743120 17561360
17:59:18 10936 8192000000 10354843088 10783117312 1391072496 14.515 99 39 79 2 1447557840 46104832 10380512
18:00:33 11221 8192000000 10369601472 10792861696 1407952912 14.666 100 43 86 1 1470484432 49958464 12573056
18:01:46 16052 8192000000 10355674656 10781847552 1401631184 14.610 100 36 71 2 1460060896 48533744 9895968
18:03:11 21195 8192000000 10333816832 10769944576 1375484960 14.376 99 29 58 1 1434418464 50505920 8427584
18:04:34 23550 8192000000 10324790928 10775138304 1353912400 14.183 99 21 42 0 1422295856 61786672 6596784
18:06:43 33623 8192000000 10272016368 10721779712 1340473568 14.062 98 16 32 0 1366226224 21911968 3840688
18:08:25 97684 8192000000 10260770368 10708344832 1277753088 13.492 93 3 6 0 1289343152 11294128 295936
18:11:16 68066 8192000000 10301506016 10748194816 1365610416 14.288 99 21 42 0 1388753200 18244608 4898176
18:13:33 80476 8192000000 10302088720 10752499712 1381229968 14.428 100 13 26 0 1396482096 12327456 2924672
18:16:00 72247 8192000000 10312206768 10760171520 1371761168 14.343 99 26 51 1 1393396512 15943296 5692048
18:18:16 101841 8192000000 10310844864 10748182528 1345462640 14.107 97 27 55 3 1362861904 11883632 5515632
18:21:07 16929 8192000000 10317813712 10761183232 1389220784 14.499 100 20 40 1 1410363584 15614080 5528720
18:22:25 30036 8192000000 10323992672 10765377536 1384604960 14.458 99 22 44 1 1401002240 11028592 5368688
18:23:57 58052 8192000000 10295948032 10763898880 1393268352 14.535 100 22 44 1 1407511648 9071152 5172144
18:25:55 42798 8192000000 10309033184 10770038784 1391817488 14.522 99 12 24 0 1407518144 12590064 3110592
18:27:42 75348 8192000000 10305936416 10775056384 1386849728 14.478 99 10 20 0 1407102800 17781728 2471344
18:30:10 69167 8192000000 10305103216 10780078080 1400863664 14.603 100 18 35 2 1417822064 12914704 4043696
18:32:23 38641 8192000000 10308416880 10764869632 1384155216 14.454 99 23 46 0 1403503216 13775760 5572240
18:34:02 104750 8192000000 10296555984 10768560128 1365449872 14.286 98 11 22 0 1386738176 18819680 2468624
18:36:59 50362 8192000000 10300053696 10764824576 1378961712 14.407 99 14 28 2 1388009264 6018848 3028704
18:38:50 69402 8192000000 10294095984 10766336000 1349685120 14.145 97 21 42 0 1367589568 13089584 4814864
18:41:00 40492 8192000000 10304063616 10766782464 1383031936 14.444 99 17 35 0 1398973360 12159760 3781664
18:42:52 49121 8192000000 10297491424 10753634304 1356236624 14.204 98 24 48 0 1373887760 12547856 5103280
18:44:44 72555 8192000000 10295581888 10746839040 1322181136 13.896 95 10 20 0 1335008464 11062640 1764688
18:46:57 56188 8192000000 10291331056 10730659840 1317291424 13.852 96 22 44 0 1334466000 12603584 4570992
18:48:54 101615 8192000000 10293514336 10742546432 1359035184 14.229 99 11 22 1 1374202384 12567392 2599808
18:52:25 53979 8192000000 10267946240 10731749376 1325877520 13.930 97 19 38 1 1342507600 12447824 4182256
18:54:21 74923 8192000000 10260313648 10722332672 1353369056 14.178 99 15 30 0 1371610688 14874016 3367616
18:56:36 74405 8192000000 10250309296 10711040000 1300400608 13.699 95 10 20 1 1313597536 10594560 2602368
18:59:01 64739 8192000000 10247628224 10707542016 1339905296 14.057 99 13 26 0 1350718288 7568608 3244384
19:01:08 58850 8192000000 10259538688 10721640448 1352912336 14.174 99 18 36 0 1365896976 9105568 3879072
19:03:13 48418 8192000000 10274299376 10740137984 1370849568 14.335 100 8 16 0 1383671216 10847408 1974240
19:05:04 100349 8192000000 10279659776 10741768192 1344907104 14.102 97 22 44 0 1360223184 9897888 5418192
19:07:46 54871 8192000000 10291399296 10754379776 1383144976 14.445 100 23 46 0 1397924592 9657120 5122496
19:09:42 110936 8192000000 10296094704 10761211904 1348287024 14.132 97 16 32 0 1362302304 10803792 3211488
19:12:33 34912 8192000000 10313064480 10757079040 1386191504 14.472 100 35 70 0 1403565152 9903776 7469872
19:14:09 140262 8192000000 10293689088 10751930368 1288771248 13.593 93 10 20 0 1303526704 12653696 2101760
19:17:35 96975 8192000000 10294695056 10735640576 1313404288 13.817 95 24 48 0 1329430144 10698944 5326912
19:20:13 82020 8192000000 10275396208 10728349696 1330376720 13.971 97 23 46 0 1350783856 15225408 5181728
19:22:36 35703 8192000000 10265312352 10729218048 1346482592 14.116 98 6 12 1 1358282192 10386032 1413568
19:24:47 55057 8192000000 10290502208 10743734272 1351572224 14.162 98 15 30 1 1365488032 10337216 3578592
19:26:51 72003 8192000000 10290292672 10756423680 1379961280 14.416 100 10 20 0 1399327808 16717280 2649248
19:29:10 52847 8192000000 10283049648 10735386624 1348445568 14.133 98 36 70 2 1365770352 8329824 8994960
19:31:07 48382 8192000000 10272260560 10723426304 1328462528 13.953 97 22 44 1 1341480896 8541968 4476400
19:33:09 84708 8192000000 10266145104 10711740416 1288411344 13.590 94 23 46 0 1306655904 13125136 5119424
19:35:44 40483 8192000000 10282771648 10711924736 1329721872 13.965 98 23 45 1 1344151200 8831664 5597664
19:37:28 34939 8192000000 9062229360 9282859008 220116096 2.616 17 1 2 0 224883888 4725440 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-10-2021 19:38:52 to 11-10-2021 19:39:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-10-2021 19:39:07 to 11-10-2021 19:39:09.

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          245357
MON$OLDEST_ACTIVE               245358
MON$OLDEST_SNAPSHOT             245358
MON$NEXT_TRANSACTION            245358
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                       2055456
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 ---
:155: 
:156: mscs-test-161.budzdorov.ru    Mon Oct 11 19:38:08 2021
:157:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:158: 
--- end of diff output ---

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

11.10.21 19:39:10. 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-11 19:39:13 No severe PSQL-related problems occured
11.10.21 19:39:14. End of report.