Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.08.2021 10: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 16167676 kB
MemAvailable 31080056 kB
Buffers 0 kB
Cached 15537808 kB
SwapCached 2628 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-06 10:37:04
DTS_END                         2021-08-06 10:37:04.2920
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/60392
TRN_ID                          230964
ATT_ID                          604
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: 8831.67 23499 1059800
customer order: creation 165.81 468 19897
customer order: refuse 14.48 304 1738
order to supplier: creation 44.13 1520 5295
order to supplier: removal 3.43 111887 412
invoice (draft): creation 44.86 3634 5383
invoice (draft): removal 50.59 944 6071
invoice accept: apply 40.35 18051 4842
invoice accept: cancel 45.88 964 5506
customer reserve: creation 3642.29 181 437075
customer reserve: removal 4496.55 67 539586
realization accept: apply 80.99 178 9719
realization accept: cancel 79.38 117 9526
payment from customer: creation 56.13 135 6735
payment from customer: removal 5.86 129 703
payment to supplier: creation 47.04 167 5645
payment to supplier: removal 3.40 76 408
service: total inventory turnovers 3.81 3670 457
service: total monetary turnovers 5.73 644 687
service: aggregate perf. data 0.93 14948 111
service: refresh index statistics 0.03 311892 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-08-2021 10:38:28 to 06-08-2021 10:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9199
TEST_TIME 2 7165
TEST_TIME 3 6505
TEST_TIME 4 6266
TEST_TIME 5 5900
TEST_TIME 6 5943
TEST_TIME 7 5827
TEST_TIME 8 6476
TEST_TIME 9 6964
TEST_TIME 10 7522
TEST_TIME 11 7666
TEST_TIME 12 8034
TEST_TIME 13 7975
TEST_TIME 14 8256
TEST_TIME 15 8600
TEST_TIME 16 8844
TEST_TIME 17 8871
TEST_TIME 18 8798
TEST_TIME 19 8829
TEST_TIME 20 9149
TEST_TIME 21 9125
TEST_TIME 22 9088
TEST_TIME 23 9185
TEST_TIME 24 9385
TEST_TIME 25 9936
TEST_TIME 26 9936
TEST_TIME 27 9906
TEST_TIME 28 11233
TEST_TIME 29 11308
TEST_TIME 30 11492
TEST_TIME 31 11258
TEST_TIME 32 11028
TEST_TIME 33 10949
TEST_TIME 34 10769
TEST_TIME 35 10598
TEST_TIME 36 10452
TEST_TIME 37 10232
TEST_TIME 38 10220
TEST_TIME 39 10170
TEST_TIME 40 9987
TEST_TIME 41 9919
TEST_TIME 42 9772
TEST_TIME 43 9878
TEST_TIME 44 9815
TEST_TIME 45 9796
TEST_TIME 46 9596
TEST_TIME 47 9559
TEST_TIME 48 9476
TEST_TIME 49 9426
TEST_TIME 50 9272
TEST_TIME 51 9203
TEST_TIME 52 9079
TEST_TIME 53 9091
TEST_TIME 54 9058
TEST_TIME 55 9038
TEST_TIME 56 9017
TEST_TIME 57 9066
TEST_TIME 58 9018
TEST_TIME 59 8952
TEST_TIME 60 8995
TEST_TIME 61 9089
TEST_TIME 62 9096
TEST_TIME 63 9275
TEST_TIME 64 9287
TEST_TIME 65 9255
TEST_TIME 66 9403
TEST_TIME 67 9356
TEST_TIME 68 9281
TEST_TIME 69 9167
TEST_TIME 70 9074
TEST_TIME 71 9202
TEST_TIME 72 9131
TEST_TIME 73 9083
TEST_TIME 74 8999
TEST_TIME 75 8932
TEST_TIME 76 8984
TEST_TIME 77 9012
TEST_TIME 78 9033
TEST_TIME 79 9025
TEST_TIME 80 8991
TEST_TIME 81 9014
TEST_TIME 82 9083
TEST_TIME 83 9068
TEST_TIME 84 9056
TEST_TIME 85 9106
TEST_TIME 86 9127
TEST_TIME 87 9193
TEST_TIME 88 9219
TEST_TIME 89 9174
TEST_TIME 90 9100
TEST_TIME 91 9080
TEST_TIME 92 9085
TEST_TIME 93 9015
TEST_TIME 94 8969
TEST_TIME 95 8939
TEST_TIME 96 8961
TEST_TIME 97 8939
TEST_TIME 98 8915
TEST_TIME 99 8938
TEST_TIME 100 8977
TEST_TIME 101 9074
TEST_TIME 102 9041
TEST_TIME 103 9067
TEST_TIME 104 9045
TEST_TIME 105 9081
TEST_TIME 106 9092
TEST_TIME 107 9169
TEST_TIME 108 9177
TEST_TIME 109 9114
TEST_TIME 110 9052
TEST_TIME 111 9033
TEST_TIME 112 9017
TEST_TIME 113 8990
TEST_TIME 114 8975
TEST_TIME 115 8966
TEST_TIME 116 8923
TEST_TIME 117 8938
TEST_TIME 118 8921
TEST_TIME 119 8906
TEST_TIME 120 8837

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-08-2021 10:38:31 to 06-08-2021 10:38:35.

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 1465765 1465765 0 0.00 0 24819 7 0 0
doc_list_biud 1465762 1465762 0 0.00 0 91965 32 0 0
sp_add_invoice_to_stock 4971 4842 129 2.59 41 222956 18051 0 129
sp_cancel_adding_invoice 5506 5506 0 0.00 5 70746 964 0 0
sp_cancel_client_order 1738 1738 0 0.00 4 8410 305 0 0
sp_cancel_customer_reserve 539586 539586 0 0.00 1 54817 68 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 2 16415 129 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 3059 77 0 0
sp_cancel_supplier_invoice 6079 6071 8 0.13 3 91969 944 0 8
sp_cancel_supplier_order 412 412 0 0.00 7 1016280 111888 0 0
sp_cancel_write_off 9526 9526 0 0.00 1 9666 118 0 0
sp_client_order 19897 19897 0 0.00 4 36593 468 0 0
sp_customer_reserve 437075 437075 0 0.00 0 78862 181 0 0
sp_fill_shopping_cart 30568 30568 0 0.00 1 30515 52 0 0
sp_fill_shopping_cart_clo_res 437064 437064 0 0.00 0 17649 17 0 0
sp_get_clo_for_invoice 4830 4830 0 0.00 0 45529 417 0 0
sp_kill_cost_storno 16143 16143 0 0.00 0 24808 62 0 0
sp_kill_qstorno_ret_qs2qd 1095181 1095181 0 0.00 0 91965 37 0 0
sp_kill_qty_storno 577388 577388 0 0.00 0 91965 79 0 0
sp_lock_selected_doc 20259 20259 0 0.00 0 10607 6 0 0
sp_make_cost_storno 26929 26929 0 0.00 0 21091 57 0 0
sp_multiply_rows_for_pdistr 26928 26928 0 0.00 0 8399 31 0 0
sp_multiply_rows_for_qdistr 19897 19897 0 0.00 0 14394 219 0 0
sp_pay_from_customer 6735 6735 0 0.00 4 32944 135 0 0
sp_pay_to_supplier 5645 5645 0 0.00 5 35934 168 0 0
sp_payment_common 12379 12379 0 0.00 2 9921 33 0 0
sp_qd_handle_on_cancel_clo 1738 1738 0 0.00 0 8404 278 0 0
sp_qd_handle_on_invoice_upd_sts 10336 10336 0 0.00 0 41537 396 0 0
sp_qd_handle_on_reserve_upd_sts 9719 9719 0 0.00 0 24147 53 0 0
sp_reserve_write_off 9719 9719 0 0.00 6 24193 179 0 0
sp_supplier_invoice 5383 5383 0 0.00 7 83174 3635 0 0
sp_supplier_order 5295 5295 0 0.00 5 37796 1521 0 0
srv_aggregate_perf_data 111 111 0 0.00 2663 194692 14948 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1415 108775 49308 0 0
srv_find_qd_qs_mism 1014837 1014837 0 0.00 0 23798 6 0 0
srv_make_invnt_saldo 457 457 0 0.00 44 96483 3671 0 0
srv_make_money_saldo 687 687 0 0.00 1 22173 645 0 0
srv_recalc_idx_stat 4 4 0 0.00 128286 432738 311893 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4842 4842 0 0.00 0 23643 184 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 2775 95 0 0
t$perf-norm:sp_cancel_client_order 1738 1738 0 0.00 0 6729 15 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 1419 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 3117 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 100 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 1515 16 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 22365 1601 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 2770 9 0 0
t$perf-norm:sp_client_order 19897 19897 0 0.00 0 5439 16 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 5420 11 0 0
t$perf-norm:sp_pay_from_customer 6735 6735 0 0.00 0 10102 17 0 0
t$perf-norm:sp_pay_to_supplier 5645 5645 0 0.00 0 6955 10 0 0
t$perf-norm:sp_reserve_write_off 9719 9719 0 0.00 0 7641 11 0 0
t$perf-norm:sp_supplier_invoice 5383 5383 0 0.00 0 8304 21 0 0
t$perf-norm:sp_supplier_order 5295 5295 0 0.00 0 2702 13 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 478 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 2265 41 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 16448 71 0 0
t$perf-norm:srv_make_money_saldo 687 687 0 0.00 0 937 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 394 102 0 0
v_add_invoice_to_stock 9916 9658 258 2.60 0 5548 17 0 258
v_all_customers 36008 36008 0 0.00 0 35 1 0 0
v_all_suppliers 29378 29378 0 0.00 0 28 1 0 0
v_all_wares 39794 39794 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 285 6 0 0
v_cancel_client_order 3476 3476 0 0.00 0 2887 5 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 2444 11 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 563 3 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 2913 12 0 0
v_cancel_supplier_invoice 770 754 16 2.07 0 3406 19 0 16
v_cancel_supplier_order 824 824 0 0.00 0 2394 11 0 0
v_cancel_write_off 1998 1998 0 0.00 0 4206 16 0 0
v_max_id_clo_ord 5294 5294 0 0.00 0 671 1 0 0
v_max_id_clo_res 990 990 0 0.00 0 2511 17 0 0
v_max_id_ord_sup 5377 5377 0 0.00 0 4391 5 0 0
v_max_non_paid_invoice 1627 1627 0 0.00 0 932 2 0 0
v_max_non_paid_realizn 3707 3707 0 0.00 0 375 2 0 0
v_min_id_clo_ord 5294 5294 0 0.00 0 10791 20 0 0
v_min_id_clo_res 990 990 0 0.00 0 6247 21 0 0
v_min_id_ord_sup 5377 5377 0 0.00 0 10421 10 0 0
v_min_non_paid_invoice 5645 5645 0 0.00 0 9917 26 0 0
v_min_non_paid_realizn 6734 6734 0 0.00 0 7561 23 0 0
v_our_firm 9840 9840 0 0.00 0 16 1 0 0
v_reserve_write_off 19437 19437 0 0.00 0 2389 4 0 0
x_lock_depdocs_on_canc_invoice 11200 11200 0 0.00 0 11432 111 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 6481 370 0 0
x_make_qstorno_1000_1200 5295 5295 0 0.00 2 37766 1419 0 0
x_make_qstorno_1200_2000 5383 5383 0 0.00 3 83168 3451 0 0
x_make_qstorno_2100_3300 437075 437075 0 0.00 0 78861 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-08-2021 10:38:36 to 06-08-2021 10:38:40.

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:08 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:09 48059 8192000000 9728841776 10029502464 800230960 8.899 100 84 168 0 828533616 8119840 20182816
08:10:01 22353 8192000000 9778227696 10087280640 845824320 9.358 100 89 178 0 880936512 13880592 21231600
08:11:45 19539 8192000000 9834949200 10164539392 891358240 9.813 100 81 162 1 946867504 37604064 17905200
08:13:09 11912 8192000000 9907294496 10246070272 942485808 10.317 100 81 162 0 1017418384 57673776 17258800
08:14:24 6861 8192000000 9931040576 10285764608 981729920 10.701 100 71 142 0 1055426960 59482992 14214048
08:15:33 3561 8192000000 9970378016 10333876224 1010682560 10.982 100 77 154 0 1088992752 62990448 15319744
08:16:39 1569 8192000000 10023843056 10390548480 1041990352 11.284 100 77 154 0 1141391408 83531328 15869728
08:17:42 1556 8192000000 10048343840 10419404800 1063066528 11.486 100 90 180 0 1154909120 72886976 18955616
08:18:45 3244 8192000000 10034533552 10422099968 1072103744 11.572 100 64 128 0 1155428736 70337424 12987568
08:19:49 5741 8192000000 10008144192 10392862720 1079002928 11.638 100 42 83 1 1114368096 26408624 8956544
08:20:58 43896 8192000000 10022638256 10429526016 1091018032 11.752 100 40 80 0 1119025856 19815408 8192416
08:22:45 6562 8192000000 10017900880 10421243904 1100333904 11.841 100 44 89 2 1128170896 18411888 9425104
08:23:54 5302 8192000000 10016754960 10411450368 1101999904 11.857 100 31 60 3 1131468992 23480384 5988704
08:25:00 14068 8192000000 10020546000 10426425344 1102957504 11.866 100 34 67 2 1128959312 18569312 7432496
08:26:20 9956 8192000000 10008018544 10421792768 1092781072 11.769 99 27 52 2 1131508592 32919008 5808512
08:27:30 7778 8192000000 10019182448 10424586240 1070408848 11.556 98 51 100 1 1104797056 22919984 11468224
08:28:38 6417 8192000000 9979601712 10382495744 1055042032 11.409 99 32 63 3 1073038512 11038368 6958112
08:29:51 13684 8192000000 9914909328 10293108736 948321840 10.375 93 37 75 0 980783168 24793872 7667456
08:31:05 15209 8192000000 9925633936 10314637312 984321232 10.726 97 15 30 0 1013536640 26263824 2951584
08:32:20 9254 8192000000 9948996256 10342653952 1028634832 11.155 99 28 54 0 1049373072 14599792 6138448
08:33:32 6414 8192000000 9968052064 10351452160 1056607472 11.424 100 45 87 1 1077375328 11933840 8834016
08:34:39 60446 8192000000 9979344640 10387628032 1065168400 11.506 100 29 58 0 1096036528 25279616 5588512
08:36:40 6683 8192000000 9994033184 10387968000 1072939200 11.580 100 33 66 1 1092199232 11558816 7701216
08:37:50 20501 8192000000 10044438768 10446733312 1106463200 11.899 100 44 86 0 1160462464 44258048 9741216
08:39:15 24330 8192000000 10082480160 10493698048 1125422096 12.078 100 41 81 0 1184986144 51268704 8295344
08:40:52 34038 8192000000 10132830704 10556157952 1144694304 12.260 100 44 88 1 1253586688 99110672 9781712
08:42:32 53980 8192000000 10165813936 10596958208 1150289296 12.312 100 34 68 1 1282404304 124143696 7971312
08:44:30 5215 8192000000 10182379072 10589552640 1153412112 12.342 100 55 110 4 1287900832 121310784 13177936
08:45:40 1456 8192000000 10211801328 10638692352 1162863488 12.430 100 78 155 1 1320908576 140881456 17163632
08:46:46 7230 8192000000 10220055440 10629599232 1158533904 12.390 100 72 144 2 1321713888 147182464 15997520
08:48:01 11662 8192000000 10228365536 10640052224 1168676400 12.484 100 66 133 3 1329816544 146811392 14328752
08:49:16 1414 8192000000 10289915520 10694799360 1168345360 12.481 100 87 174 2 1390513472 202600816 19567296
08:50:22 2794 8192000000 10271919808 10681679872 1132981568 12.149 98 85 168 0 1370551024 216927056 20642400
08:51:28 12352 8192000000 10279564672 10713735168 1161008528 12.413 100 74 148 0 1395843424 217762992 17071904
08:52:49 2511 8192000000 10304140672 10709417984 1163644768 12.437 100 83 163 1 1397318384 215337280 18336336
08:53:54 17602 8192000000 10293010992 10711068672 1149089888 12.301 99 47 94 1 1379675152 219334464 11250800
08:55:16 8942 8192000000 10284508224 10701938688 1104449120 11.880 97 49 98 2 1368390656 251710992 12230544
08:56:29 12461 8192000000 10289377904 10701656064 1084166576 11.687 96 39 77 1 1363487664 270323088 8998000
08:57:47 13866 8192000000 10302958528 10741387264 1119580464 12.023 99 30 60 1 1416145824 288443472 8121888
08:59:05 3178 8192000000 10344954368 10754260992 1132839888 12.148 100 68 136 0 1441039456 292033264 16166304
09:00:11 9865 8192000000 10399138544 10816995328 1132820944 12.148 100 37 73 2 1496006256 353434224 9751088
09:01:29 22929 8192000000 10406403168 10836996096 1127310496 12.096 99 34 68 0 1482244192 346312352 8621344
09:03:12 17491 8192000000 10337208896 10762858496 1117398288 12.002 98 32 64 0 1402997008 277625456 7973264
09:04:31 18410 8192000000 10376785328 10825568256 1118448336 12.012 100 20 40 0 1485148384 360510992 6189056
09:05:51 51379 8192000000 10115602752 10588540928 1116392016 11.993 99 24 48 1 1254725056 133055296 5277744
09:08:08 29633 8192000000 10072736352 10502762496 1137854560 12.195 100 21 42 0 1171939168 30106160 3978448
09:09:46 87125 8192000000 10061209232 10558566400 1125328064 12.077 99 19 38 1 1208956944 79439488 4189392
09:12:25 58305 8192000000 10056086576 10492723200 1081873312 11.665 96 9 17 0 1118697648 35176208 1648128
09:14:26 18723 8192000000 10096021712 10533634048 1127468336 12.097 100 41 82 2 1192995600 56393744 9133520
09:16:38 75677 8192000000 10116187296 10551316480 1135905648 12.177 100 40 78 1 1195782944 51624064 8253232
09:19:00 87184 8192000000 10124451552 10634272768 1139526512 12.211 100 16 32 1 1262426096 119228736 3670848
09:21:27 58390 8192000000 10096241600 10589069312 1143604480 12.249 100 31 60 1 1188695872 38487824 6603568
09:23:26 84977 8192000000 10095781904 10583543808 1151136848 12.320 100 21 42 0 1204409728 48389248 4883632
09:26:04 78168 8192000000 10059087376 10522206208 1139878016 12.214 99 16 32 0 1186910320 43474352 3557952
09:28:26 63947 8192000000 10070288176 10540601344 1130683824 12.128 99 19 36 3 1168014320 33562128 3768368
09:31:24 76703 8192000000 10082858400 10547150848 1153528048 12.343 100 9 18 1 1194535696 39139488 1868160
09:33:55 36016 8192000000 10062339424 10514759680 1135073744 12.169 98 16 32 1 1164378992 25979904 3325344
09:35:31 35635 8192000000 10134671648 10590138368 1160005232 12.403 100 33 66 1 1220836384 53881104 6950048
09:37:09 88929 8192000000 10176072272 10625904640 1106511120 11.899 95 17 34 1 1191782352 82014240 3256992
09:39:39 81808 8192000000 10095509136 10567704576 1125555728 12.079 98 9 18 0 1195983216 68663056 1764432
09:42:03 57157 8192000000 10137292768 10592940032 1086595568 11.710 95 33 66 0 1160039488 66985568 6458352
09:44:04 55858 8192000000 10061896160 10491084800 1060006736 11.457 94 21 42 2 1117794464 53552000 4235728
09:47:46 66906 8192000000 10027631616 10470572032 1062438880 11.480 96 25 46 1 1128281760 61165328 4677552
09:50:00 100273 8192000000 10058109168 10508947456 1046609696 11.328 95 13 26 1 1106271120 56651680 3009744
09:52:42 97097 8192000000 10046748032 10519789568 1092762256 11.769 99 15 31 0 1187906832 91751760 3392816
09:55:22 92874 8192000000 10034687568 10471931904 1067776176 11.531 96 20 40 1 1109989040 37621632 4591232
09:58:04 67694 8192000000 10045154640 10508382208 1090944416 11.752 98 32 64 1 1164243456 67212912 6086128
10:00:16 49894 8192000000 10088006128 10545123328 1108542512 11.919 99 20 40 0 1180411904 67801536 4067856
10:02:14 101206 8192000000 10110676464 10566639616 1105425616 11.889 98 20 40 0 1199709424 89910416 4373392
10:05:02 51754 8192000000 10064387600 10496626688 1121291712 12.039 99 17 34 1 1157702784 33461136 2949936
10:06:56 88687 8192000000 10067593904 10529177600 1102993744 11.866 97 31 62 0 1150188416 41413264 5781408
10:09:27 71234 8192000000 10071782592 10530111488 1112303600 11.954 98 17 34 1 1150352832 35038160 3011072
10:11:41 49010 8192000000 10075344512 10524938240 1126964768 12.093 99 16 32 0 1177709568 47261728 3483072
10:13:30 74517 8192000000 10094239568 10530734080 1124978912 12.074 98 22 44 0 1164946064 35361968 4605184
10:15:51 22236 8192000000 10150612352 10584289280 1165575392 12.455 100 40 79 2 1227745648 53349584 8820672
10:17:16 92447 8192000000 10066763808 10522193920 1095838688 11.798 95 14 28 1 1239248064 140079136 3330240
10:19:50 60597 8192000000 10085249984 10563145728 1148810192 12.298 100 19 38 1 1180094384 27371616 3912576
10:21:50 46392 8192000000 10124072768 10599342080 1155286688 12.359 100 26 52 0 1209413712 48956480 5170544
10:23:37 87712 8192000000 10107218944 10591129600 1160107824 12.404 100 11 22 0 1209120192 46782176 2230192
10:26:06 108774 8192000000 10124248512 10561318912 1151224144 12.321 99 34 68 1 1198013744 39741648 7047952
10:28:57 46599 8192000000 10116164720 10589380608 1152278128 12.331 99 19 38 1 1216201408 59636224 4287056
10:30:52 74101 8192000000 10084993616 10584047616 1124304160 12.068 97 7 14 0 1163359168 37597456 1457552
10:33:11 67791 8192000000 10084719776 10565443584 1127556288 12.098 98 15 31 1 1187117056 56338384 3222384
10:35:20 62052 8192000000 10087987312 10535026688 1092595696 11.767 96 17 34 0 1157672704 61461072 3615936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 06-08-2021 10:38:42 to 06-08-2021 10:38:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-08-2021 10:38:57 to 06-08-2021 10:38:59.

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          230750
MON$OLDEST_ACTIVE               234142
MON$OLDEST_SNAPSHOT             234142
MON$NEXT_TRANSACTION            234142
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                       2056096
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2563 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2563 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2563 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2563 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Fri Aug  6 10:37:12 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Fri Aug  6 10:37:13 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Fri Aug  6 10:37:14 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

06.08.21 10:39:00. 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-06 10:39:03 No severe PSQL-related problems occured
06.08.21 10:39:04. End of report.