Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.10.2022 22: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 15504240 kB
MemAvailable 30447960 kB
Buffers 0 kB
Cached 16193092 kB
SwapCached 2056 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-10-22 22:38:27
DTS_END                         2022-10-22 22:38:27.5440
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(57:17)
IP                              ::1/53564
TRN_ID                          248775
ATT_ID                          654
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: 9550.38 19224 1146045
customer order: creation 183.61 344 22033
customer order: refuse 15.26 314 1831
order to supplier: creation 47.91 1186 5749
order to supplier: removal 3.45 94281 414
invoice (draft): creation 48.02 3317 5762
invoice (draft): removal 53.79 674 6455
invoice accept: apply 43.81 16568 5257
invoice accept: cancel 48.55 666 5826
customer reserve: creation 4035.25 165 484230
customer reserve: removal 4757.92 53 570950
realization accept: apply 87.73 197 10527
realization accept: cancel 88.48 116 10618
payment from customer: creation 62.80 113 7536
payment from customer: removal 5.80 66 696
payment to supplier: creation 52.24 131 6269
payment to supplier: removal 3.54 90 425
service: total inventory turnovers 4.83 911 579
service: total monetary turnovers 6.40 24 768
service: aggregate perf. data 0.97 15886 116
service: refresh index statistics 0.03 249374 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-10-2022 22:39:29 to 22-10-2022 22:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5859
TEST_TIME 2 4776
TEST_TIME 3 5739
TEST_TIME 4 5371
TEST_TIME 5 5865
TEST_TIME 6 5776
TEST_TIME 7 5550
TEST_TIME 8 5797
TEST_TIME 9 5601
TEST_TIME 10 5673
TEST_TIME 11 5504
TEST_TIME 12 5434
TEST_TIME 13 5275
TEST_TIME 14 5953
TEST_TIME 15 6197
TEST_TIME 16 6269
TEST_TIME 17 6721
TEST_TIME 18 6864
TEST_TIME 19 7073
TEST_TIME 20 7743
TEST_TIME 21 7605
TEST_TIME 22 7903
TEST_TIME 23 7874
TEST_TIME 24 8235
TEST_TIME 25 8358
TEST_TIME 26 9148
TEST_TIME 27 9705
TEST_TIME 28 10415
TEST_TIME 29 10327
TEST_TIME 30 10207
TEST_TIME 31 10086
TEST_TIME 32 9994
TEST_TIME 33 9864
TEST_TIME 34 9642
TEST_TIME 35 9635
TEST_TIME 36 9703
TEST_TIME 37 9738
TEST_TIME 38 9596
TEST_TIME 39 9539
TEST_TIME 40 9460
TEST_TIME 41 9402
TEST_TIME 42 9434
TEST_TIME 43 9327
TEST_TIME 44 9556
TEST_TIME 45 9550
TEST_TIME 46 9623
TEST_TIME 47 9603
TEST_TIME 48 9555
TEST_TIME 49 9642
TEST_TIME 50 9742
TEST_TIME 51 9725
TEST_TIME 52 9752
TEST_TIME 53 9885
TEST_TIME 54 9901
TEST_TIME 55 9862
TEST_TIME 56 9754
TEST_TIME 57 9764
TEST_TIME 58 9729
TEST_TIME 59 9635
TEST_TIME 60 9688
TEST_TIME 61 9591
TEST_TIME 62 9508
TEST_TIME 63 9572
TEST_TIME 64 9538
TEST_TIME 65 9563
TEST_TIME 66 9541
TEST_TIME 67 9502
TEST_TIME 68 9564
TEST_TIME 69 9571
TEST_TIME 70 9548
TEST_TIME 71 9611
TEST_TIME 72 9627
TEST_TIME 73 9664
TEST_TIME 74 9693
TEST_TIME 75 9676
TEST_TIME 76 9744
TEST_TIME 77 9699
TEST_TIME 78 9697
TEST_TIME 79 9635
TEST_TIME 80 9618
TEST_TIME 81 9555
TEST_TIME 82 9556
TEST_TIME 83 9534
TEST_TIME 84 9510
TEST_TIME 85 9498
TEST_TIME 86 9444
TEST_TIME 87 9470
TEST_TIME 88 9502
TEST_TIME 89 9496
TEST_TIME 90 9516
TEST_TIME 91 9556
TEST_TIME 92 9534
TEST_TIME 93 9566
TEST_TIME 94 9612
TEST_TIME 95 9628
TEST_TIME 96 9599
TEST_TIME 97 9691
TEST_TIME 98 9685
TEST_TIME 99 9743
TEST_TIME 100 9738
TEST_TIME 101 9763
TEST_TIME 102 9680
TEST_TIME 103 9677
TEST_TIME 104 9628
TEST_TIME 105 9603
TEST_TIME 106 9579
TEST_TIME 107 9524
TEST_TIME 108 9484
TEST_TIME 109 9527
TEST_TIME 110 9491
TEST_TIME 111 9476
TEST_TIME 112 9455
TEST_TIME 113 9442
TEST_TIME 114 9472
TEST_TIME 115 9446
TEST_TIME 116 9509
TEST_TIME 117 9590
TEST_TIME 118 9589
TEST_TIME 119 9621
TEST_TIME 120 9554

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-10-2022 22:39:33 to 22-10-2022 22:39:36.

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 1592823 1592823 0 0.00 0 23870 6 0 0
doc_list_biud 1592823 1592823 0 0.00 0 90273 24 0 0
sp_add_invoice_to_stock 5355 5257 98 1.83 27 165229 16569 0 98
sp_cancel_adding_invoice 5826 5826 0 0.00 4 53252 667 0 0
sp_cancel_client_order 1831 1831 0 0.00 4 8302 315 0 0
sp_cancel_customer_reserve 570950 570950 0 0.00 0 20262 54 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 3 3572 67 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 9476 90 0 0
sp_cancel_supplier_invoice 6463 6455 8 0.12 1 90289 675 0 8
sp_cancel_supplier_order 414 414 0 0.00 8 951930 94282 0 0
sp_cancel_write_off 10618 10618 0 0.00 1 12876 117 0 0
sp_client_order 22033 22033 0 0.00 3 18327 345 0 0
sp_customer_reserve 484230 484230 0 0.00 0 33079 165 0 0
sp_fill_shopping_cart 33543 33543 0 0.00 1 18140 37 0 0
sp_fill_shopping_cart_clo_res 484222 484222 0 0.00 0 20111 15 0 0
sp_get_clo_for_invoice 5249 5249 0 0.00 0 23478 285 0 0
sp_kill_cost_storno 17565 17565 0 0.00 0 10289 51 0 0
sp_kill_qstorno_ret_qs2qd 1159387 1159387 0 0.00 0 90272 29 0 0
sp_kill_qty_storno 611870 611870 0 0.00 0 90273 63 0 0
sp_lock_selected_doc 21726 21726 0 0.00 0 6452 5 0 0
sp_make_cost_storno 29581 29581 0 0.00 0 23855 59 0 0
sp_multiply_rows_for_pdistr 29581 29581 0 0.00 0 9440 29 0 0
sp_multiply_rows_for_qdistr 22033 22033 0 0.00 0 16219 158 0 0
sp_pay_from_customer 7536 7536 0 0.00 4 14004 113 0 0
sp_pay_to_supplier 6269 6269 0 0.00 5 23527 132 0 0
sp_payment_common 13805 13805 0 0.00 2 9470 27 0 0
sp_qd_handle_on_cancel_clo 1831 1831 0 0.00 0 8292 287 0 0
sp_qd_handle_on_invoice_upd_sts 11075 11075 0 0.00 0 30289 276 0 0
sp_qd_handle_on_reserve_upd_sts 10527 10527 0 0.00 0 13939 62 0 0
sp_reserve_write_off 10527 10527 0 0.00 6 23471 198 0 0
sp_supplier_invoice 5762 5762 0 0.00 5 107385 3318 0 0
sp_supplier_order 5749 5749 0 0.00 4 32015 1187 0 0
srv_aggregate_perf_data 116 116 0 0.00 2485 171830 15887 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 761 159841 41960 0 0
srv_find_qd_qs_mism 1094885 1094885 0 0.00 0 13610 5 0 0
srv_make_invnt_saldo 579 579 0 0.00 36 25215 912 0 0
srv_make_money_saldo 768 768 0 0.00 0 1079 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 126813 325615 249374 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5257 5257 0 0.00 0 10139 148 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 2034 65 0 0
t$perf-norm:sp_cancel_client_order 1831 1831 0 0.00 0 3427 18 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 3613 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 372 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 3533 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 1429 10 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 22002 1614 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 4462 12 0 0
t$perf-norm:sp_client_order 22033 22033 0 0.00 0 24009 15 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 1367 6 0 0
t$perf-norm:sp_pay_from_customer 7536 7536 0 0.00 0 5656 8 0 0
t$perf-norm:sp_pay_to_supplier 6269 6269 0 0.00 0 7876 9 0 0
t$perf-norm:sp_reserve_write_off 10527 10527 0 0.00 0 7039 11 0 0
t$perf-norm:sp_supplier_invoice 5762 5762 0 0.00 0 10672 16 0 0
t$perf-norm:sp_supplier_order 5749 5749 0 0.00 0 7294 15 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 98 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 68 1 0 0
t$perf-norm:srv_make_invnt_saldo 579 579 0 0.00 0 2000 25 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 3108 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10692 10496 196 1.83 0 4753 19 0 196
v_all_customers 40186 40186 0 0.00 0 17 0 0 0
v_all_suppliers 32152 32152 0 0.00 0 21 1 0 0
v_all_wares 44066 44066 0 0.00 0 51 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 1567 9 0 0
v_cancel_client_order 3662 3662 0 0.00 0 4069 8 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 2375 11 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 1313 7 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 2898 8 0 0
v_cancel_supplier_invoice 812 796 16 1.97 0 658 15 0 16
v_cancel_supplier_order 828 828 0 0.00 0 690 5 0 0
v_cancel_write_off 2046 2046 0 0.00 0 4889 22 0 0
v_max_id_clo_ord 5749 5749 0 0.00 0 529 1 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 1934 12 0 0
v_max_id_ord_sup 5761 5761 0 0.00 0 1587 3 0 0
v_max_non_paid_invoice 1703 1703 0 0.00 0 1385 3 0 0
v_max_non_paid_realizn 3970 3970 0 0.00 0 5887 6 0 0
v_min_id_clo_ord 5749 5749 0 0.00 0 9693 12 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 1905 8 0 0
v_min_id_ord_sup 5761 5761 0 0.00 0 6625 10 0 0
v_min_non_paid_invoice 6269 6269 0 0.00 0 9384 18 0 0
v_min_non_paid_realizn 7536 7536 0 0.00 0 8861 20 0 0
v_our_firm 11012 11012 0 0.00 0 28 1 0 0
v_reserve_write_off 21054 21054 0 0.00 0 4748 9 0 0
x_lock_depdocs_on_canc_invoice 11883 11883 0 0.00 0 13068 98 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 7422 365 0 0
x_make_qstorno_1000_1200 5749 5749 0 0.00 1 31955 1115 0 0
x_make_qstorno_1200_2000 5762 5762 0 0.00 2 102240 3180 0 0
x_make_qstorno_2100_3300 484230 484230 0 0.00 0 33078 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-10-2022 22:39:37 to 22-10-2022 22:39:41.

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
20:08:31 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:32 28571 8192000000 9891729904 10194804736 972076992 10.607 100 90 180 0 1001241328 5618560 23545776
20:11:07 14446 8192000000 9936388880 10251993088 1015465584 11.028 100 95 190 0 1051365504 11774688 24125232
20:12:27 21968 8192000000 9962900400 10289840128 1053025088 11.390 100 87 174 0 1083879712 9479952 21374672
20:13:53 10090 8192000000 10023260944 10366566400 1110679552 11.939 100 81 162 0 1139602880 9588704 19334624
20:15:10 5242 8192000000 10066274960 10423390208 1160596736 12.409 100 72 144 0 1188310192 11344320 16369136
20:16:19 3771 8192000000 10117387392 10486194176 1208723872 12.857 100 79 158 1 1238860864 13250224 16886768
20:17:25 1772 8192000000 10159560672 10531393536 1246283120 13.204 100 87 171 1 1278998976 12716352 19999504
20:18:30 1170 8192000000 10200353472 10582970368 1286656480 13.574 100 84 166 0 1317051216 11527776 18866960
20:19:33 1178 8192000000 10215271792 10608287744 1306376448 13.753 100 74 151 1 1336278640 13469840 16432352
20:20:36 2485 8192000000 10212278864 10612006912 1309703552 13.783 100 53 105 1 1327125728 5558656 11863520
20:21:39 5829 8192000000 10196166944 10616012800 1314824432 13.830 100 17 35 0 1323880624 5705488 3350704
20:22:46 10154 8192000000 10204562592 10622345216 1319051280 13.868 100 27 54 0 1331627136 6814576 5761280
20:23:58 14927 8192000000 10214932928 10631626752 1325587840 13.927 100 15 31 1 1334617360 5624560 3404960
20:25:13 55014 8192000000 10222167424 10642964480 1332589120 13.991 100 29 58 0 1344014672 5403216 6022336
20:27:09 5309 8192000000 10219806736 10640748544 1327140544 13.941 100 20 40 0 1337857168 6490480 4226144
20:28:16 9727 8192000000 10189500288 10604199936 1254515072 13.280 95 17 34 0 1267415472 9360240 3540160
20:29:26 11170 8192000000 10161825376 10573254656 1212906096 12.896 93 14 28 0 1221508912 5586368 3016448
20:30:39 20818 8192000000 10155056480 10563174400 1223316160 12.992 96 26 50 0 1239158080 10321840 5520080
20:32:12 4296 8192000000 10154286496 10547314688 1255267616 13.287 100 30 60 1 1267984576 6251344 6465616
20:33:17 5473 8192000000 10139807696 10540482560 1239360112 13.140 99 29 56 1 1251264112 5355744 6548256
20:34:23 71631 8192000000 10160255952 10565763072 1264042144 13.367 99 30 60 0 1277097600 5030448 8025008
20:36:36 6597 8192000000 10173672048 10586664960 1286592240 13.573 100 32 64 0 1300553712 6850288 7111184
20:37:45 9807 8192000000 10195694272 10609057792 1300486304 13.700 100 29 57 0 1317163424 9400448 7276672
20:39:00 5668 8192000000 10233276336 10639704064 1328754784 13.956 100 50 94 0 1344644128 5644176 10245168
20:40:10 39550 8192000000 10239646928 10656493568 1338347872 14.043 100 54 106 2 1360484336 9972464 12164000
20:41:52 32949 8192000000 10245306496 10671165440 1342308224 14.078 100 30 60 1 1357158656 7971824 6878608
20:43:32 21850 8192000000 10275894256 10698690560 1364213056 14.275 100 56 109 0 1389810512 12636672 12960784
20:44:59 3115 8192000000 10303475984 10722566144 1385237616 14.463 100 57 113 2 1414136800 15506608 13392576
20:46:10 25029 8192000000 10306066096 10732335104 1385322656 14.464 100 49 96 1 1419242576 22879184 11040736
20:47:42 9872 8192000000 10308695792 10728611840 1387113312 14.480 100 53 105 1 1417094480 17994656 11986512
20:49:01 20742 8192000000 10321163712 10743889920 1394357248 14.545 100 49 98 0 1430992544 24521472 12113824
20:50:35 10366 8192000000 10331391072 10759200768 1406365152 14.652 100 41 83 0 1445481808 28533344 10583312
20:51:50 8289 8192000000 10335161872 10758561792 1388611056 14.493 99 46 92 1 1437667936 36111456 12945424
20:53:05 5656 8192000000 10348639728 10768875520 1398555856 14.582 99 58 111 2 1448701504 36404736 13740912
20:54:19 1257 8192000000 10355173152 10763149312 1391110176 14.516 99 82 163 1 1454638704 43349856 20178672
20:55:24 5307 8192000000 10358902304 10775052288 1397990608 14.577 100 62 124 2 1465452208 51192672 16268928
20:56:36 759 8192000000 10360932688 10766147584 1392288368 14.526 100 91 180 0 1466527056 51602752 22635936
20:57:42 2323 8192000000 10381913504 10791489536 1405540896 14.644 100 81 158 1 1486706512 60045920 21119696
20:58:50 11577 8192000000 10316404096 10737250304 1345637968 14.108 97 36 70 1 1401643936 46458272 9547696
21:00:07 12986 8192000000 10326299760 10746134528 1371218256 14.338 100 49 98 0 1433325824 49290080 12817488
21:01:28 12582 8192000000 10314990224 10741972992 1359863520 14.236 99 34 67 0 1414919808 46011856 9044432
21:02:44 10578 8192000000 10313227072 10750853120 1366054848 14.292 100 25 49 1 1426878064 53279440 7543776
21:03:57 22291 8192000000 10299737984 10736922624 1342467280 14.080 99 19 38 0 1410000448 61460752 6072416
21:05:23 24612 8192000000 10255217264 10701455360 1322703040 13.901 99 21 42 1 1373513920 44953680 5857200
21:07:03 13542 8192000000 10257252176 10679586816 1327210256 13.942 99 54 106 3 1347068672 7064800 12793616
21:08:23 24166 8192000000 10241757360 10661445632 1300459664 13.699 96 41 81 3 1315395440 5856256 9079520
21:09:56 117567 8192000000 10266593616 10706743296 1342504768 14.080 99 32 63 0 1365213680 14999216 7709696
21:13:05 33988 8192000000 10260890000 10697338880 1323647024 13.910 97 15 30 1 1337984688 11079008 3258656
21:14:42 24614 8192000000 10266583200 10713907200 1362401776 14.259 100 36 73 0 1381366224 10383200 8581248
21:16:10 36348 8192000000 10256677968 10698067968 1335985888 14.021 97 30 60 1 1352289552 8694528 7609136
21:17:47 28945 8192000000 10278767072 10733547520 1379772032 14.415 100 10 18 0 1393019552 11108768 2138752
21:19:16 27416 8192000000 10271586000 10729062400 1364754960 14.280 99 14 27 0 1379598624 11499664 3344000
21:20:44 25752 8192000000 10288929344 10749587456 1382308912 14.437 100 15 31 0 1402406992 16743824 3354256
21:22:21 90864 8192000000 10294301840 10756616192 1390184768 14.508 100 7 14 0 1412463072 20560000 1718304
21:25:36 27416 8192000000 10304815248 10749706240 1396342832 14.562 100 47 96 0 1413301248 6877552 10080864
21:27:07 49751 8192000000 10301689584 10766000128 1386662704 14.476 99 22 44 0 1407727248 16040208 5024336
21:28:58 40042 8192000000 10319741568 10772484096 1406356304 14.652 100 15 30 0 1420355264 10709456 3289504
21:30:40 74120 8192000000 10307867184 10766987264 1351423472 14.160 96 14 28 0 1368250016 13737824 3088720
21:32:54 41533 8192000000 10301654064 10746007552 1355791408 14.200 97 13 26 0 1371301744 12831600 2678736
21:34:36 103084 8192000000 10281911808 10744463360 1351557904 14.161 97 32 64 1 1374953792 15989152 7406736
21:37:21 9050 8192000000 10290385232 10742652928 1369088688 14.319 99 42 84 3 1391495248 12778832 9627728
21:38:31 62469 8192000000 10280205952 10741477376 1366111888 14.292 98 12 24 0 1378377712 9822976 2442848
21:40:34 53462 8192000000 10259855136 10712166400 1326636480 13.937 97 12 24 0 1341799136 12816368 2346288
21:42:31 34813 8192000000 10243864528 10683703296 1311924800 13.804 97 24 45 0 1323384960 6410704 5049456
21:44:07 59993 8192000000 10252597184 10685702144 1284764224 13.556 95 19 38 1 1298970128 9791552 4414352
21:46:08 16886 8192000000 10250691856 10683113472 1331438352 13.980 100 31 62 0 1351860608 13174560 7247696
21:47:30 41840 8192000000 10260536032 10706419712 1346864192 14.119 99 22 42 0 1359830672 8269776 4696704
21:49:13 95474 8192000000 10275939312 10716848128 1322265824 13.897 97 16 32 0 1341134544 14845744 4022976
21:51:49 71923 8192000000 10282875424 10714939392 1318671520 13.865 97 16 32 0 1335639728 13352992 3615216
21:54:02 52556 8192000000 10275966032 10726608896 1347923552 14.129 99 12 24 2 1364972768 14244848 2804368
21:56:12 39041 8192000000 10294660240 10732531712 1371946272 14.344 100 36 72 0 1391891392 12289296 7655824
21:58:16 69660 8192000000 10279197632 10732490752 1377273040 14.392 100 17 34 1 1388957680 7385488 4299152
22:00:30 66321 8192000000 10287897072 10763481088 1391665056 14.521 100 17 32 0 1414368688 18508208 4195424
22:02:37 66885 8192000000 10288715568 10742591488 1373430864 14.358 99 25 50 0 1392421072 12852928 6137280
22:04:50 79801 8192000000 10287302768 10752557056 1373701472 14.360 99 9 18 0 1386264624 10479680 2083472
22:07:14 31747 8192000000 10308860720 10759467008 1387935360 14.487 99 26 52 0 1408982912 15094400 5953152
22:08:55 89256 8192000000 10302462912 10754097152 1370876384 14.335 98 15 30 0 1382513152 8425312 3211456
22:11:25 70686 8192000000 10316178640 10769281024 1389648912 14.503 99 24 48 0 1406586240 11501040 5436288
22:13:57 30419 8192000000 10305149344 10753019904 1382115904 14.435 99 34 67 0 1398232944 8844672 7272368
22:15:28 61879 8192000000 10308317616 10780295168 1374470928 14.367 98 6 12 1 1387900976 12141936 1288112
22:17:31 62581 8192000000 10311456256 10766688256 1366638816 14.297 98 10 20 0 1384977824 16116784 2222224
22:19:33 144061 8192000000 10283171808 10742079488 1323461840 13.908 95 18 36 1 1341654704 13790192 4402672
22:22:59 16762 8192000000 10282469712 10740408320 1373750608 14.361 100 19 36 1 1387192896 9656560 3785728
22:24:19 41236 8192000000 10215010432 10669699072 1269874064 13.420 94 5 10 0 1281118896 10345616 899216
22:26:04 57117 8192000000 10262975712 10714382336 1334339168 14.006 98 18 37 0 1351361616 12641168 4381280
22:29:22 52999 8192000000 10240006464 10687971328 1267041344 13.395 93 12 24 1 1276685424 6952608 2691472
22:31:18 38778 8192000000 10255937712 10699296768 1332376064 13.989 99 24 48 0 1348820928 10897200 5547664
22:33:10 45244 8192000000 10266446176 10705973248 1316532912 13.845 97 24 46 0 1334409072 12191296 5684864
22:35:22 159841 8192000000 10254128944 10696454144 1249501344 13.234 92 13 26 0 1268323088 15756352 3065392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-10-2022 22:39:43 to 22-10-2022 22:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-10-2022 22:40:00 to 22-10-2022 22:40:02.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          247376
MON$OLDEST_ACTIVE               249035
MON$OLDEST_SNAPSHOT             249035
MON$NEXT_TRANSACTION            249035
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-03 16:41:55.3470
MON$PAGES                       2019136
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:84: 
:85: mscs-test-161.budzdorov.ru    Sat Oct 22 22:38:43 2022
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Sat Oct 22 22:38:46 2022
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:91: 
--- end of diff output ---

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

22.10.22 22:40:03. There are 3 logs in /var/tmp/logs-oltp30 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-10-22 22:40:05 No severe PSQL-related problems occured
22.10.22 22:40:07. End of report.