Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.09.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 15908800 kB
MemAvailable 30892276 kB
Buffers 0 kB
Cached 15819160 kB
SwapCached 388 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-09-12 10:37:06
DTS_END                         2021-09-12 10:37:06.3960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52092
TRN_ID                          230617
ATT_ID                          669
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: 8974.50 22748 1076940
customer order: creation 166.46 434 19975
customer order: refuse 13.98 335 1678
order to supplier: creation 43.84 1457 5261
order to supplier: removal 3.49 115315 419
invoice (draft): creation 45.67 3642 5480
invoice (draft): removal 52.78 1064 6333
invoice accept: apply 39.71 19621 4765
invoice accept: cancel 46.89 896 5627
customer reserve: creation 3630.99 195 435719
customer reserve: removal 4643.47 66 557216
realization accept: apply 79.71 216 9565
realization accept: cancel 85.47 124 10256
payment from customer: creation 56.39 150 6767
payment from customer: removal 5.61 106 673
payment to supplier: creation 46.55 162 5586
payment to supplier: removal 3.16 77 379
service: total inventory turnovers 3.75 3756 450
service: total monetary turnovers 5.66 625 679
service: aggregate perf. data 0.90 16240 108
service: refresh index statistics 0.03 290470 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-09-2021 10:39:23 to 12-09-2021 10:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6647
TEST_TIME 2 5432
TEST_TIME 3 6301
TEST_TIME 4 5304
TEST_TIME 5 5026
TEST_TIME 6 5808
TEST_TIME 7 5714
TEST_TIME 8 6152
TEST_TIME 9 6308
TEST_TIME 10 6482
TEST_TIME 11 6777
TEST_TIME 12 7342
TEST_TIME 13 7256
TEST_TIME 14 7412
TEST_TIME 15 7678
TEST_TIME 16 7611
TEST_TIME 17 8088
TEST_TIME 18 8127
TEST_TIME 19 8329
TEST_TIME 20 8255
TEST_TIME 21 8321
TEST_TIME 22 8681
TEST_TIME 23 9098
TEST_TIME 24 9248
TEST_TIME 25 8986
TEST_TIME 26 9590
TEST_TIME 27 10191
TEST_TIME 28 10838
TEST_TIME 29 11441
TEST_TIME 30 11189
TEST_TIME 31 11245
TEST_TIME 32 10999
TEST_TIME 33 10806
TEST_TIME 34 10510
TEST_TIME 35 10445
TEST_TIME 36 10543
TEST_TIME 37 10357
TEST_TIME 38 10448
TEST_TIME 39 10369
TEST_TIME 40 10253
TEST_TIME 41 10166
TEST_TIME 42 10133
TEST_TIME 43 10023
TEST_TIME 44 10021
TEST_TIME 45 10140
TEST_TIME 46 10042
TEST_TIME 47 10024
TEST_TIME 48 10014
TEST_TIME 49 9950
TEST_TIME 50 9880
TEST_TIME 51 9760
TEST_TIME 52 9672
TEST_TIME 53 9558
TEST_TIME 54 9495
TEST_TIME 55 9552
TEST_TIME 56 9475
TEST_TIME 57 9376
TEST_TIME 58 9367
TEST_TIME 59 9368
TEST_TIME 60 9364
TEST_TIME 61 9291
TEST_TIME 62 9338
TEST_TIME 63 9361
TEST_TIME 64 9367
TEST_TIME 65 9511
TEST_TIME 66 9465
TEST_TIME 67 9551
TEST_TIME 68 9492
TEST_TIME 69 9482
TEST_TIME 70 9497
TEST_TIME 71 9480
TEST_TIME 72 9509
TEST_TIME 73 9542
TEST_TIME 74 9542
TEST_TIME 75 9483
TEST_TIME 76 9411
TEST_TIME 77 9336
TEST_TIME 78 9336
TEST_TIME 79 9286
TEST_TIME 80 9276
TEST_TIME 81 9243
TEST_TIME 82 9179
TEST_TIME 83 9143
TEST_TIME 84 9133
TEST_TIME 85 9206
TEST_TIME 86 9153
TEST_TIME 87 9169
TEST_TIME 88 9214
TEST_TIME 89 9183
TEST_TIME 90 9227
TEST_TIME 91 9202
TEST_TIME 92 9238
TEST_TIME 93 9275
TEST_TIME 94 9229
TEST_TIME 95 9282
TEST_TIME 96 9349
TEST_TIME 97 9292
TEST_TIME 98 9228
TEST_TIME 99 9184
TEST_TIME 100 9150
TEST_TIME 101 9119
TEST_TIME 102 9098
TEST_TIME 103 9040
TEST_TIME 104 9023
TEST_TIME 105 9007
TEST_TIME 106 9003
TEST_TIME 107 8980
TEST_TIME 108 8949
TEST_TIME 109 9035
TEST_TIME 110 9065
TEST_TIME 111 9044
TEST_TIME 112 9034
TEST_TIME 113 9074
TEST_TIME 114 9110
TEST_TIME 115 9144
TEST_TIME 116 9156
TEST_TIME 117 9119
TEST_TIME 118 9072
TEST_TIME 119 9033
TEST_TIME 120 8975

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-09-2021 10:39:27 to 12-09-2021 10:39:30.

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 1481893 1481893 0 0.00 0 22598 7 0 0
doc_list_biud 1481893 1481893 0 0.00 0 81841 32 0 0
sp_add_invoice_to_stock 4821 4765 56 1.16 16 201524 19621 0 56
sp_cancel_adding_invoice 5627 5627 0 0.00 5 85928 897 0 0
sp_cancel_client_order 1678 1678 0 0.00 4 15613 336 0 0
sp_cancel_customer_reserve 557216 557216 0 0.00 1 45620 67 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 13342 107 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 3 7226 78 0 0
sp_cancel_supplier_invoice 6340 6333 7 0.11 2 81844 1064 0 7
sp_cancel_supplier_order 419 419 0 0.00 13 1009556 115315 0 0
sp_cancel_write_off 10256 10256 0 0.00 1 24365 125 0 0
sp_client_order 19975 19975 0 0.00 4 40077 434 0 0
sp_customer_reserve 435719 435719 0 0.00 0 71962 195 0 0
sp_fill_shopping_cart 30715 30715 0 0.00 1 16733 47 0 0
sp_fill_shopping_cart_clo_res 435717 435717 0 0.00 0 19383 17 0 0
sp_get_clo_for_invoice 4763 4763 0 0.00 0 34340 407 0 0
sp_kill_cost_storno 16935 16935 0 0.00 0 12071 64 0 0
sp_kill_qstorno_ret_qs2qd 1131440 1131440 0 0.00 0 81840 37 0 0
sp_kill_qty_storno 595857 595857 0 0.00 0 81841 79 0 0
sp_lock_selected_doc 19928 19928 0 0.00 0 8902 7 0 0
sp_make_cost_storno 26681 26681 0 0.00 0 18918 67 0 0
sp_multiply_rows_for_pdistr 26681 26681 0 0.00 0 14461 39 0 0
sp_multiply_rows_for_qdistr 19975 19975 0 0.00 0 34604 214 0 0
sp_pay_from_customer 6767 6767 0 0.00 4 13744 151 0 0
sp_pay_to_supplier 5586 5586 0 0.00 5 19714 163 0 0
sp_payment_common 12353 12353 0 0.00 1 13706 38 0 0
sp_qd_handle_on_cancel_clo 1678 1678 0 0.00 0 15605 303 0 0
sp_qd_handle_on_invoice_upd_sts 10390 10390 0 0.00 0 31793 390 0 0
sp_qd_handle_on_reserve_upd_sts 9565 9565 0 0.00 0 17766 69 0 0
sp_reserve_write_off 9565 9565 0 0.00 7 17799 217 0 0
sp_supplier_invoice 5480 5480 0 0.00 8 107659 3643 0 0
sp_supplier_order 5261 5261 0 0.00 5 46994 1458 0 0
srv_aggregate_perf_data 108 108 0 0.00 3016 142993 16240 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1129 141039 48209 0 0
srv_find_qd_qs_mism 1032173 1032173 0 0.00 0 22586 6 0 0
srv_make_invnt_saldo 450 450 0 0.00 54 100342 3756 0 0
srv_make_money_saldo 679 679 0 0.00 1 16639 625 0 0
srv_recalc_idx_stat 4 4 0 0.00 152885 552878 290471 0 0
t$perf-abend:sp_add_invoice_to_stock 56 56 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4766 4766 0 0.00 0 11899 146 0 0
t$perf-norm:sp_cancel_adding_invoice 375 375 0 0.00 0 27929 176 0 0
t$perf-norm:sp_cancel_client_order 1678 1678 0 0.00 0 3529 12 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 20098 52 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 575 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 3949 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 2408 23 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 15931 1384 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 1755 8 0 0
t$perf-norm:sp_client_order 19975 19975 0 0.00 0 27521 20 0 0
t$perf-norm:sp_customer_reserve 925 925 0 0.00 0 5451 18 0 0
t$perf-norm:sp_pay_from_customer 6767 6767 0 0.00 0 8310 11 0 0
t$perf-norm:sp_pay_to_supplier 5586 5586 0 0.00 0 12215 13 0 0
t$perf-norm:sp_reserve_write_off 9565 9565 0 0.00 0 9216 10 0 0
t$perf-norm:sp_supplier_invoice 5480 5480 0 0.00 0 5860 21 0 0
t$perf-norm:sp_supplier_order 5261 5261 0 0.00 0 6341 15 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 232 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 806 23 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 773 9 0 0
t$perf-norm:srv_make_money_saldo 679 679 0 0.00 0 2562 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 9636 9524 112 1.16 0 5331 15 0 112
v_all_customers 36398 36398 0 0.00 0 20 1 0 0
v_all_suppliers 29922 29922 0 0.00 0 70 1 0 0
v_all_wares 39950 39950 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 750 750 0 0.00 0 5476 15 0 0
v_cancel_client_order 3356 3356 0 0.00 0 3742 4 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 4701 12 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 1528 7 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 852 5 0 0
v_cancel_supplier_invoice 812 798 14 1.72 0 4061 20 0 14
v_cancel_supplier_order 838 838 0 0.00 0 1621 7 0 0
v_cancel_write_off 2036 2036 0 0.00 0 5082 28 0 0
v_max_id_clo_ord 5260 5260 0 0.00 0 1432 2 0 0
v_max_id_clo_res 925 925 0 0.00 0 9551 27 0 0
v_max_id_ord_sup 5480 5480 0 0.00 0 9796 6 0 0
v_max_non_paid_invoice 1365 1365 0 0.00 0 4024 6 0 0
v_max_non_paid_realizn 3596 3596 0 0.00 0 1955 3 0 0
v_min_id_clo_ord 5260 5260 0 0.00 0 4397 8 0 0
v_min_id_clo_res 925 925 0 0.00 0 7568 23 0 0
v_min_id_ord_sup 5480 5480 0 0.00 0 7522 11 0 0
v_min_non_paid_invoice 5586 5586 0 0.00 0 9762 24 0 0
v_min_non_paid_realizn 6767 6767 0 0.00 0 8257 30 0 0
v_our_firm 9894 9894 0 0.00 0 99 1 0 0
v_reserve_write_off 19130 19130 0 0.00 0 5515 8 0 0
x_lock_depdocs_on_canc_invoice 11561 11561 0 0.00 0 35285 129 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 12162 374 0 0
x_make_qstorno_1000_1200 5261 5261 0 0.00 2 46976 1374 0 0
x_make_qstorno_1200_2000 5480 5480 0 0.00 4 107594 3468 0 0
x_make_qstorno_2100_3300 435719 435719 0 0.00 0 71961 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-09-2021 10:39:31 to 12-09-2021 10:39:35.

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:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:10 22173 8192000000 9730489776 10029277184 812923744 9.027 100 89 178 0 842410576 7970496 21516336
08:09:38 32956 8192000000 9782732720 10091663360 853534064 9.435 100 94 188 0 888677024 13192672 21950288
08:11:13 15166 8192000000 9817862224 10140303360 883421584 9.734 100 81 162 0 924666624 23350288 17894752
08:12:36 18302 8192000000 9884362016 10221182976 931839136 10.213 100 77 153 1 989197616 41165616 16192864
08:14:00 6263 8192000000 9924945856 10271465472 970702784 10.594 100 81 162 1 1042158368 53872720 17582864
08:15:09 4487 8192000000 9962159184 10320543744 1001039952 10.889 100 74 148 2 1075791376 59670304 15081120
08:16:15 2157 8192000000 9996408160 10355826688 1029361040 11.162 100 80 160 2 1108277440 62737808 16178592
08:17:19 1940 8192000000 10028061248 10394050560 1047221376 11.334 100 89 180 1 1131905920 66284608 18399936
08:18:24 1415 8192000000 10052190752 10419830784 1058120656 11.438 100 86 172 0 1151848496 76375456 17352384
08:19:26 7392 8192000000 9988666960 10368724992 1064059616 11.495 100 39 75 1 1117574032 45701728 7812688
08:20:36 6216 8192000000 9997450272 10382606336 1077198992 11.621 100 42 82 2 1111841984 26002160 8640832
08:21:44 4768 8192000000 10004197376 10399285248 1088988032 11.733 100 43 86 2 1126144336 27520544 9635760
08:22:49 63955 8192000000 10006208224 10400403456 1090191424 11.744 100 34 66 2 1118798736 20971280 7636032
08:24:54 4607 8192000000 10021906432 10419298304 1095464448 11.795 100 47 96 0 1128012560 22439936 10108176
08:25:59 3503 8192000000 10033922032 10427465728 1099438304 11.832 100 48 96 0 1130848352 19396592 12013456
08:27:04 9072 8192000000 9993051120 10387603456 1080666624 11.654 99 22 41 0 1116528704 31536384 4325696
08:28:13 48902 8192000000 9985631296 10372366336 1043336944 11.297 97 54 105 4 1072038848 16830160 11871744
08:30:03 6893 8192000000 9941676208 10328387584 1017031824 11.043 97 38 77 1 1040751648 15800144 7919680
08:31:10 7391 8192000000 9920193584 10305228800 975340160 10.639 95 34 68 0 999967184 16602496 8024528
08:32:18 11536 8192000000 9930924272 10329137152 1012537200 11.000 99 34 68 1 1040439808 20319312 7583296
08:33:30 64422 8192000000 9968664240 10378469376 1048383456 11.345 100 40 81 3 1087144160 29491584 9269120
08:35:37 10473 8192000000 9968697760 10374283264 1060221552 11.459 100 30 59 2 1085006448 18002544 6782352
08:36:52 8646 8192000000 9971375456 10375168000 1063337936 11.488 100 32 64 0 1087983184 18101680 6543568
08:38:08 7596 8192000000 10042205728 10437406720 1094674400 11.787 100 52 103 1 1152229792 46108672 11446720
08:39:23 13326 8192000000 10064158240 10464034816 1111914032 11.951 100 52 104 1 1174982032 52529888 10538112
08:40:41 55285 8192000000 10085128272 10498600960 1125482000 12.079 100 39 78 0 1182057104 48244896 8330208
08:42:46 3947 8192000000 10124458576 10526449664 1132508944 12.145 100 61 122 1 1227457568 81667664 13280960
08:44:04 12700 8192000000 10137430368 10544869376 1144029792 12.253 100 46 92 1 1247794336 94238160 9526384
08:45:20 8855 8192000000 10156055136 10570313728 1150274144 12.312 100 47 94 1 1264085392 103360064 10451184
08:46:34 1129 8192000000 10176583456 10587856896 1157432640 12.379 100 71 143 3 1285086448 111393088 16260720
08:47:38 14487 8192000000 10225768416 10640433152 1158936576 12.393 100 52 105 0 1332382688 161222432 12223680
08:49:01 4257 8192000000 10226425280 10654212096 1164727568 12.448 100 56 112 0 1330458240 153384064 12346608
08:50:09 11015 8192000000 10240692880 10665021440 1159552336 12.399 99 53 106 2 1338949184 167698688 11698160
08:51:25 3090 8192000000 10247272768 10663428096 1141724944 12.232 98 62 123 1 1345837264 190245152 13867168
08:52:31 1969 8192000000 10288836000 10698080256 1160836912 12.411 100 68 134 0 1390567408 214507968 15222528
08:53:37 2066 8192000000 10336134048 10738778112 1170474912 12.501 100 79 158 0 1436910400 248502784 17932704
08:54:45 1144 8192000000 10350174416 10745159680 1135400720 12.172 100 97 194 2 1445733248 287747136 22585392
08:55:48 12350 8192000000 10352118960 10775478272 1143803792 12.251 100 41 82 2 1446413232 292679360 9930080
08:57:04 9243 8192000000 10377482144 10804875264 1127786384 12.100 99 44 86 1 1468377248 329576736 11014128
08:58:18 3930 8192000000 10432213328 10849824768 1143364880 12.247 100 86 166 1 1531153152 367632384 20155888
08:59:27 14199 8192000000 10419542784 10866552832 1148753888 12.298 100 34 68 1 1543931792 386014864 9163040
09:00:43 9682 8192000000 10401897264 10847166464 1142873904 12.243 99 44 86 2 1493290816 339190992 11225920
09:01:58 10986 8192000000 10427558080 10857762816 1154969872 12.356 100 34 67 2 1538399232 374677248 8752112
09:03:38 42040 8192000000 10410209680 10882035712 1131698832 12.137 100 25 50 1 1511707408 372718848 7289728
09:05:30 23427 8192000000 10208312880 10720579584 1128075600 12.103 100 14 28 0 1327395248 195948384 3371264
09:06:54 86798 8192000000 10097440512 10569637888 1127422624 12.097 100 17 34 1 1191107920 59825280 3860016
09:09:47 111106 8192000000 10113450064 10555641856 1112107248 11.952 99 34 68 0 1214079920 94671408 7301264
09:12:48 39466 8192000000 10112977232 10545635328 1120575152 12.032 99 31 62 0 1205221264 77682400 6963712
09:14:29 38061 8192000000 10164497296 10607480832 1128054576 12.103 100 36 70 0 1226991408 91305840 7630992
09:16:13 55828 8192000000 10148384912 10592468992 1124669600 12.071 99 18 36 0 1227954128 98902976 4381552
09:18:11 77926 8192000000 10149600880 10592657408 1083550112 11.681 95 25 51 0 1201250592 112107072 5593408
09:20:34 70173 8192000000 10147402720 10608451584 1130886144 12.130 100 19 38 0 1270329776 134895504 4548128
09:22:45 103273 8192000000 10099148208 10584584192 1138814016 12.204 100 25 50 0 1206793104 62428448 5550640
09:25:38 20012 8192000000 10077464000 10546106368 1142446816 12.239 100 54 105 3 1185054528 32045760 10561952
09:27:05 60643 8192000000 10092687616 10561339392 1152395088 12.332 100 24 48 0 1198336032 40459984 5480960
09:30:29 88404 8192000000 10115047968 10592518144 1154389968 12.351 100 31 61 1 1246740688 85230848 7119872
09:33:11 41760 8192000000 10089486480 10553016320 1159239376 12.396 100 29 58 2 1192607024 27471728 5895920
09:34:55 89117 8192000000 10100818720 10617782272 1160180848 12.405 100 16 32 0 1229964256 66268336 3515072
09:37:34 63920 8192000000 10081112784 10542505984 1146930480 12.281 99 16 32 1 1181746624 31645984 3170160
09:39:38 47057 8192000000 10091048208 10562969600 1129149904 12.113 98 17 34 1 1180964912 48466656 3348352
09:41:26 24499 8192000000 10103498848 10565181440 1110474432 11.937 96 17 34 0 1168473344 54491904 3507008
09:42:51 35596 8192000000 10063377296 10495709184 1080617984 11.653 95 15 30 0 1116930448 33474784 2837680
09:44:29 50340 8192000000 10062257360 10491633664 1107790496 11.911 98 30 59 2 1159652288 45669264 6192528
09:46:20 20747 8192000000 10091683696 10524672000 1123536656 12.060 100 60 118 1 1187989248 52642048 11810544
09:47:41 72733 8192000000 10048817536 10487894016 1064107888 11.496 94 27 53 0 1107759984 38590528 5061568
09:49:56 94838 8192000000 10086962864 10550231040 1062345840 11.479 94 16 33 1 1108476128 43094384 3035904
09:52:31 101943 8192000000 10044668368 10493849600 1114708240 11.977 100 19 38 0 1153191824 34825664 3657920
09:55:20 72938 8192000000 10075928544 10557505536 1126799552 12.091 100 19 38 1 1198894544 67973600 4121392
09:57:39 134212 8192000000 10044439632 10498375680 1057697360 11.434 94 19 39 0 1103233776 41122032 4414384
10:01:02 84346 8192000000 10065129392 10565234688 1097037056 11.810 97 15 30 0 1145437984 45013120 3387808
10:03:33 34349 8192000000 10117662752 10560724992 1111472128 11.946 99 40 80 0 1178112928 57787328 8853472
10:05:11 51776 8192000000 10043810816 10507956224 1083695888 11.683 97 22 44 0 1141521312 53265072 4560352
10:07:03 35395 8192000000 10071000944 10518806528 1093712944 11.778 98 40 80 0 1179576528 77880576 7983008
10:08:40 54437 8192000000 10107331728 10561986560 1110882288 11.941 99 22 44 0 1187791264 72233888 4675088
10:10:36 57023 8192000000 10068429600 10495340544 1104953360 11.885 98 11 22 0 1145258944 38338608 1966976
10:12:40 141039 8192000000 10049212736 10503946240 1104576016 11.881 97 22 44 0 1179570848 70273440 4721392
10:16:36 83118 8192000000 10080772304 10536316928 1120894352 12.035 98 15 30 1 1168288384 44250272 3143760
10:19:04 36478 8192000000 10063925952 10531319808 1134547200 12.164 100 12 24 1 1174633472 37761952 2324320
10:20:41 63641 8192000000 10079355216 10557132800 1137333872 12.190 99 15 30 0 1192837344 52145232 3358240
10:22:47 55704 8192000000 10072741056 10535628800 1134916352 12.168 99 15 30 1 1160564176 22542288 3105536
10:24:43 32441 8192000000 10107634928 10551840768 1111769312 11.949 96 22 44 0 1174156320 57290720 5096288
10:26:18 92496 8192000000 10081009712 10574204928 1118663760 12.014 97 14 28 0 1207335968 85721696 2950512
10:28:57 104446 8192000000 10096410528 10579582976 1071857536 11.570 94 14 28 0 1150259600 75414128 2987936
10:31:51 54619 8192000000 10133253168 10574757888 1096902464 11.808 96 29 58 0 1188662000 85525152 6234384
10:34:54 139320 8192000000 9398936080 9748156416 506378304 5.821 45 11 22 0 552760336 44169760 2212272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 12-09-2021 10:39:37 to 12-09-2021 10:39:51.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-09-2021 10:39:52 to 12-09-2021 10:39:54.

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          229531
MON$OLDEST_ACTIVE               231382
MON$OLDEST_SNAPSHOT             231382
MON$NEXT_TRANSACTION            231382
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                       2064416
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

12.09.21 10:39:55. 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-09-12 10:39:57 No severe PSQL-related problems occured
12.09.21 10:39:59. End of report.