Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.10.2022 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 15315984 kB
MemAvailable 30528976 kB
Buffers 0 kB
Cached 16423328 kB
SwapCached 2172 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.11
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 2022-10-16 22:38:29
DTS_END                         2022-10-16 22:38:29.2600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47660
TRN_ID                          249093
ATT_ID                          722
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: 9464.00 19800 1135680
customer order: creation 182.49 326 21899
customer order: refuse 15.16 367 1819
order to supplier: creation 47.73 1331 5727
order to supplier: removal 3.48 99609 417
invoice (draft): creation 48.92 3288 5870
invoice (draft): removal 54.98 745 6598
invoice accept: apply 44.47 16916 5336
invoice accept: cancel 49.36 804 5923
customer reserve: creation 3974.51 173 476941
customer reserve: removal 4726.61 58 567193
realization accept: apply 88.56 186 10627
realization accept: cancel 88.40 108 10608
payment from customer: creation 64.06 97 7687
payment from customer: removal 6.39 117 767
payment to supplier: creation 52.00 133 6240
payment to supplier: removal 4.12 67 494
service: total inventory turnovers 4.79 1247 575
service: total monetary turnovers 7.11 48 853
service: aggregate perf. data 0.85 22871 102
service: refresh index statistics 0.03 247507 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-10-2022 22:40:15 to 16-10-2022 22:40:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3554
TEST_TIME 2 4227
TEST_TIME 3 5347
TEST_TIME 4 4871
TEST_TIME 5 4872
TEST_TIME 6 4950
TEST_TIME 7 5605
TEST_TIME 8 5773
TEST_TIME 9 6179
TEST_TIME 10 6264
TEST_TIME 11 6791
TEST_TIME 12 6857
TEST_TIME 13 7038
TEST_TIME 14 7424
TEST_TIME 15 7845
TEST_TIME 16 7940
TEST_TIME 17 7792
TEST_TIME 18 8040
TEST_TIME 19 8321
TEST_TIME 20 8353
TEST_TIME 21 8866
TEST_TIME 22 8924
TEST_TIME 23 8914
TEST_TIME 24 8875
TEST_TIME 25 8795
TEST_TIME 26 9060
TEST_TIME 27 9140
TEST_TIME 28 9218
TEST_TIME 29 9234
TEST_TIME 30 9162
TEST_TIME 31 9938
TEST_TIME 32 10610
TEST_TIME 33 10542
TEST_TIME 34 10574
TEST_TIME 35 10596
TEST_TIME 36 10647
TEST_TIME 37 10421
TEST_TIME 38 10493
TEST_TIME 39 10399
TEST_TIME 40 10325
TEST_TIME 41 10202
TEST_TIME 42 10080
TEST_TIME 43 10039
TEST_TIME 44 9951
TEST_TIME 45 9863
TEST_TIME 46 9784
TEST_TIME 47 9812
TEST_TIME 48 9777
TEST_TIME 49 9870
TEST_TIME 50 9857
TEST_TIME 51 9796
TEST_TIME 52 9817
TEST_TIME 53 9726
TEST_TIME 54 9648
TEST_TIME 55 9570
TEST_TIME 56 9488
TEST_TIME 57 9420
TEST_TIME 58 9434
TEST_TIME 59 9504
TEST_TIME 60 9467
TEST_TIME 61 9503
TEST_TIME 62 9526
TEST_TIME 63 9591
TEST_TIME 64 9578
TEST_TIME 65 9593
TEST_TIME 66 9657
TEST_TIME 67 9662
TEST_TIME 68 9692
TEST_TIME 69 9779
TEST_TIME 70 9835
TEST_TIME 71 9812
TEST_TIME 72 9745
TEST_TIME 73 9622
TEST_TIME 74 9668
TEST_TIME 75 9588
TEST_TIME 76 9579
TEST_TIME 77 9583
TEST_TIME 78 9559
TEST_TIME 79 9521
TEST_TIME 80 9450
TEST_TIME 81 9455
TEST_TIME 82 9477
TEST_TIME 83 9504
TEST_TIME 84 9521
TEST_TIME 85 9527
TEST_TIME 86 9529
TEST_TIME 87 9542
TEST_TIME 88 9621
TEST_TIME 89 9588
TEST_TIME 90 9544
TEST_TIME 91 9632
TEST_TIME 92 9622
TEST_TIME 93 9669
TEST_TIME 94 9663
TEST_TIME 95 9619
TEST_TIME 96 9565
TEST_TIME 97 9551
TEST_TIME 98 9580
TEST_TIME 99 9515
TEST_TIME 100 9487
TEST_TIME 101 9434
TEST_TIME 102 9477
TEST_TIME 103 9471
TEST_TIME 104 9464
TEST_TIME 105 9455
TEST_TIME 106 9516
TEST_TIME 107 9508
TEST_TIME 108 9533
TEST_TIME 109 9510
TEST_TIME 110 9556
TEST_TIME 111 9535
TEST_TIME 112 9510
TEST_TIME 113 9596
TEST_TIME 114 9631
TEST_TIME 115 9603
TEST_TIME 116 9652
TEST_TIME 117 9579
TEST_TIME 118 9583
TEST_TIME 119 9520
TEST_TIME 120 9466

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-10-2022 22:40:19 to 16-10-2022 22:40:22.

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 1579058 1579058 0 0.00 0 12214 6 0 0
doc_list_biud 1579058 1579058 0 0.00 0 65425 27 0 0
sp_add_invoice_to_stock 5412 5336 76 1.40 28 180782 16917 0 76
sp_cancel_adding_invoice 5923 5923 0 0.00 4 78671 805 0 0
sp_cancel_client_order 1819 1819 0 0.00 4 8840 367 0 0
sp_cancel_customer_reserve 567193 567193 0 0.00 0 65426 59 0 0
sp_cancel_pay_from_customer 767 767 0 0.00 2 11931 117 0 0
sp_cancel_pay_to_supplier 494 494 0 0.00 3 2711 68 0 0
sp_cancel_supplier_invoice 6607 6598 9 0.13 1 23819 745 0 9
sp_cancel_supplier_order 417 417 0 0.00 9 850504 99610 0 0
sp_cancel_write_off 10608 10608 0 0.00 1 10762 109 0 0
sp_client_order 21899 21899 0 0.00 3 64519 327 0 0
sp_customer_reserve 476941 476941 0 0.00 0 63492 173 0 0
sp_fill_shopping_cart 33487 33487 0 0.00 1 12277 35 0 0
sp_fill_shopping_cart_clo_res 476937 476937 0 0.00 0 19938 13 0 0
sp_get_clo_for_invoice 5330 5330 0 0.00 0 24707 254 0 0
sp_kill_cost_storno 17792 17792 0 0.00 0 12206 55 0 0
sp_kill_qstorno_ret_qs2qd 1152009 1152009 0 0.00 0 65224 31 0 0
sp_kill_qty_storno 608515 608515 0 0.00 0 65425 68 0 0
sp_lock_selected_doc 22156 22156 0 0.00 0 6999 7 0 0
sp_make_cost_storno 29884 29884 0 0.00 0 12131 51 0 0
sp_multiply_rows_for_pdistr 29884 29884 0 0.00 0 9696 27 0 0
sp_multiply_rows_for_qdistr 21899 21899 0 0.00 0 64509 132 0 0
sp_pay_from_customer 7687 7687 0 0.00 4 11785 97 0 0
sp_pay_to_supplier 6240 6240 0 0.00 4 16509 133 0 0
sp_payment_common 13927 13927 0 0.00 1 16501 26 0 0
sp_qd_handle_on_cancel_clo 1819 1819 0 0.00 0 8835 333 0 0
sp_qd_handle_on_invoice_upd_sts 11253 11253 0 0.00 0 22366 314 0 0
sp_qd_handle_on_reserve_upd_sts 10627 10627 0 0.00 0 15866 66 0 0
sp_reserve_write_off 10627 10627 0 0.00 6 16828 187 0 0
sp_supplier_invoice 5870 5870 0 0.00 6 78302 3288 0 0
sp_supplier_order 5727 5727 0 0.00 4 58660 1331 0 0
srv_aggregate_perf_data 102 102 0 0.00 2996 358649 22871 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1210 111863 40680 0 0
srv_find_qd_qs_mism 1086198 1086198 0 0.00 0 7724 5 0 0
srv_make_invnt_saldo 575 575 0 0.00 31 43159 1248 0 0
srv_make_money_saldo 853 853 0 0.00 1 11585 48 0 0
srv_recalc_idx_stat 4 4 0 0.00 151354 379568 247507 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5337 5337 0 0.00 0 12198 157 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 9776 113 0 0
t$perf-norm:sp_cancel_client_order 1819 1819 0 0.00 0 2867 16 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 3830 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 767 767 0 0.00 0 3302 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 494 494 0 0.00 0 5653 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 458 458 0 0.00 0 2187 17 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 27323 1536 0 0
t$perf-norm:sp_cancel_write_off 1091 1091 0 0.00 0 6865 18 0 0
t$perf-norm:sp_client_order 21899 21899 0 0.00 0 8299 16 0 0
t$perf-norm:sp_customer_reserve 1131 1131 0 0.00 0 8148 16 0 0
t$perf-norm:sp_pay_from_customer 7687 7687 0 0.00 0 5422 10 0 0
t$perf-norm:sp_pay_to_supplier 6240 6240 0 0.00 0 8344 12 0 0
t$perf-norm:sp_reserve_write_off 10627 10627 0 0.00 0 5529 12 0 0
t$perf-norm:sp_supplier_invoice 5870 5870 0 0.00 0 9868 18 0 0
t$perf-norm:sp_supplier_order 5727 5727 0 0.00 0 5674 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 1097 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 195 4 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 3283 39 0 0
t$perf-norm:srv_make_money_saldo 853 853 0 0.00 0 6899 29 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 23 8 0 0
v_add_invoice_to_stock 10810 10658 152 1.40 0 4683 17 0 152
v_all_customers 40192 40192 0 0.00 0 24 1 0 0
v_all_suppliers 31804 31804 0 0.00 0 22 1 0 0
v_all_wares 43798 43798 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 3990 20 0 0
v_cancel_client_order 3638 3638 0 0.00 0 3974 8 0 0
v_cancel_customer_prepayment 1534 1534 0 0.00 0 3869 13 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 1795 6 0 0
v_cancel_payment_to_supplier 988 988 0 0.00 0 2685 8 0 0
v_cancel_supplier_invoice 934 916 18 1.92 0 3963 17 0 18
v_cancel_supplier_order 834 834 0 0.00 0 693 7 0 0
v_cancel_write_off 2182 2182 0 0.00 0 3935 18 0 0
v_max_id_clo_ord 5727 5727 0 0.00 0 616 1 0 0
v_max_id_clo_res 1131 1131 0 0.00 0 1264 8 0 0
v_max_id_ord_sup 5861 5861 0 0.00 0 6070 7 0 0
v_max_non_paid_invoice 1926 1926 0 0.00 0 102 2 0 0
v_max_non_paid_realizn 4006 4006 0 0.00 0 709 3 0 0
v_min_id_clo_ord 5727 5727 0 0.00 0 2103 6 0 0
v_min_id_clo_res 1131 1131 0 0.00 0 4599 9 0 0
v_min_id_ord_sup 5861 5861 0 0.00 0 8073 7 0 0
v_min_non_paid_invoice 6240 6240 0 0.00 0 16496 22 0 0
v_min_non_paid_realizn 7687 7687 0 0.00 0 7902 16 0 0
v_our_firm 10968 10968 0 0.00 0 19 1 0 0
v_reserve_write_off 21254 21254 0 0.00 0 4331 6 0 0
x_lock_depdocs_on_canc_invoice 12063 12063 0 0.00 0 32647 109 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 14851 439 0 0
x_make_qstorno_1000_1200 5727 5727 0 0.00 1 58524 1255 0 0
x_make_qstorno_1200_2000 5870 5870 0 0.00 3 78285 3163 0 0
x_make_qstorno_2100_3300 476941 476941 0 0.00 0 63478 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2022 22:40:23 to 16-10-2022 22:40:27.

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:08:33 5 8192000000 8804259696 8817381376 9676976 0.117 3 1 2 0 9787312 67552 42784
20:09:34 23161 8192000000 9890849488 10198974464 969242224 10.579 100 87 174 0 997149856 5219600 22688032
20:11:03 33892 8192000000 9952425664 10270896128 1026924592 11.139 100 88 176 0 1058195008 8378144 22892272
20:12:45 14945 8192000000 9982713024 10314219520 1063628368 11.491 100 81 162 0 1093939488 10036816 20274304
20:14:03 11291 8192000000 10033780784 10378178560 1118745072 12.015 100 75 150 0 1147425856 11416848 17263936
20:15:18 5309 8192000000 10096026112 10455273472 1181412192 12.603 100 80 160 1 1211252768 12309744 17530832
20:16:26 3125 8192000000 10132591440 10501816320 1216432272 12.929 100 85 168 0 1248547200 12815712 19299216
20:17:32 3471 8192000000 10161402384 10536423424 1249194464 13.231 100 80 161 0 1279596304 12800096 17601744
20:18:36 963 8192000000 10183957120 10563457024 1274108096 13.459 100 87 169 0 1305903408 12605024 19190288
20:19:38 10426 8192000000 10203986352 10594799616 1296957072 13.668 100 72 142 0 1324543488 12037040 15549376
20:20:49 4444 8192000000 10207160656 10611470336 1310948784 13.795 100 42 84 1 1329263232 9062368 9252080
20:21:59 54353 8192000000 10217642608 10623668224 1314311488 13.825 100 26 52 0 1325987088 5544048 6131552
20:23:55 4093 8192000000 10216865664 10631454720 1324557840 13.918 100 29 59 0 1337342032 5922800 6861392
20:25:01 6564 8192000000 10226876128 10645721088 1330876112 13.975 100 22 45 1 1341539312 5560112 5103088
20:26:09 15749 8192000000 10229314416 10642546688 1335582896 14.018 100 28 56 1 1347496416 5075392 6838128
20:27:26 16158 8192000000 10222604560 10646835200 1335401952 14.016 100 28 56 2 1347855408 6559984 5893472
20:28:45 4598 8192000000 10217954592 10628935680 1311058288 13.796 99 30 60 0 1324010080 6464960 6486832
20:29:51 6533 8192000000 10162113056 10563911680 1241990176 13.165 97 23 46 0 1253119760 5454368 5675216
20:30:59 7454 8192000000 10136259872 10536685568 1235351440 13.103 99 21 41 0 1246361760 6733760 4276560
20:32:11 8682 8192000000 10153883232 10562953216 1246829024 13.209 98 9 18 0 1253629040 4805488 1994528
20:33:20 7779 8192000000 10160138656 10560548864 1265316112 13.379 100 30 60 1 1278108608 5849008 6943488
20:34:30 10125 8192000000 10174174640 10584817664 1285336976 13.562 100 20 40 0 1295042512 5150080 4555456
20:35:43 17496 8192000000 10203510416 10602917888 1300834032 13.703 100 21 38 1 1310838240 4577360 5426848
20:37:00 34323 8192000000 10193351392 10611494912 1303236640 13.725 100 21 42 1 1313629184 5374816 5017728
20:38:38 8572 8192000000 10220813472 10637180928 1327669824 13.946 100 26 50 0 1337930784 4533472 5727488
20:39:51 9885 8192000000 10249124928 10661646336 1347132528 14.122 100 49 98 1 1367815952 9096752 11586672
20:41:12 12871 8192000000 10284923856 10699563008 1366823008 14.299 100 56 109 0 1396935536 17829760 12282768
20:42:27 43678 8192000000 10280117792 10707275776 1357323696 14.213 99 44 87 1 1385795584 18883552 9588336
20:44:28 7251 8192000000 10289307424 10707902464 1375439392 14.376 100 43 82 1 1399583840 13397136 10747312
20:46:02 13438 8192000000 10308404448 10732167168 1390058096 14.506 100 47 94 1 1416985200 15899280 11027824
20:47:20 3195 8192000000 10310867360 10733088768 1379950256 14.416 99 59 114 1 1413534224 19237264 14346704
20:48:27 10421 8192000000 10320747600 10742910976 1392498816 14.528 100 61 122 0 1431184480 24294752 14390912
20:49:42 30219 8192000000 10325020848 10750427136 1394163232 14.543 100 55 108 0 1430071600 23711536 12196832
20:51:22 6714 8192000000 10321193328 10740097024 1393183728 14.534 100 58 115 0 1429419856 21823360 14412768
20:52:32 1484 8192000000 10342583600 10753482752 1398190496 14.579 100 82 162 0 1440748736 23160160 19398080
20:53:37 21396 8192000000 10289340320 10709037056 1318453184 13.863 95 28 56 0 1345640336 19862592 7324560
20:55:01 6896 8192000000 10312283184 10727014400 1380408576 14.420 100 60 119 1 1416698976 21115568 15174832
20:56:12 2019 8192000000 10301754240 10703958016 1355570016 14.198 99 72 144 0 1403001264 29673744 17757504
20:57:20 1206 8192000000 10330003904 10730479616 1372896960 14.353 100 87 172 1 1427597168 33337168 21363040
20:58:26 2559 8192000000 10307853168 10711617536 1350493360 14.152 100 79 154 1 1410376880 39200944 20682576
20:59:32 4892 8192000000 10303763600 10710994944 1348191136 14.131 100 62 124 0 1407886160 44010304 15684720
21:00:41 5152 8192000000 10309000688 10719932416 1361312784 14.249 99 48 92 0 1408199360 34641040 12245536
21:01:49 6508 8192000000 10313731200 10731356160 1373052064 14.354 100 39 78 0 1421889520 38424096 10413360
21:03:08 2437 8192000000 10343189680 10756517888 1389891408 14.505 100 57 114 1 1448030112 43178960 14959744
21:04:12 13385 8192000000 10319977008 10745106432 1381009360 14.426 100 37 71 0 1430156320 39577152 9569808
21:05:28 14327 8192000000 10338663056 10747596800 1374471088 14.367 99 60 118 0 1434491152 43839552 16180512
21:06:46 16771 8192000000 10314051248 10750488576 1372708704 14.351 100 26 51 0 1423487824 43646992 7132128
21:08:49 37288 8192000000 10321313504 10759548928 1352460256 14.170 100 19 38 0 1418795520 60200272 6134992
21:10:28 93176 8192000000 10286492016 10728599552 1352397136 14.169 99 9 18 0 1366588192 12442464 1748592
21:13:02 64224 8192000000 10286606096 10732417024 1367255632 14.302 99 5 10 0 1380193792 11993024 945136
21:15:48 19381 8192000000 10303083248 10740797440 1381176080 14.427 100 36 69 0 1402690208 13785200 7728928
21:17:20 89114 8192000000 10296435664 10749595648 1386701600 14.476 100 26 52 0 1409467440 16642672 6123168
21:19:49 79443 8192000000 10303341776 10754174976 1393063280 14.533 100 39 78 1 1411617760 10047632 8506848
21:22:18 68065 8192000000 10318734912 10767187968 1405809360 14.647 100 30 60 0 1423216064 10172096 7234608
21:24:28 28289 8192000000 10322589296 10774249472 1400210848 14.597 99 38 75 0 1424271632 15016912 9043872
21:26:00 42281 8192000000 10322006096 10776330240 1404080256 14.631 99 43 85 0 1430501840 17149488 9272096
21:27:45 60159 8192000000 10312124864 10793463808 1407255536 14.660 100 13 26 0 1430696656 19997936 3443184
21:29:45 27431 8192000000 10298779488 10759991296 1388397088 14.492 99 19 37 0 1400776464 8343296 4036080
21:31:16 34706 8192000000 10292001504 10747621376 1374549344 14.368 98 31 60 0 1387150816 5959808 6641664
21:32:52 47563 8192000000 10287676544 10753839104 1385602112 14.467 99 10 20 0 1397487088 9676992 2207984
21:34:40 71344 8192000000 10290604048 10757267456 1388691264 14.494 100 12 24 0 1403626432 12246272 2688896
21:36:52 77640 8192000000 10263433152 10713948160 1310862656 13.794 95 9 18 0 1326288544 13468016 1957872
21:39:11 67481 8192000000 10255414304 10706849792 1257364752 13.306 91 15 30 0 1267689840 7037216 3287872
21:41:20 64896 8192000000 10269190176 10718838784 1309914688 13.785 96 18 35 1 1326966608 13321296 3730624
21:43:33 14335 8192000000 10261932800 10690486272 1339470112 14.053 100 49 97 2 1358677632 7515712 11691808
21:44:47 76270 8192000000 10252382624 10695577600 1267382528 13.398 94 18 36 0 1280248112 9145968 3719616
21:47:06 106536 8192000000 10257160208 10716954624 1308294400 13.771 97 15 30 0 1332581040 20699584 3587056
21:49:55 23770 8192000000 10274858944 10710024192 1351734848 14.163 100 31 62 0 1369923072 11594400 6593824
21:51:26 69341 8192000000 10251379968 10705752064 1327652640 13.946 98 14 27 0 1344547472 13665536 3229296
21:53:37 28780 8192000000 10268668720 10712559616 1354346672 14.187 100 29 56 0 1374568464 13830544 6391248
21:55:09 45257 8192000000 10255759344 10712928256 1353273712 14.177 100 27 55 0 1377471728 17200288 6997728
21:56:58 35245 8192000000 10261821792 10712469504 1359720928 14.235 100 10 19 0 1370816320 9151984 1943408
21:58:33 40556 8192000000 10262476832 10719617024 1326809568 13.938 97 7 14 0 1338247056 9871904 1565584
22:00:23 71206 8192000000 10278067184 10740584448 1373052800 14.354 100 26 52 0 1389129136 10160464 5915872
22:02:34 91512 8192000000 10295448496 10744115200 1341518656 14.071 97 13 26 0 1356166688 11696176 2951856
22:05:06 50031 8192000000 10308224560 10765176832 1394601728 14.547 100 20 40 0 1411036656 12050720 4384208
22:06:59 36608 8192000000 10306750992 10761637888 1392444880 14.528 100 19 38 0 1406352752 9759920 4147952
22:08:37 49117 8192000000 10321035408 10783006720 1402152256 14.614 100 38 76 0 1430091888 19393952 8545680
22:10:35 111862 8192000000 10302191872 10754760704 1273357200 13.452 90 12 25 0 1285145680 9367456 2421024
22:13:27 96600 8192000000 10315098224 10773770240 1393643600 14.538 100 28 56 0 1412736368 12918272 6174496
22:16:04 10301 8192000000 10317314480 10787536896 1402769104 14.620 100 19 38 1 1429255200 21957648 4528448
22:17:24 37433 8192000000 10283313584 10739908608 1341596800 14.072 96 29 57 0 1370423856 22117408 6709648
22:19:15 63515 8192000000 10287345904 10755731456 1364182496 14.275 98 16 33 0 1379721248 11617552 3921200
22:22:08 85960 8192000000 10303170400 10766655488 1373063968 14.354 98 18 35 1 1387584512 10361328 4159216
22:24:35 64750 8192000000 10312899056 10768449536 1389683536 14.503 99 17 34 0 1406199584 12810656 3705392
22:26:44 57875 8192000000 10296151136 10743246848 1332492128 13.990 96 21 42 0 1349641216 12460816 4688272
22:28:48 45739 8192000000 10276325632 10724577280 1313252128 13.816 95 27 54 0 1327113264 8358256 5502880
22:30:34 36978 8192000000 10304125120 10743382016 1376185376 14.382 100 33 64 1 1401925600 18272144 7468080
22:32:11 43795 8192000000 10258034912 10715054080 1296980832 13.668 95 17 34 0 1314491392 14090336 3420224
22:33:55 43265 8192000000 10245969936 10697007104 1281169344 13.524 95 14 28 0 1299646160 15085936 3390880
22:35:43 87471 8192000000 10222332576 10677125120 1290727376 13.611 97 18 36 1 1303843472 8593200 4522896
22:38:17 24203 8192000000 9168791296 9531629568 245591328 2.910 19 5 10 0 249881376 3184672 1105376

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-10-2022 22:40:29 to 16-10-2022 22:40:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-10-2022 22:40:46 to 16-10-2022 22:40:48.

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          250589
MON$OLDEST_ACTIVE               250590
MON$OLDEST_SNAPSHOT             250590
MON$NEXT_TRANSACTION            250590
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                       2049312
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

16.10.22 22:40:49. 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
2022-10-16 22:40:52 No severe PSQL-related problems occured
16.10.22 22:40:53. End of report.