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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-01-04 10:26:53
DTS_END                         2021-01-04 10:26:53.8020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40202
TRN_ID                          229031
ATT_ID                          576
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: 8888.06 20791 1066567
customer order: creation 164.88 457 19785
customer order: refuse 14.34 377 1721
order to supplier: creation 43.90 1442 5268
order to supplier: removal 3.52 124596 422
invoice (draft): creation 43.01 3654 5161
invoice (draft): removal 51.84 1015 6221
invoice accept: apply 38.74 19260 4649
invoice accept: cancel 46.78 1041 5613
customer reserve: creation 3595.08 187 431409
customer reserve: removal 4600.65 75 552078
realization accept: apply 78.82 188 9458
realization accept: cancel 83.19 128 9983
payment from customer: creation 56.23 113 6747
payment from customer: removal 5.67 110 680
payment to supplier: creation 48.33 123 5800
payment to supplier: removal 2.88 137 345
service: total inventory turnovers 3.51 4352 421
service: total monetary turnovers 5.84 659 701
service: aggregate perf. data 0.83 16776 100
service: refresh index statistics 0.04 241127 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-01-2021 10:27:40 to 04-01-2021 10:27:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4992
TEST_TIME 2 4594
TEST_TIME 3 4707
TEST_TIME 4 4245
TEST_TIME 5 5064
TEST_TIME 6 5099
TEST_TIME 7 5002
TEST_TIME 8 5058
TEST_TIME 9 5271
TEST_TIME 10 5444
TEST_TIME 11 5497
TEST_TIME 12 5604
TEST_TIME 13 5604
TEST_TIME 14 5637
TEST_TIME 15 5849
TEST_TIME 16 5973
TEST_TIME 17 6057
TEST_TIME 18 6389
TEST_TIME 19 6535
TEST_TIME 20 6598
TEST_TIME 21 6592
TEST_TIME 22 6980
TEST_TIME 23 7353
TEST_TIME 24 7483
TEST_TIME 25 7638
TEST_TIME 26 7782
TEST_TIME 27 7958
TEST_TIME 28 8402
TEST_TIME 29 8804
TEST_TIME 30 10146
TEST_TIME 31 10051
TEST_TIME 32 10282
TEST_TIME 33 10268
TEST_TIME 34 10274
TEST_TIME 35 10363
TEST_TIME 36 10301
TEST_TIME 37 10195
TEST_TIME 38 10069
TEST_TIME 39 9819
TEST_TIME 40 9881
TEST_TIME 41 9737
TEST_TIME 42 9723
TEST_TIME 43 9747
TEST_TIME 44 9677
TEST_TIME 45 9580
TEST_TIME 46 9473
TEST_TIME 47 9467
TEST_TIME 48 9376
TEST_TIME 49 9252
TEST_TIME 50 9364
TEST_TIME 51 9334
TEST_TIME 52 9401
TEST_TIME 53 9317
TEST_TIME 54 9300
TEST_TIME 55 9201
TEST_TIME 56 9216
TEST_TIME 57 9216
TEST_TIME 58 9149
TEST_TIME 59 9193
TEST_TIME 60 9255
TEST_TIME 61 9250
TEST_TIME 62 9313
TEST_TIME 63 9412
TEST_TIME 64 9374
TEST_TIME 65 9347
TEST_TIME 66 9453
TEST_TIME 67 9530
TEST_TIME 68 9424
TEST_TIME 69 9310
TEST_TIME 70 9267
TEST_TIME 71 9216
TEST_TIME 72 9114
TEST_TIME 73 9142
TEST_TIME 74 9097
TEST_TIME 75 9046
TEST_TIME 76 9000
TEST_TIME 77 9093
TEST_TIME 78 9134
TEST_TIME 79 9118
TEST_TIME 80 9090
TEST_TIME 81 9125
TEST_TIME 82 9080
TEST_TIME 83 9140
TEST_TIME 84 9162
TEST_TIME 85 9173
TEST_TIME 86 9106
TEST_TIME 87 9008
TEST_TIME 88 9001
TEST_TIME 89 8977
TEST_TIME 90 8920
TEST_TIME 91 8916
TEST_TIME 92 8897
TEST_TIME 93 8898
TEST_TIME 94 8883
TEST_TIME 95 8875
TEST_TIME 96 8882
TEST_TIME 97 8892
TEST_TIME 98 8896
TEST_TIME 99 8927
TEST_TIME 100 9001
TEST_TIME 101 8991
TEST_TIME 102 9115
TEST_TIME 103 9081
TEST_TIME 104 9117
TEST_TIME 105 9147
TEST_TIME 106 9075
TEST_TIME 107 8998
TEST_TIME 108 8955
TEST_TIME 109 8917
TEST_TIME 110 8904
TEST_TIME 111 8888
TEST_TIME 112 8865
TEST_TIME 113 8864
TEST_TIME 114 8835
TEST_TIME 115 8818
TEST_TIME 116 8832
TEST_TIME 117 8798
TEST_TIME 118 8875
TEST_TIME 119 8895
TEST_TIME 120 8891

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-01-2021 10:27:44 to 04-01-2021 10:27:47.

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 1467288 1467288 0 0.00 0 22942 7 0 0
doc_list_biud 1467287 1467287 0 0.00 0 117500 36 0 0
sp_add_invoice_to_stock 4776 4649 127 2.65 60 189506 19261 0 127
sp_cancel_adding_invoice 5613 5613 0 0.00 5 115983 1041 0 0
sp_cancel_client_order 1721 1721 0 0.00 5 10039 378 0 0
sp_cancel_customer_reserve 552078 552078 0 0.00 1 34190 76 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 13774 110 0 0
sp_cancel_pay_to_supplier 345 345 0 0.00 3 20847 138 0 0
sp_cancel_supplier_invoice 6233 6221 12 0.19 2 117505 1016 0 12
sp_cancel_supplier_order 422 422 0 0.00 8 1279358 124597 0 0
sp_cancel_write_off 9983 9983 0 0.00 1 13915 129 0 0
sp_client_order 19785 19785 0 0.00 4 53956 458 0 0
sp_customer_reserve 431409 431409 0 0.00 0 143492 188 0 0
sp_fill_shopping_cart 30207 30207 0 0.00 1 19602 41 0 0
sp_fill_shopping_cart_clo_res 431403 431403 0 0.00 0 27550 21 0 0
sp_get_clo_for_invoice 4643 4643 0 0.00 0 55839 397 0 0
sp_kill_cost_storno 16621 16621 0 0.00 0 14017 69 0 0
sp_kill_qstorno_ret_qs2qd 1120782 1120782 0 0.00 0 117500 41 0 0
sp_kill_qty_storno 590139 590139 0 0.00 0 117500 88 0 0
sp_lock_selected_doc 19688 19688 0 0.00 0 22774 5 0 0
sp_make_cost_storno 26648 26648 0 0.00 0 14355 55 0 0
sp_multiply_rows_for_pdistr 26648 26648 0 0.00 0 9566 34 0 0
sp_multiply_rows_for_qdistr 19785 19785 0 0.00 0 53944 253 0 0
sp_pay_from_customer 6747 6747 0 0.00 4 18915 114 0 0
sp_pay_to_supplier 5800 5800 0 0.00 5 21143 124 0 0
sp_payment_common 12547 12547 0 0.00 1 20847 17 0 0
sp_qd_handle_on_cancel_clo 1721 1721 0 0.00 0 10032 354 0 0
sp_qd_handle_on_invoice_upd_sts 10256 10256 0 0.00 0 53953 431 0 0
sp_qd_handle_on_reserve_upd_sts 9458 9458 0 0.00 0 21579 69 0 0
sp_reserve_write_off 9458 9458 0 0.00 7 21643 188 0 0
sp_supplier_invoice 5161 5161 0 0.00 7 114638 3654 0 0
sp_supplier_order 5268 5268 0 0.00 5 26851 1442 0 0
srv_aggregate_perf_data 100 100 0 0.00 2576 143808 16776 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1530 169128 47159 0 0
srv_find_qd_qs_mism 1021932 1021932 0 0.00 0 22923 6 0 0
srv_make_invnt_saldo 421 421 0 0.00 42 138190 4352 0 0
srv_make_money_saldo 701 701 0 0.00 1 13603 660 0 0
srv_recalc_idx_stat 5 5 0 0.00 63327 368326 241127 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4649 4649 0 0.00 0 7317 187 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 7217 152 0 0
t$perf-norm:sp_cancel_client_order 1721 1721 0 0.00 0 5733 17 0 0
t$perf-norm:sp_cancel_customer_reserve 622 622 0 0.00 0 2107 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 3196 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 345 345 0 0.00 0 543 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 3313 30 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 20417 1556 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 2780 8 0 0
t$perf-norm:sp_client_order 19785 19785 0 0.00 0 4946 21 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 909 7 0 0
t$perf-norm:sp_pay_from_customer 6747 6747 0 0.00 0 4264 8 0 0
t$perf-norm:sp_pay_to_supplier 5800 5800 0 0.00 0 4940 10 0 0
t$perf-norm:sp_reserve_write_off 9458 9458 0 0.00 0 6878 12 0 0
t$perf-norm:sp_supplier_invoice 5161 5161 0 0.00 0 3993 20 0 0
t$perf-norm:sp_supplier_order 5268 5268 0 0.00 0 4248 22 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 304 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 421 8 0 0
t$perf-norm:srv_make_invnt_saldo 421 421 0 0.00 0 1449 15 0 0
t$perf-norm:srv_make_money_saldo 701 701 0 0.00 0 658 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 25 8 0 0
v_add_invoice_to_stock 9540 9286 254 2.66 0 3742 16 0 254
v_all_customers 36036 36036 0 0.00 0 73 1 0 0
v_all_suppliers 29566 29566 0 0.00 0 72 1 0 0
v_all_wares 39570 39570 0 0.00 0 43 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 308 7 0 0
v_cancel_client_order 3442 3442 0 0.00 0 1576 4 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 1550 7 0 0
v_cancel_customer_reserve 1244 1244 0 0.00 0 838 4 0 0
v_cancel_payment_to_supplier 690 690 0 0.00 0 20842 37 0 0
v_cancel_supplier_invoice 816 792 24 2.94 0 1229 15 0 24
v_cancel_supplier_order 844 844 0 0.00 0 357 3 0 0
v_cancel_write_off 1976 1976 0 0.00 0 1080 13 0 0
v_max_id_clo_ord 5268 5268 0 0.00 0 1901 2 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 9175 31 0 0
v_max_id_ord_sup 5154 5154 0 0.00 0 6518 6 0 0
v_max_non_paid_invoice 1439 1439 0 0.00 0 1593 3 0 0
v_max_non_paid_realizn 3428 3428 0 0.00 0 1021 3 0 0
v_min_id_clo_ord 5268 5268 0 0.00 0 2923 6 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 7831 19 0 0
v_min_id_ord_sup 5154 5154 0 0.00 0 6442 7 0 0
v_min_non_paid_invoice 5800 5800 0 0.00 0 4886 8 0 0
v_min_non_paid_realizn 6747 6747 0 0.00 0 7761 11 0 0
v_our_firm 10172 10172 0 0.00 0 51 1 0 0
v_reserve_write_off 18916 18916 0 0.00 0 2228 4 0 0
x_lock_depdocs_on_canc_invoice 11438 11438 0 0.00 0 24715 123 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 3035 295 0 0
x_make_qstorno_1000_1200 5268 5268 0 0.00 2 26818 1353 0 0
x_make_qstorno_1200_2000 5161 5161 0 0.00 2 114404 3503 0 0
x_make_qstorno_2100_3300 431409 431409 0 0.00 0 143479 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-01-2021 10:27:48 to 04-01-2021 10:27:52.

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
07:56:57 12 8192000000 8799891760 8811700224 5552576 0.067 2 1 2 0 5703808 90192 61040
07:57:58 23158 8192000000 9708060400 10002165760 788142768 8.776 100 80 159 0 815114896 7235200 19736928
07:59:26 2187 8192000000 9763180784 10067255296 841237248 9.312 100 89 178 0 873871072 11147664 21486160
08:00:33 19526 8192000000 9793654528 10105954304 866113312 9.561 100 87 174 0 902777936 16605744 20058880
08:01:58 12210 8192000000 9834948240 10162581504 894620656 9.845 100 73 146 1 948520544 36818816 17081072
08:03:13 9060 8192000000 9906083152 10243772416 935449696 10.248 100 80 160 1 1011470864 58945296 17075872
08:04:26 7686 8192000000 9937931824 10282676224 978637200 10.671 100 76 150 0 1047759968 53863776 15258992
08:05:36 3689 8192000000 9984296912 10344411136 1014373696 11.018 100 83 166 3 1098843360 67971824 16497840
08:06:41 2409 8192000000 9996576048 10365542400 1038621312 11.251 100 86 168 2 1130345824 74709280 17015232
08:07:49 1798 8192000000 10042807312 10408427520 1060712160 11.463 100 93 186 0 1152338080 72230960 19394960
08:08:52 1152 8192000000 10046440688 10420088832 1075519776 11.605 100 94 188 0 1161556960 66486592 19550592
08:09:54 1475 8192000000 10024222768 10403811328 1081042912 11.657 100 73 147 1 1130790512 33820128 15927472
08:10:56 8617 8192000000 9998596608 10397769728 1081090320 11.658 100 27 54 0 1117639584 31093392 5455872
08:12:07 4566 8192000000 9995495248 10394755072 1085532992 11.700 100 31 59 3 1111342944 19134528 6675424
08:13:14 12304 8192000000 10016414992 10423521280 1091172048 11.754 100 22 44 2 1117045968 21104704 4769216
08:14:31 16781 8192000000 10025849648 10413162496 1096267936 11.802 100 58 114 2 1124473872 14993136 13212800
08:15:49 7999 8192000000 10021910128 10428731392 1088725216 11.731 99 59 118 0 1126644688 24352816 13566656
08:16:57 4478 8192000000 10008976432 10407444480 1082209712 11.669 99 60 120 4 1113564512 18760960 12593840
08:18:09 5600 8192000000 9975975648 10359013376 1013324144 11.008 94 34 67 5 1045943376 26001568 6617664
08:19:15 19651 8192000000 9941266048 10325041152 1011187712 10.987 98 59 119 2 1035941648 10916784 13837152
08:20:35 5955 8192000000 9937208944 10316472320 997538144 10.855 96 46 91 4 1028100768 21035920 9526704
08:21:44 6626 8192000000 9935822656 10320465920 1007055520 10.947 98 22 43 2 1034746672 23166064 4525088
08:22:53 8167 8192000000 9968226304 10350043136 1029249328 11.161 99 45 91 1 1056347808 17083968 10014512
08:24:05 57844 8192000000 9965956704 10367328256 1056531472 11.423 100 20 40 0 1083594800 22656960 4406368
08:26:03 10850 8192000000 9992501680 10405011456 1078263328 11.631 100 31 60 5 1106820944 22007536 6550080
08:27:16 4129 8192000000 10023848544 10422378496 1098753600 11.826 100 41 82 1 1125614672 18381792 8479280
08:28:33 25091 8192000000 10057204032 10456485888 1113960352 11.970 100 55 109 1 1165850512 39931376 11958784
08:30:03 35951 8192000000 10068344160 10483974144 1122136704 12.047 100 43 86 0 1173907280 42185680 9584896
08:31:47 10125 8192000000 10088931824 10497814528 1136155392 12.179 100 48 95 2 1190599936 44278832 10165712
08:33:02 14257 8192000000 10108952768 10518183936 1145248912 12.265 100 52 100 2 1214538672 58691088 10598672
08:34:22 8565 8192000000 10148463728 10555375616 1156020112 12.366 100 56 112 0 1253277056 85334592 11922352
08:35:36 19885 8192000000 10215895200 10640654336 1168337584 12.481 100 67 134 0 1323309008 140641904 14329520
08:36:59 19575 8192000000 10230206096 10653097984 1178473008 12.576 100 47 94 1 1326331648 137008736 10849904
08:38:40 16468 8192000000 10256121904 10688548864 1183947776 12.627 100 53 104 2 1355826320 159680480 12198064
08:40:06 8514 8192000000 10263443072 10688245760 1177286480 12.565 99 55 108 1 1365042096 175285856 12469760
08:41:22 2335 8192000000 10293136240 10699141120 1187658912 12.662 100 82 161 3 1390605776 185035600 17911264
08:42:28 4929 8192000000 10338772448 10739511296 1180141776 12.592 100 84 163 1 1432186496 233466800 18577920
08:43:38 2254 8192000000 10378875280 10791329792 1181126672 12.601 100 80 160 2 1474920176 275223600 18569904
08:44:47 3647 8192000000 10375253872 10813853696 1193563840 12.717 100 74 148 1 1481554784 269668896 18322048
08:45:59 2983 8192000000 10426527280 10831478784 1200166256 12.778 100 87 174 0 1524893632 304936608 19790768
08:47:07 2889 8192000000 10449077872 10856562688 1188490848 12.669 100 89 179 3 1545515008 336047792 20976368
08:48:16 1529 8192000000 10519187888 10925072384 1193148112 12.713 100 87 173 1 1605291680 391674608 20468960
08:49:24 5501 8192000000 10457210224 10894209024 1174628832 12.540 100 58 116 0 1561234080 372649744 13955504
08:50:36 1841 8192000000 10487529088 10890022912 1154530560 12.352 99 91 183 0 1578804928 402718416 21555952
08:51:42 10883 8192000000 10449934416 10865463296 1113188800 11.963 96 43 86 6 1518448608 394415872 10843936
08:52:55 19714 8192000000 10449839056 10883723264 1118226032 12.010 99 36 72 1 1552738128 424507280 10004816
08:54:23 21055 8192000000 10481913584 10940207104 1130984640 12.131 100 38 75 0 1569725680 428919712 9821328
08:56:35 49696 8192000000 10111999632 10584924160 1126345120 12.087 100 18 35 1 1283867584 153453056 4069408
08:58:34 65765 8192000000 10052138208 10479529984 1102822096 11.864 97 13 26 1 1134588368 29451504 2314768
09:00:42 64823 8192000000 10067279536 10537402368 1087924336 11.723 96 10 20 0 1125184848 35441728 1818784
09:02:48 20132 8192000000 10079592176 10503659520 1132808192 12.148 99 30 60 2 1167313920 27758864 6746864
09:04:18 122538 8192000000 10152217488 10574311424 1124598544 12.070 98 41 81 0 1210700768 77610416 8491808
09:07:34 83789 8192000000 10078685104 10528731136 1117184064 12.000 98 44 84 2 1180092864 54091488 8817312
09:10:00 46570 8192000000 10080951888 10536128512 1143525536 12.249 100 21 42 0 1191664000 43814384 4324080
09:12:22 38016 8192000000 10152727360 10596839424 1157683168 12.382 100 20 40 0 1248879264 86728368 4467728
09:14:07 75228 8192000000 10178666320 10632642560 1148218016 12.293 99 13 26 0 1265898288 114513392 3166880
09:16:23 100315 8192000000 10111732080 10606936064 1135793952 12.176 99 14 29 0 1252166544 113198496 3174096
09:19:06 42938 8192000000 10066666624 10545946624 1134507168 12.164 99 6 12 0 1169471344 33789872 1174304
09:20:49 55581 8192000000 10073590288 10523664384 1139206000 12.208 99 16 32 0 1168755392 25900352 3649040
09:22:46 61316 8192000000 10102249968 10560344064 1153432144 12.342 100 9 18 0 1224256368 68864832 1959392
09:24:47 44520 8192000000 10125974736 10563727360 1102671808 11.863 96 27 54 0 1200856096 91899312 6284976
09:26:36 89224 8192000000 10099167360 10570698752 1106492608 11.899 96 24 48 1 1199759232 88099792 5166832
09:29:06 118772 8192000000 10109092176 10595811328 1098973360 11.828 96 6 12 1 1138047680 37845344 1228976
09:32:09 72645 8192000000 10073461312 10571075584 1111004464 11.942 97 11 22 0 1178541568 65658880 1878224
09:34:22 18708 8192000000 10096258752 10511069184 1123177520 12.057 100 56 110 2 1173700480 39285424 11237536
09:35:43 154833 8192000000 10096342032 10552111104 1074113664 11.591 95 23 46 1 1153801600 74737584 4950352
09:39:20 66944 8192000000 10099546480 10601160704 1124582208 12.070 100 7 14 0 1206215872 80096784 1536880
09:42:02 51290 8192000000 10130206272 10557001728 1088951440 11.733 97 23 46 2 1181820464 87967984 4901040
09:43:55 51212 8192000000 10041299728 10477494272 1090717856 11.749 98 15 30 0 1137999744 44049120 3232768
09:45:56 15362 8192000000 10042688976 10475876352 1094904448 11.789 99 47 94 0 1137422992 32505968 10012576
09:47:17 35759 8192000000 10068522208 10529525760 1113709072 11.968 100 27 54 1 1170452528 51600032 5143424
09:48:59 57419 8192000000 10093428992 10565836800 1088437792 11.728 97 35 69 2 1184131184 88378544 7314848
09:51:03 169123 8192000000 10082701232 10532798464 1084247920 11.688 97 21 42 1 1128200704 39945392 4007392
09:55:15 46296 8192000000 10047742240 10506412032 1110495120 11.937 99 6 12 0 1140398768 28584064 1319584
09:57:18 42632 8192000000 10058112080 10526400512 1126819280 12.091 100 9 18 0 1175338448 47234160 1285008
09:59:05 62439 8192000000 10038848128 10470289408 1054387040 11.403 93 12 25 0 1077556080 20879392 2289648
10:01:14 47646 8192000000 10105535168 10546360320 1110528640 11.937 98 46 91 1 1196476752 76575568 9372544
10:03:07 62144 8192000000 10132683152 10582605824 1115145328 11.981 98 23 46 2 1186355312 66258272 4951712
10:05:09 69737 8192000000 10130252976 10659717120 1138904384 12.205 99 21 41 1 1257030128 114031040 4094704
10:07:19 85076 8192000000 10106417568 10656591872 1135347648 12.172 99 9 18 1 1265230112 127515872 2366592
10:09:46 20056 8192000000 10110033808 10529566720 1136242064 12.180 99 49 99 1 1164980496 19153136 9585296
10:11:13 121074 8192000000 10077237376 10595827712 1132200032 12.142 99 4 8 0 1191976960 59088816 688112
10:14:31 52845 8192000000 10080722240 10546114560 1137221408 12.189 99 9 18 1 1173251920 34155328 1875184
10:16:24 79595 8192000000 10091993888 10580598784 1135182672 12.170 99 10 20 2 1194747280 57614832 1949776
10:18:44 82945 8192000000 10124118528 10595401728 1117439840 12.003 97 15 30 4 1201626320 80620624 3565856
10:21:18 67486 8192000000 10140054768 10575667200 1105242048 11.887 96 18 34 2 1188066464 78752336 4072080
10:23:35 46019 8192000000 10156739856 10619097088 1130533456 12.126 99 28 55 1 1233398016 96890352 5974208
10:25:34 70277 8192000000 10086685632 10539778048 1100122016 11.839 97 16 30 2 1142170400 38791264 3257120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-01-2021 10:27:54 to 04-01-2021 10:28:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-01-2021 10:28:10 to 04-01-2021 10:28:12.

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          229030
MON$OLDEST_ACTIVE               230453
MON$OLDEST_SNAPSHOT             230453
MON$NEXT_TRANSACTION            230453
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                       2109376
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             789
MON$NEXT_STATEMENT              53


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:163: 
:164: mscs-test-161.budzdorov.ru    Mon Jan  4 10:26:54 2021
:165:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:166: 
:167: 
:168: mscs-test-161.budzdorov.ru    Mon Jan  4 10:26:55 2021
:169:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:170: 
:171: 
:172: mscs-test-161.budzdorov.ru    Mon Jan  4 10:27:00 2021
:173:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:174: 
:175: 
:176: mscs-test-161.budzdorov.ru    Mon Jan  4 10:27:06 2021
:177:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:178: 
--- end of diff output ---

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

04.01.21 10:28:13. 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-01-04 10:28:15 No severe PSQL-related problems occured
04.01.21 10:28:17. End of report.