Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.08.2021 22:38
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 15983908 kB
MemAvailable 30923456 kB
Buffers 0 kB
Cached 15670700 kB
SwapCached 1328 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-24 22:37:48
DTS_END                         2021-08-24 22:37:48.7010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36300
TRN_ID                          243215
ATT_ID                          716
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: 9441.13 20007 1132936
customer order: creation 174.51 331 20941
customer order: refuse 14.37 316 1724
order to supplier: creation 45.43 1270 5451
order to supplier: removal 3.48 94376 417
invoice (draft): creation 47.25 3641 5670
invoice (draft): removal 54.78 815 6574
invoice accept: apply 42.97 17695 5156
invoice accept: cancel 49.21 734 5905
customer reserve: creation 3899.31 178 467917
customer reserve: removal 4805.23 51 576628
realization accept: apply 85.53 189 10264
realization accept: cancel 85.55 112 10266
payment from customer: creation 60.23 131 7228
payment from customer: removal 5.85 83 702
payment to supplier: creation 50.83 147 6099
payment to supplier: removal 3.58 75 430
service: total inventory turnovers 5.03 1012 604
service: total monetary turnovers 7.22 27 866
service: aggregate perf. data 0.75 21413 90
service: refresh index statistics 0.03 257537 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-08-2021 22:38:39 to 24-08-2021 22:38:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2563
TEST_TIME 2 3620
TEST_TIME 3 3893
TEST_TIME 4 3880
TEST_TIME 5 3676
TEST_TIME 6 3982
TEST_TIME 7 4265
TEST_TIME 8 4904
TEST_TIME 9 5522
TEST_TIME 10 5871
TEST_TIME 11 6315
TEST_TIME 12 6340
TEST_TIME 13 6459
TEST_TIME 14 6583
TEST_TIME 15 7027
TEST_TIME 16 7408
TEST_TIME 17 7451
TEST_TIME 18 7622
TEST_TIME 19 7862
TEST_TIME 20 8786
TEST_TIME 21 8687
TEST_TIME 22 9025
TEST_TIME 23 9319
TEST_TIME 24 9470
TEST_TIME 25 9673
TEST_TIME 26 9655
TEST_TIME 27 9741
TEST_TIME 28 9902
TEST_TIME 29 11135
TEST_TIME 30 10902
TEST_TIME 31 10758
TEST_TIME 32 10920
TEST_TIME 33 10803
TEST_TIME 34 10778
TEST_TIME 35 10611
TEST_TIME 36 10741
TEST_TIME 37 10731
TEST_TIME 38 10633
TEST_TIME 39 10475
TEST_TIME 40 10349
TEST_TIME 41 10238
TEST_TIME 42 10294
TEST_TIME 43 10443
TEST_TIME 44 10398
TEST_TIME 45 10341
TEST_TIME 46 10240
TEST_TIME 47 10136
TEST_TIME 48 10057
TEST_TIME 49 9915
TEST_TIME 50 9873
TEST_TIME 51 9818
TEST_TIME 52 9789
TEST_TIME 53 9706
TEST_TIME 54 9779
TEST_TIME 55 9770
TEST_TIME 56 9740
TEST_TIME 57 9735
TEST_TIME 58 9699
TEST_TIME 59 9635
TEST_TIME 60 9615
TEST_TIME 61 9491
TEST_TIME 62 9554
TEST_TIME 63 9493
TEST_TIME 64 9482
TEST_TIME 65 9510
TEST_TIME 66 9552
TEST_TIME 67 9636
TEST_TIME 68 9660
TEST_TIME 69 9651
TEST_TIME 70 9775
TEST_TIME 71 9722
TEST_TIME 72 9657
TEST_TIME 73 9798
TEST_TIME 74 9799
TEST_TIME 75 9743
TEST_TIME 76 9809
TEST_TIME 77 9691
TEST_TIME 78 9674
TEST_TIME 79 9646
TEST_TIME 80 9599
TEST_TIME 81 9616
TEST_TIME 82 9565
TEST_TIME 83 9549
TEST_TIME 84 9497
TEST_TIME 85 9439
TEST_TIME 86 9391
TEST_TIME 87 9375
TEST_TIME 88 9357
TEST_TIME 89 9393
TEST_TIME 90 9418
TEST_TIME 91 9411
TEST_TIME 92 9416
TEST_TIME 93 9486
TEST_TIME 94 9461
TEST_TIME 95 9423
TEST_TIME 96 9460
TEST_TIME 97 9532
TEST_TIME 98 9666
TEST_TIME 99 9668
TEST_TIME 100 9645
TEST_TIME 101 9684
TEST_TIME 102 9685
TEST_TIME 103 9646
TEST_TIME 104 9564
TEST_TIME 105 9611
TEST_TIME 106 9584
TEST_TIME 107 9588
TEST_TIME 108 9538
TEST_TIME 109 9529
TEST_TIME 110 9512
TEST_TIME 111 9472
TEST_TIME 112 9448
TEST_TIME 113 9428
TEST_TIME 114 9440
TEST_TIME 115 9437
TEST_TIME 116 9422
TEST_TIME 117 9444
TEST_TIME 118 9459
TEST_TIME 119 9423
TEST_TIME 120 9443

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-08-2021 22:38:42 to 24-08-2021 22:38:46.

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 1566747 1566747 0 0.00 0 25378 6 0 0
doc_list_biud 1566745 1566745 0 0.00 0 54238 25 0 0
sp_add_invoice_to_stock 5234 5156 78 1.49 27 205058 17696 0 78
sp_cancel_adding_invoice 5905 5905 0 0.00 4 71071 735 0 0
sp_cancel_client_order 1724 1724 0 0.00 4 6991 317 0 0
sp_cancel_customer_reserve 576628 576628 0 0.00 1 54239 52 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 8193 84 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 1786 76 0 0
sp_cancel_supplier_invoice 6579 6574 5 0.07 1 15375 816 0 5
sp_cancel_supplier_order 417 417 0 0.00 7 982164 94377 0 0
sp_cancel_write_off 10266 10266 0 0.00 1 18121 112 0 0
sp_client_order 20941 20941 0 0.00 3 23547 331 0 0
sp_customer_reserve 467917 467917 0 0.00 0 70238 179 0 0
sp_fill_shopping_cart 32030 32030 0 0.00 1 15395 44 0 0
sp_fill_shopping_cart_clo_res 467915 467915 0 0.00 0 14440 12 0 0
sp_get_clo_for_invoice 5152 5152 0 0.00 0 29925 275 0 0
sp_kill_cost_storno 17303 17303 0 0.00 0 11576 59 0 0
sp_kill_qstorno_ret_qs2qd 1170513 1170513 0 0.00 0 54218 29 0 0
sp_kill_qty_storno 616927 616927 0 0.00 0 54237 63 0 0
sp_lock_selected_doc 21211 21211 0 0.00 0 7109 4 0 0
sp_make_cost_storno 28742 28742 0 0.00 0 25374 59 0 0
sp_multiply_rows_for_pdistr 28740 28740 0 0.00 0 14815 32 0 0
sp_multiply_rows_for_qdistr 20941 20941 0 0.00 0 14439 129 0 0
sp_pay_from_customer 7228 7228 0 0.00 4 14042 131 0 0
sp_pay_to_supplier 6099 6099 0 0.00 5 23661 148 0 0
sp_payment_common 13327 13327 0 0.00 1 23636 42 0 0
sp_qd_handle_on_cancel_clo 1724 1724 0 0.00 0 6986 289 0 0
sp_qd_handle_on_invoice_upd_sts 11055 11055 0 0.00 0 17148 331 0 0
sp_qd_handle_on_reserve_upd_sts 10263 10263 0 0.00 0 15548 61 0 0
sp_reserve_write_off 10264 10264 0 0.00 6 15597 190 0 0
sp_supplier_invoice 5670 5670 0 0.00 7 94757 3642 0 0
sp_supplier_order 5451 5451 0 0.00 5 41156 1270 0 0
srv_aggregate_perf_data 91 90 1 1.09 3065 486466 21414 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 1513 188082 48693 0 0
srv_find_qd_qs_mism 1084623 1084623 0 0.00 0 18258 5 0 0
srv_make_invnt_saldo 604 604 0 0.00 32 39797 1013 0 0
srv_make_money_saldo 866 866 0 0.00 1 3671 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 59068 492410 257538 0 0
t$perf-abend:sp_add_invoice_to_stock 78 78 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5156 5156 0 0.00 0 20765 189 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 0 14629 128 0 0
t$perf-norm:sp_cancel_client_order 1724 1724 0 0.00 0 3676 17 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 2770 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 2619 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 641 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 8316 37 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 22533 1701 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 1442 7 0 0
t$perf-norm:sp_client_order 20941 20941 0 0.00 0 13974 20 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 1848 8 0 0
t$perf-norm:sp_pay_from_customer 7228 7228 0 0.00 0 5994 12 0 0
t$perf-norm:sp_pay_to_supplier 6099 6099 0 0.00 0 6900 13 0 0
t$perf-norm:sp_reserve_write_off 10264 10264 0 0.00 0 7892 12 0 0
t$perf-norm:sp_supplier_invoice 5670 5670 0 0.00 0 5503 17 0 0
t$perf-norm:sp_supplier_order 5451 5451 0 0.00 0 9586 21 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 474 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 3927 74 0 0
t$perf-norm:srv_make_invnt_saldo 604 604 0 0.00 0 1976 27 0 0
t$perf-norm:srv_make_money_saldo 866 866 0 0.00 0 1832 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 23 9 0 0
v_add_invoice_to_stock 10452 10296 156 1.49 0 7230 21 0 156
v_all_customers 38030 38030 0 0.00 0 20 1 0 0
v_all_suppliers 30951 30951 0 0.00 0 17 1 0 0
v_all_wares 41880 41880 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 6663 17 0 0
v_cancel_client_order 3448 3448 0 0.00 0 6662 7 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 1857 6 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 4815 9 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 828 8 0 0
v_cancel_supplier_invoice 860 850 10 1.16 0 5507 24 0 10
v_cancel_supplier_order 834 834 0 0.00 0 424 4 0 0
v_cancel_write_off 1994 1994 0 0.00 0 3297 17 0 0
v_max_id_clo_ord 5446 5446 0 0.00 0 361 1 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 7127 22 0 0
v_max_id_ord_sup 5643 5643 0 0.00 0 7052 8 0 0
v_max_non_paid_invoice 1712 1712 0 0.00 0 3653 5 0 0
v_max_non_paid_realizn 3811 3811 0 0.00 0 4530 6 0 0
v_min_id_clo_ord 5446 5446 0 0.00 0 3423 8 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 432 3 0 0
v_min_id_ord_sup 5643 5643 0 0.00 0 7084 10 0 0
v_min_non_paid_invoice 6099 6099 0 0.00 0 23626 26 0 0
v_min_non_paid_realizn 7228 7228 0 0.00 0 12956 33 0 0
v_our_firm 10682 10682 0 0.00 0 15 1 0 0
v_reserve_write_off 20525 20525 0 0.00 0 7525 8 0 0
x_lock_depdocs_on_canc_invoice 12054 12054 0 0.00 0 13614 109 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 13904 388 0 0
x_make_qstorno_1000_1200 5451 5451 0 0.00 1 41148 1191 0 0
x_make_qstorno_1200_2000 5670 5670 0 0.00 3 94748 3474 0 0
x_make_qstorno_2100_3300 467917 467917 0 0.00 0 70236 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-08-2021 22:38:47 to 24-08-2021 22:38:51.

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
20:07:52 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:53 34728 8192000000 10365662208 10684321792 1412665136 14.708 100 91 182 0 1448641776 5939776 30036864
20:10:32 19961 8192000000 10428044768 10761998336 1482462608 15.323 100 90 180 0 1520746560 8845856 29438096
20:11:58 17833 8192000000 10499521232 10846351360 1555805776 15.960 100 80 160 1 1590189168 9475744 24907648
20:13:22 11061 8192000000 10568766336 10926010368 1622991568 16.535 100 76 152 1 1655950272 10740096 22218608
20:14:36 11723 8192000000 10647296224 11021877248 1706958368 17.243 100 88 176 2 1745695264 13018832 25718064
20:15:50 6183 8192000000 10700994784 11084455936 1762998688 17.709 100 72 144 3 1795291360 12555008 19737664
20:16:59 5177 8192000000 10760855200 11146522624 1816856704 18.152 100 82 160 3 1852237376 12959232 22421440
20:18:06 1887 8192000000 10796338192 11191271424 1863002096 18.528 100 87 173 1 1900250384 12822496 24425792
20:19:08 16993 8192000000 10798726096 11207507968 1875899392 18.632 100 34 68 0 1891930784 6598912 9432480
20:20:27 6042 8192000000 10791070256 11218726912 1881485440 18.677 100 23 46 1 1894329968 6367968 6476560
20:21:35 7364 8192000000 10807984928 11237351424 1897553184 18.807 100 25 50 2 1911336624 7077440 6706000
20:22:43 71758 8192000000 10855306944 11260022784 1901460352 18.838 100 42 85 4 1919159872 5767200 11932320
20:24:55 4043 8192000000 10829985792 11262996480 1914365024 18.942 100 27 54 2 1928153952 6219168 7569760
20:26:00 14380 8192000000 10853808640 11274891264 1922288048 19.005 100 16 30 3 1932515488 5424416 4803024
20:27:14 8019 8192000000 10821771920 11263266816 1898572416 18.815 99 13 26 0 1908589952 6007552 4009984
20:28:26 5217 8192000000 10793972960 11220279296 1859335872 18.498 99 27 54 0 1873973248 6858608 7778768
20:29:31 5051 8192000000 10740925728 11162554368 1798499328 18.002 98 29 58 1 1812810176 5792528 8518320
20:30:37 61320 8192000000 10679558240 11093848064 1657022848 16.824 92 20 40 1 1668100784 5136720 5941216
20:32:43 7133 8192000000 10742484672 11156430848 1813502048 18.125 100 24 46 1 1826275088 5199408 7573632
20:33:50 6260 8192000000 10768248864 11188928512 1847441776 18.401 100 35 68 1 1862775344 5669056 9664512
20:34:59 6374 8192000000 10772890576 11207000064 1857172240 18.480 100 24 48 2 1874531824 10754736 6604848
20:36:06 21236 8192000000 10776994528 11211116544 1864442656 18.539 100 33 66 2 1880394544 5797056 10154832
20:37:29 8394 8192000000 10814175680 11239350272 1894795984 18.784 100 28 56 0 1907996544 4877440 8323120
20:38:41 31837 8192000000 10849927312 11277877248 1922335888 19.006 100 58 115 2 1948887440 9391472 17160080
20:40:20 32454 8192000000 10871246592 11304472576 1943856352 19.178 100 47 94 0 1968055696 11014192 13185152
20:42:05 29417 8192000000 10900006992 11335061504 1966416208 19.357 100 43 86 2 1994782752 15502448 12864096
20:43:39 31018 8192000000 10907711472 11347251200 1973419440 19.413 100 38 73 3 2003554848 19238960 10896448
20:45:17 5488 8192000000 10931014416 11359891456 1977527904 19.445 100 55 109 1 2014408416 20635904 16244608
20:46:27 4378 8192000000 10935472288 11367047168 1986428384 19.516 100 65 130 0 2025277120 20066864 18781872
20:47:35 2619 8192000000 10953947488 11380224000 1996334560 19.594 100 73 143 1 2043601360 26053024 21213776
20:48:41 3730 8192000000 10948747952 11383984128 2001085920 19.631 100 58 115 1 2041732656 24027264 16619472
20:50:08 3524 8192000000 10962340608 11391459328 1998775568 19.613 100 62 120 3 2046811696 30211248 17824880
20:51:17 5200 8192000000 10945251632 11361894400 1963656592 19.335 99 70 140 0 2018205472 33504688 21044192
20:52:26 2574 8192000000 10956941200 11366379520 1959162384 19.299 99 87 174 1 2022605808 37681072 25762352
20:53:32 3452 8192000000 10908522256 11319013376 1907205328 18.884 99 69 137 1 1966195136 37891840 21097968
20:54:43 7199 8192000000 10934323040 11351248896 1952255248 19.244 100 67 133 1 2012915968 39548464 21112256
20:55:58 1512 8192000000 10957359968 11372347392 1965660192 19.351 100 75 146 2 2037032016 47473072 23898752
20:57:07 2199 8192000000 10982365904 11390308352 1974274000 19.419 100 88 176 3 2057247824 56214976 26758848
20:58:13 11451 8192000000 10943530416 11376463872 1957983136 19.290 100 39 78 0 2014147360 43600816 12563408
20:59:28 11025 8192000000 10916870640 11353186304 1932395472 19.086 100 34 68 2 1997425376 53574528 11455376
21:00:49 19505 8192000000 10904496864 11362430976 1913879360 18.938 98 29 58 0 1976939968 53105696 9954912
21:02:10 11866 8192000000 10908065024 11353018368 1927009872 19.043 99 25 49 0 1977801424 42071712 8719840
21:03:27 30195 8192000000 10918519728 11371057152 1947614752 19.207 100 23 46 1 2007891472 51869808 8406912
21:05:13 16545 8192000000 10934170944 11380269056 1949560368 19.223 100 31 62 2 2012813728 52243888 11009472
21:06:54 54795 8192000000 10899268320 11354722304 1955214144 19.268 100 19 38 0 1979469312 18167808 6087360
21:08:50 68644 8192000000 10895612512 11370999808 1941122176 19.156 99 11 22 0 1959385648 14701232 3562240
21:10:59 38589 8192000000 10900721744 11363037184 1970176704 19.387 100 24 47 5 1987572640 10186768 7209168
21:12:38 72223 8192000000 10885716240 11352838144 1927970384 19.051 98 12 22 1 1945983280 14834704 3178192
21:14:54 105377 8192000000 10912127440 11367993344 1955616048 19.271 99 13 26 1 1973992240 14979136 3397056
21:18:01 28530 8192000000 10960234912 11383341056 1976612688 19.438 99 59 114 1 2004536864 11117216 16806960
21:19:32 98868 8192000000 10926244400 11410751488 1965131440 19.347 99 7 14 0 1986868288 19750064 1986784
21:22:11 121091 8192000000 10916004784 11382722560 1897955120 18.810 95 9 18 1 1920499840 19755328 2789392
21:25:14 86306 8192000000 10944704144 11390222336 1953794384 19.257 98 37 74 1 1973426448 8882592 10749472
21:27:44 15811 8192000000 10940895952 11392270336 1998660352 19.612 100 47 93 0 2025373280 14013456 12699472
21:29:01 42308 8192000000 10922933888 11392786432 1991790112 19.558 100 18 36 0 2008258016 11063248 5404656
21:30:47 50290 8192000000 10941605680 11403411456 1975792448 19.431 98 34 69 2 2002134512 16702256 9639808
21:32:41 43152 8192000000 10927723808 11419594752 1981678240 19.478 99 12 24 1 2009897152 24554128 3664784
21:34:24 188082 8192000000 10871657344 11353571328 1829202016 18.253 92 12 24 0 1842970544 10212176 3556352
21:39:24 83783 8192000000 10899119008 11367571456 1887835728 18.728 95 12 24 1 1898615648 7939216 2840704
21:41:49 48946 8192000000 10886159392 11340664832 1891363680 18.757 97 33 66 2 1915976240 14352448 10260112
21:43:42 75648 8192000000 10868341344 11346534400 1896886768 18.801 97 8 16 0 1919796736 20575424 2334544
21:45:58 68318 8192000000 10880537728 11354533888 1898601392 18.815 97 11 21 3 1918394080 16217168 3575520
21:48:07 52747 8192000000 10895384368 11341152256 1884954192 18.705 96 35 70 2 1913209056 17858016 10396848
21:50:17 108860 8192000000 10845204352 11303227392 1861194096 18.513 96 14 28 0 1883826976 19110976 3521904
21:53:10 83986 8192000000 10855639840 11331637248 1917315584 18.965 99 25 50 2 1935719088 11472608 6930896
21:55:50 89628 8192000000 10875218128 11349422080 1944612768 19.184 100 29 57 0 1966140576 13596240 7931568
21:58:20 62602 8192000000 10840010096 11321126912 1900679136 18.832 98 18 35 0 1916415888 11017424 4719328
22:01:31 71020 8192000000 10861017184 11341955072 1931707040 19.081 99 11 22 1 1942824384 7835712 3281632
22:03:43 53096 8192000000 10891957184 11363696640 1961213904 19.316 100 21 42 2 1978101776 11377872 5510000
22:05:36 74544 8192000000 10881940256 11374608384 1930253936 19.069 98 12 24 0 1945124960 11355408 3515616
22:07:51 41300 8192000000 10915130768 11379232768 1964350784 19.341 99 18 36 1 1978338640 9181696 4806160
22:09:58 78817 8192000000 10882773312 11362729984 1901513648 18.838 96 18 36 3 1925622384 19056240 5052496
22:12:20 46324 8192000000 10939177664 11403345920 1968175728 19.371 99 35 70 0 2002690704 24166256 10348720
22:14:10 84685 8192000000 10895895408 11392786432 1931713888 19.081 98 8 16 0 1962995936 28492720 2789328
22:16:46 40446 8192000000 10910850416 11376091136 1940612784 19.152 98 23 46 0 1956468208 9109264 6746160
22:18:33 74957 8192000000 10923440928 11395911680 1923996464 19.019 97 11 22 0 1946475088 19575152 2903472
22:20:48 101086 8192000000 10908594688 11391635456 1977413744 19.444 100 13 26 0 1994854352 13621856 3818752
22:23:29 27714 8192000000 10882289216 11368787968 1944139696 19.180 99 15 30 0 1960128736 11788736 4200304
22:25:09 72218 8192000000 10865510240 11343618048 1878727248 18.655 95 12 24 0 1891464480 9247216 3490016
22:27:30 48916 8192000000 10849193920 11316260864 1848034656 18.406 94 12 24 2 1857976032 6565008 3376368
22:30:22 56322 8192000000 10888805408 11377344512 1957964128 19.290 99 29 57 0 1977663072 11678896 8020048
22:33:05 57904 8192000000 10887138544 11360256000 1926359984 19.038 98 15 28 0 1936796592 7135232 3301376
22:35:15 64538 8192000000 10883105984 11352805376 1887412864 18.725 96 29 58 1 1905647664 10195984 8038816
22:37:31 20475 8192000000 9393516944 9696018432 542029488 6.205 28 6 12 0 549395136 6312688 1052960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 24-08-2021 22:38:53 to 24-08-2021 22:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 78 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-08-2021 22:39:08 to 24-08-2021 22:39:10.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243956
MON$OLDEST_ACTIVE               243957
MON$OLDEST_SNAPSHOT             243957
MON$NEXT_TRANSACTION            243957
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-03 16:41:55.3470
MON$PAGES                       2058400
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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-oltp30 according to config parameter 'remove_isql_logs'

24.08.21 22:39:11. There are 3 logs in /var/tmp/logs-oltp30 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-24 22:39:13 No severe PSQL-related problems occured
24.08.21 22:39:15. End of report.