Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.02.2023 10: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 15170972 kB
MemAvailable 30434952 kB
Buffers 0 kB
Cached 16557508 kB
SwapCached 3572 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.3
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 2023-02-04 10:37:26
DTS_END                         2023-02-04 10:37:26.5410
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37944
TRN_ID                          218699
ATT_ID                          665
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: 9128.78 30002 1095453
customer order: creation 160.45 475 19254
customer order: refuse 14.11 399 1693
order to supplier: creation 43.33 1317 5199
order to supplier: removal 3.33 168702 400
invoice (draft): creation 45.15 2911 5418
invoice (draft): removal 51.77 967 6212
invoice accept: apply 40.29 27690 4835
invoice accept: cancel 46.18 1161 5542
customer reserve: creation 3824.42 262 458930
customer reserve: removal 4611.12 96 553334
realization accept: apply 79.93 275 9592
realization accept: cancel 84.22 159 10106
payment from customer: creation 55.79 165 6695
payment from customer: removal 5.79 118 695
payment to supplier: creation 47.15 185 5658
payment to supplier: removal 3.26 119 391
service: total inventory turnovers 3.98 4184 477
service: total monetary turnovers 7.57 337 908
service: aggregate perf. data 0.91 16802 109
service: refresh index statistics 0.04 373719 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-02-2023 10:39:45 to 04-02-2023 10:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7559
TEST_TIME 2 5054
TEST_TIME 3 5543
TEST_TIME 4 5674
TEST_TIME 5 5311
TEST_TIME 6 5356
TEST_TIME 7 5264
TEST_TIME 8 5328
TEST_TIME 9 5613
TEST_TIME 10 5857
TEST_TIME 11 6038
TEST_TIME 12 6278
TEST_TIME 13 6362
TEST_TIME 14 6784
TEST_TIME 15 6790
TEST_TIME 16 7008
TEST_TIME 17 7257
TEST_TIME 18 7346
TEST_TIME 19 7429
TEST_TIME 20 7546
TEST_TIME 21 7723
TEST_TIME 22 7693
TEST_TIME 23 7869
TEST_TIME 24 7779
TEST_TIME 25 8077
TEST_TIME 26 8269
TEST_TIME 27 8593
TEST_TIME 28 9136
TEST_TIME 29 9516
TEST_TIME 30 10015
TEST_TIME 31 11081
TEST_TIME 32 11049
TEST_TIME 33 11138
TEST_TIME 34 10913
TEST_TIME 35 10713
TEST_TIME 36 10598
TEST_TIME 37 10538
TEST_TIME 38 10543
TEST_TIME 39 10583
TEST_TIME 40 10384
TEST_TIME 41 10269
TEST_TIME 42 10231
TEST_TIME 43 10135
TEST_TIME 44 10152
TEST_TIME 45 10125
TEST_TIME 46 10045
TEST_TIME 47 10003
TEST_TIME 48 9926
TEST_TIME 49 10034
TEST_TIME 50 10026
TEST_TIME 51 10052
TEST_TIME 52 10158
TEST_TIME 53 10077
TEST_TIME 54 10023
TEST_TIME 55 10027
TEST_TIME 56 9941
TEST_TIME 57 9990
TEST_TIME 58 9927
TEST_TIME 59 10037
TEST_TIME 60 10122
TEST_TIME 61 10071
TEST_TIME 62 10095
TEST_TIME 63 10093
TEST_TIME 64 10253
TEST_TIME 65 10119
TEST_TIME 66 10043
TEST_TIME 67 10010
TEST_TIME 68 9893
TEST_TIME 69 9844
TEST_TIME 70 9824
TEST_TIME 71 9817
TEST_TIME 72 9780
TEST_TIME 73 9709
TEST_TIME 74 9654
TEST_TIME 75 9558
TEST_TIME 76 9482
TEST_TIME 77 9553
TEST_TIME 78 9586
TEST_TIME 79 9552
TEST_TIME 80 9515
TEST_TIME 81 9568
TEST_TIME 82 9552
TEST_TIME 83 9567
TEST_TIME 84 9578
TEST_TIME 85 9674
TEST_TIME 86 9618
TEST_TIME 87 9737
TEST_TIME 88 9695
TEST_TIME 89 9729
TEST_TIME 90 9676
TEST_TIME 91 9602
TEST_TIME 92 9566
TEST_TIME 93 9573
TEST_TIME 94 9535
TEST_TIME 95 9496
TEST_TIME 96 9494
TEST_TIME 97 9434
TEST_TIME 98 9401
TEST_TIME 99 9345
TEST_TIME 100 9299
TEST_TIME 101 9265
TEST_TIME 102 9293
TEST_TIME 103 9294
TEST_TIME 104 9328
TEST_TIME 105 9371
TEST_TIME 106 9393
TEST_TIME 107 9371
TEST_TIME 108 9385
TEST_TIME 109 9428
TEST_TIME 110 9458
TEST_TIME 111 9471
TEST_TIME 112 9432
TEST_TIME 113 9404
TEST_TIME 114 9367
TEST_TIME 115 9326
TEST_TIME 116 9303
TEST_TIME 117 9281
TEST_TIME 118 9254
TEST_TIME 119 9221
TEST_TIME 120 9151

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-02-2023 10:39:49 to 04-02-2023 10:39:52.

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 1518101 1518101 0 0.00 0 24029 13 0 0
doc_list_biud 1518093 1518093 0 0.00 0 49854 40 0 0
sp_add_invoice_to_stock 4899 4835 64 1.30 47 260836 27690 0 64
sp_cancel_adding_invoice 5542 5542 0 0.00 6 73598 1162 0 0
sp_cancel_client_order 1693 1693 0 0.00 5 9221 399 0 0
sp_cancel_customer_reserve 553334 553334 0 0.00 1 49856 96 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 4 9240 118 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 4 4394 119 0 0
sp_cancel_supplier_invoice 6216 6212 4 0.06 3 15558 967 0 4
sp_cancel_supplier_order 400 400 0 0.00 12 1542745 168703 0 0
sp_cancel_write_off 10106 10106 0 0.00 2 12836 159 0 0
sp_client_order 19254 19254 0 0.00 5 35575 475 0 0
sp_customer_reserve 458930 458930 0 0.00 1 97084 262 0 0
sp_fill_shopping_cart 29856 29856 0 0.00 1 15351 77 0 0
sp_fill_shopping_cart_clo_res 458898 458898 0 0.00 0 30258 19 0 0
sp_get_clo_for_invoice 4789 4789 0 0.00 0 27831 272 0 0
sp_kill_cost_storno 16734 16734 0 0.00 0 24021 66 0 0
sp_kill_qstorno_ret_qs2qd 1123386 1123386 0 0.00 0 49839 42 0 0
sp_kill_qty_storno 591668 591668 0 0.00 0 49854 96 0 0
sp_lock_selected_doc 19895 19895 0 0.00 0 9179 8 0 0
sp_make_cost_storno 26734 26734 0 0.00 0 14634 77 0 0
sp_multiply_rows_for_pdistr 26734 26734 0 0.00 0 12388 36 0 0
sp_multiply_rows_for_qdistr 19254 19254 0 0.00 0 10243 173 0 0
sp_pay_from_customer 6695 6695 0 0.00 6 19949 165 0 0
sp_pay_to_supplier 5658 5658 0 0.00 7 12751 186 0 0
sp_payment_common 12353 12353 0 0.00 2 6840 40 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 0 9208 323 0 0
sp_qd_handle_on_invoice_upd_sts 10331 10331 0 0.00 0 18726 487 0 0
sp_qd_handle_on_reserve_upd_sts 9592 9592 0 0.00 0 10627 68 0 0
sp_reserve_write_off 9592 9592 0 0.00 8 20829 276 0 0
sp_supplier_invoice 5418 5418 0 0.00 9 95179 2911 0 0
sp_supplier_order 5199 5199 0 0.00 7 32557 1318 0 0
srv_aggregate_perf_data 109 109 0 0.00 2358 121828 16802 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 730 139140 37488 0 0
srv_find_qd_qs_mism 1048178 1048178 0 0.00 0 19786 11 0 0
srv_make_invnt_saldo 477 477 0 0.00 56 106762 4185 0 0
srv_make_money_saldo 908 908 0 0.00 1 10607 337 0 0
srv_recalc_idx_stat 5 5 0 0.00 174838 532768 373720 0 0
t$perf-abend:sp_add_invoice_to_stock 64 64 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4839 4839 0 0.00 1 14267 832 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 1 5409 413 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 1345 28 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 1280 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1881 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 7776 33 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 844 22 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 68034 6404 0 0
t$perf-norm:sp_cancel_write_off 928 928 0 0.00 0 1578 20 0 0
t$perf-norm:sp_client_order 19254 19254 0 0.00 0 13175 32 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 3277 18 0 0
t$perf-norm:sp_pay_from_customer 6695 6695 0 0.00 0 5663 20 0 0
t$perf-norm:sp_pay_to_supplier 5659 5659 0 0.00 0 2907 15 0 0
t$perf-norm:sp_reserve_write_off 9592 9592 0 0.00 0 19636 25 0 0
t$perf-norm:sp_supplier_invoice 5418 5418 0 0.00 0 6035 26 0 0
t$perf-norm:sp_supplier_order 5199 5199 0 0.00 0 5379 29 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 163 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 581 14 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 489 10 0 0
t$perf-norm:srv_make_money_saldo 908 908 0 0.00 0 203 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 190 60 0 0
v_add_invoice_to_stock 9700 9572 128 1.31 0 4633 29 0 128
v_all_customers 35252 35252 0 0.00 0 234 3 0 0
v_all_suppliers 30074 30074 0 0.00 0 263 4 0 0
v_all_wares 38500 38500 0 0.00 0 262 4 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 846 17 0 0
v_cancel_client_order 3386 3386 0 0.00 0 2101 11 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 1105 11 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 2398 12 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 4363 16 0 0
v_cancel_supplier_invoice 758 750 8 1.05 0 8539 39 0 8
v_cancel_supplier_order 800 800 0 0.00 0 1122 16 0 0
v_cancel_write_off 1856 1856 0 0.00 0 6905 29 0 0
v_max_id_clo_ord 5198 5198 0 0.00 0 199 5 0 0
v_max_id_clo_res 987 987 0 0.00 0 2674 19 0 0
v_max_id_ord_sup 5408 5408 0 0.00 0 5368 7 0 0
v_max_non_paid_invoice 1227 1227 0 0.00 0 2688 9 0 0
v_max_non_paid_realizn 3516 3516 0 0.00 0 1778 7 0 0
v_min_id_clo_ord 5198 5198 0 0.00 0 5389 9 0 0
v_min_id_clo_res 987 987 0 0.00 0 579 8 0 0
v_min_id_ord_sup 5408 5408 0 0.00 0 8229 12 0 0
v_min_non_paid_invoice 5658 5658 0 0.00 0 4496 12 0 0
v_min_non_paid_realizn 6695 6695 0 0.00 0 4767 13 0 0
v_our_firm 9606 9606 0 0.00 0 72 4 0 0
v_reserve_write_off 19184 19184 0 0.00 0 19017 13 0 0
x_lock_depdocs_on_canc_invoice 11379 11379 0 0.00 0 12851 130 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 9923 394 0 0
x_make_qstorno_1000_1200 5199 5199 0 0.00 3 32540 1158 0 0
x_make_qstorno_1200_2000 5418 5418 0 0.00 4 95131 2707 0 0
x_make_qstorno_2100_3300 458929 458929 0 0.00 0 97077 232 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 04-02-2023 10:39:53 to 04-02-2023 10:39:58.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:30 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:31 22351 8192000000 9723831968 10025762816 805504688 8.952 100 89 176 0 835291312 7691952 22094672
08:10:00 20097 8192000000 9769233808 10077630464 843841424 9.338 100 93 186 0 879803904 13582320 22380160
08:11:24 16376 8192000000 9793544288 10113892352 859923200 9.499 100 83 166 0 900788528 21498256 19367072
08:12:53 6545 8192000000 9863388912 10194808832 916509280 10.062 100 83 165 0 973225440 38697856 18018304
08:14:04 5764 8192000000 9916507472 10260185088 953349696 10.424 100 87 169 0 1036821856 65484208 17987952
08:15:13 1758 8192000000 9956612672 10311077888 991572672 10.797 100 89 169 0 1079421552 70100896 17747984
08:16:17 818 8192000000 10001289488 10362720256 1025228640 11.122 100 96 186 2 1122013984 78252416 18532928
08:17:20 1198 8192000000 10013624912 10384121856 1045012224 11.313 100 95 183 2 1135863680 72831168 18020288
08:18:23 1026 8192000000 10050092416 10427478016 1064851120 11.503 100 95 183 1 1170148096 87254816 18042160
08:19:25 930 8192000000 10040848672 10420883456 1078959840 11.638 100 95 180 1 1167268096 70006288 18301968
08:20:27 1221 8192000000 10061726736 10441879552 1078833280 11.636 100 97 189 2 1182429392 85236976 18359136
08:21:30 2093 8192000000 10041338144 10433335296 1085068656 11.696 100 77 147 2 1162936496 62587648 15280192
08:22:33 7255 8192000000 10047019648 10445451264 1087656208 11.720 100 66 131 2 1170513744 70128592 12728944
08:23:42 1141 8192000000 10040075040 10435190784 1092363344 11.765 100 86 168 0 1162545440 50651712 19530384
08:24:44 3309 8192000000 10055980704 10453602304 1095137760 11.791 100 68 131 2 1177978784 70490096 12350928
08:25:48 992 8192000000 10074326000 10461614080 1098728624 11.826 100 96 185 1 1190980240 73368000 18883616
08:26:51 2264 8192000000 10053940816 10450284544 1098387600 11.822 100 77 145 2 1174512288 61876576 14248112
08:27:54 4743 8192000000 10029019520 10427146240 1097396736 11.813 100 63 121 0 1157907568 48371584 12139248
08:29:01 3210 8192000000 10050011152 10443497472 1093457088 11.776 100 75 133 4 1176607600 70532128 12618384
08:30:05 1184 8192000000 10017128784 10404446208 1083120688 11.677 100 91 169 1 1138210528 36119904 18969936
08:31:07 1059 8192000000 9985924384 10359549952 1053277904 11.392 100 90 169 2 1106624256 35996608 17349744
08:32:11 1033 8192000000 10028980032 10406150144 1045057872 11.313 99 95 185 2 1155211856 91509088 18644896
08:33:13 1058 8192000000 10023379968 10400751616 1054106096 11.400 100 92 183 0 1144173440 72843296 17224048
08:34:15 1044 8192000000 10015068848 10391797760 1037880800 11.244 99 78 152 1 1134762016 81798784 15082432
08:35:17 9179 8192000000 9988328144 10383273984 1035258832 11.219 99 41 82 0 1101186704 57704384 8223488
08:36:28 5533 8192000000 9984822688 10365046784 1026271616 11.133 98 53 102 0 1097136400 61058464 9806320
08:37:37 2310 8192000000 9989710384 10360475648 1044925248 11.312 100 84 151 3 1102762832 43183744 14653840
08:39:02 17843 8192000000 10024371776 10407202816 1068401600 11.537 100 71 132 0 1146812400 65708400 12702400
08:40:26 1514 8192000000 10056438896 10443124736 1081598096 11.663 100 83 157 2 1168885744 70987632 16300016
08:41:31 2442 8192000000 10063104112 10455138304 1105895216 11.894 100 86 163 0 1182227280 58205040 18127024
08:42:37 14072 8192000000 10124308304 10519691264 1107710192 11.911 100 62 119 1 1240302560 119806320 12786048
08:44:01 2981 8192000000 10161574624 10560663552 1123296144 12.058 100 67 130 0 1278982784 140717552 14969088
08:45:17 2601 8192000000 10189573424 10596155392 1133305360 12.153 100 84 159 1 1304709872 154549936 16854576
08:46:25 959 8192000000 10247411344 10661093376 1142482320 12.239 100 77 150 1 1364227984 204637120 17108544
08:47:29 1086 8192000000 10277572352 10682470400 1155760400 12.364 100 97 190 3 1384044448 206796912 21487136
08:48:35 2572 8192000000 10305205728 10711396352 1168684640 12.485 100 93 185 0 1417827792 228299136 20844016
08:49:41 1120 8192000000 10343174288 10745536512 1186776256 12.653 100 93 179 0 1452927440 245917616 20233568
08:50:46 929 8192000000 10366836768 10768531456 1179945280 12.590 100 97 190 0 1475504512 274530256 21028976
08:51:50 944 8192000000 10379455072 10797305856 1186797824 12.654 100 96 188 1 1489576816 280383472 22395520
08:52:56 1149 8192000000 10392950736 10821627904 1194418304 12.724 100 94 182 0 1499239232 283780032 21040896
08:54:00 716 8192000000 10432924192 10853367808 1200420784 12.780 100 97 185 0 1540318320 317934608 21962928
08:55:03 1057 8192000000 10444573872 10866114560 1198789536 12.765 100 94 186 0 1553810608 332945136 22075936
08:56:06 896 8192000000 10492686896 10895249408 1180527280 12.595 99 96 189 0 1599837664 396946272 22364112
08:57:09 1101 8192000000 10522892672 10957885440 1191932752 12.701 100 72 142 1 1630608768 420756368 17919648
08:58:17 1562 8192000000 10585307296 11007275008 1213740992 12.904 100 86 170 0 1700745936 466208928 20796016
08:59:20 6797 8192000000 10569445504 11027791872 1211240128 12.881 100 54 107 0 1684204288 459227568 13736592
09:00:33 4846 8192000000 10635696544 11071610880 1205547440 12.828 100 66 130 0 1734082032 512332864 16201728
09:01:40 5866 8192000000 10645880080 11090063360 1190813280 12.691 100 54 106 1 1759885008 554666768 14404960
09:02:49 8431 8192000000 10616312592 11064590336 1176487632 12.557 100 56 108 1 1729103328 538019920 14595776
09:04:03 19007 8192000000 10606917104 11094278144 1161933488 12.421 98 40 80 0 1703824960 531100304 10791168
09:05:27 15690 8192000000 10462845136 10993209344 1154714832 12.354 99 35 70 0 1602104672 438204384 9185456
09:06:43 16809 8192000000 10369508736 10899161088 1151586848 12.324 100 27 54 0 1527596192 368786464 7222880
09:08:07 20433 8192000000 10111300048 10655006720 1109500448 11.928 98 16 32 0 1230206448 116856160 3849840
09:09:29 58968 8192000000 10052501120 10548899840 1112695600 11.958 99 20 41 0 1169995136 53059952 4239584
09:11:31 34237 8192000000 10083937904 10510737408 1121990800 12.046 100 46 88 1 1176724736 45940656 8793280
09:13:37 94449 8192000000 10093747872 10548613120 1089111600 11.734 97 46 90 1 1173420512 74714128 9594784
09:16:27 112872 8192000000 10053554272 10470526976 1054020960 11.399 93 21 42 0 1081040960 23535888 3484112
09:19:20 9190 8192000000 10117315888 10550493184 1128726784 12.109 100 41 78 1 1215469984 78114800 8628400
09:20:34 26262 8192000000 10181148816 10608664576 1135559600 12.174 100 55 103 0 1281407360 135476672 10371088
09:23:05 75187 8192000000 10182296624 10632581120 1142281952 12.237 100 25 47 1 1287716704 140058448 5376304
09:25:38 48926 8192000000 10176454192 10648236032 1133456560 12.154 99 20 40 0 1278586688 140242736 4887392
09:27:34 82705 8192000000 10121056176 10606903296 1133290384 12.152 99 11 22 0 1223542864 87961408 2291072
09:29:58 33420 8192000000 10067398496 10514223104 1136936448 12.187 100 46 91 0 1180500944 34547392 9017104
09:31:32 23498 8192000000 10107271520 10541785088 1136050752 12.178 100 50 98 0 1199550800 53907312 9592736
09:33:01 51922 8192000000 10166859120 10603704320 1132320656 12.143 98 27 54 2 1225982176 87014432 6647088
09:35:18 72141 8192000000 10202565440 10649919488 1147303040 12.284 100 11 22 0 1261853728 112056320 2494368
09:37:33 91281 8192000000 10120021824 10593284096 1116491312 11.994 98 16 32 0 1187582128 67919072 3171744
09:41:42 94923 8192000000 10084790528 10517454848 1131444496 12.135 100 20 40 0 1187504144 51091600 4968048
09:44:26 41349 8192000000 10071113840 10503921664 1107908784 11.913 97 34 63 0 1149408848 35295152 6204912
09:46:11 24349 8192000000 10139779984 10569379840 1143739552 12.251 100 60 115 0 1232055776 76507200 11809024
09:47:44 74887 8192000000 10078195696 10566422528 1118429104 12.012 98 12 24 0 1174912080 53839232 2643744
09:50:05 73267 8192000000 10099588976 10559336448 1146647312 12.278 100 15 30 0 1209812992 59903504 3262176
09:52:19 105634 8192000000 10087574432 10607271936 1126028240 12.084 99 10 20 0 1226797888 98684000 2085648
09:55:20 115981 8192000000 10109922736 10547527680 1096908608 11.808 96 14 28 0 1141377200 41120576 3348016
09:58:18 50264 8192000000 10104716448 10576801792 1104598800 11.881 96 28 56 0 1206811744 96747024 5465920
10:00:08 24515 8192000000 10114262576 10576183296 1117518880 12.004 98 23 46 1 1193786176 70857760 5409536
10:01:33 27424 8192000000 10143776912 10618212352 1137157216 12.189 100 39 78 0 1260839872 115260928 8421728
10:03:07 36035 8192000000 10146996160 10579722240 1133371072 12.153 99 48 96 2 1212071456 68247632 10452752
10:04:50 39311 8192000000 10088716048 10542645248 1095900656 11.799 97 28 56 0 1156749648 55219104 5629888
10:06:36 33696 8192000000 10091014112 10538971136 1112127680 11.953 98 41 80 1 1174592400 54483904 7980816
10:08:13 115056 8192000000 10079850848 10528006144 1094938944 11.790 97 38 76 0 1163715152 60643184 8133024
10:11:08 54295 8192000000 10073019664 10523758592 1128560112 12.108 100 19 38 0 1179567456 47188064 3819280
10:13:04 37656 8192000000 10065991264 10533928960 1114407888 11.974 99 13 26 0 1179753280 62749360 2596032
10:14:43 56969 8192000000 10076710624 10528071680 1126578960 12.089 99 30 58 1 1165811472 33370320 5862192
10:16:45 67288 8192000000 10123318464 10582016000 1137527616 12.192 99 14 28 0 1220715920 80205456 2982848
10:18:52 67356 8192000000 10129387104 10614067200 1131418192 12.135 99 17 33 0 1246849744 111732832 3698720
10:21:03 30576 8192000000 10099996560 10530439168 1115654480 11.986 98 34 68 0 1187044448 64094480 7295488
10:22:37 22143 8192000000 10082091024 10514108416 1119113664 12.019 99 33 66 0 1167914464 42721328 6079472
10:23:59 43360 8192000000 10112086384 10546696192 1107938992 11.913 97 37 73 1 1174958160 59475232 7543936
10:25:43 13881 8192000000 10132860736 10575609856 1136289456 12.181 100 55 110 0 1238452560 90618912 11544192
10:26:59 42686 8192000000 10125349008 10601394176 1143793776 12.251 100 20 40 0 1216050448 68470240 3786432
10:28:45 58069 8192000000 10091067904 10534383616 1113245744 11.963 97 29 58 0 1168291056 49350784 5694528
10:30:44 139138 8192000000 10084422304 10519748608 1137457216 12.192 99 33 62 0 1202626512 59107872 6061424
10:34:20 68470 8192000000 10063527840 10531303424 1112097248 11.952 97 9 18 0 1162099264 47707984 2294032
10:36:34 57015 8192000000 10031720432 10475327488 1074729456 11.597 94 13 26 0 1131229984 53765712 2734816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-02-2023 10:40:00 to 04-02-2023 10:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 64 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-02-2023 10:40:17 to 04-02-2023 10:40:19.

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          216697
MON$OLDEST_ACTIVE               218863
MON$OLDEST_SNAPSHOT             218863
MON$NEXT_TRANSACTION            218863
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                       2048736
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2895 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2895 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2895 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2895 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 ---
:91: 
:92: mscs-test-161.budzdorov.ru    Sat Feb  4 10:37:39 2023
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:94: 
--- end of diff output ---

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

04.02.23 10:40:20. 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
2023-02-04 10:40:23 No severe PSQL-related problems occured
04.02.23 10:40:24. End of report.