Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.10.2021 07:47
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 15949500 kB
MemAvailable 30772820 kB
Buffers 0 kB
Cached 15767068 kB
SwapCached 4280 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-10-01 07:46:53
DTS_END                         2021-10-01 07:46:53.1070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35694
TRN_ID                          223947
ATT_ID                          670
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: 8795.98 26233 1055518
customer order: creation 155.95 459 18714
customer order: refuse 13.50 404 1620
order to supplier: creation 41.68 1581 5002
order to supplier: removal 3.08 132230 369
invoice (draft): creation 42.11 3922 5053
invoice (draft): removal 49.59 1078 5951
invoice accept: apply 38.85 20024 4662
invoice accept: cancel 45.10 933 5412
customer reserve: creation 3682.64 191 441917
customer reserve: removal 4447.56 71 533707
realization accept: apply 76.99 222 9239
realization accept: cancel 83.83 120 10059
payment from customer: creation 52.68 141 6322
payment from customer: removal 5.26 102 631
payment to supplier: creation 44.43 152 5332
payment to supplier: removal 3.08 69 370
service: total inventory turnovers 3.54 4028 425
service: total monetary turnovers 5.15 587 618
service: aggregate perf. data 0.92 14918 110
service: refresh index statistics 0.04 343424 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-10-2021 07:48:23 to 01-10-2021 07:48:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7114
TEST_TIME 2 4801
TEST_TIME 3 5696
TEST_TIME 4 5253
TEST_TIME 5 5262
TEST_TIME 6 5022
TEST_TIME 7 5510
TEST_TIME 8 5362
TEST_TIME 9 5584
TEST_TIME 10 5417
TEST_TIME 11 5579
TEST_TIME 12 5735
TEST_TIME 13 6012
TEST_TIME 14 6030
TEST_TIME 15 5966
TEST_TIME 16 5973
TEST_TIME 17 6316
TEST_TIME 18 6408
TEST_TIME 19 6800
TEST_TIME 20 6865
TEST_TIME 21 7104
TEST_TIME 22 7411
TEST_TIME 23 7929
TEST_TIME 24 8421
TEST_TIME 25 8715
TEST_TIME 26 8493
TEST_TIME 27 9003
TEST_TIME 28 9057
TEST_TIME 29 9568
TEST_TIME 30 10125
TEST_TIME 31 10246
TEST_TIME 32 10015
TEST_TIME 33 9989
TEST_TIME 34 9826
TEST_TIME 35 9830
TEST_TIME 36 9678
TEST_TIME 37 9650
TEST_TIME 38 9567
TEST_TIME 39 9394
TEST_TIME 40 9342
TEST_TIME 41 9234
TEST_TIME 42 9320
TEST_TIME 43 9204
TEST_TIME 44 9163
TEST_TIME 45 9084
TEST_TIME 46 9076
TEST_TIME 47 9139
TEST_TIME 48 9084
TEST_TIME 49 9005
TEST_TIME 50 8972
TEST_TIME 51 9062
TEST_TIME 52 9005
TEST_TIME 53 8999
TEST_TIME 54 9081
TEST_TIME 55 9162
TEST_TIME 56 9233
TEST_TIME 57 9147
TEST_TIME 58 9137
TEST_TIME 59 9064
TEST_TIME 60 8977
TEST_TIME 61 8934
TEST_TIME 62 8903
TEST_TIME 63 8870
TEST_TIME 64 8808
TEST_TIME 65 8861
TEST_TIME 66 8809
TEST_TIME 67 8783
TEST_TIME 68 8711
TEST_TIME 69 8599
TEST_TIME 70 8757
TEST_TIME 71 8772
TEST_TIME 72 8747
TEST_TIME 73 8809
TEST_TIME 74 8802
TEST_TIME 75 8949
TEST_TIME 76 8922
TEST_TIME 77 8894
TEST_TIME 78 9023
TEST_TIME 79 8970
TEST_TIME 80 9035
TEST_TIME 81 9028
TEST_TIME 82 9026
TEST_TIME 83 9111
TEST_TIME 84 9049
TEST_TIME 85 8990
TEST_TIME 86 9002
TEST_TIME 87 8966
TEST_TIME 88 8950
TEST_TIME 89 8902
TEST_TIME 90 8856
TEST_TIME 91 8812
TEST_TIME 92 8756
TEST_TIME 93 8708
TEST_TIME 94 8793
TEST_TIME 95 8795
TEST_TIME 96 8774
TEST_TIME 97 8756
TEST_TIME 98 8824
TEST_TIME 99 8844
TEST_TIME 100 8835
TEST_TIME 101 8842
TEST_TIME 102 8916
TEST_TIME 103 8938
TEST_TIME 104 8927
TEST_TIME 105 8929
TEST_TIME 106 8979
TEST_TIME 107 9005
TEST_TIME 108 9003
TEST_TIME 109 8961
TEST_TIME 110 8898
TEST_TIME 111 8894
TEST_TIME 112 8887
TEST_TIME 113 8847
TEST_TIME 114 8831
TEST_TIME 115 8806
TEST_TIME 116 8759
TEST_TIME 117 8723
TEST_TIME 118 8828
TEST_TIME 119 8830
TEST_TIME 120 8801

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-10-2021 07:48:27 to 01-10-2021 07:48:30.

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 1462948 1462948 0 0.00 0 22415 7 0 0
doc_list_biud 1462947 1462947 0 0.00 0 42688 33 0 0
sp_add_invoice_to_stock 4764 4662 102 2.14 32 210233 20024 0 102
sp_cancel_adding_invoice 5412 5412 0 0.00 5 68466 934 0 0
sp_cancel_client_order 1620 1620 0 0.00 5 14506 404 0 0
sp_cancel_customer_reserve 533707 533707 0 0.00 1 25174 71 0 0
sp_cancel_pay_from_customer 631 631 0 0.00 3 6468 102 0 0
sp_cancel_pay_to_supplier 370 370 0 0.00 3 1254 69 0 0
sp_cancel_supplier_invoice 5958 5951 7 0.11 2 42822 1079 0 7
sp_cancel_supplier_order 369 369 0 0.00 5 1088572 132230 0 0
sp_cancel_write_off 10059 10059 0 0.00 1 7500 121 0 0
sp_client_order 18714 18714 0 0.00 4 21642 460 0 0
sp_customer_reserve 441917 441917 0 0.00 0 127073 192 0 0
sp_fill_shopping_cart 28766 28766 0 0.00 1 18673 49 0 0
sp_fill_shopping_cart_clo_res 441909 441909 0 0.00 0 15520 17 0 0
sp_get_clo_for_invoice 4654 4654 0 0.00 0 28400 423 0 0
sp_kill_cost_storno 16472 16472 0 0.00 0 13829 67 0 0
sp_kill_qstorno_ret_qs2qd 1083793 1083793 0 0.00 0 42687 39 0 0
sp_kill_qty_storno 571010 571010 0 0.00 0 42687 84 0 0
sp_lock_selected_doc 19192 19192 0 0.00 0 11605 7 0 0
sp_make_cost_storno 25547 25547 0 0.00 0 19250 67 0 0
sp_multiply_rows_for_pdistr 25546 25546 0 0.00 0 18784 41 0 0
sp_multiply_rows_for_qdistr 18714 18714 0 0.00 0 11326 213 0 0
sp_pay_from_customer 6322 6322 0 0.00 4 23185 141 0 0
sp_pay_to_supplier 5332 5332 0 0.00 5 17622 152 0 0
sp_payment_common 11654 11654 0 0.00 1 8123 23 0 0
sp_qd_handle_on_cancel_clo 1620 1620 0 0.00 0 14496 361 0 0
sp_qd_handle_on_invoice_upd_sts 10065 10065 0 0.00 0 25487 395 0 0
sp_qd_handle_on_reserve_upd_sts 9239 9239 0 0.00 0 13657 66 0 0
sp_reserve_write_off 9239 9239 0 0.00 7 23316 223 0 0
sp_supplier_invoice 5053 5053 0 0.00 7 72630 3923 0 0
sp_supplier_order 5002 5002 0 0.00 5 36041 1582 0 0
srv_aggregate_perf_data 111 110 1 0.90 2814 124052 14919 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1081 153707 45964 0 0
srv_find_qd_qs_mism 1010653 1010653 0 0.00 0 22253 6 0 0
srv_make_invnt_saldo 425 425 0 0.00 75 106140 4029 0 0
srv_make_money_saldo 618 618 0 0.00 1 19409 588 0 0
srv_recalc_idx_stat 5 5 0 0.00 95647 564285 343424 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4662 4662 0 0.00 0 7919 156 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 1 3786 94 0 0
t$perf-norm:sp_cancel_client_order 1620 1620 0 0.00 0 4504 11 0 0
t$perf-norm:sp_cancel_customer_reserve 578 578 0 0.00 0 2536 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 631 631 0 0.00 0 2799 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 370 370 0 0.00 0 3917 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 1263 17 0 0
t$perf-norm:sp_cancel_supplier_order 369 369 0 0.00 0 32672 2031 0 0
t$perf-norm:sp_cancel_write_off 959 959 0 0.00 0 7519 19 0 0
t$perf-norm:sp_client_order 18714 18714 0 0.00 0 9708 20 0 0
t$perf-norm:sp_customer_reserve 933 933 0 0.00 0 1788 12 0 0
t$perf-norm:sp_pay_from_customer 6322 6322 0 0.00 0 11903 10 0 0
t$perf-norm:sp_pay_to_supplier 5332 5332 0 0.00 0 5900 11 0 0
t$perf-norm:sp_reserve_write_off 9239 9239 0 0.00 0 4212 8 0 0
t$perf-norm:sp_supplier_invoice 5053 5053 0 0.00 0 4495 18 0 0
t$perf-norm:sp_supplier_order 5002 5002 0 0.00 0 2192 15 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 846 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 10 1 0 0
t$perf-norm:srv_make_invnt_saldo 425 425 0 0.00 0 1730 15 0 0
t$perf-norm:srv_make_money_saldo 618 618 0 0.00 0 603 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 9510 9306 204 2.14 0 10953 20 0 204
v_all_customers 33766 33766 0 0.00 0 22 1 0 0
v_all_suppliers 27968 27968 0 0.00 0 23 1 0 0
v_all_wares 37428 37428 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 4924 19 0 0
v_cancel_client_order 3240 3240 0 0.00 0 10853 10 0 0
v_cancel_customer_prepayment 1262 1262 0 0.00 0 5582 16 0 0
v_cancel_customer_reserve 1156 1156 0 0.00 0 1296 6 0 0
v_cancel_payment_to_supplier 740 740 0 0.00 0 1244 6 0 0
v_cancel_supplier_invoice 806 792 14 1.73 0 3255 18 0 14
v_cancel_supplier_order 738 738 0 0.00 0 2792 13 0 0
v_cancel_write_off 1918 1918 0 0.00 0 3271 19 0 0
v_max_id_clo_ord 5002 5002 0 0.00 0 4238 2 0 0
v_max_id_clo_res 933 933 0 0.00 0 2841 15 0 0
v_max_id_ord_sup 5050 5050 0 0.00 0 9309 9 0 0
v_max_non_paid_invoice 1400 1400 0 0.00 0 695 3 0 0
v_max_non_paid_realizn 3514 3514 0 0.00 0 1444 3 0 0
v_min_id_clo_ord 5002 5002 0 0.00 0 6602 9 0 0
v_min_id_clo_res 933 933 0 0.00 0 15035 25 0 0
v_min_id_ord_sup 5050 5050 0 0.00 0 6554 9 0 0
v_min_non_paid_invoice 5332 5332 0 0.00 0 7546 15 0 0
v_min_non_paid_realizn 6322 6322 0 0.00 0 7751 16 0 0
v_our_firm 9278 9278 0 0.00 0 25 1 0 0
v_reserve_write_off 18478 18478 0 0.00 0 23262 9 0 0
x_lock_depdocs_on_canc_invoice 10967 10967 0 0.00 0 16537 152 0 0
x_lock_depdocs_on_canc_sup_ord 369 369 0 0.00 0 23057 388 0 0
x_make_qstorno_1000_1200 5002 5002 0 0.00 2 35964 1485 0 0
x_make_qstorno_1200_2000 5053 5053 0 0.00 3 72621 3747 0 0
x_make_qstorno_2100_3300 441917 441917 0 0.00 0 127071 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-10-2021 07:48:31 to 01-10-2021 07:48:35.

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
05:16:56 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:17:57 33317 8192000000 9723855728 10021019648 799617904 8.892 100 87 174 0 828780704 8328320 20834480
05:19:38 17366 8192000000 9761756176 10065735680 833216752 9.232 100 86 172 0 866547680 12786112 20544816
05:21:00 22439 8192000000 9800226784 10114920448 863964560 9.540 100 89 178 0 906444144 21506176 20973408
05:22:34 9463 8192000000 9884717664 10218160128 924552960 10.141 100 80 159 1 988433920 46777440 17103520
05:23:46 7245 8192000000 9952994528 10301202432 967302080 10.560 100 86 170 1 1071708656 86606416 17800160
05:24:55 5017 8192000000 9983845840 10338615296 1004307664 10.920 100 80 159 0 1092964304 72334048 16322592
05:26:02 3495 8192000000 9992091504 10358419456 1036369680 11.230 100 89 178 0 1110609680 56263328 17976672
05:27:08 1763 8192000000 10001357968 10366488576 1051673248 11.377 100 92 184 2 1114253120 44009968 18569904
05:28:11 11825 8192000000 10010442080 10384998400 1062183040 11.477 100 86 171 0 1118502992 38444624 17875328
05:29:23 3792 8192000000 10004650000 10388672512 1076513632 11.614 100 54 106 0 1112468800 25808112 10147056
05:30:28 6701 8192000000 10000218464 10399543296 1086454112 11.709 100 30 60 0 1115414912 22637504 6323296
05:31:37 3077 8192000000 10003746016 10403385344 1091325264 11.755 100 44 89 3 1128628576 28474880 8828432
05:32:40 35070 8192000000 10023106784 10435436544 1091332928 11.755 100 46 93 1 1121002416 19231408 10438080
05:34:15 5545 8192000000 10023095600 10425856000 1097065200 11.810 100 38 76 1 1132627216 27029008 8533008
05:35:24 6970 8192000000 10017395728 10419879936 1102173328 11.858 100 38 75 2 1118406832 8136352 8097152
05:36:33 7714 8192000000 10009855568 10406338560 1096619360 11.806 100 25 50 0 1133042128 30136608 6286160
05:37:44 25696 8192000000 10011283456 10415796224 1033761728 11.205 94 39 78 0 1085524880 43960720 7802432
05:39:16 4074 8192000000 9975000480 10359394304 1015060240 11.024 97 61 122 3 1066499984 38002608 13437136
05:40:21 6275 8192000000 9951337888 10327257088 991244112 10.794 96 51 102 0 1014684528 11780992 11659424
05:41:30 55218 8192000000 9952352608 10344022016 999197200 10.871 97 53 106 1 1032466736 21041776 12227760
05:43:28 8005 8192000000 9969033184 10367344640 1051132240 11.372 100 38 77 2 1088584880 29370416 8082224
05:44:36 7760 8192000000 9982441296 10384875520 1058586320 11.443 100 25 50 0 1077212480 12376528 6249632
05:45:45 53419 8192000000 9988199264 10379522048 1073721600 11.588 100 34 65 1 1096954032 16023632 7208800
05:47:41 3674 8192000000 10041433008 10438180864 1101762272 11.854 100 48 96 1 1146243728 34515344 9966112
05:48:48 4094 8192000000 10075964304 10480332800 1114083248 11.971 100 55 110 1 1189811376 64810144 10917984
05:49:58 16347 8192000000 10074484112 10499518464 1124399680 12.069 100 39 78 2 1190795440 57916000 8479760
05:51:16 6602 8192000000 10121030720 10526003200 1139634960 12.212 100 52 100 1 1228715696 77453344 11627392
05:52:25 11731 8192000000 10159780160 10565652480 1144648192 12.259 100 56 109 2 1260035376 103036560 12350624
05:53:46 10130 8192000000 10179781632 10606538752 1149644720 12.306 100 49 95 0 1285041520 124806992 10589808
05:55:00 57135 8192000000 10180997024 10614984704 1159364096 12.397 100 40 79 1 1291078464 122345648 9368720
05:57:04 14332 8192000000 10223431648 10652041216 1170058544 12.497 100 52 104 0 1335015856 152521280 12436032
05:58:27 3419 8192000000 10234037328 10648985600 1172121616 12.517 100 55 108 0 1333650448 150120656 11408176
05:59:38 3859 8192000000 10299321104 10726875136 1188834912 12.673 100 72 142 2 1396394256 191277856 16281488
06:00:46 2533 8192000000 10331584560 10740105216 1189569856 12.679 100 73 143 0 1429750560 224522816 15657888
06:01:54 7962 8192000000 10342173504 10767958016 1166454896 12.464 98 66 132 0 1435549264 253717056 15377312
06:03:14 5713 8192000000 10407473456 10817888256 1180382384 12.594 100 72 142 3 1495764688 298878640 16503664
06:04:23 1392 8192000000 10445689376 10845782016 1168666672 12.484 100 95 190 1 1541474096 351648848 21158576
06:05:30 11454 8192000000 10432394432 10853826560 1159377200 12.397 99 56 112 0 1524688464 352626816 12684448
06:06:47 15029 8192000000 10438238192 10860457984 1144452976 12.257 99 54 105 4 1534855264 376738704 13663584
06:08:08 13015 8192000000 10438313808 10886148096 1117539952 12.004 98 40 78 1 1541351648 413444656 10367040
06:09:31 1081 8192000000 10429818656 10835308544 1139907456 12.215 100 93 187 0 1518839024 356765072 22166496
06:10:37 14980 8192000000 10395682064 10843783168 1151475104 12.323 99 40 80 0 1504725408 343504320 9745984
06:12:05 30446 8192000000 10332634480 10757832704 1114121328 11.971 98 27 52 2 1406180080 285277152 6781600
06:13:43 17903 8192000000 10306635440 10765942784 1136270768 12.180 100 25 50 0 1415973808 273200736 6502304
06:15:03 21169 8192000000 10318643312 10768556032 1124009952 12.065 99 22 44 1 1425383584 295211536 6162096
06:16:27 18156 8192000000 10172403200 10683883520 1111023040 11.942 99 34 67 0 1281127632 162925088 7179504
06:17:48 47365 8192000000 10094274384 10518343680 1106018944 11.895 99 38 72 1 1174399552 60296880 8083728
06:19:38 11457 8192000000 10105784864 10568384512 1120583184 12.033 100 27 54 0 1219993328 93472176 5937968
06:20:52 73986 8192000000 10030487360 10452967424 1077179968 11.621 96 28 53 1 1139365152 56167552 6017632
06:23:07 44381 8192000000 10058617008 10498236416 1095752384 11.797 98 20 39 0 1138655024 39444944 3457696
06:24:52 79462 8192000000 10088347840 10531540992 1104329488 11.879 98 21 42 0 1164963328 56356928 4276912
06:27:19 41806 8192000000 10115810496 10562244608 1132911872 12.149 100 12 25 1 1224518816 89193264 2413680
06:29:13 64586 8192000000 10045522544 10511069184 1121921968 12.045 100 26 52 2 1182875904 55442832 5511104
06:31:27 68736 8192000000 10130439408 10613043200 1129650352 12.118 99 17 34 0 1211014864 78057536 3306976
06:33:37 56042 8192000000 10065369472 10494083072 1116424688 11.993 98 32 65 1 1157458736 34334896 6699152
06:35:34 122396 8192000000 10064341392 10590052352 1130049136 12.122 99 14 28 0 1190525152 57296464 3179552
06:38:36 60928 8192000000 10110826848 10574815232 1136755136 12.185 99 36 72 0 1204576896 60581744 7240016
06:40:39 153707 8192000000 10093280928 10622889984 1129293568 12.115 98 13 26 1 1207806544 75779424 2733552
06:44:40 128247 8192000000 10112574608 10596249600 1152680752 12.335 100 28 56 2 1211110528 52199824 6229952
06:47:52 34968 8192000000 10122617504 10591354880 1146207824 12.274 99 46 87 1 1203718512 47490144 10020544
06:49:29 26940 8192000000 10111055296 10554847232 1160939264 12.412 100 45 89 1 1214752096 44364272 9448560
06:50:59 62486 8192000000 10111968880 10605539328 1123618720 12.061 97 26 52 1 1225891776 96194048 6079008
06:53:02 44476 8192000000 10128162400 10596495360 1130942336 12.130 98 20 40 2 1196179072 61202240 4034496
06:54:56 122358 8192000000 10095042608 10639503360 1103573232 11.872 96 15 29 0 1222331456 115409712 3348512
06:57:58 54060 8192000000 10108894832 10546438144 1126344208 12.087 98 23 43 1 1180473888 49059664 5070016
06:59:59 51589 8192000000 10170174256 10620358656 1142702048 12.241 98 25 49 1 1217305264 69578848 5024368
07:01:52 32623 8192000000 10062406272 10506665984 1132410768 12.144 99 15 30 0 1168595216 33163312 3021136
07:03:28 48390 8192000000 10109781728 10599628800 1126173168 12.085 99 31 62 1 1196931408 64065536 6692704
07:06:02 88538 8192000000 10084969120 10549837824 1111992288 11.951 97 9 18 0 1160039296 46560384 1486624
07:08:36 63195 8192000000 10063287792 10551992320 1063603376 11.491 95 29 58 0 1155052480 85273456 6175648
07:10:40 132713 8192000000 10048732960 10533339136 1082908704 11.675 97 26 52 0 1145634352 57465632 5260016
07:15:07 62380 8192000000 10054969856 10497044480 1107253616 11.906 99 16 32 0 1127922176 17439616 3228944
07:17:10 61667 8192000000 10098993856 10547478528 1100072128 11.838 98 26 52 0 1181700928 75021888 6606912
07:20:25 100818 8192000000 10053867344 10503716864 1102011456 11.857 98 15 30 0 1138878736 33501904 3365376
07:23:11 49336 8192000000 10115991552 10563178496 1107947072 11.913 98 18 36 1 1191917280 80231840 3738368
07:25:19 36438 8192000000 10063077936 10494935040 1111413216 11.946 98 37 74 1 1143084016 24620368 7050432
07:27:00 98404 8192000000 10076007568 10584547328 1108103072 11.914 97 14 28 1 1175343664 64046096 3194496
07:29:50 57008 8192000000 10082610192 10526756864 1121468624 12.041 98 29 59 0 1172511584 45330640 5712320
07:31:49 86035 8192000000 10112090640 10581827584 1149805360 12.308 100 18 36 0 1237128368 83396736 3926272
07:34:16 85809 8192000000 10090718112 10609467392 1148412208 12.295 100 14 28 0 1217987072 66578592 2996272
07:37:07 1594 8192000000 10079745008 10508988416 1138999424 12.206 100 53 106 2 1178531136 29147600 10384112
07:38:10 45424 8192000000 10036265744 10499825664 1102085680 11.857 97 16 32 0 1141594528 35558944 3949904
07:40:09 51596 8192000000 10087069104 10553585664 1137594816 12.193 100 16 32 0 1200499392 59169664 3734912
07:42:08 44468 8192000000 10134176496 10588147712 1147263552 12.284 100 20 38 3 1230966192 79133248 4569392
07:44:01 70367 8192000000 10149864752 10711146496 1160796848 12.411 100 18 35 1 1289455808 124007632 4651328
07:46:30 36587 8192000000 9051754816 9350426624 203918704 2.428 18 7 13 1 274577280 69040752 1617824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-10-2021 07:48:37 to 01-10-2021 07:48:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 102 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-10-2021 07:48:53 to 01-10-2021 07:48:55.

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          224232
MON$OLDEST_ACTIVE               224233
MON$OLDEST_SNAPSHOT             224233
MON$NEXT_TRANSACTION            224233
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                       2045408
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2621 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2621 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2621 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2621 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Oct  1 07:46:36 2021
:8:     INET/inet_error: read errno = 104
:9: 
--- end of diff output ---

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

01.10.21 07:48:56. 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-10-01 07:48:58 No severe PSQL-related problems occured
01.10.21 07:48:59. End of report.