Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.08.2021 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 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 16259532 kB
MemAvailable 30881580 kB
Buffers 0 kB
Cached 15402196 kB
SwapCached 324 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-08-31 22:37:48
DTS_END                         2021-08-31 22:37:48.0480
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/33626
TRN_ID                          230094
ATT_ID                          656
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: 9055.63 21874 1086675
customer order: creation 162.22 363 19466
customer order: refuse 14.27 373 1712
order to supplier: creation 44.50 1353 5340
order to supplier: removal 3.50 106071 420
invoice (draft): creation 43.78 3431 5254
invoice (draft): removal 53.38 852 6405
invoice accept: apply 39.54 18998 4745
invoice accept: cancel 47.41 767 5689
customer reserve: creation 3703.34 186 444401
customer reserve: removal 4659.88 62 559185
realization accept: apply 77.70 199 9324
realization accept: cancel 84.45 109 10134
payment from customer: creation 55.27 132 6632
payment from customer: removal 5.45 81 654
payment to supplier: creation 46.34 150 5561
payment to supplier: removal 3.29 81 395
service: total inventory turnovers 4.55 1413 546
service: total monetary turnovers 6.06 60 727
service: aggregate perf. data 0.68 30031 81
service: refresh index statistics 0.03 272764 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-08-2021 22:38:51 to 31-08-2021 22:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5303
TEST_TIME 2 3729
TEST_TIME 3 2877
TEST_TIME 4 3193
TEST_TIME 5 3872
TEST_TIME 6 4003
TEST_TIME 7 4190
TEST_TIME 8 4246
TEST_TIME 9 4615
TEST_TIME 10 4967
TEST_TIME 11 4757
TEST_TIME 12 4914
TEST_TIME 13 5134
TEST_TIME 14 5322
TEST_TIME 15 5632
TEST_TIME 16 6026
TEST_TIME 17 6300
TEST_TIME 18 6367
TEST_TIME 19 7186
TEST_TIME 20 7097
TEST_TIME 21 7393
TEST_TIME 22 7696
TEST_TIME 23 7837
TEST_TIME 24 8206
TEST_TIME 25 8375
TEST_TIME 26 8642
TEST_TIME 27 9593
TEST_TIME 28 10417
TEST_TIME 29 10171
TEST_TIME 30 9925
TEST_TIME 31 9961
TEST_TIME 32 9898
TEST_TIME 33 9722
TEST_TIME 34 9742
TEST_TIME 35 9658
TEST_TIME 36 9513
TEST_TIME 37 9558
TEST_TIME 38 9518
TEST_TIME 39 9360
TEST_TIME 40 9227
TEST_TIME 41 9237
TEST_TIME 42 9213
TEST_TIME 43 9117
TEST_TIME 44 9035
TEST_TIME 45 8999
TEST_TIME 46 8936
TEST_TIME 47 9006
TEST_TIME 48 9123
TEST_TIME 49 9035
TEST_TIME 50 8933
TEST_TIME 51 8967
TEST_TIME 52 8908
TEST_TIME 53 8898
TEST_TIME 54 8929
TEST_TIME 55 9032
TEST_TIME 56 8998
TEST_TIME 57 9072
TEST_TIME 58 9126
TEST_TIME 59 9181
TEST_TIME 60 9213
TEST_TIME 61 9307
TEST_TIME 62 9251
TEST_TIME 63 9348
TEST_TIME 64 9354
TEST_TIME 65 9462
TEST_TIME 66 9438
TEST_TIME 67 9366
TEST_TIME 68 9302
TEST_TIME 69 9254
TEST_TIME 70 9300
TEST_TIME 71 9213
TEST_TIME 72 9130
TEST_TIME 73 9139
TEST_TIME 74 9080
TEST_TIME 75 9141
TEST_TIME 76 9071
TEST_TIME 77 9045
TEST_TIME 78 9070
TEST_TIME 79 9038
TEST_TIME 80 8985
TEST_TIME 81 8931
TEST_TIME 82 8916
TEST_TIME 83 8881
TEST_TIME 84 8849
TEST_TIME 85 8842
TEST_TIME 86 8852
TEST_TIME 87 8887
TEST_TIME 88 8995
TEST_TIME 89 8982
TEST_TIME 90 8988
TEST_TIME 91 9068
TEST_TIME 92 9058
TEST_TIME 93 9120
TEST_TIME 94 9087
TEST_TIME 95 9157
TEST_TIME 96 9161
TEST_TIME 97 9209
TEST_TIME 98 9338
TEST_TIME 99 9255
TEST_TIME 100 9189
TEST_TIME 101 9137
TEST_TIME 102 9113
TEST_TIME 103 9154
TEST_TIME 104 9097
TEST_TIME 105 9053
TEST_TIME 106 9078
TEST_TIME 107 9074
TEST_TIME 108 9061
TEST_TIME 109 9062
TEST_TIME 110 9027
TEST_TIME 111 9005
TEST_TIME 112 8988
TEST_TIME 113 8987
TEST_TIME 114 8975
TEST_TIME 115 8951
TEST_TIME 116 8950
TEST_TIME 117 8976
TEST_TIME 118 9078
TEST_TIME 119 9060
TEST_TIME 120 9058

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-08-2021 22:38:54 to 31-08-2021 22:38:58.

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 1497945 1497945 0 0.00 0 27327 6 0 0
doc_list_biud 1497945 1497945 0 0.00 0 61359 29 0 0
sp_add_invoice_to_stock 4858 4745 113 2.32 54 268935 18998 0 113
sp_cancel_adding_invoice 5689 5689 0 0.00 5 48240 768 0 0
sp_cancel_client_order 1712 1712 0 0.00 4 12723 373 0 0
sp_cancel_customer_reserve 559185 559185 0 0.00 1 20818 62 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 3 4321 82 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 2889 81 0 0
sp_cancel_supplier_invoice 6414 6405 9 0.14 2 61387 852 0 9
sp_cancel_supplier_order 420 420 0 0.00 10 1186552 106071 0 0
sp_cancel_write_off 10134 10134 0 0.00 1 11282 110 0 0
sp_client_order 19466 19466 0 0.00 3 23266 363 0 0
sp_customer_reserve 444401 444401 0 0.00 0 72709 187 0 0
sp_fill_shopping_cart 30042 30042 0 0.00 1 15571 43 0 0
sp_fill_shopping_cart_clo_res 444400 444400 0 0.00 0 21282 16 0 0
sp_get_clo_for_invoice 4742 4742 0 0.00 0 20568 362 0 0
sp_kill_cost_storno 16872 16872 0 0.00 0 11232 58 0 0
sp_kill_qstorno_ret_qs2qd 1135329 1135329 0 0.00 0 61343 34 0 0
sp_kill_qty_storno 597610 597610 0 0.00 0 61353 73 0 0
sp_lock_selected_doc 19600 19600 0 0.00 0 9980 6 0 0
sp_make_cost_storno 26258 26258 0 0.00 0 26548 62 0 0
sp_multiply_rows_for_pdistr 26258 26258 0 0.00 0 12115 33 0 0
sp_multiply_rows_for_qdistr 19466 19466 0 0.00 0 23258 166 0 0
sp_pay_from_customer 6632 6632 0 0.00 4 13249 133 0 0
sp_pay_to_supplier 5561 5561 0 0.00 5 13380 150 0 0
sp_payment_common 12193 12193 0 0.00 1 13237 42 0 0
sp_qd_handle_on_cancel_clo 1712 1712 0 0.00 0 12634 346 0 0
sp_qd_handle_on_invoice_upd_sts 10430 10430 0 0.00 0 17868 323 0 0
sp_qd_handle_on_reserve_upd_sts 9324 9324 0 0.00 0 14571 63 0 0
sp_reserve_write_off 9324 9324 0 0.00 7 14809 199 0 0
sp_supplier_invoice 5254 5254 0 0.00 6 88628 3432 0 0
sp_supplier_order 5340 5340 0 0.00 5 48608 1354 0 0
srv_aggregate_perf_data 81 81 0 0.00 3042 463935 30032 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1210 135404 48729 0 0
srv_find_qd_qs_mism 1041165 1041165 0 0.00 0 14509 5 0 0
srv_make_invnt_saldo 546 546 0 0.00 43 191168 1413 0 0
srv_make_money_saldo 727 727 0 0.00 0 12530 60 0 0
srv_recalc_idx_stat 4 4 0 0.00 105320 448887 272765 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 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 4746 4746 0 0.00 0 29051 181 0 0
t$perf-norm:sp_cancel_adding_invoice 366 366 0 0.00 0 4185 110 0 0
t$perf-norm:sp_cancel_client_order 1712 1712 0 0.00 0 10326 17 0 0
t$perf-norm:sp_cancel_customer_reserve 621 621 0 0.00 0 5456 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 3966 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 12638 34 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 3188 30 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 24999 1762 0 0
t$perf-norm:sp_cancel_write_off 966 966 0 0.00 0 2297 9 0 0
t$perf-norm:sp_client_order 19466 19466 0 0.00 0 14112 18 0 0
t$perf-norm:sp_customer_reserve 917 917 0 0.00 0 5519 20 0 0
t$perf-norm:sp_pay_from_customer 6632 6632 0 0.00 0 27566 18 0 0
t$perf-norm:sp_pay_to_supplier 5561 5561 0 0.00 0 10581 13 0 0
t$perf-norm:sp_reserve_write_off 9324 9324 0 0.00 0 7267 13 0 0
t$perf-norm:sp_supplier_invoice 5254 5254 0 0.00 0 7712 21 0 0
t$perf-norm:sp_supplier_order 5340 5340 0 0.00 0 8740 20 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 117 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 5808 97 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 13610 54 0 0
t$perf-norm:srv_make_money_saldo 727 727 0 0.00 0 1993 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 9706 9480 226 2.32 0 4047 17 0 226
v_all_customers 35432 35432 0 0.00 0 55 1 0 0
v_all_suppliers 29194 29194 0 0.00 0 21 1 0 0
v_all_wares 38932 38932 0 0.00 0 88 1 0 0
v_cancel_adding_invoice 732 732 0 0.00 0 2042 8 0 0
v_cancel_client_order 3424 3424 0 0.00 0 6416 7 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 4309 8 0 0
v_cancel_customer_reserve 1242 1242 0 0.00 0 2272 6 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 2874 10 0 0
v_cancel_supplier_invoice 822 804 18 2.18 0 2431 17 0 18
v_cancel_supplier_order 840 840 0 0.00 0 4333 15 0 0
v_cancel_write_off 1932 1932 0 0.00 0 2537 13 0 0
v_max_id_clo_ord 5340 5340 0 0.00 0 395 1 0 0
v_max_id_clo_res 917 917 0 0.00 0 3284 7 0 0
v_max_id_ord_sup 5236 5236 0 0.00 0 1332 2 0 0
v_max_non_paid_invoice 1540 1540 0 0.00 0 12196 10 0 0
v_max_non_paid_realizn 3543 3543 0 0.00 0 4913 6 0 0
v_min_id_clo_ord 5340 5340 0 0.00 0 14917 18 0 0
v_min_id_clo_res 917 917 0 0.00 0 667 2 0 0
v_min_id_ord_sup 5236 5236 0 0.00 0 4603 6 0 0
v_min_non_paid_invoice 5561 5561 0 0.00 0 8503 28 0 0
v_min_non_paid_realizn 6632 6632 0 0.00 0 11281 30 0 0
v_our_firm 9678 9678 0 0.00 0 13 1 0 0
v_reserve_write_off 18648 18648 0 0.00 0 6453 6 0 0
x_lock_depdocs_on_canc_invoice 11692 11692 0 0.00 0 12564 115 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 12466 388 0 0
x_make_qstorno_1000_1200 5340 5340 0 0.00 1 48593 1253 0 0
x_make_qstorno_1200_2000 5254 5254 0 0.00 3 84417 3290 0 0
x_make_qstorno_2100_3300 444401 444401 0 0.00 0 72688 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-08-2021 22:38:59 to 31-08-2021 22:39:03.

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:07:51 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:52 19717 8192000000 10349420320 10669268992 1409476624 14.679 100 90 180 0 1445108352 5846192 29785536
20:10:21 16412 8192000000 10429433920 10758717440 1480986000 15.310 100 86 171 0 1516313248 7706832 27620416
20:11:50 16888 8192000000 10485242688 10829725696 1542779872 15.848 100 78 156 0 1576382624 9020768 24581984
20:13:10 11905 8192000000 10581739296 10937069568 1631053072 16.604 100 80 160 1 1664757792 10253152 23451568
20:14:25 11894 8192000000 10655368608 11026530304 1716699856 17.325 100 88 174 1 1754330832 12344912 25286064
20:15:39 4428 8192000000 10720842592 11095117824 1774093072 17.801 100 76 152 0 1808436912 13494048 20849792
20:16:45 2700 8192000000 10765891776 11147001856 1818908880 18.169 100 88 176 1 1858301632 14149264 25243488
20:17:49 3905 8192000000 10785346544 11171086336 1840831280 18.348 100 83 166 0 1876605168 12722192 23051696
20:18:56 1627 8192000000 10826781440 11214807040 1880047152 18.665 100 93 188 0 1919844128 13116992 26679984
20:19:58 10072 8192000000 10824607696 11217424384 1883040464 18.690 100 82 162 0 1914159248 8292256 22826528
20:21:09 5687 8192000000 10815891712 11235176448 1893695824 18.776 100 38 74 3 1911688800 7757152 10235824
20:22:21 10990 8192000000 10815291136 11248066560 1903281392 18.853 100 20 40 2 1919250032 10024400 5944240
20:23:33 11695 8192000000 10813186272 11250044928 1908550496 18.895 100 20 40 2 1919237104 5566336 5120272
20:24:49 20260 8192000000 10862761328 11275603968 1924113088 19.020 100 67 134 4 1950186864 5711856 20361920
20:26:10 5218 8192000000 10825338544 11260252160 1908004656 18.891 100 30 59 1 1924004880 7275456 8724768
20:27:17 9235 8192000000 10790218080 11225661440 1841990576 18.357 97 13 26 1 1851450944 5836352 3624016
20:28:28 24815 8192000000 10797439264 11221868544 1804875232 18.054 95 34 69 3 1818456128 4732128 8848768
20:30:21 6312 8192000000 10762327888 11177508864 1826427600 18.230 99 27 54 2 1840037104 5556480 8053024
20:31:28 5383 8192000000 10720573872 11144511488 1798801184 18.004 99 26 50 3 1812126816 5750400 7575232
20:32:34 6486 8192000000 10713112848 11132604416 1790202976 17.933 99 31 62 2 1805954208 6247888 9503344
20:33:41 62035 8192000000 10743722256 11177357312 1838833808 18.331 100 19 36 2 1849372656 5061216 5477632
20:35:44 5198 8192000000 10778736064 11208912896 1861626656 18.516 100 27 53 1 1879143296 10454592 7062048
20:36:52 12810 8192000000 10788572480 11219914752 1872355456 18.603 100 14 28 1 1886156256 9378208 4422592
20:38:06 8915 8192000000 10843307072 11262197760 1914401072 18.942 100 36 72 1 1931161168 5530160 11229936
20:39:18 103552 8192000000 10861464816 11294756864 1916997936 18.963 99 36 71 0 1936964448 9969648 9996864
20:42:13 19162 8192000000 10889673872 11322822656 1955221424 19.268 100 41 81 2 1981254016 14858224 11174368
20:43:57 26222 8192000000 10917773824 11359539200 1977147920 19.442 100 33 66 0 2012604192 25360288 10095984
20:45:26 6972 8192000000 10940838848 11381874688 2002692816 19.644 100 45 89 3 2039684672 23013808 13978048
20:46:45 6858 8192000000 10949491296 11387506688 2000762512 19.629 100 56 112 1 2047454160 29684864 17006784
20:47:58 33018 8192000000 10965470800 11408027648 2012545936 19.722 100 51 99 1 2057409728 29342416 15521376
20:49:36 3910 8192000000 10995796608 11421720576 2010653312 19.707 99 77 150 0 2066166336 33072608 22440416
20:50:42 3396 8192000000 10998774864 11422076928 2018996192 19.772 100 79 158 0 2082404000 38701760 24706048
20:51:51 2312 8192000000 11017396640 11440361472 2031626000 19.871 100 80 158 0 2102086544 46697040 23763504
20:53:00 1373 8192000000 11017712000 11452891136 2034976272 19.898 100 68 133 1 2102651824 46900704 20774848
20:54:07 2410 8192000000 11026324816 11449196544 2032226560 19.876 100 77 154 2 2105969872 49888688 23854624
20:55:16 2719 8192000000 11001989888 11429392384 2004787312 19.660 99 74 148 1 2080776768 52745440 23244016
20:56:25 3080 8192000000 10969929904 11396075520 1970947520 19.393 100 71 140 2 2056610656 63279424 22383712
20:57:37 1207 8192000000 10983525040 11404988416 1992161024 19.561 100 80 159 1 2067482864 49246000 26075840
20:58:42 16043 8192000000 10970959024 11413475328 1998549840 19.611 100 37 74 0 2059195424 48359680 12285904
21:00:03 22478 8192000000 10972064960 11417292800 1969924816 19.385 99 33 64 1 2038033536 57041104 11067616
21:01:38 17825 8192000000 10972336144 11425185792 1992224736 19.561 100 36 72 3 2052454368 48192608 12037024
21:03:33 14004 8192000000 10928110560 11389906944 1929841568 19.066 98 27 54 1 1996009216 56310608 9857040
21:04:55 49855 8192000000 10872861616 11329335296 1827902896 18.242 94 10 20 0 1856469088 25494976 3071216
21:06:45 98356 8192000000 10884525744 11319271424 1912366224 18.926 98 44 83 1 1935867504 12204560 11296720
21:10:20 135404 8192000000 10847124368 11297869824 1902553952 18.847 99 28 57 0 1921726112 11330816 7841344
21:13:55 47810 8192000000 10864217648 11324387328 1939052656 19.139 100 15 30 1 1951833728 8781264 3999808
21:15:49 42933 8192000000 10877789120 11329908736 1919790688 18.985 98 6 12 0 1929487856 7937088 1760080
21:17:32 94082 8192000000 10865849056 11344773120 1940931328 19.154 99 22 44 1 1961237968 13057696 7248944
21:20:14 28545 8192000000 10914289920 11361124352 1957346704 19.285 99 53 103 2 1981901888 9979664 14575520
21:21:53 2556 8192000000 10904729952 11359850496 1973709408 19.415 100 40 80 2 1995077936 10147360 11221168
21:22:56 61152 8192000000 10920789472 11387060224 1992208512 19.561 100 38 76 1 2020568944 17708992 10651440
21:25:06 43883 8192000000 10902625440 11385360384 1983187424 19.490 100 14 27 1 2010004048 22644480 4172144
21:27:03 45088 8192000000 10915423024 11379650560 1988407024 19.531 100 17 34 1 2004077664 10857808 4812832
21:28:50 47060 8192000000 10937907824 11389591552 1983145600 19.490 99 25 50 1 1996157184 6769408 6242176
21:30:47 36364 8192000000 10929353408 11401691136 2006896208 19.677 100 37 74 0 2026100736 8648080 10556448
21:32:24 68702 8192000000 10933430016 11410403328 2004730032 19.660 100 14 28 1 2017298736 8363792 4204912
21:34:34 74661 8192000000 10919584112 11394109440 1954732656 19.264 97 13 26 0 1973981872 15515552 3733664
21:36:55 72888 8192000000 10932156960 11401768960 1951814992 19.241 97 22 44 0 1973267696 15474080 5978624
21:39:25 15186 8192000000 10867248032 11322552320 1909552928 18.903 97 24 48 1 1922736192 7335776 5847488
21:40:44 130625 8192000000 10883213168 11352432640 1804771488 18.053 91 14 28 0 1821231936 13245568 3214880
21:44:32 113609 8192000000 10892042800 11349143552 1942138112 19.164 99 48 96 1 1969916352 13836928 13941312
21:47:27 70340 8192000000 10890044512 11375099904 1949329424 19.221 99 8 16 0 1964892544 13067856 2495264
21:49:45 54844 8192000000 10874888032 11355856896 1930094176 19.068 98 12 24 3 1948568896 15013856 3460864
21:51:40 62385 8192000000 10868193344 11336089600 1910542112 18.911 97 15 29 1 1931936720 16989616 4404992
21:53:44 62762 8192000000 10879344624 11345494016 1924887344 19.026 98 27 54 1 1945834192 12853248 8093600
21:55:58 122528 8192000000 10842679392 11317141504 1863552144 18.532 96 8 16 0 1877960064 12316784 2091136
21:59:05 53183 8192000000 10835683696 11320492032 1915185984 18.948 99 12 24 0 1926014160 7475872 3352304
22:01:21 20084 8192000000 10886577360 11340632064 1950231776 19.228 100 51 102 1 1976406736 11722144 14452816
22:02:49 51532 8192000000 10846160528 11321290752 1899002736 18.818 98 18 36 1 1917532176 13255792 5273648
22:04:42 70975 8192000000 10862475968 11333181440 1919658288 18.984 99 11 22 1 1937446624 14638800 3149536
22:06:53 37252 8192000000 10879704752 11332042752 1922946320 19.010 99 32 62 0 1941579728 10189744 8443664
22:08:34 42523 8192000000 10899159056 11358384128 1961617088 19.319 100 13 26 1 1977043744 12330832 3095824
22:10:19 82760 8192000000 10900566768 11370725376 1931782304 19.081 98 15 30 2 1949023584 12495904 4745376
22:12:42 66635 8192000000 10914465216 11387957248 1960118080 19.307 99 9 18 1 1975579776 13287968 2173728
22:14:51 106258 8192000000 10908835424 11405361152 1962444736 19.325 99 6 12 0 1988143584 24087152 1611696
22:18:07 55449 8192000000 10928016256 11387502592 1970961264 19.393 99 32 64 2 1991282304 11115936 9205104
22:20:04 69261 8192000000 10903969296 11392073728 1968392560 19.373 99 11 22 1 1983964928 12316912 3255456
22:22:29 58202 8192000000 10919815552 11403984896 1954508560 19.262 98 13 26 0 1977522464 19233776 3780128
22:24:36 96137 8192000000 10887397568 11362861056 1938255792 19.133 98 15 30 0 1952419856 9812624 4351440
22:27:15 36860 8192000000 10912093680 11360272384 1947412896 19.206 99 29 57 1 1965942528 10323120 8206512
22:28:56 67334 8192000000 10861834592 11342802944 1871416640 18.596 95 5 10 0 1879471072 6836672 1217760
22:31:04 28950 8192000000 10895247424 11356459008 1942428560 19.166 99 12 24 0 1952421600 7035216 2957824
22:32:34 85709 8192000000 10882585120 11337289728 1855277664 18.465 94 27 54 1 1880001712 17032208 7691840
22:35:09 91469 8192000000 10882933168 11339456512 1901445472 18.838 98 15 30 2 1919939952 14447200 4047280
22:37:40 23407 8192000000 9148962704 9392824320 309210192 3.637 16 3 6 1 322755776 12757136 788448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 31-08-2021 22:39:05 to 31-08-2021 22:39:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 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 31-08-2021 22:39:20 to 31-08-2021 22:39:22.

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          231986
MON$OLDEST_ACTIVE               231987
MON$OLDEST_SNAPSHOT             231987
MON$NEXT_TRANSACTION            231987
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                       2018944
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.33490 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33490 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33490 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33490 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Tue Aug 31 22:38:09 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Tue Aug 31 22:38:13 2021
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
--- end of diff output ---

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

31.08.21 22:39:23. 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-08-31 22:39:26 No severe PSQL-related problems occured
31.08.21 22:39:27. End of report.