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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16962104 kB
MemAvailable 31386740 kB
Buffers 20 kB
Cached 14671224 kB
SwapCached 2472 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-06-17 04:27:01
DTS_END                         2021-06-17 04:27:01.9430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60914
TRN_ID                          228933
ATT_ID                          630
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: 8451.83 27632 1014219
customer order: creation 163.01 422 19561
customer order: refuse 14.64 367 1757
order to supplier: creation 43.74 1335 5249
order to supplier: removal 3.26 123074 391
invoice (draft): creation 43.24 3840 5189
invoice (draft): removal 46.50 1032 5580
invoice accept: apply 40.17 17537 4821
invoice accept: cancel 41.82 1004 5018
customer reserve: creation 3726.19 170 447143
customer reserve: removal 4053.05 79 486366
realization accept: apply 81.15 174 9738
realization accept: cancel 71.37 138 8564
payment from customer: creation 56.94 107 6833
payment from customer: removal 5.69 102 683
payment to supplier: creation 47.25 144 5670
payment to supplier: removal 3.37 91 404
service: total inventory turnovers 3.72 3355 446
service: total monetary turnovers 5.74 674 689
service: aggregate perf. data 0.93 16152 112
service: refresh index statistics 0.04 382836 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-06-2021 04:28:32 to 17-06-2021 04:28:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6788
TEST_TIME 2 4766
TEST_TIME 3 5097
TEST_TIME 4 4819
TEST_TIME 5 4974
TEST_TIME 6 4920
TEST_TIME 7 4733
TEST_TIME 8 5249
TEST_TIME 9 5519
TEST_TIME 10 5422
TEST_TIME 11 5664
TEST_TIME 12 5784
TEST_TIME 13 6188
TEST_TIME 14 6524
TEST_TIME 15 6976
TEST_TIME 16 7156
TEST_TIME 17 7398
TEST_TIME 18 7712
TEST_TIME 19 7823
TEST_TIME 20 8268
TEST_TIME 21 8298
TEST_TIME 22 8537
TEST_TIME 23 8621
TEST_TIME 24 8939
TEST_TIME 25 8771
TEST_TIME 26 9016
TEST_TIME 27 9491
TEST_TIME 28 10863
TEST_TIME 29 11194
TEST_TIME 30 10957
TEST_TIME 31 10687
TEST_TIME 32 10563
TEST_TIME 33 10399
TEST_TIME 34 10267
TEST_TIME 35 10162
TEST_TIME 36 10168
TEST_TIME 37 10063
TEST_TIME 38 9978
TEST_TIME 39 9864
TEST_TIME 40 9823
TEST_TIME 41 9719
TEST_TIME 42 9590
TEST_TIME 43 9722
TEST_TIME 44 9664
TEST_TIME 45 9666
TEST_TIME 46 9633
TEST_TIME 47 9527
TEST_TIME 48 9482
TEST_TIME 49 9369
TEST_TIME 50 9379
TEST_TIME 51 9275
TEST_TIME 52 9280
TEST_TIME 53 9257
TEST_TIME 54 9300
TEST_TIME 55 9280
TEST_TIME 56 9243
TEST_TIME 57 9209
TEST_TIME 58 9057
TEST_TIME 59 8992
TEST_TIME 60 8959
TEST_TIME 61 8889
TEST_TIME 62 8833
TEST_TIME 63 8823
TEST_TIME 64 8784
TEST_TIME 65 8814
TEST_TIME 66 8763
TEST_TIME 67 8758
TEST_TIME 68 8777
TEST_TIME 69 8832
TEST_TIME 70 9015
TEST_TIME 71 8986
TEST_TIME 72 8990
TEST_TIME 73 8938
TEST_TIME 74 8965
TEST_TIME 75 9064
TEST_TIME 76 9061
TEST_TIME 77 8956
TEST_TIME 78 8879
TEST_TIME 79 8816
TEST_TIME 80 8744
TEST_TIME 81 8683
TEST_TIME 82 8681
TEST_TIME 83 8642
TEST_TIME 84 8617
TEST_TIME 85 8592
TEST_TIME 86 8581
TEST_TIME 87 8554
TEST_TIME 88 8585
TEST_TIME 89 8581
TEST_TIME 90 8711
TEST_TIME 91 8724
TEST_TIME 92 8727
TEST_TIME 93 8760
TEST_TIME 94 8766
TEST_TIME 95 8835
TEST_TIME 96 8749
TEST_TIME 97 8694
TEST_TIME 98 8660
TEST_TIME 99 8644
TEST_TIME 100 8612
TEST_TIME 101 8660
TEST_TIME 102 8614
TEST_TIME 103 8615
TEST_TIME 104 8582
TEST_TIME 105 8604
TEST_TIME 106 8672
TEST_TIME 107 8643
TEST_TIME 108 8689
TEST_TIME 109 8727
TEST_TIME 110 8739
TEST_TIME 111 8736
TEST_TIME 112 8770
TEST_TIME 113 8715
TEST_TIME 114 8661
TEST_TIME 115 8629
TEST_TIME 116 8587
TEST_TIME 117 8568
TEST_TIME 118 8549
TEST_TIME 119 8526
TEST_TIME 120 8456

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-06-2021 04:28:36 to 17-06-2021 04:28:39.

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 1427406 1427406 0 0.00 0 14851 6 0 0
doc_list_biud 1427402 1427402 0 0.00 0 69921 34 0 0
sp_add_invoice_to_stock 4938 4821 117 2.36 60 148549 17538 0 117
sp_cancel_adding_invoice 5018 5018 0 0.00 6 53230 1004 0 0
sp_cancel_client_order 1757 1757 0 0.00 5 19235 368 0 0
sp_cancel_customer_reserve 486366 486366 0 0.00 1 21077 79 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 13737 103 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 9078 92 0 0
sp_cancel_supplier_invoice 5591 5580 11 0.19 3 69924 1033 0 11
sp_cancel_supplier_order 391 391 0 0.00 6 1179011 123074 0 0
sp_cancel_write_off 8564 8564 0 0.00 1 10100 139 0 0
sp_client_order 19561 19561 0 0.00 4 25717 423 0 0
sp_customer_reserve 447143 447143 0 0.00 0 35773 170 0 0
sp_fill_shopping_cart 29972 29972 0 0.00 1 26244 52 0 0
sp_fill_shopping_cart_clo_res 447134 447134 0 0.00 0 25400 18 0 0
sp_get_clo_for_invoice 4812 4812 0 0.00 0 40998 430 0 0
sp_kill_cost_storno 14669 14669 0 0.00 0 13191 69 0 0
sp_kill_qstorno_ret_qs2qd 987267 987267 0 0.00 0 69919 43 0 0
sp_kill_qty_storno 522225 522225 0 0.00 0 69920 91 0 0
sp_lock_selected_doc 20257 20257 0 0.00 0 21430 8 0 0
sp_make_cost_storno 27053 27053 0 0.00 0 12948 53 0 0
sp_multiply_rows_for_pdistr 27053 27053 0 0.00 0 12648 30 0 0
sp_multiply_rows_for_qdistr 19561 19561 0 0.00 0 23585 236 0 0
sp_pay_from_customer 6833 6833 0 0.00 4 12658 107 0 0
sp_pay_to_supplier 5670 5670 0 0.00 5 23824 144 0 0
sp_payment_common 12503 12503 0 0.00 1 14395 25 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 19199 329 0 0
sp_qd_handle_on_invoice_upd_sts 9829 9829 0 0.00 0 43363 415 0 0
sp_qd_handle_on_reserve_upd_sts 9738 9738 0 0.00 0 13247 51 0 0
sp_reserve_write_off 9738 9738 0 0.00 7 21564 174 0 0
sp_supplier_invoice 5189 5189 0 0.00 6 89202 3841 0 0
sp_supplier_order 5249 5249 0 0.00 6 60034 1335 0 0
srv_aggregate_perf_data 112 112 0 0.00 2360 315798 16153 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1116 195617 49456 0 0
srv_find_qd_qs_mism 968036 968036 0 0.00 0 14851 5 0 0
srv_make_invnt_saldo 446 446 0 0.00 53 89335 3356 0 0
srv_make_money_saldo 689 689 0 0.00 0 16727 675 0 0
srv_recalc_idx_stat 5 5 0 0.00 213489 612148 382837 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 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 4821 4821 0 0.00 0 15156 172 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 6131 93 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 2678 14 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 5813 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 2596 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 142 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 842 15 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 24047 1545 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 5996 15 0 0
t$perf-norm:sp_client_order 19561 19561 0 0.00 0 26047 20 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 2329 11 0 0
t$perf-norm:sp_pay_from_customer 6833 6833 0 0.00 0 4918 8 0 0
t$perf-norm:sp_pay_to_supplier 5670 5670 0 0.00 0 2836 8 0 0
t$perf-norm:sp_reserve_write_off 9738 9738 0 0.00 0 11171 12 0 0
t$perf-norm:sp_supplier_invoice 5189 5189 0 0.00 0 4658 19 0 0
t$perf-norm:sp_supplier_order 5249 5249 0 0.00 0 13648 19 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 201 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 4798 81 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 19686 51 0 0
t$perf-norm:srv_make_money_saldo 689 689 0 0.00 0 3532 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9854 9620 234 2.37 0 13575 17 0 234
v_all_customers 35670 35670 0 0.00 0 47 1 0 0
v_all_suppliers 28938 28938 0 0.00 0 15 1 0 0
v_all_wares 39122 39122 0 0.00 0 65 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1539 9 0 0
v_cancel_client_order 3514 3514 0 0.00 0 2639 7 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 10299 13 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 637 3 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 853 6 0 0
v_cancel_supplier_invoice 830 808 22 2.65 0 1567 12 0 22
v_cancel_supplier_order 782 782 0 0.00 0 2558 8 0 0
v_cancel_write_off 2012 2012 0 0.00 0 1937 16 0 0
v_max_id_clo_ord 5242 5242 0 0.00 0 1620 2 0 0
v_max_id_clo_res 990 990 0 0.00 0 10981 32 0 0
v_max_id_ord_sup 5169 5169 0 0.00 0 6778 7 0 0
v_max_non_paid_invoice 1612 1612 0 0.00 0 3007 4 0 0
v_max_non_paid_realizn 3717 3717 0 0.00 0 2965 3 0 0
v_min_id_clo_ord 5242 5242 0 0.00 0 2099 6 0 0
v_min_id_clo_res 990 990 0 0.00 0 2136 4 0 0
v_min_id_ord_sup 5169 5169 0 0.00 0 7267 15 0 0
v_min_non_paid_invoice 5670 5670 0 0.00 0 11637 21 0 0
v_min_non_paid_realizn 6833 6833 0 0.00 0 4801 13 0 0
v_our_firm 9684 9684 0 0.00 0 15 1 0 0
v_reserve_write_off 19476 19476 0 0.00 0 9496 7 0 0
x_lock_depdocs_on_canc_invoice 10194 10194 0 0.00 0 22933 128 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 7027 316 0 0
x_make_qstorno_1000_1200 5249 5249 0 0.00 2 60024 1250 0 0
x_make_qstorno_1200_2000 5189 5189 0 0.00 3 81973 3632 0 0
x_make_qstorno_2100_3300 447143 447143 0 0.00 0 35214 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-06-2021 04:28:40 to 17-06-2021 04:28:44.

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
01:57:05 5 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
01:58:06 21286 8192000000 9713493520 10007232512 791136192 8.806 100 87 177 1 819463008 7348352 20978464
01:59:33 26176 8192000000 9765258224 10072113152 838946256 9.289 100 91 182 2 875309824 15046688 21316880
02:01:01 26966 8192000000 9805719664 10120974336 869889232 9.599 100 93 186 0 910586528 18961840 21735456
02:02:30 12317 8192000000 9868037248 10200686592 916894304 10.065 100 77 152 3 976777200 43571856 16311040
02:03:48 7063 8192000000 9922701376 10268352512 958550624 10.475 100 82 164 0 1037184608 61181744 17452240
02:05:02 4862 8192000000 9965220016 10323423232 996526432 10.845 100 75 148 1 1081690992 69974672 15189888
02:06:09 5136 8192000000 9991328352 10356023296 1028296640 11.152 100 73 146 0 1104114224 60650240 15167344
02:07:16 3074 8192000000 10023608224 10391068672 1050549648 11.366 100 88 176 2 1141062096 72189840 18322608
02:08:21 1656 8192000000 10035805008 10406539264 1067438032 11.528 100 90 179 2 1150178352 64392448 18347872
02:09:24 1469 8192000000 10038665072 10412441600 1073299072 11.584 100 93 184 2 1146649488 54315408 19035008
02:10:28 5014 8192000000 10002115952 10394832896 1083260720 11.679 100 38 75 1 1131670128 41030800 7378608
02:11:34 5003 8192000000 10013522016 10396176384 1086494448 11.709 100 74 148 1 1119514256 16094112 16925696
02:12:40 3720 8192000000 10016422336 10415079424 1095659616 11.796 100 37 73 0 1135551152 31593632 8297904
02:13:46 8385 8192000000 10009613728 10415706112 1098093184 11.820 100 17 34 0 1138084080 36191392 3799504
02:14:56 25180 8192000000 10011126272 10411843584 1101032688 11.847 100 51 101 2 1130749696 18786704 10930304
02:16:22 7149 8192000000 10020405984 10437640192 1103690672 11.873 100 35 69 1 1128783888 16881808 8211408
02:17:32 6160 8192000000 10005423392 10404159488 1090333744 11.746 99 34 67 2 1139950784 42584960 7032080
02:18:42 9211 8192000000 9989685104 10380763136 1064258880 11.497 98 29 56 1 1101225728 31276032 5690816
02:19:52 30368 8192000000 9916758720 10311196672 875638416 9.656 82 17 34 1 898081088 18760880 3681792
02:21:28 8004 8192000000 9949355584 10342100992 1024359072 11.114 99 31 62 0 1060580560 28885824 7335664
02:22:38 4033 8192000000 9931225888 10318880768 1012370864 10.998 99 61 121 2 1043311408 16065040 14875504
02:23:44 66973 8192000000 9948353776 10356273152 1038364976 11.249 100 25 50 3 1066794640 21895792 6533872
02:25:54 8855 8192000000 9971896400 10376450048 1055805312 11.416 100 38 74 1 1081238064 16956816 8475936
02:27:07 37084 8192000000 9996730720 10390405120 1081372208 11.661 100 48 94 2 1109264544 17975360 9916976
02:28:49 5335 8192000000 10063823920 10456956928 1100085552 11.838 100 60 120 0 1163552944 51255920 12211472
02:29:58 30027 8192000000 10065286144 10472468480 1109420656 11.927 100 43 84 1 1182805920 64917840 8467424
02:31:37 6449 8192000000 10095274608 10509701120 1122208976 12.048 100 50 99 0 1202763136 69894528 10659632
02:32:51 17078 8192000000 10128350368 10539122688 1139102720 12.207 100 44 88 0 1235563904 86991472 9469712
02:34:16 10060 8192000000 10150092784 10562560000 1146825392 12.280 100 49 100 2 1258537296 100490368 11221536
02:35:31 7415 8192000000 10197086944 10612342784 1155725968 12.363 100 56 111 0 1304186496 135852736 12607792
02:36:48 10686 8192000000 10230906032 10647810048 1159829488 12.402 100 55 110 1 1329123856 156782816 12511552
02:38:05 10224 8192000000 10259805600 10679050240 1167445104 12.473 100 56 111 1 1363470000 183578176 12446720
02:39:21 4715 8192000000 10304446736 10710413312 1180325056 12.593 100 80 156 1 1403028576 204839104 17864416
02:40:29 2710 8192000000 10309196912 10727669760 1189289184 12.677 100 75 150 0 1409335056 202835856 17210016
02:41:39 22562 8192000000 10325272736 10751553536 1180985968 12.599 100 74 149 1 1433759552 236169200 16604384
02:43:10 1787 8192000000 10362514416 10772971520 1190753744 12.690 100 89 178 1 1459896256 249277680 19864832
02:44:20 1112 8192000000 10363089712 10788327424 1180474528 12.595 100 94 185 2 1463899872 259686656 23738688
02:45:24 4042 8192000000 10374930400 10799869952 1156878368 12.374 100 63 125 0 1483238496 311137664 15222464
02:46:34 3566 8192000000 10383016640 10791141376 1147755232 12.288 99 88 176 0 1474813488 306320512 20737744
02:47:42 6499 8192000000 10407651248 10854260736 1159475344 12.398 99 71 142 0 1495108480 318292448 17340688
02:48:52 7914 8192000000 10406597904 10826223616 1152919520 12.337 100 56 108 1 1506808416 340569184 13319712
02:50:32 5580 8192000000 10449312176 10891288576 1171587056 12.512 100 50 98 0 1550620432 366123680 12909696
02:51:41 24117 8192000000 10436993984 10880950272 1152975936 12.337 99 31 62 1 1512620944 351478496 8166512
02:53:08 22129 8192000000 10471733456 10954887168 1149170400 12.302 100 32 64 2 1594616352 436551776 8894176
02:54:36 21305 8192000000 10401993664 10879680512 1134107472 12.160 100 19 37 1 1517790496 377611856 6071168
02:56:10 36468 8192000000 10105381360 10603900928 1140709808 12.222 100 21 42 1 1208694960 63139040 4846112
02:58:06 75362 8192000000 10079009872 10527891456 1136192160 12.180 99 29 58 2 1183167216 40450768 6524288
03:00:23 75713 8192000000 10114021904 10580287488 1127493888 12.098 99 25 49 2 1195970064 63234528 5241648
03:02:39 88224 8192000000 10077335680 10549067776 1086139088 11.706 95 24 49 0 1155604880 64679296 4786496
03:05:08 68589 8192000000 10121252000 10581581824 1130827776 12.129 99 21 42 0 1197709872 63017296 3864800
03:07:17 59790 8192000000 10107417952 10551742464 1108355376 11.917 97 11 22 0 1179431376 68979888 2096112
03:09:18 71676 8192000000 10085562128 10598047744 1103530112 11.871 97 9 18 1 1188713136 83059808 2123216
03:11:38 99680 8192000000 10076949008 10574520320 1126928320 12.092 99 12 24 1 1169562160 40124608 2509232
03:14:19 30950 8192000000 10084562032 10531336192 1142915888 12.243 100 45 88 1 1165218656 12722288 9580480
03:15:58 94177 8192000000 10103237440 10575159296 1125607024 12.080 99 13 26 0 1211033552 82549456 2877072
03:18:39 195581 8192000000 10089758416 10571231232 1125984176 12.083 98 13 25 2 1161740800 33449664 2306960
03:23:02 105513 8192000000 10066650368 10543386624 1104681616 11.882 96 18 36 0 1143776672 35594256 3500800
03:26:54 53569 8192000000 10065565136 10540781568 1128918672 12.111 98 17 34 1 1169232736 36580448 3733616
03:30:37 55397 8192000000 10080765504 10527600640 1113321856 11.964 97 17 34 0 1144980320 28026176 3632288
03:32:33 46995 8192000000 10095757632 10532433920 1128232480 12.105 98 12 24 0 1170795568 39648176 2914912
03:34:21 65009 8192000000 10158988048 10632577024 1129548336 12.117 97 19 38 0 1219767296 85955840 4263120
03:36:26 74556 8192000000 10163218432 10686935040 1131205840 12.133 99 20 40 0 1269519424 133373024 4940560
03:39:10 77835 8192000000 10073344768 10504892416 1077332960 11.622 95 20 40 0 1112072992 30579456 4160576
03:41:29 99679 8192000000 10082847760 10590171136 1104470208 11.880 98 13 26 0 1190423504 83368912 2584384
03:44:10 129961 8192000000 10044853696 10501914624 1109118416 11.924 98 15 30 0 1148003152 35915728 2969008
03:47:25 86185 8192000000 10061726352 10517721088 1120092176 12.028 99 8 16 0 1163717040 42024368 1600496
03:49:51 47745 8192000000 10044378144 10529329152 1120663984 12.033 99 5 11 0 1145158640 23770752 723904
03:51:47 20671 8192000000 10086244736 10525081600 1125575696 12.080 99 29 59 1 1173697584 41978000 6143888
03:53:08 36385 8192000000 10107530784 10561282048 1124929648 12.074 99 32 64 1 1202027472 69480656 7617168
03:54:48 57238 8192000000 10129301968 10592276480 1107710688 11.911 97 31 62 0 1209450368 94741504 6998176
03:57:16 88153 8192000000 10077256608 10565337088 1093784288 11.779 97 14 28 1 1140511120 43378336 3348496
04:00:39 62520 8192000000 10118918608 10544545792 1097258224 11.812 97 24 48 0 1148929472 46935104 4736144
04:02:41 99515 8192000000 10055322272 10522361856 1132932928 12.149 100 13 26 0 1171765072 35991952 2840192
04:05:40 49303 8192000000 10052243920 10508509184 1104494128 11.880 98 7 13 1 1138449872 32556496 1399248
04:08:16 56771 8192000000 10036236336 10515116032 1062245568 11.478 95 20 39 1 1107492320 40446672 4800080
04:10:13 35367 8192000000 10053335056 10482962432 1102014656 11.857 98 18 36 0 1145345760 39570176 3760928
04:11:54 60939 8192000000 10119354608 10557825024 1093969792 11.780 97 33 66 0 1178045216 77329728 6745696
04:14:08 94993 8192000000 10082628816 10552213504 1092267216 11.764 97 27 54 1 1156541952 59096784 5177952
04:16:44 72092 8192000000 10101098640 10561052672 1133581776 12.155 100 21 42 1 1178097792 40245568 4270448
04:19:06 67226 8192000000 10073377696 10524053504 1094273120 11.783 96 27 54 0 1163188928 63480432 5435376
04:21:19 64227 8192000000 10056682960 10514731008 1135865040 12.177 100 24 48 1 1165772832 24582560 5325232
04:24:31 73461 8192000000 10104942704 10588946432 1121733808 12.043 99 12 24 1 1179998592 55639776 2625008
04:26:46 27348 8192000000 9047410800 9313865728 205374080 2.445 18 1 2 0 214168192 8758704 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-06-2021 04:28:46 to 17-06-2021 04:29:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 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 17-06-2021 04:29:01 to 17-06-2021 04:29:03.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          229233
MON$OLDEST_ACTIVE               229234
MON$OLDEST_SNAPSHOT             229234
MON$NEXT_TRANSACTION            229234
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2067168
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2517 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2517 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2517 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2517 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:97: 
:98: mscs-test-161.budzdorov.ru    Thu Jun 17 04:27:21 2021
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:100: 
--- end of diff output ---

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

17.06.21 04:29:04. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-06-17 04:29:07 No severe PSQL-related problems occured
17.06.21 04:29:08. End of report.