Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.08.2021 10:37
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 16002912 kB
MemAvailable 30942520 kB
Buffers 0 kB
Cached 15671028 kB
SwapCached 1324 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-08-25 10:37:07
DTS_END                         2021-08-25 10:37:07.2010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49826
TRN_ID                          234555
ATT_ID                          677
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: 9183.82 21882 1102058
customer order: creation 173.91 401 20869
customer order: refuse 15.48 359 1857
order to supplier: creation 45.79 1508 5495
order to supplier: removal 3.31 130262 397
invoice (draft): creation 45.94 3491 5513
invoice (draft): removal 51.46 1048 6175
invoice accept: apply 42.40 19547 5088
invoice accept: cancel 46.18 932 5542
customer reserve: creation 3915.30 191 469836
customer reserve: removal 4548.80 74 545856
realization accept: apply 83.34 194 10001
realization accept: cancel 82.93 137 9951
payment from customer: creation 59.69 113 7163
payment from customer: removal 5.67 101 680
payment to supplier: creation 49.08 142 5889
payment to supplier: removal 3.24 85 389
service: total inventory turnovers 4.08 3695 489
service: total monetary turnovers 6.23 625 748
service: aggregate perf. data 0.97 17830 116
service: refresh index statistics 0.03 256908 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-08-2021 10:38:12 to 25-08-2021 10:38:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6121
TEST_TIME 2 5802
TEST_TIME 3 5411
TEST_TIME 4 5802
TEST_TIME 5 5631
TEST_TIME 6 5251
TEST_TIME 7 5450
TEST_TIME 8 5224
TEST_TIME 9 5374
TEST_TIME 10 5423
TEST_TIME 11 5884
TEST_TIME 12 6156
TEST_TIME 13 6565
TEST_TIME 14 6473
TEST_TIME 15 6639
TEST_TIME 16 6811
TEST_TIME 17 7055
TEST_TIME 18 7491
TEST_TIME 19 7572
TEST_TIME 20 8078
TEST_TIME 21 8124
TEST_TIME 22 8231
TEST_TIME 23 8513
TEST_TIME 24 8816
TEST_TIME 25 9092
TEST_TIME 26 9126
TEST_TIME 27 9347
TEST_TIME 28 9422
TEST_TIME 29 10283
TEST_TIME 30 10939
TEST_TIME 31 11032
TEST_TIME 32 11056
TEST_TIME 33 11015
TEST_TIME 34 11112
TEST_TIME 35 10938
TEST_TIME 36 10803
TEST_TIME 37 10678
TEST_TIME 38 10509
TEST_TIME 39 10535
TEST_TIME 40 10378
TEST_TIME 41 10215
TEST_TIME 42 10123
TEST_TIME 43 10034
TEST_TIME 44 9956
TEST_TIME 45 9931
TEST_TIME 46 10031
TEST_TIME 47 10130
TEST_TIME 48 10021
TEST_TIME 49 9991
TEST_TIME 50 9886
TEST_TIME 51 10041
TEST_TIME 52 10020
TEST_TIME 53 10011
TEST_TIME 54 9950
TEST_TIME 55 9885
TEST_TIME 56 9906
TEST_TIME 57 9917
TEST_TIME 58 10098
TEST_TIME 59 10014
TEST_TIME 60 10095
TEST_TIME 61 10051
TEST_TIME 62 10038
TEST_TIME 63 10039
TEST_TIME 64 9965
TEST_TIME 65 9855
TEST_TIME 66 9788
TEST_TIME 67 9771
TEST_TIME 68 9744
TEST_TIME 69 9706
TEST_TIME 70 9637
TEST_TIME 71 9565
TEST_TIME 72 9486
TEST_TIME 73 9487
TEST_TIME 74 9467
TEST_TIME 75 9432
TEST_TIME 76 9436
TEST_TIME 77 9421
TEST_TIME 78 9425
TEST_TIME 79 9443
TEST_TIME 80 9419
TEST_TIME 81 9481
TEST_TIME 82 9458
TEST_TIME 83 9421
TEST_TIME 84 9481
TEST_TIME 85 9599
TEST_TIME 86 9582
TEST_TIME 87 9510
TEST_TIME 88 9474
TEST_TIME 89 9387
TEST_TIME 90 9414
TEST_TIME 91 9348
TEST_TIME 92 9313
TEST_TIME 93 9337
TEST_TIME 94 9300
TEST_TIME 95 9325
TEST_TIME 96 9280
TEST_TIME 97 9267
TEST_TIME 98 9290
TEST_TIME 99 9281
TEST_TIME 100 9317
TEST_TIME 101 9419
TEST_TIME 102 9377
TEST_TIME 103 9420
TEST_TIME 104 9440
TEST_TIME 105 9479
TEST_TIME 106 9457
TEST_TIME 107 9379
TEST_TIME 108 9336
TEST_TIME 109 9351
TEST_TIME 110 9310
TEST_TIME 111 9281
TEST_TIME 112 9246
TEST_TIME 113 9227
TEST_TIME 114 9252
TEST_TIME 115 9244
TEST_TIME 116 9224
TEST_TIME 117 9227
TEST_TIME 118 9239
TEST_TIME 119 9258
TEST_TIME 120 9189

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-08-2021 10:38:16 to 25-08-2021 10:38:19.

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 1537238 1537238 0 0.00 0 12145 8 0 0
doc_list_biud 1537238 1537238 0 0.00 0 46512 33 0 0
sp_add_invoice_to_stock 5201 5088 113 2.17 31 161180 19547 0 113
sp_cancel_adding_invoice 5542 5542 0 0.00 6 81704 933 0 0
sp_cancel_client_order 1857 1857 0 0.00 5 8178 360 0 0
sp_cancel_customer_reserve 545856 545856 0 0.00 1 46514 74 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 13881 101 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 1675 85 0 0
sp_cancel_supplier_invoice 6186 6175 11 0.17 3 28718 1049 0 11
sp_cancel_supplier_order 397 397 0 0.00 9 1273357 130262 0 0
sp_cancel_write_off 9951 9951 0 0.00 1 14724 137 0 0
sp_client_order 20869 20869 0 0.00 4 19265 402 0 0
sp_customer_reserve 469836 469836 0 0.00 0 39065 192 0 0
sp_fill_shopping_cart 31856 31856 0 0.00 1 16008 43 0 0
sp_fill_shopping_cart_clo_res 469821 469821 0 0.00 0 15266 17 0 0
sp_get_clo_for_invoice 5068 5068 0 0.00 0 33010 383 0 0
sp_kill_cost_storno 16562 16562 0 0.00 0 10161 71 0 0
sp_kill_qstorno_ret_qs2qd 1108235 1108235 0 0.00 0 46508 40 0 0
sp_kill_qty_storno 584846 584846 0 0.00 0 46512 86 0 0
sp_lock_selected_doc 20885 20885 0 0.00 0 9351 6 0 0
sp_make_cost_storno 28120 28120 0 0.00 0 10664 59 0 0
sp_multiply_rows_for_pdistr 28120 28120 0 0.00 0 5849 35 0 0
sp_multiply_rows_for_qdistr 20869 20869 0 0.00 0 19222 206 0 0
sp_pay_from_customer 7163 7163 0 0.00 4 12270 113 0 0
sp_pay_to_supplier 5889 5889 0 0.00 5 16354 142 0 0
sp_payment_common 13052 13052 0 0.00 1 7554 20 0 0
sp_qd_handle_on_cancel_clo 1857 1857 0 0.00 0 7929 326 0 0
sp_qd_handle_on_invoice_upd_sts 10609 10609 0 0.00 0 35274 398 0 0
sp_qd_handle_on_reserve_upd_sts 10001 10001 0 0.00 0 17354 63 0 0
sp_reserve_write_off 10001 10001 0 0.00 7 17364 195 0 0
sp_supplier_invoice 5513 5513 0 0.00 5 97628 3491 0 0
sp_supplier_order 5495 5495 0 0.00 5 33546 1508 0 0
srv_aggregate_perf_data 116 116 0 0.00 3165 446835 17831 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1598 128530 41920 0 0
srv_find_qd_qs_mism 1053993 1053993 0 0.00 0 11640 7 0 0
srv_make_invnt_saldo 489 489 0 0.00 54 125355 3696 0 0
srv_make_money_saldo 748 748 0 0.00 1 21182 625 0 0
srv_recalc_idx_stat 4 4 0 0.00 132023 419158 256909 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5088 5088 0 0.00 0 10821 175 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 7612 115 0 0
t$perf-norm:sp_cancel_client_order 1857 1857 0 0.00 0 6738 18 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 3379 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 790 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 1293 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 935 14 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 17224 1761 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 3192 8 0 0
t$perf-norm:sp_client_order 20869 20869 0 0.00 0 11912 21 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 4072 14 0 0
t$perf-norm:sp_pay_from_customer 7163 7163 0 0.00 0 6793 10 0 0
t$perf-norm:sp_pay_to_supplier 5889 5889 0 0.00 0 2939 7 0 0
t$perf-norm:sp_reserve_write_off 10001 10001 0 0.00 0 5530 9 0 0
t$perf-norm:sp_supplier_invoice 5513 5513 0 0.00 0 7196 18 0 0
t$perf-norm:sp_supplier_order 5495 5495 0 0.00 0 2641 15 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 489 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 5916 92 0 0
t$perf-norm:srv_make_invnt_saldo 489 489 0 0.00 0 4577 27 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 1887 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 3 0 0
v_add_invoice_to_stock 10360 10134 226 2.18 0 10897 19 0 226
v_all_customers 38394 38394 0 0.00 0 19 1 0 0
v_all_suppliers 30760 30760 0 0.00 0 24 1 0 0
v_all_wares 41738 41738 0 0.00 0 138 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 1637 10 0 0
v_cancel_client_order 3714 3714 0 0.00 0 5335 7 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 1003 6 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 6736 11 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 1667 15 0 0
v_cancel_supplier_invoice 842 820 22 2.61 0 1639 16 0 22
v_cancel_supplier_order 794 794 0 0.00 0 10892 20 0 0
v_cancel_write_off 1988 1988 0 0.00 0 11045 21 0 0
v_max_id_clo_ord 5495 5495 0 0.00 0 3686 2 0 0
v_max_id_clo_res 997 997 0 0.00 0 1948 21 0 0
v_max_id_ord_sup 5491 5491 0 0.00 0 7459 7 0 0
v_max_non_paid_invoice 1495 1495 0 0.00 0 1518 3 0 0
v_max_non_paid_realizn 3729 3729 0 0.00 0 5230 6 0 0
v_min_id_clo_ord 5495 5495 0 0.00 0 1956 5 0 0
v_min_id_clo_res 997 997 0 0.00 0 6249 24 0 0
v_min_id_ord_sup 5491 5491 0 0.00 0 8473 6 0 0
v_min_non_paid_invoice 5889 5889 0 0.00 0 5675 13 0 0
v_min_non_paid_realizn 7163 7163 0 0.00 0 3718 12 0 0
v_our_firm 10212 10212 0 0.00 0 23 1 0 0
v_reserve_write_off 20002 20002 0 0.00 0 10899 8 0 0
x_lock_depdocs_on_canc_invoice 11307 11307 0 0.00 0 12723 124 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 25692 478 0 0
x_make_qstorno_1000_1200 5495 5495 0 0.00 2 33537 1424 0 0
x_make_qstorno_1200_2000 5513 5513 0 0.00 3 94720 3323 0 0
x_make_qstorno_2100_3300 469836 469836 0 0.00 0 39063 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-08-2021 10:38:20 to 25-08-2021 10:38:24.

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
08:07:11 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:12 22701 8192000000 9726015136 10023215104 803885488 8.936 100 91 182 0 834584464 7808048 22890928
08:09:40 25787 8192000000 9776470544 10085044224 846014736 9.360 100 88 176 0 879577408 12634512 20928160
08:11:11 10765 8192000000 9805945408 10129022976 875259600 9.652 100 77 154 1 915169872 22056880 17853392
08:12:29 16101 8192000000 9864726160 10197450752 916771024 10.064 100 90 180 0 974267872 37252864 20243984
08:13:49 9909 8192000000 9910745776 10253496320 949784560 10.389 100 83 165 1 1023159024 55752224 17622240
08:15:04 5745 8192000000 9953457392 10308218880 987289232 10.755 100 85 170 2 1069054976 64401232 17364512
08:16:12 7103 8192000000 9979596960 10342105088 1010155792 10.977 100 69 138 2 1098594864 74679888 13759184
08:17:21 3032 8192000000 10014099344 10382471168 1046739472 11.329 100 92 183 1 1131404384 65928048 18736864
08:18:26 2366 8192000000 10030418576 10404470784 1070042208 11.552 100 97 195 0 1157430720 67770240 19618272
08:19:30 21249 8192000000 9978947568 10377781248 1071874448 11.570 100 45 89 2 1098408272 16511168 10022656
08:20:56 6990 8192000000 10012055264 10408382464 1083339360 11.679 100 45 90 1 1115782448 22508592 9934496
08:22:06 7863 8192000000 10007411216 10403655680 1093023728 11.771 100 38 75 4 1125223552 24831008 7368816
08:23:14 28987 8192000000 10025088928 10413793280 1094844016 11.789 100 68 134 2 1122837616 12719744 15273856
08:24:43 4360 8192000000 10027084096 10426060800 1101575168 11.853 100 50 101 3 1131052320 18995952 10481200
08:25:49 7183 8192000000 10023032768 10428059648 1106920800 11.903 100 41 80 3 1145624240 29884416 8819024
08:26:58 8472 8192000000 10029714016 10441355264 1095706208 11.797 99 25 50 2 1133662816 31918352 6038256
08:28:15 2734 8192000000 10022140704 10422202368 1095556112 11.795 100 51 100 1 1136150128 30341584 10252432
08:29:18 4549 8192000000 9994306704 10382503936 1069315872 11.546 99 45 89 5 1102604784 23450720 9838192
08:30:26 7722 8192000000 9958721760 10354925568 1023787920 11.109 99 41 82 0 1061660784 28702816 9170048
08:31:37 64103 8192000000 9930523776 10312413184 973563344 10.621 94 45 92 3 998440976 14793152 10084480
08:33:43 7720 8192000000 9956810976 10352410624 1040001536 11.265 100 44 89 0 1065719792 16204384 9513872
08:34:51 5313 8192000000 9963171264 10356981760 1054593744 11.405 100 31 62 0 1078906656 17623712 6689200
08:35:57 7892 8192000000 9977445344 10381414400 1068179456 11.535 100 34 68 1 1088156672 11384992 8592224
08:37:06 38671 8192000000 10015172336 10406682624 1079914352 11.647 100 44 87 0 1102380752 13066400 9400000
08:38:50 15295 8192000000 10042597712 10442657792 1097493280 11.814 100 38 76 1 1147820480 41460624 8866576
08:40:09 32618 8192000000 10067433520 10478379008 1117463696 12.003 100 40 79 0 1176595984 50941808 8190480
08:41:54 9428 8192000000 10093257776 10507722752 1132258048 12.143 100 50 100 4 1201692256 58614912 10819296
08:43:10 13305 8192000000 10146055008 10557878272 1143357440 12.247 100 45 90 2 1249501856 96761920 9382496
08:44:25 31631 8192000000 10154349744 10581331968 1149459376 12.304 100 37 74 0 1259196000 102059184 7677440
08:46:03 5871 8192000000 10213219696 10625843200 1166535392 12.464 100 69 137 2 1323805392 141093440 16176560
08:47:21 3678 8192000000 10256583936 10669277184 1179519392 12.586 100 70 137 1 1364684304 169360576 15804336
08:48:28 7537 8192000000 10347932464 10767335424 1189506768 12.679 100 68 135 1 1446517456 241406512 15604176
08:49:41 6564 8192000000 10319592960 10768445440 1199382976 12.771 100 69 134 5 1438519200 223867120 15269104
08:50:52 3266 8192000000 10343764560 10756222976 1196019680 12.739 100 83 165 2 1446075488 230827632 19228176
08:52:01 3187 8192000000 10376549584 10789527552 1194145760 12.722 100 81 158 3 1480339040 267094704 19098576
08:53:10 3693 8192000000 10390300192 10813091840 1182602528 12.614 100 68 137 0 1486446848 287882400 15961920
08:54:20 1597 8192000000 10436671520 10845458432 1180961888 12.599 100 91 179 3 1528023712 327193456 19868368
08:55:26 8128 8192000000 10411544176 10841968640 1166599248 12.465 100 46 91 0 1518217232 340120512 11497472
08:56:40 2640 8192000000 10395077984 10812035072 1153980544 12.347 99 70 139 0 1486306224 315096688 17228992
08:57:48 3483 8192000000 10464079056 10883936256 1176250624 12.555 100 75 149 0 1558176256 365041808 16883824
08:58:59 7585 8192000000 10433608128 10849116160 1133399040 12.153 99 59 118 0 1526007696 377156704 15451952
09:00:13 16556 8192000000 10463277136 10899959808 1166472432 12.464 100 49 97 0 1567533408 388594512 12466464
09:01:40 21550 8192000000 10424689712 10857758720 1151646992 12.325 99 42 83 0 1547246272 384982224 10617056
09:03:05 20434 8192000000 10451490080 10926551040 1143005184 12.244 99 22 44 1 1529710112 380542352 6162576
09:04:27 26430 8192000000 10443037056 10920407040 1149505680 12.305 100 23 46 2 1573375680 417744032 6125968
09:05:56 13981 8192000000 10348438368 10843922432 1138291168 12.199 99 35 70 1 1491149440 344244304 8613968
09:07:17 33104 8192000000 10189852176 10675220480 1157601872 12.381 100 25 50 2 1298097824 134812272 5683680
09:09:02 41880 8192000000 10103127008 10545651712 1115297008 11.983 97 31 63 0 1163971872 42391072 6283792
09:10:49 128530 8192000000 10086520480 10515042304 1105695872 11.892 96 13 26 1 1127654848 19460928 2498048
09:14:03 38700 8192000000 10104863824 10558291968 1147599920 12.287 99 51 100 3 1215727264 57121728 11005616
09:15:42 108813 8192000000 10085930112 10539515904 1110852192 11.940 96 31 62 0 1176870720 59120944 6897584
09:18:34 48485 8192000000 10101335248 10537271296 1143544000 12.249 99 46 87 2 1178701232 26472496 8684736
09:20:24 70801 8192000000 10177066448 10632273920 1125838448 12.082 97 20 40 1 1244135760 113582192 4715120
09:22:38 27488 8192000000 10211405248 10688131072 1136270528 12.180 99 35 70 2 1307380864 163476016 7634320
09:24:14 49227 8192000000 10084407440 10540961792 1128661008 12.109 98 12 24 0 1180063136 48866784 2535344
09:26:06 51958 8192000000 10140303776 10607054848 1130544208 12.126 98 17 33 0 1222546464 88096112 3906144
09:27:59 57760 8192000000 10085959824 10613932032 1125267200 12.077 98 13 26 1 1205298496 77474640 2556656
09:29:59 50304 8192000000 10080998688 10562035712 1113236704 11.963 97 30 58 1 1150388256 31814480 5337072
09:31:55 34117 8192000000 10096523632 10540380160 1099061088 11.829 96 28 56 1 1152863088 48161296 5640704
09:33:37 55154 8192000000 10160518416 10623897600 1142878432 12.243 100 9 18 0 1246188320 101282896 2026992
09:35:34 96408 8192000000 10086687552 10580885504 1151785536 12.326 100 8 16 0 1210006240 56655040 1565664
09:39:01 22165 8192000000 10084414704 10535182336 1128899968 12.111 99 28 56 0 1176837264 42530640 5406656
09:40:24 91242 8192000000 10055729216 10530193408 1102415840 11.861 97 23 46 1 1147287008 40008944 4862224
09:43:26 69167 8192000000 10060049488 10528178176 1104021712 11.876 99 27 54 0 1175132720 65364736 5746272
09:45:37 25986 8192000000 10058357344 10504302592 1120255968 12.029 99 30 60 0 1157910480 31466032 6188480
09:47:04 46409 8192000000 10054625344 10539757568 1088936160 11.733 97 8 16 0 1124862720 34277104 1649456
09:48:51 72422 8192000000 10045500688 10516402176 1086642192 11.711 97 11 22 0 1137189888 48055920 2491776
09:51:31 34535 8192000000 10053159280 10515529728 1108552992 11.919 99 20 40 1 1159235968 46719200 3963776
09:53:07 35652 8192000000 10065101440 10525601792 1087759152 11.721 98 24 48 1 1137772816 45161664 4852000
09:54:49 66595 8192000000 10095986560 10548965376 1111481312 11.946 99 21 42 0 1167829712 52097760 4250640
09:56:56 49442 8192000000 10089198528 10524598272 1102844400 11.865 98 23 46 0 1148393088 40700096 4848592
09:59:39 32872 8192000000 10099021376 10535038976 1114645200 11.976 99 35 70 0 1172230992 50506912 7078880
10:01:18 67001 8192000000 10154021968 10654486528 1131226704 12.133 100 20 40 0 1280584784 144743408 4614672
10:03:42 79069 8192000000 10078322112 10534711296 1121079280 12.037 99 26 51 1 1167834544 41618928 5136336
10:06:10 38061 8192000000 10072660720 10510000128 1128632352 12.108 100 41 82 1 1170263456 33551008 8080096
10:07:58 48348 8192000000 10077522240 10529685504 1143065952 12.244 100 17 34 2 1181881920 35433232 3382736
10:09:48 42870 8192000000 10084996768 10561462272 1123641184 12.061 98 8 16 0 1169985984 44661584 1683216
10:11:42 74186 8192000000 10069697952 10555441152 1121507360 12.041 98 13 26 0 1193988496 70032256 2448880
10:14:00 27646 8192000000 10125651680 10579955712 1139623264 12.212 100 46 92 0 1212892608 63466640 9802704
10:15:51 102800 8192000000 10137606080 10633969664 1134741872 12.166 100 18 36 1 1222837584 84190320 3905392
10:18:44 56087 8192000000 10056835776 10490613760 1103326016 11.869 97 12 24 1 1119475280 13888816 2260448
10:20:41 39141 8192000000 10130838384 10595917824 1115490592 11.984 98 36 71 1 1176860624 54291040 7078992
10:22:30 109907 8192000000 10053989072 10516533248 1072939616 11.580 94 16 33 0 1107030032 31000016 3090400
10:25:20 63469 8192000000 10090967792 10551635968 1119302000 12.020 99 22 44 0 1174982576 51125264 4555312
10:27:23 66675 8192000000 10075668800 10538160128 1109454800 11.927 98 13 26 0 1157355520 44927472 2973248
10:29:35 52322 8192000000 10078214368 10571210752 1098257584 11.821 98 23 46 0 1173171408 69609664 5304160
10:31:34 68296 8192000000 10039684944 10500710400 1085567712 11.700 97 16 32 0 1125941472 37276384 3097376
10:33:48 52794 8192000000 10061303408 10516008960 1081835648 11.665 96 26 52 1 1126711888 39459584 5416656
10:35:41 41654 8192000000 10107818560 10545614848 1096967712 11.809 97 45 91 1 1144645120 38264320 9413088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-08-2021 10:38:26 to 25-08-2021 10:38:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-08-2021 10:38:42 to 25-08-2021 10:38:44.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          232926
MON$OLDEST_ACTIVE               234937
MON$OLDEST_SNAPSHOT             234937
MON$NEXT_TRANSACTION            234937
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-30 07:30:08.6030 GMT
MON$PAGES                       2058016
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             804
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2571 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2571 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:279: 
:280: mscs-test-161.budzdorov.ru    Wed Aug 25 10:37:13 2021
:281:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:282: 
:283: 
:284: mscs-test-161.budzdorov.ru    Wed Aug 25 10:37:16 2021
:285:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:286: 
--- end of diff output ---

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

25.08.21 10:38:45. There are 3 logs in /var/tmp/logs-oltp40 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-08-25 10:38:48 No severe PSQL-related problems occured
25.08.21 10:38:49. End of report.