Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.10.2021 13: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 15909628 kB
MemAvailable 30778848 kB
Buffers 0 kB
Cached 15828168 kB
SwapCached 4684 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.1
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-10-03 13:37:09
DTS_END                         2021-10-03 13:37:09.5530
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/41680
TRN_ID                          236694
ATT_ID                          675
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: 9167.18 19148 1100062
customer order: creation 175.12 407 21014
customer order: refuse 14.26 333 1711
order to supplier: creation 44.08 1361 5290
order to supplier: removal 3.38 121640 405
invoice (draft): creation 46.82 3465 5618
invoice (draft): removal 50.01 992 6001
invoice accept: apply 43.14 20281 5177
invoice accept: cancel 45.95 963 5514
customer reserve: creation 3982.24 200 477869
customer reserve: removal 4462.80 72 535536
realization accept: apply 85.57 183 10268
realization accept: cancel 84.04 136 10085
payment from customer: creation 59.48 112 7138
payment from customer: removal 5.85 114 702
payment to supplier: creation 49.93 146 5992
payment to supplier: removal 3.47 75 416
service: total inventory turnovers 3.73 3697 448
service: total monetary turnovers 6.39 526 767
service: aggregate perf. data 0.89 19452 107
service: refresh index statistics 0.03 208803 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-10-2021 13:39:04 to 03-10-2021 13:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3229
TEST_TIME 2 5174
TEST_TIME 3 5317
TEST_TIME 4 6256
TEST_TIME 5 6057
TEST_TIME 6 5724
TEST_TIME 7 5766
TEST_TIME 8 6371
TEST_TIME 9 6683
TEST_TIME 10 6973
TEST_TIME 11 6812
TEST_TIME 12 7101
TEST_TIME 13 7183
TEST_TIME 14 7198
TEST_TIME 15 7230
TEST_TIME 16 7500
TEST_TIME 17 7490
TEST_TIME 18 7569
TEST_TIME 19 7790
TEST_TIME 20 7968
TEST_TIME 21 8279
TEST_TIME 22 8725
TEST_TIME 23 9158
TEST_TIME 24 8913
TEST_TIME 25 9086
TEST_TIME 26 9681
TEST_TIME 27 10333
TEST_TIME 28 11508
TEST_TIME 29 11272
TEST_TIME 30 10982
TEST_TIME 31 10823
TEST_TIME 32 10710
TEST_TIME 33 10759
TEST_TIME 34 10577
TEST_TIME 35 10563
TEST_TIME 36 10571
TEST_TIME 37 10414
TEST_TIME 38 10462
TEST_TIME 39 10551
TEST_TIME 40 10415
TEST_TIME 41 10336
TEST_TIME 42 10207
TEST_TIME 43 9991
TEST_TIME 44 9940
TEST_TIME 45 9995
TEST_TIME 46 9949
TEST_TIME 47 9901
TEST_TIME 48 9886
TEST_TIME 49 9910
TEST_TIME 50 9873
TEST_TIME 51 9821
TEST_TIME 52 9812
TEST_TIME 53 9859
TEST_TIME 54 9855
TEST_TIME 55 10124
TEST_TIME 56 10185
TEST_TIME 57 10119
TEST_TIME 58 10120
TEST_TIME 59 9975
TEST_TIME 60 9883
TEST_TIME 61 9913
TEST_TIME 62 9817
TEST_TIME 63 9733
TEST_TIME 64 9717
TEST_TIME 65 9643
TEST_TIME 66 9648
TEST_TIME 67 9745
TEST_TIME 68 9710
TEST_TIME 69 9702
TEST_TIME 70 9725
TEST_TIME 71 9795
TEST_TIME 72 9779
TEST_TIME 73 9730
TEST_TIME 74 9886
TEST_TIME 75 9881
TEST_TIME 76 9889
TEST_TIME 77 9794
TEST_TIME 78 9720
TEST_TIME 79 9685
TEST_TIME 80 9644
TEST_TIME 81 9594
TEST_TIME 82 9557
TEST_TIME 83 9512
TEST_TIME 84 9503
TEST_TIME 85 9456
TEST_TIME 86 9497
TEST_TIME 87 9479
TEST_TIME 88 9460
TEST_TIME 89 9470
TEST_TIME 90 9476
TEST_TIME 91 9477
TEST_TIME 92 9490
TEST_TIME 93 9453
TEST_TIME 94 9499
TEST_TIME 95 9539
TEST_TIME 96 9535
TEST_TIME 97 9509
TEST_TIME 98 9427
TEST_TIME 99 9410
TEST_TIME 100 9406
TEST_TIME 101 9368
TEST_TIME 102 9354
TEST_TIME 103 9323
TEST_TIME 104 9285
TEST_TIME 105 9300
TEST_TIME 106 9242
TEST_TIME 107 9327
TEST_TIME 108 9318
TEST_TIME 109 9358
TEST_TIME 110 9397
TEST_TIME 111 9377
TEST_TIME 112 9406
TEST_TIME 113 9388
TEST_TIME 114 9414
TEST_TIME 115 9389
TEST_TIME 116 9333
TEST_TIME 117 9317
TEST_TIME 118 9285
TEST_TIME 119 9246
TEST_TIME 120 9173

Performance per minute, chart


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

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 1540455 1540455 0 0.00 0 19493 7 0 0
doc_list_biud 1540455 1540455 0 0.00 0 31243 32 0 0
sp_add_invoice_to_stock 5274 5177 97 1.83 31 237126 20282 0 97
sp_cancel_adding_invoice 5514 5514 0 0.00 5 69258 963 0 0
sp_cancel_client_order 1711 1711 0 0.00 4 10562 333 0 0
sp_cancel_customer_reserve 535536 535536 0 0.00 1 30752 73 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 11976 115 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 7363 75 0 0
sp_cancel_supplier_invoice 6008 6001 7 0.11 2 25567 992 0 7
sp_cancel_supplier_order 405 405 0 0.00 8 1273818 121640 0 0
sp_cancel_write_off 10085 10085 0 0.00 1 11027 137 0 0
sp_client_order 21014 21014 0 0.00 4 30808 408 0 0
sp_customer_reserve 477869 477869 0 0.00 0 65461 201 0 0
sp_fill_shopping_cart 31917 31917 0 0.00 1 14186 43 0 0
sp_fill_shopping_cart_clo_res 477864 477864 0 0.00 0 27237 18 0 0
sp_get_clo_for_invoice 5166 5166 0 0.00 0 34254 388 0 0
sp_kill_cost_storno 16717 16717 0 0.00 0 7997 65 0 0
sp_kill_qstorno_ret_qs2qd 1087563 1087563 0 0.00 0 30737 39 0 0
sp_kill_qty_storno 574686 574686 0 0.00 0 31242 84 0 0
sp_lock_selected_doc 21213 21213 0 0.00 0 9102 7 0 0
sp_make_cost_storno 28564 28564 0 0.00 0 14885 61 0 0
sp_multiply_rows_for_pdistr 28564 28564 0 0.00 0 7476 31 0 0
sp_multiply_rows_for_qdistr 21014 21014 0 0.00 0 29874 229 0 0
sp_pay_from_customer 7138 7138 0 0.00 4 13213 112 0 0
sp_pay_to_supplier 5992 5992 0 0.00 5 15934 147 0 0
sp_payment_common 13130 13130 0 0.00 1 6891 19 0 0
sp_qd_handle_on_cancel_clo 1711 1711 0 0.00 0 10555 305 0 0
sp_qd_handle_on_invoice_upd_sts 10680 10680 0 0.00 0 31241 373 0 0
sp_qd_handle_on_reserve_upd_sts 10268 10268 0 0.00 0 18061 51 0 0
sp_reserve_write_off 10268 10268 0 0.00 7 18080 183 0 0
sp_supplier_invoice 5618 5618 0 0.00 6 76292 3466 0 0
sp_supplier_order 5290 5290 0 0.00 6 48321 1361 0 0
srv_aggregate_perf_data 107 107 0 0.00 2444 282088 19452 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2227 127907 41811 0 0
srv_find_qd_qs_mism 1050450 1050450 0 0.00 0 19493 6 0 0
srv_make_invnt_saldo 448 448 0 0.00 38 90309 3697 0 0
srv_make_money_saldo 767 767 0 0.00 1 13485 527 0 0
srv_recalc_idx_stat 4 4 0 0.00 183024 252231 208803 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5177 5177 0 0.00 0 15487 158 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 0 9734 139 0 0
t$perf-norm:sp_cancel_client_order 1711 1711 0 0.00 0 1713 11 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 1354 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 1898 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 689 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 3252 19 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 16793 1474 0 0
t$perf-norm:sp_cancel_write_off 1040 1040 0 0.00 0 2941 9 0 0
t$perf-norm:sp_client_order 21014 21014 0 0.00 0 7158 15 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 1516 8 0 0
t$perf-norm:sp_pay_from_customer 7138 7138 0 0.00 0 9105 10 0 0
t$perf-norm:sp_pay_to_supplier 5992 5992 0 0.00 0 5120 11 0 0
t$perf-norm:sp_reserve_write_off 10268 10268 0 0.00 0 3158 8 0 0
t$perf-norm:sp_supplier_invoice 5618 5618 0 0.00 0 1142 13 0 0
t$perf-norm:sp_supplier_order 5290 5290 0 0.00 0 2796 14 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 1167 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 49 1 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 1353 10 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 173 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10524 10330 194 1.84 0 5723 19 0 194
v_all_customers 38312 38312 0 0.00 0 22 1 0 0
v_all_suppliers 30230 30230 0 0.00 0 55 1 0 0
v_all_wares 42028 42028 0 0.00 0 172 1 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 1569 9 0 0
v_cancel_client_order 3422 3422 0 0.00 0 3774 6 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 8349 22 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 3142 11 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 7351 13 0 0
v_cancel_supplier_invoice 778 764 14 1.79 0 4006 28 0 14
v_cancel_supplier_order 810 810 0 0.00 0 1570 5 0 0
v_cancel_write_off 2080 2080 0 0.00 0 4628 17 0 0
v_max_id_clo_ord 5290 5290 0 0.00 0 3291 2 0 0
v_max_id_clo_res 992 992 0 0.00 0 4178 12 0 0
v_max_id_ord_sup 5609 5609 0 0.00 0 5698 6 0 0
v_max_non_paid_invoice 1776 1776 0 0.00 0 416 2 0 0
v_max_non_paid_realizn 3746 3746 0 0.00 0 6886 6 0 0
v_min_id_clo_ord 5290 5290 0 0.00 0 3235 5 0 0
v_min_id_clo_res 992 992 0 0.00 0 4482 13 0 0
v_min_id_ord_sup 5609 5609 0 0.00 0 4964 6 0 0
v_min_non_paid_invoice 5992 5992 0 0.00 0 3447 10 0 0
v_min_non_paid_realizn 7138 7138 0 0.00 0 6604 12 0 0
v_our_firm 10500 10500 0 0.00 0 20 1 0 0
v_reserve_write_off 20536 20536 0 0.00 0 3880 7 0 0
x_lock_depdocs_on_canc_invoice 11133 11133 0 0.00 0 16803 122 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 12682 314 0 0
x_make_qstorno_1000_1200 5290 5290 0 0.00 2 48312 1285 0 0
x_make_qstorno_1200_2000 5618 5618 0 0.00 3 76285 3299 0 0
x_make_qstorno_2100_3300 477869 477869 0 0.00 0 65459 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-10-2021 13:39:12 to 03-10-2021 13:39:16.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:13 12 8192000000 8798450688 8809664512 4478816 0.054 2 1 2 0 4578208 63616 35776
11:08:14 19215 8192000000 9715230016 10009104384 795158400 8.847 100 89 178 1 824808992 7529696 22120896
11:09:38 7789 8192000000 9753796880 10060914688 834143504 9.241 100 85 170 0 865135296 10850032 20141760
11:10:58 14346 8192000000 9791895680 10109440000 862740672 9.528 100 82 164 0 897089440 15507472 18841296
11:12:21 13646 8192000000 9856811344 10191929344 916140112 10.058 100 83 166 0 971785264 37040704 18604448
11:13:43 8442 8192000000 9925391168 10270482432 960264608 10.492 100 83 163 1 1034350704 56707120 17378976
11:14:55 4852 8192000000 9980937376 10338979840 1006378528 10.940 100 80 157 1 1093418448 71117200 15922720
11:16:02 2581 8192000000 9992380224 10356756480 1029586224 11.164 100 88 174 2 1113039872 65557392 17896256
11:17:06 1408 8192000000 10045445504 10413215744 1057356656 11.431 100 90 176 1 1157557616 81239888 18961072
11:18:10 1908 8192000000 10046645616 10416902144 1067885968 11.532 100 86 172 1 1148587072 61670288 19030816
11:19:13 4933 8192000000 10002024144 10388692992 1073610928 11.587 100 31 62 0 1119530208 39516368 6402912
11:20:19 10460 8192000000 9999111280 10403176448 1083987952 11.685 100 29 56 0 1106237712 15700944 6548816
11:21:29 48086 8192000000 10004823376 10399268864 1089635872 11.739 100 50 101 2 1112410992 11764976 11010144
11:23:18 5904 8192000000 10019291312 10417897472 1095999520 11.800 100 48 93 2 1124676848 17917056 10760272
11:24:28 7673 8192000000 10019575408 10424479744 1100050624 11.838 100 37 72 0 1136580912 28914096 7616192
11:25:39 5374 8192000000 10020892224 10417188864 1098488656 11.823 100 40 75 0 1136302928 30111088 7703184
11:26:46 56765 8192000000 9983519472 10397511680 1053919712 11.398 96 19 38 0 1081639376 22745392 4974272
11:28:46 8847 8192000000 10003460912 10412122112 1064970832 11.504 98 41 82 0 1093475648 19486784 9018032
11:29:55 6875 8192000000 9954634464 10359939072 1053910736 11.398 100 31 62 0 1079223824 16932080 8381008
11:31:02 8335 8192000000 9930985504 10313093120 993279856 10.813 97 46 91 0 1024314016 20087168 10946992
11:32:13 50128 8192000000 9920231312 10319048704 991648144 10.797 97 41 82 1 1027387600 26685216 9054240
11:34:06 7205 8192000000 9951416544 10341896192 1040013568 11.265 100 36 71 2 1066107616 18504016 7590032
11:35:14 7999 8192000000 9978996672 10377658368 1055021584 11.409 100 30 60 1 1078773312 17322528 6429200
11:36:22 4849 8192000000 9982988800 10382774272 1062989840 11.485 100 44 86 2 1104650992 31142096 10519056
11:37:28 63786 8192000000 10023257840 10423922688 1083204528 11.678 100 45 90 0 1120526208 27665536 9656144
11:40:04 7114 8192000000 10053176448 10449760256 1111123712 11.943 100 60 115 1 1158190784 35168896 11898176
11:41:17 11705 8192000000 10139011152 10549239808 1122837888 12.054 100 63 125 1 1255311920 120067744 12406288
11:42:36 6091 8192000000 10116409088 10518556672 1137331488 12.190 100 44 86 2 1218528096 72154080 9042528
11:43:46 5696 8192000000 10170629792 10574352384 1144726736 12.260 100 57 114 0 1270173792 113391152 12055904
11:44:54 13072 8192000000 10202998928 10617417728 1147346336 12.285 100 47 94 0 1320272112 162192688 10733088
11:46:11 3559 8192000000 10221352048 10630987776 1156186416 12.368 100 72 140 0 1325886976 154935776 14764784
11:47:17 3835 8192000000 10209891184 10624004096 1171121200 12.507 100 60 120 1 1314546544 130158080 13267264
11:48:25 10457 8192000000 10267875616 10684866560 1178736208 12.578 100 55 110 1 1365937920 174872208 12329504
11:49:39 10826 8192000000 10285465456 10697371648 1178958912 12.580 100 71 142 0 1388252464 194194848 15098704
11:50:56 8577 8192000000 10325435168 10739179520 1178709712 12.578 100 64 128 0 1425899440 232737968 14451760
11:52:10 3034 8192000000 10364051376 10769973248 1167762912 12.476 100 79 158 1 1468225312 282587440 17874960
11:53:17 2227 8192000000 10426189488 10827194368 1172242016 12.518 100 83 164 1 1521486480 330579232 18665232
11:54:25 4478 8192000000 10427479472 10831224832 1153698656 12.344 99 86 172 2 1518606544 344851088 20056800
11:55:33 2284 8192000000 10521780336 10928971776 1181401696 12.603 100 93 186 1 1614431648 411270016 21759936
11:56:39 2595 8192000000 10547671648 10954354688 1189163120 12.676 100 88 174 1 1643938848 433734032 21041696
11:57:46 10815 8192000000 10525759040 10956587008 1160382896 12.407 100 47 94 3 1624433264 451470816 12579552
11:59:00 15830 8192000000 10509596928 10949324800 1154882336 12.355 100 40 80 0 1610092048 444553600 10656112
12:00:20 14982 8192000000 10484205088 10921250816 1141408400 12.229 100 38 76 1 1568887904 417122192 10357312
12:01:40 18998 8192000000 10492866944 10931687424 1142866704 12.242 100 34 66 1 1580414896 428530400 9017792
12:03:01 15579 8192000000 10491660544 10970353664 1137465280 12.192 100 28 56 1 1589555216 444394064 7695872
12:04:21 26773 8192000000 10321600864 10830086144 1117901200 12.007 100 20 40 0 1441480336 318053456 5525680
12:05:49 25683 8192000000 10055144800 10478067712 1126161296 12.085 100 50 97 0 1145277424 8834608 10281520
12:07:16 91015 8192000000 10071415536 10513342464 1116982336 11.998 98 19 38 0 1135273136 13968128 4322672
12:09:50 59179 8192000000 10112819184 10567827456 1106987888 11.904 97 42 82 1 1174352896 58886128 8478880
12:12:05 26244 8192000000 10115608432 10550407168 1133537136 12.155 99 28 56 2 1204615776 65277936 5800704
12:13:36 31447 8192000000 10106943904 10535817216 1115154496 11.981 98 39 73 0 1181867824 58823984 7889344
12:15:09 22893 8192000000 10150052960 10607312896 1132600688 12.146 100 37 75 0 1253507680 112173648 8733344
12:17:13 23578 8192000000 10064825728 10526851072 1122501200 12.051 99 15 30 0 1149714000 23930704 3282096
12:18:43 76002 8192000000 10079824896 10534514688 1129362528 12.115 99 19 38 2 1172626432 38794400 4469504
12:21:08 43252 8192000000 10114817744 10586128384 1131306608 12.134 99 26 52 0 1210025760 73642064 5077088
12:23:01 43932 8192000000 10082664976 10566471680 1126501280 12.088 99 17 33 1 1171984496 42193216 3290000
12:24:45 35050 8192000000 10091470736 10561519616 1144303376 12.256 100 28 56 0 1181031392 31225920 5502096
12:26:28 52819 8192000000 10094924144 10564116480 1128248736 12.105 99 19 38 0 1187211616 54473456 4489424
12:28:22 47070 8192000000 10166998016 10606391296 1135675104 12.175 98 27 54 1 1244857952 102637792 6545056
12:30:13 58797 8192000000 10196001472 10669404160 1135529200 12.173 99 17 34 0 1277062400 137730112 3803088
12:32:39 61004 8192000000 10065915008 10519363584 1102531936 11.862 97 12 24 0 1131497904 26651232 2314736
12:34:48 5960 8192000000 10104309024 10523934720 1133324336 12.153 100 75 147 0 1185781424 36855232 15601856
12:35:55 127907 8192000000 10101431408 10555330560 1135073104 12.169 99 33 64 0 1205532848 63543360 6916384
12:39:03 28587 8192000000 10068560032 10562355200 1145296448 12.265 100 8 15 0 1178101024 31433424 1371152
12:40:48 45557 8192000000 10083782192 10561392640 1117990912 12.008 99 9 18 0 1191283568 70983664 2308992
12:42:34 62021 8192000000 10111090496 10590679040 1097333872 11.812 96 15 30 0 1196235504 94810560 4091072
12:45:43 89833 8192000000 10133747296 10621919232 1138579264 12.202 99 24 47 0 1222572960 78960752 5032944
12:48:40 22971 8192000000 10090222624 10529431552 1125282048 12.077 99 22 44 2 1167688528 37661344 4745136
12:50:13 44279 8192000000 10149749008 10593202176 1082778032 11.674 96 20 40 0 1174092528 87188560 4125936
12:52:15 62702 8192000000 10075633408 10521501696 1070927520 11.561 95 18 36 0 1124008208 49263200 3817488
12:54:17 126650 8192000000 10056086096 10493976576 1077239664 11.621 97 33 66 1 1118638032 34685872 6712496
12:57:26 49229 8192000000 10048725328 10542034944 1080969696 11.657 97 6 12 0 1153535232 71250576 1314960
12:59:15 62106 8192000000 10058928000 10515169280 1111136288 11.943 100 12 24 0 1163971936 49937712 2897936
13:01:18 103444 8192000000 10094235040 10541101056 1118536112 12.013 99 11 22 0 1179502064 58628976 2336976
13:04:02 48628 8192000000 10053226944 10495520768 1076823504 11.617 95 29 57 1 1097042512 13485584 6733424
13:05:56 35874 8192000000 10079860784 10518679552 1090991952 11.752 97 29 56 0 1158433968 61647168 5794848
13:07:36 61816 8192000000 10090593280 10548928512 1112227232 11.953 98 18 36 0 1147965440 31948896 3789312
13:09:38 45964 8192000000 10093697072 10535960576 1116941792 11.998 98 22 44 1 1162994896 41857616 4195488
13:11:28 35090 8192000000 10092295568 10615468032 1113736784 11.968 98 17 34 0 1179204256 61973392 3494080
13:13:06 35780 8192000000 10080064960 10529116160 1130831904 12.129 99 26 52 0 1165532688 29414640 5286144
13:14:46 109583 8192000000 10099115840 10573508608 1140241872 12.218 100 18 36 0 1212719248 68420320 4057056
13:17:39 56814 8192000000 10089099840 10579705856 1142776000 12.242 100 7 14 0 1203446480 59335168 1335312
13:21:19 44965 8192000000 10098058496 10542346240 1117157328 12.000 97 22 44 1 1169128608 46964800 5006480
13:23:05 108223 8192000000 10125043616 10657161216 1133945680 12.159 99 25 50 0 1274075152 134373968 5755504
13:26:03 47177 8192000000 10092058496 10560163840 1104422480 11.880 96 28 56 0 1167668928 57231840 6014608
13:27:56 70044 8192000000 10084519616 10524819456 1088681936 11.730 96 29 58 0 1117979296 23300672 5996688
13:30:14 81665 8192000000 10094288240 10552987648 1100610112 11.843 97 25 50 1 1157584992 51786976 5187904
13:32:36 79190 8192000000 10086467248 10515189760 1082039696 11.667 96 39 76 1 1129257776 38506592 8711488
13:35:49 65267 8192000000 10059632048 10519904256 1119112528 12.019 99 19 38 0 1155925280 32968832 3843920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-10-2021 13:39:18 to 03-10-2021 13:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-10-2021 13:39:34 to 03-10-2021 13:39:36.

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          234537
MON$OLDEST_ACTIVE               236876
MON$OLDEST_SNAPSHOT             236876
MON$NEXT_TRANSACTION            236876
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                       2049504
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2622 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2622 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2622 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2622 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Sun Oct  3 13:37:38 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
--- end of diff output ---

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

03.10.21 13:39:37. 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-10-03 13:39:39 No severe PSQL-related problems occured
03.10.21 13:39:41. End of report.