Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.10.2021 10: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 15730648 kB
MemAvailable 30773908 kB
Buffers 0 kB
Cached 15995948 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 10:37:06
DTS_END                         2021-10-03 10:37:06.6260
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/39732
TRN_ID                          241888
ATT_ID                          691
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: 9184.01 20876 1102081
customer order: creation 175.38 369 21045
customer order: refuse 15.43 318 1852
order to supplier: creation 46.50 1344 5580
order to supplier: removal 3.44 100669 413
invoice (draft): creation 46.28 3586 5554
invoice (draft): removal 50.77 840 6092
invoice accept: apply 42.13 18554 5055
invoice accept: cancel 45.79 804 5495
customer reserve: creation 3928.45 182 471414
customer reserve: removal 4527.48 60 543297
realization accept: apply 84.76 205 10171
realization accept: cancel 87.64 105 10517
payment from customer: creation 59.88 149 7185
payment from customer: removal 5.72 79 686
payment to supplier: creation 49.68 161 5962
payment to supplier: removal 3.64 79 437
service: total inventory turnovers 4.01 3244 481
service: total monetary turnovers 6.09 552 731
service: aggregate perf. data 0.91 15799 109
service: refresh index statistics 0.04 270419 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-10-2021 10:38:51 to 03-10-2021 10:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4180
TEST_TIME 2 4125
TEST_TIME 3 4397
TEST_TIME 4 5501
TEST_TIME 5 5546
TEST_TIME 6 5485
TEST_TIME 7 5344
TEST_TIME 8 5378
TEST_TIME 9 5783
TEST_TIME 10 5933
TEST_TIME 11 6437
TEST_TIME 12 7306
TEST_TIME 13 7774
TEST_TIME 14 7801
TEST_TIME 15 8287
TEST_TIME 16 8442
TEST_TIME 17 8622
TEST_TIME 18 9005
TEST_TIME 19 9168
TEST_TIME 20 9260
TEST_TIME 21 9696
TEST_TIME 22 9784
TEST_TIME 23 10172
TEST_TIME 24 10651
TEST_TIME 25 10710
TEST_TIME 26 10500
TEST_TIME 27 11332
TEST_TIME 28 11188
TEST_TIME 29 11167
TEST_TIME 30 10958
TEST_TIME 31 11166
TEST_TIME 32 10984
TEST_TIME 33 11043
TEST_TIME 34 11103
TEST_TIME 35 11011
TEST_TIME 36 10974
TEST_TIME 37 11133
TEST_TIME 38 11026
TEST_TIME 39 10753
TEST_TIME 40 10582
TEST_TIME 41 10464
TEST_TIME 42 10396
TEST_TIME 43 10324
TEST_TIME 44 10190
TEST_TIME 45 10230
TEST_TIME 46 10236
TEST_TIME 47 10135
TEST_TIME 48 10007
TEST_TIME 49 9977
TEST_TIME 50 9895
TEST_TIME 51 9844
TEST_TIME 52 9873
TEST_TIME 53 9789
TEST_TIME 54 9730
TEST_TIME 55 9667
TEST_TIME 56 9563
TEST_TIME 57 9438
TEST_TIME 58 9463
TEST_TIME 59 9521
TEST_TIME 60 9456
TEST_TIME 61 9584
TEST_TIME 62 9519
TEST_TIME 63 9588
TEST_TIME 64 9571
TEST_TIME 65 9542
TEST_TIME 66 9629
TEST_TIME 67 9639
TEST_TIME 68 9711
TEST_TIME 69 9691
TEST_TIME 70 9732
TEST_TIME 71 9671
TEST_TIME 72 9599
TEST_TIME 73 9519
TEST_TIME 74 9518
TEST_TIME 75 9447
TEST_TIME 76 9467
TEST_TIME 77 9415
TEST_TIME 78 9384
TEST_TIME 79 9383
TEST_TIME 80 9354
TEST_TIME 81 9361
TEST_TIME 82 9355
TEST_TIME 83 9332
TEST_TIME 84 9375
TEST_TIME 85 9451
TEST_TIME 86 9428
TEST_TIME 87 9442
TEST_TIME 88 9504
TEST_TIME 89 9495
TEST_TIME 90 9585
TEST_TIME 91 9506
TEST_TIME 92 9484
TEST_TIME 93 9456
TEST_TIME 94 9395
TEST_TIME 95 9332
TEST_TIME 96 9355
TEST_TIME 97 9357
TEST_TIME 98 9331
TEST_TIME 99 9315
TEST_TIME 100 9281
TEST_TIME 101 9288
TEST_TIME 102 9345
TEST_TIME 103 9396
TEST_TIME 104 9414
TEST_TIME 105 9390
TEST_TIME 106 9430
TEST_TIME 107 9419
TEST_TIME 108 9421
TEST_TIME 109 9471
TEST_TIME 110 9511
TEST_TIME 111 9479
TEST_TIME 112 9449
TEST_TIME 113 9398
TEST_TIME 114 9373
TEST_TIME 115 9359
TEST_TIME 116 9367
TEST_TIME 117 9336
TEST_TIME 118 9277
TEST_TIME 119 9265
TEST_TIME 120 9190

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-10-2021 10:38:55 to 03-10-2021 10: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 1536774 1536774 0 0.00 0 24749 7 0 0
doc_list_biud 1536755 1536755 0 0.00 0 24242 27 0 0
sp_add_invoice_to_stock 5164 5055 109 2.11 33 167653 18554 0 109
sp_cancel_adding_invoice 5495 5495 0 0.00 6 52938 804 0 0
sp_cancel_client_order 1852 1852 0 0.00 5 11617 319 0 0
sp_cancel_customer_reserve 543297 543297 0 0.00 1 23042 61 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 4124 80 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 6651 80 0 0
sp_cancel_supplier_invoice 6099 6092 7 0.11 3 22136 841 0 7
sp_cancel_supplier_order 413 413 0 0.00 7 1089335 100670 0 0
sp_cancel_write_off 10517 10517 0 0.00 1 18578 106 0 0
sp_client_order 21045 21045 0 0.00 4 23777 369 0 0
sp_customer_reserve 471414 471414 0 0.00 0 117205 182 0 0
sp_fill_shopping_cart 32175 32175 0 0.00 1 20244 45 0 0
sp_fill_shopping_cart_clo_res 471408 471408 0 0.00 0 20263 15 0 0
sp_get_clo_for_invoice 5049 5049 0 0.00 0 35699 361 0 0
sp_kill_cost_storno 17135 17135 0 0.00 0 8441 58 0 0
sp_kill_qstorno_ret_qs2qd 1103616 1103616 0 0.00 0 23037 33 0 0
sp_kill_qty_storno 582877 582877 0 0.00 0 24242 71 0 0
sp_lock_selected_doc 21144 21144 0 0.00 0 9065 6 0 0
sp_make_cost_storno 28367 28367 0 0.00 0 18333 69 0 0
sp_multiply_rows_for_pdistr 28363 28363 0 0.00 0 11141 39 0 0
sp_multiply_rows_for_qdistr 21045 21045 0 0.00 0 15109 180 0 0
sp_pay_from_customer 7185 7185 0 0.00 4 41028 149 0 0
sp_pay_to_supplier 5962 5962 0 0.00 5 17659 162 0 0
sp_payment_common 13147 13147 0 0.00 1 40944 42 0 0
sp_qd_handle_on_cancel_clo 1852 1852 0 0.00 0 11609 285 0 0
sp_qd_handle_on_invoice_upd_sts 10544 10544 0 0.00 0 24242 342 0 0
sp_qd_handle_on_reserve_upd_sts 10162 10162 0 0.00 0 10063 47 0 0
sp_reserve_write_off 10171 10171 0 0.00 6 19245 205 0 0
sp_supplier_invoice 5554 5554 0 0.00 6 92682 3587 0 0
sp_supplier_order 5580 5580 0 0.00 5 38646 1345 0 0
srv_aggregate_perf_data 109 109 0 0.00 2939 250132 15799 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1348 114263 41706 0 0
srv_find_qd_qs_mism 1052686 1052686 0 0.00 0 24749 6 0 0
srv_make_invnt_saldo 481 481 0 0.00 57 94872 3244 0 0
srv_make_money_saldo 731 731 0 0.00 0 19984 553 0 0
srv_recalc_idx_stat 5 5 0 0.00 169705 416164 270420 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 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 5055 5055 0 0.00 0 13184 157 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 5471 98 0 0
t$perf-norm:sp_cancel_client_order 1852 1852 0 0.00 0 2914 14 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 10255 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 1318 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 2666 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 562 13 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 26975 1493 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 2519 6 0 0
t$perf-norm:sp_client_order 21045 21045 0 0.00 0 8405 15 0 0
t$perf-norm:sp_customer_reserve 1077 1077 0 0.00 0 5486 11 0 0
t$perf-norm:sp_pay_from_customer 7185 7185 0 0.00 0 4643 10 0 0
t$perf-norm:sp_pay_to_supplier 5962 5962 0 0.00 0 3887 8 0 0
t$perf-norm:sp_reserve_write_off 10171 10171 0 0.00 0 8673 11 0 0
t$perf-norm:sp_supplier_invoice 5554 5554 0 0.00 0 9208 18 0 0
t$perf-norm:sp_supplier_order 5580 5580 0 0.00 0 3472 12 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 282 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 55 2 0 0
t$perf-norm:srv_make_invnt_saldo 481 481 0 0.00 0 3318 18 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 1911 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10312 10094 218 2.11 0 15890 23 0 218
v_all_customers 38094 38094 0 0.00 0 19 1 0 0
v_all_suppliers 30824 30824 0 0.00 0 206 1 0 0
v_all_wares 42084 42084 0 0.00 0 175 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 1235 8 0 0
v_cancel_client_order 3704 3704 0 0.00 0 9244 9 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 405 4 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 15599 15 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 2817 10 0 0
v_cancel_supplier_invoice 870 856 14 1.60 0 16360 34 0 14
v_cancel_supplier_order 826 826 0 0.00 0 10306 21 0 0
v_cancel_write_off 2022 2022 0 0.00 0 16068 26 0 0
v_max_id_clo_ord 5579 5579 0 0.00 0 303 1 0 0
v_max_id_clo_res 1077 1077 0 0.00 0 3297 21 0 0
v_max_id_ord_sup 5554 5554 0 0.00 0 10852 8 0 0
v_max_non_paid_invoice 1683 1683 0 0.00 0 4297 6 0 0
v_max_non_paid_realizn 3820 3820 0 0.00 0 1284 4 0 0
v_min_id_clo_ord 5579 5579 0 0.00 0 3604 8 0 0
v_min_id_clo_res 1077 1077 0 0.00 0 3153 14 0 0
v_min_id_ord_sup 5554 5554 0 0.00 0 11744 13 0 0
v_min_non_paid_invoice 5962 5962 0 0.00 0 8099 30 0 0
v_min_non_paid_realizn 7185 7185 0 0.00 0 40939 36 0 0
v_our_firm 10720 10720 0 0.00 0 17 1 0 0
v_reserve_write_off 20324 20324 0 0.00 0 19128 11 0 0
x_lock_depdocs_on_canc_invoice 11159 11159 0 0.00 0 17373 100 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 5085 263 0 0
x_make_qstorno_1000_1200 5580 5580 0 0.00 2 38633 1267 0 0
x_make_qstorno_1200_2000 5554 5554 0 0.00 3 88029 3409 0 0
x_make_qstorno_2100_3300 471414 471414 0 0.00 0 117195 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-10-2021 10:38:59 to 03-10-2021 10: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
08:07:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:11 23678 8192000000 9718578496 10014887936 796503808 8.861 100 80 160 0 822705584 7060048 19141728
08:09:45 19083 8192000000 9762182304 10066276352 834130736 9.241 100 87 174 0 867590656 12678672 20781248
08:11:18 15246 8192000000 9811561856 10130468864 868767840 9.588 100 83 165 0 917916368 29902464 19246064
08:12:38 12223 8192000000 9874705104 10208129024 920591216 10.102 100 77 154 1 981747568 44819088 16337264
08:13:51 7393 8192000000 9922197696 10265288704 955580480 10.446 100 84 167 0 1032944000 59785952 17577568
08:15:01 5419 8192000000 9971038160 10322235392 992402688 10.805 100 84 169 0 1084021696 73922096 17696912
08:16:09 2839 8192000000 10006199024 10365349888 1022958304 11.101 100 88 175 1 1122500112 81650256 17891552
08:17:14 1760 8192000000 10036574560 10404921344 1049193264 11.353 100 92 184 1 1153220112 85683216 18343632
08:18:17 1326 8192000000 10037978400 10408996864 1067998448 11.533 100 97 195 1 1143793968 55103856 20691664
08:19:19 2911 8192000000 10006042880 10391846912 1072330800 11.574 100 44 89 1 1111986224 30461040 9194384
08:20:23 5065 8192000000 10002773088 10393862144 1080670848 11.654 100 57 113 0 1117430752 24639712 12120192
08:21:32 11972 8192000000 9998462816 10404372480 1088608000 11.729 100 38 76 0 1113204256 15727920 8868336
08:22:48 51477 8192000000 10020618288 10423218176 1091751744 11.759 100 39 77 1 1114064720 14309968 8003008
08:24:43 8374 8192000000 10018571744 10431979520 1097092480 11.810 100 41 82 1 1127732240 21152544 9487216
08:25:53 8526 8192000000 10012178144 10425729024 1096134448 11.801 100 28 57 2 1121962240 19840400 5987392
08:27:03 3837 8192000000 9990176096 10381795328 1046103456 11.323 96 46 90 0 1072743616 16949616 9690544
08:28:08 52567 8192000000 9966351360 10368491520 971869088 10.605 90 26 52 0 1000917424 23350032 5698304
08:30:02 6149 8192000000 9947026080 10339270656 1001290992 10.891 96 44 88 1 1038829776 27501568 10037216
08:31:09 14345 8192000000 9934299520 10327650304 990493088 10.786 96 32 64 1 1016156336 18676688 6986560
08:32:23 43811 8192000000 9924929312 10314186752 1021284720 11.084 99 34 64 2 1052909152 24752448 6871984
08:34:20 6611 8192000000 9961356560 10361503744 1046864064 11.331 100 52 104 3 1070842384 12735360 11242960
08:35:28 11163 8192000000 9957739376 10364350464 1055365424 11.412 100 31 61 1 1083918400 21615328 6937648
08:36:41 8230 8192000000 9990883072 10384375808 1071106784 11.563 100 28 56 0 1101374416 24104496 6163136
08:37:52 87312 8192000000 10047710448 10453458944 1098125584 11.820 100 44 87 1 1151480480 44229888 9125008
08:40:23 8550 8192000000 10113135712 10514833408 1121317488 12.039 100 75 150 0 1217332352 80413424 15601440
08:41:41 14137 8192000000 10166728288 10579312640 1138050512 12.197 100 60 120 0 1267270352 116714144 12505696
08:43:06 9479 8192000000 10165634240 10583240704 1147811488 12.289 100 56 109 2 1286615984 126452848 12351648
08:44:20 10550 8192000000 10216283792 10627665920 1147965856 12.290 99 59 118 0 1312261952 150945248 13350848
08:45:35 8836 8192000000 10256788576 10664321024 1157185696 12.377 100 67 133 0 1352000048 180117984 14696368
08:46:51 25491 8192000000 10280480496 10698481664 1143006704 12.244 98 51 101 1 1362367952 207245168 12116080
08:48:20 2455 8192000000 10307763104 10720112640 1164174192 12.442 100 72 141 4 1408916656 228419440 16323024
08:49:25 5633 8192000000 10332609248 10739081216 1173046848 12.525 100 82 161 0 1418683824 227677744 17959232
08:50:35 1347 8192000000 10323870768 10735349760 1178662224 12.578 100 70 136 0 1428113888 233571904 15879760
08:51:40 9774 8192000000 10338972064 10755543040 1175903408 12.552 100 63 126 3 1441023472 250492608 14627456
08:52:53 3045 8192000000 10324392384 10741932032 1161236544 12.415 100 91 177 0 1421180112 239975616 19967952
08:54:02 3441 8192000000 10345513296 10756247552 1165290112 12.453 100 72 144 0 1437463072 255564048 16608912
08:55:08 6848 8192000000 10330882832 10760626176 1137151936 12.189 98 45 90 0 1416599536 268153136 11294464
08:56:22 6114 8192000000 10321305632 10750758912 1149128272 12.301 100 45 90 1 1417783904 257690896 10964736
08:57:30 4835 8192000000 10336759040 10749317120 1136630864 12.184 100 58 115 0 1433487056 283598880 13257312
08:58:37 34273 8192000000 10273512992 10749911040 1082935200 11.675 96 22 44 1 1368929856 280047712 5946944
09:00:30 26550 8192000000 10244812192 10696499200 1113765440 11.968 99 17 34 0 1321781296 203559904 4455952
09:02:01 22965 8192000000 10198403520 10687995904 1106453344 11.899 98 22 43 0 1302829520 191252352 5123824
09:03:28 28699 8192000000 10243317840 10688086016 1113376640 11.964 99 19 38 0 1328661008 210518384 4765984
09:04:58 73486 8192000000 10058700272 10522509312 1075249552 11.602 97 13 26 0 1130632496 52694592 2688352
09:07:26 109917 8192000000 10091642352 10574995456 1126176544 12.085 100 17 34 2 1212342176 83262624 2903008
09:10:26 29673 8192000000 10064184672 10497724416 1125504208 12.079 100 16 28 1 1140700944 12519264 2677472
09:12:09 56140 8192000000 10086634672 10520530944 1120760784 12.034 98 16 32 0 1155762480 32197776 2803920
09:14:07 71831 8192000000 10091982896 10584788992 1133229136 12.152 99 8 16 1 1191407520 56667776 1510608
09:16:19 69510 8192000000 10083288000 10523324416 1120576992 12.032 99 49 98 1 1167931152 36200208 11153952
09:18:35 15063 8192000000 10084114544 10529316864 1128379600 12.106 99 28 55 1 1180637408 46814976 5442832
09:19:52 33650 8192000000 10114385872 10555019264 1137412560 12.191 100 19 38 0 1206458160 64459744 4585856
09:21:31 30037 8192000000 10139038816 10574741504 1145239824 12.265 100 57 116 1 1242997520 85624304 12133392
09:23:02 95081 8192000000 10079816000 10557755392 1133723648 12.156 99 10 19 1 1188284560 52469296 2091616
09:25:42 43979 8192000000 10092594336 10569830400 1137875024 12.196 99 17 32 1 1203737008 62106816 3755168
09:29:06 55343 8192000000 10085171552 10601164800 1139075520 12.207 99 15 30 0 1202137824 60123216 2939088
09:31:04 39024 8192000000 10059913360 10512748544 1130416800 12.125 99 35 70 0 1161206480 23740720 7048960
09:32:44 49544 8192000000 10093395024 10572292096 1147207936 12.283 100 11 22 1 1198909712 49459136 2242640
09:34:39 36434 8192000000 10108292576 10593869824 1151595616 12.324 100 26 53 1 1202446096 45093072 5757408
09:36:18 81283 8192000000 10127632960 10625359872 1125821024 12.082 98 15 30 0 1201684832 72900784 2963024
09:38:53 46221 8192000000 10085797152 10532245504 1103056352 11.867 96 17 33 1 1156662560 50417872 3188336
09:40:43 41150 8192000000 10098172992 10536652800 1071165456 11.563 94 29 58 1 1151815648 74150032 6500160
09:42:48 48832 8192000000 10065890928 10535862272 1103497200 11.871 97 29 58 0 1155167536 46388768 5281568
09:44:49 66138 8192000000 10056289616 10532925440 1068847776 11.541 95 32 63 1 1172089792 97080448 6161568
09:46:55 55081 8192000000 10075830384 10513715200 1097696832 11.816 97 29 58 0 1129731696 26226992 5807872
09:48:52 84478 8192000000 10047345168 10483650560 1107676736 11.910 98 34 68 2 1147222064 32497408 7047920
09:51:18 58503 8192000000 10055896432 10511282176 1109159056 11.924 99 32 64 0 1184558096 67755920 7643120
09:53:24 54382 8192000000 10051088608 10531807232 1113718624 11.968 99 6 12 0 1164681968 49602144 1361200
09:55:19 79933 8192000000 10045658416 10545377280 1105251168 11.887 98 10 20 0 1146655888 39571952 1832768
09:57:39 11979 8192000000 10059567744 10498195456 1128743552 12.110 100 44 87 0 1160870384 22702768 9424064
09:58:57 13504 8192000000 10062680880 10501210112 1126387968 12.087 100 48 96 1 1173831184 36242016 11201200
10:00:14 55805 8192000000 10116533216 10581622784 1103176704 11.868 98 21 42 0 1199408640 91797008 4434928
10:02:13 52231 8192000000 10050412944 10478731264 1100406080 11.841 98 28 55 2 1142406464 35959632 6040752
10:04:06 28914 8192000000 10143597504 10582052864 1134148512 12.160 100 48 96 1 1208647296 65072080 9426704
10:05:36 36352 8192000000 10074167472 10519949312 1126753952 12.091 99 23 44 0 1164777344 33473248 4550144
10:07:16 57253 8192000000 10123708432 10645770240 1146089200 12.273 100 16 32 1 1265951344 116090368 3771776
10:09:44 30336 8192000000 10073874368 10511413248 1142386000 12.238 100 41 82 2 1164575344 13358896 8830448
10:11:15 66914 8192000000 10098200048 10568466432 1126862800 12.092 98 9 17 1 1186813296 57760656 2189840
10:13:33 60134 8192000000 10089880032 10592493568 1140852272 12.224 99 17 32 0 1197640592 53172448 3615872
10:15:33 34079 8192000000 10109080352 10561605632 1136746928 12.185 99 20 40 1 1182596016 42220400 3628688
10:17:08 27143 8192000000 10132624880 10581065728 1131547168 12.136 98 28 56 2 1222025504 83474448 7003888
10:18:41 95701 8192000000 10124200368 10645274624 1133269792 12.152 98 17 34 0 1264694672 127522096 3902784
10:21:50 19013 8192000000 10088063888 10528395264 1154262288 12.349 100 37 72 1 1178256720 17036000 6958432
10:23:11 25621 8192000000 10109605760 10558709760 1121958096 12.045 99 45 90 1 1180792944 49932640 8902208
10:24:40 46738 8192000000 10091074272 10522279936 1064583008 11.500 94 13 26 0 1140314912 73349808 2382096
10:26:28 48529 8192000000 10105272512 10541506560 1097229936 11.811 98 23 46 1 1176681952 74585296 4866720
10:28:17 114263 8192000000 10061878336 10522578944 1116323440 11.992 99 23 45 2 1150977248 29591408 5062400
10:32:15 60788 8192000000 10071223408 10562260992 1088459472 11.728 97 8 16 0 1181966992 91680752 1826768
10:34:16 91886 8192000000 10062622464 10515603456 1105916624 11.894 99 10 20 0 1136641920 28643392 2081904
10:36:49 30272 8192000000 9370862288 9745174528 493768704 5.684 45 2 4 0 517972496 23976016 227776

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 10:39:05 to 03-10-2021 10:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 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 10:39:21 to 03-10-2021 10:39: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          242238
MON$OLDEST_ACTIVE               242239
MON$OLDEST_SNAPSHOT             242239
MON$NEXT_TRANSACTION            242239
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                       2067616
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             817
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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Sun Oct  3 10:37:32 2021
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
--- end of diff output ---

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

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