Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.11.2021 07:47
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 16702932 kB
MemAvailable 31600720 kB
Buffers 0 kB
Cached 14961644 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-11-16 07:46:12
DTS_END                         2021-11-16 07:46:12.8390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42470
TRN_ID                          224911
ATT_ID                          617
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: 9158.47 25294 1099016
customer order: creation 158.98 435 19078
customer order: refuse 13.93 339 1671
order to supplier: creation 42.83 1514 5140
order to supplier: removal 3.54 102325 425
invoice (draft): creation 42.88 3517 5146
invoice (draft): removal 55.13 743 6615
invoice accept: apply 38.64 19194 4637
invoice accept: cancel 49.93 809 5992
customer reserve: creation 3584.19 187 430103
customer reserve: removal 4890.45 60 586854
realization accept: apply 77.93 196 9351
realization accept: cancel 80.92 125 9710
payment from customer: creation 54.32 135 6518
payment from customer: removal 5.53 94 663
payment to supplier: creation 45.88 137 5505
payment to supplier: removal 3.28 80 394
service: total inventory turnovers 3.71 3608 445
service: total monetary turnovers 5.62 604 674
service: aggregate perf. data 0.76 20772 91
service: refresh index statistics 0.03 350998 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-11-2021 07:47:32 to 16-11-2021 07:47:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9528
TEST_TIME 2 7362
TEST_TIME 3 8194
TEST_TIME 4 7761
TEST_TIME 5 7752
TEST_TIME 6 7165
TEST_TIME 7 7052
TEST_TIME 8 6909
TEST_TIME 9 6651
TEST_TIME 10 6731
TEST_TIME 11 6651
TEST_TIME 12 6870
TEST_TIME 13 7138
TEST_TIME 14 7649
TEST_TIME 15 7797
TEST_TIME 16 7681
TEST_TIME 17 8092
TEST_TIME 18 9049
TEST_TIME 19 9633
TEST_TIME 20 9630
TEST_TIME 21 9897
TEST_TIME 22 9855
TEST_TIME 23 9857
TEST_TIME 24 10202
TEST_TIME 25 11384
TEST_TIME 26 11390
TEST_TIME 27 11004
TEST_TIME 28 10743
TEST_TIME 29 10571
TEST_TIME 30 10525
TEST_TIME 31 10371
TEST_TIME 32 10192
TEST_TIME 33 10163
TEST_TIME 34 10038
TEST_TIME 35 9845
TEST_TIME 36 9824
TEST_TIME 37 9907
TEST_TIME 38 9835
TEST_TIME 39 9751
TEST_TIME 40 9864
TEST_TIME 41 9808
TEST_TIME 42 9806
TEST_TIME 43 9831
TEST_TIME 44 9718
TEST_TIME 45 9791
TEST_TIME 46 9866
TEST_TIME 47 9784
TEST_TIME 48 9876
TEST_TIME 49 9705
TEST_TIME 50 9557
TEST_TIME 51 9474
TEST_TIME 52 9417
TEST_TIME 53 9343
TEST_TIME 54 9202
TEST_TIME 55 9284
TEST_TIME 56 9317
TEST_TIME 57 9314
TEST_TIME 58 9308
TEST_TIME 59 9379
TEST_TIME 60 9363
TEST_TIME 61 9391
TEST_TIME 62 9345
TEST_TIME 63 9425
TEST_TIME 64 9406
TEST_TIME 65 9561
TEST_TIME 66 9600
TEST_TIME 67 9591
TEST_TIME 68 9539
TEST_TIME 69 9460
TEST_TIME 70 9388
TEST_TIME 71 9358
TEST_TIME 72 9317
TEST_TIME 73 9256
TEST_TIME 74 9235
TEST_TIME 75 9210
TEST_TIME 76 9205
TEST_TIME 77 9222
TEST_TIME 78 9260
TEST_TIME 79 9237
TEST_TIME 80 9268
TEST_TIME 81 9311
TEST_TIME 82 9311
TEST_TIME 83 9330
TEST_TIME 84 9506
TEST_TIME 85 9583
TEST_TIME 86 9572
TEST_TIME 87 9493
TEST_TIME 88 9457
TEST_TIME 89 9384
TEST_TIME 90 9402
TEST_TIME 91 9318
TEST_TIME 92 9276
TEST_TIME 93 9331
TEST_TIME 94 9313
TEST_TIME 95 9276
TEST_TIME 96 9243
TEST_TIME 97 9300
TEST_TIME 98 9387
TEST_TIME 99 9403
TEST_TIME 100 9386
TEST_TIME 101 9406
TEST_TIME 102 9421
TEST_TIME 103 9434
TEST_TIME 104 9454
TEST_TIME 105 9544
TEST_TIME 106 9518
TEST_TIME 107 9515
TEST_TIME 108 9436
TEST_TIME 109 9403
TEST_TIME 110 9384
TEST_TIME 111 9382
TEST_TIME 112 9348
TEST_TIME 113 9312
TEST_TIME 114 9290
TEST_TIME 115 9233
TEST_TIME 116 9246
TEST_TIME 117 9238
TEST_TIME 118 9231
TEST_TIME 119 9228
TEST_TIME 120 9162

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-11-2021 07:47:35 to 16-11-2021 07:47:39.

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 1497256 1497256 0 0.00 0 25330 7 0 0
doc_list_biud 1497256 1497256 0 0.00 0 30101 29 0 0
sp_add_invoice_to_stock 4718 4637 81 1.71 33 238059 19194 0 81
sp_cancel_adding_invoice 5992 5992 0 0.00 4 66781 809 0 0
sp_cancel_client_order 1671 1671 0 0.00 5 14399 339 0 0
sp_cancel_customer_reserve 586854 586854 0 0.00 1 30104 60 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 4472 94 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 4199 80 0 0
sp_cancel_supplier_invoice 6622 6615 7 0.10 1 27739 744 0 7
sp_cancel_supplier_order 425 425 0 0.00 6 1072261 102325 0 0
sp_cancel_write_off 9710 9710 0 0.00 1 8780 126 0 0
sp_client_order 19078 19078 0 0.00 4 26142 436 0 0
sp_customer_reserve 430103 430103 0 0.00 0 120328 188 0 0
sp_fill_shopping_cart 29354 29354 0 0.00 1 20140 48 0 0
sp_fill_shopping_cart_clo_res 430097 430097 0 0.00 0 18022 17 0 0
sp_get_clo_for_invoice 4622 4622 0 0.00 0 24206 384 0 0
sp_kill_cost_storno 16759 16759 0 0.00 0 6098 55 0 0
sp_kill_qstorno_ret_qs2qd 1190458 1190458 0 0.00 0 30088 32 0 0
sp_kill_qty_storno 625239 625239 0 0.00 0 30101 69 0 0
sp_lock_selected_doc 19550 19550 0 0.00 0 8263 5 0 0
sp_make_cost_storno 25995 25995 0 0.00 0 15715 57 0 0
sp_multiply_rows_for_pdistr 25995 25995 0 0.00 0 7304 33 0 0
sp_multiply_rows_for_qdistr 19078 19078 0 0.00 0 26116 241 0 0
sp_pay_from_customer 6518 6518 0 0.00 4 16307 135 0 0
sp_pay_to_supplier 5505 5505 0 0.00 5 13999 138 0 0
sp_payment_common 12023 12023 0 0.00 1 5903 27 0 0
sp_qd_handle_on_cancel_clo 1671 1671 0 0.00 0 13700 292 0 0
sp_qd_handle_on_invoice_upd_sts 10613 10613 0 0.00 0 21363 334 0 0
sp_qd_handle_on_reserve_upd_sts 9351 9351 0 0.00 0 12864 65 0 0
sp_reserve_write_off 9351 9351 0 0.00 7 15741 196 0 0
sp_supplier_invoice 5146 5146 0 0.00 7 104347 3518 0 0
sp_supplier_order 5140 5140 0 0.00 5 59748 1515 0 0
srv_aggregate_perf_data 91 91 0 0.00 2768 326139 20773 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1273 177980 51392 0 0
srv_find_qd_qs_mism 1054715 1054715 0 0.00 0 25330 6 0 0
srv_make_invnt_saldo 445 445 0 0.00 68 119702 3608 0 0
srv_make_money_saldo 674 674 0 0.00 1 13792 604 0 0
srv_recalc_idx_stat 4 4 0 0.00 183893 555192 350998 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 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 4637 4637 0 0.00 0 18389 195 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 0 7832 111 0 0
t$perf-norm:sp_cancel_client_order 1671 1671 0 0.00 0 8058 19 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 5486 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 309 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 296 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1175 14 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 23266 1313 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 10631 23 0 0
t$perf-norm:sp_client_order 19078 19078 0 0.00 0 5698 17 0 0
t$perf-norm:sp_customer_reserve 948 948 0 0.00 0 2395 6 0 0
t$perf-norm:sp_pay_from_customer 6518 6518 0 0.00 0 6244 12 0 0
t$perf-norm:sp_pay_to_supplier 5505 5505 0 0.00 0 5227 10 0 0
t$perf-norm:sp_reserve_write_off 9351 9351 0 0.00 0 14558 15 0 0
t$perf-norm:sp_supplier_invoice 5146 5146 0 0.00 0 16407 27 0 0
t$perf-norm:sp_supplier_order 5140 5140 0 0.00 0 9062 17 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 188 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 306 7 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 615 6 0 0
t$perf-norm:srv_make_money_saldo 674 674 0 0.00 0 418 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9402 9240 162 1.72 0 13223 22 0 162
v_all_customers 34868 34868 0 0.00 0 37 1 0 0
v_all_suppliers 28576 28576 0 0.00 0 93 1 0 0
v_all_wares 38156 38156 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 2140 10 0 0
v_cancel_client_order 3342 3342 0 0.00 0 13777 18 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 4430 14 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 10441 12 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 2141 7 0 0
v_cancel_supplier_invoice 798 784 14 1.75 0 1579 15 0 14
v_cancel_supplier_order 850 850 0 0.00 0 10980 17 0 0
v_cancel_write_off 1946 1946 0 0.00 0 4405 14 0 0
v_max_id_clo_ord 5140 5140 0 0.00 0 2372 2 0 0
v_max_id_clo_res 948 948 0 0.00 0 6407 19 0 0
v_max_id_ord_sup 5135 5135 0 0.00 0 4371 6 0 0
v_max_non_paid_invoice 1492 1492 0 0.00 0 1647 3 0 0
v_max_non_paid_realizn 3473 3473 0 0.00 0 2148 7 0 0
v_min_id_clo_ord 5140 5140 0 0.00 0 2666 6 0 0
v_min_id_clo_res 948 948 0 0.00 0 7977 25 0 0
v_min_id_ord_sup 5135 5135 0 0.00 0 17985 12 0 0
v_min_non_paid_invoice 5505 5505 0 0.00 0 5316 20 0 0
v_min_non_paid_realizn 6518 6518 0 0.00 0 5898 20 0 0
v_our_firm 9378 9378 0 0.00 0 13 1 0 0
v_reserve_write_off 18702 18702 0 0.00 0 13900 11 0 0
x_lock_depdocs_on_canc_invoice 12215 12215 0 0.00 0 21890 101 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 6193 317 0 0
x_make_qstorno_1000_1200 5140 5140 0 0.00 2 59576 1444 0 0
x_make_qstorno_1200_2000 5146 5146 0 0.00 4 100534 3322 0 0
x_make_qstorno_2100_3300 430103 430103 0 0.00 0 120306 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-11-2021 07:47:40 to 16-11-2021 07:47:44.

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:16:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:17:17 15989 8192000000 9704538080 10002190336 791878240 8.814 100 86 172 1 820240976 7596144 20766592
05:18:38 19577 8192000000 9752422448 10055991296 825670800 9.156 100 92 184 2 860070320 12535408 21864112
05:20:02 18764 8192000000 9789917648 10104610816 848275648 9.383 100 77 154 0 891908752 25555392 18077712
05:21:24 11347 8192000000 9853722416 10186510336 906384000 9.962 100 81 162 0 967287520 43222800 17680720
05:22:39 8984 8192000000 9900755680 10244820992 945267952 10.345 100 76 152 0 1024227904 63084928 15875024
05:23:50 6047 8192000000 9962960560 10318004224 985321472 10.736 100 72 144 1 1082081136 82444208 14315456
05:24:59 3946 8192000000 9979405328 10345123840 1011814624 10.993 100 63 124 0 1098400672 74282416 12303632
05:26:05 2073 8192000000 10003225536 10369191936 1041011616 11.274 100 90 180 0 1112532240 52946096 18574528
05:27:09 1796 8192000000 10009400976 10376822784 1057350096 11.431 100 84 167 2 1122534480 47477712 17706672
05:28:12 2232 8192000000 10003237824 10383937536 1066309744 11.517 100 65 130 0 1116737296 36437136 13990416
05:29:16 6586 8192000000 9993417840 10389450752 1075064016 11.600 100 42 84 0 1103448784 19436256 8948512
05:30:24 51331 8192000000 10003637312 10392141824 1079452160 11.642 100 53 106 1 1116140608 25326016 11362432
05:32:16 5372 8192000000 10008840768 10404696064 1088857584 11.732 100 52 105 0 1123958800 24519936 10581280
05:33:25 5288 8192000000 10014347888 10410057728 1096090976 11.801 100 33 65 1 1124175024 20578144 7505904
05:34:33 7960 8192000000 10003232816 10413928448 1091702128 11.759 99 37 75 0 1118201392 18438576 8060688
05:35:46 36394 8192000000 10012459040 10404376576 1097680768 11.816 100 70 135 2 1128382288 15113280 15588240
05:37:22 7956 8192000000 10026435728 10424348672 1064881696 11.503 97 44 88 0 1093687664 20004192 8801776
05:38:32 5843 8192000000 9943480192 10316062720 1015927920 11.033 97 55 111 1 1037813344 9969120 11916304
05:39:40 13095 8192000000 9939688128 10339205120 991810048 10.799 96 23 46 0 1025424432 28596224 5018160
05:40:56 25497 8192000000 9942563616 10340851712 999990576 10.878 98 47 94 2 1038022032 28023760 10007696
05:42:27 5787 8192000000 9963606448 10354892800 1029522752 11.164 99 50 99 2 1069758064 29126928 11108384
05:43:36 6303 8192000000 9975277440 10377867264 1057431152 11.432 100 29 58 0 1082195792 18552928 6211712
05:44:43 7371 8192000000 9983888976 10380324864 1068383280 11.537 100 30 60 1 1091933680 16493568 7056832
05:45:51 38407 8192000000 10006899712 10398490624 1082109024 11.668 100 39 78 3 1115319360 24987392 8222944
05:47:32 20124 8192000000 10048029504 10458402816 1106380496 11.898 100 41 82 0 1162675136 47997920 8296720
05:49:10 25489 8192000000 10110144352 10521272320 1125623152 12.080 100 59 118 1 1210399456 73074816 11701488
05:50:41 10110 8192000000 10141188032 10546601984 1141816016 12.233 100 60 120 0 1237693200 83147200 12729984
05:51:56 13417 8192000000 10133651072 10543190016 1151480064 12.323 100 47 93 2 1244445984 83217984 9747936
05:53:13 10146 8192000000 10194329472 10611249152 1158957856 12.394 100 52 104 3 1305314352 134786368 11570128
05:54:28 18403 8192000000 10222729472 10648186880 1172956960 12.524 100 46 92 2 1336578272 152963712 10657600
05:55:51 11578 8192000000 10259392864 10674716672 1168760032 12.485 99 60 116 2 1350722768 168629520 13333216
05:57:12 3874 8192000000 10287003088 10695372800 1189074816 12.675 100 77 152 1 1395574432 189344848 17154768
05:58:22 4579 8192000000 10372945424 10779287552 1196654272 12.745 100 80 160 4 1469385872 254922320 17809280
05:59:36 1272 8192000000 10413319664 10822905856 1205223984 12.825 100 89 174 1 1509470208 283997088 20249136
06:00:42 2872 8192000000 10413621232 10828091392 1190605216 12.689 99 85 168 0 1512879200 303074048 19199936
06:01:49 10464 8192000000 10407210064 10844098560 1179132384 12.582 99 42 83 1 1518739984 328991680 10615920
06:03:09 3150 8192000000 10445312624 10884923392 1184156272 12.629 100 65 130 1 1559589856 360113216 15320368
06:04:15 4035 8192000000 10425005792 10845884416 1151537296 12.324 100 82 162 2 1517767312 346553072 19676944
06:05:21 11393 8192000000 10304311760 10727407616 1135588640 12.174 99 32 63 1 1395319552 251563232 8167680
06:06:39 26517 8192000000 10257506464 10707968000 1113293824 11.964 99 29 58 1 1368424592 247893152 7237616
06:08:06 41190 8192000000 10267290368 10688565248 1090974256 11.752 97 23 46 1 1326932016 230215152 5742608
06:10:30 28183 8192000000 10267695856 10706735104 1082247552 11.669 97 32 63 3 1381453792 291130816 8075424
06:11:59 45200 8192000000 10033683824 10447454208 1089365040 11.737 99 29 59 0 1144467520 49559680 5542800
06:13:55 177980 8192000000 10065723232 10520309760 1073971376 11.590 96 36 72 0 1114621152 33675952 6973824
06:17:54 102735 8192000000 10075350400 10549596160 1090324624 11.746 96 13 26 1 1151337648 58445632 2567392
06:21:07 86487 8192000000 10111380912 10559209472 1116664112 11.995 99 26 52 0 1202340080 79572384 6103584
06:23:37 41954 8192000000 10066097072 10502316032 1125745808 12.081 100 11 22 0 1197680720 69313168 2621744
06:25:21 82995 8192000000 10116220336 10552733696 1128203728 12.104 100 21 42 2 1207300240 74722160 4374352
06:27:51 32293 8192000000 10137539744 10565959680 1142400416 12.238 100 48 95 1 1242836944 90448464 9988064
06:29:36 36764 8192000000 10094716816 10526572544 1136503616 12.183 100 35 70 1 1202021744 58505456 7012672
06:31:20 40319 8192000000 10143075536 10591662080 1148081440 12.291 100 44 88 1 1220695840 63654944 8959456
06:33:31 24333 8192000000 10120413712 10549350400 1153400912 12.341 100 48 94 0 1212030128 48490096 10139120
06:34:57 110137 8192000000 10061027248 10510913536 1142431568 12.238 99 27 54 3 1178168304 30119136 5617600
06:37:58 76350 8192000000 10098471696 10571800576 1140577888 12.221 99 6 12 0 1223325376 81511856 1235632
06:40:28 56158 8192000000 10130872464 10607849472 1157907264 12.384 100 9 18 0 1261794096 101511152 2375680
06:42:31 67180 8192000000 10154096576 10618392576 1153291696 12.340 99 17 35 1 1202468192 45730336 3446160
06:44:46 73244 8192000000 10083604368 10561462272 1114851536 11.978 97 10 20 1 1184707056 67899520 1956000
06:47:06 64433 8192000000 10062603072 10520141824 1088932368 11.733 95 12 24 0 1121408304 30652640 1823296
06:49:10 99591 8192000000 10129690064 10586849280 1122106080 12.047 98 8 16 1 1179966160 56240528 1619552
06:52:00 63798 8192000000 10088866336 10529116160 1079362208 11.641 94 17 34 0 1124728608 41861968 3504432
06:54:04 108242 8192000000 10060656224 10530545664 1109200864 11.925 99 38 77 0 1168815040 51456640 8157536
06:56:53 40925 8192000000 10085052576 10547146752 1125488112 12.079 100 16 32 0 1195653264 66967936 3197216
06:59:24 53742 8192000000 10030123872 10485866496 1083140816 11.677 98 18 36 0 1135644576 48862288 3641472
07:01:31 78544 8192000000 10100587344 10580340736 1099558944 11.833 98 19 38 0 1168296624 64079712 4657968
07:04:09 45295 8192000000 10054996496 10494509056 1031162896 11.180 93 13 26 0 1098754848 64837760 2754192
07:05:55 58961 8192000000 10106239232 10569977856 1090962112 11.752 99 22 44 0 1185947760 89937376 5048272
07:07:54 54225 8192000000 10113400928 10576506880 1105717392 11.892 100 20 37 1 1204744288 94755072 4271824
07:09:50 42535 8192000000 10141111840 10673782784 1123690400 12.062 100 19 38 0 1289903392 162131344 4081648
07:11:40 53101 8192000000 10079879344 10587267072 1111822144 11.950 100 10 20 0 1170455920 56772256 1861520
07:13:36 107556 8192000000 10091633152 10548551680 1116412032 11.993 100 37 75 0 1169672128 44977968 8282128
07:16:31 68730 8192000000 10102900416 10598486016 1132885936 12.149 100 14 28 2 1229539440 94193088 2460416
07:18:39 87839 8192000000 10094328896 10611322880 1136172304 12.180 100 17 34 0 1240138656 100291808 3674544
07:21:07 61072 8192000000 10115732560 10561732608 1122995984 12.055 98 25 48 0 1165201824 37032816 5173024
07:23:09 78422 8192000000 10196206320 10709573632 1157055376 12.376 100 23 46 0 1275098096 113017408 5025312
07:25:56 35275 8192000000 10102115152 10555318272 1149578112 12.306 100 27 54 0 1215935952 60165504 6192336
07:27:34 24213 8192000000 10107956000 10547838976 1149702528 12.307 100 37 74 1 1193943808 37081760 7159520
07:29:01 70686 8192000000 10118405440 10564042752 1140361424 12.219 99 28 56 1 1198658576 52932112 5365040
07:31:16 95843 8192000000 10142915968 10682155008 1145175872 12.264 99 7 14 1 1218214816 71842832 1196112
07:35:01 70214 8192000000 10094865472 10541629440 1164036016 12.441 100 25 49 2 1198356416 28942144 5378256
07:37:12 89482 8192000000 10093586672 10569838592 1136882480 12.186 98 9 18 0 1179159152 40451280 1825392
07:40:21 70009 8192000000 10126825888 10584481792 1147165728 12.283 98 14 28 0 1209707408 59365888 3175792
07:43:18 53783 8192000000 10098408368 10567053312 1144532608 12.258 99 11 22 2 1188160032 41296000 2331424
07:45:13 67590 8192000000 9299060544 9622351872 396515584 4.616 35 8 16 0 441807088 43781104 1510400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 16-11-2021 07:47:46 to 16-11-2021 07:48:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 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 16-11-2021 07:48:01 to 16-11-2021 07:48:03.

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          225154
MON$OLDEST_ACTIVE               225155
MON$OLDEST_SNAPSHOT             225155
MON$NEXT_TRANSACTION            225155
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                       2046688
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

16.11.21 07:48:04. 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-11-16 07:48:06 No severe PSQL-related problems occured
16.11.21 07:48:07. End of report.