Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.12.2022 10: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 14392456 kB
MemAvailable 30091564 kB
Buffers 0 kB
Cached 17321584 kB
SwapCached 3228 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 2022-12-16 10:37:22
DTS_END                         2022-12-16 10:37:22.8840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36612
TRN_ID                          211261
ATT_ID                          636
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: 8645.91 30076 1037509
customer order: creation 158.32 505 18999
customer order: refuse 13.53 478 1624
order to supplier: creation 42.39 1493 5087
order to supplier: removal 3.42 184720 410
invoice (draft): creation 41.99 3579 5039
invoice (draft): removal 50.21 1070 6025
invoice accept: apply 38.03 29118 4563
invoice accept: cancel 45.18 1397 5422
customer reserve: creation 3565.27 272 427832
customer reserve: removal 4410.34 111 529241
realization accept: apply 76.25 327 9150
realization accept: cancel 79.86 202 9583
payment from customer: creation 54.98 201 6598
payment from customer: removal 5.11 197 613
payment to supplier: creation 45.87 233 5504
payment to supplier: removal 3.39 140 407
service: total inventory turnovers 3.44 5237 413
service: total monetary turnovers 7.54 424 905
service: aggregate perf. data 0.75 20347 90
service: refresh index statistics 0.03 351469 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-12-2022 10:38:54 to 16-12-2022 10:38:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7651
TEST_TIME 2 6284
TEST_TIME 3 5667
TEST_TIME 4 5725
TEST_TIME 5 6072
TEST_TIME 6 6300
TEST_TIME 7 6061
TEST_TIME 8 5803
TEST_TIME 9 5985
TEST_TIME 10 6069
TEST_TIME 11 6300
TEST_TIME 12 6470
TEST_TIME 13 6743
TEST_TIME 14 6943
TEST_TIME 15 6903
TEST_TIME 16 6762
TEST_TIME 17 6887
TEST_TIME 18 6922
TEST_TIME 19 6909
TEST_TIME 20 7166
TEST_TIME 21 7384
TEST_TIME 22 7514
TEST_TIME 23 7576
TEST_TIME 24 7718
TEST_TIME 25 7711
TEST_TIME 26 7775
TEST_TIME 27 7892
TEST_TIME 28 8144
TEST_TIME 29 8205
TEST_TIME 30 8446
TEST_TIME 31 8858
TEST_TIME 32 9017
TEST_TIME 33 9365
TEST_TIME 34 9624
TEST_TIME 35 10011
TEST_TIME 36 10013
TEST_TIME 37 10413
TEST_TIME 38 10521
TEST_TIME 39 10559
TEST_TIME 40 10383
TEST_TIME 41 10273
TEST_TIME 42 10253
TEST_TIME 43 10252
TEST_TIME 44 10212
TEST_TIME 45 10164
TEST_TIME 46 10317
TEST_TIME 47 10249
TEST_TIME 48 10305
TEST_TIME 49 10230
TEST_TIME 50 10102
TEST_TIME 51 10098
TEST_TIME 52 9948
TEST_TIME 53 9803
TEST_TIME 54 9724
TEST_TIME 55 9757
TEST_TIME 56 9670
TEST_TIME 57 9581
TEST_TIME 58 9451
TEST_TIME 59 9445
TEST_TIME 60 9362
TEST_TIME 61 9328
TEST_TIME 62 9328
TEST_TIME 63 9269
TEST_TIME 64 9169
TEST_TIME 65 9135
TEST_TIME 66 9018
TEST_TIME 67 9024
TEST_TIME 68 9162
TEST_TIME 69 9161
TEST_TIME 70 9127
TEST_TIME 71 9102
TEST_TIME 72 9196
TEST_TIME 73 9220
TEST_TIME 74 9169
TEST_TIME 75 9154
TEST_TIME 76 9226
TEST_TIME 77 9191
TEST_TIME 78 9204
TEST_TIME 79 9172
TEST_TIME 80 9238
TEST_TIME 81 9253
TEST_TIME 82 9192
TEST_TIME 83 9110
TEST_TIME 84 9089
TEST_TIME 85 9050
TEST_TIME 86 9007
TEST_TIME 87 8996
TEST_TIME 88 9009
TEST_TIME 89 8972
TEST_TIME 90 8958
TEST_TIME 91 8922
TEST_TIME 92 8870
TEST_TIME 93 8803
TEST_TIME 94 8838
TEST_TIME 95 8831
TEST_TIME 96 8795
TEST_TIME 97 8818
TEST_TIME 98 8830
TEST_TIME 99 8819
TEST_TIME 100 8845
TEST_TIME 101 8870
TEST_TIME 102 8885
TEST_TIME 103 8940
TEST_TIME 104 8943
TEST_TIME 105 9005
TEST_TIME 106 8985
TEST_TIME 107 8956
TEST_TIME 108 9025
TEST_TIME 109 9040
TEST_TIME 110 8980
TEST_TIME 111 8970
TEST_TIME 112 8935
TEST_TIME 113 8916
TEST_TIME 114 8875
TEST_TIME 115 8843
TEST_TIME 116 8800
TEST_TIME 117 8773
TEST_TIME 118 8739
TEST_TIME 119 8714
TEST_TIME 120 8675

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-12-2022 10:38:57 to 16-12-2022 10:39:01.

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 1433094 1433094 0 0.00 0 35802 15 0 0
doc_list_biud 1433094 1433094 0 0.00 0 44759 47 0 0
sp_add_invoice_to_stock 4681 4563 118 2.52 44 200062 29118 0 118
sp_cancel_adding_invoice 5422 5422 0 0.00 8 92307 1398 0 0
sp_cancel_client_order 1624 1624 0 0.00 6 10925 479 0 0
sp_cancel_customer_reserve 529241 529241 0 0.00 1 43923 112 0 0
sp_cancel_pay_from_customer 613 613 0 0.00 4 13493 197 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 4799 141 0 0
sp_cancel_supplier_invoice 6036 6025 11 0.18 3 29154 1071 0 11
sp_cancel_supplier_order 410 410 0 0.00 13 1744022 184720 0 0
sp_cancel_write_off 9583 9583 0 0.00 2 16146 202 0 0
sp_client_order 18999 18999 0 0.00 5 38632 505 0 0
sp_customer_reserve 427832 427832 0 0.00 1 113393 272 0 0
sp_fill_shopping_cart 29116 29116 0 0.00 1 38480 95 0 0
sp_fill_shopping_cart_clo_res 427790 427790 0 0.00 0 15774 20 0 0
sp_get_clo_for_invoice 4510 4510 0 0.00 0 21219 368 0 0
sp_kill_cost_storno 16025 16025 0 0.00 0 35795 97 0 0
sp_kill_qstorno_ret_qs2qd 1074500 1074500 0 0.00 0 37135 48 0 0
sp_kill_qty_storno 565964 565964 0 0.00 0 44755 111 0 0
sp_lock_selected_doc 19035 19035 0 0.00 0 8761 9 0 0
sp_make_cost_storno 25761 25761 0 0.00 0 23461 98 0 0
sp_multiply_rows_for_pdistr 25761 25761 0 0.00 0 15841 45 0 0
sp_multiply_rows_for_qdistr 18999 18999 0 0.00 0 21556 187 0 0
sp_pay_from_customer 6598 6598 0 0.00 5 24017 202 0 0
sp_pay_to_supplier 5504 5504 0 0.00 7 15858 233 0 0
sp_payment_common 12102 12102 0 0.00 2 7557 51 0 0
sp_qd_handle_on_cancel_clo 1624 1624 0 0.00 1 10915 396 0 0
sp_qd_handle_on_invoice_upd_sts 9931 9931 0 0.00 0 44751 617 0 0
sp_qd_handle_on_reserve_upd_sts 9150 9150 0 0.00 0 13101 79 0 0
sp_reserve_write_off 9150 9150 0 0.00 8 16679 328 0 0
sp_supplier_invoice 5039 5039 0 0.00 11 101076 3579 0 0
sp_supplier_order 5087 5087 0 0.00 7 45237 1494 0 0
srv_aggregate_perf_data 90 90 0 0.00 2661 148660 20347 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 782 214895 35073 0 0
srv_find_qd_qs_mism 992975 992975 0 0.00 0 11517 11 0 0
srv_make_invnt_saldo 413 413 0 0.00 37 63656 5238 0 0
srv_make_money_saldo 905 905 0 0.00 1 8516 424 0 0
srv_recalc_idx_stat 4 4 0 0.00 90848 536056 351469 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 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 4566 4566 0 0.00 1 14380 1117 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 1 7528 522 0 0
t$perf-norm:sp_cancel_client_order 1624 1624 0 0.00 0 2010 33 0 0
t$perf-norm:sp_cancel_customer_reserve 627 627 0 0.00 0 3903 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 613 613 0 0.00 0 2971 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 2521 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 6550 69 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 104215 7929 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 7082 35 0 0
t$perf-norm:sp_client_order 18999 18999 0 0.00 0 8057 47 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 4166 26 0 0
t$perf-norm:sp_pay_from_customer 6598 6598 0 0.00 0 7046 24 0 0
t$perf-norm:sp_pay_to_supplier 5504 5504 0 0.00 0 6850 26 0 0
t$perf-norm:sp_reserve_write_off 9150 9150 0 0.00 0 13489 31 0 0
t$perf-norm:sp_supplier_invoice 5039 5039 0 0.00 0 10744 43 0 0
t$perf-norm:sp_supplier_order 5087 5087 0 0.00 0 43374 48 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 9325 118 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 10101 151 0 0
t$perf-norm:srv_make_invnt_saldo 413 413 0 0.00 0 3231 42 0 0
t$perf-norm:srv_make_money_saldo 905 905 0 0.00 0 2727 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 190 50 0 0
v_add_invoice_to_stock 9254 9018 236 2.55 0 12358 32 0 236
v_all_customers 35214 35214 0 0.00 0 113 4 0 0
v_all_suppliers 28580 28580 0 0.00 0 154 5 0 0
v_all_wares 37992 37992 0 0.00 0 111 4 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 724 15 0 0
v_cancel_client_order 3248 3248 0 0.00 0 1470 11 0 0
v_cancel_customer_prepayment 1226 1226 0 0.00 0 6621 21 0 0
v_cancel_customer_reserve 1254 1254 0 0.00 1 628 12 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 4481 20 0 0
v_cancel_supplier_invoice 758 736 22 2.90 0 5310 43 0 22
v_cancel_supplier_order 820 820 0 0.00 0 813 13 0 0
v_cancel_write_off 1880 1880 0 0.00 0 4231 29 0 0
v_max_id_clo_ord 5086 5086 0 0.00 0 896 6 0 0
v_max_id_clo_res 942 942 0 0.00 0 3061 15 0 0
v_max_id_ord_sup 5034 5034 0 0.00 0 7789 8 0 0
v_max_non_paid_invoice 1334 1334 0 0.00 0 120 9 0 0
v_max_non_paid_realizn 3285 3285 0 0.00 0 4256 11 0 0
v_min_id_clo_ord 5086 5086 0 0.00 0 2128 10 0 0
v_min_id_clo_res 942 942 0 0.00 0 1931 9 0 0
v_min_id_ord_sup 5034 5034 0 0.00 0 3001 9 0 0
v_min_non_paid_invoice 5504 5504 0 0.00 0 6581 16 0 0
v_min_non_paid_realizn 6598 6598 0 0.00 0 4467 17 0 0
v_our_firm 9404 9404 0 0.00 0 89 5 0 0
v_reserve_write_off 18300 18300 0 0.00 0 12188 13 0 0
x_lock_depdocs_on_canc_invoice 11079 11079 0 0.00 0 17092 145 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 12622 458 0 0
x_make_qstorno_1000_1200 5087 5087 0 0.00 3 44893 1297 0 0
x_make_qstorno_1200_2000 5039 5039 0 0.00 5 100815 3311 0 0
x_make_qstorno_2100_3300 427832 427832 0 0.00 0 113375 238 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-12-2022 10:39:02 to 16-12-2022 10:39:06.

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:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:27 28885 8192000000 9737096640 10042834944 820875072 9.107 100 90 180 0 850229776 7547520 21807184
08:10:09 20691 8192000000 9780702864 10094481408 858385024 9.484 100 93 184 1 893404048 13469520 21549504
08:11:36 19336 8192000000 9822163488 10143899648 886195456 9.761 100 87 170 0 926615504 20449392 19970656
08:13:01 6875 8192000000 9862906112 10197860352 917320288 10.070 100 76 152 0 979074704 45226288 16528128
08:14:10 4972 8192000000 9904375856 10247700480 949093856 10.382 100 83 166 0 1012708160 45918560 17695744
08:15:18 2112 8192000000 9948034288 10299744256 990353216 10.785 100 87 166 1 1061413424 54193280 16866928
08:16:22 2475 8192000000 10002846944 10366328832 1020478224 11.077 100 92 176 1 1119051488 81081088 17492176
08:17:26 2194 8192000000 10011664672 10382319616 1041699984 11.281 100 82 150 2 1138487200 82450480 14336736
08:18:30 1022 8192000000 10009626768 10383925248 1062997616 11.485 100 95 182 3 1130864144 49349776 18516752
08:19:33 781 8192000000 10031478544 10408316928 1069886656 11.551 100 97 180 1 1157310928 68830912 18593360
08:20:35 920 8192000000 10037612208 10421633024 1071880480 11.570 100 96 184 1 1161847120 70277328 19689312
08:21:37 1360 8192000000 10049734384 10437668864 1083103552 11.677 100 89 176 2 1177268592 76845408 17319632
08:22:39 987 8192000000 10060558960 10451062784 1081703680 11.664 100 95 180 0 1183547392 83207504 18636208
08:23:43 1095 8192000000 10067226736 10455539712 1080770976 11.655 100 95 176 0 1187503200 88798256 17933968
08:24:46 1219 8192000000 10073497568 10461569024 1085131648 11.696 100 94 184 1 1194876656 90534576 19210432
08:25:48 5175 8192000000 10053387200 10451206144 1092926976 11.770 100 83 163 2 1182849776 73106432 16816368
08:26:54 1240 8192000000 10066032240 10457030656 1088997648 11.733 100 88 167 1 1185033872 77775456 18260768
08:27:57 7819 8192000000 10034678480 10429710336 1092127232 11.763 100 61 110 1 1149736864 46540256 11069376
08:29:05 1065 8192000000 10023575136 10414047232 1075096560 11.601 100 84 163 1 1147759616 56068960 16594096
08:30:07 1128 8192000000 10029078944 10415276032 1083917056 11.685 100 97 180 1 1150928096 48664432 18346608
08:31:09 7632 8192000000 10013890736 10396418048 1058685184 11.444 100 79 142 2 1130950080 56652192 15612704
08:32:17 907 8192000000 10011002688 10392879104 1052795632 11.387 100 82 159 1 1136265344 66101120 17368592
08:33:19 914 8192000000 9996357408 10377703424 1034677200 11.213 99 87 169 1 1118466736 65365280 18424256
08:34:21 1232 8192000000 10012888128 10386112512 1036521552 11.231 100 94 176 2 1137078416 81984208 18572656
08:35:24 1002 8192000000 9991164576 10366484480 1036340192 11.229 100 97 181 1 1114663808 59903312 18420304
08:36:26 1014 8192000000 10006759056 10382327808 1046061168 11.323 100 95 176 2 1127462240 61331904 20069168
08:37:28 2669 8192000000 9989699360 10360070144 1017432368 11.047 97 69 133 2 1099460144 69423216 12604560
08:38:40 8338 8192000000 10001554432 10392158208 1053273392 11.392 100 60 110 1 1126707344 62611488 10822464
08:39:51 2486 8192000000 10011526944 10395275264 1060348224 11.460 100 78 151 1 1137748560 60546032 16854304
08:41:01 16311 8192000000 10037027680 10434904064 1084686944 11.692 100 58 116 0 1166402912 69961472 11754496
08:42:24 1816 8192000000 10073816688 10460868608 1096082512 11.800 100 78 145 3 1191219168 80384640 14752016
08:43:28 4221 8192000000 10100941936 10490404864 1111228384 11.944 100 72 138 0 1212107728 86764288 14115056
08:44:38 7190 8192000000 10136630464 10529923072 1118801408 12.016 100 86 161 1 1254470272 118446912 17221952
08:45:56 2320 8192000000 10188355744 10583830528 1129092944 12.113 100 87 173 3 1303288112 154989264 19205904
08:47:02 2094 8192000000 10211542176 10612678656 1137790912 12.195 100 82 159 0 1326276768 170884880 17600976
08:48:07 966 8192000000 10250225344 10652319744 1160124112 12.404 100 95 185 3 1359082544 178663072 20295360
08:49:12 1783 8192000000 10280364448 10687651840 1169968992 12.497 100 92 177 2 1390295584 201056592 19270000
08:50:17 1365 8192000000 10287448128 10694144000 1168365984 12.482 100 91 178 0 1391486416 203285120 19835312
08:51:23 1192 8192000000 10299570240 10714312704 1181745872 12.606 100 87 163 0 1407758336 207064320 18948144
08:52:28 2361 8192000000 10334246432 10737815552 1180420976 12.594 100 95 182 0 1436715376 236022112 20272288
08:53:36 1475 8192000000 10376771488 10785054720 1197255392 12.751 100 95 183 4 1491117232 272330816 21531024
08:54:41 2685 8192000000 10425449776 10840838144 1199673312 12.773 100 93 179 1 1532692432 311319232 21699888
08:55:47 770 8192000000 10472525824 10879152128 1206140704 12.833 100 98 197 0 1577253696 347610384 23502608
08:56:52 1401 8192000000 10473818976 10911141888 1205255760 12.825 100 96 186 0 1582475312 352938976 24280576
08:58:00 1653 8192000000 10494152064 10941853696 1210244064 12.871 100 87 171 0 1598923280 368486192 20193024
08:59:06 1020 8192000000 10487864528 10915434496 1202900400 12.803 100 93 177 3 1595364352 370743136 21720816
09:00:10 942 8192000000 10524380304 10941374464 1197272576 12.751 100 96 187 1 1628268960 407534480 23461904
09:01:15 841 8192000000 10526125904 10956279808 1200507744 12.781 100 99 190 2 1628304160 404841184 22955232
09:02:19 1252 8192000000 10564473552 10981302272 1187035136 12.656 100 81 154 2 1671989648 465780400 19174112
09:03:22 4192 8192000000 10575176928 11019276288 1185038976 12.637 100 75 149 0 1681916080 477375200 19501904
09:04:30 1535 8192000000 10586491104 11021422592 1178353456 12.575 100 95 188 4 1683160384 482000704 22806224
09:05:34 1874 8192000000 10587676000 11027845120 1186423392 12.650 100 69 138 0 1692494352 488598592 17472368
09:06:50 5189 8192000000 10558701792 11049816064 1175362608 12.547 100 49 98 0 1663012272 474458880 13190784
09:08:50 23199 8192000000 10480184016 10949795840 1154826016 12.355 99 33 66 0 1584566720 421039312 8701392
09:10:20 67333 8192000000 10376462976 10883092480 1139610944 12.212 99 27 54 0 1491532272 345043008 6878320
09:12:47 40902 8192000000 10296929632 10827362304 1139407280 12.210 100 23 46 0 1409902960 264394176 6101504
09:14:39 11407 8192000000 10159126656 10638090240 1128893456 12.111 98 37 69 0 1225434336 89500912 7039968
09:16:02 3935 8192000000 10176508704 10633142272 1161552336 12.418 100 73 146 0 1273916352 96365360 15998656
09:17:10 38982 8192000000 10211534400 10633736192 1160926336 12.412 100 73 152 2 1308785664 130495424 17363904
09:18:50 19721 8192000000 10256539696 10679791616 1139556848 12.211 99 57 115 1 1336494160 184557056 12380256
09:20:11 43231 8192000000 10243085936 10765750272 1140241472 12.218 100 16 32 0 1365984112 221777504 3965136
09:22:01 40197 8192000000 10218664864 10706837504 1126361024 12.087 99 15 29 0 1319150464 189704896 3084544
09:23:44 102573 8192000000 10121048208 10576752640 1127124128 12.094 99 31 61 0 1244538240 111100080 6314032
09:26:37 214882 8192000000 10100378352 10548502528 1076731216 11.616 94 19 39 0 1134561712 53802160 4028336
09:31:24 78011 8192000000 10064164304 10520416256 1120656240 12.033 99 37 72 0 1208959584 80480400 7822944
09:33:57 85361 8192000000 10085847648 10533527552 1138251072 12.199 99 29 58 0 1190253888 45780864 6221952
09:36:24 29125 8192000000 10104002352 10559475712 1124043520 12.065 98 35 70 0 1186056592 54970544 7042528
09:37:54 29346 8192000000 10143262624 10581708800 1160538224 12.408 100 46 91 0 1257291472 86979424 9773824
09:39:34 116854 8192000000 10139692384 10612867072 1145394464 12.266 99 15 32 0 1254933392 105714896 3824032
09:42:31 73546 8192000000 10157363504 10641215488 1130232368 12.124 98 20 38 0 1243862976 109583120 4047488
09:45:44 51494 8192000000 10055173296 10498146304 1106838096 11.902 97 13 26 0 1149366992 39396880 3132016
09:47:41 46660 8192000000 10094871440 10548637696 1119996336 12.027 98 29 58 0 1184653824 58696912 5960576
09:49:28 69793 8192000000 10072223376 10543341568 1112332640 11.954 97 11 22 0 1166554672 52221136 2000896
09:51:38 20754 8192000000 10119300496 10550915072 1144461664 12.257 99 46 87 0 1200488864 46718864 9308336
09:53:03 100419 8192000000 10095291632 10564005888 1085219712 11.697 94 23 46 1 1123610208 34151168 4239328
09:55:47 30945 8192000000 10086960400 10519986176 1113223728 11.963 98 28 54 0 1155785200 37345008 5216464
09:57:22 63263 8192000000 10082548976 10567520256 1146818928 12.280 100 12 24 0 1224975536 75610688 2545920
09:59:26 109103 8192000000 10041156128 10477404160 1104403456 11.879 97 35 64 1 1147942592 36482416 7056720
10:02:21 52089 8192000000 10071107968 10499223552 1116056080 11.990 99 46 88 1 1162100544 36583072 9461392
10:04:30 10341 8192000000 10147007552 10585419776 1145614400 12.268 100 50 93 0 1247597344 91743360 10239584
10:05:58 64524 8192000000 10082177168 10529316864 1088448240 11.728 96 11 21 0 1144069312 53529888 2091184
10:08:11 43122 8192000000 10071854112 10526093312 1105052352 11.886 98 10 20 0 1160039232 52407760 2579120
10:09:57 61789 8192000000 10089707376 10540212224 1114710032 11.977 99 28 53 0 1187907392 67613632 5583728
10:12:11 61920 8192000000 10062389136 10524999680 1104413152 11.879 98 11 22 0 1161546016 55247024 1885840
10:14:14 30804 8192000000 10100709264 10563907584 1110244592 11.935 99 35 68 1 1197164592 80092864 6827136
10:15:47 38184 8192000000 10071655824 10505334784 1109371152 11.926 99 44 86 1 1162128608 43456496 9300960
10:17:35 33903 8192000000 10086746448 10537668608 1113163808 11.962 98 18 37 0 1158925728 42263504 3498416
10:19:41 65470 8192000000 10121771808 10617561088 1081090384 11.658 95 25 50 0 1199555088 112783456 5681248
10:21:50 35530 8192000000 10110687760 10563256320 1112564672 11.957 99 34 68 0 1213856592 93870816 7421104
10:23:29 14772 8192000000 10076500544 10492866560 1119506400 12.022 99 50 90 0 1156976816 27757072 9713344
10:24:47 36970 8192000000 10141904288 10590060544 1121399296 12.040 99 44 87 1 1218876592 88513552 8963744
10:26:27 80634 8192000000 10124059312 10583826432 1108854496 11.922 98 19 37 0 1157964880 45611136 3499248
10:30:25 67415 8192000000 10057744592 10528649216 1129847296 12.120 100 9 18 0 1157274400 25795616 1631488
10:32:33 55691 8192000000 10058743408 10526957568 1129620880 12.118 100 6 12 0 1175100144 44483856 995408
10:34:34 63484 8192000000 10055164896 10523762688 1072334304 11.574 95 15 30 0 1124611472 49228512 3048656
10:36:38 47203 8192000000 9516188048 9869524992 594868320 6.769 53 17 32 0 654622464 56268080 3486064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-12-2022 10:39:08 to 16-12-2022 10:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 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 16-12-2022 10:39:25 to 16-12-2022 10:39:27.

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          207345
MON$OLDEST_ACTIVE               211815
MON$OLDEST_SNAPSHOT             211815
MON$NEXT_TRANSACTION            211815
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                       2110464
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             761
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:107: 
:108: mscs-test-161.budzdorov.ru    Fri Dec 16 10:37:33 2022
:109:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:110: 
--- end of diff output ---

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

16.12.22 10:39:28. 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-12-16 10:39:30 No severe PSQL-related problems occured
16.12.22 10:39:32. End of report.