Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.03.2022 04:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 11207236 kB
MemAvailable 31616920 kB
Buffers 20 kB
Cached 20196112 kB
SwapCached 1868 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-03-20 04:37:15
DTS_END                         2022-03-20 04:37:15.5580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49882
TRN_ID                          204841
ATT_ID                          593
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: 9040.67 29585 1084880
customer order: creation 173.43 442 20811
customer order: refuse 15.02 379 1802
order to supplier: creation 46.66 1548 5599
order to supplier: removal 3.35 161422 402
invoice (draft): creation 46.12 3074 5534
invoice (draft): removal 49.65 956 5958
invoice accept: apply 42.35 28080 5082
invoice accept: cancel 44.87 1231 5384
customer reserve: creation 3972.64 265 476717
customer reserve: removal 4348.11 97 521773
realization accept: apply 84.78 275 10174
realization accept: cancel 82.46 159 9895
payment from customer: creation 59.97 170 7196
payment from customer: removal 5.77 131 692
payment to supplier: creation 49.68 180 5962
payment to supplier: removal 3.58 138 430
service: total inventory turnovers 3.87 4465 464
service: total monetary turnovers 7.43 425 891
service: aggregate perf. data 0.92 17314 110
service: refresh index statistics 0.03 370953 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-03-2022 04:38:26 to 20-03-2022 04:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10691
TEST_TIME 2 8645
TEST_TIME 3 7736
TEST_TIME 4 6588
TEST_TIME 5 7050
TEST_TIME 6 7058
TEST_TIME 7 6750
TEST_TIME 8 6684
TEST_TIME 9 6940
TEST_TIME 10 6903
TEST_TIME 11 7127
TEST_TIME 12 7305
TEST_TIME 13 7650
TEST_TIME 14 7845
TEST_TIME 15 7748
TEST_TIME 16 7584
TEST_TIME 17 7534
TEST_TIME 18 7645
TEST_TIME 19 7713
TEST_TIME 20 7807
TEST_TIME 21 7942
TEST_TIME 22 8350
TEST_TIME 23 8400
TEST_TIME 24 8817
TEST_TIME 25 8841
TEST_TIME 26 9144
TEST_TIME 27 9173
TEST_TIME 28 9268
TEST_TIME 29 9635
TEST_TIME 30 9687
TEST_TIME 31 9994
TEST_TIME 32 10143
TEST_TIME 33 10138
TEST_TIME 34 10243
TEST_TIME 35 10440
TEST_TIME 36 10923
TEST_TIME 37 10922
TEST_TIME 38 10708
TEST_TIME 39 10617
TEST_TIME 40 10538
TEST_TIME 41 10439
TEST_TIME 42 10444
TEST_TIME 43 10356
TEST_TIME 44 10260
TEST_TIME 45 10211
TEST_TIME 46 10203
TEST_TIME 47 10237
TEST_TIME 48 10144
TEST_TIME 49 10070
TEST_TIME 50 9922
TEST_TIME 51 9938
TEST_TIME 52 9827
TEST_TIME 53 9760
TEST_TIME 54 9710
TEST_TIME 55 9725
TEST_TIME 56 9635
TEST_TIME 57 9579
TEST_TIME 58 9655
TEST_TIME 59 9556
TEST_TIME 60 9497
TEST_TIME 61 9519
TEST_TIME 62 9539
TEST_TIME 63 9503
TEST_TIME 64 9385
TEST_TIME 65 9409
TEST_TIME 66 9317
TEST_TIME 67 9349
TEST_TIME 68 9397
TEST_TIME 69 9381
TEST_TIME 70 9359
TEST_TIME 71 9419
TEST_TIME 72 9360
TEST_TIME 73 9374
TEST_TIME 74 9477
TEST_TIME 75 9446
TEST_TIME 76 9453
TEST_TIME 77 9416
TEST_TIME 78 9448
TEST_TIME 79 9415
TEST_TIME 80 9326
TEST_TIME 81 9254
TEST_TIME 82 9249
TEST_TIME 83 9170
TEST_TIME 84 9114
TEST_TIME 85 9041
TEST_TIME 86 9025
TEST_TIME 87 9198
TEST_TIME 88 9151
TEST_TIME 89 9120
TEST_TIME 90 9101
TEST_TIME 91 9103
TEST_TIME 92 9119
TEST_TIME 93 9127
TEST_TIME 94 9096
TEST_TIME 95 9123
TEST_TIME 96 9232
TEST_TIME 97 9202
TEST_TIME 98 9208
TEST_TIME 99 9221
TEST_TIME 100 9161
TEST_TIME 101 9136
TEST_TIME 102 9093
TEST_TIME 103 9076
TEST_TIME 104 9033
TEST_TIME 105 8982
TEST_TIME 106 8957
TEST_TIME 107 8977
TEST_TIME 108 9014
TEST_TIME 109 9031
TEST_TIME 110 9004
TEST_TIME 111 8976
TEST_TIME 112 9010
TEST_TIME 113 9008
TEST_TIME 114 8982
TEST_TIME 115 9071
TEST_TIME 116 9067
TEST_TIME 117 9114
TEST_TIME 118 9098
TEST_TIME 119 9101
TEST_TIME 120 9072

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-03-2022 04:38:29 to 20-03-2022 04:38:33.

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 1522572 1522572 0 0.00 0 18303 14 0 0
doc_list_biud 1522556 1522556 0 0.00 0 25958 39 0 0
sp_add_invoice_to_stock 5220 5082 138 2.64 35 261374 28081 0 138
sp_cancel_adding_invoice 5384 5384 0 0.00 7 61088 1232 0 0
sp_cancel_client_order 1802 1802 0 0.00 6 11989 380 0 0
sp_cancel_customer_reserve 521773 521773 0 0.00 1 25960 98 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 4 3192 131 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 4 7075 139 0 0
sp_cancel_supplier_invoice 5966 5958 8 0.13 4 23860 957 0 8
sp_cancel_supplier_order 402 402 0 0.00 10 1453574 161422 0 0
sp_cancel_write_off 9895 9895 0 0.00 2 6442 159 0 0
sp_client_order 20811 20811 0 0.00 6 21404 442 0 0
sp_customer_reserve 476717 476717 0 0.00 1 124804 266 0 0
sp_fill_shopping_cart 31931 31931 0 0.00 2 20294 126 0 0
sp_fill_shopping_cart_clo_res 476680 476680 0 0.00 0 15019 19 0 0
sp_get_clo_for_invoice 5031 5031 0 0.00 0 18703 371 0 0
sp_kill_cost_storno 16401 16401 0 0.00 0 7196 74 0 0
sp_kill_qstorno_ret_qs2qd 1059801 1059801 0 0.00 0 25950 42 0 0
sp_kill_qty_storno 560417 560417 0 0.00 0 25956 98 0 0
sp_lock_selected_doc 21110 21110 0 0.00 0 7062 9 0 0
sp_make_cost_storno 28361 28361 0 0.00 0 17693 77 0 0
sp_multiply_rows_for_pdistr 28360 28360 0 0.00 0 18232 38 0 0
sp_multiply_rows_for_qdistr 20809 20809 0 0.00 0 18993 168 0 0
sp_pay_from_customer 7196 7196 0 0.00 5 17076 171 0 0
sp_pay_to_supplier 5962 5962 0 0.00 6 8650 181 0 0
sp_payment_common 13156 13156 0 0.00 2 17051 43 0 0
sp_qd_handle_on_cancel_clo 1802 1802 0 0.00 1 11977 314 0 0
sp_qd_handle_on_invoice_upd_sts 10413 10413 0 0.00 0 23526 532 0 0
sp_qd_handle_on_reserve_upd_sts 10174 10174 0 0.00 0 18834 60 0 0
sp_reserve_write_off 10174 10174 0 0.00 8 18868 275 0 0
sp_supplier_invoice 5534 5534 0 0.00 10 98180 3075 0 0
sp_supplier_order 5599 5599 0 0.00 9 29018 1548 0 0
srv_aggregate_perf_data 110 110 0 0.00 2209 158624 17314 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 919 152354 31123 0 0
srv_find_qd_qs_mism 1034369 1034369 0 0.00 0 10725 11 0 0
srv_make_invnt_saldo 464 464 0 0.00 68 107918 4466 0 0
srv_make_money_saldo 891 891 0 0.00 2 16316 426 0 0
srv_recalc_idx_stat 4 4 0 0.00 227383 467653 370954 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 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 5086 5086 0 0.00 1 15259 941 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 2 7046 483 0 0
t$perf-norm:sp_cancel_client_order 1802 1802 0 0.00 0 3865 32 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 4957 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 8094 35 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 588 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 574 30 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 87734 7371 0 0
t$perf-norm:sp_cancel_write_off 1050 1050 0 0.00 0 7642 24 0 0
t$perf-norm:sp_client_order 20813 20813 0 0.00 0 7272 35 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 623 14 0 0
t$perf-norm:sp_pay_from_customer 7196 7196 0 0.00 0 7817 22 0 0
t$perf-norm:sp_pay_to_supplier 5962 5962 0 0.00 0 6956 22 0 0
t$perf-norm:sp_reserve_write_off 10174 10174 0 0.00 0 7157 21 0 0
t$perf-norm:sp_supplier_invoice 5535 5535 0 0.00 0 6502 30 0 0
t$perf-norm:sp_supplier_order 5599 5599 0 0.00 0 7053 30 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 650 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 42 4 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 696 15 0 0
t$perf-norm:srv_make_money_saldo 891 891 0 0.00 0 2242 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 13 175 73 0 0
v_add_invoice_to_stock 10324 10048 276 2.67 0 3200 23 0 276
v_all_customers 38593 38593 0 0.00 0 61 4 0 0
v_all_suppliers 31172 31172 0 0.00 0 296 4 0 0
v_all_wares 41602 41602 0 0.00 0 184 4 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 750 17 0 0
v_cancel_client_order 3604 3604 0 0.00 0 2777 10 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 2785 16 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 577 11 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 2937 14 0 0
v_cancel_supplier_invoice 896 880 16 1.78 0 1976 28 0 16
v_cancel_supplier_order 804 804 0 0.00 0 1294 13 0 0
v_cancel_write_off 2100 2100 0 0.00 0 3023 27 0 0
v_max_id_clo_ord 5595 5595 0 0.00 0 957 5 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 1613 11 0 0
v_max_id_ord_sup 5531 5531 0 0.00 0 5536 8 0 0
v_max_non_paid_invoice 1498 1498 0 0.00 0 210 7 0 0
v_max_non_paid_realizn 3490 3490 0 0.00 0 17046 12 0 0
v_min_id_clo_ord 5595 5595 0 0.00 0 3878 15 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 2127 13 0 0
v_min_id_ord_sup 5531 5531 0 0.00 0 4872 9 0 0
v_min_non_paid_invoice 5960 5960 0 0.00 0 2891 11 0 0
v_min_non_paid_realizn 7196 7196 0 0.00 0 6352 14 0 0
v_our_firm 10420 10420 0 0.00 0 81 4 0 0
v_reserve_write_off 20348 20348 0 0.00 0 3198 11 0 0
x_lock_depdocs_on_canc_invoice 10902 10902 0 0.00 0 12096 130 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 4251 328 0 0
x_make_qstorno_1000_1200 5599 5599 0 0.00 2 28961 1122 0 0
x_make_qstorno_1200_2000 5534 5534 0 0.00 5 97713 2860 0 0
x_make_qstorno_2100_3300 476717 476717 0 0.00 0 124794 235 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-03-2022 04:38:34 to 20-03-2022 04:38:38.

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
02:15:27 17872 8192000000 9838199344 10168344576 898557280 9.884 100 81 161 1 955094768 38625504 17911984
02:16:48 15504 8192000000 9905124880 10242977792 929949488 10.194 100 86 172 0 1010478464 61340064 19188912
02:18:09 7210 8192000000 9927444320 10273337344 963299504 10.521 100 81 161 0 1042743024 62151760 17291760
02:19:26 3856 8192000000 9957537616 10315055104 998401808 10.863 100 78 151 0 1073343776 59420144 15521824
02:20:33 3047 8192000000 9978469264 10341588992 1015209792 11.026 100 82 157 1 1100452992 68974992 16268208
02:21:38 1336 8192000000 10008261104 10373914624 1032601616 11.193 100 98 190 3 1120451056 68397376 19452064
02:22:42 779 8192000000 10013908400 10382520320 1047888624 11.340 100 94 174 0 1130757472 65363648 17505200
02:23:47 1438 8192000000 10040667120 10413174784 1053874336 11.398 100 92 179 1 1162835088 90375184 18585568
02:24:50 1188 8192000000 10041658528 10417479680 1059015344 11.447 100 93 187 1 1159967600 81977296 18974960
02:25:54 1470 8192000000 10040763152 10417795072 1063182368 11.487 100 94 186 1 1162667184 80674304 18810512
02:26:57 1356 8192000000 10041077472 10423324672 1068886784 11.541 100 94 186 1 1163943776 76965072 18091920
02:28:00 2374 8192000000 10049773744 10430676992 1074188816 11.592 100 97 193 1 1170766672 77230896 19346960
02:29:03 2636 8192000000 10056509216 10442436608 1083085712 11.677 100 96 192 2 1181899600 79416960 19396928
02:30:07 1800 8192000000 10058202048 10445742080 1088924320 11.732 100 83 159 1 1175843824 70737136 16182368
02:31:10 1582 8192000000 10055806336 10441965568 1095316048 11.793 100 99 188 4 1178066976 63315536 19435392
02:32:12 2647 8192000000 10078720352 10468847616 1096544544 11.805 100 97 197 1 1199395360 83068960 19781856
02:33:16 2066 8192000000 10049190720 10433015808 1102428256 11.861 100 97 191 3 1171302400 48723120 20151024
02:34:19 2412 8192000000 10062211696 10455248896 1098612816 11.824 100 95 194 0 1185457232 67548192 19296224
02:35:22 1932 8192000000 10057854256 10442792960 1094247984 11.783 100 96 192 0 1178125584 64743680 19133920
02:36:25 2337 8192000000 10034120400 10416111616 1088512496 11.729 100 93 184 4 1158076976 50059696 19504784
02:37:29 1915 8192000000 10026604256 10406064128 1078066752 11.629 100 88 165 2 1144057280 50145712 15844816
02:38:39 13356 8192000000 10044438464 10442100736 1085208480 11.697 100 72 144 0 1182175408 82395312 14571616
02:40:00 2229 8192000000 10038964032 10423197696 1080542352 11.653 100 76 142 4 1158625808 64275424 13808032
02:41:07 9709 8192000000 10045321488 10435129344 1070775680 11.559 99 69 137 0 1167824016 82904816 14143520
02:42:26 1324 8192000000 10073985616 10457378816 1079291632 11.641 100 96 179 2 1194622768 94111232 21219904
02:43:30 5032 8192000000 10072994176 10459287552 1080681472 11.654 99 75 144 1 1192290880 96216608 15392800
02:44:45 3930 8192000000 10129683968 10514296832 1099878048 11.836 100 77 146 1 1245570368 130362528 15329792
02:45:52 1584 8192000000 10176813696 10561470464 1097986112 11.819 100 86 167 0 1291718992 175646000 18086880
02:46:57 1144 8192000000 10194258288 10576773120 1104853776 11.884 100 88 170 3 1310757376 186744112 19159488
02:48:06 2186 8192000000 10232754688 10621292544 1119262432 12.020 100 85 162 1 1354149008 215986976 18899600
02:49:11 3356 8192000000 10243603648 10642735104 1126490512 12.088 100 72 139 1 1363399008 221105040 15803456
02:50:20 1582 8192000000 10264768032 10685706240 1148735952 12.298 100 96 193 1 1376260864 205538576 21986336
02:51:24 2328 8192000000 10280096320 10684358656 1156313520 12.369 100 95 189 1 1387662864 210321360 21027984
02:52:30 2449 8192000000 10326719840 10727460864 1164052608 12.441 100 77 151 0 1433813120 252893696 16866816
02:53:36 1507 8192000000 10387559040 10786619392 1173691472 12.531 100 77 151 0 1496238432 304372384 18174576
02:54:43 1205 8192000000 10446955808 10847457280 1187530112 12.660 100 88 177 0 1556003552 346453472 22019968
02:55:47 2194 8192000000 10453422672 10880266240 1183077472 12.619 100 67 129 0 1560909552 362169440 15662640
02:56:52 1216 8192000000 10524672288 10932568064 1191176832 12.694 100 94 183 0 1630265264 417925456 21162976
02:57:58 1485 8192000000 10519380592 10967953408 1184615744 12.633 100 90 177 0 1624167200 418429696 21121760
02:59:07 900 8192000000 10543562720 10966282240 1176341312 12.556 99 94 183 0 1653275744 454779856 22154576
03:00:11 2529 8192000000 10478921744 10940878848 1185613712 12.643 100 66 132 2 1583878128 381015472 17248944
03:01:17 2456 8192000000 10465559216 10937757696 1190252576 12.686 100 57 112 1 1577885184 373590272 14042336
03:02:23 3743 8192000000 10453908448 10947502080 1192177088 12.704 100 57 112 0 1571389056 364993712 14218256
03:03:31 4374 8192000000 10499909424 10916196352 1204466448 12.818 100 81 158 0 1597611968 375052448 18093072
03:04:38 7719 8192000000 10517885696 10962931712 1187533616 12.660 100 55 103 0 1617854576 416047872 14273088
03:05:50 9160 8192000000 10453290144 10968645632 1174180480 12.536 99 52 102 2 1553852080 366165264 13506336
03:07:05 2342 8192000000 10463864656 10883244032 1183233184 12.620 100 71 142 0 1566291280 366438704 16619392
03:08:12 14722 8192000000 10411158400 10911780864 1159523488 12.399 99 42 82 0 1516866576 346807648 10535440
03:09:34 55687 8192000000 10330949680 10778238976 1136229712 12.180 97 21 42 0 1398076592 256677504 5169376
03:11:33 9909 8192000000 10287602480 10727112704 1160424368 12.407 100 45 87 2 1395877120 225010704 10442048
03:13:16 52330 8192000000 10086794384 10575323136 1100456736 11.842 97 17 33 3 1213056304 109156512 3443056
03:15:10 27322 8192000000 10071808096 10494808064 1125013424 12.074 100 58 98 2 1159864112 23567296 11283392
03:16:43 96242 8192000000 10092559264 10566619136 1096094768 11.801 97 17 34 1 1141556064 42357872 3103424
03:19:41 88908 8192000000 10059717984 10487406592 1104028144 11.876 97 35 65 4 1136359344 26146464 6184736
03:22:14 8435 8192000000 10068357968 10501615616 1122661872 12.052 100 61 120 2 1174419872 38129232 13628768
03:23:28 152353 8192000000 10095369216 10566045696 1109229488 11.925 97 13 26 2 1218334288 105553824 3550976
03:27:01 106564 8192000000 10101306448 10549669888 1107879808 11.912 97 27 54 0 1164451184 50232544 6338832
03:29:49 83308 8192000000 10093266864 10520023040 1134295232 12.162 99 65 128 4 1180867840 34383440 12189168
03:32:13 23853 8192000000 10081720160 10521493504 1123204528 12.057 99 33 65 1 1190400176 60681984 6513664
03:33:48 20106 8192000000 10133532080 10559492096 1121152976 12.038 98 55 97 0 1202733328 71479328 10101024
03:35:12 58817 8192000000 10118556368 10611843072 1100084976 11.838 97 16 33 0 1203327584 99774640 3467968
03:37:20 20866 8192000000 10119167296 10574856192 1108234816 11.916 98 23 43 4 1215382080 102169232 4978032
03:39:24 54691 8192000000 10052845904 10550915072 1107861296 11.912 100 24 49 1 1196380704 83098208 5421200
03:41:23 49717 8192000000 10080784496 10533294080 1096898832 11.808 98 34 68 0 1173780064 69815424 7065808
03:44:52 63520 8192000000 10053194832 10491944960 1100976624 11.847 98 18 35 1 1136677360 32471104 3229632
03:47:00 12505 8192000000 10097421248 10525741056 1122500880 12.051 100 56 110 1 1190986384 56783744 11701760
03:48:15 51838 8192000000 10077882576 10532671488 1086525888 11.710 96 21 42 0 1165619264 74847872 4245504
03:50:13 40486 8192000000 10095330048 10537631744 1085228752 11.697 96 24 47 1 1169322464 79444784 4648928
03:51:54 57429 8192000000 10078800384 10501931008 1118334384 12.011 100 17 32 0 1160321104 38975840 3010880
03:53:51 21539 8192000000 10104904752 10521681920 1135136080 12.170 100 41 82 1 1188406400 45665696 7604624
03:55:14 21844 8192000000 10125806544 10546720768 1117596960 12.004 98 63 128 0 1198081984 68390208 12094816
03:56:37 73795 8192000000 10083991792 10510897152 1124188224 12.067 99 59 116 1 1188723856 52299600 12236032
03:58:57 5470 8192000000 10107090144 10530230272 1127227760 12.095 100 67 128 1 1210080176 69316336 13536080
04:00:04 72876 8192000000 10139923184 10597998592 1141005312 12.225 100 19 38 1 1247936960 102544304 4387344
04:02:21 60068 8192000000 10184994704 10641035264 1143375296 12.247 100 15 30 1 1285259760 138019456 3865008
04:04:33 72805 8192000000 10058351584 10503397376 1112878224 11.960 98 10 20 1 1169226192 54432736 1915232
04:06:59 23706 8192000000 10095203792 10534780928 1120310864 12.030 98 41 80 1 1182509584 53505136 8693584
04:08:26 74087 8192000000 10080471536 10519470080 1088711824 11.730 96 16 32 0 1148621344 56822240 3087280
04:10:44 11755 8192000000 10096254576 10526322688 1112034576 11.952 98 56 112 2 1176919440 52298576 12586288
04:11:56 48862 8192000000 10129655424 10607726592 1093911536 11.780 98 23 46 1 1222001152 122814560 5275056
04:13:46 30605 8192000000 10060202496 10495471616 1098890192 11.827 98 33 66 3 1121675328 15864624 6920512
04:15:17 29098 8192000000 10108408752 10553954304 1103424832 11.870 98 32 63 1 1187256576 77500576 6331168
04:16:49 15177 8192000000 10088085088 10515259392 1123213600 12.057 100 61 120 2 1178889408 43293888 12381920
04:18:11 62868 8192000000 10057850048 10488074240 1113638016 11.967 99 39 76 3 1153479728 32495408 7346304
04:20:14 52573 8192000000 10079998304 10540138496 1134170992 12.161 100 17 34 0 1205354720 67746224 3437504
04:22:59 38024 8192000000 10118834800 10599604224 1134202400 12.161 100 12 24 1 1229848544 93077104 2569040
04:24:40 82556 8192000000 10086090736 10587844608 1126420592 12.088 100 14 28 0 1176784400 47215696 3148112
04:27:10 27827 8192000000 10081075968 10520162304 1120802784 12.035 98 34 70 1 1165778608 37818960 7156864
04:28:41 17345 8192000000 10126692576 10564866048 1136250112 12.180 100 37 67 3 1211806896 68099792 7456992
04:30:03 78940 8192000000 10135343392 10569789440 1120867456 12.035 98 26 51 1 1222576480 95999856 5709168
04:32:23 42261 8192000000 10106953344 10570305536 1135817296 12.176 100 28 56 3 1208591264 67182336 5591632
04:34:15 38969 8192000000 10101828704 10564354048 1110404368 11.936 98 26 52 0 1197654912 81738960 5511584
04:35:55 25958 8192000000 10144276192 10595729408 1124141552 12.066 100 45 89 1 1226112160 93262096 8708512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 20-03-2022 04:38:40 to 20-03-2022 04:38:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 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 20-03-2022 04:38:57 to 20-03-2022 04: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          201615
MON$OLDEST_ACTIVE               205190
MON$OLDEST_SNAPSHOT             205190
MON$NEXT_TRANSACTION            205190
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                       2025696
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             752
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2742 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2742 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2742 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2742 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


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

20.03.22 04:47:05. 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-03-20 04:47:08 No severe PSQL-related problems occured
20.03.22 04:47:09. End of report.