Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.10.2021 10:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15731780 kB
MemAvailable 30722116 kB
Buffers 0 kB
Cached 15963004 kB
SwapCached 4780 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-06 10:38:37
DTS_END                         2021-10-06 10:37:08.5240
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/57272
TRN_ID                          240168
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: 9103.63 21980 1092435
customer order: creation 174.11 411 20893
customer order: refuse 15.70 309 1884
order to supplier: creation 46.33 1536 5559
order to supplier: removal 3.28 111617 393
invoice (draft): creation 47.58 3563 5709
invoice (draft): removal 51.39 952 6167
invoice accept: apply 41.43 17976 4971
invoice accept: cancel 45.88 779 5505
customer reserve: creation 3854.70 177 462564
customer reserve: removal 4532.24 63 543869
realization accept: apply 84.33 193 10120
realization accept: cancel 76.69 133 9203
payment from customer: creation 60.62 123 7274
payment from customer: removal 5.79 77 695
payment to supplier: creation 49.77 139 5972
payment to supplier: removal 3.38 67 405
service: total inventory turnovers 3.62 3588 434
service: total monetary turnovers 5.86 498 703
service: aggregate perf. data 0.93 16000 111
service: refresh index statistics 0.03 281396 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-10-2021 10:39:54 to 06-10-2021 10:39:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4537
TEST_TIME 2 5364
TEST_TIME 3 4692
TEST_TIME 4 4361
TEST_TIME 5 4628
TEST_TIME 6 4372
TEST_TIME 7 4535
TEST_TIME 8 4434
TEST_TIME 9 4686
TEST_TIME 10 5145
TEST_TIME 11 5317
TEST_TIME 12 5784
TEST_TIME 13 6391
TEST_TIME 14 6731
TEST_TIME 15 6881
TEST_TIME 16 7567
TEST_TIME 17 7958
TEST_TIME 18 8038
TEST_TIME 19 8413
TEST_TIME 20 8734
TEST_TIME 21 9088
TEST_TIME 22 9755
TEST_TIME 23 9657
TEST_TIME 24 10067
TEST_TIME 25 9996
TEST_TIME 26 10377
TEST_TIME 27 10889
TEST_TIME 28 11010
TEST_TIME 29 10917
TEST_TIME 30 10727
TEST_TIME 31 10912
TEST_TIME 32 10872
TEST_TIME 33 10890
TEST_TIME 34 10766
TEST_TIME 35 10506
TEST_TIME 36 10395
TEST_TIME 37 10182
TEST_TIME 38 10132
TEST_TIME 39 10091
TEST_TIME 40 10052
TEST_TIME 41 9985
TEST_TIME 42 9996
TEST_TIME 43 9865
TEST_TIME 44 9808
TEST_TIME 45 9752
TEST_TIME 46 9730
TEST_TIME 47 9688
TEST_TIME 48 9607
TEST_TIME 49 9535
TEST_TIME 50 9627
TEST_TIME 51 9660
TEST_TIME 52 9721
TEST_TIME 53 9759
TEST_TIME 54 9777
TEST_TIME 55 9773
TEST_TIME 56 9735
TEST_TIME 57 9695
TEST_TIME 58 9717
TEST_TIME 59 9863
TEST_TIME 60 9883
TEST_TIME 61 9740
TEST_TIME 62 9739
TEST_TIME 63 9719
TEST_TIME 64 9680
TEST_TIME 65 9608
TEST_TIME 66 9542
TEST_TIME 67 9521
TEST_TIME 68 9480
TEST_TIME 69 9513
TEST_TIME 70 9522
TEST_TIME 71 9493
TEST_TIME 72 9569
TEST_TIME 73 9538
TEST_TIME 74 9661
TEST_TIME 75 9622
TEST_TIME 76 9601
TEST_TIME 77 9599
TEST_TIME 78 9576
TEST_TIME 79 9506
TEST_TIME 80 9456
TEST_TIME 81 9389
TEST_TIME 82 9412
TEST_TIME 83 9371
TEST_TIME 84 9315
TEST_TIME 85 9250
TEST_TIME 86 9226
TEST_TIME 87 9242
TEST_TIME 88 9240
TEST_TIME 89 9232
TEST_TIME 90 9254
TEST_TIME 91 9265
TEST_TIME 92 9274
TEST_TIME 93 9285
TEST_TIME 94 9266
TEST_TIME 95 9278
TEST_TIME 96 9304
TEST_TIME 97 9317
TEST_TIME 98 9319
TEST_TIME 99 9337
TEST_TIME 100 9354
TEST_TIME 101 9298
TEST_TIME 102 9287
TEST_TIME 103 9233
TEST_TIME 104 9226
TEST_TIME 105 9185
TEST_TIME 106 9174
TEST_TIME 107 9126
TEST_TIME 108 9103
TEST_TIME 109 9092
TEST_TIME 110 9100
TEST_TIME 111 9128
TEST_TIME 112 9107
TEST_TIME 113 9123
TEST_TIME 114 9136
TEST_TIME 115 9151
TEST_TIME 116 9170
TEST_TIME 117 9234
TEST_TIME 118 9207
TEST_TIME 119 9184
TEST_TIME 120 9108

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-10-2021 10:39:58 to 06-10-2021 10:40:01.

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 1519832 1519832 0 0.00 0 26376 7 0 0
doc_list_biud 1519830 1519830 0 0.00 0 26274 29 0 0
sp_add_invoice_to_stock 5074 4971 103 2.02 50 186592 17976 0 103
sp_cancel_adding_invoice 5505 5505 0 0.00 5 46724 780 0 0
sp_cancel_client_order 1884 1884 0 0.00 5 8879 309 0 0
sp_cancel_customer_reserve 543869 543869 0 0.00 1 19665 63 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 2 6441 77 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 2362 67 0 0
sp_cancel_supplier_invoice 6180 6167 13 0.21 2 16175 953 0 13
sp_cancel_supplier_order 393 393 0 0.00 9 1015846 111618 0 0
sp_cancel_write_off 9203 9203 0 0.00 1 26292 134 0 0
sp_client_order 20893 20893 0 0.00 4 23232 412 0 0
sp_customer_reserve 462564 462564 0 0.00 0 134052 177 0 0
sp_fill_shopping_cart 32149 32149 0 0.00 1 15486 47 0 0
sp_fill_shopping_cart_clo_res 462554 462554 0 0.00 0 16209 15 0 0
sp_get_clo_for_invoice 4961 4961 0 0.00 0 26649 344 0 0
sp_kill_cost_storno 15808 15808 0 0.00 0 9367 59 0 0
sp_kill_qstorno_ret_qs2qd 1103501 1103501 0 0.00 0 26273 35 0 0
sp_kill_qty_storno 582100 582100 0 0.00 0 26273 75 0 0
sp_lock_selected_doc 21070 21070 0 0.00 0 7463 5 0 0
sp_make_cost_storno 28327 28327 0 0.00 0 21478 62 0 0
sp_multiply_rows_for_pdistr 28325 28325 0 0.00 0 12926 35 0 0
sp_multiply_rows_for_qdistr 20893 20893 0 0.00 0 23225 225 0 0
sp_pay_from_customer 7274 7274 0 0.00 4 17010 124 0 0
sp_pay_to_supplier 5972 5972 0 0.00 5 17024 140 0 0
sp_payment_common 13246 13246 0 0.00 1 11574 28 0 0
sp_qd_handle_on_cancel_clo 1884 1884 0 0.00 0 8868 286 0 0
sp_qd_handle_on_invoice_upd_sts 10464 10464 0 0.00 0 17857 329 0 0
sp_qd_handle_on_reserve_upd_sts 10120 10120 0 0.00 0 15408 59 0 0
sp_reserve_write_off 10120 10120 0 0.00 7 21095 194 0 0
sp_supplier_invoice 5709 5709 0 0.00 7 87787 3563 0 0
sp_supplier_order 5559 5559 0 0.00 5 40451 1536 0 0
srv_aggregate_perf_data 111 111 0 0.00 3000 208137 16001 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1892 164301 48959 0 0
srv_find_qd_qs_mism 1043798 1043798 0 0.00 0 26375 6 0 0
srv_make_invnt_saldo 434 434 0 0.00 58 117881 3589 0 0
srv_make_money_saldo 703 703 0 0.00 1 11726 498 0 0
srv_recalc_idx_stat 4 4 0 0.00 208712 410815 281396 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4971 4971 0 0.00 0 19351 159 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 3989 95 0 0
t$perf-norm:sp_cancel_client_order 1884 1884 0 0.00 0 16079 25 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 3912 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 6888 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 348 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 792 11 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 54484 1924 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 6680 13 0 0
t$perf-norm:sp_client_order 20893 20893 0 0.00 0 17817 22 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 4976 13 0 0
t$perf-norm:sp_pay_from_customer 7274 7274 0 0.00 0 6883 12 0 0
t$perf-norm:sp_pay_to_supplier 5972 5972 0 0.00 0 6439 16 0 0
t$perf-norm:sp_reserve_write_off 10120 10120 0 0.00 0 7101 14 0 0
t$perf-norm:sp_supplier_invoice 5709 5709 0 0.00 0 8678 22 0 0
t$perf-norm:sp_supplier_order 5559 5559 0 0.00 0 9722 19 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 307 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 10 1 0 0
t$perf-norm:srv_make_invnt_saldo 434 434 0 0.00 0 735 8 0 0
t$perf-norm:srv_make_money_saldo 703 703 0 0.00 0 5872 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10122 9916 206 2.03 0 4644 16 0 206
v_all_customers 38460 38460 0 0.00 0 47 1 0 0
v_all_suppliers 31376 31376 0 0.00 0 18 1 0 0
v_all_wares 41786 41786 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 1098 7 0 0
v_cancel_client_order 3768 3768 0 0.00 0 1755 3 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 1078 6 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 2935 8 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 2347 6 0 0
v_cancel_supplier_invoice 890 864 26 2.92 0 844 13 0 26
v_cancel_supplier_order 786 786 0 0.00 0 2752 16 0 0
v_cancel_write_off 2096 2096 0 0.00 0 313 12 0 0
v_max_id_clo_ord 5559 5559 0 0.00 0 1343 1 0 0
v_max_id_clo_res 998 998 0 0.00 0 2430 21 0 0
v_max_id_ord_sup 5697 5697 0 0.00 0 5458 7 0 0
v_max_non_paid_invoice 1540 1540 0 0.00 0 302 2 0 0
v_max_non_paid_realizn 3720 3720 0 0.00 0 2298 3 0 0
v_min_id_clo_ord 5559 5559 0 0.00 0 1837 6 0 0
v_min_id_clo_res 998 998 0 0.00 0 3418 10 0 0
v_min_id_ord_sup 5697 5697 0 0.00 0 8399 19 0 0
v_min_non_paid_invoice 5972 5972 0 0.00 0 10639 22 0 0
v_min_non_paid_realizn 7274 7274 0 0.00 0 9356 21 0 0
v_our_firm 10434 10434 0 0.00 0 18 1 0 0
v_reserve_write_off 20240 20240 0 0.00 0 3162 6 0 0
x_lock_depdocs_on_canc_invoice 11240 11240 0 0.00 0 10559 109 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 7329 315 0 0
x_make_qstorno_1000_1200 5559 5559 0 0.00 2 40432 1459 0 0
x_make_qstorno_1200_2000 5709 5709 0 0.00 2 84620 3381 0 0
x_make_qstorno_2100_3300 462564 462564 0 0.00 0 133995 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-10-2021 10:40:02 to 06-10-2021 10:40:06.

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
08:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:13 31416 8192000000 9714106768 10012192768 795504000 8.851 100 86 172 0 824642976 8090640 21048336
08:09:52 29199 8192000000 9757912224 10065805312 836174320 9.261 100 92 184 0 872806208 14813184 21818704
08:11:26 12686 8192000000 9819346112 10140667904 879378080 9.693 100 76 152 1 922802976 26216576 17208320
08:12:49 18011 8192000000 9906519408 10242736128 934887200 10.243 100 87 174 0 1009361968 56350064 18124704
08:14:13 9345 8192000000 9947955888 10298699776 977178944 10.657 100 78 154 1 1062924368 69366448 16378976
08:15:24 3870 8192000000 9966406720 10323640320 1004796784 10.925 100 87 170 4 1093340528 70131808 18411936
08:16:31 2262 8192000000 10016729904 10378547200 1028381648 11.153 100 92 182 2 1130463184 83818256 18263280
08:17:35 2594 8192000000 10005862640 10378407936 1052928880 11.389 100 86 170 2 1122087776 51929552 17229344
08:18:39 2725 8192000000 10066822080 10437906432 1063057808 11.486 100 92 181 1 1177779440 96537856 18183776
08:19:43 6616 8192000000 9991005376 10382098432 1069529856 11.548 100 54 105 1 1126493712 44806048 12157808
08:20:50 7424 8192000000 10000971648 10394918912 1073561072 11.586 100 35 70 1 1127864048 46909856 7393120
08:21:59 6076 8192000000 10009648800 10406920192 1085565536 11.700 100 47 91 2 1114753824 19325936 9862352
08:23:09 14401 8192000000 9991773776 10403160064 1093799312 11.779 100 44 87 0 1131776432 28614256 9362864
08:24:24 17479 8192000000 10009613248 10424586240 1097164032 11.811 100 17 35 0 1118937360 18235536 3537792
08:25:42 9856 8192000000 10031801744 10429394944 1090190528 11.744 99 30 58 3 1111834976 15476448 6168000
08:26:59 4217 8192000000 10008918448 10403127296 1093398880 11.775 100 50 99 1 1118699216 14002144 11298192
08:28:04 55398 8192000000 9969518336 10368540672 1019043072 11.063 95 32 64 0 1045906864 19669696 7194096
08:30:04 6654 8192000000 9931015008 10315268096 950713152 10.398 92 35 70 0 991755648 33861488 7181008
08:31:12 6308 8192000000 9951471232 10329407488 1022474384 11.096 99 53 106 0 1058910112 23985952 12449776
08:32:22 5932 8192000000 9940842064 10324987904 1030294976 11.171 100 50 101 1 1055700128 13661488 11743664
08:33:30 22999 8192000000 9949602960 10357710848 1031823408 11.186 99 37 73 1 1060589696 20211728 8554560
08:34:55 10126 8192000000 9969902992 10380169216 1056535552 11.423 100 47 94 1 1082659344 15565696 10558096
08:36:07 7150 8192000000 9980216976 10375008256 1061432848 11.470 100 35 70 1 1088942976 19827536 7682592
08:37:17 10664 8192000000 9997903360 10391629824 1082449744 11.671 100 53 104 2 1105801520 12635120 10716656
08:38:32 84433 8192000000 10037424048 10455031808 1104291824 11.878 100 36 72 0 1184064912 71901232 7871856
08:41:21 32926 8192000000 10122498016 10527776768 1131116048 12.132 100 61 122 1 1216200128 71897008 13187072
08:43:03 24904 8192000000 10170748848 10577907712 1148220976 12.293 100 52 104 0 1271200480 111176960 11802544
08:44:32 21033 8192000000 10225756560 10640998400 1158314848 12.387 100 52 104 0 1328977504 157913168 12749488
08:46:02 5998 8192000000 10248089264 10656112640 1142225536 12.236 98 61 121 3 1346380064 190310160 13844368
08:47:13 8010 8192000000 10306398672 10727743488 1172123168 12.517 100 73 144 1 1409331616 220760256 16448192
08:48:30 1891 8192000000 10330306096 10740854784 1187372816 12.659 100 81 161 1 1433190112 227484960 18332336
08:49:36 4405 8192000000 10348071088 10768633856 1185202336 12.639 100 65 130 1 1453368912 252945984 15220592
08:50:46 3178 8192000000 10349633744 10762133504 1158285872 12.387 98 69 138 1 1441679600 267514096 15879632
08:51:56 2849 8192000000 10366847664 10780127232 1152850400 12.336 98 65 130 1 1459464496 291231840 15382256
08:53:01 2377 8192000000 10374191056 10791862272 1156990832 12.375 100 78 154 0 1473934256 298731584 18211840
08:54:06 6785 8192000000 10365372416 10779926528 1147532272 12.286 100 54 104 1 1463921040 303851424 12537344
08:55:19 5454 8192000000 10346464048 10775625728 1133863472 12.158 99 51 101 0 1441394688 295037088 12494128
08:56:34 4217 8192000000 10353308016 10773106688 1147294224 12.284 100 77 155 2 1455932912 291055472 17583216
08:57:40 5673 8192000000 10344155904 10773323776 1128484224 12.107 100 41 82 0 1446208640 307327904 10396512
08:59:02 17786 8192000000 10327682032 10775203840 1122940160 12.055 100 32 63 0 1419920592 288400624 8579808
09:00:46 27895 8192000000 10228500544 10684170240 1102859616 11.865 98 17 34 2 1306950768 199633040 4458112
09:02:16 21022 8192000000 10205955888 10668609536 1123706192 12.062 100 21 42 0 1301135552 172127552 5301808
09:04:17 30813 8192000000 10150078816 10645385216 1097623968 11.815 98 12 24 0 1228234800 127756288 2854544
09:05:49 76154 8192000000 10072270656 10498187264 1093156432 11.773 97 29 57 0 1138805952 39963312 5686208
09:08:08 81343 8192000000 10097609200 10557333504 1100910480 11.846 97 16 32 0 1176916400 72898896 3107024
09:10:31 89645 8192000000 10063477744 10515582976 1087222128 11.716 96 20 38 1 1134845696 43835664 3787904
09:13:07 164300 8192000000 10096364784 10547683328 1088064032 11.724 96 29 56 1 1115585232 21592544 5928656
09:17:04 37103 8192000000 10113989456 10565853184 1151024976 12.319 100 34 67 2 1212664800 54810560 6829264
09:18:49 45465 8192000000 10153797360 10614222848 1138448096 12.201 99 20 40 1 1232870992 89497168 4925728
09:20:35 44540 8192000000 10083403008 10526470144 1109124768 11.924 97 10 21 0 1163240768 51894848 2221152
09:22:20 75036 8192000000 10083878800 10532417536 1137191712 12.189 100 14 28 0 1215366048 75000352 3173984
09:24:47 61489 8192000000 10109293840 10575196160 1147242112 12.284 100 8 16 0 1210237808 61310016 1685680
09:26:50 52336 8192000000 10107319536 10565033984 1130948912 12.130 98 28 56 1 1170091120 32924816 6217392
09:28:46 52692 8192000000 10088674624 10566205440 1126110096 12.085 98 15 30 0 1201389568 72074128 3205344
09:30:42 88570 8192000000 10073893632 10531921920 1087849168 11.722 95 15 30 0 1125580832 35007328 2724336
09:33:16 12430 8192000000 10087561760 10515849216 1128538352 12.108 100 59 120 0 1181180016 40501712 12139952
09:34:30 43360 8192000000 10161185360 10598699008 1091328576 11.755 97 33 66 0 1196546272 98428880 6788816
09:36:14 89665 8192000000 10090086544 10563223552 1102860640 11.865 98 15 30 0 1220501392 114625520 3015232
09:38:55 94460 8192000000 10038673696 10510630912 1102221216 11.859 97 18 36 0 1152279376 46084672 3973488
09:41:58 65797 8192000000 10050461936 10490982400 1092859552 11.770 97 20 40 0 1140683664 44033472 3790640
09:44:05 89746 8192000000 10062687360 10502852608 1066664400 11.520 95 12 22 0 1115283776 45677280 2942096
09:46:42 42203 8192000000 10101518528 10540285952 1102208560 11.859 99 23 46 0 1186542528 79393248 4940720
09:48:30 80705 8192000000 10098382480 10542026752 1120869712 12.035 99 17 34 0 1163248272 38368032 4010528
09:50:53 95547 8192000000 10075841280 10529652736 1097303568 11.812 98 19 38 1 1163952096 62689280 3959248
09:53:29 16971 8192000000 10091400432 10526023680 1120882112 12.035 100 67 133 1 1165302624 31409680 13010832
09:54:48 21243 8192000000 10092040368 10512961536 1130042256 12.122 100 45 90 0 1180158496 40681552 9434688
09:56:11 95020 8192000000 10066058464 10509451264 1109886128 11.931 98 40 80 0 1149213088 30846656 8480304
09:59:04 46594 8192000000 10063117040 10546741248 1110790720 11.940 98 18 36 1 1179929632 64932480 4206432
10:01:41 30568 8192000000 10071551664 10523877376 1124967744 12.074 99 15 29 0 1162466272 34713488 2785040
10:03:15 89449 8192000000 10074363936 10591027200 1090507936 11.747 96 12 24 0 1158014576 64978464 2528176
10:05:45 61363 8192000000 10103536656 10569789440 1128532752 12.108 99 18 36 0 1190217104 57973936 3710416
10:07:56 46648 8192000000 10064009472 10511306752 1125389792 12.078 98 16 32 0 1181360016 52983648 2986576
10:09:46 81133 8192000000 10075500640 10521526272 1107710576 11.911 96 13 26 0 1136344704 26210496 2423632
10:12:10 104178 8192000000 10105789328 10586660864 1152687040 12.335 100 14 28 1 1234976128 79553008 2736080
10:14:54 76344 8192000000 10093548544 10574610432 1106676272 11.901 96 13 26 0 1149787040 40334912 2775856
10:17:15 96521 8192000000 10086546224 10602717184 1110379376 11.936 97 10 20 1 1189750800 77393200 1978224
10:20:00 39321 8192000000 10067483856 10514788352 1123984592 12.065 99 28 56 0 1149106128 19222672 5898864
10:21:39 42117 8192000000 10050483296 10525306880 1113125296 11.962 99 7 13 0 1165193632 50879216 1189120
10:23:31 61259 8192000000 10099902064 10582630400 1115682544 11.986 98 28 56 0 1172812992 50828560 6301888
10:25:46 63428 8192000000 10108431824 10574827520 1099367280 11.832 97 11 22 0 1168982688 67315328 2300080
10:27:50 58899 8192000000 10087590512 10549850112 1120352080 12.030 99 19 38 1 1186536528 62056240 4128208
10:29:57 69471 8192000000 10114653040 10577575936 1132618704 12.146 99 16 32 0 1210362432 73872496 3871232
10:32:06 67175 8192000000 10128151264 10608152576 1107447792 11.908 98 14 28 0 1192404624 81902768 3054064
10:34:14 41207 8192000000 10050945392 10533314560 1090346384 11.746 98 23 46 1 1178913648 83511440 5055824
10:35:56 21517 8192000000 10092219312 10524962816 1109323328 11.926 100 48 96 0 1174585152 55852528 9409296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-10-2021 10:40:08 to 06-10-2021 10:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-10-2021 10:40:23 to 06-10-2021 10:40:25.

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          254904
MON$OLDEST_ACTIVE               254905
MON$OLDEST_SNAPSHOT             254905
MON$NEXT_TRANSACTION            254905
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                       2056352
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             815
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

06.10.21 10:40:26. 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-06 10:40:29 No severe PSQL-related problems occured
06.10.21 10:40:30. End of report.