Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.04.2022 13: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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 16645128 kB
MemAvailable 31576160 kB
Buffers 0 kB
Cached 15087556 kB
SwapCached 2288 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.2
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 2022-04-07 13:37:15
DTS_END                         2022-04-07 13:37:15.4450
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/43594
TRN_ID                          221983
ATT_ID                          649
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: 8699.25 24064 1043910
customer order: creation 170.88 423 20506
customer order: refuse 14.53 382 1744
order to supplier: creation 44.43 1349 5332
order to supplier: removal 3.13 173133 375
invoice (draft): creation 45.41 3095 5449
invoice (draft): removal 48.78 968 5853
invoice accept: apply 40.70 25401 4884
invoice accept: cancel 43.78 1216 5254
customer reserve: creation 3759.75 244 451170
customer reserve: removal 4235.21 102 508225
realization accept: apply 80.64 261 9677
realization accept: cancel 83.60 152 10032
payment from customer: creation 57.84 176 6941
payment from customer: removal 5.73 120 688
payment to supplier: creation 48.83 192 5859
payment to supplier: removal 3.25 137 390
service: total inventory turnovers 4.22 3522 507
service: total monetary turnovers 7.55 416 906
service: aggregate perf. data 0.93 15948 112
service: refresh index statistics 0.05 254039 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-04-2022 13:38:35 to 07-04-2022 13:38:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10419
TEST_TIME 2 7373
TEST_TIME 3 6972
TEST_TIME 4 6373
TEST_TIME 5 6034
TEST_TIME 6 5668
TEST_TIME 7 5977
TEST_TIME 8 5802
TEST_TIME 9 5824
TEST_TIME 10 5992
TEST_TIME 11 6274
TEST_TIME 12 6316
TEST_TIME 13 6505
TEST_TIME 14 6548
TEST_TIME 15 6732
TEST_TIME 16 7293
TEST_TIME 17 7231
TEST_TIME 18 7335
TEST_TIME 19 7554
TEST_TIME 20 7741
TEST_TIME 21 8404
TEST_TIME 22 8574
TEST_TIME 23 8712
TEST_TIME 24 8710
TEST_TIME 25 8985
TEST_TIME 26 9019
TEST_TIME 27 9042
TEST_TIME 28 9711
TEST_TIME 29 9871
TEST_TIME 30 10093
TEST_TIME 31 10535
TEST_TIME 32 10902
TEST_TIME 33 10688
TEST_TIME 34 10418
TEST_TIME 35 10399
TEST_TIME 36 10585
TEST_TIME 37 10357
TEST_TIME 38 10296
TEST_TIME 39 10227
TEST_TIME 40 10111
TEST_TIME 41 10058
TEST_TIME 42 10068
TEST_TIME 43 10039
TEST_TIME 44 9961
TEST_TIME 45 9849
TEST_TIME 46 9749
TEST_TIME 47 9709
TEST_TIME 48 9563
TEST_TIME 49 9528
TEST_TIME 50 9482
TEST_TIME 51 9388
TEST_TIME 52 9331
TEST_TIME 53 9273
TEST_TIME 54 9211
TEST_TIME 55 9122
TEST_TIME 56 9107
TEST_TIME 57 9135
TEST_TIME 58 9153
TEST_TIME 59 9106
TEST_TIME 60 9067
TEST_TIME 61 9011
TEST_TIME 62 9050
TEST_TIME 63 9062
TEST_TIME 64 9224
TEST_TIME 65 9249
TEST_TIME 66 9191
TEST_TIME 67 9192
TEST_TIME 68 9242
TEST_TIME 69 9219
TEST_TIME 70 9180
TEST_TIME 71 9285
TEST_TIME 72 9201
TEST_TIME 73 9118
TEST_TIME 74 9094
TEST_TIME 75 9069
TEST_TIME 76 9067
TEST_TIME 77 9003
TEST_TIME 78 8940
TEST_TIME 79 8926
TEST_TIME 80 8881
TEST_TIME 81 8856
TEST_TIME 82 8836
TEST_TIME 83 8822
TEST_TIME 84 8816
TEST_TIME 85 8799
TEST_TIME 86 8767
TEST_TIME 87 8813
TEST_TIME 88 8836
TEST_TIME 89 8835
TEST_TIME 90 9042
TEST_TIME 91 9117
TEST_TIME 92 9156
TEST_TIME 93 9099
TEST_TIME 94 9098
TEST_TIME 95 9046
TEST_TIME 96 9016
TEST_TIME 97 9019
TEST_TIME 98 9155
TEST_TIME 99 9167
TEST_TIME 100 9140
TEST_TIME 101 9112
TEST_TIME 102 9189
TEST_TIME 103 9159
TEST_TIME 104 9144
TEST_TIME 105 9067
TEST_TIME 106 9084
TEST_TIME 107 9032
TEST_TIME 108 9050
TEST_TIME 109 9004
TEST_TIME 110 8964
TEST_TIME 111 8951
TEST_TIME 112 8928
TEST_TIME 113 8923
TEST_TIME 114 8889
TEST_TIME 115 8863
TEST_TIME 116 8817
TEST_TIME 117 8790
TEST_TIME 118 8793
TEST_TIME 119 8795
TEST_TIME 120 8737

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-04-2022 13:38:38 to 07-04-2022 13:38:42.

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 1459845 1459845 0 0.00 0 17785 12 0 0
doc_list_biud 1459836 1459836 0 0.00 0 25709 41 0 0
sp_add_invoice_to_stock 5041 4884 157 3.11 38 233919 25402 0 157
sp_cancel_adding_invoice 5254 5254 0 0.00 6 84813 1217 0 0
sp_cancel_client_order 1744 1744 0 0.00 6 10698 382 0 0
sp_cancel_customer_reserve 508225 508225 0 0.00 1 17093 102 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 4 7017 120 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 4 12849 138 0 0
sp_cancel_supplier_invoice 5869 5853 16 0.27 3 22477 968 0 16
sp_cancel_supplier_order 375 375 0 0.00 13 1231786 173133 0 0
sp_cancel_write_off 10032 10032 0 0.00 2 7873 153 0 0
sp_client_order 20506 20506 0 0.00 6 20008 424 0 0
sp_customer_reserve 451170 451170 0 0.00 1 72497 245 0 0
sp_fill_shopping_cart 31279 31279 0 0.00 1 17581 81 0 0
sp_fill_shopping_cart_clo_res 451139 451139 0 0.00 0 12483 17 0 0
sp_get_clo_for_invoice 4835 4835 0 0.00 0 25820 317 0 0
sp_kill_cost_storno 16364 16364 0 0.00 0 17779 70 0 0
sp_kill_qstorno_ret_qs2qd 1032710 1032710 0 0.00 0 25708 45 0 0
sp_kill_qty_storno 545994 545994 0 0.00 0 25709 101 0 0
sp_lock_selected_doc 20221 20221 0 0.00 0 10183 8 0 0
sp_make_cost_storno 27311 27311 0 0.00 0 12166 75 0 0
sp_multiply_rows_for_pdistr 27311 27311 0 0.00 0 10704 37 0 0
sp_multiply_rows_for_qdistr 20506 20506 0 0.00 0 19956 155 0 0
sp_pay_from_customer 6941 6941 0 0.00 6 15207 177 0 0
sp_pay_to_supplier 5859 5859 0 0.00 6 12521 193 0 0
sp_payment_common 12800 12800 0 0.00 2 15164 46 0 0
sp_qd_handle_on_cancel_clo 1744 1744 0 0.00 1 10468 309 0 0
sp_qd_handle_on_invoice_upd_sts 10088 10088 0 0.00 0 14742 467 0 0
sp_qd_handle_on_reserve_upd_sts 9677 9677 0 0.00 0 12361 56 0 0
sp_reserve_write_off 9677 9677 0 0.00 8 17499 262 0 0
sp_supplier_invoice 5449 5449 0 0.00 11 91617 3096 0 0
sp_supplier_order 5332 5332 0 0.00 8 26115 1349 0 0
srv_aggregate_perf_data 112 112 0 0.00 2580 138771 15948 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 690 92286 32652 0 0
srv_find_qd_qs_mism 995988 995988 0 0.00 0 17572 9 0 0
srv_make_invnt_saldo 507 507 0 0.00 35 75296 3522 0 0
srv_make_money_saldo 906 906 0 0.00 1 23336 416 0 0
srv_recalc_idx_stat 6 6 0 0.00 66161 495580 254040 0 0
t$perf-abend:sp_add_invoice_to_stock 157 157 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4894 4894 0 0.00 1 18857 843 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 1 5253 428 0 0
t$perf-norm:sp_cancel_client_order 1744 1744 0 0.00 0 14267 39 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 856 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 651 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 1922 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 2926 39 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 88882 6881 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 2410 26 0 0
t$perf-norm:sp_client_order 20506 20506 0 0.00 0 10602 35 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 2274 17 0 0
t$perf-norm:sp_pay_from_customer 6942 6942 0 0.00 0 2603 17 0 0
t$perf-norm:sp_pay_to_supplier 5859 5859 0 0.00 0 14267 23 0 0
t$perf-norm:sp_reserve_write_off 9677 9677 0 0.00 0 7636 26 0 0
t$perf-norm:sp_supplier_invoice 5449 5449 0 0.00 0 10523 32 0 0
t$perf-norm:sp_supplier_order 5332 5332 0 0.00 0 14366 32 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 289 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 1317 22 0 0
t$perf-norm:srv_make_invnt_saldo 507 507 0 0.00 0 1419 17 0 0
t$perf-norm:srv_make_money_saldo 906 906 0 0.00 0 320 4 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 5 177 92 0 0
v_add_invoice_to_stock 9976 9662 314 3.14 0 7468 27 0 314
v_all_customers 37518 37518 0 0.00 0 125 3 0 0
v_all_suppliers 30338 30338 0 0.00 0 88 4 0 0
v_all_wares 41004 41004 0 0.00 0 205 3 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 1922 16 0 0
v_cancel_client_order 3488 3488 0 0.00 0 2820 11 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 5589 14 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 2945 12 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 12824 28 0 0
v_cancel_supplier_invoice 876 844 32 3.65 0 993 23 0 32
v_cancel_supplier_order 750 750 0 0.00 0 5203 23 0 0
v_cancel_write_off 2078 2078 0 0.00 0 4121 24 0 0
v_max_id_clo_ord 5328 5328 0 0.00 0 513 4 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 2026 16 0 0
v_max_id_ord_sup 5444 5444 0 0.00 0 3818 8 0 0
v_max_non_paid_invoice 1461 1461 0 0.00 0 2327 9 0 0
v_max_non_paid_realizn 3669 3669 0 0.00 0 1446 7 0 0
v_min_id_clo_ord 5328 5328 0 0.00 0 462 7 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 1183 8 0 0
v_min_id_ord_sup 5444 5444 0 0.00 0 3753 9 0 0
v_min_non_paid_invoice 5859 5859 0 0.00 0 4232 15 0 0
v_min_non_paid_realizn 6941 6941 0 0.00 0 15137 25 0 0
v_our_firm 10028 10028 0 0.00 0 57 4 0 0
v_reserve_write_off 19354 19354 0 0.00 0 12900 16 0 0
x_lock_depdocs_on_canc_invoice 10685 10685 0 0.00 0 13913 128 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 9940 389 0 0
x_make_qstorno_1000_1200 5332 5332 0 0.00 3 26091 1189 0 0
x_make_qstorno_1200_2000 5449 5449 0 0.00 5 78579 2864 0 0
x_make_qstorno_2100_3300 451167 451167 0 0.00 0 72493 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-04-2022 13:38:43 to 07-04-2022 13:38:47.

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
11:07:19 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:20 19420 8192000000 9717763712 10015531008 797442368 8.870 100 91 181 1 827003696 7651168 21910160
11:09:44 16932 8192000000 9750461808 10054889472 825344736 9.152 100 90 180 0 859114112 11902224 21867152
11:11:06 20751 8192000000 9793187568 10117644288 866957440 9.570 100 74 148 0 904623248 20939984 16725824
11:12:30 7967 8192000000 9871308640 10207465472 926676144 10.162 100 86 167 2 984213648 39915216 17622288
11:13:39 2883 8192000000 9915970672 10259787776 955588368 10.446 100 86 171 2 1036070400 63035824 17446208
11:14:44 3079 8192000000 9981786784 10336522240 994973616 10.830 100 85 170 1 1097943888 85197296 17772976
11:15:50 1507 8192000000 9987949248 10350952448 1021068992 11.082 100 91 173 1 1107600592 68778800 17752800
11:16:53 1237 8192000000 10017836048 10384109568 1039029552 11.255 100 87 168 2 1133379824 78077440 16272832
11:17:55 1191 8192000000 10031853200 10404450304 1056274272 11.421 100 99 188 1 1148673616 73139152 19260192
11:18:57 1085 8192000000 10030844000 10411282432 1062335440 11.479 100 93 182 1 1147864448 66640048 18888960
11:19:59 1383 8192000000 10052245456 10434387968 1071260832 11.564 100 96 187 2 1173134512 82926160 18947520
11:21:02 4805 8192000000 10047645168 10437881856 1077805008 11.627 100 61 118 0 1170062176 80454752 11802416
11:22:07 1648 8192000000 10050479760 10436583424 1088580704 11.729 100 87 165 1 1174062336 68267872 17213760
11:23:09 1154 8192000000 10062264656 10449039360 1089108496 11.734 100 92 184 0 1181984208 73375536 19500176
11:24:11 1238 8192000000 10069209520 10456690688 1092317920 11.765 100 97 188 0 1188566080 76771120 19477040
11:25:13 1217 8192000000 10069527568 10459049984 1094267248 11.783 100 96 183 3 1186869552 73816480 18785824
11:26:16 1103 8192000000 10074498896 10459512832 1094695136 11.787 100 95 184 0 1193248304 79960640 18592528
11:27:18 1280 8192000000 10043275872 10432548864 1099753456 11.835 100 91 177 0 1167690096 49190032 18746608
11:28:20 2755 8192000000 10029805568 10420957184 1066260336 11.516 99 78 151 0 1149384400 65957424 17166640
11:29:25 1031 8192000000 10033975776 10419429376 1079803072 11.646 100 93 179 2 1159883824 61270480 18810272
11:30:27 3987 8192000000 10024636672 10411253760 1070142256 11.553 100 71 130 1 1152594720 70960928 11491536
11:31:31 980 8192000000 10040176192 10425196544 1076097216 11.610 100 90 165 2 1166608944 73375200 17136528
11:32:33 1143 8192000000 10017029136 10395971584 1045379008 11.316 100 94 173 3 1136064400 73499168 17186224
11:33:35 2914 8192000000 9969386000 10342453248 1030788640 11.176 99 68 125 4 1083664672 40621952 12254080
11:34:39 966 8192000000 9995944800 10368249856 1037287136 11.239 99 84 158 0 1116487024 61934576 17265312
11:35:41 923 8192000000 10001174048 10380943360 1052808944 11.388 100 87 163 1 1122060928 51516560 17735424
11:36:43 1279 8192000000 9988418064 10363224064 1045213824 11.315 100 97 182 2 1113079872 47146896 20719152
11:37:48 4062 8192000000 10000796672 10380660736 1048448800 11.346 99 76 146 2 1125148704 62229616 14470288
11:38:57 2576 8192000000 10037557456 10420916224 1073078784 11.581 100 80 152 2 1155416592 66707328 15630480
11:40:07 2980 8192000000 10061396432 10453262336 1090907136 11.751 100 67 130 3 1184094224 80174928 13012160
11:41:24 5313 8192000000 10060080096 10451099648 1103283184 11.869 100 66 116 2 1175947264 60196640 12467440
11:42:33 3243 8192000000 10109959968 10503540736 1116694528 11.996 100 67 124 3 1227600224 97617424 13288272
11:43:42 1798 8192000000 10173807680 10563203072 1134832144 12.167 100 88 166 3 1286660816 134423552 17405120
11:44:47 27312 8192000000 10216950032 10617597952 1144354400 12.256 100 67 130 1 1315956352 157219824 14382128
11:46:18 2192 8192000000 10236264208 10637062144 1162280256 12.425 100 79 152 0 1354995728 175528064 17187408
11:47:26 1079 8192000000 10300078656 10702442496 1176510800 12.558 100 91 182 0 1418407680 220220640 21676240
11:48:31 1705 8192000000 10345155760 10750816256 1185970928 12.646 100 88 171 1 1456905440 251799568 19134944
11:49:35 1881 8192000000 10375392592 10794225664 1190914032 12.692 100 90 172 1 1491110048 279598208 20597808
11:50:42 1150 8192000000 10398398832 10805555200 1186554784 12.651 100 97 188 0 1510733200 302281872 21896544
11:51:46 1752 8192000000 10420512912 10838622208 1175061856 12.544 99 90 180 1 1531947456 335473328 21412272
11:52:53 998 8192000000 10424340576 10874068992 1195122944 12.731 100 89 171 2 1533040720 317644048 20273728
11:53:57 1082 8192000000 10451904096 10855600128 1187437776 12.660 100 92 174 2 1552929088 345257936 20233376
11:55:06 1771 8192000000 10467969024 10898997248 1191693280 12.699 100 79 152 1 1576102736 365849648 18559808
11:56:10 2069 8192000000 10494708720 10927628288 1185747008 12.644 100 80 156 1 1610501104 405277504 19476592
11:57:15 682 8192000000 10481150112 10934308864 1185903184 12.645 100 98 194 1 1589104928 380492288 22709456
11:58:18 793 8192000000 10460573472 10927640576 1178038016 12.572 100 89 173 2 1566857376 367213120 21606240
11:59:20 9495 8192000000 10392752688 10833793024 1153419936 12.342 99 51 98 1 1496249968 330115424 12714608
12:00:33 4534 8192000000 10417147344 10871623680 1166544304 12.465 100 49 95 1 1522912224 344995856 11372064
12:01:49 6576 8192000000 10413268784 10869346304 1158981152 12.394 100 50 95 1 1541311200 369444464 12885584
12:03:07 16922 8192000000 10432679792 10876100608 1164523760 12.446 100 35 70 1 1528203072 354076880 9602432
12:04:27 9428 8192000000 10434304304 10876776448 1165777184 12.457 100 37 74 2 1554051568 378584176 9690208
12:05:42 26417 8192000000 10368608608 10836791296 1159329536 12.397 99 35 68 0 1471498768 303295680 8873552
12:07:17 33362 8192000000 10322777952 10839056384 1149956624 12.309 99 25 50 2 1442953024 286718768 6277632
12:08:52 34407 8192000000 10135755568 10585473024 1120603472 12.033 98 32 64 0 1228882240 100654224 7624544
12:10:33 51214 8192000000 10141100448 10575613952 1151360416 12.322 100 31 60 1 1240306496 82177280 6768800
12:12:25 15838 8192000000 10197701856 10641305600 1151227712 12.321 100 48 95 0 1302703264 141680576 9794976
12:13:42 65360 8192000000 10102114064 10614247424 1119605664 12.023 98 11 22 0 1185779008 63481488 2691856
12:16:00 54025 8192000000 10106832800 10546520064 1145749184 12.270 100 16 32 1 1214836576 65293840 3793552
12:18:34 75831 8192000000 10111843552 10604691456 1124117008 12.066 99 9 18 2 1201328000 75003072 2207920
12:20:53 35958 8192000000 10098106656 10518597632 1119945888 12.026 98 62 118 2 1168460464 34799808 13714768
12:22:37 86453 8192000000 10057180480 10517938176 1112255840 11.954 98 34 66 0 1148116368 27717392 8143136
12:25:14 28958 8192000000 10086518544 10512027648 1127562752 12.098 99 60 118 2 1184085856 44259408 12263696
12:26:47 27678 8192000000 10075516000 10519547904 1138629600 12.203 100 40 80 0 1192140320 45391120 8119600
12:28:23 5252 8192000000 10080480512 10508935168 1145235296 12.265 100 72 114 1 1182243504 25495200 11513008
12:29:30 61922 8192000000 10096470400 10556321792 1138910304 12.205 99 27 54 1 1202477344 58118160 5448880
12:31:33 92285 8192000000 10108003584 10628108288 1130840720 12.129 99 13 26 0 1209973600 76327760 2805120
12:34:13 35986 8192000000 10080927600 10541289472 1107178928 11.906 97 11 22 0 1157728128 48115648 2433552
12:35:54 56178 8192000000 10072826672 10510422016 1098074960 11.819 97 13 24 0 1131118656 30781728 2261968
12:37:51 41701 8192000000 10138411280 10577629184 1142987968 12.244 100 36 72 1 1244295488 93641392 7666128
12:39:43 45495 8192000000 10129774496 10599497728 1112566656 11.957 97 20 40 1 1225342864 108262032 4514176
12:41:31 47308 8192000000 10068935056 10501763072 1085821088 11.703 96 28 56 0 1151214928 59668672 5725168
12:43:20 42205 8192000000 10074933648 10516856832 1100784896 11.845 97 27 54 0 1145708128 39556096 5367136
12:45:03 4626 8192000000 10126351456 10543927296 1128008448 12.103 100 53 106 0 1212872576 73987472 10876656
12:46:08 39589 8192000000 10087220128 10515451904 1085360672 11.699 96 23 46 3 1153047168 63142880 4543616
12:47:49 63828 8192000000 10053342336 10498801664 1072267648 11.574 96 34 66 1 1120035936 40746448 7021840
12:49:54 47543 8192000000 10098687248 10550853632 1113196192 11.963 100 50 98 2 1199533120 75455856 10881072
12:51:57 19838 8192000000 10053030976 10477334528 1086614304 11.710 98 53 99 0 1134981936 39113984 9253648
12:53:20 61853 8192000000 10028263488 10484637696 1087129920 11.715 98 7 12 1 1126473696 38082352 1261424
12:55:22 50977 8192000000 10049652992 10505302016 1107056240 11.905 100 10 20 1 1153785792 44775136 1954416
12:58:03 12274 8192000000 10051670736 10490327040 1107729808 11.911 99 43 82 5 1151774496 35535952 8508736
12:59:23 19023 8192000000 10074940368 10514472960 1116743152 11.996 100 28 56 3 1188491168 65222800 6525216
13:00:43 42673 8192000000 10133136416 10572197888 1132395584 12.144 100 30 60 0 1235398240 95733632 7269024
13:02:26 21958 8192000000 10180299200 10615570432 1146417152 12.276 100 35 71 0 1279224160 124278592 8528416
13:03:51 30102 8192000000 10245025536 10692886528 1152307824 12.331 100 41 82 2 1329471728 167455664 9708240
13:05:28 46516 8192000000 10225028352 10700648448 1152167680 12.330 100 30 61 3 1351172864 191707072 7298112
13:07:16 73478 8192000000 10151749744 10594627584 1114733936 11.977 98 12 24 0 1183334320 66367744 2232640
13:09:30 88373 8192000000 10076223984 10515689472 1107527552 11.909 97 17 34 0 1142372672 31768752 3076368
13:12:25 37879 8192000000 10160720864 10601267200 1136285984 12.181 99 50 80 6 1194460128 48468624 9705520
13:14:03 55397 8192000000 10149384480 10626310144 1141975152 12.234 100 30 58 1 1283508336 135312384 6220800
13:15:59 87479 8192000000 10067132736 10490961920 1096704992 11.806 96 36 72 1 1159845104 55462944 7677168
13:18:30 37844 8192000000 10142123616 10589569024 1143247216 12.246 100 37 74 0 1257456880 106539040 7670624
13:20:26 52377 8192000000 10117178096 10564849664 1113048112 11.961 98 29 56 4 1175323120 55765248 6509760
13:22:20 76087 8192000000 10082273568 10544275456 1129803536 12.120 100 39 76 1 1211352816 73538752 8010528
13:24:40 3874 8192000000 10071021520 10512195584 1135267952 12.171 100 19 38 1 1175124256 35147552 4708752
13:25:44 81898 8192000000 10050637952 10509856768 1096457200 11.804 97 10 21 0 1127076992 28624256 1995536
13:28:12 58492 8192000000 10065618672 10532098048 1101731792 11.854 97 22 43 4 1174403984 67577376 5094816
13:30:15 51533 8192000000 10055438928 10535514112 1132736176 12.147 100 8 16 0 1171143472 37059168 1348128
13:32:10 35985 8192000000 10075095168 10524884992 1140057536 12.216 100 30 58 1 1171992080 25331856 6602688
13:34:13 77681 8192000000 10063847168 10533765120 1107293888 11.907 97 11 22 0 1148363424 38733040 2336496
13:36:37 28999 8192000000 10041333712 10481549312 1086425920 11.709 97 30 60 1 1138557856 45828816 6303120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-04-2022 13:38:49 to 07-04-2022 13:39:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 157 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-04-2022 13:39:06 to 07-04-2022 13:39:08.

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          219989
MON$OLDEST_ACTIVE               222785
MON$OLDEST_SNAPSHOT             222785
MON$NEXT_TRANSACTION            222785
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                       2037280
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2750 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2750 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2750 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2750 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 ---
:76: 
:77: mscs-test-161.budzdorov.ru    Thu Apr  7 13:37:19 2022
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
--- end of diff output ---

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

07.04.22 13:39:09. 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
2022-04-07 13:39:12 No severe PSQL-related problems occured
07.04.22 13:39:13. End of report.