Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.10.2021 07: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 15909272 kB
MemAvailable 30677500 kB
Buffers 0 kB
Cached 15829492 kB
SwapCached 3716 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-20 07:37:07
DTS_END                         2021-10-20 07:37:07.5820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48578
TRN_ID                          237069
ATT_ID                          640
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: 9014.99 20841 1081799
customer order: creation 174.29 462 20915
customer order: refuse 14.85 335 1782
order to supplier: creation 45.74 1470 5489
order to supplier: removal 3.11 117623 373
invoice (draft): creation 45.83 3544 5500
invoice (draft): removal 49.88 960 5986
invoice accept: apply 41.81 19004 5017
invoice accept: cancel 44.97 965 5396
customer reserve: creation 3850.72 189 462086
customer reserve: removal 4452.74 65 534329
realization accept: apply 83.95 189 10074
realization accept: cancel 76.60 125 9192
payment from customer: creation 60.68 131 7281
payment from customer: removal 5.78 114 693
payment to supplier: creation 49.54 148 5945
payment to supplier: removal 3.31 84 397
service: total inventory turnovers 4.13 3175 495
service: total monetary turnovers 6.22 580 746
service: aggregate perf. data 0.83 21536 99
service: refresh index statistics 0.03 246120 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-10-2021 07:39:02 to 20-10-2021 07:39:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7875
TEST_TIME 2 7169
TEST_TIME 3 5842
TEST_TIME 4 5498
TEST_TIME 5 5387
TEST_TIME 6 5385
TEST_TIME 7 5839
TEST_TIME 8 5623
TEST_TIME 9 5736
TEST_TIME 10 5879
TEST_TIME 11 6035
TEST_TIME 12 6133
TEST_TIME 13 6581
TEST_TIME 14 6742
TEST_TIME 15 6865
TEST_TIME 16 6893
TEST_TIME 17 6904
TEST_TIME 18 7782
TEST_TIME 19 7912
TEST_TIME 20 8193
TEST_TIME 21 8691
TEST_TIME 22 9254
TEST_TIME 23 10108
TEST_TIME 24 10192
TEST_TIME 25 10961
TEST_TIME 26 11000
TEST_TIME 27 10945
TEST_TIME 28 10579
TEST_TIME 29 10508
TEST_TIME 30 10571
TEST_TIME 31 10525
TEST_TIME 32 10329
TEST_TIME 33 10341
TEST_TIME 34 10237
TEST_TIME 35 10192
TEST_TIME 36 10147
TEST_TIME 37 10125
TEST_TIME 38 10083
TEST_TIME 39 10129
TEST_TIME 40 9993
TEST_TIME 41 9927
TEST_TIME 42 9779
TEST_TIME 43 9631
TEST_TIME 44 9472
TEST_TIME 45 9589
TEST_TIME 46 9598
TEST_TIME 47 9520
TEST_TIME 48 9524
TEST_TIME 49 9549
TEST_TIME 50 9573
TEST_TIME 51 9624
TEST_TIME 52 9601
TEST_TIME 53 9646
TEST_TIME 54 9641
TEST_TIME 55 9679
TEST_TIME 56 9899
TEST_TIME 57 9845
TEST_TIME 58 9688
TEST_TIME 59 9621
TEST_TIME 60 9526
TEST_TIME 61 9541
TEST_TIME 62 9422
TEST_TIME 63 9394
TEST_TIME 64 9324
TEST_TIME 65 9294
TEST_TIME 66 9251
TEST_TIME 67 9214
TEST_TIME 68 9349
TEST_TIME 69 9376
TEST_TIME 70 9422
TEST_TIME 71 9560
TEST_TIME 72 9504
TEST_TIME 73 9457
TEST_TIME 74 9504
TEST_TIME 75 9581
TEST_TIME 76 9625
TEST_TIME 77 9616
TEST_TIME 78 9507
TEST_TIME 79 9443
TEST_TIME 80 9427
TEST_TIME 81 9421
TEST_TIME 82 9337
TEST_TIME 83 9271
TEST_TIME 84 9289
TEST_TIME 85 9256
TEST_TIME 86 9232
TEST_TIME 87 9218
TEST_TIME 88 9261
TEST_TIME 89 9237
TEST_TIME 90 9243
TEST_TIME 91 9241
TEST_TIME 92 9216
TEST_TIME 93 9291
TEST_TIME 94 9333
TEST_TIME 95 9309
TEST_TIME 96 9305
TEST_TIME 97 9424
TEST_TIME 98 9380
TEST_TIME 99 9293
TEST_TIME 100 9240
TEST_TIME 101 9250
TEST_TIME 102 9176
TEST_TIME 103 9198
TEST_TIME 104 9186
TEST_TIME 105 9180
TEST_TIME 106 9164
TEST_TIME 107 9136
TEST_TIME 108 9127
TEST_TIME 109 9197
TEST_TIME 110 9160
TEST_TIME 111 9231
TEST_TIME 112 9238
TEST_TIME 113 9264
TEST_TIME 114 9261
TEST_TIME 115 9251
TEST_TIME 116 9190
TEST_TIME 117 9151
TEST_TIME 118 9146
TEST_TIME 119 9092
TEST_TIME 120 9021

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-10-2021 07:39:06 to 20-10-2021 07:39:09.

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 1508778 1508778 0 0.00 0 37015 7 0 0
doc_list_biud 1508776 1508776 0 0.00 0 79079 30 0 0
sp_add_invoice_to_stock 5148 5017 131 2.54 33 190361 19005 0 131
sp_cancel_adding_invoice 5396 5396 0 0.00 6 74363 966 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 8822 336 0 0
sp_cancel_customer_reserve 534329 534329 0 0.00 1 75386 66 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 3 9912 114 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 3616 85 0 0
sp_cancel_supplier_invoice 5995 5986 9 0.15 2 79085 960 0 9
sp_cancel_supplier_order 373 373 0 0.00 10 1089552 117623 0 0
sp_cancel_write_off 9192 9192 0 0.00 1 11087 125 0 0
sp_client_order 20915 20915 0 0.00 4 50830 463 0 0
sp_customer_reserve 462086 462086 0 0.00 0 89167 190 0 0
sp_fill_shopping_cart 31900 31900 0 0.00 1 20642 44 0 0
sp_fill_shopping_cart_clo_res 462081 462081 0 0.00 0 21596 17 0 0
sp_get_clo_for_invoice 5008 5008 0 0.00 0 39499 391 0 0
sp_kill_cost_storno 15678 15678 0 0.00 0 10072 55 0 0
sp_kill_qstorno_ret_qs2qd 1084209 1084209 0 0.00 0 79078 36 0 0
sp_kill_qty_storno 572139 572139 0 0.00 0 79079 77 0 0
sp_lock_selected_doc 20812 20812 0 0.00 0 6556 4 0 0
sp_make_cost_storno 28308 28308 0 0.00 0 13178 52 0 0
sp_multiply_rows_for_pdistr 28307 28307 0 0.00 0 12529 35 0 0
sp_multiply_rows_for_qdistr 20915 20915 0 0.00 0 50821 238 0 0
sp_pay_from_customer 7281 7281 0 0.00 4 17413 131 0 0
sp_pay_to_supplier 5945 5945 0 0.00 5 16961 149 0 0
sp_payment_common 13226 13226 0 0.00 1 11019 26 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 8814 301 0 0
sp_qd_handle_on_invoice_upd_sts 10404 10404 0 0.00 0 26656 346 0 0
sp_qd_handle_on_reserve_upd_sts 10073 10073 0 0.00 0 20064 58 0 0
sp_reserve_write_off 10074 10074 0 0.00 7 20085 190 0 0
sp_supplier_invoice 5500 5500 0 0.00 8 92166 3545 0 0
sp_supplier_order 5489 5489 0 0.00 5 43823 1470 0 0
srv_aggregate_perf_data 99 99 0 0.00 2783 382536 21536 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1188 133737 46228 0 0
srv_find_qd_qs_mism 1033304 1033304 0 0.00 0 37015 7 0 0
srv_make_invnt_saldo 495 495 0 0.00 42 124206 3176 0 0
srv_make_money_saldo 746 746 0 0.00 1 13961 581 0 0
srv_recalc_idx_stat 4 4 0 0.00 130780 352559 246120 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 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 5017 5017 0 0.00 0 13724 162 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 2481 92 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 4729 11 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 1921 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 1337 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 801 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 1776 24 0 0
t$perf-norm:sp_cancel_supplier_order 373 373 0 0.00 0 17227 1456 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 3232 15 0 0
t$perf-norm:sp_client_order 20915 20915 0 0.00 0 14180 19 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 646 3 0 0
t$perf-norm:sp_pay_from_customer 7281 7281 0 0.00 0 9053 11 0 0
t$perf-norm:sp_pay_to_supplier 5945 5945 0 0.00 0 7971 11 0 0
t$perf-norm:sp_reserve_write_off 10074 10074 0 0.00 0 5912 10 0 0
t$perf-norm:sp_supplier_invoice 5500 5500 0 0.00 0 3584 16 0 0
t$perf-norm:sp_supplier_order 5489 5489 0 0.00 0 5732 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 402 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 90 2 0 0
t$perf-norm:srv_make_invnt_saldo 495 495 0 0.00 0 2029 15 0 0
t$perf-norm:srv_make_money_saldo 746 746 0 0.00 0 329 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10272 10010 262 2.55 0 9854 25 0 262
v_all_customers 38456 38456 0 0.00 0 106 1 0 0
v_all_suppliers 30576 30576 0 0.00 0 49 1 0 0
v_all_wares 41828 41828 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 1474 8 0 0
v_cancel_client_order 3564 3564 0 0.00 0 8300 10 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 9884 31 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 3708 7 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 3600 17 0 0
v_cancel_supplier_invoice 784 766 18 2.29 0 7602 26 0 18
v_cancel_supplier_order 746 746 0 0.00 0 7609 14 0 0
v_cancel_write_off 2048 2048 0 0.00 0 2946 16 0 0
v_max_id_clo_ord 5489 5489 0 0.00 0 1047 2 0 0
v_max_id_clo_res 956 956 0 0.00 0 6449 21 0 0
v_max_id_ord_sup 5497 5497 0 0.00 0 6052 6 0 0
v_max_non_paid_invoice 1643 1643 0 0.00 0 3005 4 0 0
v_max_non_paid_realizn 3759 3759 0 0.00 0 862 3 0 0
v_min_id_clo_ord 5489 5489 0 0.00 0 2590 7 0 0
v_min_id_clo_res 956 956 0 0.00 0 6499 25 0 0
v_min_id_ord_sup 5497 5497 0 0.00 0 5123 9 0 0
v_min_non_paid_invoice 5945 5945 0 0.00 0 6847 19 0 0
v_min_non_paid_realizn 7281 7281 0 0.00 0 8868 19 0 0
v_our_firm 10416 10416 0 0.00 0 80 1 0 0
v_reserve_write_off 20146 20146 0 0.00 0 9356 9 0 0
x_lock_depdocs_on_canc_invoice 10999 10999 0 0.00 0 9539 112 0 0
x_lock_depdocs_on_canc_sup_ord 373 373 0 0.00 0 11540 349 0 0
x_make_qstorno_1000_1200 5489 5489 0 0.00 2 43782 1391 0 0
x_make_qstorno_1200_2000 5500 5500 0 0.00 4 88844 3377 0 0
x_make_qstorno_2100_3300 462086 462086 0 0.00 0 89166 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-10-2021 07:39:10 to 20-10-2021 07:39:14.

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:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:12 30853 8192000000 9707042048 9998229504 779333440 8.686 100 84 168 0 807486816 7537904 20615472
05:09:46 16380 8192000000 9733157408 10037415936 813166768 9.030 100 89 178 0 847626240 12957408 21502064
05:11:16 20172 8192000000 9799300032 10119319552 870738800 9.607 100 78 156 0 910569120 22145200 17685120
05:12:44 9442 8192000000 9877197696 10211037184 926496448 10.160 100 80 160 0 985272032 40976336 17799248
05:13:56 10099 8192000000 9943619232 10290233344 966416096 10.552 100 83 167 1 1053527280 69210688 17900496
05:15:08 4196 8192000000 9972816192 10325839872 994180416 10.822 100 84 167 0 1085866976 74793120 16893440
05:16:15 3133 8192000000 10002493056 10358898688 1022469904 11.096 100 91 180 1 1112520160 71929008 18121248
05:17:20 3195 8192000000 10020941152 10386763776 1044660544 11.309 100 89 177 1 1132334192 69856048 17817600
05:18:25 1418 8192000000 10028389840 10398453760 1059163984 11.448 100 89 178 0 1142066080 64588256 18313840
05:19:27 12338 8192000000 9986075280 10369179648 1065857696 11.513 100 67 131 1 1101755648 21454512 14443440
05:20:40 5994 8192000000 9993606704 10393186304 1068808400 11.541 100 36 72 2 1111707296 34818048 8080848
05:21:49 9300 8192000000 10008061920 10414673920 1081635776 11.663 100 28 57 1 1114452976 26763040 6054160
05:23:02 5290 8192000000 10015263424 10409750528 1089974752 11.742 100 38 75 1 1124560128 26505312 8080064
05:24:10 71149 8192000000 10001474192 10416111616 1080530672 11.653 99 28 56 2 1110419776 23150016 6739088
05:26:26 5757 8192000000 10002267040 10402119680 1095752448 11.797 100 42 84 0 1133956704 28906768 9297488
05:27:32 5712 8192000000 9989907168 10388045824 1075500640 11.605 100 50 100 2 1100307344 13876704 10930000
05:28:41 7793 8192000000 9942322816 10329272320 990297984 10.784 93 27 53 2 1011800816 15048064 6454768
05:29:49 53116 8192000000 9992799760 10395807744 1027666480 11.146 97 33 65 2 1062770720 28608544 6495696
05:31:46 4912 8192000000 9939125168 10304086016 979714240 10.681 96 53 103 1 1019689216 29191488 10783488
05:32:51 5135 8192000000 9936148112 10316238848 1014427440 11.018 99 41 84 1 1037054560 13796448 8830672
05:34:01 3088 8192000000 9971755152 10353991680 1059097536 11.448 100 60 116 0 1079126960 8087168 11942256
05:35:05 34686 8192000000 9974328432 10363629568 1062480704 11.480 100 30 60 1 1085664992 16146112 7038176
05:36:40 7249 8192000000 9982280384 10376560640 1070054560 11.553 100 45 90 3 1097200496 17263568 9882368
05:37:53 15194 8192000000 10043796304 10437963776 1100665520 11.844 100 58 116 0 1149386688 36809456 11911712
05:39:14 1591 8192000000 10063014496 10459553792 1113010928 11.961 100 54 108 0 1174850432 50186784 11652720
05:40:23 49287 8192000000 10083135728 10486198272 1130390960 12.125 100 53 104 2 1199228368 57428272 11409136
05:42:16 23331 8192000000 10117086576 10527096832 1137506928 12.192 100 48 91 1 1235211712 87949344 9755440
05:43:44 15948 8192000000 10154217040 10558988288 1145918624 12.271 100 58 118 0 1261039024 103060768 12059632
05:45:05 10395 8192000000 10186304608 10594639872 1162503696 12.427 100 54 107 1 1289467952 115340384 11623872
05:46:28 9993 8192000000 10239627120 10647269376 1174837808 12.542 100 50 100 0 1343211904 157278320 11095776
05:47:46 7102 8192000000 10302830496 10714152960 1180361248 12.594 99 62 124 1 1404515408 209618384 14535776
05:48:59 1728 8192000000 10371740304 10777190400 1198996304 12.767 100 89 176 0 1468739088 248924304 20818480
05:50:11 1185 8192000000 10397612896 10803544064 1196825216 12.747 100 87 172 2 1492834656 275897712 20111728
05:51:19 2811 8192000000 10423674288 10824581120 1190739856 12.690 100 89 174 2 1521970496 310531168 20699472
05:52:28 2295 8192000000 10437755216 10847698944 1202407584 12.799 100 69 138 0 1535754304 317523440 15823280
05:53:38 5858 8192000000 10475399008 10883657728 1187038000 12.656 100 78 156 0 1577096976 371618976 18440000
05:54:56 1920 8192000000 10496260432 10915020800 1173590192 12.530 100 93 182 0 1590449680 394981952 21877536
05:56:02 9544 8192000000 10433742016 10861928448 1143269808 12.246 98 37 74 0 1534110256 380764576 10075872
05:57:16 17187 8192000000 10422444288 10902810624 1119375552 12.021 98 39 77 1 1553198320 423185264 10637504
05:58:42 6804 8192000000 10400514400 10854731776 1135016816 12.169 100 43 86 0 1529777824 383827856 10933152
05:59:55 14102 8192000000 10303934448 10748465152 1128873136 12.111 100 21 42 0 1421338800 287335088 5130576
06:01:23 33496 8192000000 10210288640 10653908992 1111563024 11.947 98 24 46 1 1305804800 188468624 5773152
06:03:05 34135 8192000000 10076939280 10521149440 1114433872 11.974 99 15 30 2 1172883632 55140608 3309152
06:04:43 108368 8192000000 10095797840 10513907712 1087813568 11.722 97 35 71 1 1158870784 62919600 8137616
06:07:39 133735 8192000000 10071090416 10490605568 1073357712 11.584 95 21 41 1 1106810560 28976816 4476032
06:10:54 20956 8192000000 10087577888 10508050432 1125158640 12.076 100 44 88 0 1170726000 35718576 9848784
06:12:19 52741 8192000000 10119425456 10571661312 1131188528 12.133 99 23 46 1 1212738832 76299888 5250416
06:14:12 32706 8192000000 10133854016 10624303104 1125467952 12.079 99 15 29 0 1234901728 106148720 3285056
06:15:46 88367 8192000000 10069299824 10546520064 1106156880 11.896 97 18 36 0 1199445440 88988336 4300224
06:18:14 49333 8192000000 10068993168 10511908864 1119877088 12.026 98 17 33 0 1163827248 41129824 2820336
06:20:04 94284 8192000000 10119800512 10573086720 1148044720 12.291 100 21 43 1 1226681504 74140960 4495824
06:22:57 58602 8192000000 10116607696 10566873088 1132609328 12.146 99 22 44 1 1197635504 60411328 4614848
06:25:16 45003 8192000000 10106702048 10548035584 1122734032 12.053 98 21 42 0 1189738080 62357424 4646624
06:27:11 93638 8192000000 10097860864 10558312448 1130728112 12.128 99 26 52 1 1190449344 54276528 5444704
06:29:46 20168 8192000000 10093555376 10522501120 1125779920 12.082 98 39 78 0 1193751760 60330432 7641408
06:31:07 54972 8192000000 10127382608 10565619712 1134870320 12.167 99 24 48 0 1216963456 77334912 4758224
06:33:05 110423 8192000000 10071065088 10536198144 1061122944 11.467 93 12 24 0 1131456080 67514048 2819088
06:36:16 46329 8192000000 10061851536 10510925824 1117311440 12.002 99 18 36 0 1160912512 40136304 3464768
06:38:03 50227 8192000000 10027661728 10507489280 1081986784 11.666 95 10 20 1 1120116720 36209824 1920112
06:39:58 30633 8192000000 10024353920 10458877952 1087364848 11.718 97 11 21 1 1106401360 17094784 1941728
06:41:29 62029 8192000000 10110517472 10551558144 1100268672 11.840 97 23 46 1 1166283472 61039696 4975104
06:43:32 39832 8192000000 10166019616 10602704896 1138233984 12.199 99 23 46 2 1265329152 122116880 4978288
06:45:13 75849 8192000000 10112189360 10598363136 1094102000 11.782 96 23 46 1 1238558256 139172464 5283792
06:47:33 61082 8192000000 10138567088 10634993664 1124142512 12.066 100 15 30 1 1270658064 143309024 3206528
06:49:45 12714 8192000000 10085802400 10499006464 1107618640 11.910 99 50 100 1 1151207008 33468672 10119696
06:51:04 57114 8192000000 10150185616 10667053056 1110243904 11.935 99 11 22 0 1266763184 153939888 2579392
06:53:16 25614 8192000000 10087361072 10532007936 1104144928 11.877 99 48 96 0 1181383072 66858672 10379472
06:55:11 99073 8192000000 10054508736 10475249664 1103403184 11.870 98 46 92 1 1163364320 49261824 10699312
06:57:57 29049 8192000000 10073593920 10526806016 1124273760 12.067 100 21 42 2 1183797552 54949552 4574240
07:01:12 67621 8192000000 10049808848 10529353728 1120340928 12.030 99 17 34 1 1166872064 43389344 3141792
07:03:25 36769 8192000000 10053414512 10492518400 1119594784 12.023 99 23 44 1 1147975088 23500000 4880304
07:05:01 70113 8192000000 10053423616 10501427200 1099527824 11.833 97 24 48 1 1147950336 43339312 5083200
07:07:17 33893 8192000000 10078353440 10533396480 1107312096 11.907 98 28 56 0 1157671632 44661904 5697632
07:08:51 87634 8192000000 10141595968 10606710784 1128178528 12.104 99 22 44 1 1220280160 87832320 4269312
07:11:24 53614 8192000000 10115214432 10548641792 1118771872 12.015 98 36 72 1 1166844336 40388304 7684160
07:13:22 56623 8192000000 10114000816 10620977152 1140111760 12.217 100 18 36 1 1272535760 128429088 3994912
07:15:30 132336 8192000000 10099675792 10546782208 1106526656 11.900 97 38 75 1 1183269360 68808112 7934592
07:18:45 77304 8192000000 10094471216 10539360256 1117467104 12.003 99 6 12 0 1187604160 68779392 1357664
07:21:10 58924 8192000000 10084413888 10538000384 1124328080 12.068 100 15 30 0 1185680576 58276752 3075744
07:23:14 33140 8192000000 10128898576 10582958080 1134191616 12.161 99 22 44 2 1203447664 64755872 4500176
07:24:47 87929 8192000000 10132218144 10608390144 1098680416 11.825 95 19 38 1 1203443136 100208768 4553952
07:27:29 47194 8192000000 10085141344 10555498496 1104717776 11.882 97 20 39 3 1188584672 79572080 4294816
07:29:17 92379 8192000000 10090830032 10565259264 1117288480 12.001 98 28 57 1 1181774640 58509264 5976896
07:31:52 102204 8192000000 10049555968 10479542272 1108149200 11.915 99 26 50 1 1168375744 55583424 4643120
07:34:34 5400 8192000000 10048110736 10476097536 1100226640 11.840 99 33 66 1 1143983440 37010624 6746176
07:35:40 52293 8192000000 10031502976 10499768320 1116602640 11.995 100 8 16 0 1151159936 32490112 2067184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-10-2021 07:39:16 to 20-10-2021 07:39:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 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 20-10-2021 07:39:32 to 20-10-2021 07:39:34.

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          236551
MON$OLDEST_ACTIVE               237178
MON$OLDEST_SNAPSHOT             237178
MON$NEXT_TRANSACTION            237178
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                       2035360
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             804
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:374: 
:375: mscs-test-161.budzdorov.ru    Wed Oct 20 07:37:29 2021
:376:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:377: 
--- end of diff output ---

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

20.10.21 07:39:35. 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-20 07:39:38 No severe PSQL-related problems occured
20.10.21 07:39:39. End of report.