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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-09-23 22:38:26
DTS_END                         2022-09-23 22:38:26.0160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60744
TRN_ID                          237761
ATT_ID                          689
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: 9311.73 18798 1117408
customer order: creation 168.71 357 20245
customer order: refuse 13.74 373 1649
order to supplier: creation 44.15 1352 5298
order to supplier: removal 3.30 105956 396
invoice (draft): creation 44.88 3491 5385
invoice (draft): removal 54.68 791 6562
invoice accept: apply 41.00 17416 4920
invoice accept: cancel 49.28 756 5914
customer reserve: creation 3811.99 171 457439
customer reserve: removal 4784.65 56 574158
realization accept: apply 79.35 186 9522
realization accept: cancel 89.33 106 10720
payment from customer: creation 57.60 117 6912
payment from customer: removal 5.91 77 709
payment to supplier: creation 48.18 153 5782
payment to supplier: removal 3.45 79 414
service: total inventory turnovers 4.47 1171 536
service: total monetary turnovers 6.13 30 736
service: aggregate perf. data 0.89 18841 107
service: refresh index statistics 0.03 224486 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-09-2022 22:39:18 to 23-09-2022 22:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10348
TEST_TIME 2 6270
TEST_TIME 3 5634
TEST_TIME 4 5565
TEST_TIME 5 5161
TEST_TIME 6 4920
TEST_TIME 7 5562
TEST_TIME 8 5386
TEST_TIME 9 5622
TEST_TIME 10 5803
TEST_TIME 11 5886
TEST_TIME 12 6243
TEST_TIME 13 6444
TEST_TIME 14 6804
TEST_TIME 15 6881
TEST_TIME 16 7360
TEST_TIME 17 7772
TEST_TIME 18 7957
TEST_TIME 19 8315
TEST_TIME 20 8411
TEST_TIME 21 8480
TEST_TIME 22 8506
TEST_TIME 23 8652
TEST_TIME 24 8869
TEST_TIME 25 8682
TEST_TIME 26 8688
TEST_TIME 27 8885
TEST_TIME 28 9614
TEST_TIME 29 9945
TEST_TIME 30 10055
TEST_TIME 31 10901
TEST_TIME 32 10791
TEST_TIME 33 10885
TEST_TIME 34 10854
TEST_TIME 35 10544
TEST_TIME 36 10452
TEST_TIME 37 10332
TEST_TIME 38 10171
TEST_TIME 39 10140
TEST_TIME 40 10025
TEST_TIME 41 9965
TEST_TIME 42 9911
TEST_TIME 43 9746
TEST_TIME 44 9710
TEST_TIME 45 9798
TEST_TIME 46 9655
TEST_TIME 47 9615
TEST_TIME 48 9565
TEST_TIME 49 9426
TEST_TIME 50 9372
TEST_TIME 51 9253
TEST_TIME 52 9182
TEST_TIME 53 9268
TEST_TIME 54 9260
TEST_TIME 55 9294
TEST_TIME 56 9231
TEST_TIME 57 9297
TEST_TIME 58 9311
TEST_TIME 59 9327
TEST_TIME 60 9312
TEST_TIME 61 9396
TEST_TIME 62 9424
TEST_TIME 63 9429
TEST_TIME 64 9560
TEST_TIME 65 9583
TEST_TIME 66 9564
TEST_TIME 67 9604
TEST_TIME 68 9652
TEST_TIME 69 9699
TEST_TIME 70 9724
TEST_TIME 71 9696
TEST_TIME 72 9625
TEST_TIME 73 9533
TEST_TIME 74 9510
TEST_TIME 75 9495
TEST_TIME 76 9480
TEST_TIME 77 9462
TEST_TIME 78 9453
TEST_TIME 79 9387
TEST_TIME 80 9357
TEST_TIME 81 9295
TEST_TIME 82 9288
TEST_TIME 83 9289
TEST_TIME 84 9261
TEST_TIME 85 9221
TEST_TIME 86 9150
TEST_TIME 87 9169
TEST_TIME 88 9219
TEST_TIME 89 9240
TEST_TIME 90 9273
TEST_TIME 91 9265
TEST_TIME 92 9303
TEST_TIME 93 9320
TEST_TIME 94 9340
TEST_TIME 95 9327
TEST_TIME 96 9407
TEST_TIME 97 9392
TEST_TIME 98 9360
TEST_TIME 99 9416
TEST_TIME 100 9438
TEST_TIME 101 9454
TEST_TIME 102 9598
TEST_TIME 103 9514
TEST_TIME 104 9436
TEST_TIME 105 9428
TEST_TIME 106 9407
TEST_TIME 107 9349
TEST_TIME 108 9302
TEST_TIME 109 9273
TEST_TIME 110 9235
TEST_TIME 111 9256
TEST_TIME 112 9297
TEST_TIME 113 9319
TEST_TIME 114 9300
TEST_TIME 115 9301
TEST_TIME 116 9327
TEST_TIME 117 9370
TEST_TIME 118 9388
TEST_TIME 119 9361
TEST_TIME 120 9315

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-09-2022 22:39:22 to 23-09-2022 22:39:25.

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 1539749 1539749 0 0.00 0 19161 6 0 0
doc_list_biud 1539749 1539749 0 0.00 0 69137 27 0 0
sp_add_invoice_to_stock 5009 4920 89 1.77 36 175511 17417 0 89
sp_cancel_adding_invoice 5914 5914 0 0.00 5 69217 756 0 0
sp_cancel_client_order 1649 1649 0 0.00 4 10415 373 0 0
sp_cancel_customer_reserve 574158 574158 0 0.00 0 17532 57 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 3 3093 77 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 2484 79 0 0
sp_cancel_supplier_invoice 6572 6562 10 0.15 1 21435 792 0 10
sp_cancel_supplier_order 396 396 0 0.00 5 949207 105956 0 0
sp_cancel_write_off 10720 10720 0 0.00 1 14263 107 0 0
sp_client_order 20245 20245 0 0.00 3 34041 357 0 0
sp_customer_reserve 457439 457439 0 0.00 0 72798 172 0 0
sp_fill_shopping_cart 30925 30925 0 0.00 1 19080 42 0 0
sp_fill_shopping_cart_clo_res 457426 457426 0 0.00 0 15539 14 0 0
sp_get_clo_for_invoice 4907 4907 0 0.00 0 34238 325 0 0
sp_kill_cost_storno 17757 17757 0 0.00 0 14457 53 0 0
sp_kill_qstorno_ret_qs2qd 1165994 1165994 0 0.00 0 17392 31 0 0
sp_kill_qty_storno 613828 613828 0 0.00 0 69126 67 0 0
sp_lock_selected_doc 20016 20016 0 0.00 0 8040 6 0 0
sp_make_cost_storno 27123 27123 0 0.00 0 19119 55 0 0
sp_multiply_rows_for_pdistr 27123 27123 0 0.00 0 15760 30 0 0
sp_multiply_rows_for_qdistr 20245 20245 0 0.00 0 34024 162 0 0
sp_pay_from_customer 6912 6912 0 0.00 4 16430 118 0 0
sp_pay_to_supplier 5782 5782 0 0.00 4 18047 154 0 0
sp_payment_common 12694 12694 0 0.00 1 18028 33 0 0
sp_qd_handle_on_cancel_clo 1649 1649 0 0.00 0 10401 338 0 0
sp_qd_handle_on_invoice_upd_sts 10821 10821 0 0.00 0 69113 325 0 0
sp_qd_handle_on_reserve_upd_sts 9522 9522 0 0.00 0 13246 63 0 0
sp_reserve_write_off 9522 9522 0 0.00 6 13264 186 0 0
sp_supplier_invoice 5385 5385 0 0.00 6 80217 3491 0 0
sp_supplier_order 5298 5298 0 0.00 5 29216 1353 0 0
srv_aggregate_perf_data 107 107 0 0.00 2901 397663 18842 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 889 116889 43371 0 0
srv_find_qd_qs_mism 1069888 1069888 0 0.00 0 14826 5 0 0
srv_make_invnt_saldo 536 536 0 0.00 38 26568 1171 0 0
srv_make_money_saldo 736 736 0 0.00 1 2242 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 51205 368152 224487 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4920 4920 0 0.00 0 12219 175 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 8444 118 0 0
t$perf-norm:sp_cancel_client_order 1649 1649 0 0.00 0 4342 14 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 1781 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 2715 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 2158 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 4790 29 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 19269 1619 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 3757 11 0 0
t$perf-norm:sp_client_order 20245 20245 0 0.00 0 9395 21 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 2396 11 0 0
t$perf-norm:sp_pay_from_customer 6912 6912 0 0.00 0 6513 11 0 0
t$perf-norm:sp_pay_to_supplier 5782 5782 0 0.00 0 6509 11 0 0
t$perf-norm:sp_reserve_write_off 9522 9522 0 0.00 0 6500 12 0 0
t$perf-norm:sp_supplier_invoice 5385 5385 0 0.00 0 11109 23 0 0
t$perf-norm:sp_supplier_order 5298 5298 0 0.00 0 11214 17 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 1102 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 294 5 0 0
t$perf-norm:srv_make_invnt_saldo 536 536 0 0.00 0 803 15 0 0
t$perf-norm:srv_make_money_saldo 736 736 0 0.00 0 633 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 23 8 0 0
v_add_invoice_to_stock 9992 9814 178 1.78 0 6117 17 0 178
v_all_customers 37006 37006 0 0.00 0 118 1 0 0
v_all_suppliers 29702 29702 0 0.00 0 22 1 0 0
v_all_wares 40490 40490 0 0.00 0 99 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 1777 10 0 0
v_cancel_client_order 3298 3298 0 0.00 0 8680 9 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 2712 10 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 3716 9 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 2455 13 0 0
v_cancel_supplier_invoice 754 734 20 2.65 0 4095 20 0 20
v_cancel_supplier_order 792 792 0 0.00 0 1614 9 0 0
v_cancel_write_off 1954 1954 0 0.00 0 8127 20 0 0
v_max_id_clo_ord 5298 5298 0 0.00 0 2240 2 0 0
v_max_id_clo_res 967 967 0 0.00 0 1639 10 0 0
v_max_id_ord_sup 5382 5382 0 0.00 0 4548 4 0 0
v_max_non_paid_invoice 1611 1611 0 0.00 0 296 3 0 0
v_max_non_paid_realizn 3676 3676 0 0.00 0 5728 7 0 0
v_min_id_clo_ord 5298 5298 0 0.00 0 3589 7 0 0
v_min_id_clo_res 967 967 0 0.00 0 975 4 0 0
v_min_id_ord_sup 5382 5382 0 0.00 0 3828 6 0 0
v_min_non_paid_invoice 5782 5782 0 0.00 0 18023 27 0 0
v_min_non_paid_realizn 6912 6912 0 0.00 0 15291 23 0 0
v_our_firm 9956 9956 0 0.00 0 23 1 0 0
v_reserve_write_off 19044 19044 0 0.00 0 8127 8 0 0
x_lock_depdocs_on_canc_invoice 12109 12109 0 0.00 0 19807 119 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 16784 391 0 0
x_make_qstorno_1000_1200 5298 5298 0 0.00 1 29203 1277 0 0
x_make_qstorno_1200_2000 5385 5385 0 0.00 3 80211 3329 0 0
x_make_qstorno_2100_3300 457439 457439 0 0.00 0 65949 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-09-2022 22:39:26 to 23-09-2022 22:39:30.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:29 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:31 2929 8192000000 9898179136 10210172928 988304912 10.765 100 86 170 0 1016156944 5849760 22002272
20:10:43 26187 8192000000 9956296336 10278166528 1031576944 11.184 100 90 180 0 1062931008 8422752 22931312
20:12:15 11281 8192000000 9971730496 10302722048 1059292672 11.450 100 87 172 0 1090896176 9597696 22005808
20:13:37 8234 8192000000 10027082160 10375413760 1113030304 11.961 100 78 156 0 1142308368 10481760 18796304
20:14:49 4862 8192000000 10080115984 10433658880 1161532944 12.418 100 85 170 0 1193733824 12309984 19890896
20:15:58 4565 8192000000 10106801792 10473201664 1197593312 12.754 100 73 145 0 1225302368 11758384 15950672
20:17:04 2965 8192000000 10156584832 10530373632 1239889952 13.145 100 80 161 0 1271299552 14147040 17262560
20:18:09 1229 8192000000 10174446912 10551709696 1263353680 13.361 100 89 174 0 1295846752 13778656 18714416
20:19:11 1356 8192000000 10199399648 10583646208 1288215264 13.588 100 85 166 0 1318448272 12920608 17312400
20:20:13 10818 8192000000 10189267120 10595250176 1295763840 13.657 100 33 66 0 1310058240 6664592 7629808
20:21:24 3073 8192000000 10199812784 10606604288 1309154432 13.778 100 42 81 0 1326367344 8140032 9072880
20:22:28 10432 8192000000 10203378848 10620211200 1315820976 13.839 100 16 32 1 1325636880 6374400 3441504
20:23:41 55296 8192000000 10222379984 10638712832 1326044032 13.931 100 23 46 1 1337130928 5884736 5202160
20:25:37 9301 8192000000 10217061408 10642624512 1330471392 13.971 100 17 34 0 1340136432 5890896 3774144
20:26:47 9938 8192000000 10221353168 10647597056 1333835344 14.002 100 16 33 0 1342908304 5220080 3852880
20:27:58 9555 8192000000 10182742576 10602467328 1241666752 13.162 94 21 42 0 1252503488 5804800 5031936
20:29:08 53370 8192000000 10169787152 10580205568 1258662320 13.318 96 30 58 0 1270158640 4540368 6955952
20:31:03 7582 8192000000 10146099472 10547449856 1235636832 13.106 98 22 44 0 1251022752 10840784 4545136
20:32:11 8906 8192000000 10142430256 10548436992 1236786192 13.117 98 20 41 1 1251450304 9732400 4931712
20:33:20 11303 8192000000 10158772576 10562138112 1264620464 13.372 100 26 52 0 1276590240 5885504 6084272
20:34:36 48956 8192000000 10165629520 10572394496 1252350816 13.260 98 27 53 0 1263720336 5324848 6044672
20:36:25 6093 8192000000 10187301616 10592378880 1288433184 13.590 100 18 36 0 1297930224 5389056 4107984
20:37:35 10293 8192000000 10199597008 10613411840 1306952736 13.758 100 19 36 1 1316205744 5050176 4202832
20:38:49 31113 8192000000 10243280288 10653753344 1337197584 14.032 100 55 108 1 1357828800 8025776 12605440
20:40:40 13119 8192000000 10258040640 10676985856 1351864816 14.164 100 48 96 1 1372540592 8100272 12575504
20:41:59 12025 8192000000 10272298016 10694365184 1368126160 14.310 100 44 88 0 1391856000 13499408 10230432
20:43:15 8074 8192000000 10295293920 10715107328 1381605776 14.431 100 41 81 1 1405594672 13544560 10444336
20:44:33 6254 8192000000 10307762800 10726633472 1388489968 14.492 100 57 114 0 1420361760 18420160 13451632
20:45:56 20909 8192000000 10305189952 10729201664 1389802624 14.504 100 42 76 0 1415387040 16316752 9267664
20:47:20 10603 8192000000 10307863616 10739310592 1397540016 14.573 100 38 75 0 1426460992 19663696 9257280
20:48:35 8565 8192000000 10306443888 10730901504 1373326064 14.357 98 50 98 0 1405702464 20280864 12095536
20:49:47 4523 8192000000 10325733584 10752094208 1401593744 14.609 100 50 100 0 1437460048 23460256 12406048
20:51:00 1465 8192000000 10335465536 10747678720 1374638688 14.369 98 72 136 1 1416136992 25034352 16463952
20:52:07 1171 8192000000 10309140528 10710986752 1361103280 14.247 99 84 167 1 1410299328 28977280 20218768
20:53:13 7916 8192000000 10310359344 10731204608 1376648816 14.387 100 48 96 1 1423209904 33994304 12566784
20:54:27 2607 8192000000 10290039536 10694877184 1343799232 14.092 100 66 131 0 1398456000 37815840 16840928
20:55:32 4225 8192000000 10295676464 10713997312 1359044928 14.229 100 46 92 1 1408047504 37050176 11952400
20:56:47 888 8192000000 10329645472 10729287680 1366444256 14.295 100 85 166 2 1430492320 43489248 20558816
20:57:58 8965 8192000000 10311792112 10729848832 1370271632 14.329 100 52 104 0 1420463904 36937040 13255232
20:59:16 1556 8192000000 10298566656 10711150592 1348099936 14.130 100 70 137 0 1403223840 37560112 17563792
21:00:25 18554 8192000000 10292003792 10711142400 1329573584 13.963 98 31 60 0 1377725520 39855744 8296192
21:01:54 19571 8192000000 10301740496 10722402304 1346678368 14.118 99 29 58 0 1402721776 47991232 8052176
21:03:15 19825 8192000000 10306132992 10734379008 1361218224 14.248 100 24 47 0 1415860336 48188096 6454016
21:04:57 15132 8192000000 10335086528 10767626240 1356954224 14.210 99 42 83 1 1443265872 74874640 11437008
21:06:20 23594 8192000000 10320408272 10770096128 1357473584 14.215 99 25 50 1 1422196800 57403088 7320128
21:07:51 22590 8192000000 10302662464 10753232896 1369784496 14.325 100 15 30 0 1409985328 36047728 4153104
21:09:15 32085 8192000000 10307210512 10743873536 1372314496 14.348 99 21 42 0 1400179776 22986816 4878464
21:11:32 72222 8192000000 10320278672 10783592448 1402765408 14.620 100 11 22 0 1422747968 17462448 2520112
21:13:48 46716 8192000000 10308915440 10754052096 1388513184 14.493 99 40 74 1 1408399584 11813696 8072704
21:15:56 49025 8192000000 10316519248 10767495168 1404277120 14.633 100 35 71 0 1426810832 14479312 8054400
21:17:56 64114 8192000000 10305622160 10771263488 1404543968 14.635 100 10 20 0 1423871168 16651712 2675488
21:20:01 78679 8192000000 10321605440 10777440256 1396097296 14.560 99 15 30 0 1414782928 15021376 3664256
21:22:20 58147 8192000000 10316740832 10781970432 1415287776 14.731 100 20 40 1 1433704768 13558304 4858688
21:24:40 59245 8192000000 10312480192 10771402752 1400555968 14.600 99 22 42 0 1412451072 6829968 5065136
21:26:41 41338 8192000000 10313690976 10769285120 1402689152 14.619 100 33 63 0 1418657744 8400880 7567712
21:28:30 41621 8192000000 10348391216 10804826112 1425531696 14.822 100 30 60 0 1450176496 17140304 7504496
21:30:23 53839 8192000000 10341374928 10808942592 1426682688 14.832 100 15 30 0 1452239088 21883344 3673056
21:32:18 97103 8192000000 10317118816 10796822528 1402129696 14.614 99 20 40 2 1426030032 19059728 4840608
21:35:12 64641 8192000000 10308147664 10791272448 1385302384 14.464 98 5 10 0 1402506368 16126672 1077312
21:37:17 72427 8192000000 10322414864 10790264832 1363734064 14.271 96 19 38 0 1386921072 18691744 4495264
21:39:37 64623 8192000000 10281894816 10742595584 1347261360 14.123 96 9 18 0 1364290368 15071808 1957200
21:41:42 107405 8192000000 10294373536 10766446592 1355658208 14.198 97 6 12 0 1370287200 13295104 1333888
21:44:35 18518 8192000000 10281485088 10718478336 1346966512 14.120 98 42 84 1 1366527936 9955456 9605968
21:46:00 41549 8192000000 10307206560 10760781824 1359474432 14.233 98 22 44 0 1388797184 23876896 5445856
21:47:44 116836 8192000000 10278459008 10719719424 1313697936 13.820 95 13 26 0 1332862064 16335856 2828272
21:50:45 70350 8192000000 10296382880 10736726016 1372155920 14.346 99 65 128 1 1395927216 8952688 14818608
21:52:57 84626 8192000000 10265235552 10723561472 1345286480 14.105 98 21 40 0 1361400400 11853424 4260496
21:55:36 79718 8192000000 10247730144 10705915904 1304214208 13.734 95 16 32 1 1317298912 9836880 3247824
21:57:57 74355 8192000000 10284394944 10744696832 1383066496 14.444 100 6 12 0 1396554432 12058880 1429056
22:00:12 58690 8192000000 10274586704 10737147904 1373087760 14.355 100 12 24 0 1390073920 14265152 2721008
22:02:18 5239 8192000000 10291602256 10727804928 1373723712 14.360 100 50 96 5 1391556224 6981840 10850672
22:03:26 61833 8192000000 10263953408 10729545728 1373120016 14.355 100 17 34 0 1388631808 11678096 3833696
22:06:04 45967 8192000000 10283016112 10749370368 1382725152 14.441 100 12 24 0 1396856912 11264768 2866992
22:07:56 75245 8192000000 10292341488 10752106496 1360423808 14.241 98 8 16 0 1370256656 8163504 1669344
22:10:12 37216 8192000000 10303531872 10758012928 1386629968 14.476 99 16 32 0 1400621296 10450224 3541104
22:11:56 30249 8192000000 10321629824 10768121856 1399070944 14.587 100 51 104 1 1421344272 10506800 11766528
22:13:27 22153 8192000000 10317348544 10764406784 1385583616 14.466 99 40 80 0 1405169472 10996016 8589840
22:14:56 72005 8192000000 10312806256 10765549568 1381265552 14.428 98 19 38 0 1394038480 9127504 3645424
22:17:14 24310 8192000000 10324104224 10775552000 1405203216 14.641 100 50 100 0 1432734032 16726656 10804160
22:18:39 63512 8192000000 10328364128 10791448576 1401287696 14.606 99 20 40 0 1422155856 16571424 4296736
22:20:48 116888 8192000000 10295991680 10756587520 1400444624 14.599 100 11 22 0 1423260896 20101936 2714336
22:23:45 53629 8192000000 10282974256 10750328832 1369898656 14.326 98 12 24 0 1379620768 7127248 2594864
22:25:39 47778 8192000000 10288594176 10751197184 1382484304 14.439 99 12 23 0 1394898912 9901168 2513440
22:27:27 75052 8192000000 10304437984 10766716928 1386614288 14.476 99 21 42 0 1407093248 15684448 4794512
22:30:03 76223 8192000000 10275925216 10745802752 1341155136 14.068 96 9 18 0 1356319568 13253456 1910976
22:32:23 46308 8192000000 10268884656 10728222720 1353234784 14.177 99 32 62 0 1371589152 11479168 6875200
22:34:09 55949 8192000000 10262267424 10707169280 1322534992 13.900 97 21 43 0 1344960736 18153504 4272240
22:36:05 98205 8192000000 10263085216 10702659584 1270581248 13.427 93 18 36 0 1285355376 10914320 3859808

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-09-2022 22:39:32 to 23-09-2022 22:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-09-2022 22:39:48 to 23-09-2022 22:39:50.

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          235737
MON$OLDEST_ACTIVE               237870
MON$OLDEST_SNAPSHOT             237870
MON$NEXT_TRANSACTION            237870
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                       2039136
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.33617 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33617 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33617 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33617 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 ---
:42: 
:43: mscs-test-161.budzdorov.ru    Fri Sep 23 22:38:35 2022
:44:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:45: 
:46: 
:47: mscs-test-161.budzdorov.ru    Fri Sep 23 22:38:35 2022
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Fri Sep 23 22:38:35 2022
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:53: 
:54: 
:55: mscs-test-161.budzdorov.ru    Fri Sep 23 22:38:35 2022
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Fri Sep 23 22:38:35 2022
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:61: 
--- end of diff output ---

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

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