Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.11.2021 19: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15700500 kB
MemAvailable 30771888 kB
Buffers 0 kB
Cached 15973548 kB
SwapCached 2444 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.8
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 2021-11-07 19:37:50
DTS_END                         2021-11-07 19:37:50.8590
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48986
TRN_ID                          239043
ATT_ID                          657
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: 9208.44 28724 1105013
customer order: creation 166.98 332 20038
customer order: refuse 14.72 333 1766
order to supplier: creation 45.81 1282 5497
order to supplier: removal 3.38 114953 405
invoice (draft): creation 46.13 3462 5536
invoice (draft): removal 51.83 909 6219
invoice accept: apply 41.41 19588 4969
invoice accept: cancel 46.80 919 5616
customer reserve: creation 3862.84 193 463541
customer reserve: removal 4640.83 66 556900
realization accept: apply 81.74 177 9809
realization accept: cancel 76.75 109 9210
payment from customer: creation 58.57 135 7028
payment from customer: removal 5.53 69 663
payment to supplier: creation 49.22 115 5906
payment to supplier: removal 3.41 58 409
service: total inventory turnovers 4.74 1283 569
service: total monetary turnovers 6.93 40 832
service: aggregate perf. data 0.80 23576 96
service: refresh index statistics 0.03 406872 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-11-2021 19:39:03 to 07-11-2021 19:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2761
TEST_TIME 2 3353
TEST_TIME 3 4378
TEST_TIME 4 4688
TEST_TIME 5 4700
TEST_TIME 6 4756
TEST_TIME 7 5005
TEST_TIME 8 5479
TEST_TIME 9 5458
TEST_TIME 10 6643
TEST_TIME 11 6886
TEST_TIME 12 7277
TEST_TIME 13 7489
TEST_TIME 14 7367
TEST_TIME 15 7997
TEST_TIME 16 7828
TEST_TIME 17 7917
TEST_TIME 18 8444
TEST_TIME 19 8765
TEST_TIME 20 8805
TEST_TIME 21 8531
TEST_TIME 22 8865
TEST_TIME 23 9225
TEST_TIME 24 9195
TEST_TIME 25 9377
TEST_TIME 26 9508
TEST_TIME 27 9646
TEST_TIME 28 10346
TEST_TIME 29 10774
TEST_TIME 30 11584
TEST_TIME 31 11768
TEST_TIME 32 11730
TEST_TIME 33 11524
TEST_TIME 34 11204
TEST_TIME 35 11147
TEST_TIME 36 10957
TEST_TIME 37 10939
TEST_TIME 38 10755
TEST_TIME 39 10685
TEST_TIME 40 10590
TEST_TIME 41 10482
TEST_TIME 42 10328
TEST_TIME 43 10346
TEST_TIME 44 10303
TEST_TIME 45 10266
TEST_TIME 46 10201
TEST_TIME 47 10109
TEST_TIME 48 10023
TEST_TIME 49 9933
TEST_TIME 50 9856
TEST_TIME 51 9863
TEST_TIME 52 9868
TEST_TIME 53 9747
TEST_TIME 54 9683
TEST_TIME 55 9601
TEST_TIME 56 9530
TEST_TIME 57 9520
TEST_TIME 58 9474
TEST_TIME 59 9606
TEST_TIME 60 9500
TEST_TIME 61 9604
TEST_TIME 62 9590
TEST_TIME 63 9575
TEST_TIME 64 9508
TEST_TIME 65 9557
TEST_TIME 66 9506
TEST_TIME 67 9455
TEST_TIME 68 9528
TEST_TIME 69 9500
TEST_TIME 70 9511
TEST_TIME 71 9575
TEST_TIME 72 9547
TEST_TIME 73 9562
TEST_TIME 74 9674
TEST_TIME 75 9634
TEST_TIME 76 9638
TEST_TIME 77 9611
TEST_TIME 78 9675
TEST_TIME 79 9697
TEST_TIME 80 9617
TEST_TIME 81 9576
TEST_TIME 82 9534
TEST_TIME 83 9552
TEST_TIME 84 9512
TEST_TIME 85 9452
TEST_TIME 86 9435
TEST_TIME 87 9450
TEST_TIME 88 9386
TEST_TIME 89 9389
TEST_TIME 90 9385
TEST_TIME 91 9376
TEST_TIME 92 9324
TEST_TIME 93 9331
TEST_TIME 94 9329
TEST_TIME 95 9364
TEST_TIME 96 9366
TEST_TIME 97 9359
TEST_TIME 98 9378
TEST_TIME 99 9411
TEST_TIME 100 9441
TEST_TIME 101 9465
TEST_TIME 102 9437
TEST_TIME 103 9505
TEST_TIME 104 9506
TEST_TIME 105 9476
TEST_TIME 106 9483
TEST_TIME 107 9453
TEST_TIME 108 9387
TEST_TIME 109 9452
TEST_TIME 110 9392
TEST_TIME 111 9389
TEST_TIME 112 9342
TEST_TIME 113 9325
TEST_TIME 114 9318
TEST_TIME 115 9270
TEST_TIME 116 9256
TEST_TIME 117 9230
TEST_TIME 118 9214
TEST_TIME 119 9222
TEST_TIME 120 9210

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-11-2021 19:39:07 to 07-11-2021 19:39:10.

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 1532750 1532750 0 0.00 0 25153 6 0 0
doc_list_biud 1532750 1532750 0 0.00 0 38973 30 0 0
sp_add_invoice_to_stock 5103 4969 134 2.62 35 241129 19588 0 134
sp_cancel_adding_invoice 5616 5616 0 0.00 4 72614 919 0 0
sp_cancel_client_order 1766 1766 0 0.00 4 13055 333 0 0
sp_cancel_customer_reserve 556900 556900 0 0.00 1 37379 66 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 3140 70 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 2 2953 58 0 0
sp_cancel_supplier_invoice 6228 6219 9 0.14 1 38989 910 0 9
sp_cancel_supplier_order 405 405 0 0.00 9 1062846 114954 0 0
sp_cancel_write_off 9210 9210 0 0.00 1 8923 109 0 0
sp_client_order 20038 20038 0 0.00 3 20231 332 0 0
sp_customer_reserve 463541 463541 0 0.00 0 55500 193 0 0
sp_fill_shopping_cart 31056 31056 0 0.00 1 17109 44 0 0
sp_fill_shopping_cart_clo_res 463538 463538 0 0.00 0 26347 12 0 0
sp_get_clo_for_invoice 4965 4965 0 0.00 0 21651 298 0 0
sp_kill_cost_storno 15898 15898 0 0.00 0 11454 57 0 0
sp_kill_qstorno_ret_qs2qd 1129634 1129634 0 0.00 0 38963 36 0 0
sp_kill_qty_storno 594890 594890 0 0.00 0 38968 77 0 0
sp_lock_selected_doc 20485 20485 0 0.00 0 6742 4 0 0
sp_make_cost_storno 27708 27708 0 0.00 0 25151 56 0 0
sp_multiply_rows_for_pdistr 27708 27708 0 0.00 0 15636 30 0 0
sp_multiply_rows_for_qdistr 20038 20038 0 0.00 0 12910 143 0 0
sp_pay_from_customer 7028 7028 0 0.00 4 24406 136 0 0
sp_pay_to_supplier 5906 5906 0 0.00 4 11899 115 0 0
sp_payment_common 12934 12934 0 0.00 1 15172 30 0 0
sp_qd_handle_on_cancel_clo 1766 1766 0 0.00 0 12886 310 0 0
sp_qd_handle_on_invoice_upd_sts 10581 10581 0 0.00 0 38545 367 0 0
sp_qd_handle_on_reserve_upd_sts 9809 9809 0 0.00 0 17690 62 0 0
sp_reserve_write_off 9809 9809 0 0.00 6 25195 177 0 0
sp_supplier_invoice 5536 5536 0 0.00 6 76958 3462 0 0
sp_supplier_order 5497 5497 0 0.00 4 70930 1283 0 0
srv_aggregate_perf_data 96 96 0 0.00 2631 373844 23577 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1603 166454 46969 0 0
srv_find_qd_qs_mism 1056826 1056826 0 0.00 0 17268 6 0 0
srv_make_invnt_saldo 569 569 0 0.00 25 107888 1284 0 0
srv_make_money_saldo 832 832 0 0.00 0 8868 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 186848 727717 406873 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4969 4969 0 0.00 0 14537 212 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 2631 92 0 0
t$perf-norm:sp_cancel_client_order 1766 1766 0 0.00 0 12670 26 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 2604 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 744 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 587 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 3700 28 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 21482 1766 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 12215 16 0 0
t$perf-norm:sp_client_order 20038 20038 0 0.00 0 12665 21 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 25182 35 0 0
t$perf-norm:sp_pay_from_customer 7028 7028 0 0.00 0 16362 9 0 0
t$perf-norm:sp_pay_to_supplier 5906 5906 0 0.00 0 5826 13 0 0
t$perf-norm:sp_reserve_write_off 9809 9809 0 0.00 0 8259 13 0 0
t$perf-norm:sp_supplier_invoice 5536 5536 0 0.00 0 10114 18 0 0
t$perf-norm:sp_supplier_order 5497 5497 0 0.00 0 5108 15 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 61 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 27 1 0 0
t$perf-norm:srv_make_invnt_saldo 569 569 0 0.00 0 11710 41 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 1238 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10194 9926 268 2.62 0 6555 21 0 268
v_all_customers 36558 36558 0 0.00 0 125 1 0 0
v_all_suppliers 30580 30580 0 0.00 0 121 1 0 0
v_all_wares 40076 40076 0 0.00 0 210 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 1917 11 0 0
v_cancel_client_order 3532 3532 0 0.00 0 4366 6 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 1173 7 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 1533 4 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 2942 8 0 0
v_cancel_supplier_invoice 784 766 18 2.29 0 2571 21 0 18
v_cancel_supplier_order 810 810 0 0.00 0 426 4 0 0
v_cancel_write_off 2024 2024 0 0.00 0 2784 17 0 0
v_max_id_clo_ord 5497 5497 0 0.00 0 1307 2 0 0
v_max_id_clo_res 966 966 0 0.00 0 2584 12 0 0
v_max_id_ord_sup 5521 5521 0 0.00 0 5311 5 0 0
v_max_non_paid_invoice 1634 1634 0 0.00 0 4282 6 0 0
v_max_non_paid_realizn 3771 3771 0 0.00 0 2161 5 0 0
v_min_id_clo_ord 5497 5497 0 0.00 0 3112 6 0 0
v_min_id_clo_res 966 966 0 0.00 0 9501 18 0 0
v_min_id_ord_sup 5521 5521 0 0.00 0 6483 4 0 0
v_min_non_paid_invoice 5906 5906 0 0.00 0 8955 14 0 0
v_min_non_paid_realizn 7028 7028 0 0.00 0 15167 26 0 0
v_our_firm 10032 10032 0 0.00 0 31 1 0 0
v_reserve_write_off 19618 19618 0 0.00 0 4422 5 0 0
x_lock_depdocs_on_canc_invoice 11452 11452 0 0.00 0 24859 134 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 7706 409 0 0
x_make_qstorno_1000_1200 5497 5497 0 0.00 1 70919 1197 0 0
x_make_qstorno_1200_2000 5536 5536 0 0.00 3 76941 3307 0 0
x_make_qstorno_2100_3300 463541 463541 0 0.00 0 55487 178 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 07-11-2021 19:39:11 to 07-11-2021 19:39:16.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:54 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:55 19482 8192000000 9908873568 10221789184 987892160 10.761 100 86 172 0 1016548656 5933664 22722832
17:10:22 23353 8192000000 9957233440 10282983424 1040844064 11.273 100 92 185 0 1072467184 8345680 23277440
17:11:49 24947 8192000000 9996115616 10330746880 1072546640 11.576 100 86 172 0 1103382000 9923696 20911664
17:13:18 17296 8192000000 10050661040 10398801920 1129290816 12.115 100 85 169 0 1160721808 11630896 19800096
17:14:37 7689 8192000000 10090280528 10448793600 1175539904 12.549 100 83 166 2 1207394176 13187056 18667216
17:15:48 3757 8192000000 10128488544 10499309568 1215621824 12.921 100 78 154 1 1245461088 12433296 17405968
17:16:55 3833 8192000000 10157168064 10533691392 1244668320 13.189 100 83 166 5 1276236352 13160096 18407936
17:18:00 2705 8192000000 10191850640 10568908800 1272972848 13.449 100 95 191 0 1308289488 14544528 20772112
17:19:04 38856 8192000000 10193062080 10592137216 1291224304 13.615 100 59 117 2 1313797232 9405824 13167104
17:20:43 5166 8192000000 10191414848 10602795008 1304198064 13.733 100 29 58 1 1317992016 6982896 6811056
17:21:49 10588 8192000000 10204644912 10617655296 1314829904 13.830 100 18 35 0 1325465744 6662864 3972976
17:23:00 48245 8192000000 10206542848 10627088384 1320038624 13.877 100 22 44 0 1330604048 5903648 4661776
17:24:49 8791 8192000000 10228152416 10644615168 1328761312 13.956 100 23 46 0 1339683152 5822272 5099568
17:25:58 5549 8192000000 10229585456 10645807104 1319393376 13.871 99 31 63 1 1337512096 11535984 6582736
17:27:04 7557 8192000000 10200566144 10617393152 1300451712 13.699 99 27 53 1 1312814112 6388160 5974240
17:28:12 29008 8192000000 10190535152 10611032064 1291954288 13.622 98 35 70 0 1304866208 5358112 7553808
17:29:43 6074 8192000000 10167749984 10577272832 1276903440 13.485 100 21 42 2 1287112048 5494896 4713712
17:30:51 6671 8192000000 10132000624 10523099136 1201129936 12.787 95 24 48 2 1212202816 5522704 5550176
17:32:00 7908 8192000000 10148486800 10547154944 1247210272 13.213 99 28 56 1 1260330496 7165904 5954320
17:33:12 32167 8192000000 10165421424 10575028224 1275161472 13.469 100 38 76 2 1289683072 5745104 8776496
17:34:45 6491 8192000000 10187533584 10598739968 1295561424 13.655 100 29 58 0 1309076240 6540288 6974528
17:35:52 6592 8192000000 10209264800 10622545920 1314227024 13.824 100 33 66 1 1327668880 5577424 7864432
17:37:00 9516 8192000000 10221137888 10636722176 1327669040 13.946 100 11 22 0 1334731456 4804864 2257552
17:38:11 42383 8192000000 10254357520 10668249088 1347922256 14.129 100 42 84 2 1364013728 6492320 9599152
17:40:03 10572 8192000000 10276529520 10692788224 1367148560 14.301 100 51 100 2 1388096704 9689104 11259040
17:41:19 6837 8192000000 10296325472 10716512256 1386742016 14.477 100 58 116 0 1413330480 13890496 12697968
17:42:28 20768 8192000000 10311526208 10728050688 1379913344 14.416 99 77 153 0 1413404032 16371520 17119168
17:43:58 11339 8192000000 10324677888 10740375552 1398430736 14.581 100 66 129 3 1431911488 18320304 15160448
17:45:13 3061 8192000000 10334707312 10749255680 1404052768 14.631 100 82 164 0 1446006160 22666816 19286576
17:46:20 9377 8192000000 10330842256 10751152128 1399267840 14.588 100 55 110 2 1439630656 27254960 13107856
17:47:38 2477 8192000000 10348482224 10763563008 1405021792 14.640 100 80 158 2 1453020048 29051216 18947040
17:48:44 10274 8192000000 10348113280 10770702336 1410398272 14.687 100 63 126 2 1453353888 28202368 14753248
17:50:00 4252 8192000000 10320124272 10746871808 1385282128 14.464 99 49 97 1 1425691680 28722640 11686912
17:51:09 3128 8192000000 10345999808 10759409664 1399703328 14.592 100 80 160 0 1450970096 31264208 20002560
17:52:18 7910 8192000000 10333703840 10761224192 1386845184 14.478 99 51 102 3 1439061600 39157248 13059168
17:53:30 2471 8192000000 10335513568 10740035584 1371630304 14.342 99 81 161 2 1430262528 38114032 20518192
17:54:38 1601 8192000000 10330656240 10737762304 1367447248 14.304 100 77 153 1 1433757504 46747392 19562864
17:55:44 6975 8192000000 10357651552 10773323776 1387170208 14.481 99 68 135 2 1451581568 47340512 17070848
17:56:54 22183 8192000000 10346695616 10775179264 1375833776 14.379 98 38 76 1 1432212064 46567952 9810336
17:58:22 4411 8192000000 10330836976 10747899904 1350975920 14.156 98 65 130 1 1422098368 53923920 17198528
17:59:34 2376 8192000000 10389468912 10801561600 1401821328 14.611 100 88 176 1 1493512464 68339888 23351248
18:00:41 26790 8192000000 10357348272 10781642752 1378817408 14.406 98 42 84 2 1446233760 55778160 11638192
18:02:16 9721 8192000000 10369785440 10795208704 1394910256 14.550 100 39 78 1 1470367040 64185728 11271056
18:03:28 21877 8192000000 10364850752 10805846016 1401324928 14.607 100 32 64 0 1470696576 59969808 9401840
18:04:54 34196 8192000000 10353105424 10820632576 1386134016 14.471 100 26 52 1 1465436400 71646944 7655440
18:06:44 45088 8192000000 10299073824 10777350144 1375809088 14.379 100 18 36 0 1433680368 52643344 5227936
18:08:30 72062 8192000000 10293097920 10748334080 1362410704 14.259 98 9 18 0 1376390848 11574480 2405664
18:10:53 90564 8192000000 10283001072 10739085312 1366703856 14.298 98 29 58 0 1382117664 8186272 7227536
18:13:27 69031 8192000000 10283532144 10741620736 1362163744 14.257 98 12 23 2 1377893488 12672288 3057456
18:16:58 46447 8192000000 10298274160 10746859520 1379485104 14.412 99 15 30 1 1391574704 8835152 3254448
18:18:45 73024 8192000000 10316158400 10766028800 1393725312 14.539 99 32 64 1 1411498464 11141888 6631264
18:21:20 69752 8192000000 10305344160 10766966784 1380664032 14.422 98 16 33 0 1395018768 11115184 3239552
18:23:30 91541 8192000000 10323228640 10784321536 1400969024 14.604 99 18 36 1 1424834800 19903120 3962656
18:26:13 75102 8192000000 10312288240 10764484608 1396817312 14.567 99 18 36 0 1407116000 6727552 3571136
18:28:31 118961 8192000000 10302832448 10771447808 1359283888 14.231 96 31 62 0 1378102576 12222432 6596256
18:31:40 44871 8192000000 10326279856 10789179392 1407483520 14.662 99 30 60 0 1431348752 17282576 6582656
18:33:26 29096 8192000000 10317035552 10769465344 1369330624 14.321 97 37 74 0 1392218176 14159680 8727872
18:35:01 70666 8192000000 10310506672 10777210880 1386526832 14.475 99 13 26 1 1413766400 23667904 3571664
18:37:11 126222 8192000000 10293786800 10771496960 1382819872 14.442 99 27 54 2 1409755584 20412688 6523024
18:40:35 144283 8192000000 10282793632 10743660544 1296374224 13.662 93 8 16 1 1312432848 14562464 1496160
18:44:14 45409 8192000000 10301212976 10751078400 1369115840 14.319 98 27 53 0 1386899424 10797888 6985696
18:46:06 34796 8192000000 10300166240 10740293632 1336446352 14.025 96 42 84 1 1357759392 11502080 9810960
18:47:42 67081 8192000000 10270960960 10731429888 1320276784 13.879 96 24 47 2 1338065344 12518448 5270112
18:49:57 23708 8192000000 10283681216 10732158976 1362772560 14.262 99 30 60 1 1381382928 11686944 6923424
18:51:22 127018 8192000000 10252771232 10706993152 1290653440 13.610 94 6 10 2 1303784352 11951552 1179360
18:54:49 72263 8192000000 10277143232 10739220480 1341360704 14.070 98 21 42 0 1363585760 17729872 4495184
18:57:03 91004 8192000000 10241024816 10689310720 1288116656 13.587 95 18 36 0 1304614000 11917744 4579600
18:59:35 26780 8192000000 10261994272 10715549696 1363046528 14.265 100 27 54 0 1379884240 10045008 6792704
19:01:09 50907 8192000000 10256060320 10714599424 1334367456 14.007 98 14 28 0 1350402160 12913408 3121296
19:03:01 47031 8192000000 10272371216 10727809024 1370078656 14.328 100 16 32 0 1387727744 13801216 3847872
19:05:00 51576 8192000000 10275078000 10734718976 1364987792 14.282 100 11 23 0 1386819216 19247392 2584032
19:06:51 81708 8192000000 10271873056 10731503616 1372088000 14.346 100 12 24 0 1385329600 10756992 2484608
19:09:13 64552 8192000000 10271605408 10729398272 1350554736 14.152 98 12 24 0 1362989856 9876688 2558432
19:11:18 25364 8192000000 10275891168 10728091648 1353318352 14.177 98 16 32 1 1363279120 6793120 3167648
19:12:49 32515 8192000000 10282344960 10734702592 1352702848 14.172 98 21 42 0 1365208640 7373456 5132336
19:14:25 17793 8192000000 10301255888 10750726144 1386233232 14.472 100 29 58 1 1404970960 12536736 6200992
19:15:45 69673 8192000000 10295290752 10752065536 1361882432 14.254 97 34 68 2 1386068240 16239760 7946048
19:17:55 58838 8192000000 10310177776 10762162176 1383054000 14.444 99 16 32 1 1400425040 13754880 3616160
19:20:34 66310 8192000000 10303955328 10750627840 1368277600 14.312 98 26 51 0 1388849408 14918992 5652816
19:22:49 17129 8192000000 10294567360 10732498944 1350677120 14.154 98 35 70 0 1372741552 14858736 7205696
19:24:06 166451 8192000000 10293795072 10754797568 1374360048 14.366 99 33 66 0 1401364736 19641856 7362832
19:27:55 82980 8192000000 10265306288 10728976384 1324047488 13.913 96 6 12 0 1336820688 11277456 1495744
19:30:24 73581 8192000000 10281839440 10731675648 1364308048 14.276 99 16 32 1 1378548752 10444016 3796688
19:32:45 47458 8192000000 10259131376 10715803648 1344383424 14.097 98 11 22 0 1354901472 7573088 2944960
19:34:33 40394 8192000000 10266022144 10724610048 1357080736 14.211 99 8 15 0 1366851744 8247088 1523920
19:36:21 44861 8192000000 10250807408 10702221312 1319774336 13.875 97 11 22 1 1332125552 9229136 3122080

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-11-2021 19:39:17 to 07-11-2021 19:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-11-2021 19:39:33 to 07-11-2021 19:39:35.

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          238644
MON$OLDEST_ACTIVE               240143
MON$OLDEST_SNAPSHOT             240143
MON$NEXT_TRANSACTION            240143
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                       2070112
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33528 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33528 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33528 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33528 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 ---
:190: 
:191: mscs-test-161.budzdorov.ru    Sun Nov  7 19:37:54 2021
:192:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:193: 
:194: 
:195: mscs-test-161.budzdorov.ru    Sun Nov  7 19:37:58 2021
:196:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:197: 
:198: 
:199: mscs-test-161.budzdorov.ru    Sun Nov  7 19:37:58 2021
:200:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:201: 
:202: 
:203: mscs-test-161.budzdorov.ru    Sun Nov  7 19:37:58 2021
:204:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:205: 
--- end of diff output ---

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

07.11.21 19:39:36. 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
2021-11-07 19:39:39 No severe PSQL-related problems occured
07.11.21 19:39:40. End of report.