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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-13 07:37:24
DTS_END                         2023-01-13 07:37:24.0810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38506
TRN_ID                          206857
ATT_ID                          593
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: 8579.41 33993 1029529
customer order: creation 152.25 499 18270
customer order: refuse 13.35 504 1602
order to supplier: creation 41.36 1460 4963
order to supplier: removal 3.15 210990 378
invoice (draft): creation 42.12 3783 5054
invoice (draft): removal 48.59 1206 5831
invoice accept: apply 39.02 29441 4682
invoice accept: cancel 44.33 1455 5320
customer reserve: creation 3619.78 278 434373
customer reserve: removal 4299.42 118 515930
realization accept: apply 76.13 332 9135
realization accept: cancel 81.63 201 9796
payment from customer: creation 53.31 214 6397
payment from customer: removal 5.40 180 648
payment to supplier: creation 45.18 244 5422
payment to supplier: removal 3.13 149 376
service: total inventory turnovers 3.13 5556 376
service: total monetary turnovers 7.25 455 870
service: aggregate perf. data 0.85 17683 102
service: refresh index statistics 0.03 405116 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-01-2023 07:39:43 to 13-01-2023 07:39:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3578
TEST_TIME 2 3988
TEST_TIME 3 3818
TEST_TIME 4 4126
TEST_TIME 5 4681
TEST_TIME 6 5056
TEST_TIME 7 5064
TEST_TIME 8 5289
TEST_TIME 9 5591
TEST_TIME 10 5940
TEST_TIME 11 5951
TEST_TIME 12 6087
TEST_TIME 13 6135
TEST_TIME 14 6250
TEST_TIME 15 6325
TEST_TIME 16 6428
TEST_TIME 17 6416
TEST_TIME 18 6527
TEST_TIME 19 6476
TEST_TIME 20 6785
TEST_TIME 21 6896
TEST_TIME 22 7221
TEST_TIME 23 7324
TEST_TIME 24 7472
TEST_TIME 25 7576
TEST_TIME 26 7760
TEST_TIME 27 7818
TEST_TIME 28 8291
TEST_TIME 29 8317
TEST_TIME 30 8302
TEST_TIME 31 8269
TEST_TIME 32 8295
TEST_TIME 33 8577
TEST_TIME 34 8491
TEST_TIME 35 8355
TEST_TIME 36 8565
TEST_TIME 37 8858
TEST_TIME 38 9331
TEST_TIME 39 9477
TEST_TIME 40 9817
TEST_TIME 41 10377
TEST_TIME 42 10424
TEST_TIME 43 10497
TEST_TIME 44 10471
TEST_TIME 45 10329
TEST_TIME 46 10180
TEST_TIME 47 10032
TEST_TIME 48 10045
TEST_TIME 49 10184
TEST_TIME 50 10058
TEST_TIME 51 9885
TEST_TIME 52 9846
TEST_TIME 53 9795
TEST_TIME 54 9752
TEST_TIME 55 9716
TEST_TIME 56 9640
TEST_TIME 57 9664
TEST_TIME 58 9634
TEST_TIME 59 9553
TEST_TIME 60 9448
TEST_TIME 61 9462
TEST_TIME 62 9356
TEST_TIME 63 9273
TEST_TIME 64 9178
TEST_TIME 65 9137
TEST_TIME 66 9080
TEST_TIME 67 9046
TEST_TIME 68 8953
TEST_TIME 69 8905
TEST_TIME 70 8891
TEST_TIME 71 8893
TEST_TIME 72 8848
TEST_TIME 73 8799
TEST_TIME 74 8779
TEST_TIME 75 8794
TEST_TIME 76 8826
TEST_TIME 77 8831
TEST_TIME 78 8835
TEST_TIME 79 8867
TEST_TIME 80 8853
TEST_TIME 81 8835
TEST_TIME 82 8838
TEST_TIME 83 8850
TEST_TIME 84 8943
TEST_TIME 85 8924
TEST_TIME 86 8882
TEST_TIME 87 8906
TEST_TIME 88 8892
TEST_TIME 89 8868
TEST_TIME 90 8840
TEST_TIME 91 8774
TEST_TIME 92 8779
TEST_TIME 93 8779
TEST_TIME 94 8723
TEST_TIME 95 8720
TEST_TIME 96 8715
TEST_TIME 97 8659
TEST_TIME 98 8639
TEST_TIME 99 8604
TEST_TIME 100 8582
TEST_TIME 101 8567
TEST_TIME 102 8561
TEST_TIME 103 8552
TEST_TIME 104 8599
TEST_TIME 105 8582
TEST_TIME 106 8621
TEST_TIME 107 8664
TEST_TIME 108 8658
TEST_TIME 109 8707
TEST_TIME 110 8739
TEST_TIME 111 8725
TEST_TIME 112 8750
TEST_TIME 113 8748
TEST_TIME 114 8729
TEST_TIME 115 8663
TEST_TIME 116 8670
TEST_TIME 117 8683
TEST_TIME 118 8642
TEST_TIME 119 8635
TEST_TIME 120 8598

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-01-2023 07:39:47 to 13-01-2023 07:39:51.

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 1428720 1428720 0 0.00 0 19505 16 0 0
doc_list_biud 1428717 1428717 0 0.00 0 35975 50 0 0
sp_add_invoice_to_stock 4799 4682 117 2.43 53 202006 29442 0 117
sp_cancel_adding_invoice 5320 5320 0 0.00 5 103759 1456 0 0
sp_cancel_client_order 1602 1602 0 0.00 6 25867 505 0 0
sp_cancel_customer_reserve 515930 515930 0 0.00 1 28448 119 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 4626 181 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 4 5051 149 0 0
sp_cancel_supplier_invoice 5838 5831 7 0.11 4 24092 1207 0 7
sp_cancel_supplier_order 378 378 0 0.00 17 1633042 210990 0 0
sp_cancel_write_off 9796 9796 0 0.00 2 12005 202 0 0
sp_client_order 18270 18270 0 0.00 5 30421 499 0 0
sp_customer_reserve 434373 434373 0 0.00 0 98808 278 0 0
sp_fill_shopping_cart 28272 28272 0 0.00 1 26603 101 0 0
sp_fill_shopping_cart_clo_res 434351 434351 0 0.00 0 19614 20 0 0
sp_get_clo_for_invoice 4660 4660 0 0.00 0 35321 364 0 0
sp_kill_cost_storno 16140 16140 0 0.00 0 14610 98 0 0
sp_kill_qstorno_ret_qs2qd 1047865 1047865 0 0.00 0 28400 51 0 0
sp_kill_qty_storno 552652 552652 0 0.00 0 35975 119 0 0
sp_lock_selected_doc 19136 19136 0 0.00 0 10308 9 0 0
sp_make_cost_storno 25614 25614 0 0.00 0 11545 99 0 0
sp_multiply_rows_for_pdistr 25614 25614 0 0.00 0 13185 51 0 0
sp_multiply_rows_for_qdistr 18270 18270 0 0.00 0 17408 176 0 0
sp_pay_from_customer 6397 6397 0 0.00 5 10316 215 0 0
sp_pay_to_supplier 5422 5422 0 0.00 6 12110 244 0 0
sp_payment_common 11819 11819 0 0.00 2 8466 57 0 0
sp_qd_handle_on_cancel_clo 1602 1602 0 0.00 1 25858 422 0 0
sp_qd_handle_on_invoice_upd_sts 9980 9980 0 0.00 0 35975 690 0 0
sp_qd_handle_on_reserve_upd_sts 9135 9135 0 0.00 0 18557 77 0 0
sp_reserve_write_off 9135 9135 0 0.00 8 18639 333 0 0
sp_supplier_invoice 5054 5054 0 0.00 10 82826 3784 0 0
sp_supplier_order 4963 4963 0 0.00 8 26900 1461 0 0
srv_aggregate_perf_data 102 102 0 0.00 2121 187127 17684 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 756 148253 31522 0 0
srv_find_qd_qs_mism 984272 984272 0 0.00 0 19505 12 0 0
srv_make_invnt_saldo 376 376 0 0.00 52 123931 5556 0 0
srv_make_money_saldo 870 870 0 0.00 1 12792 456 0 0
srv_recalc_idx_stat 4 4 0 0.00 219284 491371 405116 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4687 4687 0 0.00 0 18614 1103 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 12497 539 0 0
t$perf-norm:sp_cancel_client_order 1602 1602 0 0.00 0 5879 38 0 0
t$perf-norm:sp_cancel_customer_reserve 605 605 0 0.00 0 1636 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 1981 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 1932 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 358 358 0 0.00 0 1988 39 0 0
t$perf-norm:sp_cancel_supplier_order 378 378 0 0.00 0 115074 9993 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 1757 20 0 0
t$perf-norm:sp_client_order 18270 18270 0 0.00 0 27364 43 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 6572 25 0 0
t$perf-norm:sp_pay_from_customer 6397 6397 0 0.00 0 17349 28 0 0
t$perf-norm:sp_pay_to_supplier 5423 5423 0 0.00 0 6843 23 0 0
t$perf-norm:sp_reserve_write_off 9135 9135 0 0.00 0 18844 29 0 0
t$perf-norm:sp_supplier_invoice 5055 5055 0 0.00 0 5815 38 0 0
t$perf-norm:sp_supplier_order 4963 4963 0 0.00 0 15716 43 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 570 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 46 4 0 0
t$perf-norm:srv_make_invnt_saldo 376 376 0 0.00 0 691 17 0 0
t$perf-norm:srv_make_money_saldo 870 870 0 0.00 0 2045 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 136 39 0 0
v_add_invoice_to_stock 9554 9320 234 2.44 0 9172 30 0 234
v_all_customers 33822 33822 0 0.00 0 80 4 0 0
v_all_suppliers 27994 27994 0 0.00 0 66 5 0 0
v_all_wares 36540 36540 0 0.00 0 59 5 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 3457 21 0 0
v_cancel_client_order 3204 3204 0 0.00 0 2772 11 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 2811 17 0 0
v_cancel_customer_reserve 1210 1210 0 0.00 0 3219 19 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 1392 15 0 0
v_cancel_supplier_invoice 730 716 14 1.91 0 1504 33 0 14
v_cancel_supplier_order 756 756 0 0.00 0 524 14 0 0
v_cancel_write_off 1956 1956 0 0.00 0 3215 30 0 0
v_max_id_clo_ord 4958 4958 0 0.00 0 333 6 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 2121 12 0 0
v_max_id_ord_sup 5044 5044 0 0.00 0 5751 9 0 0
v_max_non_paid_invoice 1427 1427 0 0.00 0 689 10 0 0
v_max_non_paid_realizn 3246 3246 0 0.00 0 8176 11 0 0
v_min_id_clo_ord 4958 4958 0 0.00 0 2329 10 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 536 9 0 0
v_min_id_ord_sup 5044 5044 0 0.00 0 6793 14 0 0
v_min_non_paid_invoice 5422 5422 0 0.00 0 8414 25 0 0
v_min_non_paid_realizn 6397 6397 0 0.00 0 4819 23 0 0
v_our_firm 9020 9020 0 0.00 0 61 5 0 0
v_reserve_write_off 18270 18270 0 0.00 0 10021 14 0 0
x_lock_depdocs_on_canc_invoice 10793 10793 0 0.00 0 15537 145 0 0
x_lock_depdocs_on_canc_sup_ord 378 378 0 0.00 0 5385 468 0 0
x_make_qstorno_1000_1200 4963 4963 0 0.00 3 26887 1255 0 0
x_make_qstorno_1200_2000 5054 5054 0 0.00 5 82757 3500 0 0
x_make_qstorno_2100_3300 434373 434373 0 0.00 0 98805 243 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-01-2023 07:39:52 to 13-01-2023 07:39:56.

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
05:07:28 936 8192000000 8930063040 8987824128 21062544 0.256 6 4 8 0 22246896 156944 1027408
05:08:30 22676 8192000000 9722840960 10021044224 801751072 8.914 100 91 182 1 831788880 7532656 22505152
05:09:59 23115 8192000000 9766965872 10074734592 831753072 9.217 100 86 172 0 870043920 18043152 20247696
05:11:27 10352 8192000000 9798955760 10118225920 865177792 9.552 100 82 164 0 909405936 24882544 19345600
05:12:45 9325 8192000000 9859078336 10186883072 902933904 9.927 100 83 164 1 964542032 43371056 18237072
05:13:56 2746 8192000000 9902508176 10241662976 940207072 10.295 100 90 170 0 1015618112 56744688 18666352
05:15:04 2000 8192000000 9961869232 10312568832 984187712 10.725 100 93 180 0 1076501568 73402784 18911072
05:16:09 1800 8192000000 10002668640 10364682240 1023428384 11.105 100 95 186 0 1120739424 78387344 18923696
05:17:14 2401 8192000000 10014993520 10381529088 1035734400 11.224 100 88 169 0 1140770480 89014624 16021456
05:18:18 1237 8192000000 10022541920 10392264704 1052242576 11.382 100 95 181 1 1132280400 62600816 17437008
05:19:22 838 8192000000 10039301264 10417942528 1064083600 11.496 100 96 185 3 1161554704 78968176 18502928
05:20:25 857 8192000000 10053315152 10428760064 1072222256 11.573 100 94 180 1 1172692400 82853760 17616384
05:21:28 1737 8192000000 10047365920 10436378624 1075816832 11.608 100 69 138 0 1169583040 80443872 13322336
05:22:30 1320 8192000000 10051173792 10436706304 1080237504 11.650 100 96 179 0 1172529264 72505216 19786544
05:23:33 1036 8192000000 10052642320 10436018176 1086592048 11.710 100 96 186 1 1178408240 72628400 19187792
05:24:36 1150 8192000000 10041853808 10428305408 1087244304 11.716 100 90 175 0 1164632608 59852240 17536064
05:25:38 3098 8192000000 10046971216 10444812288 1090114544 11.744 100 67 132 3 1179248096 75056336 14077216
05:26:42 1227 8192000000 10061576384 10448744448 1099326784 11.831 100 96 184 3 1178937664 61152496 18458384
05:27:44 1789 8192000000 10067808512 10458836992 1094511760 11.786 100 94 179 1 1180187504 67434256 18241488
05:28:47 756 8192000000 10067544368 10458419200 1098476080 11.823 100 94 180 0 1188804752 72034208 18294464
05:29:50 939 8192000000 10042999456 10434637824 1085214224 11.697 100 83 163 0 1164234080 61802288 17217568
05:30:52 1065 8192000000 10010427296 10403999744 1080969088 11.657 100 66 126 3 1136648592 41636016 14043488
05:31:54 1098 8192000000 10036892496 10417438720 1060374368 11.460 100 98 187 0 1154326400 74215680 19736352
05:32:56 1253 8192000000 10013780480 10391412736 1038264704 11.248 100 96 182 1 1137366464 80622144 18479616
05:33:59 2202 8192000000 10023066528 10402897920 1037522640 11.241 98 85 160 4 1146625344 92522128 16580576
05:35:06 1144 8192000000 9985594416 10356686848 1032314992 11.191 100 87 165 3 1109355040 59276800 17763248
05:36:08 1263 8192000000 9947105056 10321686528 1011109328 10.986 99 76 147 0 1068966752 41351840 16505584
05:37:10 14331 8192000000 9988938800 10380431360 1043506368 11.298 100 62 118 2 1123869008 67869584 12493056
05:38:50 14457 8192000000 9995596832 10379935744 1051669008 11.377 100 58 113 1 1108196096 45660128 10866960
05:40:11 3934 8192000000 10033123984 10417442816 1061871360 11.474 100 78 150 0 1144582624 66625440 16085824
05:41:21 25069 8192000000 10039762128 10446811136 1067146160 11.525 100 55 98 0 1160020016 81604464 11269392
05:42:51 1852 8192000000 10072880784 10467155968 1087045760 11.715 100 89 173 2 1191182464 84593968 19542736
05:43:57 9098 8192000000 10100443440 10489126912 1094681520 11.787 100 70 133 0 1215072000 106660704 13729776
05:45:13 4127 8192000000 10150906960 10544943104 1115354704 11.983 100 70 135 0 1264777312 134787104 14635504
05:46:20 890 8192000000 10178588064 10572513280 1123441760 12.059 100 97 179 2 1294487184 150096576 20948848
05:47:24 1332 8192000000 10196831904 10588688384 1146996256 12.281 100 91 161 1 1311775008 146332576 18446176
05:48:28 2393 8192000000 10253346928 10650443776 1161669392 12.419 100 91 174 1 1356526624 175826736 19030496
05:49:34 4047 8192000000 10280449376 10684698624 1170222912 12.499 100 90 172 0 1394500304 205950512 18326880
05:50:44 2882 8192000000 10310952112 10710470656 1178542848 12.577 100 89 170 0 1416951536 219305760 19102928
05:51:51 794 8192000000 10339149424 10745028608 1186380736 12.650 100 92 178 2 1449373648 242400880 20592032
05:52:55 2783 8192000000 10349515200 10762399744 1178990576 12.581 100 77 150 1 1462892448 266782240 17119632
05:54:02 2720 8192000000 10399575392 10804826112 1194827184 12.728 100 95 185 0 1510522832 294185040 21510608
05:55:09 1335 8192000000 10441290512 10844725248 1198806672 12.765 100 95 180 1 1547474432 328305824 20361936
05:56:18 760 8192000000 10467747040 10887208960 1204074304 12.814 100 92 184 0 1573848624 346588448 23185872
05:57:24 983 8192000000 10459521328 10891153408 1197661616 12.755 100 95 188 0 1565601472 345499056 22440800
05:58:30 878 8192000000 10498563840 10922913792 1209438112 12.864 100 96 187 0 1603427504 371672160 22317232
05:59:36 10377 8192000000 10477708064 10911412224 1197496624 12.753 100 51 99 0 1586165120 375895584 12772912
06:00:52 7857 8192000000 10494420944 10927472640 1197415312 12.752 100 57 114 0 1601395008 389916496 14063200
06:02:11 1991 8192000000 10473480448 10926886912 1183234896 12.620 100 60 119 0 1582932544 385484336 14213312
06:03:18 784 8192000000 10475639200 10921709568 1184922800 12.636 100 97 193 1 1579809904 372050512 22836592
06:04:22 1076 8192000000 10444392832 10921086976 1185273680 12.639 100 93 184 1 1546780016 339185456 22320880
06:05:26 768 8192000000 10427410304 10906877952 1186569536 12.651 100 96 187 1 1529113376 320902720 21641120
06:06:29 2809 8192000000 10463469760 10878566400 1190939600 12.692 100 79 150 0 1561096912 352335360 17821952
06:07:38 3519 8192000000 10533009088 10964619264 1188096096 12.666 100 63 125 0 1643434752 440023984 15314672
06:08:55 899 8192000000 10527582384 10954379264 1166730544 12.466 100 96 187 2 1627307520 439131584 21445392
06:09:58 745 8192000000 10572759136 11032567808 1186730896 12.653 100 88 177 0 1674850208 466659984 21459328
06:11:01 10614 8192000000 10573897680 10999951360 1162107632 12.423 99 56 110 0 1661606336 485757104 13741600
06:12:16 3479 8192000000 10646658832 11075321856 1178321776 12.575 100 66 129 0 1740228592 545058128 16848688
06:13:37 21514 8192000000 10615083408 11136163840 1174856144 12.542 100 44 86 1 1748159568 561303120 12000304
06:15:06 21924 8192000000 10493202496 10987229184 1167771392 12.476 100 38 76 0 1619352624 441333216 10248016
06:16:41 14520 8192000000 10442765744 10948947968 1164678736 12.447 100 37 74 0 1550704352 376182272 9843344
06:17:58 13266 8192000000 10331998240 10824146944 1147088192 12.282 99 30 58 0 1418807856 263916832 7802832
06:19:32 28136 8192000000 10187137936 10650595328 1157230016 12.377 100 44 83 3 1284536880 118613440 8693424
06:21:02 7626 8192000000 10172407936 10619158528 1169893600 12.496 100 52 102 0 1290098928 108714864 11490464
06:22:14 63379 8192000000 10217730736 10688450560 1149559984 12.305 99 20 40 0 1308465632 154255328 4650320
06:24:56 13335 8192000000 10223014688 10650112000 1147488528 12.286 100 71 138 1 1319175904 155900624 15786752
06:26:11 70626 8192000000 10137360016 10597568512 1135279712 12.171 99 15 30 0 1227309440 88736608 3293120
06:28:25 33243 8192000000 10113157168 10591162368 1142727392 12.241 99 44 72 2 1216115968 64447472 8941104
06:29:59 26363 8192000000 10144719008 10587111424 1136761232 12.185 99 55 95 1 1243483072 97136336 9585504
06:31:26 30477 8192000000 10140502336 10594934784 1155483968 12.361 100 62 114 1 1234709728 66717072 12508688
06:33:03 148226 8192000000 10083169904 10624413696 1144282400 12.256 99 24 44 0 1234465744 85252896 4930448
06:36:33 76679 8192000000 10093514048 10543239168 1106242736 11.897 96 12 24 0 1149312592 40297776 2772080
06:38:58 51212 8192000000 10065367552 10517082112 1140037408 12.216 100 27 52 0 1187487088 41567344 5882336
06:41:12 131654 8192000000 10068407072 10527281152 1101079232 11.848 96 21 42 0 1125553312 20165200 4308880
06:44:26 65979 8192000000 10085315152 10537177088 1127727744 12.100 98 13 26 0 1178506736 48346528 2432464
06:46:34 51535 8192000000 10097201760 10558898176 1118878768 12.016 97 27 53 1 1186251824 61954976 5418080
06:48:27 24763 8192000000 10084886704 10545905664 1135401088 12.172 99 18 36 0 1181020576 42218736 3400752
06:50:27 75577 8192000000 10119989184 10600873984 1143126064 12.245 99 11 22 0 1209164256 63480640 2557552
06:52:43 65946 8192000000 10127477696 10652979200 1159421536 12.398 100 14 28 0 1239329888 76868672 3039680
06:54:58 35532 8192000000 10100030032 10558971904 1138916704 12.205 99 36 72 0 1205004976 58605616 7482656
06:56:39 23711 8192000000 10077850000 10521227264 1115386256 11.983 98 29 56 2 1150946016 29626144 5933616
06:58:08 32890 8192000000 10127439264 10560733184 1134194832 12.161 100 48 94 0 1207783472 64268416 9320224
06:59:42 33348 8192000000 10107908848 10544394240 1086966960 11.714 96 38 76 0 1168784064 74344384 7472720
07:01:21 83232 8192000000 10041108384 10481053696 1090948000 11.752 97 21 41 0 1159577552 64357824 4271728
07:03:54 11746 8192000000 10100437584 10527408128 1117592768 12.004 100 45 90 0 1194395184 68067504 8734912
07:05:15 8115 8192000000 10083123328 10503000064 1097828192 11.817 98 55 108 0 1169109440 60426432 10854816
07:06:24 124940 8192000000 10105042000 10557734912 1103077952 11.867 98 32 64 0 1172856208 62423232 7355024
07:09:34 58369 8192000000 10078719968 10601639936 1125959952 12.083 100 8 16 0 1181173184 53551472 1661760
07:11:34 58570 8192000000 10082392352 10529632256 1118091952 12.009 99 16 31 0 1170117120 48771584 3253584
07:13:50 60019 8192000000 10050850800 10513760256 1129946352 12.121 100 11 21 0 1177714368 45652272 2115744
07:15:50 54243 8192000000 10046774272 10515009536 1130176032 12.123 100 17 32 0 1154541616 21247840 3117744
07:17:50 49707 8192000000 10084251328 10547687424 1105766704 11.892 97 17 32 0 1168668224 60013360 2888160
07:19:41 37806 8192000000 10104851728 10550915072 1132243472 12.143 99 19 38 0 1193615648 57370336 4001840
07:21:23 43339 8192000000 10098729520 10545078272 1138434688 12.201 100 23 46 0 1197054736 53998240 4621808
07:23:07 23520 8192000000 10154093040 10592288768 1135805776 12.176 99 46 90 1 1230107712 85336960 8964976
07:24:46 67694 8192000000 10143600112 10585194496 1149807600 12.308 100 21 43 0 1228308352 73969856 4530896
07:26:54 56056 8192000000 10112279472 10619719680 1126384720 12.087 98 35 70 0 1206520752 73278048 6857984
07:28:52 51052 8192000000 10124910768 10607316992 1145647888 12.269 100 26 51 0 1200444704 50164224 4632592
07:30:46 38245 8192000000 10137700000 10577764352 1142087232 12.235 99 32 64 1 1214437952 65939840 6410880
07:32:24 105202 8192000000 10072197168 10592837632 1110660240 11.939 97 19 38 0 1202998384 88140432 4197712
07:35:10 99286 8192000000 10126036976 10622730240 1142657456 12.241 100 10 20 0 1207754048 62943472 2153120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-01-2023 07:39:58 to 13-01-2023 07:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-01-2023 07:40:15 to 13-01-2023 07:40:17.

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          206856
MON$OLDEST_ACTIVE               208556
MON$OLDEST_SNAPSHOT             208556
MON$NEXT_TRANSACTION            208556
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                       2131968
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             751
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 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 ---
:290: 
:291: mscs-test-161.budzdorov.ru    Fri Jan 13 07:37:44 2023
:292:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:293: 
:294: 
:295: mscs-test-161.budzdorov.ru    Fri Jan 13 07:37:44 2023
:296:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:297: 
:298: 
:299: mscs-test-161.budzdorov.ru    Fri Jan 13 07:37:44 2023
:300:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:301: 
:302: 
:303: mscs-test-161.budzdorov.ru    Fri Jan 13 07:37:44 2023
:304:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:305: 
:306: 
:307: mscs-test-161.budzdorov.ru    Fri Jan 13 07:37:44 2023
:308:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:309: 
:310: 
:311: mscs-test-161.budzdorov.ru    Fri Jan 13 07:37:44 2023
:312:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:313: 
:314: 
:315: mscs-test-161.budzdorov.ru    Fri Jan 13 07:37:44 2023
:316:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:317: 
:318: 
:319: mscs-test-161.budzdorov.ru    Fri Jan 13 07:37:44 2023
:320:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:321: 
--- end of diff output ---

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

13.01.23 07:40:18. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-01-13 07:40:21 No severe PSQL-related problems occured
13.01.23 07:40:22. End of report.