Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.06.2022 19: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 16293956 kB
MemAvailable 31172564 kB
Buffers 0 kB
Cached 15389788 kB
SwapCached 2504 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.10
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-06-26 19:38:22
DTS_END                         2022-06-26 19:38:22.3310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40436
TRN_ID                          236159
ATT_ID                          628
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: 9054.27 23867 1086512
customer order: creation 167.05 344 20046
customer order: refuse 14.92 379 1790
order to supplier: creation 44.83 1253 5379
order to supplier: removal 3.57 89809 428
invoice (draft): creation 45.48 3279 5457
invoice (draft): removal 52.88 716 6345
invoice accept: apply 40.01 19298 4801
invoice accept: cancel 47.49 800 5699
customer reserve: creation 3693.68 192 443241
customer reserve: removal 4649.32 54 557918
realization accept: apply 80.93 156 9711
realization accept: cancel 87.63 96 10515
payment from customer: creation 58.01 97 6961
payment from customer: removal 5.63 79 676
payment to supplier: creation 48.00 115 5760
payment to supplier: removal 2.96 69 355
service: total inventory turnovers 4.66 1058 559
service: total monetary turnovers 6.40 30 768
service: aggregate perf. data 0.83 19810 99
service: refresh index statistics 0.03 339706 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-06-2022 19:39:40 to 26-06-2022 19:39:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2773
TEST_TIME 2 4805
TEST_TIME 3 4694
TEST_TIME 4 5093
TEST_TIME 5 5865
TEST_TIME 6 6382
TEST_TIME 7 6233
TEST_TIME 8 6450
TEST_TIME 9 6619
TEST_TIME 10 6846
TEST_TIME 11 7087
TEST_TIME 12 7205
TEST_TIME 13 7218
TEST_TIME 14 7114
TEST_TIME 15 7206
TEST_TIME 16 7420
TEST_TIME 17 7373
TEST_TIME 18 7650
TEST_TIME 19 8237
TEST_TIME 20 8634
TEST_TIME 21 8867
TEST_TIME 22 9223
TEST_TIME 23 9627
TEST_TIME 24 9766
TEST_TIME 25 9911
TEST_TIME 26 10557
TEST_TIME 27 10441
TEST_TIME 28 10264
TEST_TIME 29 10178
TEST_TIME 30 10244
TEST_TIME 31 10097
TEST_TIME 32 9963
TEST_TIME 33 9907
TEST_TIME 34 9949
TEST_TIME 35 9960
TEST_TIME 36 9835
TEST_TIME 37 9776
TEST_TIME 38 9861
TEST_TIME 39 9685
TEST_TIME 40 9592
TEST_TIME 41 9450
TEST_TIME 42 9503
TEST_TIME 43 9469
TEST_TIME 44 9354
TEST_TIME 45 9361
TEST_TIME 46 9366
TEST_TIME 47 9264
TEST_TIME 48 9236
TEST_TIME 49 9333
TEST_TIME 50 9300
TEST_TIME 51 9280
TEST_TIME 52 9354
TEST_TIME 53 9402
TEST_TIME 54 9395
TEST_TIME 55 9348
TEST_TIME 56 9483
TEST_TIME 57 9541
TEST_TIME 58 9516
TEST_TIME 59 9507
TEST_TIME 60 9553
TEST_TIME 61 9456
TEST_TIME 62 9425
TEST_TIME 63 9333
TEST_TIME 64 9372
TEST_TIME 65 9278
TEST_TIME 66 9283
TEST_TIME 67 9257
TEST_TIME 68 9265
TEST_TIME 69 9271
TEST_TIME 70 9222
TEST_TIME 71 9223
TEST_TIME 72 9233
TEST_TIME 73 9299
TEST_TIME 74 9416
TEST_TIME 75 9440
TEST_TIME 76 9411
TEST_TIME 77 9473
TEST_TIME 78 9439
TEST_TIME 79 9477
TEST_TIME 80 9539
TEST_TIME 81 9521
TEST_TIME 82 9427
TEST_TIME 83 9415
TEST_TIME 84 9408
TEST_TIME 85 9351
TEST_TIME 86 9294
TEST_TIME 87 9314
TEST_TIME 88 9283
TEST_TIME 89 9219
TEST_TIME 90 9262
TEST_TIME 91 9262
TEST_TIME 92 9190
TEST_TIME 93 9217
TEST_TIME 94 9189
TEST_TIME 95 9253
TEST_TIME 96 9246
TEST_TIME 97 9280
TEST_TIME 98 9274
TEST_TIME 99 9306
TEST_TIME 100 9271
TEST_TIME 101 9275
TEST_TIME 102 9312
TEST_TIME 103 9338
TEST_TIME 104 9299
TEST_TIME 105 9348
TEST_TIME 106 9347
TEST_TIME 107 9384
TEST_TIME 108 9301
TEST_TIME 109 9278
TEST_TIME 110 9250
TEST_TIME 111 9229
TEST_TIME 112 9185
TEST_TIME 113 9168
TEST_TIME 114 9119
TEST_TIME 115 9153
TEST_TIME 116 9118
TEST_TIME 117 9098
TEST_TIME 118 9094
TEST_TIME 119 9122
TEST_TIME 120 9055

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-06-2022 19:39:44 to 26-06-2022 19:39:47.

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 1498770 1498770 0 0.00 0 15954 6 0 0
doc_list_biud 1498770 1498770 0 0.00 0 39080 26 0 0
sp_add_invoice_to_stock 4894 4801 93 1.90 54 223342 19299 0 93
sp_cancel_adding_invoice 5699 5699 0 0.00 3 80683 800 0 0
sp_cancel_client_order 1790 1790 0 0.00 4 12049 379 0 0
sp_cancel_customer_reserve 557918 557918 0 0.00 1 22163 55 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 3 3831 79 0 0
sp_cancel_pay_to_supplier 355 355 0 0.00 2 2060 69 0 0
sp_cancel_supplier_invoice 6355 6345 10 0.15 1 25958 717 0 10
sp_cancel_supplier_order 428 428 0 0.00 13 1170374 89810 0 0
sp_cancel_write_off 10515 10515 0 0.00 1 15250 97 0 0
sp_client_order 20046 20046 0 0.00 3 19880 345 0 0
sp_customer_reserve 443241 443241 0 0.00 0 48406 192 0 0
sp_fill_shopping_cart 30878 30878 0 0.00 1 12090 40 0 0
sp_fill_shopping_cart_clo_res 443239 443239 0 0.00 0 16500 13 0 0
sp_get_clo_for_invoice 4797 4797 0 0.00 0 34808 332 0 0
sp_kill_cost_storno 17245 17245 0 0.00 0 4846 50 0 0
sp_kill_qstorno_ret_qs2qd 1133124 1133124 0 0.00 0 25948 29 0 0
sp_kill_qty_storno 597202 597202 0 0.00 0 39080 64 0 0
sp_lock_selected_doc 20172 20172 0 0.00 0 7426 4 0 0
sp_make_cost_storno 27228 27228 0 0.00 0 15941 46 0 0
sp_multiply_rows_for_pdistr 27228 27228 0 0.00 0 6739 27 0 0
sp_multiply_rows_for_qdistr 20046 20046 0 0.00 0 17062 175 0 0
sp_pay_from_customer 6961 6961 0 0.00 4 14028 98 0 0
sp_pay_to_supplier 5760 5760 0 0.00 5 20227 116 0 0
sp_payment_common 12721 12721 0 0.00 1 20103 27 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 12019 359 0 0
sp_qd_handle_on_invoice_upd_sts 10495 10495 0 0.00 0 39079 343 0 0
sp_qd_handle_on_reserve_upd_sts 9711 9711 0 0.00 0 16405 54 0 0
sp_reserve_write_off 9711 9711 0 0.00 6 19243 157 0 0
sp_supplier_invoice 5457 5457 0 0.00 7 62493 3280 0 0
sp_supplier_order 5379 5379 0 0.00 5 33810 1253 0 0
srv_aggregate_perf_data 100 99 1 1.00 2802 131447 19810 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 1834 132380 48901 0 0
srv_find_qd_qs_mism 1041113 1041113 0 0.00 0 15352 6 0 0
srv_make_invnt_saldo 559 559 0 0.00 35 85012 1059 0 0
srv_make_money_saldo 768 768 0 0.00 0 3924 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 151920 473206 339706 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4801 4801 0 0.00 0 12685 183 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 3412 105 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 5082 15 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 7927 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 6996 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 355 355 0 0.00 0 236 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 1110 12 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 27418 1468 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 3554 8 0 0
t$perf-norm:sp_client_order 20046 20046 0 0.00 0 19971 18 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 2943 10 0 0
t$perf-norm:sp_pay_from_customer 6961 6961 0 0.00 0 6635 13 0 0
t$perf-norm:sp_pay_to_supplier 5760 5760 0 0.00 0 6677 10 0 0
t$perf-norm:sp_reserve_write_off 9711 9711 0 0.00 0 9027 14 0 0
t$perf-norm:sp_supplier_invoice 5457 5457 0 0.00 0 6911 23 0 0
t$perf-norm:sp_supplier_order 5379 5379 0 0.00 0 5068 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 218 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 346 12 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 3214 22 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 808 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9774 9588 186 1.90 0 7167 19 0 186
v_all_customers 36618 36618 0 0.00 0 108 1 0 0
v_all_suppliers 29956 29956 0 0.00 0 23 1 0 0
v_all_wares 40092 40092 0 0.00 0 125 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 1572 8 0 0
v_cancel_client_order 3580 3580 0 0.00 0 928 3 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 3812 14 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 1288 4 0 0
v_cancel_payment_to_supplier 710 710 0 0.00 0 2048 6 0 0
v_cancel_supplier_invoice 836 816 20 2.39 0 5849 20 0 20
v_cancel_supplier_order 856 856 0 0.00 0 194 3 0 0
v_cancel_write_off 1926 1926 0 0.00 0 4026 17 0 0
v_max_id_clo_ord 5379 5379 0 0.00 0 2514 3 0 0
v_max_id_clo_res 986 986 0 0.00 0 1536 13 0 0
v_max_id_ord_sup 5453 5453 0 0.00 0 7723 7 0 0
v_max_non_paid_invoice 1614 1614 0 0.00 0 4658 6 0 0
v_max_non_paid_realizn 3669 3669 0 0.00 0 6742 6 0 0
v_min_id_clo_ord 5379 5379 0 0.00 0 3547 5 0 0
v_min_id_clo_res 986 986 0 0.00 0 9409 19 0 0
v_min_id_ord_sup 5453 5453 0 0.00 0 3627 4 0 0
v_min_non_paid_invoice 5760 5760 0 0.00 0 20089 18 0 0
v_min_non_paid_realizn 6961 6961 0 0.00 0 12299 20 0 0
v_our_firm 10058 10058 0 0.00 0 16 1 0 0
v_reserve_write_off 19422 19422 0 0.00 0 4547 6 0 0
x_lock_depdocs_on_canc_invoice 11636 11636 0 0.00 0 16635 122 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 12357 345 0 0
x_make_qstorno_1000_1200 5379 5379 0 0.00 1 33697 1171 0 0
x_make_qstorno_1200_2000 5457 5457 0 0.00 4 60980 3139 0 0
x_make_qstorno_2100_3300 443241 443241 0 0.00 0 48402 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-06-2022 19:39:48 to 26-06-2022 19:39:52.

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
17:08:26 11 8192000000 8798873136 8810168320 5315664 0.064 2 1 2 0 5415264 56816 42784
17:09:26 43006 8192000000 9907724576 10215694336 982802512 10.711 100 89 178 1 1013199952 6243344 24154096
17:11:14 22087 8192000000 9958333376 10280128512 1038660928 11.252 100 86 172 0 1068526592 8329920 21535744
17:12:43 15865 8192000000 9993309648 10328031232 1074654928 11.597 100 87 174 0 1105918960 9853552 21410480
17:14:03 7373 8192000000 10036959104 10387415040 1125954592 12.083 100 81 162 0 1156241120 11615248 18671280
17:15:18 9490 8192000000 10093357792 10455715840 1177200624 12.564 100 83 166 0 1209559584 13131088 19227872
17:16:32 4872 8192000000 10133086768 10498039808 1222148000 12.982 100 85 167 2 1253407664 13391632 17868032
17:17:38 2852 8192000000 10165605568 10539556864 1249735376 13.236 100 87 172 1 1282008896 13528400 18745120
17:18:43 1895 8192000000 10195792272 10575073280 1280888576 13.521 100 92 186 0 1314686464 13407024 20390864
17:19:47 1444 8192000000 10214127504 10604920832 1293333216 13.635 100 85 166 2 1330294336 18375920 18585200
17:20:50 6993 8192000000 10196288336 10601377792 1303293360 13.725 100 37 74 0 1320542160 9113680 8135120
17:21:58 9566 8192000000 10198682832 10613313536 1314501776 13.827 100 22 44 0 1325042480 5989952 4550752
17:23:12 65884 8192000000 10213994256 10636070912 1324990688 13.922 100 18 36 0 1335461248 5979104 4491456
17:25:18 7236 8192000000 10223034144 10642333696 1329120896 13.959 100 35 70 0 1343917600 6809584 7987120
17:26:26 7749 8192000000 10226970496 10649628672 1336516112 14.026 100 25 50 2 1349283680 6590368 6177200
17:27:34 8658 8192000000 10206867904 10623488000 1306106192 13.751 99 9 18 0 1313330544 4995216 2229136
17:28:43 43831 8192000000 10189003696 10600054784 1248062080 13.220 95 31 63 1 1260581328 5011344 7507904
17:30:27 4448 8192000000 10142451248 10539446272 1233252720 13.084 98 27 54 1 1245478320 5898016 6327584
17:31:33 5373 8192000000 10141863840 10541944832 1249632176 13.235 100 36 72 2 1264308656 6517424 8159056
17:32:41 5518 8192000000 10150932000 10548645888 1246662720 13.208 99 21 41 2 1256751120 5538736 4549664
17:33:47 13861 8192000000 10185926592 10578386944 1270792912 13.429 100 64 129 1 1292628336 5617088 16218336
17:35:01 5052 8192000000 10193938736 10598064128 1289398192 13.599 100 36 72 1 1308793072 12076800 7318080
17:36:06 7044 8192000000 10194729488 10609070080 1305556048 13.746 100 23 45 0 1316181840 5650080 4975712
17:37:14 11925 8192000000 10222872320 10631143424 1316360016 13.844 100 22 44 0 1327323632 4953888 6009728
17:38:27 42538 8192000000 10245269104 10651496448 1335133472 14.014 100 45 90 1 1356119056 11003360 9982224
17:40:30 22050 8192000000 10264220432 10682634240 1357088160 14.211 100 45 90 1 1379183456 11834384 10260912
17:41:55 41806 8192000000 10281836672 10710605824 1370826384 14.334 100 31 62 0 1391983856 13862144 7295328
17:43:39 2506 8192000000 10312680144 10728771584 1388264096 14.490 100 76 152 1 1422066544 16329008 17473440
17:44:51 8673 8192000000 10310778832 10734669824 1392029680 14.524 100 49 97 2 1420210032 16988960 11191392
17:46:02 12600 8192000000 10321446688 10738360320 1380141200 14.418 99 61 122 2 1415899680 21793888 13964592
17:47:19 2640 8192000000 10328835600 10744098816 1369842448 14.326 98 76 152 1 1408209952 20349136 18018368
17:48:26 1833 8192000000 10331830256 10738425856 1388760560 14.495 99 81 160 2 1432443840 24955616 18727664
17:49:31 12460 8192000000 10331313792 10761097216 1396134592 14.561 99 48 96 0 1436025840 28114064 11777184
17:50:54 4967 8192000000 10336156512 10750799872 1396883616 14.567 100 77 155 1 1443203632 27176864 19143152
17:52:04 4466 8192000000 10337350992 10746318848 1382901584 14.442 98 74 146 3 1429607312 28673488 18032240
17:53:14 7125 8192000000 10344570592 10758242304 1397270832 14.571 100 64 127 1 1448411424 35457168 15683424
17:54:32 4248 8192000000 10328707904 10731659264 1362176208 14.257 99 78 158 4 1420639424 38649264 19813952
17:55:49 4590 8192000000 10347781536 10760228864 1385843168 14.469 100 70 141 1 1452212144 47529696 18839280
17:57:06 8494 8192000000 10321741376 10737221632 1376372640 14.384 100 55 110 1 1432793392 42114080 14306672
17:58:17 7369 8192000000 10334570976 10756386816 1384404656 14.456 100 42 84 1 1437447536 41890800 11152080
17:59:29 16296 8192000000 10326067984 10751279104 1375377168 14.375 100 44 86 1 1434915296 46982448 12555680
18:00:58 40817 8192000000 10298881440 10737197056 1365099840 14.283 100 26 52 0 1411777824 38988576 7689408
18:02:41 27155 8192000000 10291556912 10732613632 1365469936 14.286 100 19 38 3 1397815648 27051024 5294688
18:04:09 37126 8192000000 10277424128 10717057024 1339611120 14.054 99 13 26 0 1368688416 25341536 3735760
18:05:48 124878 8192000000 10257713200 10709987328 1355291040 14.195 100 35 70 0 1375415360 12466752 7657568
18:09:46 68395 8192000000 10263460608 10703884288 1347550640 14.125 98 32 63 1 1370404912 15455200 7399072
18:11:58 31717 8192000000 10277879008 10739953664 1375950336 14.380 100 17 34 0 1398499056 18711760 3836960
18:13:49 46644 8192000000 10284943264 10726490112 1367774176 14.307 99 19 38 1 1384531808 12293632 4464000
18:15:42 82191 8192000000 10264144016 10728026112 1347055952 14.121 97 12 24 2 1365711632 15814640 2841040
18:18:05 44671 8192000000 10280636032 10732187648 1376052496 14.381 100 18 35 1 1387906192 7734048 4119648
18:19:50 53841 8192000000 10291569104 10750844928 1387268960 14.481 100 31 62 1 1409092784 14438384 7385440
18:22:36 43966 8192000000 10287224176 10746044416 1391759376 14.522 100 14 28 0 1412289152 17568144 2961632
18:26:08 59465 8192000000 10291008032 10758234112 1371526032 14.341 98 21 42 0 1393619968 17444736 4649200
18:28:11 36598 8192000000 10297624496 10753626112 1387382864 14.483 99 22 42 1 1400094144 6771648 5939632
18:29:48 35853 8192000000 10308179296 10755452928 1373348592 14.357 98 16 32 2 1392524848 15518992 3657264
18:31:25 110714 8192000000 10315254304 10769321984 1399691360 14.592 100 12 22 1 1424767600 22594480 2481760
18:34:21 123133 8192000000 10290306176 10744942592 1272647248 13.446 91 12 24 0 1288259952 13071392 2541312
18:37:31 26571 8192000000 10294123600 10735497216 1362740832 14.262 99 33 66 1 1386358768 16336752 7281184
18:38:59 128693 8192000000 10295142064 10752004096 1369835136 14.326 99 28 56 1 1398256240 22375520 6045584
18:42:20 42542 8192000000 10277289264 10738974720 1356039088 14.202 98 23 45 2 1380428400 18657408 5731904
18:44:03 50636 8192000000 10261762128 10716864512 1301293168 13.707 94 11 22 0 1319314368 14884592 3136608
18:45:57 59943 8192000000 10257685936 10716119040 1329647088 13.964 98 8 16 0 1345049376 13458512 1943776
18:47:57 56286 8192000000 10266720992 10696421376 1290907328 13.612 95 19 38 0 1306286592 10821072 4558192
18:50:12 54601 8192000000 10275305104 10716856320 1338459984 14.044 98 17 33 0 1360436864 18096352 3880528
18:52:10 49175 8192000000 10275888096 10730459136 1354575904 14.189 99 13 26 1 1378567936 21048112 2943920
18:54:03 44431 8192000000 10270684048 10712510464 1337850592 14.038 99 20 40 1 1359435280 16917040 4667648
18:56:27 26959 8192000000 10274413856 10708631552 1332745600 13.992 98 29 58 0 1351546128 12394176 6406352
18:57:54 32988 8192000000 10272989744 10704605184 1354202736 14.185 100 30 60 0 1376935952 16080064 6653152
18:59:30 132379 8192000000 10256167280 10698129408 1329554448 13.963 98 32 64 0 1348413664 11508352 7350864
19:02:43 39196 8192000000 10251448512 10709848064 1362766608 14.262 100 15 30 1 1374294016 7869072 3658336
19:04:43 71985 8192000000 10263754192 10718343168 1335626960 14.018 98 10 20 0 1347272016 9058608 2586448
19:06:57 83761 8192000000 10269827232 10715938816 1330126512 13.968 97 24 47 0 1347577168 12032912 5417744
19:09:47 70098 8192000000 10275475616 10729402368 1369622432 14.324 100 10 20 0 1383705760 12100704 1982624
19:12:00 84773 8192000000 10291744960 10755268608 1386869408 14.478 100 15 31 0 1405084576 14723392 3491776
19:14:29 94968 8192000000 10304903104 10749452288 1381166288 14.427 99 16 33 0 1395761040 11179072 3415680
19:17:27 70590 8192000000 10294320592 10748469248 1369345840 14.321 98 22 44 1 1386493984 11579984 5568160
19:19:43 49717 8192000000 10295057328 10747633664 1353592384 14.180 98 38 76 0 1378515904 16478384 8445136
19:21:35 53110 8192000000 10289865456 10722103296 1346121568 14.113 97 28 57 0 1360864800 8829264 5913968
19:23:36 48911 8192000000 10301440048 10747265024 1364912560 14.281 99 23 46 0 1380725200 10992304 4820336
19:25:33 115807 8192000000 10289267472 10747846656 1387883280 14.487 100 10 19 1 1404686848 14622928 2180640
19:28:36 27922 8192000000 10269481936 10735984640 1374876480 14.371 100 13 26 1 1390574656 12727184 2970992
19:30:11 76771 8192000000 10278069776 10743963648 1376501360 14.385 100 7 14 0 1393957216 15546608 1909248
19:32:35 89937 8192000000 10263996304 10727088128 1348197712 14.131 98 8 14 2 1366613568 16405248 2010608
19:35:05 62560 8192000000 10280713248 10731769856 1354183296 14.185 98 23 46 0 1370329584 10214176 5932112
19:37:18 65763 8192000000 9794536144 10166935552 877359408 9.673 63 16 32 1 892179008 11525456 3294144

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-06-2022 19:39:54 to 26-06-2022 19:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 93 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

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

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          236460
MON$OLDEST_ACTIVE               236461
MON$OLDEST_SNAPSHOT             236461
MON$NEXT_TRANSACTION            236461
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                       2017664
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:467: 
:468: mscs-test-161.budzdorov.ru    Sun Jun 26 19:38:44 2022
:469:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:470: 
--- end of diff output ---

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

26.06.22 19:40:13. 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-06-26 19:40:15 No severe PSQL-related problems occured
26.06.22 19:40:17. End of report.