Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.08.2021 04: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 16210192 kB
MemAvailable 30987952 kB
Buffers 20 kB
Cached 15442764 kB
SwapCached 2580 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-08-17 04:37:08
DTS_END                         2021-08-17 04:37:08.2680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60568
TRN_ID                          226865
ATT_ID                          630
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: 9112.17 28666 1093460
customer order: creation 157.92 449 18950
customer order: refuse 13.86 344 1663
order to supplier: creation 40.57 1400 4868
order to supplier: removal 3.46 119470 415
invoice (draft): creation 41.08 3233 4929
invoice (draft): removal 53.81 998 6457
invoice accept: apply 38.50 18219 4620
invoice accept: cancel 49.28 879 5914
customer reserve: creation 3583.68 178 430041
customer reserve: removal 4854.35 67 582522
realization accept: apply 77.17 202 9260
realization accept: cancel 81.25 116 9750
payment from customer: creation 54.34 130 6521
payment from customer: removal 5.24 67 629
payment to supplier: creation 44.03 149 5284
payment to supplier: removal 3.23 52 387
service: total inventory turnovers 3.68 3085 442
service: total monetary turnovers 5.76 504 691
service: aggregate perf. data 0.94 13583 113
service: refresh index statistics 0.03 410192 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-08-2021 04:39:26 to 17-08-2021 04:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7251
TEST_TIME 2 6202
TEST_TIME 3 5907
TEST_TIME 4 5310
TEST_TIME 5 5314
TEST_TIME 6 5199
TEST_TIME 7 5088
TEST_TIME 8 5109
TEST_TIME 9 5257
TEST_TIME 10 5392
TEST_TIME 11 5848
TEST_TIME 12 6314
TEST_TIME 13 6420
TEST_TIME 14 6574
TEST_TIME 15 7339
TEST_TIME 16 7530
TEST_TIME 17 7837
TEST_TIME 18 8032
TEST_TIME 19 8467
TEST_TIME 20 8937
TEST_TIME 21 9936
TEST_TIME 22 11512
TEST_TIME 23 11527
TEST_TIME 24 11104
TEST_TIME 25 11289
TEST_TIME 26 11123
TEST_TIME 27 10864
TEST_TIME 28 10891
TEST_TIME 29 10771
TEST_TIME 30 10484
TEST_TIME 31 10255
TEST_TIME 32 10058
TEST_TIME 33 9921
TEST_TIME 34 9825
TEST_TIME 35 9778
TEST_TIME 36 9689
TEST_TIME 37 9512
TEST_TIME 38 9495
TEST_TIME 39 9303
TEST_TIME 40 9414
TEST_TIME 41 9558
TEST_TIME 42 9531
TEST_TIME 43 9458
TEST_TIME 44 9367
TEST_TIME 45 9446
TEST_TIME 46 9433
TEST_TIME 47 9484
TEST_TIME 48 9415
TEST_TIME 49 9474
TEST_TIME 50 9442
TEST_TIME 51 9433
TEST_TIME 52 9543
TEST_TIME 53 9434
TEST_TIME 54 9291
TEST_TIME 55 9214
TEST_TIME 56 9124
TEST_TIME 57 9204
TEST_TIME 58 9161
TEST_TIME 59 9082
TEST_TIME 60 9005
TEST_TIME 61 8985
TEST_TIME 62 8967
TEST_TIME 63 9012
TEST_TIME 64 9046
TEST_TIME 65 9121
TEST_TIME 66 9171
TEST_TIME 67 9323
TEST_TIME 68 9275
TEST_TIME 69 9282
TEST_TIME 70 9310
TEST_TIME 71 9372
TEST_TIME 72 9422
TEST_TIME 73 9406
TEST_TIME 74 9453
TEST_TIME 75 9462
TEST_TIME 76 9348
TEST_TIME 77 9294
TEST_TIME 78 9317
TEST_TIME 79 9286
TEST_TIME 80 9229
TEST_TIME 81 9218
TEST_TIME 82 9163
TEST_TIME 83 9137
TEST_TIME 84 9081
TEST_TIME 85 9055
TEST_TIME 86 9077
TEST_TIME 87 9041
TEST_TIME 88 9154
TEST_TIME 89 9158
TEST_TIME 90 9226
TEST_TIME 91 9167
TEST_TIME 92 9226
TEST_TIME 93 9246
TEST_TIME 94 9317
TEST_TIME 95 9335
TEST_TIME 96 9354
TEST_TIME 97 9384
TEST_TIME 98 9328
TEST_TIME 99 9249
TEST_TIME 100 9202
TEST_TIME 101 9232
TEST_TIME 102 9197
TEST_TIME 103 9150
TEST_TIME 104 9103
TEST_TIME 105 9146
TEST_TIME 106 9109
TEST_TIME 107 9084
TEST_TIME 108 9099
TEST_TIME 109 9065
TEST_TIME 110 9054
TEST_TIME 111 9087
TEST_TIME 112 9145
TEST_TIME 113 9155
TEST_TIME 114 9131
TEST_TIME 115 9133
TEST_TIME 116 9156
TEST_TIME 117 9247
TEST_TIME 118 9231
TEST_TIME 119 9186
TEST_TIME 120 9117

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-08-2021 04:39:30 to 17-08-2021 04:39:33.

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 1492998 1492998 0 0.00 0 22956 6 0 0
doc_list_biud 1492998 1492998 0 0.00 0 80723 33 0 0
sp_add_invoice_to_stock 4789 4620 169 3.52 29 170405 18220 0 169
sp_cancel_adding_invoice 5914 5914 0 0.00 5 78221 879 0 0
sp_cancel_client_order 1663 1663 0 0.00 5 18516 345 0 0
sp_cancel_customer_reserve 582522 582522 0 0.00 1 62662 67 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 3 2020 67 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 2110 53 0 0
sp_cancel_supplier_invoice 6473 6457 16 0.24 2 80729 999 0 16
sp_cancel_supplier_order 415 415 0 0.00 6 1228233 119471 0 0
sp_cancel_write_off 9750 9750 0 0.00 1 11764 117 0 0
sp_client_order 18950 18950 0 0.00 4 63875 450 0 0
sp_customer_reserve 430041 430041 0 0.00 0 98081 178 0 0
sp_fill_shopping_cart 28742 28742 0 0.00 1 29147 40 0 0
sp_fill_shopping_cart_clo_res 430032 430032 0 0.00 0 11337 17 0 0
sp_get_clo_for_invoice 4610 4610 0 0.00 0 40424 408 0 0
sp_kill_cost_storno 16680 16680 0 0.00 0 15739 58 0 0
sp_kill_qstorno_ret_qs2qd 1181666 1181666 0 0.00 0 80721 37 0 0
sp_kill_qty_storno 620591 620591 0 0.00 0 80723 78 0 0
sp_lock_selected_doc 19309 19309 0 0.00 0 10729 7 0 0
sp_make_cost_storno 25675 25675 0 0.00 0 13720 58 0 0
sp_multiply_rows_for_pdistr 25675 25675 0 0.00 0 8772 35 0 0
sp_multiply_rows_for_qdistr 18950 18950 0 0.00 0 63863 220 0 0
sp_pay_from_customer 6521 6521 0 0.00 4 15093 131 0 0
sp_pay_to_supplier 5284 5284 0 0.00 5 20421 149 0 0
sp_payment_common 11805 11805 0 0.00 1 11518 28 0 0
sp_qd_handle_on_cancel_clo 1663 1663 0 0.00 0 18508 302 0 0
sp_qd_handle_on_invoice_upd_sts 10524 10524 0 0.00 0 44852 356 0 0
sp_qd_handle_on_reserve_upd_sts 9260 9260 0 0.00 0 15342 64 0 0
sp_reserve_write_off 9260 9260 0 0.00 6 15372 203 0 0
sp_supplier_invoice 4929 4929 0 0.00 7 103765 3233 0 0
sp_supplier_order 4868 4868 0 0.00 6 77451 1401 0 0
srv_aggregate_perf_data 113 113 0 0.00 2757 230290 13584 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2124 133853 49276 0 0
srv_find_qd_qs_mism 1050388 1050388 0 0.00 0 22955 5 0 0
srv_make_invnt_saldo 442 442 0 0.00 49 133026 3086 0 0
srv_make_money_saldo 691 691 0 0.00 1 27033 504 0 0
srv_recalc_idx_stat 4 4 0 0.00 134090 714563 410193 0 0
t$perf-abend:sp_add_invoice_to_stock 169 169 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4620 4620 0 0.00 0 20017 159 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 4725 103 0 0
t$perf-norm:sp_cancel_client_order 1663 1663 0 0.00 0 5965 17 0 0
t$perf-norm:sp_cancel_customer_reserve 628 628 0 0.00 0 9614 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 883 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 2558 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 12135 51 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 15649 1208 0 0
t$perf-norm:sp_cancel_write_off 937 937 0 0.00 0 1707 4 0 0
t$perf-norm:sp_client_order 18950 18950 0 0.00 0 10883 19 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 1225 5 0 0
t$perf-norm:sp_pay_from_customer 6521 6521 0 0.00 0 7811 9 0 0
t$perf-norm:sp_pay_to_supplier 5284 5284 0 0.00 0 8233 11 0 0
t$perf-norm:sp_reserve_write_off 9260 9260 0 0.00 0 3323 8 0 0
t$perf-norm:sp_supplier_invoice 4929 4929 0 0.00 0 9487 19 0 0
t$perf-norm:sp_supplier_order 4868 4868 0 0.00 0 8121 17 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 1497 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 99 2 0 0
t$perf-norm:srv_make_invnt_saldo 442 442 0 0.00 0 5587 16 0 0
t$perf-norm:srv_make_money_saldo 691 691 0 0.00 0 2761 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9556 9218 338 3.53 0 7326 19 0 338
v_all_customers 34902 34902 0 0.00 0 27 1 0 0
v_all_suppliers 27384 27384 0 0.00 0 23 1 0 0
v_all_wares 37900 37900 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 262 5 0 0
v_cancel_client_order 3326 3326 0 0.00 0 12361 12 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 2012 6 0 0
v_cancel_customer_reserve 1256 1256 0 0.00 0 6125 13 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 2070 8 0 0
v_cancel_supplier_invoice 784 752 32 4.08 0 2257 17 0 32
v_cancel_supplier_order 830 830 0 0.00 0 2106 5 0 0
v_cancel_write_off 1874 1874 0 0.00 0 6119 23 0 0
v_max_id_clo_ord 4868 4868 0 0.00 0 1792 2 0 0
v_max_id_clo_res 975 975 0 0.00 0 3653 26 0 0
v_max_id_ord_sup 4924 4924 0 0.00 0 6990 4 0 0
v_max_non_paid_invoice 1384 1384 0 0.00 0 2959 7 0 0
v_max_non_paid_realizn 3371 3371 0 0.00 0 4953 4 0 0
v_min_id_clo_ord 4868 4868 0 0.00 0 5148 7 0 0
v_min_id_clo_res 975 975 0 0.00 0 7044 22 0 0
v_min_id_ord_sup 4924 4924 0 0.00 0 7901 9 0 0
v_min_non_paid_invoice 5284 5284 0 0.00 0 7588 19 0 0
v_min_non_paid_realizn 6521 6521 0 0.00 0 11514 20 0 0
v_our_firm 9298 9298 0 0.00 0 14 1 0 0
v_reserve_write_off 18520 18520 0 0.00 0 8686 9 0 0
x_lock_depdocs_on_canc_invoice 11995 11995 0 0.00 0 17811 120 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 17809 384 0 0
x_make_qstorno_1000_1200 4868 4868 0 0.00 2 77443 1331 0 0
x_make_qstorno_1200_2000 4929 4929 0 0.00 4 103755 3080 0 0
x_make_qstorno_2100_3300 430041 430041 0 0.00 0 98081 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-08-2021 04:39:34 to 17-08-2021 04:39:38.

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
02:07:10 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:11 31958 8192000000 9720760352 10018230272 792406384 8.819 100 91 181 1 822533120 7494192 22632544
02:09:51 30259 8192000000 9770897664 10077736960 836557248 9.265 100 85 169 3 869890368 13103328 20229792
02:11:27 14719 8192000000 9806671232 10129018880 870723264 9.607 100 84 168 0 917397968 27347312 19327392
02:12:46 10849 8192000000 9893059296 10225664000 922730608 10.123 100 83 166 0 997669696 56453920 18485168
02:14:01 8652 8192000000 9924264032 10272792576 965184784 10.540 100 75 150 2 1037407184 56093216 16129184
02:15:11 4296 8192000000 9974213040 10331504640 1002016400 10.898 100 84 167 1 1087330928 68180320 17134208
02:16:17 3512 8192000000 10003684144 10371416064 1031979968 11.188 100 83 166 2 1108800032 59328608 17491456
02:17:22 1770 8192000000 10021392800 10389622784 1057105040 11.429 100 96 190 1 1137326000 61492080 18728880
02:18:25 10971 8192000000 10027656880 10419126272 1061813936 11.474 100 56 112 0 1156946400 83764096 11368368
02:19:38 5536 8192000000 9992425088 10385702912 1073078768 11.581 100 53 104 1 1113723760 29002176 11642816
02:20:46 8039 8192000000 9995493760 10395897856 1083505568 11.681 100 21 42 0 1121618304 33313280 4799456
02:21:58 35230 8192000000 10014482720 10408988672 1091508960 11.757 100 53 104 2 1119122720 15652512 11961248
02:23:33 6112 8192000000 10017186688 10410872832 1093441856 11.775 100 39 74 2 1130302704 27854224 9006624
02:24:40 8623 8192000000 10009769824 10410598400 1099620240 11.834 100 28 57 2 1127952576 22179504 6152832
02:25:51 6759 8192000000 9997820096 10396594176 1077841712 11.627 98 37 68 1 1099183472 13009344 8332416
02:26:59 28717 8192000000 9986604144 10393677824 1051988080 11.380 96 33 66 0 1073069504 12974976 8106448
02:28:31 3898 8192000000 9978871616 10358714368 1029515856 11.164 97 49 98 1 1070345184 30365360 10463968
02:29:35 8635 8192000000 9975551680 10361876480 1016857072 11.042 97 48 95 2 1040353024 13142960 10352992
02:30:48 8012 8192000000 9910617856 10290339840 949227072 10.384 92 41 82 0 969420960 10582624 9611264
02:31:56 18218 8192000000 9946539168 10349404160 1002808928 10.906 98 20 40 3 1043473200 36514640 4149632
02:33:15 4632 8192000000 9962267440 10347020288 1032704160 11.194 99 51 101 1 1059684272 15320144 11659968
02:34:20 8024 8192000000 9975534000 10379894784 1054310672 11.402 100 43 88 2 1091378272 26719808 10347792
02:35:31 23685 8192000000 9974060832 10386436096 1068904496 11.542 100 31 62 2 1089119136 11668048 8546592
02:37:18 32651 8192000000 10004884096 10408087552 1089813776 11.741 100 37 74 1 1114026864 16120672 8092416
02:39:01 8233 8192000000 10071181680 10466754560 1109031344 11.923 100 60 120 1 1178168880 57385728 11751808
02:40:15 16796 8192000000 10100153872 10502656000 1133677840 12.156 100 45 89 2 1216082160 72643408 9760912
02:41:35 32606 8192000000 10184714848 10597662720 1147434240 12.285 100 51 101 0 1284966528 125897408 11634880
02:43:32 16110 8192000000 10235122816 10656649216 1162482304 12.427 100 48 94 4 1347227184 173886768 10858112
02:45:05 7128 8192000000 10290373456 10702942208 1174522128 12.539 100 61 121 0 1394500512 205893088 14085296
02:46:19 7491 8192000000 10329936112 10742001664 1178650256 12.578 100 66 131 0 1433682944 240759680 14273008
02:47:29 3369 8192000000 10401903008 10811916288 1192158480 12.703 100 77 154 1 1500766384 290893712 17714192
02:48:42 2123 8192000000 10460502272 10873921536 1188837440 12.673 100 69 138 0 1557697776 352880880 15979456
02:49:48 2170 8192000000 10447528832 10877886464 1182177408 12.610 99 76 150 2 1546766368 346517504 18071456
02:50:55 4408 8192000000 10535791664 10954858496 1193574128 12.717 100 60 118 0 1638585856 429952016 15059712
02:52:10 2476 8192000000 10557388832 10978861056 1192273824 12.705 100 87 173 0 1655675696 443266576 20135296
02:53:16 4435 8192000000 10555530640 10974126080 1189705232 12.681 100 71 141 1 1654477648 447744560 17027856
02:54:23 10859 8192000000 10489371088 10946555904 1135004464 12.169 100 44 88 1 1590943520 443599424 12339632
02:55:37 14011 8192000000 10549425680 10999201792 1151892464 12.327 100 42 84 0 1646385696 482982448 11510784
02:56:54 19709 8192000000 10523775792 10978701312 1112510960 11.956 98 32 64 1 1607881264 486245328 9124976
02:58:15 14359 8192000000 10424162016 10860019712 1089015792 11.733 98 31 63 1 1511157264 413526448 8615024
02:59:36 17031 8192000000 10100406256 10554712064 1101569840 11.853 98 21 42 1 1247223264 141260560 4392864
03:01:03 50405 8192000000 10106228688 10531135488 1095190128 11.792 99 42 81 4 1193570720 86734768 11645824
03:02:58 54281 8192000000 10111537632 10564825088 1097504752 11.814 99 15 31 0 1201028176 99994448 3528976
03:04:54 64451 8192000000 10059685744 10548756480 1113176960 11.962 100 15 30 0 1195100752 78761808 3161984
03:07:07 52885 8192000000 10065066224 10499837952 1101616976 11.853 98 34 66 0 1121026512 12425280 6984256
03:09:00 121420 8192000000 10132986224 10599583744 1122409008 12.050 99 17 34 0 1217991792 92507840 3074944
03:12:01 115967 8192000000 10106603088 10586247168 1129912320 12.121 99 17 34 0 1207111248 73522304 3676624
03:15:19 26045 8192000000 10134524832 10594709504 1147217840 12.283 100 19 38 2 1239576656 88559808 3799008
03:16:46 76324 8192000000 10121626496 10681860096 1156134368 12.367 100 12 24 1 1266067904 107108048 2825488
03:19:07 42027 8192000000 10081423888 10515931136 1144080400 12.254 99 40 80 0 1172987360 20235568 8671392
03:20:52 54111 8192000000 10110986928 10553290752 1117341424 12.002 97 40 80 0 1184089104 57495536 9252144
03:22:47 73396 8192000000 10082210672 10559299584 1115454224 11.984 97 22 43 3 1159616896 39657040 4505632
03:25:21 22270 8192000000 10066311536 10502930432 1099319616 11.831 97 31 61 0 1137700896 32716272 5665008
03:26:45 66925 8192000000 10045625392 10485792768 1118001184 12.008 98 18 36 0 1153392992 31753728 3638080
03:28:52 69766 8192000000 10110352304 10618888192 1135773408 12.176 99 23 46 1 1192022832 51715424 4534000
03:31:03 94231 8192000000 10100052000 10540470272 1114376992 11.974 97 39 76 1 1149254880 27015360 7862528
03:33:44 48837 8192000000 10100914976 10615181312 1146193632 12.274 100 15 29 3 1232291184 83098784 2998768
03:35:35 36176 8192000000 10076448384 10549145600 1151174928 12.321 100 11 21 0 1174966464 21807264 1984272
03:37:11 58976 8192000000 10120867136 10582798336 1157570096 12.380 100 12 24 0 1233414880 73255744 2589040
03:39:37 72060 8192000000 10174845664 10663403520 1133413648 12.154 97 26 52 3 1249145776 110119840 5612288
03:41:51 79498 8192000000 10130338320 10580037632 1127813184 12.101 97 19 38 1 1207107296 75375008 3919104
03:44:11 33083 8192000000 10097698672 10622230528 1128382784 12.106 98 12 24 3 1209198112 78120304 2695024
03:45:58 38627 8192000000 10120664512 10577514496 1135720176 12.175 99 38 77 1 1221392288 77207408 8464704
03:47:45 75714 8192000000 10098754496 10583687168 1066468112 11.518 93 26 52 2 1126875232 55064992 5342128
03:50:41 46681 8192000000 10061127312 10553384960 1078047952 11.629 96 37 74 0 1165289936 79881952 7360032
03:53:03 133853 8192000000 10088069840 10535043072 1072711200 11.578 96 28 55 0 1113190304 34835824 5643280
03:56:19 35666 8192000000 10029508384 10475397120 1117556880 12.004 100 24 48 0 1154344976 32118848 4669248
03:58:00 74135 8192000000 10059014064 10529091584 1105196176 11.887 99 14 28 0 1157543264 48973104 3373984
04:00:15 60099 8192000000 10074689248 10561613824 1113220016 11.963 99 12 25 0 1206630080 90524880 2885184
04:03:37 73884 8192000000 10130043376 10583371776 1105720800 11.892 98 17 34 0 1215338688 105331776 4286112
04:06:29 77559 8192000000 10087891360 10553118720 1107273664 11.907 98 13 26 0 1174357456 64927360 2156432
04:09:16 48729 8192000000 10115077744 10615365632 1126549184 12.089 99 25 50 0 1222393184 91151104 4692896
04:11:09 121391 8192000000 10069351472 10544668672 1109030304 11.923 97 9 18 0 1153046672 42313552 1702816
04:14:10 101985 8192000000 10086686048 10557722624 1088675760 11.730 95 19 38 0 1134525888 42251088 3599040
04:18:00 47163 8192000000 10062819712 10545901568 1131245744 12.133 99 13 26 0 1179960384 45673184 3041456
04:19:48 60870 8192000000 10086883680 10551422976 1122444864 12.050 98 16 32 0 1176011408 50146800 3419744
04:22:07 57331 8192000000 10067868576 10547470336 1147186400 12.283 100 10 20 0 1219781440 69988272 2606768
04:24:08 61151 8192000000 10091828112 10540851200 1140433680 12.220 99 15 30 1 1195686688 52408848 2844160
04:26:17 45294 8192000000 10144197824 10586374144 1165845040 12.458 100 34 66 3 1238293776 65644064 6804672
04:28:18 61278 8192000000 10135596736 10592620544 1156251488 12.368 99 20 40 1 1233127952 72645888 4230576
04:30:24 72898 8192000000 10087422736 10522058752 1093810144 11.779 94 30 60 0 1125555824 25906880 5838800
04:32:43 33048 8192000000 10141125056 10622930944 1151924384 12.328 100 30 60 0 1292625264 133944944 6755936
04:34:19 53602 8192000000 10067639056 10521767936 1138190528 12.199 100 10 20 0 1167607920 27559840 1857552
04:36:17 95730 8192000000 9134490608 9391120384 278192336 3.284 25 17 34 0 307472224 26121008 3158880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-08-2021 04:39:40 to 17-08-2021 04:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 169 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 17-08-2021 04:39:56 to 17-08-2021 04:39:57.

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          227130
MON$OLDEST_ACTIVE               227131
MON$OLDEST_SNAPSHOT             227131
MON$NEXT_TRANSACTION            227131
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                       2037344
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

17.08.21 04:39:59. 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-08-17 04:40:01 No severe PSQL-related problems occured
17.08.21 04:40:02. End of report.