Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.03.2023 16: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 15234504 kB
MemAvailable 30400728 kB
Buffers 0 kB
Cached 16452104 kB
SwapCached 3232 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 2023-03-14 16:39:02
DTS_END                         2023-03-14 16:38:48.4320
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44974
TRN_ID                          234204
ATT_ID                          274
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: 9180.93 19089 1101712
customer order: creation 164.18 368 19702
customer order: refuse 14.05 349 1686
order to supplier: creation 43.94 1366 5273
order to supplier: removal 3.58 101548 429
invoice (draft): creation 45.52 3532 5462
invoice (draft): removal 53.62 830 6434
invoice accept: apply 39.73 17665 4767
invoice accept: cancel 48.28 766 5794
customer reserve: creation 3735.63 172 448276
customer reserve: removal 4740.14 60 568817
realization accept: apply 80.81 188 9697
realization accept: cancel 89.17 106 10700
payment from customer: creation 55.45 137 6654
payment from customer: removal 5.53 108 663
payment to supplier: creation 46.94 151 5633
payment to supplier: removal 3.29 75 395
service: total inventory turnovers 4.20 1305 504
service: total monetary turnovers 6.14 33 737
service: aggregate perf. data 0.71 27393 85
service: refresh index statistics 0.03 225619 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-03-2023 16:39:42 to 14-03-2023 16:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4053
TEST_TIME 2 3600
TEST_TIME 3 4167
TEST_TIME 4 4470
TEST_TIME 5 4216
TEST_TIME 6 4810
TEST_TIME 7 4565
TEST_TIME 8 5082
TEST_TIME 9 5558
TEST_TIME 10 5879
TEST_TIME 11 6160
TEST_TIME 12 6394
TEST_TIME 13 6425
TEST_TIME 14 6467
TEST_TIME 15 6750
TEST_TIME 16 7037
TEST_TIME 17 7146
TEST_TIME 18 7372
TEST_TIME 19 7669
TEST_TIME 20 7928
TEST_TIME 21 7977
TEST_TIME 22 8109
TEST_TIME 23 8427
TEST_TIME 24 8555
TEST_TIME 25 8625
TEST_TIME 26 8501
TEST_TIME 27 8707
TEST_TIME 28 9130
TEST_TIME 29 9326
TEST_TIME 30 9343
TEST_TIME 31 10014
TEST_TIME 32 10939
TEST_TIME 33 10695
TEST_TIME 34 10642
TEST_TIME 35 10501
TEST_TIME 36 10395
TEST_TIME 37 10360
TEST_TIME 38 10314
TEST_TIME 39 10162
TEST_TIME 40 10140
TEST_TIME 41 10060
TEST_TIME 42 10025
TEST_TIME 43 9848
TEST_TIME 44 9771
TEST_TIME 45 9720
TEST_TIME 46 9644
TEST_TIME 47 9759
TEST_TIME 48 9704
TEST_TIME 49 9566
TEST_TIME 50 9549
TEST_TIME 51 9476
TEST_TIME 52 9600
TEST_TIME 53 9570
TEST_TIME 54 9451
TEST_TIME 55 9418
TEST_TIME 56 9375
TEST_TIME 57 9220
TEST_TIME 58 9280
TEST_TIME 59 9254
TEST_TIME 60 9181
TEST_TIME 61 9151
TEST_TIME 62 9093
TEST_TIME 63 9183
TEST_TIME 64 9194
TEST_TIME 65 9181
TEST_TIME 66 9236
TEST_TIME 67 9236
TEST_TIME 68 9243
TEST_TIME 69 9189
TEST_TIME 70 9239
TEST_TIME 71 9264
TEST_TIME 72 9274
TEST_TIME 73 9254
TEST_TIME 74 9339
TEST_TIME 75 9378
TEST_TIME 76 9348
TEST_TIME 77 9376
TEST_TIME 78 9274
TEST_TIME 79 9269
TEST_TIME 80 9229
TEST_TIME 81 9171
TEST_TIME 82 9186
TEST_TIME 83 9189
TEST_TIME 84 9120
TEST_TIME 85 9104
TEST_TIME 86 9069
TEST_TIME 87 9103
TEST_TIME 88 9105
TEST_TIME 89 9120
TEST_TIME 90 9103
TEST_TIME 91 9112
TEST_TIME 92 9120
TEST_TIME 93 9205
TEST_TIME 94 9152
TEST_TIME 95 9227
TEST_TIME 96 9302
TEST_TIME 97 9261
TEST_TIME 98 9267
TEST_TIME 99 9264
TEST_TIME 100 9314
TEST_TIME 101 9302
TEST_TIME 102 9318
TEST_TIME 103 9296
TEST_TIME 104 9259
TEST_TIME 105 9219
TEST_TIME 106 9235
TEST_TIME 107 9218
TEST_TIME 108 9188
TEST_TIME 109 9166
TEST_TIME 110 9138
TEST_TIME 111 9100
TEST_TIME 112 9105
TEST_TIME 113 9073
TEST_TIME 114 9093
TEST_TIME 115 9102
TEST_TIME 116 9103
TEST_TIME 117 9122
TEST_TIME 118 9151
TEST_TIME 119 9173
TEST_TIME 120 9182

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-03-2023 16:39:46 to 14-03-2023 16:39:49.

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 1517184 1517184 0 0.00 0 25422 6 0 0
doc_list_biud 1517181 1517181 0 0.00 0 22543 29 0 0
sp_add_invoice_to_stock 4843 4767 76 1.56 48 175166 17666 0 76
sp_cancel_adding_invoice 5794 5794 0 0.00 4 75485 766 0 0
sp_cancel_client_order 1686 1686 0 0.00 4 12594 349 0 0
sp_cancel_customer_reserve 568817 568817 0 0.00 0 21077 61 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 10986 109 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 4560 75 0 0
sp_cancel_supplier_invoice 6439 6434 5 0.07 1 14446 831 0 5
sp_cancel_supplier_order 429 429 0 0.00 5 1135962 101549 0 0
sp_cancel_write_off 10700 10700 0 0.00 1 21803 107 0 0
sp_client_order 19702 19702 0 0.00 3 75732 368 0 0
sp_customer_reserve 448276 448276 0 0.00 0 35487 172 0 0
sp_fill_shopping_cart 30421 30421 0 0.00 1 11399 44 0 0
sp_fill_shopping_cart_clo_res 448273 448273 0 0.00 0 10667 15 0 0
sp_get_clo_for_invoice 4764 4764 0 0.00 0 48294 334 0 0
sp_kill_cost_storno 17552 17552 0 0.00 0 21679 54 0 0
sp_kill_qstorno_ret_qs2qd 1155197 1155197 0 0.00 0 22538 33 0 0
sp_kill_qty_storno 608320 608320 0 0.00 0 22542 70 0 0
sp_lock_selected_doc 20102 20102 0 0.00 0 8799 5 0 0
sp_make_cost_storno 26748 26748 0 0.00 0 25412 56 0 0
sp_multiply_rows_for_pdistr 26748 26748 0 0.00 0 24218 31 0 0
sp_multiply_rows_for_qdistr 19702 19702 0 0.00 0 75693 157 0 0
sp_pay_from_customer 6654 6654 0 0.00 4 24457 137 0 0
sp_pay_to_supplier 5633 5633 0 0.00 4 24728 152 0 0
sp_payment_common 12286 12286 0 0.00 1 23747 60 0 0
sp_qd_handle_on_cancel_clo 1686 1686 0 0.00 0 12588 323 0 0
sp_qd_handle_on_invoice_upd_sts 10558 10558 0 0.00 0 17888 302 0 0
sp_qd_handle_on_reserve_upd_sts 9696 9696 0 0.00 0 22495 61 0 0
sp_reserve_write_off 9697 9697 0 0.00 6 24895 188 0 0
sp_supplier_invoice 5462 5462 0 0.00 6 96913 3532 0 0
sp_supplier_order 5273 5273 0 0.00 4 40178 1367 0 0
srv_aggregate_perf_data 85 85 0 0.00 2335 291262 27394 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2247 125945 42178 0 0
srv_find_qd_qs_mism 1056086 1056086 0 0.00 0 12710 5 0 0
srv_make_invnt_saldo 504 504 0 0.00 26 97714 1306 0 0
srv_make_money_saldo 737 737 0 0.00 0 5772 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 100719 306165 225620 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4767 4767 0 0.00 0 14473 186 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 5724 112 0 0
t$perf-norm:sp_cancel_client_order 1686 1686 0 0.00 0 2803 13 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 1837 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 1923 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 5183 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 4329 30 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 24078 1824 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 2356 10 0 0
t$perf-norm:sp_client_order 19702 19702 0 0.00 0 21348 20 0 0
t$perf-norm:sp_customer_reserve 936 936 0 0.00 0 2630 13 0 0
t$perf-norm:sp_pay_from_customer 6654 6654 0 0.00 0 5785 15 0 0
t$perf-norm:sp_pay_to_supplier 5633 5633 0 0.00 0 4596 14 0 0
t$perf-norm:sp_reserve_write_off 9697 9697 0 0.00 0 8835 14 0 0
t$perf-norm:sp_supplier_invoice 5462 5462 0 0.00 0 8831 20 0 0
t$perf-norm:sp_supplier_order 5273 5273 0 0.00 0 7066 20 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 209 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 8647 136 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 1064 21 0 0
t$perf-norm:srv_make_money_saldo 737 737 0 0.00 0 1711 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9678 9526 152 1.57 0 5427 19 0 152
v_all_customers 36064 36064 0 0.00 0 22 0 0 0
v_all_suppliers 29730 29730 0 0.00 0 27 1 0 0
v_all_wares 39400 39400 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 1630 11 0 0
v_cancel_client_order 3372 3372 0 0.00 0 2112 6 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 1618 5 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 532 3 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 4544 13 0 0
v_cancel_supplier_invoice 806 796 10 1.24 0 5029 18 0 10
v_cancel_supplier_order 858 858 0 0.00 0 3137 8 0 0
v_cancel_write_off 1974 1974 0 0.00 0 2663 14 0 0
v_max_id_clo_ord 5270 5270 0 0.00 0 1208 1 0 0
v_max_id_clo_res 936 936 0 0.00 0 3572 25 0 0
v_max_id_ord_sup 5450 5450 0 0.00 0 4479 7 0 0
v_max_non_paid_invoice 1487 1487 0 0.00 0 1372 5 0 0
v_max_non_paid_realizn 3482 3482 0 0.00 0 573 3 0 0
v_min_id_clo_ord 5270 5270 0 0.00 0 5484 10 0 0
v_min_id_clo_res 936 936 0 0.00 0 2811 11 0 0
v_min_id_ord_sup 5450 5450 0 0.00 0 4090 7 0 0
v_min_non_paid_invoice 5632 5632 0 0.00 0 23167 46 0 0
v_min_non_paid_realizn 6654 6654 0 0.00 0 23132 49 0 0
v_our_firm 9680 9680 0 0.00 0 12 1 0 0
v_reserve_write_off 19392 19392 0 0.00 0 5093 5 0 0
x_lock_depdocs_on_canc_invoice 11830 11830 0 0.00 0 31687 115 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 11767 369 0 0
x_make_qstorno_1000_1200 5273 5273 0 0.00 2 40125 1290 0 0
x_make_qstorno_1200_2000 5462 5462 0 0.00 3 95171 3366 0 0
x_make_qstorno_2100_3300 448276 448276 0 0.00 0 33104 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-03-2023 16:39:50 to 14-03-2023 16:39:54.

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
14:08:52 38 8192000000 8825193952 8845766656 23191952 0.282 9 4 6 0 23436400 94720 149728
14:09:53 28898 8192000000 9897152240 10206380032 971938896 10.606 100 86 172 0 999471120 5332000 22200224
14:11:24 19182 8192000000 9946612080 10270220288 1031786112 11.186 100 84 168 0 1061335568 7714576 21834880
14:12:49 4223 8192000000 9988771360 10320936960 1074418064 11.594 100 82 164 0 1104516928 9607216 20491648
14:14:02 6675 8192000000 10020939936 10367213568 1114229136 11.972 100 73 146 0 1142205232 10559088 17417008
14:15:24 4094 8192000000 10082831904 10439004160 1167294720 12.472 100 77 154 0 1198216688 13631920 17290048
14:16:30 3646 8192000000 10116028112 10484482048 1212002384 12.888 100 82 165 0 1243110640 12844176 18264080
14:17:36 2981 8192000000 10154394304 10529341440 1245008560 13.192 100 90 179 1 1278723344 13507488 20207296
14:18:42 1724 8192000000 10183687872 10564423680 1274100784 13.459 100 80 158 0 1304182864 12445776 17636304
14:19:47 1164 8192000000 10214961504 10598752256 1297718496 13.674 100 90 178 1 1331167312 13816304 19632512
14:20:51 10306 8192000000 10205922960 10599809024 1300724512 13.702 100 62 124 0 1325505616 11153648 13627456
14:22:03 5644 8192000000 10202080944 10606329856 1310209568 13.788 100 38 74 1 1327292272 9007344 8075360
14:23:09 6890 8192000000 10201178864 10614730752 1313906448 13.822 100 25 50 0 1326046416 6442816 5697152
14:24:19 7965 8192000000 10202752384 10620256256 1320875856 13.885 100 18 36 0 1330684256 5903184 3905216
14:25:27 60628 8192000000 10216514912 10636840960 1326601392 13.936 100 30 58 0 1339319616 5708976 7009248
14:27:31 5432 8192000000 10228192864 10644774912 1330285344 13.970 100 37 74 0 1346329584 7788544 8255696
14:28:37 4534 8192000000 10212286496 10625622016 1306521120 13.754 99 24 49 0 1318021232 5647488 5852624
14:29:42 13739 8192000000 10192938304 10605895680 1276741712 13.483 98 18 36 0 1286267584 5363216 4162656
14:30:57 34690 8192000000 10153923520 10561003520 1198968928 12.767 93 15 30 0 1208329760 6051392 3309440
14:32:33 7296 8192000000 10129572496 10531745792 1215830832 12.923 97 23 46 1 1226615744 5577152 5207760
14:33:41 7175 8192000000 10164993824 10564947968 1267148416 13.396 100 25 50 1 1278769152 5469920 6150816
14:34:49 15554 8192000000 10171045168 10579169280 1279405856 13.508 100 23 45 0 1290017136 5628560 4982720
14:36:33 11095 8192000000 10185078400 10600554496 1292542592 13.627 100 32 62 1 1310056896 10296528 7217776
14:37:46 10155 8192000000 10192307120 10607017984 1303562976 13.728 100 22 43 1 1313791728 4961424 5267328
14:38:59 10401 8192000000 10239131296 10646634496 1330722736 13.974 100 34 64 5 1342863392 4624112 7516544
14:40:24 63810 8192000000 10262686464 10685632512 1355544048 14.197 100 30 61 0 1376444304 13973456 6926800
14:42:47 20552 8192000000 10282701952 10702712832 1369703936 14.324 100 32 64 1 1392108160 15419504 6984720
14:44:19 11076 8192000000 10289749568 10717155328 1382283488 14.437 100 39 76 0 1404588720 12936208 9369024
14:45:33 10505 8192000000 10290730368 10711339008 1378564672 14.404 100 53 106 1 1405166816 14453984 12148160
14:46:53 26195 8192000000 10308977456 10731819008 1391875424 14.523 100 50 100 1 1422458656 18850704 11732528
14:48:24 24050 8192000000 10304166384 10739232768 1383744768 14.450 99 42 84 0 1414281680 19612000 10924912
14:49:54 8417 8192000000 10323165424 10743304192 1393828640 14.540 100 53 106 0 1427326560 20793872 12704048
14:51:14 6810 8192000000 10333850352 10750398464 1399220304 14.588 100 53 105 1 1433451600 21428704 12802592
14:52:26 7684 8192000000 10334105808 10754134016 1400131072 14.596 100 57 114 0 1440092896 25382400 14579424
14:53:37 2246 8192000000 10324727408 10736803840 1378606912 14.404 98 66 129 3 1421587600 26605600 16375088
14:54:51 15500 8192000000 10342450272 10770751488 1406930848 14.657 100 52 102 1 1450736528 30311072 13494608
14:56:12 5811 8192000000 10352978496 10776178688 1409686048 14.681 100 58 114 0 1457182736 32371888 15124800
14:57:21 2905 8192000000 10343186912 10755661824 1384510608 14.457 99 73 139 1 1435324080 32877856 17935616
14:58:33 5194 8192000000 10338387360 10763620352 1400493200 14.599 100 42 82 0 1445027216 32878176 11655840
14:59:42 8865 8192000000 10328631168 10754985984 1389658512 14.503 100 45 88 0 1436415744 35682672 11074560
15:00:55 7931 8192000000 10329382064 10747039744 1360835264 14.245 98 39 78 0 1412819392 42020720 9963408
15:02:08 9572 8192000000 10329361568 10753593344 1379951776 14.416 100 37 74 0 1432095696 42374080 9769840
15:03:25 8236 8192000000 10332968640 10750099456 1369030208 14.318 99 38 76 0 1421991760 42689328 10272224
15:04:36 17413 8192000000 10340985184 10765262848 1383321728 14.446 100 34 68 1 1445028416 52289264 9417424
15:05:59 19793 8192000000 10336465632 10767712256 1375176672 14.373 100 31 62 1 1443280784 58997728 9106384
15:07:21 16000 8192000000 10322682608 10753806336 1365470896 14.286 100 31 62 1 1429184832 54720080 8993856
15:09:00 28352 8192000000 10309591760 10741391360 1352236544 14.168 100 25 52 0 1420236080 60085216 7914320
15:10:29 66829 8192000000 10277826832 10712281088 1351986736 14.165 99 28 57 1 1376357008 17654928 6715344
15:12:50 52567 8192000000 10281519840 10727202816 1376050944 14.381 100 20 39 0 1393078432 12925760 4101728
15:14:46 124753 8192000000 10288598896 10749227008 1368085856 14.310 99 18 37 2 1387009056 15017824 3905376
15:17:54 125944 8192000000 10293786816 10755616768 1368888128 14.317 98 21 44 1 1388239824 14211744 5139952
15:21:06 62682 8192000000 10302598096 10750873600 1377670976 14.396 99 49 96 1 1400041456 11000448 11370032
15:23:14 17296 8192000000 10317736432 10756132864 1396213440 14.561 100 24 48 2 1412541648 10380496 5947712
15:24:37 72513 8192000000 10297636224 10753966080 1369603648 14.323 98 47 93 2 1401968816 21541072 10824096
15:27:20 10159 8192000000 10289581984 10732064768 1378023712 14.399 99 38 75 1 1397320032 11138928 8157392
15:28:34 22510 8192000000 10307707872 10755104768 1398792480 14.584 100 27 54 0 1417454688 12505376 6156832
15:29:59 39368 8192000000 10303450656 10764038144 1398179024 14.579 100 35 69 1 1423693520 17154080 8360416
15:31:41 35142 8192000000 10281185200 10744733696 1380333968 14.420 99 7 14 0 1390951936 9532880 1085088
15:33:22 87379 8192000000 10284487200 10755493888 1381465168 14.430 99 13 26 0 1395841952 10740112 3636672
15:35:55 67125 8192000000 10277999776 10742902784 1371078000 14.337 98 38 76 0 1392533520 12740528 8714992
15:38:09 97448 8192000000 10295528912 10763796480 1387620160 14.485 99 17 33 0 1398676288 7400192 3655936
15:40:48 62883 8192000000 10303823664 10775580672 1391549456 14.520 99 11 22 0 1404315392 10712032 2053904
15:42:59 43248 8192000000 10293164912 10738597888 1357784320 14.217 97 23 44 0 1372150160 9651792 4714048
15:44:45 78800 8192000000 10260650240 10704019456 1259120624 13.322 91 10 20 0 1270106304 8763248 2222432
15:47:06 19065 8192000000 10277272976 10721599488 1366380144 14.295 100 23 46 0 1380035712 8617856 5037712
15:48:28 43603 8192000000 10279173184 10737451008 1368161440 14.311 99 13 26 0 1383599936 12318992 3119504
15:50:15 45689 8192000000 10270699488 10714656768 1334505648 14.008 98 27 54 0 1352002128 11279248 6217232
15:52:30 68302 8192000000 10277946016 10725859328 1333821024 14.002 97 19 38 0 1352494592 14555232 4118336
15:54:42 27269 8192000000 10267961216 10702237696 1329521888 13.963 98 39 79 0 1349235280 11299376 8414016
15:56:14 77024 8192000000 10225028176 10654408704 1290043616 13.605 96 29 58 0 1305770896 9490880 6236400
15:58:37 66660 8192000000 10259815264 10702946304 1344196880 14.095 99 22 44 0 1359496448 9822048 5477520
16:00:55 72491 8192000000 10245915808 10704699392 1325163392 13.923 98 5 10 0 1343508048 17463696 880960
16:03:08 56501 8192000000 10252759088 10708422656 1349690800 14.145 99 9 18 0 1363683936 12030480 1962656
16:05:08 68101 8192000000 10253600912 10717028352 1348349376 14.133 99 13 24 0 1360258208 9021760 2887072
16:07:17 72187 8192000000 10291246720 10732875776 1354477232 14.188 99 20 38 0 1371988032 13478800 4032000
16:09:37 47268 8192000000 10276887904 10724564992 1356559792 14.206 99 25 50 0 1373570064 11522768 5487504
16:11:29 70241 8192000000 10294079408 10740994048 1373256864 14.356 100 13 26 0 1391433440 15535008 2641568
16:13:39 63631 8192000000 10288362688 10756620288 1376814800 14.388 99 11 22 0 1396347248 16813280 2719168
16:15:43 17995 8192000000 10307846016 10745266176 1385977712 14.470 100 51 103 0 1408430304 10824960 11627632
16:17:04 53245 8192000000 10300662064 10744799232 1360632256 14.243 98 25 48 0 1376245136 10378080 5234800
16:19:03 27396 8192000000 10309662400 10743107584 1347674768 14.127 97 45 91 1 1366878704 9671600 9532336
16:20:34 43969 8192000000 10294793808 10744922112 1379450848 14.412 100 34 68 0 1397837856 11036944 7350064
16:22:18 82764 8192000000 10291566928 10742042624 1343073728 14.085 97 23 45 0 1356099872 8143088 4883056
16:24:46 22546 8192000000 10274932224 10739298304 1367742000 14.307 99 7 14 0 1384378272 15420016 1216256
16:26:36 39637 8192000000 10271911616 10726871040 1364387184 14.277 99 11 22 0 1376854656 10024016 2443456
16:28:19 90857 8192000000 10274087744 10729566208 1345083664 14.103 97 14 28 0 1357903904 9334096 3486144
16:30:56 41479 8192000000 10285193440 10751557632 1377444368 14.394 99 19 38 1 1393409968 11081104 4884496
16:32:40 40841 8192000000 10266889152 10715086848 1341361472 14.070 97 27 54 1 1361822400 14690112 5770816
16:34:23 52794 8192000000 10307068304 10755756032 1382173936 14.436 99 38 76 0 1402006720 11666208 8166576
16:36:25 68200 8192000000 10299314320 10759286784 1398821664 14.585 100 17 34 0 1415107440 12133344 4152432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-03-2023 16:39:56 to 14-03-2023 16:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-03-2023 16:40:13 to 14-03-2023 16:40:15.

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          244331
MON$OLDEST_ACTIVE               244332
MON$OLDEST_SNAPSHOT             244332
MON$NEXT_TRANSACTION            244332
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                       2034464
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.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 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 ---
--- end of diff output ---

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

14.03.23 16:40:16. 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
2023-03-14 16:40:18 No severe PSQL-related problems occured
14.03.23 16:40:20. End of report.