Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.10.2021 07:37
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 15856180 kB
MemAvailable 30679300 kB
Buffers 0 kB
Cached 15824408 kB
SwapCached 3832 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-09 07:37:22
DTS_END                         2021-10-09 07:37:05.6450
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44292
TRN_ID                          226760
ATT_ID                          259
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: 8993.97 24009 1079276
customer order: creation 160.63 394 19275
customer order: refuse 13.63 321 1635
order to supplier: creation 42.89 1513 5147
order to supplier: removal 3.30 141317 396
invoice (draft): creation 42.50 3948 5100
invoice (draft): removal 53.13 1280 6375
invoice accept: apply 38.75 17978 4650
invoice accept: cancel 47.69 945 5723
customer reserve: creation 3641.37 171 436964
customer reserve: removal 4667.26 75 560071
realization accept: apply 77.89 222 9347
realization accept: cancel 85.47 131 10256
payment from customer: creation 53.98 135 6477
payment from customer: removal 5.73 106 688
payment to supplier: creation 46.38 155 5566
payment to supplier: removal 3.28 69 393
service: total inventory turnovers 3.48 3938 418
service: total monetary turnovers 5.61 551 673
service: aggregate perf. data 0.98 15263 117
service: refresh index statistics 0.04 291670 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-10-2021 07:38:10 to 09-10-2021 07:38:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6195
TEST_TIME 2 4625
TEST_TIME 3 4457
TEST_TIME 4 4757
TEST_TIME 5 4813
TEST_TIME 6 4927
TEST_TIME 7 4880
TEST_TIME 8 4894
TEST_TIME 9 5147
TEST_TIME 10 5191
TEST_TIME 11 5397
TEST_TIME 12 5717
TEST_TIME 13 6153
TEST_TIME 14 6101
TEST_TIME 15 6131
TEST_TIME 16 6722
TEST_TIME 17 6611
TEST_TIME 18 6699
TEST_TIME 19 6940
TEST_TIME 20 7420
TEST_TIME 21 7685
TEST_TIME 22 8358
TEST_TIME 23 8710
TEST_TIME 24 8837
TEST_TIME 25 9681
TEST_TIME 26 10032
TEST_TIME 27 11863
TEST_TIME 28 11590
TEST_TIME 29 11541
TEST_TIME 30 11255
TEST_TIME 31 11103
TEST_TIME 32 11121
TEST_TIME 33 10879
TEST_TIME 34 10709
TEST_TIME 35 10660
TEST_TIME 36 10448
TEST_TIME 37 10315
TEST_TIME 38 10158
TEST_TIME 39 10076
TEST_TIME 40 9957
TEST_TIME 41 9888
TEST_TIME 42 9780
TEST_TIME 43 9706
TEST_TIME 44 9651
TEST_TIME 45 9804
TEST_TIME 46 9678
TEST_TIME 47 9511
TEST_TIME 48 9360
TEST_TIME 49 9396
TEST_TIME 50 9487
TEST_TIME 51 9437
TEST_TIME 52 9378
TEST_TIME 53 9344
TEST_TIME 54 9416
TEST_TIME 55 9392
TEST_TIME 56 9355
TEST_TIME 57 9353
TEST_TIME 58 9384
TEST_TIME 59 9421
TEST_TIME 60 9367
TEST_TIME 61 9506
TEST_TIME 62 9490
TEST_TIME 63 9372
TEST_TIME 64 9265
TEST_TIME 65 9205
TEST_TIME 66 9229
TEST_TIME 67 9238
TEST_TIME 68 9202
TEST_TIME 69 9131
TEST_TIME 70 9109
TEST_TIME 71 9015
TEST_TIME 72 8945
TEST_TIME 73 8959
TEST_TIME 74 8950
TEST_TIME 75 8937
TEST_TIME 76 8928
TEST_TIME 77 8932
TEST_TIME 78 8945
TEST_TIME 79 8979
TEST_TIME 80 9136
TEST_TIME 81 9098
TEST_TIME 82 9086
TEST_TIME 83 9176
TEST_TIME 84 9182
TEST_TIME 85 9211
TEST_TIME 86 9162
TEST_TIME 87 9112
TEST_TIME 88 9149
TEST_TIME 89 9119
TEST_TIME 90 9062
TEST_TIME 91 9029
TEST_TIME 92 9022
TEST_TIME 93 8989
TEST_TIME 94 9079
TEST_TIME 95 9075
TEST_TIME 96 9116
TEST_TIME 97 9073
TEST_TIME 98 9174
TEST_TIME 99 9209
TEST_TIME 100 9230
TEST_TIME 101 9251
TEST_TIME 102 9281
TEST_TIME 103 9299
TEST_TIME 104 9270
TEST_TIME 105 9292
TEST_TIME 106 9239
TEST_TIME 107 9188
TEST_TIME 108 9151
TEST_TIME 109 9177
TEST_TIME 110 9132
TEST_TIME 111 9116
TEST_TIME 112 9083
TEST_TIME 113 9041
TEST_TIME 114 8992
TEST_TIME 115 9019
TEST_TIME 116 9031
TEST_TIME 117 9010
TEST_TIME 118 9033
TEST_TIME 119 9064
TEST_TIME 120 8997

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-10-2021 07:38:14 to 09-10-2021 07:38:17.

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 1482756 1482756 0 0.00 0 25879 7 0 0
doc_list_biud 1482756 1482756 0 0.00 0 69681 37 0 0
sp_add_invoice_to_stock 4728 4650 78 1.64 43 188514 17979 0 78
sp_cancel_adding_invoice 5723 5723 0 0.00 6 100110 945 0 0
sp_cancel_client_order 1635 1635 0 0.00 5 8805 321 0 0
sp_cancel_customer_reserve 560071 560071 0 0.00 1 43130 75 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 3 9822 106 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 1724 69 0 0
sp_cancel_supplier_invoice 6382 6375 7 0.10 2 69686 1281 0 7
sp_cancel_supplier_order 396 396 0 0.00 9 1283507 141317 0 0
sp_cancel_write_off 10256 10256 0 0.00 1 12687 131 0 0
sp_client_order 19275 19275 0 0.00 4 34098 395 0 0
sp_customer_reserve 436964 436964 0 0.00 0 74008 172 0 0
sp_fill_shopping_cart 29517 29517 0 0.00 1 49989 58 0 0
sp_fill_shopping_cart_clo_res 436960 436960 0 0.00 0 18479 19 0 0
sp_get_clo_for_invoice 4645 4645 0 0.00 0 37082 464 0 0
sp_kill_cost_storno 17060 17060 0 0.00 0 17583 66 0 0
sp_kill_qstorno_ret_qs2qd 1137169 1137169 0 0.00 0 69680 42 0 0
sp_kill_qty_storno 598447 598447 0 0.00 0 69681 89 0 0
sp_lock_selected_doc 19436 19436 0 0.00 0 6975 5 0 0
sp_make_cost_storno 26034 26034 0 0.00 0 25876 72 0 0
sp_multiply_rows_for_pdistr 26034 26034 0 0.00 0 10699 34 0 0
sp_multiply_rows_for_qdistr 19275 19275 0 0.00 0 10720 194 0 0
sp_pay_from_customer 6477 6477 0 0.00 4 16591 135 0 0
sp_pay_to_supplier 5566 5566 0 0.00 4 25389 155 0 0
sp_payment_common 12043 12043 0 0.00 1 12696 28 0 0
sp_qd_handle_on_cancel_clo 1635 1635 0 0.00 0 5993 281 0 0
sp_qd_handle_on_invoice_upd_sts 10367 10367 0 0.00 0 23647 431 0 0
sp_qd_handle_on_reserve_upd_sts 9347 9347 0 0.00 0 11943 61 0 0
sp_reserve_write_off 9347 9347 0 0.00 6 25903 223 0 0
sp_supplier_invoice 5100 5100 0 0.00 9 121849 3948 0 0
sp_supplier_order 5147 5147 0 0.00 6 117684 1513 0 0
srv_aggregate_perf_data 117 117 0 0.00 3126 124489 15263 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 996 145911 47039 0 0
srv_find_qd_qs_mism 1034044 1034044 0 0.00 0 12774 5 0 0
srv_make_invnt_saldo 418 418 0 0.00 45 130378 3938 0 0
srv_make_money_saldo 673 673 0 0.00 1 31802 552 0 0
srv_recalc_idx_stat 5 5 0 0.00 128323 434028 291670 0 0
t$perf-abend:sp_add_invoice_to_stock 78 78 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4650 4650 0 0.00 0 12745 161 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 1 5708 132 0 0
t$perf-norm:sp_cancel_client_order 1635 1635 0 0.00 0 1953 11 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 1116 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 454 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 5839 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 5688 33 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 27334 1710 0 0
t$perf-norm:sp_cancel_write_off 921 921 0 0.00 0 1805 10 0 0
t$perf-norm:sp_client_order 19275 19275 0 0.00 0 9782 19 0 0
t$perf-norm:sp_customer_reserve 932 932 0 0.00 0 433 4 0 0
t$perf-norm:sp_pay_from_customer 6477 6477 0 0.00 0 6855 11 0 0
t$perf-norm:sp_pay_to_supplier 5566 5566 0 0.00 0 4203 9 0 0
t$perf-norm:sp_reserve_write_off 9347 9347 0 0.00 0 13533 13 0 0
t$perf-norm:sp_supplier_invoice 5100 5100 0 0.00 0 10267 16 0 0
t$perf-norm:sp_supplier_order 5147 5147 0 0.00 0 19827 24 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 1584 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1044 22 0 0
t$perf-norm:srv_make_invnt_saldo 418 418 0 0.00 0 1797 15 0 0
t$perf-norm:srv_make_money_saldo 673 673 0 0.00 0 6918 19 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 8 6 0 0
v_add_invoice_to_stock 9440 9284 156 1.65 0 7268 20 0 156
v_all_customers 35172 35172 0 0.00 0 20 1 0 0
v_all_suppliers 28826 28826 0 0.00 0 20 1 0 0
v_all_wares 38546 38546 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 4793 19 0 0
v_cancel_client_order 3270 3270 0 0.00 0 6779 10 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 3411 12 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 1221 7 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 1678 8 0 0
v_cancel_supplier_invoice 772 758 14 1.81 0 6133 43 0 14
v_cancel_supplier_order 792 792 0 0.00 0 846 7 0 0
v_cancel_write_off 1842 1842 0 0.00 0 6695 18 0 0
v_max_id_clo_ord 5145 5145 0 0.00 0 3666 2 0 0
v_max_id_clo_res 932 932 0 0.00 0 1540 15 0 0
v_max_id_ord_sup 5098 5098 0 0.00 0 6619 7 0 0
v_max_non_paid_invoice 1396 1396 0 0.00 0 4647 6 0 0
v_max_non_paid_realizn 3364 3364 0 0.00 0 4597 8 0 0
v_min_id_clo_ord 5145 5145 0 0.00 0 3260 7 0 0
v_min_id_clo_res 932 932 0 0.00 0 8672 18 0 0
v_min_id_ord_sup 5098 5098 0 0.00 0 15552 18 0 0
v_min_non_paid_invoice 5566 5566 0 0.00 0 12688 23 0 0
v_min_non_paid_realizn 6477 6477 0 0.00 0 9418 17 0 0
v_our_firm 9600 9600 0 0.00 0 28 1 0 0
v_reserve_write_off 18694 18694 0 0.00 0 6603 9 0 0
x_lock_depdocs_on_canc_invoice 11719 11719 0 0.00 0 15375 127 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 16924 403 0 0
x_make_qstorno_1000_1200 5147 5147 0 0.00 2 116806 1426 0 0
x_make_qstorno_1200_2000 5100 5100 0 0.00 3 121729 3710 0 0
x_make_qstorno_2100_3300 436964 436964 0 0.00 0 74007 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-10-2021 07:38:18 to 09-10-2021 07:38:22.

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:09 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:10 34002 8192000000 9722538768 10018250752 784688544 8.741 100 87 173 1 813375600 7622288 21064768
05:09:51 18865 8192000000 9758345504 10063675392 831892048 9.218 100 86 172 0 864896640 12186128 20818464
05:11:16 16726 8192000000 9811679952 10130644992 875103904 9.651 100 86 172 0 917500848 22087248 20309696
05:12:37 14232 8192000000 9863653872 10199703552 926156320 10.157 100 75 150 2 979611392 37135552 16319520
05:13:55 12034 8192000000 9943536432 10288033792 965956144 10.547 100 80 160 1 1047563408 64729488 16877776
05:15:10 6009 8192000000 9957895056 10316828672 999976064 10.878 100 75 150 1 1072552272 56817008 15759200
05:16:18 3805 8192000000 9993417168 10355773440 1019904352 11.071 100 85 167 1 1108816816 71797808 17114656
05:17:28 2476 8192000000 10007564368 10372591616 1047975984 11.341 100 85 170 2 1112548800 47665232 16907584
05:18:32 2518 8192000000 10044042944 10425020416 1066861920 11.522 100 85 171 1 1160457600 76982000 16613680
05:19:37 11742 8192000000 9975122144 10375274496 1062471168 11.480 100 31 61 2 1083019184 14321168 6226848
05:20:50 4085 8192000000 9990245712 10381893632 1072972416 11.580 100 46 92 0 1105853392 23036128 9844848
05:21:57 9040 8192000000 9994582480 10402455552 1079624720 11.644 100 32 64 1 1110076720 23436464 7015536
05:23:07 50686 8192000000 9991235616 10393911296 1084760208 11.693 100 40 80 0 1115774528 22356128 8658192
05:24:58 8204 8192000000 10001007648 10396106752 1089783728 11.741 100 35 68 4 1127192768 30032720 7376320
05:26:07 7466 8192000000 10008640368 10417975296 1092998112 11.771 100 46 93 1 1124290784 21131552 10161120
05:27:18 10171 8192000000 10001925616 10399965184 1073049664 11.581 99 19 38 0 1109714640 32722224 3942752
05:28:29 36592 8192000000 9990552256 10400194560 1069847680 11.551 99 27 54 0 1100230608 24697920 5685008
05:30:09 5315 8192000000 9972783248 10357051392 1036551120 11.232 98 35 69 0 1066664832 22726112 7387600
05:31:17 3431 8192000000 9935444912 10310918144 1022952832 11.101 100 41 82 1 1052530720 19826000 9751888
05:32:23 5491 8192000000 9937088864 10317942784 1027100512 11.141 99 35 68 2 1047186208 12162112 7923584
05:33:30 43240 8192000000 9961579872 10361802752 1008562384 10.961 96 24 48 0 1034103296 20648848 4892064
05:35:18 5274 8192000000 9985162304 10382495744 1059476880 11.451 100 53 107 2 1097918240 27245008 11196352
05:36:25 11039 8192000000 9978530272 10386354176 1063337824 11.488 100 34 65 2 1079145824 8472496 7335504
05:37:40 86525 8192000000 10023916736 10422325248 1102904608 11.865 100 46 91 2 1139067088 26717312 9445168
05:40:11 3000 8192000000 10088261424 10484649984 1117939328 12.008 100 70 138 0 1188506960 56259712 14307920
05:41:22 43306 8192000000 10132365792 10543013888 1133952640 12.159 100 45 90 0 1242720400 98727680 10040080
05:43:15 2756 8192000000 10191115536 10607755264 1149398144 12.304 100 43 85 2 1297484512 138465232 9621136
05:44:21 17042 8192000000 10267186912 10689335296 1166442576 12.464 100 53 103 0 1371303744 193076464 11784704
05:45:46 10887 8192000000 10288388176 10719227904 1166188544 12.461 100 52 104 0 1395067104 216319584 12558976
05:47:04 4097 8192000000 10320873792 10739916800 1177473136 12.567 100 60 120 0 1429355936 238218336 13664464
05:48:12 5456 8192000000 10370016432 10783936512 1180658592 12.596 100 66 131 1 1479250112 283713216 14878304
05:49:29 9248 8192000000 10435837616 10859266048 1201545120 12.791 100 73 144 1 1530115920 312110864 16459936
05:50:43 1807 8192000000 10473889184 10893934592 1195632192 12.736 100 97 192 0 1569125552 350970480 22522880
05:51:51 6164 8192000000 10515871024 10924146688 1195535840 12.735 100 81 162 1 1611539088 396810608 19192640
05:53:02 2269 8192000000 10605616992 11010158592 1192286672 12.705 99 86 172 2 1697677760 484635008 20756080
05:54:11 3456 8192000000 10592014624 10995363840 1174018720 12.534 100 92 183 0 1689331328 492527312 22785296
05:55:22 5214 8192000000 10661328816 11070087168 1176416928 12.557 99 76 151 3 1754327216 558810448 19099840
05:56:31 2305 8192000000 10699513024 11121983488 1179466928 12.585 100 64 127 1 1796790112 600482560 16840624
05:57:38 994 8192000000 10666965056 11131691008 1173048624 12.525 100 73 145 0 1768224016 576755696 18419696
05:58:49 7532 8192000000 10654358896 11095040000 1162097632 12.423 100 57 112 0 1749973680 572828240 15047808
06:00:02 12794 8192000000 10599204448 11081822208 1164207536 12.443 99 49 96 0 1692824464 516461536 12155392
06:01:18 11716 8192000000 10606409920 11048235008 1145069744 12.263 99 36 72 0 1700694688 545668784 9956160
06:02:31 25613 8192000000 10453419552 10936623104 1109674336 11.929 99 22 44 1 1536839600 420637840 6527424
06:04:03 8785 8192000000 10280461888 10786660352 1110244992 11.935 99 32 64 1 1432224256 313903840 8075424
06:05:19 88909 8192000000 10086415616 10557169664 1130126688 12.123 100 21 42 0 1204151312 69163984 4860640
06:07:49 70813 8192000000 10081562528 10550616064 1083644672 11.682 97 23 44 1 1163518368 75153328 4720368
06:10:14 94730 8192000000 10057543328 10489057280 1068108736 11.534 95 12 24 0 1116547120 46540544 1897840
06:12:49 145911 8192000000 10044924624 10500796416 1084463648 11.690 96 22 44 0 1126863808 37889824 4510336
06:16:15 92654 8192000000 10082046944 10524667904 1093786736 11.779 96 31 62 0 1159708240 59490096 6431408
06:18:53 18854 8192000000 10085223888 10536165376 1136755872 12.185 100 20 40 0 1195328128 54214848 4357408
06:20:29 65281 8192000000 10117529072 10583527424 1127311584 12.096 99 32 64 3 1237211392 102803232 7096576
06:23:14 112134 8192000000 10102737200 10552287232 1135014256 12.169 99 20 40 0 1194867680 55606192 4247232
06:26:06 51675 8192000000 10116797328 10613743616 1127382128 12.097 99 11 22 0 1211803392 82046320 2374944
06:28:04 42165 8192000000 10045422368 10502627328 1117384912 12.002 98 8 16 0 1161133184 41868640 1879632
06:29:47 37167 8192000000 10080515920 10520793088 1125181520 12.076 98 32 64 0 1165983856 34481888 6320448
06:31:29 93245 8192000000 10081543056 10537951232 1122451760 12.050 98 8 16 0 1154689376 30747744 1489872
06:34:08 57383 8192000000 10138990448 10584535040 1141345712 12.228 99 15 30 1 1232071328 87710768 3014848
06:36:16 58228 8192000000 10122440944 10586513408 1151290320 12.322 99 12 24 1 1208271824 54456304 2525200
06:38:17 68031 8192000000 10085779696 10583973888 1118661520 12.014 97 12 24 1 1192369072 71323728 2383824
06:40:26 68687 8192000000 10094640752 10534088704 1107726656 11.911 97 45 90 0 1179345184 62152416 9466112
06:42:43 61714 8192000000 10098324032 10604830720 1128273200 12.105 100 16 32 1 1239167920 107808384 3086336
06:44:46 48103 8192000000 10051692112 10503938048 1112731328 11.958 98 16 31 2 1144721616 29222848 2767440
06:46:43 55524 8192000000 10045553984 10525200384 1123657968 12.062 99 13 24 1 1169433600 43392528 2383104
06:48:46 13549 8192000000 10045335872 10502737920 1130121600 12.123 100 12 24 1 1161260112 28486768 2651744
06:50:02 50478 8192000000 10061321472 10543022080 1096865264 11.808 96 14 28 2 1157776528 57987504 2923760
06:51:59 36685 8192000000 10051138544 10490224640 1096220816 11.802 97 14 28 0 1129218288 30172336 2825136
06:53:42 50416 8192000000 10085465136 10556645376 1109502288 11.928 99 23 46 0 1183255184 68825328 4927568
06:55:36 57237 8192000000 10139384160 10598350848 1130954464 12.130 99 35 70 1 1220044176 81424000 7665712
06:57:38 73333 8192000000 10081845536 10519392256 1102437872 11.861 97 17 33 1 1168751600 62630976 3682752
06:59:54 36598 8192000000 10074897104 10554871808 1096552688 11.805 98 22 45 0 1201809568 100778928 4477952
07:01:49 51482 8192000000 10062575344 10538283008 1107635584 11.910 98 30 58 0 1163223040 49183712 6403744
07:03:45 140687 8192000000 10088476864 10603421696 1111560784 11.947 98 28 56 0 1210318368 92915856 5841728
07:07:08 43275 8192000000 10072891568 10597322752 1122620832 12.052 100 4 8 0 1176424736 52988560 815344
07:09:42 107338 8192000000 10089209840 10587750400 1081324720 11.660 96 18 36 0 1166729360 81691424 3713216
07:12:36 62409 8192000000 10085636112 10536644608 1114022640 11.970 98 28 56 0 1187577968 67268016 6287312
07:14:49 44828 8192000000 10169975488 10642513920 1139384064 12.210 100 16 32 0 1238326880 95354112 3588704
07:16:55 12479 8192000000 10084583120 10513510400 1122392176 12.050 98 54 109 2 1157006816 23451216 11163424
07:18:08 61105 8192000000 10119151520 10595270656 1120314656 12.030 98 18 36 0 1180804448 56970624 3519168
07:20:10 52470 8192000000 10065972224 10530992128 1070858048 11.560 94 17 34 0 1102436992 28311152 3267792
07:22:05 143287 8192000000 10093610544 10567110656 1147838992 12.289 100 25 50 1 1224580656 71693008 5048656
07:26:50 53827 8192000000 10057828432 10519621632 1089283088 11.736 95 12 25 0 1119266448 27478336 2505024
07:28:44 64413 8192000000 10089402000 10562650112 1129769280 12.119 99 18 36 0 1176485264 42999440 3716544
07:30:48 64573 8192000000 10089705312 10547838976 1134691584 12.166 100 11 22 0 1190257120 53112288 2453248
07:33:31 50560 8192000000 10074648288 10521944064 1128102512 12.103 99 15 30 0 1150340672 18370352 3867808
07:35:22 43135 8192000000 10076308240 10536153088 1075318240 11.603 94 25 51 0 1152557056 71628048 5610768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 09-10-2021 07:38:24 to 09-10-2021 07:38:38.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-10-2021 07:38:39 to 09-10-2021 07:38:41.

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          233284
MON$OLDEST_ACTIVE               233285
MON$OLDEST_SNAPSHOT             233285
MON$NEXT_TRANSACTION            233285
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                       2044384
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2624 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2624 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2624 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2624 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 ---
--- end of diff output ---

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

09.10.21 07:38:42. 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-09 07:38:45 No severe PSQL-related problems occured
09.10.21 07:38:46. End of report.