Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.01.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 15410876 kB
MemAvailable 30419288 kB
Buffers 0 kB
Cached 16283088 kB
SwapCached 1856 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-01-19 16:38:38
DTS_END                         2023-01-19 16:38:38.9760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36218
TRN_ID                          241771
ATT_ID                          695
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: 9287.15 17931 1114458
customer order: creation 175.71 356 21085
customer order: refuse 15.01 329 1801
order to supplier: creation 46.38 1187 5565
order to supplier: removal 3.36 96500 403
invoice (draft): creation 46.93 2973 5631
invoice (draft): removal 52.96 731 6355
invoice accept: apply 43.01 17661 5161
invoice accept: cancel 47.47 833 5696
customer reserve: creation 3917.16 178 470059
customer reserve: removal 4639.62 55 556754
realization accept: apply 83.93 182 10071
realization accept: cancel 85.18 107 10222
payment from customer: creation 59.17 145 7100
payment from customer: removal 5.59 140 671
payment to supplier: creation 50.40 131 6048
payment to supplier: removal 3.64 66 437
service: total inventory turnovers 4.33 1104 520
service: total monetary turnovers 6.49 26 779
service: aggregate perf. data 0.80 21607 96
service: refresh index statistics 0.03 214317 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-01-2023 16:39:55 to 19-01-2023 16:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2350
TEST_TIME 2 4403
TEST_TIME 3 5320
TEST_TIME 4 5128
TEST_TIME 5 5122
TEST_TIME 6 5204
TEST_TIME 7 5892
TEST_TIME 8 6029
TEST_TIME 9 5865
TEST_TIME 10 6023
TEST_TIME 11 6335
TEST_TIME 12 6212
TEST_TIME 13 6151
TEST_TIME 14 6034
TEST_TIME 15 5837
TEST_TIME 16 6116
TEST_TIME 17 6233
TEST_TIME 18 6486
TEST_TIME 19 6782
TEST_TIME 20 7236
TEST_TIME 21 7803
TEST_TIME 22 8129
TEST_TIME 23 8735
TEST_TIME 24 8650
TEST_TIME 25 8686
TEST_TIME 26 8880
TEST_TIME 27 9007
TEST_TIME 28 9489
TEST_TIME 29 9702
TEST_TIME 30 10014
TEST_TIME 31 10103
TEST_TIME 32 9947
TEST_TIME 33 10035
TEST_TIME 34 10171
TEST_TIME 35 10213
TEST_TIME 36 10069
TEST_TIME 37 9981
TEST_TIME 38 9791
TEST_TIME 39 9687
TEST_TIME 40 9672
TEST_TIME 41 9627
TEST_TIME 42 9523
TEST_TIME 43 9577
TEST_TIME 44 9569
TEST_TIME 45 9470
TEST_TIME 46 9346
TEST_TIME 47 9349
TEST_TIME 48 9304
TEST_TIME 49 9272
TEST_TIME 50 9313
TEST_TIME 51 9236
TEST_TIME 52 9312
TEST_TIME 53 9236
TEST_TIME 54 9120
TEST_TIME 55 9045
TEST_TIME 56 9013
TEST_TIME 57 8965
TEST_TIME 58 8929
TEST_TIME 59 8997
TEST_TIME 60 8991
TEST_TIME 61 9132
TEST_TIME 62 9112
TEST_TIME 63 9198
TEST_TIME 64 9168
TEST_TIME 65 9271
TEST_TIME 66 9291
TEST_TIME 67 9337
TEST_TIME 68 9423
TEST_TIME 69 9399
TEST_TIME 70 9440
TEST_TIME 71 9427
TEST_TIME 72 9421
TEST_TIME 73 9341
TEST_TIME 74 9315
TEST_TIME 75 9288
TEST_TIME 76 9270
TEST_TIME 77 9232
TEST_TIME 78 9194
TEST_TIME 79 9186
TEST_TIME 80 9126
TEST_TIME 81 9155
TEST_TIME 82 9185
TEST_TIME 83 9228
TEST_TIME 84 9213
TEST_TIME 85 9246
TEST_TIME 86 9276
TEST_TIME 87 9254
TEST_TIME 88 9245
TEST_TIME 89 9267
TEST_TIME 90 9451
TEST_TIME 91 9473
TEST_TIME 92 9474
TEST_TIME 93 9467
TEST_TIME 94 9500
TEST_TIME 95 9438
TEST_TIME 96 9393
TEST_TIME 97 9344
TEST_TIME 98 9309
TEST_TIME 99 9338
TEST_TIME 100 9319
TEST_TIME 101 9324
TEST_TIME 102 9298
TEST_TIME 103 9271
TEST_TIME 104 9243
TEST_TIME 105 9262
TEST_TIME 106 9249
TEST_TIME 107 9237
TEST_TIME 108 9207
TEST_TIME 109 9284
TEST_TIME 110 9292
TEST_TIME 111 9286
TEST_TIME 112 9306
TEST_TIME 113 9345
TEST_TIME 114 9355
TEST_TIME 115 9328
TEST_TIME 116 9377
TEST_TIME 117 9352
TEST_TIME 118 9353
TEST_TIME 119 9359
TEST_TIME 120 9288

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-01-2023 16:39:59 to 19-01-2023 16:40:02.

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 1550760 1550760 0 0.00 0 33417 6 0 0
doc_list_biud 1550760 1550760 0 0.00 0 51729 25 0 0
sp_add_invoice_to_stock 5257 5161 96 1.82 35 154695 17661 0 96
sp_cancel_adding_invoice 5696 5696 0 0.00 4 100105 834 0 0
sp_cancel_client_order 1801 1801 0 0.00 4 6500 330 0 0
sp_cancel_customer_reserve 556754 556754 0 0.00 1 16069 56 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 2 15392 141 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 4556 66 0 0
sp_cancel_supplier_invoice 6362 6355 7 0.11 1 51737 731 0 7
sp_cancel_supplier_order 403 403 0 0.00 7 895229 96501 0 0
sp_cancel_write_off 10222 10222 0 0.00 1 23689 108 0 0
sp_client_order 21085 21085 0 0.00 3 33119 357 0 0
sp_customer_reserve 470059 470059 0 0.00 0 66650 178 0 0
sp_fill_shopping_cart 32278 32278 0 0.00 1 10447 36 0 0
sp_fill_shopping_cart_clo_res 470055 470055 0 0.00 0 14584 13 0 0
sp_get_clo_for_invoice 5157 5157 0 0.00 0 17329 247 0 0
sp_kill_cost_storno 17026 17026 0 0.00 0 23624 59 0 0
sp_kill_qstorno_ret_qs2qd 1130488 1130488 0 0.00 0 51727 30 0 0
sp_kill_qty_storno 596459 596459 0 0.00 0 51728 65 0 0
sp_lock_selected_doc 21109 21109 0 0.00 0 8681 6 0 0
sp_make_cost_storno 28376 28376 0 0.00 0 23567 58 0 0
sp_multiply_rows_for_pdistr 28376 28376 0 0.00 0 19389 33 0 0
sp_multiply_rows_for_qdistr 21085 21085 0 0.00 0 17335 160 0 0
sp_pay_from_customer 7100 7100 0 0.00 4 22226 146 0 0
sp_pay_to_supplier 6048 6048 0 0.00 4 22864 132 0 0
sp_payment_common 13148 13148 0 0.00 1 22848 40 0 0
sp_qd_handle_on_cancel_clo 1801 1801 0 0.00 0 5975 307 0 0
sp_qd_handle_on_invoice_upd_sts 10853 10853 0 0.00 0 19634 322 0 0
sp_qd_handle_on_reserve_upd_sts 10071 10071 0 0.00 0 11768 48 0 0
sp_reserve_write_off 10071 10071 0 0.00 5 25130 183 0 0
sp_supplier_invoice 5631 5631 0 0.00 5 82685 2974 0 0
sp_supplier_order 5565 5565 0 0.00 5 37092 1188 0 0
srv_aggregate_perf_data 96 96 0 0.00 2352 281667 21608 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1117 105364 44171 0 0
srv_find_qd_qs_mism 1066484 1066484 0 0.00 0 33417 5 0 0
srv_make_invnt_saldo 520 520 0 0.00 26 45713 1104 0 0
srv_make_money_saldo 779 779 0 0.00 0 570 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 151893 296797 214317 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 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 5161 5161 0 0.00 0 22901 162 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 5744 94 0 0
t$perf-norm:sp_cancel_client_order 1801 1801 0 0.00 0 4005 20 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 1330 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 1022 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 7038 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 2069 22 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 36543 1704 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 6102 12 0 0
t$perf-norm:sp_client_order 21085 21085 0 0.00 0 18513 17 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 5565 13 0 0
t$perf-norm:sp_pay_from_customer 7100 7100 0 0.00 0 15731 14 0 0
t$perf-norm:sp_pay_to_supplier 6048 6048 0 0.00 0 6402 12 0 0
t$perf-norm:sp_reserve_write_off 10071 10071 0 0.00 0 9951 13 0 0
t$perf-norm:sp_supplier_invoice 5631 5631 0 0.00 0 25621 33 0 0
t$perf-norm:sp_supplier_order 5565 5565 0 0.00 0 6956 17 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 79 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1151 19 0 0
t$perf-norm:srv_make_invnt_saldo 520 520 0 0.00 0 1047 21 0 0
t$perf-norm:srv_make_money_saldo 779 779 0 0.00 0 832 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 63 21 0 0
v_add_invoice_to_stock 10506 10314 192 1.82 0 4816 15 0 192
v_all_customers 38180 38180 0 0.00 0 24 1 0 0
v_all_suppliers 31160 31160 0 0.00 0 237 1 0 0
v_all_wares 42170 42170 0 0.00 0 42 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 1760 11 0 0
v_cancel_client_order 3602 3602 0 0.00 0 1247 3 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 2583 6 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 739 3 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 4425 10 0 0
v_cancel_supplier_invoice 882 868 14 1.58 0 11576 28 0 14
v_cancel_supplier_order 806 806 0 0.00 0 317 4 0 0
v_cancel_write_off 2070 2070 0 0.00 0 3191 13 0 0
v_max_id_clo_ord 5563 5563 0 0.00 0 1466 1 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 3934 24 0 0
v_max_id_ord_sup 5630 5630 0 0.00 0 10441 9 0 0
v_max_non_paid_invoice 1661 1661 0 0.00 0 1547 4 0 0
v_max_non_paid_realizn 3803 3803 0 0.00 0 1061 3 0 0
v_min_id_clo_ord 5563 5563 0 0.00 0 5498 6 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 6574 11 0 0
v_min_id_ord_sup 5630 5630 0 0.00 0 4621 5 0 0
v_min_non_paid_invoice 6048 6048 0 0.00 0 18816 24 0 0
v_min_non_paid_realizn 7100 7100 0 0.00 0 20992 39 0 0
v_our_firm 10584 10584 0 0.00 0 20 1 0 0
v_reserve_write_off 20142 20142 0 0.00 0 5982 6 0 0
x_lock_depdocs_on_canc_invoice 11617 11617 0 0.00 0 12155 99 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 15364 307 0 0
x_make_qstorno_1000_1200 5565 5565 0 0.00 2 37079 1117 0 0
x_make_qstorno_1200_2000 5631 5631 0 0.00 2 82673 2839 0 0
x_make_qstorno_2100_3300 470059 470059 0 0.00 0 66646 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-01-2023 16:40:03 to 19-01-2023 16:40:07.

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:42 3 8192000000 8805538544 8819417088 9953856 0.121 5 1 2 0 10057536 60896 42784
14:09:44 28643 8192000000 9903456528 10210402304 980376576 10.688 100 91 182 0 1010419232 6071152 23971504
14:11:17 20703 8192000000 9936870816 10252226560 1015312992 11.027 100 90 180 0 1047239824 8418384 23508448
14:12:46 9464 8192000000 9949395056 10276913152 1037291648 11.239 100 80 160 1 1067624096 9952304 20380144
14:14:21 9334 8192000000 10032578912 10380251136 1118154992 12.010 100 81 160 0 1149157856 11624000 19378864
14:15:34 5456 8192000000 10082943696 10444095488 1172301040 12.518 100 73 146 0 1200868864 11136432 17431392
14:16:41 3627 8192000000 10127276192 10499309568 1220825728 12.969 100 65 127 1 1246309328 11246704 14236896
14:17:48 2500 8192000000 10167535312 10539933696 1254356384 13.278 100 90 178 1 1288115552 14640784 19118384
14:18:52 1294 8192000000 10192724000 10574389248 1280796208 13.520 100 87 175 0 1313625712 13514496 19315008
14:19:55 1245 8192000000 10205174192 10590633984 1293874880 13.640 100 80 161 2 1323406288 11515600 18015808
14:20:58 4205 8192000000 10206388544 10603302912 1303849280 13.730 100 71 140 0 1327877584 8464976 15563328
14:22:04 2451 8192000000 10213248368 10613911552 1313600144 13.819 100 55 110 0 1334473888 7680112 13193632
14:23:09 2397 8192000000 10213719424 10621022208 1319183152 13.869 100 43 86 2 1335830272 7002416 9644704
14:24:12 11170 8192000000 10206302512 10623569920 1326620512 13.937 100 21 41 1 1336551040 5443024 4487504
14:25:28 31707 8192000000 10216672160 10641248256 1333101136 13.995 100 39 78 0 1348383760 5694976 9587648
14:27:00 4373 8192000000 10232428032 10648440832 1337790416 14.037 100 21 43 0 1348220576 5937536 4492624
14:28:05 3322 8192000000 10241799344 10656337920 1345282880 14.105 100 37 74 0 1360115648 6626496 8206272
14:29:11 14816 8192000000 10195934672 10608496640 1277494464 13.490 97 11 24 0 1285595344 5489728 2611152
14:30:26 14355 8192000000 10194564320 10595033088 1264577536 13.372 97 47 95 1 1279731072 4403712 10749824
14:31:44 6791 8192000000 10134752992 10527670272 1199198128 12.769 95 27 55 0 1212065392 6419408 6447856
14:32:55 8302 8192000000 10144499392 10539978752 1217562368 12.939 97 24 48 0 1228761968 4760336 6439264
14:34:05 52365 8192000000 10151708816 10555170816 1250076624 13.239 98 22 43 0 1260538784 4790384 5671776
14:36:13 6298 8192000000 10192298224 10599354368 1292189648 13.624 100 24 48 0 1306636576 9193024 5253904
14:37:22 9098 8192000000 10198589776 10608021504 1300443856 13.699 100 23 45 3 1311528224 5441584 5642784
14:38:31 11356 8192000000 10220914208 10634223616 1324774048 13.920 100 20 40 0 1333788368 5100640 3913680
14:39:50 62001 8192000000 10243688880 10659778560 1340139024 14.059 100 41 82 0 1357589584 8001232 9449328
14:41:55 8534 8192000000 10261242320 10682314752 1358514352 14.224 100 42 84 0 1375707952 7753696 9439904
14:43:12 21165 8192000000 10280574384 10700951552 1371836784 14.344 100 51 102 0 1394710608 11680688 11193136
14:44:40 3544 8192000000 10304912512 10723921920 1387436992 14.483 100 56 112 0 1416840848 15487056 13916800
14:45:47 5765 8192000000 10313351680 10733793280 1391331168 14.518 100 53 106 1 1420339616 16866576 12141872
14:47:00 4098 8192000000 10323767648 10749894656 1404461968 14.635 100 52 104 4 1436179904 19586688 12131248
14:48:09 5919 8192000000 10339790608 10764529664 1410231568 14.686 100 59 115 0 1450693104 26689632 13771904
14:49:23 11756 8192000000 10336227216 10762235904 1400085040 14.596 99 51 100 0 1436121680 23761840 12274800
14:50:39 22931 8192000000 10333692208 10767036416 1393283520 14.535 98 37 74 0 1429730832 27123840 9323472
14:52:06 13832 8192000000 10348868112 10780033024 1422177232 14.792 100 45 87 0 1459845040 26900384 10767424
14:53:24 11610 8192000000 10350149696 10770481152 1410644832 14.690 100 58 116 0 1456587968 30974256 14968880
14:54:40 4804 8192000000 10364692128 10777464832 1413020144 14.711 100 70 140 2 1465904304 35298240 17585920
14:55:50 6606 8192000000 10356212800 10778673152 1407568752 14.662 100 52 104 1 1462204304 40252080 14383472
14:56:58 2507 8192000000 10342590800 10754715648 1375351888 14.375 99 70 139 0 1439348176 46440576 17555712
14:58:05 1116 8192000000 10336944656 10735185920 1366559232 14.296 99 89 173 0 1439029904 50415008 22055664
14:59:11 3902 8192000000 10307265584 10715078656 1342145968 14.077 99 63 127 0 1407204224 48816736 16241520
15:00:18 5726 8192000000 10301519136 10719023104 1349002992 14.139 99 48 95 1 1400551008 39652832 11895184
15:01:27 7163 8192000000 10291685696 10715435008 1350843472 14.155 99 35 70 2 1394937136 35079664 9014000
15:02:38 27708 8192000000 10308435008 10729021440 1370399328 14.331 100 36 73 1 1409347712 29221360 9727024
15:04:18 7307 8192000000 10302429312 10733821952 1353003824 14.174 99 32 64 1 1401732144 40070208 8658112
15:05:39 18717 8192000000 10279856240 10717323264 1345635152 14.108 100 25 50 1 1390649520 38245040 6769328
15:07:01 36189 8192000000 10274194720 10723389440 1348821920 14.137 100 15 30 0 1382759840 29820816 4117104
15:08:44 29395 8192000000 10282446144 10720587776 1364618880 14.279 100 23 46 1 1386697104 16449824 5628400
15:10:14 32149 8192000000 10276048944 10707902464 1347702240 14.127 99 34 67 0 1369658576 13539392 8416944
15:11:54 50752 8192000000 10264639200 10711908352 1340977392 14.066 98 8 16 0 1357563072 15007568 1578112
15:13:52 75897 8192000000 10295579728 10737295360 1366385392 14.295 99 17 34 0 1385956112 15784864 3785856
15:16:13 96001 8192000000 10283574304 10728222720 1358371840 14.223 99 18 34 1 1375976656 13492304 4112512
15:18:58 31230 8192000000 10304551472 10742423552 1384317072 14.455 100 64 126 1 1407273440 8584832 14371536
15:20:31 46349 8192000000 10302682448 10747428864 1379875504 14.415 99 37 75 0 1399554048 10827760 8850784
15:22:21 70933 8192000000 10297584800 10753343488 1371199104 14.338 99 13 26 0 1390662512 16437616 3025792
15:24:48 68469 8192000000 10304393568 10767654912 1394969808 14.550 100 15 30 1 1417768016 19391456 3406752
15:26:57 55929 8192000000 10294619808 10755080192 1376386672 14.384 99 17 34 0 1397078848 16400976 4291200
15:28:53 92806 8192000000 10311039456 10782609408 1401455744 14.608 100 14 28 1 1420059760 15323056 3280960
15:31:30 62862 8192000000 10298032720 10760548352 1398407104 14.581 100 13 26 0 1407994384 6849440 2737840
15:33:45 64365 8192000000 10280790016 10755878912 1379399760 14.411 99 7 14 0 1387954816 7284304 1270752
15:35:50 63967 8192000000 10320632368 10776825856 1393599888 14.538 99 18 36 0 1411476352 13858736 4017728
15:37:56 48210 8192000000 10299215056 10750455808 1373075392 14.355 99 18 35 0 1389744672 12872432 3796848
15:39:44 39939 8192000000 10290629168 10741141504 1326312960 13.934 95 27 54 0 1338959376 6857904 5788512
15:41:30 38730 8192000000 10289383104 10748878848 1369591200 14.323 99 12 24 0 1381144784 9073792 2479792
15:43:31 51046 8192000000 10281241712 10721009664 1317127168 13.851 96 27 54 0 1331913824 8877712 5908944
15:45:35 78124 8192000000 10261218320 10709737472 1274493328 13.463 93 17 32 0 1291969632 13887072 3589232
15:48:48 58472 8192000000 10260667200 10706079744 1343481952 14.089 99 20 40 0 1356830400 9074960 4273488
15:50:51 64337 8192000000 10250221104 10677075968 1304253152 13.734 97 53 106 0 1328741216 12707120 11780944
15:53:04 40484 8192000000 10243401424 10696396800 1348760096 14.136 100 27 54 0 1362934640 7560768 6613776
15:54:51 50297 8192000000 10233715200 10692804608 1321280816 13.888 98 12 24 0 1334146192 10072608 2792768
15:56:41 60945 8192000000 10236916144 10689880064 1321957232 13.894 98 8 16 0 1336889984 12899360 2033392
15:58:45 55279 8192000000 10249524176 10712211456 1332020336 13.985 98 8 16 0 1348221856 14389328 1812192
16:00:41 42138 8192000000 10255953536 10707279872 1341273488 14.069 99 18 37 0 1355153824 9711760 4168576
16:02:24 45128 8192000000 10276562096 10731409408 1371152976 14.337 100 31 62 0 1388532784 9556672 7823136
16:04:14 64592 8192000000 10275038640 10728144896 1338715728 14.046 98 20 40 0 1353403024 10381616 4305680
16:06:19 69679 8192000000 10304952416 10765103104 1371774144 14.343 99 23 45 0 1392369072 15608560 4986368
16:08:32 57146 8192000000 10298386240 10758967296 1367305408 14.303 99 6 12 0 1398194800 29913088 976304
16:10:33 61227 8192000000 10301502576 10752061440 1359174752 14.230 97 14 28 1 1375254240 13221680 2857808
16:12:35 81669 8192000000 10297942528 10764156928 1391018832 14.515 100 15 30 0 1412169568 17390832 3759904
16:15:16 45481 8192000000 10304750288 10752798720 1392988848 14.533 100 36 70 1 1409535920 8589168 7957904
16:17:02 99138 8192000000 10306469568 10786353152 1398858576 14.585 100 15 31 0 1424286512 22163312 3264624
16:19:53 69663 8192000000 10305344480 10769186816 1398046896 14.578 99 22 43 2 1411657952 8541568 5069488
16:22:03 83997 8192000000 10287258176 10756128768 1368081280 14.310 98 8 16 0 1378991824 9044992 1865552
16:24:27 48635 8192000000 10286574496 10748751872 1369860160 14.326 98 9 18 0 1380046288 8230080 1956048
16:26:17 58351 8192000000 10305594176 10764177408 1391007504 14.515 99 24 46 0 1408738192 12331840 5398848
16:28:18 78259 8192000000 10274558400 10718068736 1300689360 13.702 94 9 18 0 1311106576 8391168 2026048
16:30:44 72949 8192000000 10285468048 10752380928 1358962912 14.228 99 12 24 0 1381010816 19086736 2961168
16:32:58 17296 8192000000 10287398784 10735022080 1373875184 14.362 100 23 46 0 1386082944 7402192 4805568
16:34:18 83251 8192000000 10253511232 10700795904 1295855856 13.658 95 16 32 0 1312559504 13337760 3365888
16:36:53 105364 8192000000 10251080704 10704773120 1312926704 13.813 97 16 32 0 1334641376 18430672 3284000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-01-2023 16:40:09 to 19-01-2023 16:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 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 19-01-2023 16:40:25 to 19-01-2023 16:40:27.

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          241572
MON$OLDEST_ACTIVE               241891
MON$OLDEST_SNAPSHOT             241891
MON$NEXT_TRANSACTION            241891
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                       2023744
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.33654 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33654 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33654 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33654 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 ---
:138: 
:139: mscs-test-161.budzdorov.ru    Thu Jan 19 16:38:42 2023
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:141: 
:142: 
:143: mscs-test-161.budzdorov.ru    Thu Jan 19 16:38:48 2023
:144:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:145: 
--- end of diff output ---

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

19.01.23 16:40:29. 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-01-19 16:40:31 No severe PSQL-related problems occured
19.01.23 16:40:32. End of report.