Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.01.2022 04:39
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 16331280 kB
MemAvailable 31301004 kB
Buffers 20 kB
Cached 15338424 kB
SwapCached 1176 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-01-11 04:37:12
DTS_END                         2022-01-11 04:37:12.9760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39696
TRN_ID                          218080
ATT_ID                          658
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: 8495.33 24438 1019440
customer order: creation 166.47 456 19976
customer order: refuse 13.73 391 1648
order to supplier: creation 44.90 1444 5388
order to supplier: removal 2.98 172190 358
invoice (draft): creation 44.39 3439 5327
invoice (draft): removal 45.54 1011 5465
invoice accept: apply 40.10 28166 4812
invoice accept: cancel 41.46 1258 4975
customer reserve: creation 3807.12 263 456854
customer reserve: removal 4015.72 100 481886
realization accept: apply 78.93 269 9472
realization accept: cancel 68.00 180 8160
payment from customer: creation 57.47 181 6896
payment from customer: removal 5.63 114 676
payment to supplier: creation 47.23 197 5667
payment to supplier: removal 3.24 117 389
service: total inventory turnovers 4.12 3551 494
service: total monetary turnovers 7.43 372 892
service: aggregate perf. data 0.84 16839 101
service: refresh index statistics 0.03 258230 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-01-2022 04:39:21 to 11-01-2022 04:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6828
TEST_TIME 2 5278
TEST_TIME 3 5025
TEST_TIME 4 5046
TEST_TIME 5 5025
TEST_TIME 6 5830
TEST_TIME 7 6119
TEST_TIME 8 6528
TEST_TIME 9 7076
TEST_TIME 10 7445
TEST_TIME 11 7699
TEST_TIME 12 7621
TEST_TIME 13 7722
TEST_TIME 14 7726
TEST_TIME 15 7710
TEST_TIME 16 7694
TEST_TIME 17 7671
TEST_TIME 18 7688
TEST_TIME 19 7711
TEST_TIME 20 7721
TEST_TIME 21 7708
TEST_TIME 22 7659
TEST_TIME 23 8126
TEST_TIME 24 8110
TEST_TIME 25 8485
TEST_TIME 26 8586
TEST_TIME 27 8898
TEST_TIME 28 8887
TEST_TIME 29 9442
TEST_TIME 30 10244
TEST_TIME 31 10344
TEST_TIME 32 10651
TEST_TIME 33 11354
TEST_TIME 34 11106
TEST_TIME 35 10938
TEST_TIME 36 10694
TEST_TIME 37 10724
TEST_TIME 38 10601
TEST_TIME 39 10630
TEST_TIME 40 10439
TEST_TIME 41 10252
TEST_TIME 42 10196
TEST_TIME 43 10072
TEST_TIME 44 10051
TEST_TIME 45 9908
TEST_TIME 46 9736
TEST_TIME 47 9791
TEST_TIME 48 9657
TEST_TIME 49 9645
TEST_TIME 50 9506
TEST_TIME 51 9397
TEST_TIME 52 9284
TEST_TIME 53 9290
TEST_TIME 54 9171
TEST_TIME 55 9120
TEST_TIME 56 9104
TEST_TIME 57 9033
TEST_TIME 58 8914
TEST_TIME 59 8902
TEST_TIME 60 8800
TEST_TIME 61 8808
TEST_TIME 62 8746
TEST_TIME 63 8775
TEST_TIME 64 8788
TEST_TIME 65 8887
TEST_TIME 66 8863
TEST_TIME 67 8937
TEST_TIME 68 8937
TEST_TIME 69 8910
TEST_TIME 70 8922
TEST_TIME 71 8986
TEST_TIME 72 9007
TEST_TIME 73 8966
TEST_TIME 74 8968
TEST_TIME 75 8896
TEST_TIME 76 8828
TEST_TIME 77 8882
TEST_TIME 78 8803
TEST_TIME 79 8743
TEST_TIME 80 8728
TEST_TIME 81 8657
TEST_TIME 82 8674
TEST_TIME 83 8628
TEST_TIME 84 8633
TEST_TIME 85 8628
TEST_TIME 86 8606
TEST_TIME 87 8611
TEST_TIME 88 8664
TEST_TIME 89 8632
TEST_TIME 90 8672
TEST_TIME 91 8627
TEST_TIME 92 8664
TEST_TIME 93 8685
TEST_TIME 94 8716
TEST_TIME 95 8706
TEST_TIME 96 8662
TEST_TIME 97 8596
TEST_TIME 98 8599
TEST_TIME 99 8563
TEST_TIME 100 8557
TEST_TIME 101 8569
TEST_TIME 102 8556
TEST_TIME 103 8555
TEST_TIME 104 8576
TEST_TIME 105 8589
TEST_TIME 106 8596
TEST_TIME 107 8630
TEST_TIME 108 8606
TEST_TIME 109 8629
TEST_TIME 110 8620
TEST_TIME 111 8622
TEST_TIME 112 8603
TEST_TIME 113 8672
TEST_TIME 114 8662
TEST_TIME 115 8654
TEST_TIME 116 8593
TEST_TIME 117 8564
TEST_TIME 118 8625
TEST_TIME 119 8583
TEST_TIME 120 8519

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-01-2022 04:39:25 to 11-01-2022 04:39:28.

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 1438255 1438255 0 0.00 0 16205 14 0 0
doc_list_biud 1438239 1438239 0 0.00 0 46371 39 0 0
sp_add_invoice_to_stock 4889 4812 77 1.57 62 221049 28167 0 77
sp_cancel_adding_invoice 4975 4975 0 0.00 8 72084 1259 0 0
sp_cancel_client_order 1648 1648 0 0.00 6 13153 391 0 0
sp_cancel_customer_reserve 481886 481886 0 0.00 1 46380 100 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 4 2274 115 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 4 2186 118 0 0
sp_cancel_supplier_invoice 5470 5465 5 0.09 2 25588 1012 0 5
sp_cancel_supplier_order 358 358 0 0.00 9 1480803 172191 0 0
sp_cancel_write_off 8160 8160 0 0.00 2 9474 180 0 0
sp_client_order 19976 19976 0 0.00 6 30347 456 0 0
sp_customer_reserve 456854 456854 0 0.00 1 62177 264 0 0
sp_fill_shopping_cart 30677 30677 0 0.00 2 22583 87 0 0
sp_fill_shopping_cart_clo_res 456828 456828 0 0.00 0 16625 20 0 0
sp_get_clo_for_invoice 4781 4781 0 0.00 0 30259 300 0 0
sp_kill_cost_storno 14200 14200 0 0.00 0 7586 78 0 0
sp_kill_qstorno_ret_qs2qd 977755 977755 0 0.00 0 46368 42 0 0
sp_kill_qty_storno 516741 516741 0 0.00 0 46369 99 0 0
sp_lock_selected_doc 19851 19851 0 0.00 0 6455 6 0 0
sp_make_cost_storno 26815 26815 0 0.00 0 16202 84 0 0
sp_multiply_rows_for_pdistr 26811 26811 0 0.00 0 9573 36 0 0
sp_multiply_rows_for_qdistr 19976 19976 0 0.00 0 14064 160 0 0
sp_pay_from_customer 6896 6896 0 0.00 6 16907 181 0 0
sp_pay_to_supplier 5667 5667 0 0.00 6 16706 198 0 0
sp_payment_common 12560 12560 0 0.00 2 7713 43 0 0
sp_qd_handle_on_cancel_clo 1648 1648 0 0.00 0 13140 319 0 0
sp_qd_handle_on_invoice_upd_sts 9756 9756 0 0.00 0 21899 522 0 0
sp_qd_handle_on_reserve_upd_sts 9468 9468 0 0.00 0 10556 59 0 0
sp_reserve_write_off 9472 9472 0 0.00 8 11991 270 0 0
sp_supplier_invoice 5327 5327 0 0.00 10 98825 3439 0 0
sp_supplier_order 5388 5388 0 0.00 6 53443 1444 0 0
srv_aggregate_perf_data 101 101 0 0.00 2704 121803 16840 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 587 138521 39024 0 0
srv_find_qd_qs_mism 970694 970694 0 0.00 0 14853 11 0 0
srv_make_invnt_saldo 494 494 0 0.00 52 53668 3551 0 0
srv_make_money_saldo 892 892 0 0.00 1 17360 372 0 0
srv_recalc_idx_stat 4 4 0 0.00 146290 399344 258231 0 0
t$perf-abend:sp_add_invoice_to_stock 77 77 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4816 4816 0 0.00 1 14523 991 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 2 5864 504 0 0
t$perf-norm:sp_cancel_client_order 1648 1648 0 0.00 0 1996 28 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 1151 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 2084 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 151 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 1029 30 0 0
t$perf-norm:sp_cancel_supplier_order 358 358 0 0.00 0 109132 7096 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 2198 24 0 0
t$perf-norm:sp_client_order 19976 19976 0 0.00 0 6826 36 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 1012 15 0 0
t$perf-norm:sp_pay_from_customer 6896 6896 0 0.00 0 4633 19 0 0
t$perf-norm:sp_pay_to_supplier 5667 5667 0 0.00 0 3865 22 0 0
t$perf-norm:sp_reserve_write_off 9472 9472 0 0.00 0 3661 21 0 0
t$perf-norm:sp_supplier_invoice 5327 5327 0 0.00 0 3526 30 0 0
t$perf-norm:sp_supplier_order 5388 5388 0 0.00 0 7079 36 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 1564 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 2856 44 0 0
t$perf-norm:srv_make_invnt_saldo 494 494 0 0.00 0 1250 17 0 0
t$perf-norm:srv_make_money_saldo 892 892 0 0.00 0 3089 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 828 263 0 0
v_add_invoice_to_stock 9708 9554 154 1.58 0 9352 25 0 154
v_all_customers 36712 36712 0 0.00 0 220 4 0 0
v_all_suppliers 29822 29822 0 0.00 0 68 4 0 0
v_all_wares 39941 39941 0 0.00 0 64 4 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1683 15 0 0
v_cancel_client_order 3296 3296 0 0.00 0 2624 10 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 2258 15 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 1 4344 15 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 2121 17 0 0
v_cancel_supplier_invoice 846 836 10 1.18 0 1910 23 0 10
v_cancel_supplier_order 716 716 0 0.00 0 2708 18 0 0
v_cancel_write_off 2018 2018 0 0.00 0 2552 27 0 0
v_max_id_clo_ord 5380 5380 0 0.00 0 446 5 0 0
v_max_id_clo_res 961 961 0 0.00 0 5055 15 0 0
v_max_id_ord_sup 5322 5322 0 0.00 0 7836 10 0 0
v_max_non_paid_invoice 1454 1454 0 0.00 0 1388 9 0 0
v_max_non_paid_realizn 3534 3534 0 0.00 0 7248 9 0 0
v_min_id_clo_ord 5379 5379 0 0.00 0 5930 12 0 0
v_min_id_clo_res 961 961 0 0.00 0 8117 31 0 0
v_min_id_ord_sup 5321 5321 0 0.00 0 8555 10 0 0
v_min_non_paid_invoice 5665 5665 0 0.00 0 5592 12 0 0
v_min_non_paid_realizn 6895 6895 0 0.00 0 7691 16 0 0
v_our_firm 9950 9950 0 0.00 0 78 4 0 0
v_reserve_write_off 18936 18936 0 0.00 0 6140 12 0 0
x_lock_depdocs_on_canc_invoice 10022 10022 0 0.00 0 18632 145 0 0
x_lock_depdocs_on_canc_sup_ord 358 358 0 0.00 0 3467 396 0 0
x_make_qstorno_1000_1200 5388 5388 0 0.00 2 53413 1257 0 0
x_make_qstorno_1200_2000 5327 5327 0 0.00 4 98645 3202 0 0
x_make_qstorno_2100_3300 456854 456854 0 0.00 0 56221 231 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-01-2022 04:39:29 to 11-01-2022 04:39:33.

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:07:16 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:18 25691 8192000000 9711387632 10005643264 783758480 8.731 100 83 165 1 810848768 7098512 19991776
02:09:51 17371 8192000000 9746915424 10051850240 825010560 9.149 100 87 174 0 857759296 12093120 20655616
02:11:13 15029 8192000000 9793411584 10108973056 858177984 9.482 100 81 162 0 894671584 17755488 18738112
02:12:38 11566 8192000000 9851726224 10188746752 910143248 9.999 100 72 144 1 964524368 38810512 15570608
02:13:52 5504 8192000000 9916134384 10257625088 951441808 10.405 100 86 172 0 1027937984 58396800 18099376
02:15:00 2231 8192000000 9960099728 10310299648 981308816 10.697 100 89 171 1 1077772304 78147216 18316272
02:16:04 1330 8192000000 10006499712 10363838464 1018027520 11.053 100 95 185 1 1123218176 87172944 18017712
02:17:07 2419 8192000000 10002773248 10370252800 1033380512 11.201 100 89 170 1 1118576512 68215280 16980720
02:18:10 1441 8192000000 10044052784 10416062464 1058212624 11.439 100 95 175 1 1158173520 82057824 17903072
02:19:13 1281 8192000000 10036421232 10408574976 1067779776 11.531 100 96 182 1 1159412048 73554752 18077520
02:20:14 1451 8192000000 10036351712 10416607232 1076314992 11.612 100 86 161 1 1158052352 64012064 17725296
02:21:16 9019 8192000000 10019020080 10403729408 1075783760 11.607 100 71 133 1 1147989648 59132880 13073008
02:22:26 1074 8192000000 10045066096 10429964288 1080832592 11.655 100 93 180 0 1165030592 65023536 19174464
02:23:28 5661 8192000000 10027271440 10419937280 1086354240 11.708 100 71 137 2 1155787616 56152752 13280624
02:24:35 1183 8192000000 10061318880 10453565440 1090478320 11.747 100 78 153 0 1182228720 76279632 15470768
02:25:37 1699 8192000000 10034819184 10424819712 1093164704 11.773 100 65 123 0 1164407792 58218432 13024656
02:26:39 1092 8192000000 10055567872 10445750272 1096579984 11.805 100 93 180 0 1175941584 60638272 18723328
02:27:41 1312 8192000000 10072444000 10462523392 1096680880 11.806 100 96 185 2 1196977392 81330192 18966320
02:28:43 1288 8192000000 10058839584 10447048704 1092599872 11.767 100 97 195 1 1185228576 72889728 19738976
02:29:45 3845 8192000000 10021196960 10407706624 1066648464 11.520 99 70 134 2 1135211296 54824544 13738288
02:30:50 1212 8192000000 10009397424 10397364224 1051678736 11.377 99 84 163 1 1131200656 62563952 16957968
02:31:52 1071 8192000000 10015981472 10392170496 1052968960 11.389 100 94 176 2 1135349440 63746304 18634176
02:32:54 1221 8192000000 10003521904 10373283840 1042252576 11.286 100 86 165 1 1128038672 69059152 16726944
02:33:56 1030 8192000000 9995564336 10366365696 1039273552 11.258 100 85 170 0 1114603328 58271728 17058048
02:34:58 1033 8192000000 10007543232 10378625024 1021727296 11.089 100 89 170 2 1125492944 84951632 18814016
02:36:00 1187 8192000000 9997404208 10368008192 1034879168 11.215 100 89 166 1 1119898064 66937136 18081760
02:37:02 1024 8192000000 10004939408 10379223040 1048398976 11.345 100 92 179 1 1123146352 55908192 18839184
02:38:14 1408 8192000000 9988768208 10364919808 1050672928 11.367 100 77 141 3 1110241408 44575008 14993472
02:39:21 14410 8192000000 10040511264 10416091136 1069155472 11.544 100 80 155 2 1153776784 68779184 15842128
02:40:47 7699 8192000000 10051815952 10441285632 1081750608 11.664 100 61 119 0 1180109248 85552608 12806032
02:42:01 10663 8192000000 10071997904 10457358336 1089843728 11.741 100 67 126 0 1194905360 92066576 12995056
02:43:18 2151 8192000000 10118242208 10506813440 1113122752 11.962 100 72 140 2 1238828704 111450000 14255952
02:44:23 2089 8192000000 10153694688 10541187072 1124505952 12.070 100 89 167 2 1275882560 133536048 17840560
02:45:29 2299 8192000000 10184713280 10578264064 1135316192 12.171 100 80 149 1 1301616976 150171824 16128960
02:46:33 1808 8192000000 10188170928 10596970496 1147130992 12.283 100 85 163 1 1307961744 142432656 18398096
02:47:39 811 8192000000 10242597872 10644353024 1164384240 12.444 100 92 181 1 1356472144 172740192 19347712
02:48:42 2156 8192000000 10227624336 10630209536 1174206912 12.536 100 76 149 0 1337215520 147398368 15610240
02:49:49 2495 8192000000 10254240864 10667409408 1186445776 12.650 100 84 165 0 1365320880 160579344 18295760
02:50:55 1485 8192000000 10309801456 10710269952 1194366272 12.724 100 89 175 0 1422380768 209183168 18831328
02:52:00 1249 8192000000 10354957488 10757476352 1203580560 12.810 100 94 184 2 1461903984 237894528 20428896
02:53:04 832 8192000000 10367475136 10769911808 1202373440 12.798 100 98 188 2 1478046816 252289056 23384320
02:54:10 1193 8192000000 10423028480 10832732160 1207820976 12.849 100 88 176 0 1531256000 302722320 20712704
02:55:13 777 8192000000 10472007200 10873532416 1215492992 12.920 100 98 191 1 1579309088 340361024 23455072
02:56:17 964 8192000000 10500091440 10910457856 1203944400 12.813 100 97 194 2 1609571344 382918032 22708912
02:57:22 579 8192000000 10498812096 10923909120 1200950496 12.785 99 96 186 2 1605292272 382403504 21938272
02:58:26 999 8192000000 10556624832 10962325504 1206152672 12.833 100 94 188 2 1664053040 434421984 23478384
02:59:29 1227 8192000000 10594010768 11034775552 1205022880 12.823 100 97 187 3 1697837968 469413392 23401696
03:00:32 936 8192000000 10569926576 11032686592 1191386224 12.696 99 97 187 3 1675653632 461073920 23193488
03:01:35 1460 8192000000 10618360112 11050192896 1198811632 12.765 100 95 189 1 1721676672 500351888 22513152
03:02:39 3009 8192000000 10595445472 11066814464 1198862320 12.766 100 65 125 1 1701896240 486346272 16687648
03:03:49 6363 8192000000 10602950032 11048226816 1195879904 12.738 100 66 130 1 1719404064 506404096 17120064
03:04:57 12804 8192000000 10598916416 11042930688 1188771200 12.672 100 42 84 0 1689799504 490063680 10964624
03:06:54 9506 8192000000 10458213696 10983161856 1175183472 12.545 100 43 81 1 1601859536 415087600 11588464
03:08:21 40619 8192000000 10259389104 10752851968 1125586848 12.080 98 19 38 0 1408718880 278395328 4736704
03:10:04 44055 8192000000 10086665616 10633474048 1098289680 11.821 97 14 28 1 1296804096 195158416 3356000
03:11:48 12528 8192000000 10115665536 10524446720 1113139472 11.962 99 70 130 1 1206935984 80195824 13600688
03:13:29 84024 8192000000 10110447184 10596982784 1123769520 12.063 100 31 62 0 1235467104 104991568 6706016
03:15:59 48780 8192000000 10068410032 10549366784 1099329264 11.831 97 26 50 2 1155966544 51271856 5365424
03:17:51 74395 8192000000 10098506400 10542583808 1106038128 11.895 97 15 30 0 1173107952 63878096 3191728
03:20:06 67064 8192000000 10078492544 10548486144 1118607584 12.014 99 13 27 0 1193467024 72358992 2500448
03:22:14 87951 8192000000 10120609056 10564931584 1127718736 12.100 99 16 32 0 1197836624 66728416 3389472
03:24:50 25054 8192000000 10056891024 10499129344 1106247648 11.897 97 11 20 1 1154124176 45963232 1913296
03:26:18 78285 8192000000 10052343504 10520223744 1100693808 11.844 98 20 40 0 1152539632 47822432 4023392
03:29:25 85921 8192000000 10082947008 10578866176 1114531552 11.975 98 17 34 0 1196531744 78525296 3474896
03:32:16 98442 8192000000 10039705632 10542940160 1104581792 11.881 98 26 52 1 1174413328 64456432 5375104
03:34:58 95367 8192000000 10063884000 10529185792 1117157872 12.000 99 23 46 2 1158401968 36499120 4744976
03:37:45 73500 8192000000 10074591728 10537033728 1123523312 12.060 99 10 20 0 1155466352 29857472 2085568
03:40:08 51260 8192000000 10111892384 10579603456 1125910528 12.083 99 32 65 3 1215901248 83113424 6877296
03:41:59 88029 8192000000 10120656752 10584002560 1140105376 12.217 100 15 30 1 1233878944 90852816 2920752
03:44:28 48381 8192000000 10078096544 10515320832 1115148848 11.981 98 20 41 1 1165241984 45984512 4108624
03:46:25 33918 8192000000 10125924384 10571849728 1137416000 12.191 100 44 88 1 1212406656 66650304 8340352
03:48:04 30570 8192000000 10109930048 10578354176 1128617840 12.108 99 31 62 2 1186609568 51795648 6196080
03:49:56 33101 8192000000 10113744704 10554523648 1125695616 12.081 98 30 60 2 1182406832 50508048 6203168
03:51:32 79579 8192000000 10082526352 10512539648 1149563008 12.305 100 36 69 2 1189785440 33392592 6829840
03:53:56 26643 8192000000 10117826912 10572976128 1137972272 12.196 99 29 58 1 1210951568 67044784 5934512
03:55:57 67718 8192000000 10077384368 10544951296 1125394576 12.078 98 13 26 0 1173492096 45585424 2512096
03:58:09 55469 8192000000 10075889392 10521403392 1127124832 12.094 98 30 60 0 1164292368 31159376 6008160
04:00:11 120824 8192000000 10082321072 10594578432 1121107936 12.037 98 7 14 0 1167888720 45371360 1409424
04:03:12 19729 8192000000 10069549440 10508734464 1135003808 12.169 99 37 73 1 1171310256 28068704 8237744
04:04:35 53056 8192000000 10102798304 10557972480 1121456560 12.041 98 40 80 0 1210829920 81475328 7898032
04:06:29 53331 8192000000 10130433040 10564902912 1116034304 11.990 97 27 53 0 1201704208 80142688 5527216
04:08:24 30870 8192000000 10117294992 10569846784 1141772928 12.232 99 37 73 1 1217699696 68476912 7449856
04:09:58 75019 8192000000 10083749504 10560868352 1102377248 11.860 96 25 50 0 1153336512 46001472 4957792
04:12:17 31810 8192000000 10091432544 10540064768 1116955360 11.998 99 46 91 1 1178801584 52724416 9121808
04:13:51 106236 8192000000 10119715792 10555387904 1130880800 12.130 100 58 116 0 1222681552 79699056 12101696
04:16:44 61443 8192000000 10066223328 10568167424 1109569520 11.928 99 5 10 0 1181693072 71214720 908832
04:18:46 66009 8192000000 10052787936 10495946752 1075035200 11.600 95 14 28 1 1093050192 15193568 2821424
04:20:52 20900 8192000000 10086372128 10506416128 1100215184 11.840 98 37 73 1 1151517504 43265184 8037136
04:22:15 109221 8192000000 10076301776 10523824128 1072630032 11.577 95 21 42 1 1143295552 66474848 4190672
04:25:08 6036 8192000000 10067914144 10479718400 1127759376 12.100 100 63 114 6 1157587360 17145008 12682976
04:26:16 138519 8192000000 10065786400 10491383808 1084094176 11.686 96 23 44 0 1141522272 52017888 5410208
04:29:37 34076 8192000000 10131943824 10588819456 1132052832 12.141 100 39 78 1 1225996928 86397552 7546544
04:31:20 33375 8192000000 10093276112 10531491840 1113795632 11.968 99 33 65 0 1159866464 39331040 6739792
04:32:59 90290 8192000000 10130961744 10572738560 1099371680 11.832 98 16 32 1 1221935536 119012480 3551376
04:35:30 80577 8192000000 10052863632 10522316800 1097067744 11.810 98 12 24 0 1136927440 37385536 2474160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-01-2022 04:39:35 to 11-01-2022 04:39:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 77 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-01-2022 04:39:52 to 11-01-2022 04:39:54.

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          216142
MON$OLDEST_ACTIVE               218191
MON$OLDEST_SNAPSHOT             218191
MON$NEXT_TRANSACTION            218191
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                       2050656
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

11.01.22 04:39:55. 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-01-11 04:39:57 No severe PSQL-related problems occured
11.01.22 04:39:58. End of report.