Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.03.2021 13:28
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.26
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 16728780 kB
MemAvailable 31512904 kB
Buffers 0 kB
Cached 14961224 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
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-03-30 13:26:54
DTS_END                         2021-03-30 13:26:54.7330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35592
TRN_ID                          240777
ATT_ID                          646
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: 9222.80 16787 1106736
customer order: creation 180.85 388 21702
customer order: refuse 15.44 281 1853
order to supplier: creation 48.37 1253 5804
order to supplier: removal 3.49 91171 419
invoice (draft): creation 48.26 3298 5791
invoice (draft): removal 49.97 829 5996
invoice accept: apply 44.10 16651 5292
invoice accept: cancel 45.10 800 5412
customer reserve: creation 4056.10 165 486732
customer reserve: removal 4431.89 57 531827
realization accept: apply 86.03 179 10323
realization accept: cancel 79.61 116 9553
payment from customer: creation 60.33 110 7239
payment from customer: removal 5.90 92 708
payment to supplier: creation 52.82 145 6338
payment to supplier: removal 3.31 86 397
service: total inventory turnovers 3.99 3261 479
service: total monetary turnovers 6.47 426 776
service: aggregate perf. data 0.76 20009 91
service: refresh index statistics 0.03 196432 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-03-2021 13:29:17 to 30-03-2021 13:29:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2867
TEST_TIME 2 3731
TEST_TIME 3 4227
TEST_TIME 4 5041
TEST_TIME 5 4955
TEST_TIME 6 5127
TEST_TIME 7 5003
TEST_TIME 8 5011
TEST_TIME 9 5142
TEST_TIME 10 5520
TEST_TIME 11 6085
TEST_TIME 12 6342
TEST_TIME 13 6806
TEST_TIME 14 6978
TEST_TIME 15 7372
TEST_TIME 16 7621
TEST_TIME 17 7537
TEST_TIME 18 8085
TEST_TIME 19 8523
TEST_TIME 20 8816
TEST_TIME 21 9189
TEST_TIME 22 9214
TEST_TIME 23 9626
TEST_TIME 24 9779
TEST_TIME 25 9915
TEST_TIME 26 9888
TEST_TIME 27 9893
TEST_TIME 28 10138
TEST_TIME 29 10659
TEST_TIME 30 10612
TEST_TIME 31 10612
TEST_TIME 32 10430
TEST_TIME 33 10438
TEST_TIME 34 10334
TEST_TIME 35 10480
TEST_TIME 36 10605
TEST_TIME 37 10650
TEST_TIME 38 10564
TEST_TIME 39 10459
TEST_TIME 40 10211
TEST_TIME 41 10063
TEST_TIME 42 9986
TEST_TIME 43 9936
TEST_TIME 44 9919
TEST_TIME 45 9874
TEST_TIME 46 9944
TEST_TIME 47 9828
TEST_TIME 48 9784
TEST_TIME 49 9712
TEST_TIME 50 9718
TEST_TIME 51 9686
TEST_TIME 52 9744
TEST_TIME 53 9670
TEST_TIME 54 9630
TEST_TIME 55 9602
TEST_TIME 56 9760
TEST_TIME 57 9850
TEST_TIME 58 9794
TEST_TIME 59 9766
TEST_TIME 60 9763
TEST_TIME 61 9775
TEST_TIME 62 9788
TEST_TIME 63 9874
TEST_TIME 64 9850
TEST_TIME 65 9833
TEST_TIME 66 9782
TEST_TIME 67 9680
TEST_TIME 68 9609
TEST_TIME 69 9545
TEST_TIME 70 9468
TEST_TIME 71 9481
TEST_TIME 72 9445
TEST_TIME 73 9399
TEST_TIME 74 9411
TEST_TIME 75 9395
TEST_TIME 76 9438
TEST_TIME 77 9439
TEST_TIME 78 9431
TEST_TIME 79 9499
TEST_TIME 80 9500
TEST_TIME 81 9484
TEST_TIME 82 9552
TEST_TIME 83 9485
TEST_TIME 84 9400
TEST_TIME 85 9394
TEST_TIME 86 9373
TEST_TIME 87 9328
TEST_TIME 88 9337
TEST_TIME 89 9281
TEST_TIME 90 9265
TEST_TIME 91 9245
TEST_TIME 92 9246
TEST_TIME 93 9317
TEST_TIME 94 9306
TEST_TIME 95 9279
TEST_TIME 96 9284
TEST_TIME 97 9351
TEST_TIME 98 9359
TEST_TIME 99 9382
TEST_TIME 100 9403
TEST_TIME 101 9407
TEST_TIME 102 9401
TEST_TIME 103 9315
TEST_TIME 104 9258
TEST_TIME 105 9188
TEST_TIME 106 9210
TEST_TIME 107 9205
TEST_TIME 108 9184
TEST_TIME 109 9167
TEST_TIME 110 9163
TEST_TIME 111 9188
TEST_TIME 112 9205
TEST_TIME 113 9177
TEST_TIME 114 9188
TEST_TIME 115 9196
TEST_TIME 116 9194
TEST_TIME 117 9258
TEST_TIME 118 9254
TEST_TIME 119 9286
TEST_TIME 120 9225

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-03-2021 13:29:21 to 30-03-2021 13:29:24.

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 1555474 1555474 0 0.00 0 30764 7 0 0
doc_list_biud 1555474 1555474 0 0.00 0 25136 25 0 0
sp_add_invoice_to_stock 5418 5292 126 2.32 23 212550 16651 0 126
sp_cancel_adding_invoice 5412 5412 0 0.00 6 82454 800 0 0
sp_cancel_client_order 1853 1853 0 0.00 4 12548 282 0 0
sp_cancel_customer_reserve 531827 531827 0 0.00 1 25140 57 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 3 6802 93 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 6584 87 0 0
sp_cancel_supplier_invoice 6000 5996 4 0.06 2 18596 830 0 4
sp_cancel_supplier_order 419 419 0 0.00 7 890651 91172 0 0
sp_cancel_write_off 9553 9553 0 0.00 1 20198 117 0 0
sp_client_order 21702 21702 0 0.00 4 19047 389 0 0
sp_customer_reserve 486732 486732 0 0.00 0 79308 165 0 0
sp_fill_shopping_cart 33275 33275 0 0.00 1 15855 41 0 0
sp_fill_shopping_cart_clo_res 486730 486730 0 0.00 0 16496 15 0 0
sp_get_clo_for_invoice 5290 5290 0 0.00 0 22317 331 0 0
sp_kill_cost_storno 16070 16070 0 0.00 0 17027 58 0 0
sp_kill_qstorno_ret_qs2qd 1079622 1079622 0 0.00 0 25131 31 0 0
sp_kill_qty_storno 570673 570673 0 0.00 0 25136 68 0 0
sp_lock_selected_doc 21570 21570 0 0.00 0 10591 6 0 0
sp_make_cost_storno 29190 29190 0 0.00 0 21965 54 0 0
sp_multiply_rows_for_pdistr 29190 29190 0 0.00 0 8778 32 0 0
sp_multiply_rows_for_qdistr 21702 21702 0 0.00 0 15230 222 0 0
sp_pay_from_customer 7239 7239 0 0.00 4 18982 111 0 0
sp_pay_to_supplier 6338 6338 0 0.00 5 14357 145 0 0
sp_payment_common 13577 13577 0 0.00 1 15558 30 0 0
sp_qd_handle_on_cancel_clo 1853 1853 0 0.00 0 10681 253 0 0
sp_qd_handle_on_invoice_upd_sts 10702 10702 0 0.00 0 21421 319 0 0
sp_qd_handle_on_reserve_upd_sts 10323 10323 0 0.00 0 16465 57 0 0
sp_reserve_write_off 10323 10323 0 0.00 6 30836 179 0 0
sp_supplier_invoice 5791 5791 0 0.00 6 90044 3299 0 0
sp_supplier_order 5804 5804 0 0.00 5 19870 1254 0 0
srv_aggregate_perf_data 91 91 0 0.00 2816 494986 20009 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2050 137692 44562 0 0
srv_find_qd_qs_mism 1055743 1055743 0 0.00 0 20196 6 0 0
srv_make_invnt_saldo 479 479 0 0.00 40 88366 3261 0 0
srv_make_money_saldo 776 776 0 0.00 1 11425 427 0 0
srv_recalc_idx_stat 4 4 0 0.00 80142 294662 196433 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5294 5294 0 0.00 0 18997 159 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 1 4912 122 0 0
t$perf-norm:sp_cancel_client_order 1853 1853 0 0.00 0 1029 9 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 755 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 431 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 4225 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 2702 20 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 32272 1483 0 0
t$perf-norm:sp_cancel_write_off 1051 1051 0 0.00 0 3540 8 0 0
t$perf-norm:sp_client_order 21702 21702 0 0.00 0 11427 17 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 1106 5 0 0
t$perf-norm:sp_pay_from_customer 7239 7239 0 0.00 0 10412 12 0 0
t$perf-norm:sp_pay_to_supplier 6338 6338 0 0.00 0 6449 10 0 0
t$perf-norm:sp_reserve_write_off 10323 10323 0 0.00 0 7062 10 0 0
t$perf-norm:sp_supplier_invoice 5791 5791 0 0.00 0 4833 14 0 0
t$perf-norm:sp_supplier_order 5804 5804 0 0.00 0 8844 15 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 36 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 6 1 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 570 7 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 648 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10832 10580 252 2.32 0 9460 14 0 252
v_all_customers 38990 38990 0 0.00 0 149 1 0 0
v_all_suppliers 32214 32214 0 0.00 0 13 1 0 0
v_all_wares 43404 43404 0 0.00 0 43 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 3805 11 0 0
v_cancel_client_order 3706 3706 0 0.00 0 1731 3 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 4286 12 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 3704 5 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 386 4 0 0
v_cancel_supplier_invoice 804 796 8 0.99 0 2875 15 0 8
v_cancel_supplier_order 838 838 0 0.00 0 2942 6 0 0
v_cancel_write_off 2102 2102 0 0.00 0 4142 12 0 0
v_max_id_clo_ord 5797 5797 0 0.00 0 3820 2 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 6621 25 0 0
v_max_id_ord_sup 5776 5776 0 0.00 0 5127 6 0 0
v_max_non_paid_invoice 1804 1804 0 0.00 0 6945 6 0 0
v_max_non_paid_realizn 4053 4053 0 0.00 0 4741 5 0 0
v_min_id_clo_ord 5797 5797 0 0.00 0 2742 6 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 3569 13 0 0
v_min_id_ord_sup 5776 5776 0 0.00 0 6982 9 0 0
v_min_non_paid_invoice 6338 6338 0 0.00 0 7438 23 0 0
v_min_non_paid_realizn 7239 7239 0 0.00 0 7830 19 0 0
v_our_firm 10786 10786 0 0.00 0 79 1 0 0
v_reserve_write_off 20646 20646 0 0.00 0 3960 5 0 0
x_lock_depdocs_on_canc_invoice 11010 11010 0 0.00 0 14553 98 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 2489 272 0 0
x_make_qstorno_1000_1200 5804 5804 0 0.00 2 19740 1179 0 0
x_make_qstorno_1200_2000 5791 5791 0 0.00 3 90007 3142 0 0
x_make_qstorno_2100_3300 486732 486732 0 0.00 0 79308 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-03-2021 13:29:25 to 30-03-2021 13:29:29.

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
10:56:58 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:57:59 14375 8192000000 9718429776 10013429760 795247168 8.848 100 83 166 1 822423712 7140560 20035984
10:59:29 9133 8192000000 9770180800 10077626368 843408576 9.334 100 90 181 0 876878048 12191056 21278416
11:00:42 20792 8192000000 9803336896 10120638464 873052320 9.630 100 85 170 0 907896544 15258512 19585712
11:02:07 19271 8192000000 9857632944 10190036992 910505136 10.002 100 76 152 1 962689520 35404784 16779600
11:03:30 8916 8192000000 9905511888 10247712768 957760144 10.467 100 82 164 0 1019702112 44878544 17063424
11:04:42 6459 8192000000 9957415424 10311094272 988859216 10.770 100 91 180 4 1075647808 68292944 18495648
11:05:51 2963 8192000000 9995040336 10351366144 1012030192 10.995 100 94 187 1 1111183696 80420512 18732992
11:06:56 3200 8192000000 10012641280 10377572352 1027706448 11.146 100 92 179 1 1120774960 74693296 18375216
11:08:01 1162 8192000000 10037711952 10405875712 1056877888 11.427 100 97 194 2 1151996160 75608928 19509344
11:09:03 57650 8192000000 9982886544 10366799872 1065968976 11.514 100 30 60 0 1100067152 27903760 6194416
11:11:01 4438 8192000000 10007660576 10408927232 1073039408 11.581 100 40 79 2 1113518496 32377824 8101264
11:12:07 6891 8192000000 10023115760 10418511872 1084589792 11.691 100 41 82 3 1128226128 34952176 8684160
11:13:15 4456 8192000000 9987908064 10384293888 1089923728 11.742 100 38 76 1 1108512208 9574336 9014144
11:14:22 57661 8192000000 10007145264 10415112192 1088267072 11.726 100 53 108 1 1116431824 16638272 11526480
11:16:25 7060 8192000000 10018935648 10414514176 1082695920 11.673 99 52 103 2 1128213872 34880752 10637200
11:17:33 2837 8192000000 10001255072 10394714112 1088326496 11.727 100 55 111 5 1112219360 10759024 13133840
11:18:38 6094 8192000000 9954425920 10345218048 1037768192 11.243 99 46 92 0 1067666320 19647792 10250336
11:19:45 62833 8192000000 9936342736 10324291584 881646256 9.716 83 24 48 0 897894032 10983808 5263968
11:21:48 9969 8192000000 9938459728 10330054656 1022556048 11.097 99 29 58 3 1048763792 19898704 6309040
11:23:03 7036 8192000000 9951419824 10350186496 1030496720 11.173 99 36 70 1 1059067808 20239904 8331184
11:24:13 10832 8192000000 9971567968 10368020480 1064362752 11.498 100 23 44 1 1096578208 27609424 4606032
11:25:25 28735 8192000000 9978915104 10393055232 1070322064 11.555 100 16 32 1 1096091728 22155344 3614320
11:26:57 20599 8192000000 10004989280 10405384192 1093736512 11.778 100 52 100 1 1120623232 16022256 10864464
11:28:24 15097 8192000000 10044231808 10444816384 1109061456 11.924 100 53 104 2 1161391744 41455456 10874832
11:29:52 9412 8192000000 10087326208 10497716224 1126254912 12.086 100 47 93 0 1193734704 57840240 9639552
11:31:06 40561 8192000000 10117396848 10530992128 1140727936 12.222 100 40 80 1 1229902496 79762304 9412256
11:32:52 13458 8192000000 10149227104 10564804608 1146540320 12.277 100 53 106 2 1262989648 105712784 10736544
11:34:12 12543 8192000000 10177065680 10590281728 1161261152 12.415 100 49 98 2 1297766448 125470992 11034304
11:35:27 22680 8192000000 10240141776 10658381824 1171993904 12.515 100 56 111 0 1348340464 163916560 12430000
11:36:53 5233 8192000000 10265684400 10700242944 1161884752 12.421 99 75 147 4 1375711232 196796832 17029648
11:38:06 4079 8192000000 10292649680 10701299712 1169140336 12.489 100 75 150 0 1391281616 205190416 16950864
11:39:18 4022 8192000000 10305213584 10723745792 1184501168 12.632 100 66 130 0 1413243616 214431040 14311408
11:40:26 4227 8192000000 10309712816 10733133824 1170473328 12.501 99 72 144 2 1403394576 216428160 16493088
11:41:36 2049 8192000000 10353491472 10759651328 1181909104 12.608 100 78 157 0 1450132336 250803728 17419504
11:42:43 14005 8192000000 10310900928 10730254336 1151670544 12.325 99 53 106 1 1407612944 243560016 12382384
11:44:07 5712 8192000000 10294227280 10723823616 1150602512 12.315 99 53 104 1 1388987200 225332688 13052000
11:45:14 12015 8192000000 10253572128 10678489088 1112586608 11.957 97 41 81 1 1343978080 222000048 9391424
11:46:33 14706 8192000000 10225934768 10681778176 1117722560 12.005 99 31 62 1 1337971216 212605808 7642848
11:47:52 13767 8192000000 10222126208 10650234880 1122179808 12.048 99 28 55 0 1323809712 194770832 6859072
11:49:10 16750 8192000000 10224313088 10652446720 1126571296 12.089 100 21 42 0 1318377408 186426944 5379168
11:50:29 13583 8192000000 10234137072 10688262144 1143910496 12.252 100 21 42 1 1329154448 180120672 5123280
11:51:50 20020 8192000000 10200427728 10677616640 1131727312 12.138 100 17 34 0 1296997792 161011760 4258720
11:53:14 20538 8192000000 10206319472 10682703872 1137083568 12.188 100 18 36 2 1329576352 188557744 3935040
11:54:36 22902 8192000000 10166663968 10671742976 1089728240 11.740 98 11 22 2 1243269456 150743936 2797280
11:56:05 60571 8192000000 10065548800 10514046976 1075254752 11.602 96 11 21 1 1110124096 32834432 2034912
11:58:07 43918 8192000000 10092425536 10510438400 1125120096 12.075 99 38 78 0 1160449168 27765808 7563264
11:59:52 31663 8192000000 10084056448 10499813376 1125295056 12.077 99 42 84 0 1158985776 24743872 8946848
12:01:25 43301 8192000000 10108605120 10585862144 1123876608 12.064 99 11 22 2 1246801584 119892208 3032768
12:03:14 137691 8192000000 10083382240 10517901312 1122261376 12.048 99 17 34 0 1185255152 59214912 3778864
12:06:38 123440 8192000000 10073077600 10516873216 1139850640 12.214 100 55 107 1 1182057680 30541664 11665376
12:09:43 10612 8192000000 10078398976 10525843456 1133789616 12.157 99 15 30 1 1173566240 36835552 2941072
12:10:56 48963 8192000000 10093470000 10553483264 1140830768 12.223 100 20 40 2 1194293600 49249280 4213552
12:12:46 60468 8192000000 10092122608 10547392512 1122535184 12.051 98 14 28 1 1176519376 50874224 3109968
12:14:47 101526 8192000000 10102106864 10560946176 1125886864 12.083 98 17 33 3 1200572640 71103792 3581984
12:17:29 32234 8192000000 10072794368 10509406208 1134397680 12.163 100 50 99 0 1181955056 36826640 10730736
12:19:02 54682 8192000000 10080049392 10523344896 1132222240 12.142 99 12 23 0 1159921376 25474160 2224976
12:21:15 51690 8192000000 10160592720 10611462144 1121368608 12.040 98 23 45 3 1212884480 86381888 5133984
12:23:28 78653 8192000000 10061124224 10487918592 1106712160 11.901 97 16 32 1 1136458240 26500208 3245872
12:25:49 43248 8192000000 10093924000 10550312960 1099220192 11.830 97 21 42 2 1163812208 60364880 4227136
12:27:38 26446 8192000000 10073249920 10492088320 1080490720 11.652 96 38 76 1 1117445328 29796016 7158592
12:29:05 50060 8192000000 10061629840 10522386432 1106038768 11.895 98 16 32 1 1179211440 69960528 3212144
12:30:58 52918 8192000000 10075696960 10519429120 1102609216 11.862 98 13 26 1 1130283024 25203504 2470304
12:32:51 107434 8192000000 10074740224 10523267072 1122990656 12.055 99 29 58 0 1168613568 39428896 6194016
12:35:39 73535 8192000000 10090661808 10557636608 1078950464 11.637 95 13 24 3 1155267376 73968192 2348720
12:37:53 47910 8192000000 10100677696 10544988160 1131774240 12.138 100 12 24 0 1196307728 62029936 2503552
12:39:53 37971 8192000000 10141784736 10583638016 1128441712 12.107 98 27 53 3 1221002384 86726352 5834320
12:41:31 59862 8192000000 10116018080 10582851584 1095469472 11.795 97 22 43 0 1196030656 95529488 5031696
12:43:52 34700 8192000000 10101494816 10534715392 1096421072 11.804 98 23 46 1 1174567936 73256272 4890592
12:45:28 74755 8192000000 10088304448 10553470976 1098529840 11.824 98 16 32 3 1166159008 64450800 3178368
12:47:51 23204 8192000000 10113734848 10535632896 1124095920 12.066 100 38 72 0 1196543872 65087424 7360528
12:49:20 68334 8192000000 10118670656 10568957952 1120183808 12.029 100 39 78 0 1194594160 66250272 8160080
12:51:33 66180 8192000000 10047982512 10542804992 1101531696 11.852 98 8 16 0 1181167392 77768192 1867504
12:53:42 80925 8192000000 10053658720 10525298688 1113333376 11.964 99 9 18 0 1172089472 56832048 1924048
12:56:05 50734 8192000000 10081664272 10526892032 1115221184 11.982 98 24 48 0 1150917024 30722224 4973616
12:57:56 92869 8192000000 10092330720 10566692864 1111385392 11.946 98 20 40 1 1229080144 112875696 4819056
13:00:30 37157 8192000000 10048222480 10504011776 1112856496 11.959 99 12 24 1 1141185120 24857344 3471280
13:02:07 59849 8192000000 10136343584 10590060544 1138425792 12.201 100 20 40 2 1208246832 65900144 3920896
13:04:12 92533 8192000000 10074477088 10548715520 1108622928 11.919 97 17 34 0 1144671776 32534640 3514208
13:06:50 64614 8192000000 10088090816 10531180544 1107202832 11.906 97 26 52 2 1136452768 24162000 5087936
13:10:53 60188 8192000000 10118173920 10576179200 1135695568 12.175 100 15 29 1 1216967696 77906512 3365616
13:12:59 57045 8192000000 10065264496 10606628864 1098011808 11.819 97 9 18 0 1155115952 55142432 1961712
13:15:03 56567 8192000000 10126844400 10590416896 1113442832 11.965 98 19 38 1 1183090944 65658432 3989680
13:17:53 52384 8192000000 10059623904 10530033664 1090201984 11.745 97 14 28 0 1166305552 73409072 2694496
13:19:48 59684 8192000000 10084738464 10524930048 1128596736 12.108 100 28 56 0 1197901104 63513824 5790544
13:21:48 43698 8192000000 10138638320 10610167808 1128430336 12.107 99 28 56 1 1186245504 51989328 5825840
13:23:40 60319 8192000000 10089939936 10545491968 1102056592 11.857 97 16 32 1 1156983040 52015792 2910656
13:25:45 73803 8192000000 9314176208 9611997184 444539712 5.147 40 9 18 0 455371552 9298128 1533712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-03-2021 13:29:31 to 30-03-2021 13:29:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-03-2021 13:29:47 to 30-03-2021 13:29:49.

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          240459
MON$OLDEST_ACTIVE               241254
MON$OLDEST_SNAPSHOT             241254
MON$NEXT_TRANSACTION            241254
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                       2111872
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             809
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

30.03.21 13:29:50. 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-03-30 13:29:53 No severe PSQL-related problems occured
30.03.21 13:29:54. End of report.