Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.08.2021 16: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 16076916 kB
MemAvailable 31017448 kB
Buffers 0 kB
Cached 15550060 kB
SwapCached 2580 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 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-04 16:37:46
DTS_END                         2021-08-04 16:37:46.2710
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(57:17)
IP                              ::1/54846
TRN_ID                          246545
ATT_ID                          665
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: 9596.42 22132 1151571
customer order: creation 181.32 363 21758
customer order: refuse 16.10 353 1932
order to supplier: creation 49.52 1350 5942
order to supplier: removal 3.42 88875 410
invoice (draft): creation 47.87 3290 5744
invoice (draft): removal 53.67 718 6440
invoice accept: apply 44.75 17407 5370
invoice accept: cancel 48.01 764 5761
customer reserve: creation 4084.60 174 490152
customer reserve: removal 4758.67 49 571040
realization accept: apply 89.71 192 10765
realization accept: cancel 79.83 100 9579
payment from customer: creation 63.68 132 7641
payment from customer: removal 6.08 121 730
payment to supplier: creation 52.11 136 6253
payment to supplier: removal 3.69 73 443
service: total inventory turnovers 5.17 1075 620
service: total monetary turnovers 7.34 36 881
service: aggregate perf. data 0.88 21218 106
service: refresh index statistics 0.03 306210 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-08-2021 16:38:44 to 04-08-2021 16:38:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4481
TEST_TIME 2 4690
TEST_TIME 3 4896
TEST_TIME 4 5598
TEST_TIME 5 5703
TEST_TIME 6 5733
TEST_TIME 7 5799
TEST_TIME 8 5868
TEST_TIME 9 5832
TEST_TIME 10 5868
TEST_TIME 11 6400
TEST_TIME 12 6470
TEST_TIME 13 6761
TEST_TIME 14 7346
TEST_TIME 15 7699
TEST_TIME 16 7799
TEST_TIME 17 8600
TEST_TIME 18 8949
TEST_TIME 19 9014
TEST_TIME 20 8989
TEST_TIME 21 9318
TEST_TIME 22 9446
TEST_TIME 23 9604
TEST_TIME 24 9733
TEST_TIME 25 9915
TEST_TIME 26 10372
TEST_TIME 27 10435
TEST_TIME 28 10519
TEST_TIME 29 10429
TEST_TIME 30 10605
TEST_TIME 31 10618
TEST_TIME 32 10404
TEST_TIME 33 10265
TEST_TIME 34 10110
TEST_TIME 35 10037
TEST_TIME 36 10065
TEST_TIME 37 9999
TEST_TIME 38 10049
TEST_TIME 39 9979
TEST_TIME 40 9966
TEST_TIME 41 10057
TEST_TIME 42 10074
TEST_TIME 43 9950
TEST_TIME 44 10037
TEST_TIME 45 10035
TEST_TIME 46 9968
TEST_TIME 47 9866
TEST_TIME 48 9746
TEST_TIME 49 9735
TEST_TIME 50 9704
TEST_TIME 51 9610
TEST_TIME 52 9640
TEST_TIME 53 9565
TEST_TIME 54 9635
TEST_TIME 55 9567
TEST_TIME 56 9549
TEST_TIME 57 9476
TEST_TIME 58 9420
TEST_TIME 59 9306
TEST_TIME 60 9393
TEST_TIME 61 9338
TEST_TIME 62 9485
TEST_TIME 63 9470
TEST_TIME 64 9522
TEST_TIME 65 9567
TEST_TIME 66 9627
TEST_TIME 67 9637
TEST_TIME 68 9715
TEST_TIME 69 9680
TEST_TIME 70 9736
TEST_TIME 71 9820
TEST_TIME 72 9779
TEST_TIME 73 9935
TEST_TIME 74 9879
TEST_TIME 75 9802
TEST_TIME 76 9732
TEST_TIME 77 9743
TEST_TIME 78 9683
TEST_TIME 79 9668
TEST_TIME 80 9697
TEST_TIME 81 9635
TEST_TIME 82 9632
TEST_TIME 83 9582
TEST_TIME 84 9539
TEST_TIME 85 9540
TEST_TIME 86 9597
TEST_TIME 87 9552
TEST_TIME 88 9540
TEST_TIME 89 9592
TEST_TIME 90 9553
TEST_TIME 91 9565
TEST_TIME 92 9684
TEST_TIME 93 9650
TEST_TIME 94 9749
TEST_TIME 95 9780
TEST_TIME 96 9733
TEST_TIME 97 9756
TEST_TIME 98 9814
TEST_TIME 99 9837
TEST_TIME 100 9754
TEST_TIME 101 9708
TEST_TIME 102 9729
TEST_TIME 103 9687
TEST_TIME 104 9665
TEST_TIME 105 9643
TEST_TIME 106 9662
TEST_TIME 107 9629
TEST_TIME 108 9565
TEST_TIME 109 9538
TEST_TIME 110 9552
TEST_TIME 111 9557
TEST_TIME 112 9538
TEST_TIME 113 9553
TEST_TIME 114 9521
TEST_TIME 115 9558
TEST_TIME 116 9535
TEST_TIME 117 9601
TEST_TIME 118 9598
TEST_TIME 119 9607
TEST_TIME 120 9602

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-08-2021 16:38:48 to 04-08-2021 16:38:51.

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 1605752 1605752 0 0.00 0 23808 6 0 0
doc_list_biud 1605751 1605751 0 0.00 0 32010 23 0 0
sp_add_invoice_to_stock 5528 5370 158 2.85 34 224971 17407 0 158
sp_cancel_adding_invoice 5761 5761 0 0.00 3 66446 765 0 0
sp_cancel_client_order 1932 1932 0 0.00 4 11673 354 0 0
sp_cancel_customer_reserve 571040 571040 0 0.00 1 16901 49 0 0
sp_cancel_pay_from_customer 730 730 0 0.00 2 16516 122 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 3 1562 73 0 0
sp_cancel_supplier_invoice 6451 6440 11 0.17 1 21059 719 0 11
sp_cancel_supplier_order 410 410 0 0.00 8 935613 88876 0 0
sp_cancel_write_off 9579 9579 0 0.00 1 10875 101 0 0
sp_client_order 21758 21758 0 0.00 3 21098 364 0 0
sp_customer_reserve 490152 490152 0 0.00 0 108131 175 0 0
sp_fill_shopping_cart 33409 33409 0 0.00 1 19187 39 0 0
sp_fill_shopping_cart_clo_res 490144 490144 0 0.00 0 12087 12 0 0
sp_get_clo_for_invoice 5362 5362 0 0.00 0 28580 280 0 0
sp_kill_cost_storno 16513 16513 0 0.00 0 10145 50 0 0
sp_kill_qstorno_ret_qs2qd 1158509 1158509 0 0.00 0 16898 27 0 0
sp_kill_qty_storno 611286 611286 0 0.00 0 32007 60 0 0
sp_lock_selected_doc 22319 22319 0 0.00 0 12222 7 0 0
sp_make_cost_storno 30018 30018 0 0.00 0 19337 56 0 0
sp_multiply_rows_for_pdistr 30017 30017 0 0.00 0 11269 29 0 0
sp_multiply_rows_for_qdistr 21758 21758 0 0.00 0 12624 152 0 0
sp_pay_from_customer 7641 7641 0 0.00 4 22756 133 0 0
sp_pay_to_supplier 6253 6253 0 0.00 5 21270 136 0 0
sp_payment_common 13893 13893 0 0.00 1 13372 32 0 0
sp_qd_handle_on_cancel_clo 1932 1932 0 0.00 0 11663 329 0 0
sp_qd_handle_on_invoice_upd_sts 11120 11120 0 0.00 0 32002 325 0 0
sp_qd_handle_on_reserve_upd_sts 10765 10765 0 0.00 0 15650 60 0 0
sp_reserve_write_off 10765 10765 0 0.00 7 24514 192 0 0
sp_supplier_invoice 5744 5744 0 0.00 7 85309 3291 0 0
sp_supplier_order 5942 5942 0 0.00 5 20907 1350 0 0
srv_aggregate_perf_data 106 106 0 0.00 2989 454629 21219 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2116 157639 46120 0 0
srv_find_qd_qs_mism 1101001 1101001 0 0.00 0 23807 5 0 0
srv_make_invnt_saldo 620 620 0 0.00 43 82197 1076 0 0
srv_make_money_saldo 881 881 0 0.00 1 10447 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 165441 421251 306210 0 0
t$perf-abend:sp_add_invoice_to_stock 158 158 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5370 5370 0 0.00 0 8075 159 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 5540 80 0 0
t$perf-norm:sp_cancel_client_order 1932 1932 0 0.00 0 2465 13 0 0
t$perf-norm:sp_cancel_customer_reserve 756 756 0 0.00 0 4710 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 730 730 0 0.00 0 787 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 639 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 3479 22 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 25019 1511 0 0
t$perf-norm:sp_cancel_write_off 1069 1069 0 0.00 0 978 6 0 0
t$perf-norm:sp_client_order 21758 21758 0 0.00 0 9058 19 0 0
t$perf-norm:sp_customer_reserve 1070 1070 0 0.00 0 4788 14 0 0
t$perf-norm:sp_pay_from_customer 7641 7641 0 0.00 0 6930 14 0 0
t$perf-norm:sp_pay_to_supplier 6253 6253 0 0.00 0 4976 10 0 0
t$perf-norm:sp_reserve_write_off 10765 10765 0 0.00 0 7654 11 0 0
t$perf-norm:sp_supplier_invoice 5744 5744 0 0.00 0 7868 17 0 0
t$perf-norm:sp_supplier_order 5942 5942 0 0.00 0 5318 19 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 310 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 299 9 0 0
t$perf-norm:srv_make_invnt_saldo 620 620 0 0.00 0 5701 33 0 0
t$perf-norm:srv_make_money_saldo 881 881 0 0.00 0 2750 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 11034 10718 316 2.86 0 8467 22 0 316
v_all_customers 39072 39072 0 0.00 0 75 1 0 0
v_all_suppliers 32158 32158 0 0.00 0 20 1 0 0
v_all_wares 43516 43516 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 4929 14 0 0
v_cancel_client_order 3864 3864 0 0.00 0 3841 6 0 0
v_cancel_customer_prepayment 1460 1460 0 0.00 0 16309 29 0 0
v_cancel_customer_reserve 1512 1512 0 0.00 0 4699 12 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 1517 8 0 0
v_cancel_supplier_invoice 880 858 22 2.50 0 4063 24 0 22
v_cancel_supplier_order 820 820 0 0.00 0 2158 7 0 0
v_cancel_write_off 2138 2138 0 0.00 0 4747 15 0 0
v_max_id_clo_ord 5936 5936 0 0.00 0 1402 2 0 0
v_max_id_clo_res 1070 1070 0 0.00 0 2780 16 0 0
v_max_id_ord_sup 5715 5715 0 0.00 0 4189 4 0 0
v_max_non_paid_invoice 1824 1824 0 0.00 0 447 3 0 0
v_max_non_paid_realizn 4264 4264 0 0.00 0 7812 6 0 0
v_min_id_clo_ord 5936 5936 0 0.00 0 7816 10 0 0
v_min_id_clo_res 1070 1070 0 0.00 0 8820 11 0 0
v_min_id_ord_sup 5715 5715 0 0.00 0 4664 6 0 0
v_min_non_paid_invoice 6252 6252 0 0.00 0 6594 20 0 0
v_min_non_paid_realizn 7641 7641 0 0.00 0 8580 16 0 0
v_our_firm 11198 11198 0 0.00 0 75 1 0 0
v_reserve_write_off 21530 21530 0 0.00 0 16309 9 0 0
x_lock_depdocs_on_canc_invoice 11772 11772 0 0.00 0 21450 111 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 3220 316 0 0
x_make_qstorno_1000_1200 5942 5942 0 0.00 2 20903 1256 0 0
x_make_qstorno_1200_2000 5744 5744 0 0.00 3 85300 3151 0 0
x_make_qstorno_2100_3300 490152 490152 0 0.00 0 108130 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-08-2021 16:38:52 to 04-08-2021 16:38:56.

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
14:07:50 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:51 9343 8192000000 10357863248 10672553984 1417274640 14.749 100 89 179 0 1452507120 6077488 29154992
14:10:05 17049 8192000000 10435863296 10765733888 1490897168 15.397 100 90 180 1 1528316928 8472144 28947616
14:11:28 13372 8192000000 10467051536 10804719616 1523193728 15.678 100 86 172 1 1559710016 9622496 26893792
14:12:55 14522 8192000000 10545951840 10896293888 1593719440 16.286 100 79 158 0 1628766128 11249536 23797152
14:14:12 12667 8192000000 10609379312 10976485376 1674947728 16.975 100 84 168 1 1712175184 12624848 24602608
14:15:26 6942 8192000000 10679129008 11055939584 1740751184 17.525 100 79 158 1 1776264816 13068448 22445184
14:16:36 4186 8192000000 10737900720 11116412928 1792523280 17.953 100 89 178 0 1831899984 14184544 25192160
14:17:42 2258 8192000000 10781389424 11164811264 1829693168 18.257 100 88 177 0 1868431088 14231152 24506768
14:18:46 1982 8192000000 10821244912 11207499776 1870601552 18.589 100 90 181 1 1914718400 17973008 26143840
14:19:48 4777 8192000000 10794362160 11200471040 1874548240 18.621 100 35 70 3 1892409200 8543248 9317712
14:20:54 8664 8192000000 10794600064 11220357120 1886121712 18.715 100 25 50 2 1899351904 6307616 6922576
14:22:05 10185 8192000000 10802690112 11228250112 1891910416 18.761 100 21 42 2 1904121280 6291712 5919152
14:23:17 46887 8192000000 10827009536 11254906880 1902083328 18.843 100 32 63 0 1916546704 5431440 9031936
14:25:08 4045 8192000000 10831846464 11252576256 1895247952 18.788 99 42 84 4 1917563664 10257712 12058000
14:26:18 12851 8192000000 10823194064 11266871296 1897041328 18.802 99 9 19 2 1905840320 6510528 2288464
14:27:34 72452 8192000000 10799846240 11230425088 1820447168 18.181 95 34 67 1 1836776864 5804176 10525520
14:29:47 6747 8192000000 10763203392 11184717824 1832719392 18.282 99 33 66 1 1848039088 5514768 9804928
14:30:54 8302 8192000000 10694755664 11113291776 1748388224 17.588 97 23 46 1 1760687616 5052320 7247072
14:32:04 61823 8192000000 10716352720 11140337664 1795427120 17.976 99 26 52 0 1808044768 4964800 7652848
14:34:14 7407 8192000000 10763943680 11182587904 1830699184 18.265 99 22 44 1 1843026592 5476080 6851328
14:35:23 5480 8192000000 10780948368 11210280960 1868298816 18.571 100 27 54 2 1881591872 5070912 8222144
14:36:29 9797 8192000000 10800043456 11223724032 1875618896 18.630 100 27 54 0 1888843392 5200976 8023520
14:37:40 22513 8192000000 10820735824 11245834240 1898207856 18.812 100 47 93 0 1917444688 5039760 14197072
14:39:07 24983 8192000000 10867544848 11292704768 1935032704 19.107 100 49 98 0 1957499952 8934384 13532864
14:40:39 12172 8192000000 10884858000 11311669248 1951252400 19.236 100 51 101 2 1980015568 14851568 13911600
14:42:02 3522 8192000000 10911646720 11335188480 1969581968 19.382 100 60 117 1 2001278640 15241408 16455264
14:43:19 40994 8192000000 10908312128 11351941120 1978046400 19.449 100 40 80 3 2008811744 19165024 11600320
14:45:03 8624 8192000000 10944731696 11362893824 1986327024 19.515 100 67 133 1 2026529792 20096384 20106384
14:46:22 16512 8192000000 10940125584 11372650496 1993552496 19.572 100 59 118 0 2032094512 21508224 17033792
14:47:44 6920 8192000000 10954849440 11383521280 1998127184 19.608 100 68 136 1 2046050256 26070688 21852384
14:48:55 4543 8192000000 10958529344 11381559296 1997528096 19.603 100 76 153 1 2047016656 27493712 21994848
14:50:05 2115 8192000000 10951952080 11377868800 1960596704 19.311 98 61 119 2 2012663472 33911840 18154928
14:51:11 2910 8192000000 10956735456 11374116864 1975498736 19.429 100 76 148 0 2040678816 41592480 23587600
14:52:19 2808 8192000000 10950292848 11368747008 1973484848 19.413 99 71 137 2 2029908672 35074944 21348880
14:53:28 2695 8192000000 10955854672 11373498368 1974678400 19.423 100 72 141 2 2030701840 34295680 21727760
14:54:34 4397 8192000000 10931822320 11357335552 1964881568 19.345 100 68 137 0 2018986896 33028656 21076672
14:55:46 15496 8192000000 10900431072 11333324800 1939633216 19.144 99 50 100 2 1980473072 25544048 15295808
14:57:17 12988 8192000000 10890614544 11321659392 1916808672 18.961 99 41 82 1 1957767280 28352560 12606048
14:58:35 14258 8192000000 10867386224 11305730048 1877104528 18.642 97 26 52 1 1914806832 28456112 9246192
14:59:52 15014 8192000000 10895182512 11346276352 1939810432 19.145 100 29 58 2 1982489456 33419552 9259472
15:01:11 20201 8192000000 10896125888 11344031744 1939374224 19.142 100 35 70 2 1983510576 32590256 11546096
15:02:33 25390 8192000000 10885518448 11331014656 1902592704 18.847 98 24 48 2 1946101312 34965024 8543584
15:04:30 70099 8192000000 10895649120 11346739200 1949128496 19.220 100 30 60 1 1985475040 27436320 8910224
15:06:43 32414 8192000000 10894170384 11359748096 1958612032 19.295 100 12 25 1 1978128384 16305616 3210736
15:08:18 19855 8192000000 10903852176 11379617792 1975087312 19.426 100 10 20 0 1991921520 14190560 2643648
15:10:07 41567 8192000000 10906120880 11367616512 1946064400 19.195 98 28 51 1 1963039600 10252832 6722368
15:11:58 53953 8192000000 10913582064 11376771072 1965138352 19.347 99 29 57 0 1989487520 15904144 8445024
15:14:11 89893 8192000000 10923356144 11392446464 1962847552 19.329 99 10 20 1 1986196784 20971408 2377824
15:16:57 36200 8192000000 10932417888 11403542528 1989764384 19.542 100 26 52 0 2013691776 16406064 7521328
15:18:39 109821 8192000000 10943266352 11391557632 1962746096 19.328 98 25 51 2 1987837504 18119296 6972112
15:21:31 93410 8192000000 10932284848 11383750656 1933186928 19.092 97 12 24 0 1947808000 10820784 3800288
15:24:08 25589 8192000000 10931596704 11387633664 1992017472 19.560 100 20 40 1 2013471280 15512448 5941360
15:27:00 21419 8192000000 10924215552 11371573248 1938708912 19.136 97 43 87 0 1959972720 8800400 12463408
15:28:25 34464 8192000000 10863268688 11296149504 1898924832 18.818 96 39 77 2 1916950688 7706672 10319184
15:30:00 15661 8192000000 10918884944 11369148416 1964965632 19.345 99 45 88 0 1987036048 9886096 12184320
15:31:31 157637 8192000000 10891125872 11364569088 1866521888 18.556 94 10 20 1 1877551280 8008096 3021296
15:35:09 54103 8192000000 10897706912 11377946624 1975069984 19.426 100 15 31 1 1991730688 12002288 4658416
15:37:05 27858 8192000000 10908302560 11347259392 1907103072 18.883 96 45 88 3 1941396224 20715536 13577616
15:38:33 60359 8192000000 10854812064 11327623168 1867748432 18.566 95 12 24 0 1885081696 14179680 3153584
15:40:34 35778 8192000000 10878000736 11345387520 1916193408 18.956 98 17 34 0 1932662400 11520864 4948128
15:42:25 52066 8192000000 10896632352 11374301184 1947673424 19.208 99 22 44 0 1967274080 13491888 6108768
15:44:18 52793 8192000000 10884664880 11353010176 1914691728 18.944 98 16 32 0 1932228960 13038128 4499104
15:46:13 60139 8192000000 10871026320 11329531904 1855226720 18.465 95 16 29 1 1881929472 21750224 4952528
15:48:17 63276 8192000000 10909595936 11355500544 1920269952 18.989 98 40 81 0 1947078512 15490832 11317728
15:50:22 85495 8192000000 10918158400 11390234624 1961260192 19.316 100 11 22 0 1985511520 21270336 2980992
15:52:55 51129 8192000000 10895539152 11353055232 1940860032 19.154 99 33 65 0 1958667648 7018832 10788784
15:54:50 45961 8192000000 10903716304 11360526336 1939524320 19.143 98 40 81 1 1960726336 10023776 11178240
15:56:37 78742 8192000000 10897980928 11385708544 1954947568 19.266 99 13 26 0 1978994864 20386032 3661264
15:58:56 40241 8192000000 10899211152 11373744128 1972722608 19.407 100 25 50 0 1991009248 11353280 6933360
16:00:43 60015 8192000000 10912030128 11390189568 1980991360 19.473 100 16 32 1 1997724576 12057184 4676032
16:02:43 89936 8192000000 10876836560 11357560832 1927772832 19.049 98 6 12 0 1941928208 12601376 1554000
16:05:16 69389 8192000000 10893030912 11382648832 1956491568 19.278 99 23 46 0 1973246928 10532368 6222992
16:07:25 60128 8192000000 10918925232 11392122880 1977400128 19.444 100 30 60 0 1998184400 12762384 8021888
16:09:26 54162 8192000000 10901769408 11379822592 1941889056 19.162 98 10 20 0 1961478464 16975744 2613664
16:11:34 117854 8192000000 10923272496 11388968960 1941062048 19.155 97 18 36 3 1956851056 10412928 5376080
16:14:35 63657 8192000000 10931690352 11411431424 1938202176 19.132 97 39 78 0 1969910336 21008848 10699312
16:17:55 106075 8192000000 10881685216 11338575872 1846089984 18.390 93 18 36 1 1861198192 10025248 5082960
16:20:52 11827 8192000000 10866079680 11342483456 1915103728 18.948 98 16 32 1 1934218000 14752784 4361488
16:22:14 70991 8192000000 10901261904 11388678144 1973404672 19.412 100 14 28 1 1996470224 18627472 4438080
16:24:46 76865 8192000000 10889649056 11369717760 1943803088 19.177 98 14 28 0 1961401904 12786224 4812592
16:28:06 76727 8192000000 10871045136 11348865024 1903083264 18.851 97 17 34 0 1917232688 9398784 4750640
16:30:23 37819 8192000000 10844943360 11310125056 1830198704 18.261 94 26 51 1 1846998656 8677664 8122288
16:32:01 72272 8192000000 10860850288 11310723072 1795502224 17.977 93 11 22 1 1810678400 12090880 3085296
16:34:14 116509 8192000000 10863404320 11328602112 1827886256 18.242 95 9 18 1 1846172288 15838912 2447120
16:37:11 52229 8192000000 9425483296 9726730240 569588640 6.500 29 6 12 0 577331008 6209328 1533040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 04-08-2021 16:38:58 to 04-08-2021 16:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 158 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-08-2021 16:39:13 to 04-08-2021 16:39:15.

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          248722
MON$OLDEST_ACTIVE               248723
MON$OLDEST_SNAPSHOT             248723
MON$NEXT_TRANSACTION            248723
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                       2055520
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:158: 
:159: mscs-test-161.budzdorov.ru    Wed Aug  4 16:38:07 2021
:160:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:161: 
:162: 
:163: mscs-test-161.budzdorov.ru    Wed Aug  4 16:38:07 2021
:164:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:165: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

04.08.21 16:39:16. 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-04 16:39:19 No severe PSQL-related problems occured
04.08.21 16:39:20. End of report.