Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.12.2021 01: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 16320476 kB
MemAvailable 31333456 kB
Buffers 0 kB
Cached 15312316 kB
SwapCached 440 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.9
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-12-27 01:37:57
DTS_END                         2021-12-27 01:37:57.4400
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44480
TRN_ID                          246241
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: 9740.61 19235 1168873
customer order: creation 180.42 339 21650
customer order: refuse 15.98 332 1917
order to supplier: creation 48.70 1380 5844
order to supplier: removal 3.68 98443 441
invoice (draft): creation 47.80 3275 5736
invoice (draft): removal 53.41 752 6409
invoice accept: apply 44.27 18410 5312
invoice accept: cancel 48.58 791 5830
customer reserve: creation 4143.87 181 497264
customer reserve: removal 4840.31 59 580837
realization accept: apply 88.33 166 10599
realization accept: cancel 87.63 101 10515
payment from customer: creation 64.13 98 7695
payment from customer: removal 5.96 79 715
payment to supplier: creation 50.93 134 6111
payment to supplier: removal 3.60 54 432
service: total inventory turnovers 4.83 1106 579
service: total monetary turnovers 7.29 37 875
service: aggregate perf. data 0.90 20434 108
service: refresh index statistics 0.03 238534 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-12-2021 01:38:37 to 27-12-2021 01:38:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2875
TEST_TIME 2 3457
TEST_TIME 3 4581
TEST_TIME 4 5050
TEST_TIME 5 4838
TEST_TIME 6 4881
TEST_TIME 7 5366
TEST_TIME 8 5288
TEST_TIME 9 5731
TEST_TIME 10 6233
TEST_TIME 11 6399
TEST_TIME 12 7111
TEST_TIME 13 7445
TEST_TIME 14 7450
TEST_TIME 15 7641
TEST_TIME 16 7692
TEST_TIME 17 7765
TEST_TIME 18 7892
TEST_TIME 19 7951
TEST_TIME 20 8311
TEST_TIME 21 8626
TEST_TIME 22 8727
TEST_TIME 23 9172
TEST_TIME 24 9419
TEST_TIME 25 9646
TEST_TIME 26 9776
TEST_TIME 27 10105
TEST_TIME 28 9946
TEST_TIME 29 10073
TEST_TIME 30 10346
TEST_TIME 31 10619
TEST_TIME 32 11074
TEST_TIME 33 10896
TEST_TIME 34 10861
TEST_TIME 35 10779
TEST_TIME 36 10647
TEST_TIME 37 10521
TEST_TIME 38 10581
TEST_TIME 39 10495
TEST_TIME 40 10583
TEST_TIME 41 10571
TEST_TIME 42 10601
TEST_TIME 43 10533
TEST_TIME 44 10557
TEST_TIME 45 10469
TEST_TIME 46 10343
TEST_TIME 47 10281
TEST_TIME 48 10424
TEST_TIME 49 10350
TEST_TIME 50 10265
TEST_TIME 51 10219
TEST_TIME 52 10206
TEST_TIME 53 10183
TEST_TIME 54 10107
TEST_TIME 55 10171
TEST_TIME 56 10136
TEST_TIME 57 10089
TEST_TIME 58 10117
TEST_TIME 59 10122
TEST_TIME 60 10050
TEST_TIME 61 10044
TEST_TIME 62 9994
TEST_TIME 63 10005
TEST_TIME 64 9935
TEST_TIME 65 9922
TEST_TIME 66 9896
TEST_TIME 67 9835
TEST_TIME 68 9851
TEST_TIME 69 9812
TEST_TIME 70 9920
TEST_TIME 71 10052
TEST_TIME 72 10034
TEST_TIME 73 10008
TEST_TIME 74 10017
TEST_TIME 75 9985
TEST_TIME 76 10038
TEST_TIME 77 10031
TEST_TIME 78 9983
TEST_TIME 79 9894
TEST_TIME 80 9889
TEST_TIME 81 9855
TEST_TIME 82 9804
TEST_TIME 83 9765
TEST_TIME 84 9772
TEST_TIME 85 9736
TEST_TIME 86 9696
TEST_TIME 87 9737
TEST_TIME 88 9757
TEST_TIME 89 9727
TEST_TIME 90 9737
TEST_TIME 91 9708
TEST_TIME 92 9755
TEST_TIME 93 9723
TEST_TIME 94 9813
TEST_TIME 95 9807
TEST_TIME 96 9787
TEST_TIME 97 9854
TEST_TIME 98 9871
TEST_TIME 99 9865
TEST_TIME 100 9890
TEST_TIME 101 9816
TEST_TIME 102 9753
TEST_TIME 103 9727
TEST_TIME 104 9720
TEST_TIME 105 9700
TEST_TIME 106 9701
TEST_TIME 107 9656
TEST_TIME 108 9647
TEST_TIME 109 9625
TEST_TIME 110 9615
TEST_TIME 111 9626
TEST_TIME 112 9615
TEST_TIME 113 9635
TEST_TIME 114 9618
TEST_TIME 115 9606
TEST_TIME 116 9682
TEST_TIME 117 9662
TEST_TIME 118 9640
TEST_TIME 119 9711
TEST_TIME 120 9742

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-12-2021 01:38:41 to 27-12-2021 01:38:44.

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 1626733 1626733 0 0.00 0 10965 6 0 0
doc_list_biud 1626732 1626732 0 0.00 0 22934 26 0 0
sp_add_invoice_to_stock 5431 5312 119 2.19 21 198568 18410 0 119
sp_cancel_adding_invoice 5830 5830 0 0.00 6 70959 791 0 0
sp_cancel_client_order 1917 1917 0 0.00 3 11122 333 0 0
sp_cancel_customer_reserve 580837 580837 0 0.00 1 22937 60 0 0
sp_cancel_pay_from_customer 715 715 0 0.00 3 7616 80 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 1386 54 0 0
sp_cancel_supplier_invoice 6413 6409 4 0.06 2 13855 752 0 4
sp_cancel_supplier_order 441 441 0 0.00 6 879429 98444 0 0
sp_cancel_write_off 10515 10515 0 0.00 1 13404 101 0 0
sp_client_order 21650 21650 0 0.00 3 20116 340 0 0
sp_customer_reserve 497264 497264 0 0.00 0 94073 182 0 0
sp_fill_shopping_cart 33214 33214 0 0.00 1 19302 36 0 0
sp_fill_shopping_cart_clo_res 497257 497257 0 0.00 0 15955 12 0 0
sp_get_clo_for_invoice 5305 5305 0 0.00 0 29070 247 0 0
sp_kill_cost_storno 17492 17492 0 0.00 0 6398 53 0 0
sp_kill_qstorno_ret_qs2qd 1179039 1179039 0 0.00 0 22912 32 0 0
sp_kill_qty_storno 621853 621853 0 0.00 0 22934 68 0 0
sp_lock_selected_doc 22057 22057 0 0.00 0 8567 6 0 0
sp_make_cost_storno 29710 29710 0 0.00 0 7951 49 0 0
sp_multiply_rows_for_pdistr 29710 29710 0 0.00 0 10941 28 0 0
sp_multiply_rows_for_qdistr 21650 21650 0 0.00 0 10599 149 0 0
sp_pay_from_customer 7695 7695 0 0.00 4 7947 99 0 0
sp_pay_to_supplier 6111 6111 0 0.00 4 10968 135 0 0
sp_payment_common 13805 13805 0 0.00 1 9856 25 0 0
sp_qd_handle_on_cancel_clo 1917 1917 0 0.00 0 11116 297 0 0
sp_qd_handle_on_invoice_upd_sts 11135 11135 0 0.00 0 12955 314 0 0
sp_qd_handle_on_reserve_upd_sts 10599 10599 0 0.00 0 15472 52 0 0
sp_reserve_write_off 10599 10599 0 0.00 6 15482 166 0 0
sp_supplier_invoice 5736 5736 0 0.00 6 75547 3276 0 0
sp_supplier_order 5844 5844 0 0.00 5 41754 1380 0 0
srv_aggregate_perf_data 108 108 0 0.00 3080 384116 20434 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1720 110990 40814 0 0
srv_find_qd_qs_mism 1116828 1116828 0 0.00 0 10598 6 0 0
srv_make_invnt_saldo 579 579 0 0.00 43 52389 1106 0 0
srv_make_money_saldo 875 875 0 0.00 1 8510 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 162150 393590 238535 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5312 5312 0 0.00 0 8150 173 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 1 7785 116 0 0
t$perf-norm:sp_cancel_client_order 1917 1917 0 0.00 0 3492 10 0 0
t$perf-norm:sp_cancel_customer_reserve 741 741 0 0.00 0 2626 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 715 715 0 0.00 0 3257 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 231 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 1086 17 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 35803 1730 0 0
t$perf-norm:sp_cancel_write_off 1074 1074 0 0.00 0 2490 10 0 0
t$perf-norm:sp_client_order 21650 21650 0 0.00 0 5430 16 0 0
t$perf-norm:sp_customer_reserve 1061 1061 0 0.00 0 997 5 0 0
t$perf-norm:sp_pay_from_customer 7695 7695 0 0.00 0 4800 9 0 0
t$perf-norm:sp_pay_to_supplier 6111 6111 0 0.00 0 3081 9 0 0
t$perf-norm:sp_reserve_write_off 10599 10599 0 0.00 0 3235 9 0 0
t$perf-norm:sp_supplier_invoice 5736 5736 0 0.00 0 3269 16 0 0
t$perf-norm:sp_supplier_order 5844 5844 0 0.00 0 3331 15 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 514 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 2473 38 0 0
t$perf-norm:srv_make_invnt_saldo 579 579 0 0.00 0 1400 20 0 0
t$perf-norm:srv_make_money_saldo 875 875 0 0.00 0 3972 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10848 10610 238 2.19 0 8019 23 0 238
v_all_customers 40096 40096 0 0.00 0 15 1 0 0
v_all_suppliers 31992 31992 0 0.00 0 18 1 0 0
v_all_wares 43296 43296 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 883 6 0 0
v_cancel_client_order 3834 3834 0 0.00 0 3395 9 0 0
v_cancel_customer_prepayment 1430 1430 0 0.00 0 954 6 0 0
v_cancel_customer_reserve 1482 1482 0 0.00 0 3408 10 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 1371 5 0 0
v_cancel_supplier_invoice 782 774 8 1.02 0 4777 28 0 8
v_cancel_supplier_order 882 882 0 0.00 0 500 4 0 0
v_cancel_write_off 2148 2148 0 0.00 0 2078 13 0 0
v_max_id_clo_ord 5839 5839 0 0.00 0 378 1 0 0
v_max_id_clo_res 1061 1061 0 0.00 0 2937 14 0 0
v_max_id_ord_sup 5727 5727 0 0.00 0 4159 4 0 0
v_max_non_paid_invoice 1681 1681 0 0.00 0 1052 3 0 0
v_max_non_paid_realizn 3878 3878 0 0.00 0 1799 4 0 0
v_min_id_clo_ord 5839 5839 0 0.00 0 5051 6 0 0
v_min_id_clo_res 1061 1061 0 0.00 0 889 2 0 0
v_min_id_ord_sup 5727 5727 0 0.00 0 7222 8 0 0
v_min_non_paid_invoice 6111 6111 0 0.00 0 9852 23 0 0
v_min_non_paid_realizn 7694 7694 0 0.00 0 6357 18 0 0
v_our_firm 10832 10832 0 0.00 0 17 1 0 0
v_reserve_write_off 21198 21198 0 0.00 0 7764 6 0 0
x_lock_depdocs_on_canc_invoice 11852 11852 0 0.00 0 19777 121 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 8626 327 0 0
x_make_qstorno_1000_1200 5844 5844 0 0.00 2 41629 1299 0 0
x_make_qstorno_1200_2000 5736 5736 0 0.00 3 73128 3148 0 0
x_make_qstorno_2100_3300 497264 497264 0 0.00 0 94071 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-12-2021 01:38:45 to 27-12-2021 01:38:49.

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
23:08:01 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:01 23847 8192000000 9903879408 10217299968 989751888 10.779 100 88 174 1 1018964096 6410480 22801728
23:10:28 26319 8192000000 9954904912 10280509440 1034543056 11.212 100 83 167 0 1064330032 8665552 21121424
23:12:02 15111 8192000000 9987155856 10319978496 1067463168 11.528 100 84 168 0 1098477072 10325632 20688272
23:13:25 11695 8192000000 10044468608 10396143616 1130752320 12.128 100 82 162 2 1161446768 11819008 18875440
23:14:40 7316 8192000000 10085886912 10446974976 1176049296 12.553 100 79 157 1 1206311072 11756272 18505504
23:15:51 5511 8192000000 10149111456 10521436160 1228555472 13.041 100 84 169 0 1263642208 17130288 17956448
23:16:58 5451 8192000000 10183530128 10564194304 1263911904 13.366 100 85 168 2 1296351952 14231920 18208128
23:18:06 1725 8192000000 10211689968 10592817152 1292244256 13.625 100 94 186 0 1327352496 14870112 20238128
23:19:10 19049 8192000000 10205116736 10605600768 1302171744 13.715 100 56 112 0 1323980480 8574752 13233984
23:20:30 6084 8192000000 10197963584 10614767616 1314741520 13.829 100 26 51 1 1327087648 6488256 5857872
23:21:37 8552 8192000000 10202989024 10627239936 1325762000 13.929 100 23 46 0 1337389120 6098896 5528224
23:22:46 43813 8192000000 10219147024 10649780224 1332400016 13.989 100 25 49 0 1347824240 9927296 5496928
23:24:30 5975 8192000000 10233627808 10658709504 1344422480 14.097 100 26 52 0 1355775936 5668720 5684736
23:25:38 5237 8192000000 10243376016 10662842368 1345897760 14.111 100 27 54 1 1359023792 7222896 5903136
23:26:43 6236 8192000000 10233294464 10656268288 1332379664 13.989 99 17 34 1 1342504320 6026624 4098032
23:27:50 17292 8192000000 10203528096 10624208896 1294861936 13.649 97 49 98 1 1312698080 5585296 12250848
23:29:08 4777 8192000000 10184260976 10594480128 1268729072 13.410 97 28 56 0 1281835184 5257760 7848352
23:30:17 7486 8192000000 10175277408 10582343680 1272441200 13.444 99 22 44 4 1283668816 5440352 5787264
23:31:25 11501 8192000000 10142539136 10541875200 1234414608 13.095 99 23 46 0 1249714480 10085952 5213920
23:32:41 15501 8192000000 10136765840 10542276608 1217077824 12.935 97 18 36 1 1227040000 5522768 4439408
23:34:02 7892 8192000000 10171080800 10581958656 1269766704 13.419 99 27 53 1 1281360416 5410592 6183120
23:35:10 5959 8192000000 10193580800 10603634688 1295615824 13.655 100 37 74 3 1310261328 5461280 9184224
23:36:16 19867 8192000000 10191605232 10610589696 1302681136 13.720 100 23 45 2 1314744208 5557984 6505088
23:37:42 8079 8192000000 10200403520 10615455744 1306179344 13.751 100 16 31 0 1315218880 5682944 3356592
23:38:56 14285 8192000000 10239897408 10650243072 1331934304 13.985 100 43 86 1 1348639536 6856928 9848304
23:40:12 3959 8192000000 10263852224 10676535296 1354037280 14.184 100 46 90 0 1375202128 10298304 10866544
23:41:19 20637 8192000000 10278282064 10696527872 1368471056 14.313 100 50 100 0 1392434176 12271776 11691344
23:42:43 37106 8192000000 10278493760 10705842176 1375939472 14.380 100 34 68 1 1396130320 11751792 8439056
23:44:26 9399 8192000000 10297563536 10722430976 1388904192 14.496 100 40 76 3 1412013616 13026176 10083248
23:45:48 10633 8192000000 10317430624 10739712000 1396286400 14.562 100 52 104 5 1424807168 15797728 12723040
23:47:04 11423 8192000000 10331235408 10751602688 1403624816 14.627 100 66 132 1 1440858832 21600784 15633232
23:48:23 4262 8192000000 10319938384 10737250304 1389599936 14.502 99 69 136 2 1426584992 21140272 15844784
23:49:34 5200 8192000000 10342117312 10757029888 1400057808 14.596 100 76 152 1 1449020112 30734880 18227424
23:50:47 1717 8192000000 10355104448 10774900736 1414371088 14.723 100 82 164 1 1463802032 29319328 20111616
23:51:53 2086 8192000000 10338528272 10763939840 1407341872 14.660 100 61 122 2 1448472224 25574336 15556016
23:53:02 3021 8192000000 10352306672 10774052864 1410498336 14.688 100 70 139 1 1459252640 31189584 17564720
23:54:08 4246 8192000000 10353113952 10774646784 1408380256 14.670 100 59 119 0 1458667504 35299696 14987552
23:55:17 7573 8192000000 10351655728 10765856768 1400529872 14.600 100 67 134 0 1452764464 35589632 16644960
23:56:29 4509 8192000000 10353849088 10765586432 1393066016 14.533 100 69 138 0 1456703664 45726928 17910720
23:57:39 5840 8192000000 10356900960 10766934016 1387853232 14.487 99 63 123 1 1454092624 50170160 16069232
23:58:48 15751 8192000000 10347510896 10771542016 1393373504 14.536 100 46 92 1 1451289248 45410896 12504848
00:00:09 5024 8192000000 10344792112 10768527360 1366488464 14.296 98 49 95 4 1430540656 51103088 12949104
00:01:26 15897 8192000000 10325737072 10760933376 1379262784 14.410 100 45 90 2 1437115136 45622752 12229600
00:02:49 18958 8192000000 10338519904 10770149376 1390012368 14.506 100 33 66 0 1441538064 42355360 9170336
00:04:12 12887 8192000000 10331179792 10770661376 1387089120 14.480 100 30 60 1 1439244016 44265952 7888944
00:05:38 30716 8192000000 10323936368 10759208960 1383547104 14.448 100 33 66 1 1429842064 37719984 8574976
00:07:13 30295 8192000000 10303832944 10763456512 1353656864 14.180 99 11 22 0 1400855488 43815440 3383184
00:08:45 37380 8192000000 10283884720 10746683392 1340542928 14.062 98 10 20 0 1376136240 33017200 2576112
00:10:22 54826 8192000000 10272767088 10710573056 1341894080 14.074 98 19 38 0 1353182928 7653088 3635760
00:12:19 77856 8192000000 10270719104 10708602880 1312261392 13.807 96 13 26 0 1323891104 8945488 2684224
00:14:37 80317 8192000000 10295455472 10745233408 1371204256 14.338 100 36 72 0 1389224848 9263808 8756784
00:17:01 72436 8192000000 10302121520 10767978496 1382396032 14.438 100 8 16 0 1402134384 17777392 1960960
00:19:15 43946 8192000000 10284084912 10733416448 1344692976 14.100 97 13 26 0 1358913760 11731984 2488800
00:21:00 74709 8192000000 10286552896 10746150912 1370177040 14.329 99 11 21 0 1386773728 13661248 2935440
00:23:18 48976 8192000000 10297343520 10744336384 1383759808 14.450 100 47 94 2 1402977056 7994736 11222512
00:25:09 67671 8192000000 10299362560 10752569344 1379732400 14.414 99 24 47 0 1402284400 17367152 5184848
00:27:18 84759 8192000000 10315321456 10757914624 1376655792 14.387 98 51 98 1 1397762944 9249696 11857456
00:29:49 33987 8192000000 10320761648 10766155776 1395422864 14.554 99 22 44 0 1414760352 14118656 5218832
00:31:37 19419 8192000000 10325631472 10778726400 1391145984 14.516 99 42 84 2 1421309840 20396800 9767056
00:32:58 34927 8192000000 10321465984 10795126784 1411436320 14.697 100 6 12 0 1435582448 22281072 1865056
00:34:40 32671 8192000000 10307761248 10769563648 1392780976 14.531 99 15 30 2 1416709760 20066320 3862464
00:37:17 68928 8192000000 10312922688 10779500544 1399364048 14.589 99 30 60 0 1417397968 10437344 7596576
00:39:28 20659 8192000000 10296910528 10747949056 1391779424 14.522 100 28 53 1 1403239888 6045696 5414768
00:40:49 75943 8192000000 10266099744 10734034944 1342373968 14.079 97 10 20 0 1353419664 8874272 2171424
00:43:23 40226 8192000000 10266741376 10726514688 1341923936 14.075 97 10 20 0 1355000176 10883712 2192528
00:45:10 43254 8192000000 10280912816 10732314624 1344512384 14.098 98 40 81 3 1370646448 17407968 8726096
00:46:55 78860 8192000000 10272108992 10734141440 1338680208 14.046 97 19 37 0 1369837776 26162416 4995152
00:49:15 23715 8192000000 10294542336 10734694400 1373107312 14.355 100 55 110 2 1395978368 10691744 12179312
00:50:41 41749 8192000000 10265628144 10718371840 1336937888 14.030 98 30 60 2 1355525296 11871504 6715904
00:52:24 36978 8192000000 10276649952 10713739264 1335851952 14.020 98 27 55 0 1351815600 9690608 6273040
00:54:02 48065 8192000000 10258361360 10703286272 1317875104 13.857 97 24 48 0 1332365536 9350704 5139728
00:55:52 72043 8192000000 10277738032 10710032384 1354091248 14.184 100 58 116 2 1379899696 13004128 12804320
00:58:05 48462 8192000000 10258724528 10708049920 1335671024 14.018 98 32 63 1 1351454016 8143008 7639984
00:59:56 57290 8192000000 10270198064 10733506560 1359355696 14.232 99 9 17 0 1372490384 11367312 1767376
01:02:01 72693 8192000000 10265571440 10725548032 1354340240 14.187 99 8 16 0 1368082112 11894800 1847072
01:04:57 100726 8192000000 10285494800 10738905088 1354161264 14.185 98 14 28 1 1372648960 15242944 3244752
01:07:40 37871 8192000000 10301989440 10746978304 1389030320 14.497 100 49 98 0 1408719936 9314512 10375104
01:09:18 58295 8192000000 10286496176 10738143232 1361266304 14.249 98 17 34 1 1373632032 9009712 3356016
01:11:17 75202 8192000000 10300385568 10766483456 1382878192 14.442 99 9 18 0 1397982672 13193776 1910704
01:14:10 100908 8192000000 10305728880 10767470592 1353375984 14.178 96 17 34 0 1365848784 8510016 3962784
01:16:55 21943 8192000000 10315033104 10761302016 1386252336 14.472 99 44 88 0 1408265728 11682608 10330784
01:18:23 110990 8192000000 10304717136 10756845568 1394732928 14.548 100 29 58 3 1410613200 9284752 6595520
01:21:14 75232 8192000000 10292801872 10765234176 1384241232 14.454 99 6 12 0 1400827696 15248608 1337856
01:23:29 75655 8192000000 10264958816 10727002112 1319341472 13.871 95 18 36 0 1333105744 9008416 4755856
01:25:46 45391 8192000000 10271144352 10737131520 1358931520 14.228 99 10 20 0 1373964000 13135344 1897136
01:27:33 60335 8192000000 10268234992 10725847040 1317810080 13.857 96 15 30 0 1335751344 13686224 4255040
01:29:40 25616 8192000000 10276266688 10728435712 1364347536 14.276 99 32 64 1 1381863872 10309616 7206720
01:31:09 64912 8192000000 10263196576 10704666624 1300313328 13.698 95 28 56 0 1319417440 12954768 6149344
01:33:17 71938 8192000000 10276708048 10713448448 1331493808 13.981 98 23 46 2 1345512208 9424832 4593568
01:35:29 29289 8192000000 10279038560 10713821184 1319786352 13.875 98 36 72 0 1343177168 15561344 7829472
01:37:54 12013 8192000000 8923194528 9093758976 92186496 1.112 8 1 2 0 93382448 1153600 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-12-2021 01:38:51 to 27-12-2021 01:39:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-12-2021 01:39:07 to 27-12-2021 01:39:09.

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          246621
MON$OLDEST_ACTIVE               246622
MON$OLDEST_SNAPSHOT             246622
MON$NEXT_TRANSACTION            246622
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                       2052512
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

27.12.21 01:39:10. 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-12-27 01:39:13 No severe PSQL-related problems occured
27.12.21 01:39:14. End of report.