Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.08.2021 04:39
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 16296488 kB
MemAvailable 30973784 kB
Buffers 20 kB
Cached 15376852 kB
SwapCached 2420 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-08-23 04:37:06
DTS_END                         2021-08-23 04:37:06.8310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_PAY_FROM_CUSTOMER(18:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42418
TRN_ID                          231359
ATT_ID                          679
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: 8994.11 23543 1079293
customer order: creation 163.20 424 19584
customer order: refuse 14.33 311 1720
order to supplier: creation 43.89 1500 5267
order to supplier: removal 3.25 113501 390
invoice (draft): creation 45.31 3390 5437
invoice (draft): removal 49.82 890 5978
invoice accept: apply 41.48 18453 4978
invoice accept: cancel 45.63 844 5475
customer reserve: creation 3831.73 182 459808
customer reserve: removal 4469.52 66 536343
realization accept: apply 80.59 198 9671
realization accept: cancel 80.18 124 9622
payment from customer: creation 57.15 128 6858
payment from customer: removal 5.33 71 640
payment to supplier: creation 48.53 177 5824
payment to supplier: removal 3.43 106 412
service: total inventory turnovers 3.98 2984 477
service: total monetary turnovers 5.88 460 705
service: aggregate perf. data 0.83 20151 100
service: refresh index statistics 0.03 306903 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-08-2021 04:39:20 to 23-08-2021 04:39:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5353
TEST_TIME 2 5097
TEST_TIME 3 5569
TEST_TIME 4 5078
TEST_TIME 5 6013
TEST_TIME 6 5626
TEST_TIME 7 5273
TEST_TIME 8 5366
TEST_TIME 9 5686
TEST_TIME 10 5811
TEST_TIME 11 6330
TEST_TIME 12 7111
TEST_TIME 13 7037
TEST_TIME 14 7938
TEST_TIME 15 7952
TEST_TIME 16 8334
TEST_TIME 17 8450
TEST_TIME 18 8531
TEST_TIME 19 8973
TEST_TIME 20 9284
TEST_TIME 21 9268
TEST_TIME 22 9442
TEST_TIME 23 9538
TEST_TIME 24 9811
TEST_TIME 25 10944
TEST_TIME 26 11442
TEST_TIME 27 11380
TEST_TIME 28 11212
TEST_TIME 29 10912
TEST_TIME 30 10888
TEST_TIME 31 10642
TEST_TIME 32 10432
TEST_TIME 33 10333
TEST_TIME 34 10275
TEST_TIME 35 10427
TEST_TIME 36 10309
TEST_TIME 37 10098
TEST_TIME 38 9945
TEST_TIME 39 9804
TEST_TIME 40 9637
TEST_TIME 41 9670
TEST_TIME 42 9682
TEST_TIME 43 9514
TEST_TIME 44 9489
TEST_TIME 45 9443
TEST_TIME 46 9475
TEST_TIME 47 9516
TEST_TIME 48 9452
TEST_TIME 49 9429
TEST_TIME 50 9544
TEST_TIME 51 9480
TEST_TIME 52 9479
TEST_TIME 53 9503
TEST_TIME 54 9510
TEST_TIME 55 9600
TEST_TIME 56 9715
TEST_TIME 57 9747
TEST_TIME 58 9604
TEST_TIME 59 9494
TEST_TIME 60 9384
TEST_TIME 61 9430
TEST_TIME 62 9352
TEST_TIME 63 9348
TEST_TIME 64 9261
TEST_TIME 65 9205
TEST_TIME 66 9140
TEST_TIME 67 9127
TEST_TIME 68 9111
TEST_TIME 69 9125
TEST_TIME 70 9103
TEST_TIME 71 9166
TEST_TIME 72 9151
TEST_TIME 73 9230
TEST_TIME 74 9301
TEST_TIME 75 9361
TEST_TIME 76 9349
TEST_TIME 77 9324
TEST_TIME 78 9414
TEST_TIME 79 9368
TEST_TIME 80 9408
TEST_TIME 81 9412
TEST_TIME 82 9310
TEST_TIME 83 9272
TEST_TIME 84 9297
TEST_TIME 85 9269
TEST_TIME 86 9215
TEST_TIME 87 9168
TEST_TIME 88 9128
TEST_TIME 89 9047
TEST_TIME 90 8991
TEST_TIME 91 8990
TEST_TIME 92 9008
TEST_TIME 93 9032
TEST_TIME 94 9040
TEST_TIME 95 9177
TEST_TIME 96 9158
TEST_TIME 97 9186
TEST_TIME 98 9175
TEST_TIME 99 9195
TEST_TIME 100 9240
TEST_TIME 101 9240
TEST_TIME 102 9198
TEST_TIME 103 9129
TEST_TIME 104 9141
TEST_TIME 105 9084
TEST_TIME 106 9057
TEST_TIME 107 9015
TEST_TIME 108 8995
TEST_TIME 109 8974
TEST_TIME 110 8958
TEST_TIME 111 8936
TEST_TIME 112 8936
TEST_TIME 113 8935
TEST_TIME 114 8946
TEST_TIME 115 9010
TEST_TIME 116 9038
TEST_TIME 117 9039
TEST_TIME 118 9100
TEST_TIME 119 9062
TEST_TIME 120 9001

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-08-2021 04:39:23 to 23-08-2021 04:39:27.

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 1503646 1503646 0 0.00 0 21438 7 0 0
doc_list_biud 1503638 1503638 0 0.00 0 40532 30 0 0
sp_add_invoice_to_stock 5115 4978 137 2.67 27 247484 18453 0 137
sp_cancel_adding_invoice 5475 5475 0 0.00 5 63737 844 0 0
sp_cancel_client_order 1720 1720 0 0.00 4 8704 312 0 0
sp_cancel_customer_reserve 536343 536343 0 0.00 1 40534 66 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 3 5622 72 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 5453 107 0 0
sp_cancel_supplier_invoice 5984 5978 6 0.10 2 36072 891 0 6
sp_cancel_supplier_order 390 390 0 0.00 7 1051833 113502 0 0
sp_cancel_write_off 9622 9622 0 0.00 1 12690 125 0 0
sp_client_order 19584 19584 0 0.00 4 33285 424 0 0
sp_customer_reserve 459808 459808 0 0.00 0 102285 182 0 0
sp_fill_shopping_cart 30274 30274 0 0.00 1 22665 49 0 0
sp_fill_shopping_cart_clo_res 459790 459790 0 0.00 0 15612 17 0 0
sp_get_clo_for_invoice 4960 4960 0 0.00 0 26081 425 0 0
sp_kill_cost_storno 16149 16149 0 0.00 0 21411 62 0 0
sp_kill_qstorno_ret_qs2qd 1088676 1088676 0 0.00 0 40529 36 0 0
sp_kill_qty_storno 574155 574155 0 0.00 0 40532 76 0 0
sp_lock_selected_doc 20268 20268 0 0.00 0 9334 4 0 0
sp_make_cost_storno 27313 27313 0 0.00 0 17679 67 0 0
sp_multiply_rows_for_pdistr 27307 27307 0 0.00 0 9217 38 0 0
sp_multiply_rows_for_qdistr 19584 19584 0 0.00 0 33278 230 0 0
sp_pay_from_customer 6858 6858 0 0.00 4 13255 128 0 0
sp_pay_to_supplier 5824 5824 0 0.00 5 16320 177 0 0
sp_payment_common 12677 12677 0 0.00 1 9005 35 0 0
sp_qd_handle_on_cancel_clo 1720 1720 0 0.00 0 8699 289 0 0
sp_qd_handle_on_invoice_upd_sts 10434 10434 0 0.00 0 31754 343 0 0
sp_qd_handle_on_reserve_upd_sts 9668 9668 0 0.00 0 23708 51 0 0
sp_reserve_write_off 9671 9671 0 0.00 6 23735 199 0 0
sp_supplier_invoice 5437 5437 0 0.00 6 103215 3390 0 0
sp_supplier_order 5267 5267 0 0.00 6 53336 1500 0 0
srv_aggregate_perf_data 100 100 0 0.00 2723 210230 20152 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1712 197724 52243 0 0
srv_find_qd_qs_mism 1032046 1032046 0 0.00 0 16800 6 0 0
srv_make_invnt_saldo 477 477 0 0.00 33 88943 2985 0 0
srv_make_money_saldo 705 705 0 0.00 1 19392 461 0 0
srv_recalc_idx_stat 4 4 0 0.00 181426 395104 306903 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4978 4978 0 0.00 0 13666 156 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 1 3695 88 0 0
t$perf-norm:sp_cancel_client_order 1720 1720 0 0.00 0 2894 20 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 5251 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 4324 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 434 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 1029 14 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 27787 1998 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 3453 11 0 0
t$perf-norm:sp_client_order 19584 19584 0 0.00 0 16845 19 0 0
t$perf-norm:sp_customer_reserve 970 970 0 0.00 0 1229 7 0 0
t$perf-norm:sp_pay_from_customer 6858 6858 0 0.00 0 5042 6 0 0
t$perf-norm:sp_pay_to_supplier 5824 5824 0 0.00 0 22550 21 0 0
t$perf-norm:sp_reserve_write_off 9671 9671 0 0.00 0 21353 16 0 0
t$perf-norm:sp_supplier_invoice 5437 5437 0 0.00 0 4073 14 0 0
t$perf-norm:sp_supplier_order 5267 5267 0 0.00 0 21072 25 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 152 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 1274 36 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 370 5 0 0
t$perf-norm:srv_make_money_saldo 705 705 0 0.00 0 778 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 46 13 0 0
v_add_invoice_to_stock 10180 9906 274 2.69 0 8275 17 0 274
v_all_customers 36192 36192 0 0.00 0 19 1 0 0
v_all_suppliers 29906 29906 0 0.00 0 14 1 0 0
v_all_wares 39156 39156 0 0.00 0 90 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 1212 7 0 0
v_cancel_client_order 3440 3440 0 0.00 0 3803 4 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 1223 5 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 6387 9 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 4468 15 0 0
v_cancel_supplier_invoice 804 792 12 1.49 0 1378 15 0 12
v_cancel_supplier_order 780 780 0 0.00 0 8129 21 0 0
v_cancel_write_off 1984 1984 0 0.00 0 6404 18 0 0
v_max_id_clo_ord 5262 5262 0 0.00 0 161 1 0 0
v_max_id_clo_res 970 970 0 0.00 0 3342 30 0 0
v_max_id_ord_sup 5431 5431 0 0.00 0 9066 6 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 6377 8 0 0
v_max_non_paid_realizn 3518 3518 0 0.00 0 3934 4 0 0
v_min_id_clo_ord 5262 5262 0 0.00 0 3565 9 0 0
v_min_id_clo_res 970 970 0 0.00 0 6887 14 0 0
v_min_id_ord_sup 5431 5431 0 0.00 0 6136 5 0 0
v_min_non_paid_invoice 5821 5821 0 0.00 0 8997 34 0 0
v_min_non_paid_realizn 6856 6856 0 0.00 0 8353 20 0 0
v_our_firm 9638 9638 0 0.00 0 15 1 0 0
v_reserve_write_off 19334 19334 0 0.00 0 8194 6 0 0
x_lock_depdocs_on_canc_invoice 11057 11057 0 0.00 0 16118 123 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 10530 358 0 0
x_make_qstorno_1000_1200 5267 5267 0 0.00 2 53325 1409 0 0
x_make_qstorno_1200_2000 5437 5437 0 0.00 3 103158 3214 0 0
x_make_qstorno_2100_3300 459808 459808 0 0.00 0 102285 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-08-2021 04:39:28 to 23-08-2021 04:39:32.

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
02:07:10 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:12 33665 8192000000 9716944096 10012536832 794249584 8.838 100 86 172 1 822990432 7563232 21177616
02:09:56 17768 8192000000 9749072080 10056507392 829616368 9.195 100 87 174 0 862472096 11895536 20960192
02:11:16 17007 8192000000 9811691760 10127425536 877485344 9.675 100 84 165 1 915412480 19158560 18768576
02:12:35 9477 8192000000 9880303760 10217562112 926843296 10.164 100 79 158 0 990183504 45871792 17468416
02:13:50 8994 8192000000 9927906560 10278023168 966738256 10.555 100 77 154 1 1040814944 57737424 16339264
02:15:01 4430 8192000000 9984887024 10340978688 998464880 10.864 100 87 172 0 1101616832 85628032 17523920
02:16:08 3486 8192000000 10000515824 10366980096 1021161776 11.083 100 77 152 1 1117141776 80890832 15089168
02:17:14 2228 8192000000 10038108080 10405023744 1053644864 11.396 100 90 177 1 1146380784 74149936 18585984
02:18:19 1448 8192000000 10057351536 10427559936 1067853984 11.532 100 98 196 0 1165772128 77858096 20060048
02:19:21 36650 8192000000 9998925664 10380636160 1080185312 11.649 100 47 93 0 1119888976 28594704 11108960
02:20:59 7777 8192000000 9999348080 10399854592 1082669472 11.673 100 35 69 1 1121967280 31849072 7448736
02:22:08 8777 8192000000 10019029120 10423513088 1088729536 11.731 100 47 94 0 1125640032 26973200 9937296
02:23:18 10994 8192000000 9996607168 10392993792 1093458272 11.776 100 26 48 1 1116322032 17267216 5596544
02:24:33 51277 8192000000 10022343808 10410504192 1097504768 11.814 100 62 123 1 1122066992 11298432 13263792
02:26:25 5417 8192000000 10038518368 10439139328 1095808736 11.798 100 35 69 1 1128650432 25630784 7210912
02:27:31 9659 8192000000 9994186560 10396831744 1084667072 11.692 100 24 48 1 1106877312 17340528 4869712
02:28:43 10371 8192000000 9957843600 10363437056 1027554336 11.145 97 15 30 0 1051576992 20559600 3463056
02:30:01 58417 8192000000 9942818688 10341167104 939713168 10.290 91 26 52 2 970654544 24976240 5965136
02:32:00 7535 8192000000 9922930880 10309980160 977133120 10.656 96 36 72 1 1003111040 17928240 8049680
02:33:10 10235 8192000000 9941417296 10336997376 1029462416 11.163 100 41 80 1 1054518000 15704384 9351200
02:34:21 6308 8192000000 9975011456 10362425344 1047750896 11.339 100 35 69 1 1074294992 19073328 7470768
02:35:40 35296 8192000000 9979807360 10384265216 1043099472 11.294 99 24 48 0 1077711072 29703600 4908000
02:37:18 28303 8192000000 9998437040 10389065728 1065358528 11.508 100 59 117 0 1102275840 24306224 12611088
02:38:51 22394 8192000000 10044140400 10437574656 1077776864 11.626 100 55 111 2 1148202176 58797072 11628240
02:40:16 22175 8192000000 10063158560 10461310976 1090828064 11.751 100 45 88 1 1178069120 77655504 9585552
02:41:43 39312 8192000000 10131475264 10563760128 1112900400 11.960 100 48 94 0 1237776864 114314768 10561696
02:43:34 16693 8192000000 10144722896 10557997056 1126136432 12.085 100 36 72 1 1252540544 118342624 8061488
02:45:03 12298 8192000000 10222238144 10625277952 1133562736 12.155 100 65 129 0 1329048912 181259152 14227024
02:46:27 3381 8192000000 10234253280 10647896064 1153122960 12.339 100 63 122 0 1340934176 174111888 13699328
02:47:37 11179 8192000000 10292787568 10716454912 1163009760 12.431 100 67 134 0 1393575120 215233552 15331808
02:48:54 2483 8192000000 10308545184 10720792576 1175638336 12.549 100 83 167 0 1407321840 213671984 18011520
02:49:59 14101 8192000000 10293402256 10711425024 1157362320 12.379 99 40 80 1 1392378448 225233040 9783088
02:51:17 1707 8192000000 10346957248 10750414848 1166030944 12.460 100 86 172 1 1445415936 260264608 19120384
02:52:21 5260 8192000000 10333938128 10746449920 1150029728 12.310 99 60 120 0 1428663152 264732032 13901392
02:53:32 9348 8192000000 10368891904 10821292032 1157310896 12.378 99 48 96 0 1495867344 326882912 11673536
02:54:48 4721 8192000000 10408146464 10827345920 1167376288 12.472 100 61 121 1 1512769568 330596064 14797216
02:55:59 7170 8192000000 10425548304 10861301760 1158662368 12.391 100 45 90 1 1541167664 370920368 11584928
02:57:18 26126 8192000000 10381547328 10836869120 1166056064 12.460 100 35 70 2 1481128688 306320320 8752304
02:58:59 15434 8192000000 10368221408 10828791808 1134840976 12.167 98 35 70 0 1469947328 326368048 8738304
03:00:35 20715 8192000000 10365927488 10832171008 1122262176 12.048 99 25 50 0 1455872464 327024336 6585952
03:02:02 25350 8192000000 10252646800 10728464384 1082429888 11.671 98 31 60 0 1394639488 304570448 7639152
03:03:29 34954 8192000000 10115275760 10577989632 1104526256 11.881 100 16 32 0 1220932560 112585152 3821152
03:05:05 197722 8192000000 10080428304 10518245376 1108869264 11.922 100 36 70 4 1186213344 69821696 7522384
03:09:30 75595 8192000000 10124597264 10569908224 1107071744 11.905 98 21 40 1 1200637584 88924512 4641328
03:11:52 117300 8192000000 10032738528 10494537728 1102730928 11.864 99 15 30 0 1150478080 44482896 3264256
03:14:55 88277 8192000000 10046103488 10486751232 1058201520 11.439 94 18 36 0 1118590576 56822800 3566256
03:17:24 35855 8192000000 10071274144 10505297920 1112218240 11.953 99 22 43 0 1175691424 58694800 4778384
03:19:08 67400 8192000000 10062173568 10528526336 1128293152 12.105 100 15 29 1 1164227280 32848928 3085200
03:21:24 60208 8192000000 10057193312 10560724992 1130898112 12.130 100 4 8 0 1161865040 30571968 394960
03:23:29 74542 8192000000 10072176480 10576715776 1118224496 12.010 98 16 32 0 1175387568 53921520 3241552
03:25:45 22673 8192000000 10096294560 10523508736 1122742688 12.053 98 48 96 1 1171947520 39382896 9821936
03:27:08 78287 8192000000 10091861632 10546589696 1111430144 11.946 97 17 34 2 1155546640 40638256 3478240
03:29:42 77251 8192000000 10110001952 10610778112 1138746144 12.204 100 12 24 0 1257973296 116569936 2657216
03:32:06 96964 8192000000 10080515184 10590224384 1080820928 11.655 94 11 22 0 1137192112 53841504 2529680
03:34:55 165919 8192000000 10111531680 10548359168 1131653168 12.137 99 21 42 1 1165767504 28601360 5512976
03:38:52 95619 8192000000 10108135648 10565644288 1130234672 12.124 98 14 28 0 1193630544 60293616 3102256
03:41:28 94535 8192000000 10082110448 10532761600 1127915856 12.102 98 9 18 0 1160334960 30229408 2189696
03:44:03 82207 8192000000 10058059072 10564448256 1123274832 12.058 98 6 12 0 1165698384 41136400 1287152
03:46:26 52812 8192000000 10089571968 10541723648 1111649056 11.948 97 16 32 1 1165093088 50218352 3225680
03:48:19 45527 8192000000 10065292944 10494222336 1086129856 11.706 96 24 48 1 1158085968 66847648 5108464
03:50:05 79394 8192000000 10123343488 10656686080 1132199536 12.142 99 32 63 1 1233580560 95481136 5899888
03:52:29 96496 8192000000 10069902512 10521600000 1063009776 11.485 94 21 41 1 1102912144 35972896 3929472
03:55:06 75335 8192000000 10061380656 10527191040 1087763152 11.721 97 17 34 0 1153907024 62284688 3859184
03:57:21 82621 8192000000 10051183728 10510299136 1055360976 11.412 94 12 24 1 1104990672 46809488 2820208
04:00:35 103234 8192000000 10039427040 10511872000 1045536256 11.318 94 27 52 4 1103469664 52544768 5388640
04:03:26 61939 8192000000 10041312944 10486493184 1106317936 11.898 100 18 36 1 1131996704 22044176 3634592
04:05:37 43908 8192000000 10109840240 10571509760 1127953120 12.102 100 13 26 0 1198848528 67889440 3005968
04:08:05 61047 8192000000 10159174768 10604089344 1109111232 11.924 98 18 36 1 1229230992 115620912 4498848
04:10:13 43102 8192000000 10122652368 10571767808 1099347248 11.831 99 31 62 0 1220870432 113763712 7759472
04:12:03 63012 8192000000 10048229872 10497363968 1095444736 11.794 98 19 38 1 1140224224 40930752 3848736
04:14:06 33652 8192000000 10097825776 10536685568 1115167184 11.981 99 26 52 0 1207587744 86659712 5760848
04:15:44 9722 8192000000 10066369520 10496749568 1120410096 12.031 100 41 80 1 1155486336 27037280 8038960
04:16:55 47768 8192000000 10129450528 10596081664 1144508624 12.258 100 34 67 5 1217641840 66168272 6964944
04:18:46 32576 8192000000 10075204736 10513244160 1090131904 11.744 95 34 68 1 1123009920 26341904 6536112
04:20:22 66449 8192000000 10087938480 10534998016 1123218832 12.057 99 31 63 0 1178485328 48668048 6598448
04:22:30 53261 8192000000 10054592816 10516414464 1113068256 11.961 98 10 20 1 1153040448 37950048 2022144
04:24:26 72880 8192000000 10072122048 10523512832 1121952464 12.045 98 13 26 0 1155293952 30458768 2882720
04:26:40 47634 8192000000 10106587296 10565906432 1141035376 12.225 99 17 34 0 1179916048 35249264 3631408
04:28:28 46454 8192000000 10135491232 10605142016 1159608912 12.400 100 14 28 0 1245308608 82613104 3086592
04:30:21 42749 8192000000 10148824688 10636390400 1152337248 12.331 99 23 46 0 1254788592 97355248 5096096
04:32:08 80144 8192000000 10154767152 10616971264 1145728032 12.269 99 25 50 0 1239722192 88549952 5444208
04:34:43 63221 8192000000 10099298176 10577711104 1141418304 12.229 99 15 30 1 1204285776 59456464 3411008
04:36:49 28343 8192000000 9442831760 9802502144 549714272 6.288 48 20 40 0 608294176 54561808 4018096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 23-08-2021 04:39:34 to 23-08-2021 04:39:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-08-2021 04:39:49 to 23-08-2021 04:39:51.

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          231641
MON$OLDEST_ACTIVE               231642
MON$OLDEST_SNAPSHOT             231642
MON$NEXT_TRANSACTION            231642
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                       2027936
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2571 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2571 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2571 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 ---
:133: 
:134: mscs-test-161.budzdorov.ru    Mon Aug 23 04:37:20 2021
:135:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:136: 
--- end of diff output ---

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

23.08.21 04:39:52. 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-08-23 04:39:55 No severe PSQL-related problems occured
23.08.21 04:39:56. End of report.