Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.12.2021 07:48
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
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 16627604 kB
MemAvailable 31425452 kB
Buffers 0 kB
Cached 15028824 kB
SwapCached 0 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-12-14 07:47:16
DTS_END                         2021-12-14 07:47:16.0400
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57340
TRN_ID                          230452
ATT_ID                          662
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: 8932.79 22495 1071935
customer order: creation 165.77 454 19892
customer order: refuse 13.58 320 1630
order to supplier: creation 44.78 1359 5373
order to supplier: removal 3.36 110292 403
invoice (draft): creation 43.39 3351 5207
invoice (draft): removal 50.93 901 6111
invoice accept: apply 39.23 20042 4707
invoice accept: cancel 46.28 816 5553
customer reserve: creation 3736.88 192 448426
customer reserve: removal 4497.38 65 539686
realization accept: apply 78.66 203 9439
realization accept: cancel 87.88 116 10545
payment from customer: creation 57.17 137 6860
payment from customer: removal 5.64 81 677
payment to supplier: creation 47.78 132 5734
payment to supplier: removal 3.26 82 391
service: total inventory turnovers 4.05 3247 486
service: total monetary turnovers 5.93 481 711
service: aggregate perf. data 0.83 17949 100
service: refresh index statistics 0.03 289684 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-12-2021 07:48:27 to 14-12-2021 07:48:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3253
TEST_TIME 2 4368
TEST_TIME 3 3037
TEST_TIME 4 3420
TEST_TIME 5 3709
TEST_TIME 6 3800
TEST_TIME 7 4336
TEST_TIME 8 4295
TEST_TIME 9 4773
TEST_TIME 10 5122
TEST_TIME 11 5396
TEST_TIME 12 5821
TEST_TIME 13 5890
TEST_TIME 14 6279
TEST_TIME 15 6683
TEST_TIME 16 7543
TEST_TIME 17 7498
TEST_TIME 18 7840
TEST_TIME 19 8105
TEST_TIME 20 8083
TEST_TIME 21 8519
TEST_TIME 22 8394
TEST_TIME 23 9153
TEST_TIME 24 9453
TEST_TIME 25 9796
TEST_TIME 26 10270
TEST_TIME 27 11036
TEST_TIME 28 10859
TEST_TIME 29 10595
TEST_TIME 30 10409
TEST_TIME 31 10195
TEST_TIME 32 10150
TEST_TIME 33 10040
TEST_TIME 34 10045
TEST_TIME 35 10060
TEST_TIME 36 9909
TEST_TIME 37 9772
TEST_TIME 38 9672
TEST_TIME 39 9531
TEST_TIME 40 9600
TEST_TIME 41 9528
TEST_TIME 42 9601
TEST_TIME 43 9746
TEST_TIME 44 9690
TEST_TIME 45 9630
TEST_TIME 46 9775
TEST_TIME 47 9800
TEST_TIME 48 9748
TEST_TIME 49 9735
TEST_TIME 50 9835
TEST_TIME 51 9824
TEST_TIME 52 9677
TEST_TIME 53 9595
TEST_TIME 54 9512
TEST_TIME 55 9494
TEST_TIME 56 9410
TEST_TIME 57 9363
TEST_TIME 58 9294
TEST_TIME 59 9251
TEST_TIME 60 9356
TEST_TIME 61 9339
TEST_TIME 62 9459
TEST_TIME 63 9425
TEST_TIME 64 9378
TEST_TIME 65 9481
TEST_TIME 66 9576
TEST_TIME 67 9514
TEST_TIME 68 9579
TEST_TIME 69 9571
TEST_TIME 70 9558
TEST_TIME 71 9617
TEST_TIME 72 9642
TEST_TIME 73 9597
TEST_TIME 74 9497
TEST_TIME 75 9469
TEST_TIME 76 9442
TEST_TIME 77 9431
TEST_TIME 78 9382
TEST_TIME 79 9313
TEST_TIME 80 9240
TEST_TIME 81 9246
TEST_TIME 82 9230
TEST_TIME 83 9246
TEST_TIME 84 9213
TEST_TIME 85 9267
TEST_TIME 86 9257
TEST_TIME 87 9238
TEST_TIME 88 9261
TEST_TIME 89 9284
TEST_TIME 90 9335
TEST_TIME 91 9376
TEST_TIME 92 9398
TEST_TIME 93 9365
TEST_TIME 94 9294
TEST_TIME 95 9244
TEST_TIME 96 9227
TEST_TIME 97 9211
TEST_TIME 98 9163
TEST_TIME 99 9129
TEST_TIME 100 9115
TEST_TIME 101 9085
TEST_TIME 102 9133
TEST_TIME 103 9148
TEST_TIME 104 9107
TEST_TIME 105 9100
TEST_TIME 106 9136
TEST_TIME 107 9130
TEST_TIME 108 9223
TEST_TIME 109 9222
TEST_TIME 110 9212
TEST_TIME 111 9244
TEST_TIME 112 9191
TEST_TIME 113 9152
TEST_TIME 114 9131
TEST_TIME 115 9073
TEST_TIME 116 9033
TEST_TIME 117 9015
TEST_TIME 118 9021
TEST_TIME 119 9001
TEST_TIME 120 8934

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-12-2021 07:48:31 to 14-12-2021 07:48:34.

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 1486895 1486895 0 0.00 0 35211 7 0 0
doc_list_biud 1486894 1486894 0 0.00 0 33636 30 0 0
sp_add_invoice_to_stock 4829 4707 122 2.52 19 220486 20042 0 122
sp_cancel_adding_invoice 5553 5553 0 0.00 5 121891 817 0 0
sp_cancel_client_order 1630 1630 0 0.00 4 12026 320 0 0
sp_cancel_customer_reserve 539686 539686 0 0.00 1 32238 66 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 4974 81 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 2820 82 0 0
sp_cancel_supplier_invoice 6120 6111 9 0.14 2 19395 901 0 9
sp_cancel_supplier_order 403 403 0 0.00 10 888977 110292 0 0
sp_cancel_write_off 10545 10545 0 0.00 1 10175 117 0 0
sp_client_order 19892 19892 0 0.00 4 22980 455 0 0
sp_customer_reserve 448426 448426 0 0.00 0 93704 193 0 0
sp_fill_shopping_cart 30467 30467 0 0.00 1 15170 40 0 0
sp_fill_shopping_cart_clo_res 448423 448423 0 0.00 0 19221 19 0 0
sp_get_clo_for_invoice 4704 4704 0 0.00 0 39802 439 0 0
sp_kill_cost_storno 17166 17166 0 0.00 0 23975 59 0 0
sp_kill_qstorno_ret_qs2qd 1096431 1096431 0 0.00 0 23781 36 0 0
sp_kill_qty_storno 578071 578071 0 0.00 0 33631 76 0 0
sp_lock_selected_doc 19613 19613 0 0.00 0 7377 6 0 0
sp_make_cost_storno 26737 26737 0 0.00 0 20502 59 0 0
sp_multiply_rows_for_pdistr 26737 26737 0 0.00 0 8124 32 0 0
sp_multiply_rows_for_qdistr 19892 19892 0 0.00 0 22964 244 0 0
sp_pay_from_customer 6860 6860 0 0.00 4 16126 138 0 0
sp_pay_to_supplier 5734 5734 0 0.00 4 20507 133 0 0
sp_payment_common 12593 12593 0 0.00 1 12053 31 0 0
sp_qd_handle_on_cancel_clo 1630 1630 0 0.00 0 12012 293 0 0
sp_qd_handle_on_invoice_upd_sts 10257 10257 0 0.00 0 33625 313 0 0
sp_qd_handle_on_reserve_upd_sts 9439 9439 0 0.00 0 13583 56 0 0
sp_reserve_write_off 9439 9439 0 0.00 7 17382 203 0 0
sp_supplier_invoice 5207 5207 0 0.00 6 73071 3352 0 0
sp_supplier_order 5373 5373 0 0.00 5 75842 1359 0 0
srv_aggregate_perf_data 100 100 0 0.00 2606 212253 17949 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1750 145711 47342 0 0
srv_find_qd_qs_mism 1025654 1025654 0 0.00 0 35181 6 0 0
srv_make_invnt_saldo 486 486 0 0.00 51 92149 3247 0 0
srv_make_money_saldo 711 711 0 0.00 1 15921 481 0 0
srv_recalc_idx_stat 4 4 0 0.00 163228 370311 289685 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4707 4707 0 0.00 0 19500 148 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 6294 125 0 0
t$perf-norm:sp_cancel_client_order 1630 1630 0 0.00 0 3404 10 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 1117 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 2590 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 335 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 7043 32 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 17647 1293 0 0
t$perf-norm:sp_cancel_write_off 919 919 0 0.00 0 4018 14 0 0
t$perf-norm:sp_client_order 19892 19892 0 0.00 0 15428 19 0 0
t$perf-norm:sp_customer_reserve 937 937 0 0.00 0 4871 9 0 0
t$perf-norm:sp_pay_from_customer 6860 6860 0 0.00 0 4224 6 0 0
t$perf-norm:sp_pay_to_supplier 5734 5734 0 0.00 0 7208 11 0 0
t$perf-norm:sp_reserve_write_off 9439 9439 0 0.00 0 6811 10 0 0
t$perf-norm:sp_supplier_invoice 5207 5207 0 0.00 0 10826 20 0 0
t$perf-norm:sp_supplier_order 5373 5373 0 0.00 0 6134 15 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 2303 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 1561 7 0 0
t$perf-norm:srv_make_money_saldo 711 711 0 0.00 0 211 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 9652 9408 244 2.52 0 3791 19 0 244
v_all_customers 36294 36294 0 0.00 0 17 1 0 0
v_all_suppliers 29336 29336 0 0.00 0 100 1 0 0
v_all_wares 39784 39784 0 0.00 0 133 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 1327 8 0 0
v_cancel_client_order 3260 3260 0 0.00 0 1738 4 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 3043 9 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 1659 5 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 2088 9 0 0
v_cancel_supplier_invoice 826 808 18 2.17 0 5499 23 0 18
v_cancel_supplier_order 806 806 0 0.00 0 2303 8 0 0
v_cancel_write_off 1838 1838 0 0.00 0 4177 15 0 0
v_max_id_clo_ord 5373 5373 0 0.00 0 1318 1 0 0
v_max_id_clo_res 937 937 0 0.00 0 6325 19 0 0
v_max_id_ord_sup 5202 5202 0 0.00 0 6502 6 0 0
v_max_non_paid_invoice 1641 1641 0 0.00 0 318 2 0 0
v_max_non_paid_realizn 3573 3573 0 0.00 0 6899 5 0 0
v_min_id_clo_ord 5373 5373 0 0.00 0 2194 6 0 0
v_min_id_clo_res 937 937 0 0.00 0 6926 17 0 0
v_min_id_ord_sup 5202 5202 0 0.00 0 5743 7 0 0
v_min_non_paid_invoice 5734 5734 0 0.00 0 12043 22 0 0
v_min_non_paid_realizn 6859 6859 0 0.00 0 12044 24 0 0
v_our_firm 10062 10062 0 0.00 0 12 1 0 0
v_reserve_write_off 18878 18878 0 0.00 0 4242 7 0 0
x_lock_depdocs_on_canc_invoice 11260 11260 0 0.00 0 14499 115 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 7978 286 0 0
x_make_qstorno_1000_1200 5373 5373 0 0.00 1 75830 1282 0 0
x_make_qstorno_1200_2000 5207 5207 0 0.00 3 73066 3205 0 0
x_make_qstorno_2100_3300 448426 448426 0 0.00 0 93703 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-12-2021 07:48:35 to 14-12-2021 07:48:39.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:17:19 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:20 22355 8192000000 9723717072 10018684928 798029008 8.876 100 93 186 0 828713136 8040704 22643424
05:19:48 19377 8192000000 9761182592 10068250624 834573888 9.245 100 92 184 0 869705808 12937568 22194352
05:21:20 18594 8192000000 9805259264 10121515008 860614800 9.506 100 83 164 1 905287344 25377664 19294880
05:22:49 12397 8192000000 9865788304 10195156992 905142144 9.949 100 84 167 0 974809872 51429008 18238720
05:24:05 6900 8192000000 9901821088 10245607424 952242832 10.413 100 76 150 0 1019187584 51131472 15813280
05:25:14 6312 8192000000 9962586624 10318671872 992766048 10.808 100 86 171 0 1081748400 71488400 17493952
05:26:23 1944 8192000000 10004060224 10367332352 1024642288 11.117 100 80 159 0 1115894240 75181776 16070176
05:27:27 2724 8192000000 10022210912 10388570112 1052463904 11.384 100 92 185 0 1136314288 65140240 18710144
05:28:30 1443 8192000000 10037800192 10415255552 1067529168 11.528 100 92 183 1 1158675936 72594560 18552208
05:29:32 6655 8192000000 9999298768 10390593536 1072025408 11.571 100 44 88 1 1102326544 21476704 8824432
05:30:43 4250 8192000000 9998986288 10396696576 1079496800 11.643 100 50 98 2 1115179136 24344624 11337712
05:31:52 3008 8192000000 9999711696 10402086912 1083496880 11.681 100 35 70 0 1117347360 26457984 7392496
05:33:00 64280 8192000000 10009241136 10424709120 1088268928 11.726 100 26 51 1 1123074736 28935520 5870288
05:35:10 8130 8192000000 10017209680 10416226304 1092614944 11.768 100 34 68 1 1127141120 26998064 7528112
05:36:21 7557 8192000000 10002061376 10408521728 1069481168 11.547 98 37 74 2 1093411472 15568368 8361936
05:37:31 4608 8192000000 9982779824 10383683584 1071516832 11.567 99 28 56 3 1099348768 21796528 6035408
05:38:36 60766 8192000000 9955645552 10357719040 966265776 10.550 90 30 60 1 996678432 23065840 7346816
05:40:42 6780 8192000000 9937709664 10324168704 1008470784 10.961 99 28 56 0 1043681936 29482240 5728912
05:41:50 6347 8192000000 9933147056 10308620288 980066960 10.685 97 41 82 1 1008926128 20053472 8805696
05:42:57 10530 8192000000 9943861216 10333847552 1026693344 11.137 100 43 86 1 1054593936 18172976 9727616
05:44:11 36588 8192000000 9953123952 10356514816 1042829168 11.292 100 30 58 2 1058153968 8616256 6708544
05:45:48 7351 8192000000 9983265904 10363097088 1052381392 11.384 100 57 115 1 1084282016 19362112 12538512
05:46:56 6200 8192000000 9981856304 10367762432 1065321792 11.507 100 37 74 3 1079959296 6250080 8387424
05:48:07 59881 8192000000 10066707472 10459717632 1101012960 11.847 100 47 94 1 1150815008 39748256 10053792
05:50:11 95499 8192000000 10121200272 10538541056 1121194800 12.038 100 41 82 0 1210053440 80170000 8688640
05:52:55 29551 8192000000 10151899680 10561777664 1133771968 12.157 100 35 68 3 1253893536 112601616 7519952
05:54:29 7464 8192000000 10183940128 10593804288 1149722320 12.307 100 46 92 0 1299950816 139843744 10384752
05:55:42 14860 8192000000 10229789184 10638917632 1159449232 12.398 100 58 115 0 1328421088 156269120 12702736
05:57:02 7201 8192000000 10232762944 10654035968 1157445088 12.379 99 60 119 0 1332099152 161811616 12842448
05:58:15 1904 8192000000 10304427584 10710073344 1178383536 12.575 100 72 143 0 1401473584 207735312 15354736
05:59:22 6447 8192000000 10320134352 10744094720 1184822416 12.635 100 71 139 2 1411182368 210685360 15674592
06:00:35 2642 8192000000 10339718880 10749100032 1167189392 12.471 99 82 162 0 1438962736 253435392 18337952
06:01:43 3058 8192000000 10369889968 10800074752 1183009920 12.618 100 67 134 0 1471197520 272432912 15754688
06:02:51 1749 8192000000 10418007264 10813399040 1182881600 12.617 100 90 180 0 1512220272 307286864 22051808
06:03:58 3609 8192000000 10333327696 10743336960 1176384768 12.556 100 57 114 1 1433428192 243509728 13533696
06:05:05 12611 8192000000 10391593472 10808283136 1146962272 12.281 98 45 90 2 1487575824 329218384 11395168
06:06:19 8266 8192000000 10386430320 10805989376 1137887984 12.196 98 43 86 1 1477868160 329351920 10628256
06:07:35 5968 8192000000 10425927472 10845597696 1164661360 12.447 100 49 96 1 1522150336 345567120 11921856
06:08:50 27251 8192000000 10401436192 10831872000 1148839584 12.299 100 25 50 1 1487911264 332196528 6875152
06:10:23 18867 8192000000 10364440944 10835021824 1156764672 12.373 100 25 50 1 1487861136 324288400 6808064
06:11:52 31326 8192000000 10316039904 10794078208 1140880192 12.224 99 29 58 2 1428472400 280695104 6897104
06:13:26 28224 8192000000 10173326224 10653777920 1132484272 12.145 100 25 50 0 1331638768 193239632 5914864
06:14:55 59284 8192000000 10048494752 10487877632 1076685296 11.616 96 33 66 1 1116909408 32319632 7904480
06:17:42 62052 8192000000 10058099616 10473275392 1084048960 11.686 96 45 85 1 1119081984 26537904 8495120
06:19:47 35467 8192000000 10104081664 10533482496 1119943584 12.026 99 41 81 2 1176094720 48275744 7875392
06:21:31 43603 8192000000 10049577568 10544750592 1103034688 11.866 98 11 22 1 1162725760 57426992 2264080
06:23:22 97039 8192000000 10068946816 10514759680 1116876336 11.997 99 17 34 0 1147142864 26655232 3611296
06:26:02 77459 8192000000 10115567776 10573721600 1119685408 12.024 98 12 24 0 1187738656 65600624 2452624
06:28:22 61831 8192000000 10098562656 10587070464 1120888928 12.035 98 28 56 0 1200799232 74535472 5374832
06:30:29 78133 8192000000 10115800640 10562875392 1132015616 12.140 99 21 42 0 1202635696 66051104 4568976
06:33:04 96207 8192000000 10085053472 10547920896 1148755936 12.298 100 15 30 2 1238437648 86202640 3479072
06:35:44 27288 8192000000 10126572688 10563780608 1160032608 12.404 100 36 72 1 1239697568 72204736 7460224
06:37:17 145711 8192000000 10082041840 10546716672 1110608352 11.938 96 11 22 0 1158548464 45734768 2205344
06:40:53 55573 8192000000 10110017488 10550263808 1141036608 12.225 99 46 92 0 1191075648 40736208 9302832
06:42:51 46275 8192000000 10117646064 10564673536 1156869056 12.374 100 14 28 0 1225046832 65609424 2568352
06:44:38 53154 8192000000 10166701008 10640248832 1135364320 12.172 98 13 26 0 1237819520 99412224 3042976
06:46:46 68672 8192000000 10125686928 10629320704 1146304112 12.275 99 12 24 1 1284105104 135247344 2553648
06:48:59 66171 8192000000 10054071952 10508845056 1089091760 11.734 95 10 20 0 1154218048 63342368 1783920
06:51:05 44280 8192000000 10089465008 10522288128 1076373920 11.613 95 23 46 0 1141761456 60770128 4617408
06:53:02 56466 8192000000 10048040048 10546089984 1077984688 11.628 96 17 34 0 1157764256 76276288 3503280
06:55:02 59123 8192000000 10089264528 10547499008 1111142096 11.943 98 27 54 1 1169768816 53017424 5609296
06:57:05 20114 8192000000 10116000688 10542841856 1107640160 11.910 99 48 96 0 1181790464 64646320 9503984
06:58:30 52401 8192000000 10069714432 10509078528 1089318448 11.736 98 20 40 0 1141150192 47386624 4445120
07:00:53 93112 8192000000 10084512608 10507145216 1103016240 11.866 99 36 73 0 1171922656 61016288 7890128
07:03:32 11486 8192000000 10052939712 10493083648 1105716864 11.892 99 46 92 0 1165068816 49333856 10018096
07:04:45 42168 8192000000 10049483648 10515021824 1118534384 12.013 100 7 14 0 1156931856 37109488 1287984
07:06:27 82677 8192000000 10111691104 10573123584 1109741680 11.930 99 11 22 0 1168894656 56720480 2432496
07:10:38 36486 8192000000 10060327408 10510778368 1103554512 11.871 98 31 61 3 1154793776 45203728 6035536
07:12:36 53706 8192000000 10052006640 10483355648 1095599312 11.796 98 27 51 1 1148957376 47613536 5744528
07:14:30 62728 8192000000 10122161264 10572009472 1116572896 11.995 99 29 58 0 1197677120 75048304 6055920
07:16:38 69725 8192000000 10088191888 10585018368 1090196000 11.745 97 11 20 0 1153112992 61223056 1693936
07:18:48 25563 8192000000 10092880768 10510991360 1131582224 12.136 100 45 90 1 1171906544 30914672 9409648
07:20:14 38426 8192000000 10085350368 10519584768 1123186432 12.057 99 35 70 0 1169763216 39576768 7000016
07:22:02 83768 8192000000 10073449792 10553733120 1143879808 12.252 100 25 50 1 1211865632 62774768 5211056
07:26:36 65513 8192000000 10106744944 10568073216 1146753456 12.279 100 19 38 0 1194552720 43946576 3852688
07:28:50 85830 8192000000 10123049504 10610413568 1140966016 12.225 99 20 40 0 1219533968 74348784 4219168
07:31:25 55010 8192000000 10142620768 10581000192 1133523680 12.155 98 25 50 0 1198574048 60277088 4773280
07:33:27 68282 8192000000 10094660768 10560757760 1095033904 11.790 96 16 32 2 1155605696 57346016 3225776
07:35:35 74664 8192000000 10083551680 10566557696 1128348096 12.106 99 15 30 2 1204534256 73297136 2889024
07:37:50 43216 8192000000 10067510192 10509434880 1096958528 11.809 96 13 26 0 1142892320 43547056 2386736
07:39:38 65250 8192000000 10067891232 10520801280 1082120576 11.668 96 35 70 1 1148413760 59092688 7200496
07:41:46 43223 8192000000 10049731152 10512588800 1123256928 12.058 100 7 14 0 1155957776 31431408 1269440
07:43:29 76011 8192000000 10098547904 10574974976 1133384032 12.153 100 12 24 1 1189959328 54051184 2524112
07:46:43 38271 8192000000 8960932528 9163362304 130840080 1.572 12 4 6 1 141157888 9536080 781728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 14-12-2021 07:48:41 to 14-12-2021 07:48:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-12-2021 07:48:56 to 14-12-2021 07:48:58.

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          230748
MON$OLDEST_ACTIVE               230749
MON$OLDEST_SNAPSHOT             230749
MON$NEXT_TRANSACTION            230749
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                       2032992
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2687 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2687 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2687 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2687 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Dec 14 07:47:25 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

14.12.21 07:48:59. 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-12-14 07:49:01 No severe PSQL-related problems occured
14.12.21 07:49:03. End of report.