Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.01.2021 07:37
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16665820 kB
MemAvailable 31247144 kB
Buffers 0 kB
Cached 14948356 kB
SwapCached 4868 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-01-21 07:36:19
DTS_END                         2021-01-21 07:36:19.9820
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(68:17)
IP                              ::1/45034
TRN_ID                          227279
ATT_ID                          674
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: 8664.09 23575 1039691
customer order: creation 163.28 399 19594
customer order: refuse 14.00 318 1680
order to supplier: creation 44.09 1370 5291
order to supplier: removal 3.27 139381 392
invoice (draft): creation 43.28 3704 5194
invoice (draft): removal 48.95 1053 5874
invoice accept: apply 39.47 18052 4736
invoice accept: cancel 44.53 956 5344
customer reserve: creation 3652.58 174 438310
customer reserve: removal 4324.80 83 518976
realization accept: apply 77.72 174 9326
realization accept: cancel 84.70 132 10164
payment from customer: creation 56.93 115 6832
payment from customer: removal 5.59 72 671
payment to supplier: creation 47.55 116 5706
payment to supplier: removal 3.14 93 377
service: total inventory turnovers 3.63 3713 436
service: total monetary turnovers 5.74 626 689
service: aggregate perf. data 0.79 17770 95
service: refresh index statistics 0.03 283191 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-01-2021 07:37:51 to 21-01-2021 07:37:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3388
TEST_TIME 2 3518
TEST_TIME 3 3986
TEST_TIME 4 4646
TEST_TIME 5 4763
TEST_TIME 6 5248
TEST_TIME 7 5431
TEST_TIME 8 5619
TEST_TIME 9 5823
TEST_TIME 10 5731
TEST_TIME 11 5817
TEST_TIME 12 5521
TEST_TIME 13 5544
TEST_TIME 14 5782
TEST_TIME 15 5695
TEST_TIME 16 6031
TEST_TIME 17 6058
TEST_TIME 18 6319
TEST_TIME 19 6449
TEST_TIME 20 6649
TEST_TIME 21 6670
TEST_TIME 22 7202
TEST_TIME 23 7760
TEST_TIME 24 7757
TEST_TIME 25 8230
TEST_TIME 26 8296
TEST_TIME 27 9262
TEST_TIME 28 10194
TEST_TIME 29 10490
TEST_TIME 30 10497
TEST_TIME 31 10403
TEST_TIME 32 10507
TEST_TIME 33 10358
TEST_TIME 34 10453
TEST_TIME 35 10394
TEST_TIME 36 10220
TEST_TIME 37 9953
TEST_TIME 38 9768
TEST_TIME 39 9670
TEST_TIME 40 9662
TEST_TIME 41 9690
TEST_TIME 42 9611
TEST_TIME 43 9589
TEST_TIME 44 9479
TEST_TIME 45 9375
TEST_TIME 46 9322
TEST_TIME 47 9297
TEST_TIME 48 9275
TEST_TIME 49 9241
TEST_TIME 50 9239
TEST_TIME 51 9173
TEST_TIME 52 9253
TEST_TIME 53 9318
TEST_TIME 54 9273
TEST_TIME 55 9367
TEST_TIME 56 9511
TEST_TIME 57 9490
TEST_TIME 58 9514
TEST_TIME 59 9633
TEST_TIME 60 9579
TEST_TIME 61 9466
TEST_TIME 62 9341
TEST_TIME 63 9275
TEST_TIME 64 9183
TEST_TIME 65 9129
TEST_TIME 66 9052
TEST_TIME 67 9032
TEST_TIME 68 9054
TEST_TIME 69 9159
TEST_TIME 70 9151
TEST_TIME 71 9150
TEST_TIME 72 9067
TEST_TIME 73 9227
TEST_TIME 74 9221
TEST_TIME 75 9229
TEST_TIME 76 9368
TEST_TIME 77 9325
TEST_TIME 78 9236
TEST_TIME 79 9168
TEST_TIME 80 9146
TEST_TIME 81 9070
TEST_TIME 82 9017
TEST_TIME 83 8989
TEST_TIME 84 8950
TEST_TIME 85 8950
TEST_TIME 86 8910
TEST_TIME 87 8926
TEST_TIME 88 8970
TEST_TIME 89 8949
TEST_TIME 90 8947
TEST_TIME 91 8962
TEST_TIME 92 8971
TEST_TIME 93 9013
TEST_TIME 94 8969
TEST_TIME 95 9047
TEST_TIME 96 9016
TEST_TIME 97 9018
TEST_TIME 98 8944
TEST_TIME 99 8903
TEST_TIME 100 8859
TEST_TIME 101 8798
TEST_TIME 102 8763
TEST_TIME 103 8777
TEST_TIME 104 8772
TEST_TIME 105 8759
TEST_TIME 106 8769
TEST_TIME 107 8760
TEST_TIME 108 8779
TEST_TIME 109 8797
TEST_TIME 110 8805
TEST_TIME 111 8830
TEST_TIME 112 8829
TEST_TIME 113 8869
TEST_TIME 114 8900
TEST_TIME 115 8860
TEST_TIME 116 8797
TEST_TIME 117 8761
TEST_TIME 118 8741
TEST_TIME 119 8724
TEST_TIME 120 8665

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-01-2021 07:37:55 to 21-01-2021 07:37: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 1446222 1446222 0 0.00 0 45539 7 0 0
doc_list_biud 1446215 1446215 0 0.00 0 57991 37 0 0
sp_add_invoice_to_stock 4831 4736 95 1.96 21 193452 18052 0 95
sp_cancel_adding_invoice 5344 5344 0 0.00 5 92176 957 0 0
sp_cancel_client_order 1680 1680 0 0.00 4 16882 319 0 0
sp_cancel_customer_reserve 518976 518976 0 0.00 1 57995 84 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 7629 72 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 6740 93 0 0
sp_cancel_supplier_invoice 5876 5874 2 0.03 2 14352 1054 0 2
sp_cancel_supplier_order 392 392 0 0.00 7 1331993 139381 0 0
sp_cancel_write_off 10164 10164 0 0.00 1 20521 132 0 0
sp_client_order 19594 19594 0 0.00 4 50816 400 0 0
sp_customer_reserve 438310 438310 0 0.00 0 45798 175 0 0
sp_fill_shopping_cart 30073 30073 0 0.00 1 22102 47 0 0
sp_fill_shopping_cart_clo_res 438301 438301 0 0.00 0 21289 19 0 0
sp_get_clo_for_invoice 4726 4726 0 0.00 0 49052 426 0 0
sp_kill_cost_storno 16556 16556 0 0.00 0 7662 63 0 0
sp_kill_qstorno_ret_qs2qd 1054382 1054382 0 0.00 0 57675 45 0 0
sp_kill_qty_storno 556482 556482 0 0.00 0 57989 95 0 0
sp_lock_selected_doc 19485 19485 0 0.00 0 8763 6 0 0
sp_make_cost_storno 26590 26590 0 0.00 0 15668 56 0 0
sp_multiply_rows_for_pdistr 26590 26590 0 0.00 0 11656 31 0 0
sp_multiply_rows_for_qdistr 19593 19593 0 0.00 0 45841 226 0 0
sp_pay_from_customer 6832 6832 0 0.00 4 18654 115 0 0
sp_pay_to_supplier 5706 5706 0 0.00 5 11865 117 0 0
sp_payment_common 12538 12538 0 0.00 1 6221 18 0 0
sp_qd_handle_on_cancel_clo 1680 1680 0 0.00 0 16876 279 0 0
sp_qd_handle_on_invoice_upd_sts 10070 10070 0 0.00 0 37599 417 0 0
sp_qd_handle_on_reserve_upd_sts 9326 9326 0 0.00 0 9903 46 0 0
sp_reserve_write_off 9326 9326 0 0.00 6 18873 175 0 0
sp_supplier_invoice 5194 5194 0 0.00 7 116467 3705 0 0
sp_supplier_order 5291 5291 0 0.00 6 32650 1370 0 0
srv_aggregate_perf_data 96 95 1 1.04 2863 380242 17770 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1537 120408 47408 0 0
srv_find_qd_qs_mism 994563 994563 0 0.00 0 45537 5 0 0
srv_make_invnt_saldo 436 436 0 0.00 35 138371 3714 0 0
srv_make_money_saldo 689 689 0 0.00 0 15934 627 0 0
srv_recalc_idx_stat 4 4 0 0.00 175745 367767 283191 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4736 4736 0 0.00 0 13845 183 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 4074 84 0 0
t$perf-norm:sp_cancel_client_order 1680 1680 0 0.00 0 3536 11 0 0
t$perf-norm:sp_cancel_customer_reserve 616 616 0 0.00 0 1522 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 4297 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 1902 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 364 364 0 0.00 0 1737 24 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 37784 1606 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 3446 14 0 0
t$perf-norm:sp_client_order 19594 19594 0 0.00 0 9887 21 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 2250 7 0 0
t$perf-norm:sp_pay_from_customer 6832 6832 0 0.00 0 5085 10 0 0
t$perf-norm:sp_pay_to_supplier 5706 5706 0 0.00 0 10202 12 0 0
t$perf-norm:sp_reserve_write_off 9326 9326 0 0.00 0 10201 15 0 0
t$perf-norm:sp_supplier_invoice 5194 5194 0 0.00 0 6917 18 0 0
t$perf-norm:sp_supplier_order 5291 5291 0 0.00 0 4764 18 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 148 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2569 44 0 0
t$perf-norm:srv_make_invnt_saldo 436 436 0 0.00 0 1724 18 0 0
t$perf-norm:srv_make_money_saldo 689 689 0 0.00 0 1351 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 36 11 0 0
v_add_invoice_to_stock 9636 9446 190 1.97 0 3979 15 0 190
v_all_customers 36028 36028 0 0.00 0 129 1 0 0
v_all_suppliers 29508 29508 0 0.00 0 15 1 0 0
v_all_wares 39186 39186 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 669 8 0 0
v_cancel_client_order 3360 3360 0 0.00 0 2559 7 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 1298 5 0 0
v_cancel_customer_reserve 1232 1232 0 0.00 0 2316 9 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 5502 19 0 0
v_cancel_supplier_invoice 732 728 4 0.54 0 2385 17 0 4
v_cancel_supplier_order 784 784 0 0.00 0 1228 6 0 0
v_cancel_write_off 1872 1872 0 0.00 0 4108 17 0 0
v_max_id_clo_ord 5291 5291 0 0.00 0 1653 1 0 0
v_max_id_clo_res 989 989 0 0.00 0 1635 9 0 0
v_max_id_ord_sup 5189 5189 0 0.00 0 3881 4 0 0
v_max_non_paid_invoice 1432 1432 0 0.00 0 333 2 0 0
v_max_non_paid_realizn 3564 3564 0 0.00 0 2518 4 0 0
v_min_id_clo_ord 5291 5291 0 0.00 0 1062 5 0 0
v_min_id_clo_res 989 989 0 0.00 0 6192 18 0 0
v_min_id_ord_sup 5189 5189 0 0.00 0 7952 16 0 0
v_min_non_paid_invoice 5706 5706 0 0.00 0 4563 10 0 0
v_min_non_paid_realizn 6832 6832 0 0.00 0 5394 12 0 0
v_our_firm 9694 9694 0 0.00 0 157 1 0 0
v_reserve_write_off 18652 18652 0 0.00 0 5561 7 0 0
x_lock_depdocs_on_canc_invoice 10854 10854 0 0.00 0 19224 121 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 8467 353 0 0
x_make_qstorno_1000_1200 5291 5291 0 0.00 2 32634 1297 0 0
x_make_qstorno_1200_2000 5194 5194 0 0.00 2 116363 3511 0 0
x_make_qstorno_2100_3300 438310 438310 0 0.00 0 45798 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-01-2021 07:37:59 to 21-01-2021 07:38: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
05:06:23 8 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
05:07:24 22153 8192000000 9740785296 10038419456 816685824 9.065 100 89 179 0 845569392 7764320 21119248
05:08:52 21980 8192000000 9770602176 10077933568 844575968 9.346 100 91 182 1 879111760 13035504 21500288
05:10:17 18573 8192000000 9801228832 10119303168 869535984 9.595 100 80 160 1 908329904 20598416 18195504
05:11:42 9502 8192000000 9865481840 10196213760 921775936 10.114 100 76 151 1 978227024 40179872 16271216
05:12:56 9211 8192000000 9906978352 10252423168 954040016 10.431 100 76 150 0 1021250688 51590928 15619744
05:14:11 6473 8192000000 9967045792 10321690624 993728096 10.818 100 76 152 0 1080895056 71998240 15168720
05:15:21 3060 8192000000 9987707136 10351521792 1020729520 11.079 100 79 157 1 1099292480 62306208 16256752
05:16:27 1424 8192000000 10017085584 10380595200 1047440736 11.336 100 90 180 1 1137003472 70925040 18637696
05:17:30 2032 8192000000 10024936944 10393145344 1061160976 11.468 100 91 182 1 1141123152 60667952 19294224
05:18:33 8395 8192000000 10004487776 10382979072 1067091040 11.524 100 80 162 0 1119322848 34813248 17418560
05:19:42 3952 8192000000 9990206480 10380873728 1073963824 11.590 100 44 87 3 1107510240 24191168 9355248
05:20:48 10312 8192000000 10006276736 10408996864 1082824560 11.674 100 38 76 1 1131551760 40479920 8247280
05:22:02 8995 8192000000 9999220656 10412089344 1089294256 11.736 100 30 61 1 1113999008 18169440 6535312
05:23:12 38463 8192000000 10002279680 10399903744 1092700992 11.768 100 45 89 1 1115763648 13108976 9953680
05:24:51 6297 8192000000 10017558032 10423709696 1096240896 11.802 100 28 57 3 1129112288 26672368 6199024
05:26:01 6293 8192000000 9999632304 10407092224 1092526624 11.767 100 25 49 0 1110439728 12448288 5464816
05:27:09 6832 8192000000 9987155072 10394824704 1064028848 11.495 98 29 58 3 1085587536 15290608 6268080
05:28:18 17907 8192000000 9971765984 10375798784 1037875360 11.244 96 34 69 1 1068792352 23306880 7610112
05:29:43 9702 8192000000 9919070880 10302320640 981547376 10.699 96 38 75 4 1006204032 14676480 9980176
05:30:54 7399 8192000000 9929761696 10314190848 1017623024 11.049 99 32 65 0 1035666640 10548176 7495440
05:32:05 70579 8192000000 9950896640 10331119616 1030581728 11.174 99 42 83 1 1056334400 15411440 10341232
05:34:17 4207 8192000000 9978702448 10366529536 1060746208 11.464 100 60 116 4 1090254880 16959920 12548752
05:35:22 9231 8192000000 9999037264 10407186432 1076487360 11.614 100 52 104 1 1110662352 22271248 11903744
05:36:34 41926 8192000000 10016089840 10408914944 1087382768 11.718 100 53 103 1 1118131312 20452352 10296192
05:38:23 23005 8192000000 10056696128 10458963968 1107755424 11.911 100 49 98 1 1167579808 50029344 9795040
05:40:02 16289 8192000000 10096940832 10503753728 1122629472 12.052 100 54 107 3 1208164848 74716032 10819344
05:41:27 4264 8192000000 10138907808 10542338048 1138517056 12.202 100 58 117 2 1255608944 104314224 12777664
05:42:41 16007 8192000000 10188384896 10602897408 1157940832 12.384 100 61 120 2 1284361936 113327360 13093744
05:44:06 15010 8192000000 10180594592 10614759424 1160492800 12.408 100 45 90 0 1290335728 119678256 10164672
05:45:26 10810 8192000000 10207642352 10620895232 1170590432 12.502 100 52 103 1 1311958512 130046768 11321312
05:46:40 2710 8192000000 10269885040 10674954240 1185954384 12.646 100 74 148 0 1374243376 171676848 16612144
05:47:50 4872 8192000000 10274247968 10695491584 1195884864 12.738 100 70 140 1 1389036560 177164256 15987440
05:49:01 7592 8192000000 10358954768 10768240640 1198218384 12.760 99 70 140 2 1454973904 241148496 15607024
05:50:13 11033 8192000000 10391777024 10808983552 1203201456 12.806 99 61 120 1 1489701168 272523424 13976288
05:51:29 2296 8192000000 10422950352 10831818752 1212853504 12.896 100 79 158 1 1522076784 290745728 18477552
05:52:36 1536 8192000000 10462251792 10872664064 1203882640 12.812 100 83 165 0 1565925856 342651024 19392192
05:53:46 10867 8192000000 10481167424 10901729280 1203411408 12.808 100 62 124 0 1582225792 362962848 15851536
05:55:02 3745 8192000000 10496034080 10911592448 1188144912 12.666 99 75 150 0 1590489664 384536096 17808656
05:56:17 7067 8192000000 10538489248 10952032256 1190585680 12.689 100 72 144 0 1634268736 425683424 17999632
05:57:44 3043 8192000000 10575697456 10988998656 1205942496 12.831 100 89 176 0 1678211024 450002544 22265984
05:58:56 15574 8192000000 10547900160 11003248640 1180882960 12.598 99 52 104 0 1648563360 454212208 13468192
06:00:15 5317 8192000000 10537199152 10958954496 1186140304 12.647 100 67 133 0 1626944256 424734704 16069248
06:01:23 8075 8192000000 10540510928 10977017856 1172058896 12.516 100 47 94 0 1644732912 459859264 12814752
06:02:44 26882 8192000000 10406859296 10978390016 1153452032 12.342 100 27 54 2 1584546960 423208128 7886800
06:04:14 27907 8192000000 10163984048 10659532800 1139750432 12.213 99 16 33 0 1335607376 191677024 4179920
06:05:51 112471 8192000000 10100300608 10526359552 1090073408 11.743 95 23 45 1 1143604240 49004096 4526736
06:08:48 81328 8192000000 10071275056 10545164288 1119925040 12.026 98 18 35 2 1170358704 47050576 3383088
06:11:10 83794 8192000000 10061562928 10480644096 1043967504 11.303 91 14 28 0 1072375936 25856848 2551584
06:13:38 68378 8192000000 10081049712 10505125888 1100740800 11.845 98 31 61 1 1154536144 46844944 6950400
06:15:48 89537 8192000000 10088826304 10607513600 1104213552 11.878 98 25 50 1 1203062192 93103712 5744928
06:18:20 22267 8192000000 10090552192 10514067456 1128030320 12.103 100 47 90 0 1193757584 55883840 9843424
06:19:46 67000 8192000000 10071519440 10535264256 1107278864 11.907 98 13 26 1 1152728288 42421136 3028288
06:21:54 48475 8192000000 10090897936 10555183104 1139155200 12.208 100 12 25 2 1209164560 67237712 2771648
06:24:03 47259 8192000000 10050634992 10534563840 1124483072 12.069 100 8 17 0 1172766528 46401776 1881680
06:25:50 28525 8192000000 10091499376 10531745792 1139863280 12.214 100 27 54 0 1194730240 49104112 5762848
06:27:26 56259 8192000000 10133949536 10609598464 1134986752 12.168 99 23 46 0 1237667056 97775968 4904336
06:29:22 38224 8192000000 10150069920 10583228416 1152631040 12.334 100 27 54 0 1221757456 63791328 5335088
06:31:17 68880 8192000000 10106694704 10640801792 1147659280 12.288 100 8 16 1 1262336800 112612752 2064768
06:33:52 55155 8192000000 10162333632 10630479872 1163059040 12.432 100 17 35 0 1230029968 63107648 3863280
06:35:57 74753 8192000000 10073902432 10519379968 1123342608 12.059 97 22 43 0 1151291792 22791136 5158048
06:38:12 102800 8192000000 10046737744 10493702144 1082709552 11.673 94 21 41 1 1106435552 20057088 3668912
06:41:03 60421 8192000000 10128147216 10594443264 1144561712 12.258 99 10 20 0 1214831824 68466400 1803712
06:43:12 64709 8192000000 10156746848 10610491392 1127915248 12.102 98 16 32 1 1245961680 114231920 3814512
06:45:33 36994 8192000000 10104927728 10550435840 1144620832 12.259 99 5 10 0 1176826096 31126224 1079040
06:47:10 70352 8192000000 10116917456 10560184320 1085312112 11.698 94 15 30 0 1169281344 80846128 3123104
06:49:21 95326 8192000000 10088572160 10575405056 1052182992 11.382 93 12 24 0 1092658752 37852560 2623200
06:51:57 47046 8192000000 10110339248 10634579968 1127255760 12.095 99 12 24 0 1240226080 110382848 2587472
06:53:44 69746 8192000000 10070945904 10511220736 1114872048 11.979 99 27 54 2 1167129792 46667472 5590272
06:56:32 74477 8192000000 10052744464 10531557376 1112968176 11.961 99 7 14 0 1150623136 36248960 1406000
06:58:47 33907 8192000000 10060397136 10520985600 1120043648 12.027 100 5 10 0 1158936752 37612640 1280464
07:01:04 58582 8192000000 10057246048 10491793408 1104369920 11.879 98 23 46 1 1136856848 27815040 4671888
07:03:08 25104 8192000000 10089365600 10536132608 1100908544 11.846 98 27 55 0 1161843616 55165488 5769584
07:04:33 88306 8192000000 10070899456 10516037632 1095894256 11.799 97 24 48 1 1152078592 50892048 5292288
07:07:03 69018 8192000000 10073183136 10530664448 1100939120 11.847 97 18 36 0 1136321744 31994224 3388400
07:09:12 85507 8192000000 10089297024 10520207360 1112498336 11.956 98 13 26 0 1151170464 36094528 2577600
07:11:41 57672 8192000000 10065440688 10496499712 1115179904 11.981 98 18 33 1 1157987360 39807760 2999696
07:13:42 109762 8192000000 10041624912 10490454016 1121350528 12.040 99 21 42 0 1176540560 50965344 4224688
07:16:50 81569 8192000000 10047349664 10515652608 1121188496 12.038 100 8 16 0 1151510048 28677248 1644304
07:19:17 51781 8192000000 10042223312 10482274304 1122445280 12.050 100 8 16 0 1148898496 24978400 1474816
07:21:10 52098 8192000000 10081245120 10531909632 1120730224 12.034 99 37 74 2 1166739248 38600080 7408944
07:23:06 53224 8192000000 10097053680 10535174144 1139597136 12.212 100 23 46 1 1180971184 36546784 4827264
07:25:04 67058 8192000000 10103603744 10549469184 1125316128 12.077 99 17 34 0 1173463808 44779296 3368384
07:27:11 96716 8192000000 10137849936 10621353984 1120642704 12.033 98 27 53 1 1240073488 113028272 6402512
07:29:49 120405 8192000000 10071475744 10533556224 1104672192 11.882 97 14 26 1 1132535872 25406016 2457664
07:33:26 85936 8192000000 10031835552 10506510336 1096633936 11.806 97 8 16 0 1114767520 16220992 1912592
07:36:05 19946 8192000000 9054504080 9296928768 215496016 2.563 20 2 2 1 221741520 6174672 70832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-01-2021 07:38:05 to 21-01-2021 07:38:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 95 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-01-2021 07:38:21 to 21-01-2021 07:38:23.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          227599
MON$OLDEST_ACTIVE               227600
MON$OLDEST_SNAPSHOT             227600
MON$NEXT_TRANSACTION            227600
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-30 07:30:08.6030 GMT
MON$PAGES                       2092480
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             782
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2335 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2335 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2335 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2335 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Jan 21 07:36:29 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Jan 21 07:36:29 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Jan 21 07:36:29 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Thu Jan 21 07:36:29 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Thu Jan 21 07:36:29 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:25: 
:26: 
:27: mscs-test-161.budzdorov.ru    Thu Jan 21 07:36:29 2021
:28:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:29: 
:30: 
:31: mscs-test-161.budzdorov.ru    Thu Jan 21 07:36:29 2021
:32:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:33: 
--- end of diff output ---

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

21.01.21 07:38:24. There are 3 logs in /var/tmp/logs-oltp40 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-01-21 07:38:26 No severe PSQL-related problems occured
21.01.21 07:38:28. End of report.