Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.10.2021 13: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 15985796 kB
MemAvailable 30842428 kB
Buffers 0 kB
Cached 15697244 kB
SwapCached 4792 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-27 13:37:07
DTS_END                         2021-10-27 13:37:07.9810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49796
TRN_ID                          226172
ATT_ID                          657
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: 8782.44 22869 1053893
customer order: creation 157.87 462 18944
customer order: refuse 13.73 347 1647
order to supplier: creation 43.66 1560 5239
order to supplier: removal 3.33 126994 400
invoice (draft): creation 41.58 3969 4990
invoice (draft): removal 51.29 1140 6155
invoice accept: apply 39.03 20197 4684
invoice accept: cancel 46.67 950 5600
customer reserve: creation 3570.13 200 428416
customer reserve: removal 4537.36 71 544483
realization accept: apply 77.26 208 9271
realization accept: cancel 82.13 119 9855
payment from customer: creation 54.62 147 6554
payment from customer: removal 5.45 113 654
payment to supplier: creation 44.83 163 5379
payment to supplier: removal 3.50 85 420
service: total inventory turnovers 3.53 3588 424
service: total monetary turnovers 5.63 658 675
service: aggregate perf. data 0.81 16254 97
service: refresh index statistics 0.05 280163 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-10-2021 13:38:01 to 27-10-2021 13:38:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4430
TEST_TIME 2 5359
TEST_TIME 3 4888
TEST_TIME 4 4573
TEST_TIME 5 4294
TEST_TIME 6 4222
TEST_TIME 7 4952
TEST_TIME 8 5119
TEST_TIME 9 5165
TEST_TIME 10 5378
TEST_TIME 11 5660
TEST_TIME 12 6155
TEST_TIME 13 6482
TEST_TIME 14 6584
TEST_TIME 15 7330
TEST_TIME 16 7750
TEST_TIME 17 8167
TEST_TIME 18 8167
TEST_TIME 19 8012
TEST_TIME 20 8038
TEST_TIME 21 8415
TEST_TIME 22 8580
TEST_TIME 23 8706
TEST_TIME 24 8641
TEST_TIME 25 9695
TEST_TIME 26 11037
TEST_TIME 27 11338
TEST_TIME 28 11344
TEST_TIME 29 11125
TEST_TIME 30 10774
TEST_TIME 31 10467
TEST_TIME 32 10266
TEST_TIME 33 10224
TEST_TIME 34 10225
TEST_TIME 35 10069
TEST_TIME 36 9997
TEST_TIME 37 10015
TEST_TIME 38 10057
TEST_TIME 39 9966
TEST_TIME 40 9871
TEST_TIME 41 9768
TEST_TIME 42 9542
TEST_TIME 43 9605
TEST_TIME 44 9526
TEST_TIME 45 9496
TEST_TIME 46 9376
TEST_TIME 47 9283
TEST_TIME 48 9210
TEST_TIME 49 9153
TEST_TIME 50 9238
TEST_TIME 51 9118
TEST_TIME 52 9128
TEST_TIME 53 9205
TEST_TIME 54 9207
TEST_TIME 55 9174
TEST_TIME 56 9222
TEST_TIME 57 9161
TEST_TIME 58 9206
TEST_TIME 59 9322
TEST_TIME 60 9336
TEST_TIME 61 9282
TEST_TIME 62 9257
TEST_TIME 63 9136
TEST_TIME 64 9026
TEST_TIME 65 9069
TEST_TIME 66 9030
TEST_TIME 67 8969
TEST_TIME 68 8894
TEST_TIME 69 8852
TEST_TIME 70 8884
TEST_TIME 71 8950
TEST_TIME 72 8910
TEST_TIME 73 8867
TEST_TIME 74 8866
TEST_TIME 75 8984
TEST_TIME 76 9016
TEST_TIME 77 9052
TEST_TIME 78 9080
TEST_TIME 79 9159
TEST_TIME 80 9161
TEST_TIME 81 9117
TEST_TIME 82 9052
TEST_TIME 83 9000
TEST_TIME 84 8961
TEST_TIME 85 8943
TEST_TIME 86 8881
TEST_TIME 87 8831
TEST_TIME 88 8794
TEST_TIME 89 8822
TEST_TIME 90 8826
TEST_TIME 91 8825
TEST_TIME 92 8792
TEST_TIME 93 8781
TEST_TIME 94 8837
TEST_TIME 95 8824
TEST_TIME 96 8835
TEST_TIME 97 8891
TEST_TIME 98 8947
TEST_TIME 99 8952
TEST_TIME 100 8996
TEST_TIME 101 9008
TEST_TIME 102 9035
TEST_TIME 103 8976
TEST_TIME 104 8920
TEST_TIME 105 8893
TEST_TIME 106 8913
TEST_TIME 107 8865
TEST_TIME 108 8852
TEST_TIME 109 8821
TEST_TIME 110 8761
TEST_TIME 111 8796
TEST_TIME 112 8765
TEST_TIME 113 8746
TEST_TIME 114 8738
TEST_TIME 115 8837
TEST_TIME 116 8797
TEST_TIME 117 8842
TEST_TIME 118 8861
TEST_TIME 119 8854
TEST_TIME 120 8785

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-10-2021 13:38:05 to 27-10-2021 13:38:08.

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 1450815 1450815 0 0.00 0 32425 7 0 0
doc_list_biud 1450813 1450813 0 0.00 0 53881 35 0 0
sp_add_invoice_to_stock 4807 4684 123 2.55 41 227738 20198 0 123
sp_cancel_adding_invoice 5600 5600 0 0.00 6 78695 951 0 0
sp_cancel_client_order 1647 1647 0 0.00 5 10366 347 0 0
sp_cancel_customer_reserve 544483 544483 0 0.00 1 48554 71 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 3 20652 113 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 3339 85 0 0
sp_cancel_supplier_invoice 6161 6155 6 0.09 3 53889 1141 0 6
sp_cancel_supplier_order 400 400 0 0.00 7 1253035 126995 0 0
sp_cancel_write_off 9855 9855 0 0.00 1 18557 120 0 0
sp_client_order 18944 18944 0 0.00 4 36051 462 0 0
sp_customer_reserve 428416 428416 0 0.00 0 52298 201 0 0
sp_fill_shopping_cart 29157 29157 0 0.00 1 18295 56 0 0
sp_fill_shopping_cart_clo_res 428402 428402 0 0.00 0 13968 18 0 0
sp_get_clo_for_invoice 4670 4670 0 0.00 0 30167 425 0 0
sp_kill_cost_storno 16529 16529 0 0.00 0 32413 66 0 0
sp_kill_qstorno_ret_qs2qd 1105376 1105376 0 0.00 0 53879 39 0 0
sp_kill_qty_storno 582081 582081 0 0.00 0 53880 85 0 0
sp_lock_selected_doc 19281 19281 0 0.00 0 6048 5 0 0
sp_make_cost_storno 25874 25874 0 0.00 0 15346 68 0 0
sp_multiply_rows_for_pdistr 25874 25874 0 0.00 0 13678 38 0 0
sp_multiply_rows_for_qdistr 18944 18944 0 0.00 0 28274 236 0 0
sp_pay_from_customer 6554 6554 0 0.00 4 20668 148 0 0
sp_pay_to_supplier 5379 5379 0 0.00 5 13427 163 0 0
sp_payment_common 11933 11933 0 0.00 1 12476 37 0 0
sp_qd_handle_on_cancel_clo 1647 1647 0 0.00 0 10360 316 0 0
sp_qd_handle_on_invoice_upd_sts 10270 10270 0 0.00 0 24924 430 0 0
sp_qd_handle_on_reserve_upd_sts 9271 9271 0 0.00 0 9114 62 0 0
sp_reserve_write_off 9271 9271 0 0.00 7 13912 208 0 0
sp_supplier_invoice 4990 4990 0 0.00 8 92327 3970 0 0
sp_supplier_order 5239 5239 0 0.00 5 61405 1561 0 0
srv_aggregate_perf_data 97 97 0 0.00 2794 195597 16254 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1114 138959 44446 0 0
srv_find_qd_qs_mism 1009953 1009953 0 0.00 0 22516 6 0 0
srv_make_invnt_saldo 424 424 0 0.00 75 96907 3588 0 0
srv_make_money_saldo 675 675 0 0.00 1 14978 658 0 0
srv_recalc_idx_stat 6 6 0 0.00 166663 375846 280163 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4684 4684 0 0.00 0 12807 189 0 0
t$perf-norm:sp_cancel_adding_invoice 375 375 0 0.00 1 7938 115 0 0
t$perf-norm:sp_cancel_client_order 1647 1647 0 0.00 0 7678 17 0 0
t$perf-norm:sp_cancel_customer_reserve 624 624 0 0.00 0 5455 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 472 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 562 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 347 347 0 0.00 0 2547 27 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 27948 1707 0 0
t$perf-norm:sp_cancel_write_off 880 880 0 0.00 0 1177 6 0 0
t$perf-norm:sp_client_order 18944 18944 0 0.00 0 9182 19 0 0
t$perf-norm:sp_customer_reserve 923 923 0 0.00 0 9123 33 0 0
t$perf-norm:sp_pay_from_customer 6554 6554 0 0.00 0 8640 13 0 0
t$perf-norm:sp_pay_to_supplier 5379 5379 0 0.00 0 8583 13 0 0
t$perf-norm:sp_reserve_write_off 9271 9271 0 0.00 0 8608 18 0 0
t$perf-norm:sp_supplier_invoice 4990 4990 0 0.00 0 7337 21 0 0
t$perf-norm:sp_supplier_order 5239 5239 0 0.00 0 8304 18 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 688 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 125 3 0 0
t$perf-norm:srv_make_invnt_saldo 424 424 0 0.00 0 2604 22 0 0
t$perf-norm:srv_make_money_saldo 675 675 0 0.00 0 3111 13 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9572 9326 246 2.56 0 7495 13 0 246
v_all_customers 34416 34416 0 0.00 0 27 1 0 0
v_all_suppliers 28190 28190 0 0.00 0 98 1 0 0
v_all_wares 37888 37888 0 0.00 0 91 1 0 0
v_cancel_adding_invoice 750 750 0 0.00 0 2174 9 0 0
v_cancel_client_order 3294 3294 0 0.00 0 8403 6 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 20644 22 0 0
v_cancel_customer_reserve 1248 1248 0 0.00 0 7064 11 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 1387 7 0 0
v_cancel_supplier_invoice 706 694 12 1.69 0 3567 17 0 12
v_cancel_supplier_order 800 800 0 0.00 0 1972 9 0 0
v_cancel_write_off 1760 1760 0 0.00 0 2397 16 0 0
v_max_id_clo_ord 5235 5235 0 0.00 0 272 1 0 0
v_max_id_clo_res 923 923 0 0.00 0 6639 30 0 0
v_max_id_ord_sup 4978 4978 0 0.00 0 7211 6 0 0
v_max_non_paid_invoice 1497 1497 0 0.00 0 1448 4 0 0
v_max_non_paid_realizn 3549 3549 0 0.00 0 1568 4 0 0
v_min_id_clo_ord 5235 5235 0 0.00 0 3000 10 0 0
v_min_id_clo_res 923 923 0 0.00 0 6841 18 0 0
v_min_id_ord_sup 4978 4978 0 0.00 0 7663 12 0 0
v_min_non_paid_invoice 5379 5379 0 0.00 0 12402 25 0 0
v_min_non_paid_realizn 6554 6554 0 0.00 0 12398 28 0 0
v_our_firm 9482 9482 0 0.00 0 35 1 0 0
v_reserve_write_off 18542 18542 0 0.00 0 8404 7 0 0
x_lock_depdocs_on_canc_invoice 11408 11408 0 0.00 0 15246 124 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 13814 373 0 0
x_make_qstorno_1000_1200 5239 5239 0 0.00 2 61390 1468 0 0
x_make_qstorno_1200_2000 4990 4990 0 0.00 4 92293 3750 0 0
x_make_qstorno_2100_3300 428416 428416 0 0.00 0 51814 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-10-2021 13:38:09 to 27-10-2021 13:38:13.

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
11:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:12 20269 8192000000 9720339584 10017075200 798453728 8.881 100 86 172 0 826811408 7459168 20898512
11:09:41 16659 8192000000 9757268048 10062356480 832253440 9.222 100 90 180 0 866205312 12566928 21384944
11:11:01 17750 8192000000 9796132896 10114703360 866651984 9.567 100 83 166 0 908499360 22346816 19500560
11:12:23 15228 8192000000 9868778144 10199109632 910005872 9.997 100 78 155 1 975228448 47818576 17404000
11:13:44 7555 8192000000 9924693456 10267951104 962691232 10.515 100 76 152 0 1025747600 47169888 15886480
11:14:54 4159 8192000000 9971424336 10325807104 997190784 10.851 100 80 160 0 1089352384 75931936 16229664
11:16:02 2946 8192000000 9982357824 10344660992 1026417072 11.134 100 82 162 1 1101463888 58495328 16551488
11:17:06 2640 8192000000 10034856624 10404261888 1055074640 11.409 100 94 189 1 1148687968 73555440 20057888
11:18:09 11925 8192000000 10044475984 10422591488 1069279824 11.545 100 78 155 1 1154978272 69991936 15706512
11:19:22 5666 8192000000 9996418352 10386153472 1078949056 11.637 100 34 68 2 1114143088 28445328 6748704
11:20:29 49487 8192000000 10006561456 10398760960 1088004336 11.724 100 45 91 1 1110977280 12760448 10212496
11:22:19 5063 8192000000 10007807504 10414829568 1095593056 11.796 100 43 86 2 1119878320 15452016 8833248
11:23:25 6737 8192000000 10007624800 10410070016 1095256432 11.793 100 31 62 2 1131536192 29543888 6735872
11:24:37 6672 8192000000 10011458960 10421534720 1100196544 11.840 100 28 57 1 1124091184 17569648 6324992
11:25:45 53367 8192000000 10012804192 10416963584 1079720544 11.645 98 30 60 2 1103921056 17969856 6230656
11:27:44 5481 8192000000 9986471296 10384662528 1066164592 11.515 99 43 84 2 1093480672 17288560 10027520
11:28:51 7124 8192000000 9948509664 10336047104 1023558608 11.106 97 34 70 1 1039132912 7493152 8081152
11:30:00 7539 8192000000 9947824864 10327543808 997877008 10.858 98 29 58 2 1031899664 27697216 6325440
11:31:12 10998 8192000000 9962541808 10323607552 993707680 10.817 97 79 159 2 1038176224 26733200 17735344
11:32:23 8553 8192000000 9963099552 10345525248 1027930352 11.149 100 48 97 0 1062258384 24268944 10059088
11:33:35 6405 8192000000 9970667648 10346921984 1049659216 11.357 100 31 61 1 1077271296 20134944 7477136
11:34:43 75846 8192000000 9976424448 10378932224 1061109216 11.467 100 33 65 1 1101552640 33059968 7383456
11:36:59 7638 8192000000 9988623552 10380361728 1074080000 11.591 100 32 64 2 1094244960 13368464 6796496
11:38:13 15330 8192000000 10044554544 10438340608 1095821376 11.798 100 54 108 1 1149292368 42286000 11184992
11:39:31 9556 8192000000 10075618048 10474438656 1112708256 11.958 100 44 87 1 1181749216 60446576 8594384
11:40:44 21433 8192000000 10121013200 10525880320 1127005776 12.093 100 58 116 1 1237471936 97387392 13078768
11:42:19 51275 8192000000 10141179488 10558164992 1135746448 12.176 100 33 66 2 1260159504 117028384 7384672
11:44:17 4242 8192000000 10195695296 10609881088 1146808112 12.280 100 59 118 1 1316032448 155503312 13721024
11:45:36 1517 8192000000 10235584432 10637557760 1160451840 12.407 100 69 137 0 1350115152 174810288 14853024
11:46:40 5409 8192000000 10249284144 10665459712 1162320720 12.425 100 50 99 3 1358346576 184646096 11379760
11:47:51 2908 8192000000 10327737568 10740621312 1178899424 12.580 100 85 170 0 1429065072 230779664 19385984
11:48:58 1895 8192000000 10358147504 10753933312 1163127184 12.433 100 84 168 1 1459744608 276724128 19893296
11:50:04 8385 8192000000 10389874720 10798989312 1163398720 12.435 100 74 145 1 1495627328 315443216 16785392
11:51:15 3997 8192000000 10384024096 10787102720 1140574112 12.221 99 78 154 2 1482357232 323123936 18659184
11:52:24 5552 8192000000 10360893488 10780467200 1134654416 12.165 99 72 143 2 1471857376 319686000 17516960
11:53:36 3344 8192000000 10351235840 10746716160 1147196208 12.283 100 82 160 2 1448855072 282631600 19027264
11:54:45 1113 8192000000 10393335344 10808041472 1168648512 12.484 100 89 175 1 1490287696 300915408 20723776
11:55:53 6468 8192000000 10380250736 10798350336 1151401520 12.323 99 45 90 1 1472653552 309557344 11694688
11:57:10 4471 8192000000 10495375664 10906914816 1162843168 12.430 100 62 124 0 1588356800 410775728 14737904
11:58:23 6155 8192000000 10509593808 10931945472 1141623856 12.231 99 74 146 0 1600831056 441475952 17731248
11:59:31 11159 8192000000 10483760336 10907279360 1156976016 12.375 100 46 92 0 1598374192 429224272 12173904
12:00:49 12848 8192000000 10494220528 10941124608 1132476224 12.145 100 34 68 0 1603917472 461389472 10051776
12:02:05 11844 8192000000 10503554624 10976985088 1134825664 12.167 100 28 56 1 1617323552 474431888 8066000
12:03:18 19158 8192000000 10212335328 10748346368 1131604640 12.136 100 22 44 0 1413286368 276040576 5641152
12:04:42 21850 8192000000 10070379856 10491314176 1108686064 11.920 98 47 90 2 1147429360 28464160 10279136
12:06:08 39953 8192000000 10105919472 10580934656 1135693616 12.175 100 15 30 0 1200064992 61283424 3087952
12:08:51 69157 8192000000 10125650416 10564042752 1130097536 12.122 99 31 59 1 1211359552 74974672 6287344
12:11:14 36702 8192000000 10149035248 10610122752 1154843952 12.355 100 36 72 0 1254090416 91508928 7737536
12:13:09 73642 8192000000 10178612096 10658009088 1155121984 12.358 100 15 31 3 1281874768 123134720 3618064
12:15:26 39167 8192000000 10114135952 10610655232 1142038384 12.235 99 11 22 0 1205146224 60150816 2957024
12:17:05 61654 8192000000 10114400176 10564190208 1112560576 11.957 97 12 24 1 1196269680 80845584 2863520
12:19:18 64198 8192000000 10072435264 10505621504 1119568880 12.023 99 40 76 1 1168597440 38848720 10179840
12:21:23 60220 8192000000 10086892000 10570653696 1125883520 12.083 99 14 28 0 1183416912 54618784 2914608
12:23:33 98526 8192000000 10086711136 10566680576 1131345424 12.134 99 19 38 0 1182893120 47532944 4014752
12:26:11 71399 8192000000 10092158064 10551955456 1111954896 11.951 97 13 27 0 1164294528 49568432 2771200
12:28:23 33442 8192000000 10097842320 10526662656 1117277760 12.001 98 35 70 2 1146912784 22444896 7190128
12:29:58 57973 8192000000 10077455504 10519621632 1115935008 11.989 98 22 44 3 1190232320 69818672 4478640
12:31:59 45364 8192000000 10113560064 10555654144 1146225072 12.274 100 19 38 0 1214226416 64071600 3929744
12:33:49 27675 8192000000 10085218800 10516201472 1141302432 12.228 100 31 62 1 1174314240 27006560 6005248
12:35:20 61443 8192000000 10154682832 10623647744 1148204592 12.293 100 30 60 2 1237352896 82860416 6287888
12:37:25 138959 8192000000 10098853600 10595348480 1099524272 11.833 96 14 28 0 1153661792 51802432 2335088
12:41:37 31905 8192000000 10094473184 10531393536 1123167152 12.057 99 29 58 0 1195763472 67065312 5531008
12:43:11 59703 8192000000 10073767088 10527264768 1144764160 12.260 100 17 34 1 1177886048 30230944 2890944
12:45:18 58616 8192000000 10105901936 10576625664 1141865648 12.233 99 9 18 0 1190182800 46712256 1604896
12:47:17 55464 8192000000 10064873136 10591662080 1114547584 11.975 98 7 14 0 1176866640 60941968 1377088
12:49:13 38021 8192000000 10072362624 10501476352 1071823776 11.569 95 24 47 0 1116985280 40214032 4947472
12:50:52 59723 8192000000 10154753216 10614018048 1136237680 12.180 100 25 50 1 1258085424 116148208 5699536
12:53:28 48076 8192000000 10087655744 10530713600 1102137984 11.858 97 29 56 1 1180809760 72429600 6242176
12:55:18 96793 8192000000 10072640112 10531958784 1119853184 12.026 99 14 29 0 1215907904 93392544 2662176
12:57:55 37852 8192000000 10057338736 10484436992 1087544832 11.719 96 38 76 0 1109833072 14147392 8140848
12:59:38 109921 8192000000 10083587328 10543706112 1122355984 12.049 99 40 80 0 1184430944 53534416 8540544
13:02:33 21451 8192000000 10052306592 10504859648 1107478480 11.909 98 16 32 0 1144973808 34408976 3086352
13:03:55 48832 8192000000 10079409600 10539831296 1133473392 12.154 100 6 12 0 1160926880 26187424 1266064
13:05:46 63299 8192000000 10091973328 10596999168 1146753184 12.279 100 18 36 1 1231592224 80271968 4567072
13:07:56 64792 8192000000 10044464976 10499518464 1105574096 11.890 97 14 27 1 1161617184 52818064 3225024
13:10:04 42054 8192000000 10122536160 10573246464 1132870368 12.148 99 30 60 0 1214266720 75317344 6079008
13:12:02 46404 8192000000 10108769104 10552631296 1131078608 12.132 98 29 58 0 1173895472 37084160 5732704
13:13:57 66637 8192000000 10105066624 10563211264 1123658688 12.062 98 28 56 0 1211323616 81901824 5763104
13:16:05 90912 8192000000 10071605776 10572427264 1119506064 12.022 98 16 30 1 1173427872 50924976 2996832
13:18:36 82164 8192000000 10077401312 10569834496 1131449984 12.135 99 13 26 1 1194527200 60816272 2260944
13:21:01 134834 8192000000 10098605392 10538450944 1114980320 11.980 98 39 78 0 1182774080 59735712 8058048
13:24:17 42508 8192000000 10064415120 10521239552 1119008960 12.018 98 11 22 0 1156418304 34981472 2427872
13:26:00 98572 8192000000 10100000752 10546221056 1124577536 12.070 98 20 40 0 1184524336 54921584 5025216
13:29:17 61861 8192000000 10095068016 10541477888 1116459248 11.994 98 15 30 0 1167833248 48000096 3373904
13:31:22 50540 8192000000 10177261824 10644226048 1149258384 12.303 100 22 44 2 1286432752 132157328 5017040
13:34:05 49995 8192000000 10046589792 10476986368 1052290816 11.383 93 24 48 0 1143307968 86057760 4959392
13:36:02 74100 8192000000 9266506224 9577025536 396114736 4.612 35 15 28 1 436424816 37285808 3024272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-10-2021 13:38:15 to 27-10-2021 13:38:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-10-2021 13:38:31 to 27-10-2021 13:38:33.

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          226436
MON$OLDEST_ACTIVE               226437
MON$OLDEST_SNAPSHOT             226437
MON$NEXT_TRANSACTION            226437
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                       2046176
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

27.10.21 13:38:34. 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-27 13:38:37 No severe PSQL-related problems occured
27.10.21 13:38:38. End of report.