Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.03.2022 07: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 16377956 kB
MemAvailable 31633292 kB
Buffers 0 kB
Cached 15352108 kB
SwapCached 2180 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-30 07:37:12
DTS_END                         2022-03-30 07:37:12.8530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(108:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42978
TRN_ID                          218090
ATT_ID                          619
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: 8897.94 25264 1067753
customer order: creation 170.93 450 20511
customer order: refuse 14.43 409 1732
order to supplier: creation 44.98 1362 5397
order to supplier: removal 3.46 161478 415
invoice (draft): creation 45.27 3052 5432
invoice (draft): removal 49.98 1049 5997
invoice accept: apply 40.67 25864 4880
invoice accept: cancel 45.38 1327 5446
customer reserve: creation 3818.73 243 458248
customer reserve: removal 4372.16 100 524659
realization accept: apply 83.34 283 10001
realization accept: cancel 81.51 158 9781
payment from customer: creation 57.15 157 6858
payment from customer: removal 5.37 108 644
payment to supplier: creation 48.72 180 5846
payment to supplier: removal 3.66 133 439
service: total inventory turnovers 3.85 4165 462
service: total monetary turnovers 7.52 428 902
service: aggregate perf. data 0.83 18223 99
service: refresh index statistics 0.03 286106 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-03-2022 07:38:58 to 30-03-2022 07:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6174
TEST_TIME 2 6843
TEST_TIME 3 7883
TEST_TIME 4 7474
TEST_TIME 5 7370
TEST_TIME 6 7118
TEST_TIME 7 7229
TEST_TIME 8 6983
TEST_TIME 9 6909
TEST_TIME 10 6979
TEST_TIME 11 6873
TEST_TIME 12 7127
TEST_TIME 13 7270
TEST_TIME 14 7494
TEST_TIME 15 7567
TEST_TIME 16 7749
TEST_TIME 17 7685
TEST_TIME 18 7665
TEST_TIME 19 7830
TEST_TIME 20 8235
TEST_TIME 21 8429
TEST_TIME 22 8420
TEST_TIME 23 8573
TEST_TIME 24 8631
TEST_TIME 25 8590
TEST_TIME 26 8859
TEST_TIME 27 8978
TEST_TIME 28 9521
TEST_TIME 29 9527
TEST_TIME 30 9658
TEST_TIME 31 9623
TEST_TIME 32 9769
TEST_TIME 33 10221
TEST_TIME 34 10754
TEST_TIME 35 10610
TEST_TIME 36 10783
TEST_TIME 37 10869
TEST_TIME 38 10682
TEST_TIME 39 10568
TEST_TIME 40 10400
TEST_TIME 41 10254
TEST_TIME 42 10374
TEST_TIME 43 10307
TEST_TIME 44 10201
TEST_TIME 45 10138
TEST_TIME 46 10213
TEST_TIME 47 10445
TEST_TIME 48 10389
TEST_TIME 49 10359
TEST_TIME 50 10284
TEST_TIME 51 10172
TEST_TIME 52 10071
TEST_TIME 53 10007
TEST_TIME 54 9975
TEST_TIME 55 9930
TEST_TIME 56 9820
TEST_TIME 57 9836
TEST_TIME 58 9799
TEST_TIME 59 9728
TEST_TIME 60 9691
TEST_TIME 61 9631
TEST_TIME 62 9685
TEST_TIME 63 9713
TEST_TIME 64 9713
TEST_TIME 65 9623
TEST_TIME 66 9608
TEST_TIME 67 9541
TEST_TIME 68 9454
TEST_TIME 69 9465
TEST_TIME 70 9550
TEST_TIME 71 9531
TEST_TIME 72 9532
TEST_TIME 73 9563
TEST_TIME 74 9537
TEST_TIME 75 9535
TEST_TIME 76 9562
TEST_TIME 77 9591
TEST_TIME 78 9537
TEST_TIME 79 9545
TEST_TIME 80 9539
TEST_TIME 81 9546
TEST_TIME 82 9448
TEST_TIME 83 9491
TEST_TIME 84 9429
TEST_TIME 85 9392
TEST_TIME 86 9364
TEST_TIME 87 9312
TEST_TIME 88 9265
TEST_TIME 89 9253
TEST_TIME 90 9179
TEST_TIME 91 9178
TEST_TIME 92 9222
TEST_TIME 93 9204
TEST_TIME 94 9220
TEST_TIME 95 9199
TEST_TIME 96 9211
TEST_TIME 97 9245
TEST_TIME 98 9235
TEST_TIME 99 9251
TEST_TIME 100 9269
TEST_TIME 101 9346
TEST_TIME 102 9284
TEST_TIME 103 9321
TEST_TIME 104 9296
TEST_TIME 105 9291
TEST_TIME 106 9258
TEST_TIME 107 9208
TEST_TIME 108 9216
TEST_TIME 109 9206
TEST_TIME 110 9155
TEST_TIME 111 9146
TEST_TIME 112 9119
TEST_TIME 113 9068
TEST_TIME 114 9030
TEST_TIME 115 9003
TEST_TIME 116 8991
TEST_TIME 117 8985
TEST_TIME 118 9014
TEST_TIME 119 8985
TEST_TIME 120 8921

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-03-2022 07:39:02 to 30-03-2022 07:39:05.

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 1490707 1490707 0 0.00 0 17959 13 0 0
doc_list_biud 1490706 1490706 0 0.00 0 41038 42 0 0
sp_add_invoice_to_stock 5016 4880 136 2.71 42 198361 25865 0 136
sp_cancel_adding_invoice 5446 5446 0 0.00 7 79713 1327 0 0
sp_cancel_client_order 1732 1732 0 0.00 7 31055 410 0 0
sp_cancel_customer_reserve 524659 524659 0 0.00 1 41046 101 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 4 3017 108 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 4 6743 134 0 0
sp_cancel_supplier_invoice 6008 5997 11 0.18 2 39340 1049 0 11
sp_cancel_supplier_order 415 415 0 0.00 10 1264595 161479 0 0
sp_cancel_write_off 9781 9781 0 0.00 2 14918 158 0 0
sp_client_order 20511 20511 0 0.00 5 31713 450 0 0
sp_customer_reserve 458248 458248 0 0.00 1 50392 244 0 0
sp_fill_shopping_cart 31333 31333 0 0.00 1 29195 83 0 0
sp_fill_shopping_cart_clo_res 458218 458218 0 0.00 0 16202 18 0 0
sp_get_clo_for_invoice 4835 4835 0 0.00 0 39155 295 0 0
sp_kill_cost_storno 16310 16310 0 0.00 0 8157 72 0 0
sp_kill_qstorno_ret_qs2qd 1065511 1065511 0 0.00 0 41031 44 0 0
sp_kill_qty_storno 562862 562862 0 0.00 0 41036 102 0 0
sp_lock_selected_doc 20587 20587 0 0.00 0 11824 7 0 0
sp_make_cost_storno 27536 27536 0 0.00 0 14614 76 0 0
sp_multiply_rows_for_pdistr 27536 27536 0 0.00 0 9672 35 0 0
sp_multiply_rows_for_qdistr 20511 20511 0 0.00 0 14165 165 0 0
sp_pay_from_customer 6858 6858 0 0.00 5 9753 158 0 0
sp_pay_to_supplier 5846 5846 0 0.00 7 11080 181 0 0
sp_payment_common 12704 12704 0 0.00 2 8057 41 0 0
sp_qd_handle_on_cancel_clo 1732 1732 0 0.00 0 31036 334 0 0
sp_qd_handle_on_invoice_upd_sts 10277 10277 0 0.00 0 37102 531 0 0
sp_qd_handle_on_reserve_upd_sts 10001 10001 0 0.00 0 25568 78 0 0
sp_reserve_write_off 10001 10001 0 0.00 9 25615 284 0 0
sp_supplier_invoice 5432 5432 0 0.00 13 87844 3053 0 0
sp_supplier_order 5397 5397 0 0.00 8 63785 1362 0 0
srv_aggregate_perf_data 99 99 0 0.00 2256 223026 18223 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 662 137280 33887 0 0
srv_find_qd_qs_mism 1019909 1019909 0 0.00 0 17958 10 0 0
srv_make_invnt_saldo 462 462 0 0.00 65 86879 4165 0 0
srv_make_money_saldo 902 902 0 0.00 1 14916 429 0 0
srv_recalc_idx_stat 4 4 0 0.00 81464 506643 286106 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4884 4884 0 0.00 1 10672 869 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 1 6404 457 0 0
t$perf-norm:sp_cancel_client_order 1732 1732 0 0.00 0 6937 34 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 4900 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 943 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 877 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 1577 34 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 98705 6559 0 0
t$perf-norm:sp_cancel_write_off 1017 1017 0 0.00 0 1133 20 0 0
t$perf-norm:sp_client_order 20511 20511 0 0.00 0 8266 35 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 5302 19 0 0
t$perf-norm:sp_pay_from_customer 6858 6858 0 0.00 0 5866 20 0 0
t$perf-norm:sp_pay_to_supplier 5846 5846 0 0.00 0 5247 18 0 0
t$perf-norm:sp_reserve_write_off 10001 10001 0 0.00 0 7121 22 0 0
t$perf-norm:sp_supplier_invoice 5432 5432 0 0.00 0 5073 30 0 0
t$perf-norm:sp_supplier_order 5397 5397 0 0.00 0 6246 30 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 1346 34 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 330 10 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 296 10 0 0
t$perf-norm:srv_make_money_saldo 902 902 0 0.00 0 738 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 126 39 0 0
v_add_invoice_to_stock 9934 9662 272 2.73 0 11432 25 0 272
v_all_customers 37516 37516 0 0.00 0 81 4 0 0
v_all_suppliers 30510 30510 0 0.00 0 86 4 0 0
v_all_wares 41022 41022 0 0.00 0 80 4 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 7102 23 0 0
v_cancel_client_order 3464 3464 0 0.00 0 2733 11 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 2775 14 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 1284 11 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 1616 15 0 0
v_cancel_supplier_invoice 878 856 22 2.50 0 10584 39 0 22
v_cancel_supplier_order 830 830 0 0.00 0 685 11 0 0
v_cancel_write_off 2034 2034 0 0.00 0 9272 28 0 0
v_max_id_clo_ord 5396 5396 0 0.00 0 171 5 0 0
v_max_id_clo_res 987 987 0 0.00 0 2956 16 0 0
v_max_id_ord_sup 5426 5426 0 0.00 0 2992 7 0 0
v_max_non_paid_invoice 1413 1413 0 0.00 0 99 7 0 0
v_max_non_paid_realizn 3499 3499 0 0.00 0 520 7 0 0
v_min_id_clo_ord 5396 5396 0 0.00 0 3946 8 0 0
v_min_id_clo_res 987 987 0 0.00 0 618 8 0 0
v_min_id_ord_sup 5426 5426 0 0.00 0 5645 10 0 0
v_min_non_paid_invoice 5846 5846 0 0.00 0 8048 15 0 0
v_min_non_paid_realizn 6858 6858 0 0.00 0 3938 14 0 0
v_our_firm 10224 10224 0 0.00 0 45 4 0 0
v_reserve_write_off 20002 20002 0 0.00 0 9581 12 0 0
x_lock_depdocs_on_canc_invoice 11015 11015 0 0.00 0 26723 128 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 6765 354 0 0
x_make_qstorno_1000_1200 5397 5397 0 0.00 3 63769 1198 0 0
x_make_qstorno_1200_2000 5432 5432 0 0.00 6 86324 2825 0 0
x_make_qstorno_2100_3300 458248 458248 0 0.00 0 44646 214 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-03-2022 07:39:06 to 30-03-2022 07:39:10.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:16 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:17 25204 8192000000 9721462752 10021396480 792070432 8.816 100 88 176 0 820465984 7555296 20840256
05:09:58 25980 8192000000 9771738784 10080411648 843927376 9.339 100 93 186 0 878691408 12668832 22095200
05:11:33 17876 8192000000 9810330800 10133852160 878442416 9.684 100 82 164 0 916031920 19080560 18508944
05:12:57 10102 8192000000 9887575920 10222620672 931531920 10.210 100 83 162 0 997045968 48003824 17510224
05:14:11 4643 8192000000 9949634320 10293268480 964620016 10.534 100 91 181 0 1064297904 80683808 18994080
05:15:18 2781 8192000000 9965913872 10321223680 1004642592 10.924 100 90 177 2 1079867040 56793776 18430672
05:16:23 1315 8192000000 10004424064 10367705088 1030008640 11.169 100 96 190 1 1123307584 73570480 19728464
05:17:26 1189 8192000000 10026845760 10398109696 1046471968 11.327 100 94 188 0 1154373568 88335136 19566464
05:18:29 934 8192000000 10053759984 10434453504 1064122176 11.496 100 95 180 1 1175891440 92998928 18770336
05:19:31 1949 8192000000 10026929056 10404892672 1071162176 11.563 100 85 166 2 1149361472 61543952 16655344
05:20:34 1190 8192000000 10072981968 10456399872 1079068064 11.639 100 97 179 3 1194593376 95253136 20272176
05:21:36 1216 8192000000 10060965792 10447073280 1079871520 11.646 100 96 188 1 1180654672 82065648 18717504
05:22:37 3670 8192000000 10052294752 10440683520 1086690144 11.711 100 74 143 1 1165767696 64953088 14124464
05:23:45 1013 8192000000 10036016224 10424406016 1093680976 11.778 100 85 161 2 1160701408 49468192 17552240
05:24:47 1056 8192000000 10055414720 10444926976 1096489584 11.804 100 91 179 3 1175301792 58114608 20697600
05:25:49 1362 8192000000 10064591504 10454441984 1095280336 11.793 100 80 156 2 1190294192 79396576 15617280
05:26:51 1196 8192000000 10063128704 10450440192 1098158864 11.820 100 94 181 4 1181794416 65775008 17860544
05:27:53 845 8192000000 10048021056 10442252288 1096388512 11.803 100 88 172 0 1172911104 58855440 17667152
05:28:55 1297 8192000000 10049305168 10440568832 1099660624 11.834 100 93 166 2 1171923904 55260976 17002304
05:29:57 942 8192000000 10044849488 10434695168 1085561936 11.700 100 86 165 3 1165888560 63513056 16813568
05:30:59 2089 8192000000 10023323776 10414690304 1074516720 11.595 100 76 148 0 1153285648 63159152 15609776
05:32:02 1257 8192000000 10011956944 10394763264 1056822544 11.426 100 81 149 0 1132250272 60550048 14877680
05:33:03 1826 8192000000 10009693760 10388119552 1051960960 11.379 100 78 150 2 1136033344 69711744 14360640
05:34:07 834 8192000000 10015109232 10391371776 1052413232 11.384 100 92 179 0 1135892704 65089392 18390080
05:35:09 2337 8192000000 9961573040 10339069952 1031024480 11.178 100 77 138 1 1085821264 40441312 14355472
05:36:12 1159 8192000000 9963584672 10339123200 1030959456 11.178 99 75 139 1 1088307920 42267440 15081024
05:37:15 6035 8192000000 9968751936 10345664512 1026841008 11.138 99 61 118 0 1093563568 54647504 12075056
05:38:30 28167 8192000000 10020205440 10397327360 1066712112 11.521 100 81 150 1 1137385248 55513984 15159152
05:40:01 1262 8192000000 10058984912 10440810496 1076295648 11.612 99 93 177 0 1185951984 89549408 20106928
05:41:05 4250 8192000000 10090491536 10481340416 1100479920 11.842 100 91 166 4 1203562496 86576384 16506192
05:42:13 1054 8192000000 10093042864 10479124480 1116480368 11.994 100 89 162 2 1209628816 78304864 14843584
05:43:20 2270 8192000000 10147823728 10537799680 1130810736 12.129 100 90 166 1 1268793248 120820608 17161904
05:44:26 8270 8192000000 10148622368 10545786880 1136467856 12.182 100 72 137 0 1266865136 116642928 13754352
05:45:39 14460 8192000000 10184112320 10591694848 1139953392 12.215 100 67 130 0 1289914704 135648048 14313264
05:47:00 4221 8192000000 10173962240 10586206208 1142790656 12.242 99 76 132 3 1279177696 122690352 13696688
05:48:07 859 8192000000 10243213760 10644336640 1168992304 12.487 100 87 171 1 1358314640 170044000 19278336
05:49:12 1166 8192000000 10281550592 10683715584 1176014576 12.553 100 85 169 0 1394226320 199270496 18941248
05:50:16 918 8192000000 10314143232 10727735296 1180871104 12.598 100 96 185 0 1426233088 222063600 23298384
05:51:20 1429 8192000000 10329026976 10739855360 1181182752 12.601 100 85 170 0 1440318992 239913136 19223104
05:52:23 813 8192000000 10355431776 10768252928 1176268896 12.555 99 91 180 0 1465071872 267851104 20951872
05:53:28 653 8192000000 10387951904 10804019200 1201456304 12.790 100 97 187 1 1497854144 273692928 22704912
05:54:31 980 8192000000 10425023344 10830827520 1200615712 12.782 100 99 196 1 1532021264 308841472 22564080
05:55:35 967 8192000000 10452676544 10870030336 1200288368 12.779 100 97 192 0 1560352992 337603200 22461424
05:56:41 1787 8192000000 10434214336 10900832256 1196013488 12.739 100 87 171 0 1542317920 325271200 21033232
05:57:47 1043 8192000000 10502511504 10930950144 1215732672 12.922 100 94 184 1 1606147776 368111536 22303568
05:58:52 6836 8192000000 10450967568 10880561152 1188800880 12.672 100 65 128 0 1562289904 357141664 16347360
06:00:04 3402 8192000000 10465599392 10912415744 1198376336 12.761 100 50 100 0 1577919056 366627232 12915488
06:01:10 10360 8192000000 10486563808 10927673344 1193681248 12.718 100 49 96 0 1582183600 375961888 12540464
06:02:26 12954 8192000000 10511993296 10953420800 1189848512 12.682 100 39 78 0 1608904960 408527392 10529056
06:03:53 10987 8192000000 10470742224 10915508224 1178885504 12.580 100 51 97 0 1583372736 392700960 11786272
06:05:06 15374 8192000000 10426382384 10877992960 1159398512 12.398 99 38 76 1 1531605584 362554320 9652752
06:06:38 24875 8192000000 10398288608 10867535872 1158766656 12.392 99 36 72 1 1504729024 337162864 8799504
06:08:04 24504 8192000000 10423764672 10899795968 1172455536 12.520 100 31 61 0 1553123136 373111296 7556304
06:09:30 15132 8192000000 10374282480 10830491648 1153551312 12.343 99 45 87 0 1459610896 294893584 11166000
06:10:49 25063 8192000000 10298484848 10768412672 1139226256 12.208 99 31 62 1 1426456032 279889104 7340672
06:12:16 19175 8192000000 10174558256 10654289920 1127540320 12.098 99 47 93 2 1266464080 128270912 10652848
06:13:36 25586 8192000000 10105144688 10584817664 1148107696 12.292 100 10 19 1 1229807296 80034912 1664688
06:15:03 63784 8192000000 10092050480 10522533888 1131211456 12.133 99 37 73 0 1185026544 46531024 7284064
06:17:29 63078 8192000000 10142134512 10576195584 1130641040 12.127 99 29 56 1 1209330048 72320400 6368608
06:19:34 111631 8192000000 10126602560 10556485632 1089154496 11.735 96 16 30 1 1137346848 45087760 3104592
06:22:35 70210 8192000000 10142249488 10637991936 1135137280 12.170 100 9 18 0 1259587072 122054672 2395120
06:24:46 137279 8192000000 10038238704 10484944896 1045907680 11.321 92 14 28 2 1081119936 32368320 2843936
06:28:10 73930 8192000000 10107452960 10537934848 1103892512 11.875 98 33 65 1 1169772656 59162656 6717488
06:30:26 65932 8192000000 10063756768 10504867840 1126273600 12.086 100 54 101 1 1177953040 39412608 12266832
06:32:37 63541 8192000000 10138217712 10563452928 1128289136 12.105 99 53 106 0 1215120896 75305536 11526224
06:34:42 48449 8192000000 10098717984 10532532224 1106122768 11.896 98 17 34 1 1180993584 70780144 4090672
06:36:33 37093 8192000000 10118977392 10553692160 1119424720 12.022 99 40 77 0 1201546160 73828848 8292592
06:38:14 48089 8192000000 10160248080 10619179008 1127273552 12.096 99 46 92 0 1263262928 126238544 9750832
06:40:09 45274 8192000000 10095401712 10568548352 1128288336 12.105 99 13 26 1 1203390448 72304880 2797232
06:41:55 32827 8192000000 10109699392 10587557888 1145707344 12.269 100 11 22 1 1209660496 61670208 2282944
06:44:05 48652 8192000000 10102396640 10568396800 1134841728 12.167 99 18 36 1 1190466656 52041984 3582944
06:46:02 70045 8192000000 10130091440 10621108224 1137127264 12.188 99 11 22 2 1220666880 81392560 2147056
06:48:13 19539 8192000000 10091597936 10530803712 1155666432 12.363 100 41 82 0 1190865136 27195024 8003680
06:50:16 81426 8192000000 10090449296 10547077120 1093091728 11.772 95 10 20 0 1138156656 43066816 1998112
06:52:39 12701 8192000000 10108914112 10538188800 1140716960 12.222 100 49 98 1 1205635120 54632560 10285600
06:54:38 17776 8192000000 10050194048 10485661696 1120284256 12.030 99 28 56 1 1149268496 23110832 5873408
06:55:56 8454 8192000000 10103712720 10532990976 1128489808 12.107 99 53 104 2 1188272944 48984592 10798544
06:57:05 5999 8192000000 10097674560 10516983808 1128382784 12.106 100 47 92 2 1193027968 55297376 9347808
06:58:28 16949 8192000000 10148802864 10600603648 1142167456 12.236 100 38 76 2 1266863648 117180624 7515568
06:59:46 103263 8192000000 10064627232 10546216960 1119175456 12.019 98 21 42 0 1167400704 43083552 5141696
07:02:37 62003 8192000000 10095029840 10573959168 1120380432 12.031 98 22 44 1 1183159200 58437904 4340864
07:04:40 106327 8192000000 10085568240 10559836160 1045912240 11.321 92 16 32 0 1128311952 78241216 4158496
07:07:38 61052 8192000000 10139769456 10580959232 1118239808 12.010 99 21 42 1 1240379888 117790224 4349856
07:09:43 79710 8192000000 10077284816 10517348352 1106234624 11.897 98 20 39 1 1162121808 52140176 3747008
07:12:04 27542 8192000000 10088857328 10515714048 1096487104 11.804 97 35 70 3 1158529648 54582224 7460320
07:13:32 47650 8192000000 10056222608 10516815872 1086525360 11.710 97 37 75 1 1173094672 78471232 8098080
07:15:23 13933 8192000000 10037364896 10446286848 1064514384 11.500 96 64 118 1 1108462752 32773808 11174560
07:16:40 42860 8192000000 10092214560 10567774208 1116741216 11.996 100 37 74 0 1207891584 83473360 7677008
07:18:33 67753 8192000000 10081878016 10521608192 1114138272 11.972 99 24 48 0 1159229888 40461456 4630160
07:20:53 50769 8192000000 10093855872 10523770880 1133040096 12.150 100 40 80 0 1195636096 55086384 7509616
07:22:44 117919 8192000000 10111072912 10556227584 1100555328 11.843 97 36 72 3 1173132368 65520048 7056992
07:25:52 22191 8192000000 10065473008 10518953984 1101812560 11.855 99 31 60 0 1148610720 41020848 5777312
07:27:14 55866 8192000000 10062287888 10517565440 1112741264 11.958 99 13 26 1 1146736224 31297968 2696992
07:29:12 81562 8192000000 10067372048 10521821184 1107512880 11.909 98 8 16 0 1135644352 26358528 1772944
07:31:52 25413 8192000000 10126141584 10561884160 1131660480 12.137 99 26 49 3 1214117312 76744368 5712464
07:33:22 24293 8192000000 10161931504 10595954688 1149215776 12.302 100 29 58 0 1263987360 107682128 7089456
07:34:47 25468 8192000000 10195370224 10635747328 1145425168 12.267 99 34 68 1 1278854336 125071952 8357216
07:36:25 6564 8192000000 10211449760 10644783104 1164140480 12.442 100 43 86 1 1305434864 131266512 10027872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-03-2022 07:39:12 to 30-03-2022 07:39:28.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-03-2022 07:39:29 to 30-03-2022 07:39:31.

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          208311
MON$OLDEST_ACTIVE               218588
MON$OLDEST_SNAPSHOT             218588
MON$NEXT_TRANSACTION            218588
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                       2077024
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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

30.03.22 07:39:32. 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-30 07:39:35 No severe PSQL-related problems occured
30.03.22 07:39:36. End of report.