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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16407704 kB
MemAvailable 30861504 kB
Buffers 20 kB
Cached 15274412 kB
SwapCached 376 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-09-10 04:37:05
DTS_END                         2021-09-10 04:37:05.5530
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(68:17)
IP                              ::1/45024
TRN_ID                          227079
ATT_ID                          649
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: 8615.79 17967 1033895
customer order: creation 173.24 420 20789
customer order: refuse 14.12 368 1694
order to supplier: creation 46.89 1528 5627
order to supplier: removal 3.21 114108 385
invoice (draft): creation 45.18 3972 5422
invoice (draft): removal 46.79 1123 5615
invoice accept: apply 41.98 20519 5038
invoice accept: cancel 42.03 1005 5043
customer reserve: creation 3814.09 207 457691
customer reserve: removal 4101.98 68 492237
realization accept: apply 83.43 179 10011
realization accept: cancel 74.99 128 8999
payment from customer: creation 59.07 131 7088
payment from customer: removal 5.78 81 693
payment to supplier: creation 49.16 139 5899
payment to supplier: removal 3.18 79 382
service: total inventory turnovers 3.63 3974 435
service: total monetary turnovers 6.19 510 743
service: aggregate perf. data 0.83 15007 99
service: refresh index statistics 0.04 195790 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-09-2021 04:38:34 to 10-09-2021 04:38:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6268
TEST_TIME 2 6659
TEST_TIME 3 5583
TEST_TIME 4 5266
TEST_TIME 5 5178
TEST_TIME 6 5249
TEST_TIME 7 5444
TEST_TIME 8 5319
TEST_TIME 9 5303
TEST_TIME 10 5200
TEST_TIME 11 5340
TEST_TIME 12 5613
TEST_TIME 13 5688
TEST_TIME 14 5727
TEST_TIME 15 6064
TEST_TIME 16 6506
TEST_TIME 17 6820
TEST_TIME 18 6973
TEST_TIME 19 7495
TEST_TIME 20 8098
TEST_TIME 21 8008
TEST_TIME 22 8182
TEST_TIME 23 8230
TEST_TIME 24 8376
TEST_TIME 25 9197
TEST_TIME 26 9876
TEST_TIME 27 10242
TEST_TIME 28 10043
TEST_TIME 29 9818
TEST_TIME 30 9787
TEST_TIME 31 9534
TEST_TIME 32 9258
TEST_TIME 33 9294
TEST_TIME 34 9385
TEST_TIME 35 9287
TEST_TIME 36 9418
TEST_TIME 37 9355
TEST_TIME 38 9228
TEST_TIME 39 9109
TEST_TIME 40 9067
TEST_TIME 41 9081
TEST_TIME 42 9006
TEST_TIME 43 8927
TEST_TIME 44 8984
TEST_TIME 45 9112
TEST_TIME 46 9028
TEST_TIME 47 9210
TEST_TIME 48 9201
TEST_TIME 49 9263
TEST_TIME 50 9276
TEST_TIME 51 9348
TEST_TIME 52 9439
TEST_TIME 53 9324
TEST_TIME 54 9174
TEST_TIME 55 9153
TEST_TIME 56 9079
TEST_TIME 57 9100
TEST_TIME 58 9011
TEST_TIME 59 8920
TEST_TIME 60 8983
TEST_TIME 61 9095
TEST_TIME 62 9015
TEST_TIME 63 9048
TEST_TIME 64 9046
TEST_TIME 65 9025
TEST_TIME 66 9023
TEST_TIME 67 9007
TEST_TIME 68 9140
TEST_TIME 69 9141
TEST_TIME 70 9163
TEST_TIME 71 9201
TEST_TIME 72 9238
TEST_TIME 73 9215
TEST_TIME 74 9178
TEST_TIME 75 9276
TEST_TIME 76 9179
TEST_TIME 77 9152
TEST_TIME 78 9083
TEST_TIME 79 9061
TEST_TIME 80 8996
TEST_TIME 81 8933
TEST_TIME 82 8862
TEST_TIME 83 8856
TEST_TIME 84 8923
TEST_TIME 85 8926
TEST_TIME 86 8910
TEST_TIME 87 8884
TEST_TIME 88 8976
TEST_TIME 89 9003
TEST_TIME 90 9022
TEST_TIME 91 9063
TEST_TIME 92 9045
TEST_TIME 93 9038
TEST_TIME 94 9060
TEST_TIME 95 9009
TEST_TIME 96 8956
TEST_TIME 97 8971
TEST_TIME 98 8907
TEST_TIME 99 8857
TEST_TIME 100 8870
TEST_TIME 101 8822
TEST_TIME 102 8815
TEST_TIME 103 8829
TEST_TIME 104 8855
TEST_TIME 105 8887
TEST_TIME 106 8857
TEST_TIME 107 8828
TEST_TIME 108 8860
TEST_TIME 109 8829
TEST_TIME 110 8881
TEST_TIME 111 8912
TEST_TIME 112 8881
TEST_TIME 113 8810
TEST_TIME 114 8811
TEST_TIME 115 8761
TEST_TIME 116 8722
TEST_TIME 117 8723
TEST_TIME 118 8692
TEST_TIME 119 8685
TEST_TIME 120 8622

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-09-2021 04:38:38 to 10-09-2021 04:38:41.

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 1454758 1454758 0 0.00 0 24744 8 0 0
doc_list_biud 1454758 1454758 0 0.00 0 66615 30 0 0
sp_add_invoice_to_stock 5114 5038 76 1.48 19 266842 20519 0 76
sp_cancel_adding_invoice 5043 5043 0 0.00 5 90813 1005 0 0
sp_cancel_client_order 1694 1694 0 0.00 5 9563 368 0 0
sp_cancel_customer_reserve 492237 492237 0 0.00 1 41674 69 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 3 6101 82 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 2785 80 0 0
sp_cancel_supplier_invoice 5618 5615 3 0.05 2 34130 1123 0 3
sp_cancel_supplier_order 385 385 0 0.00 8 1128584 114109 0 0
sp_cancel_write_off 8999 8999 0 0.00 1 20736 128 0 0
sp_client_order 20789 20789 0 0.00 4 43835 420 0 0
sp_customer_reserve 457691 457691 0 0.00 0 70672 208 0 0
sp_fill_shopping_cart 31823 31823 0 0.00 1 14260 46 0 0
sp_fill_shopping_cart_clo_res 457680 457680 0 0.00 0 17215 17 0 0
sp_get_clo_for_invoice 5027 5027 0 0.00 0 31662 358 0 0
sp_kill_cost_storno 15117 15117 0 0.00 0 18875 67 0 0
sp_kill_qstorno_ret_qs2qd 999473 999473 0 0.00 0 41670 38 0 0
sp_kill_qty_storno 529011 529011 0 0.00 0 66615 83 0 0
sp_lock_selected_doc 20689 20689 0 0.00 0 10870 5 0 0
sp_make_cost_storno 28025 28025 0 0.00 0 9984 54 0 0
sp_multiply_rows_for_pdistr 28025 28025 0 0.00 0 8129 35 0 0
sp_multiply_rows_for_qdistr 20789 20789 0 0.00 0 18951 215 0 0
sp_pay_from_customer 7088 7088 0 0.00 4 14817 132 0 0
sp_pay_to_supplier 5899 5899 0 0.00 5 12505 140 0 0
sp_payment_common 12987 12987 0 0.00 1 12442 28 0 0
sp_qd_handle_on_cancel_clo 1694 1694 0 0.00 0 9482 336 0 0
sp_qd_handle_on_invoice_upd_sts 10070 10070 0 0.00 0 66614 388 0 0
sp_qd_handle_on_reserve_upd_sts 10011 10011 0 0.00 0 20416 56 0 0
sp_reserve_write_off 10011 10011 0 0.00 7 20446 180 0 0
sp_supplier_invoice 5422 5422 0 0.00 6 181855 3972 0 0
sp_supplier_order 5627 5627 0 0.00 6 118927 1528 0 0
srv_aggregate_perf_data 99 99 0 0.00 2681 137848 15008 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1331 136394 43415 0 0
srv_find_qd_qs_mism 984866 984866 0 0.00 0 24742 8 0 0
srv_make_invnt_saldo 435 435 0 0.00 54 96698 3974 0 0
srv_make_money_saldo 743 743 0 0.00 1 12157 510 0 0
srv_recalc_idx_stat 5 5 0 0.00 97089 317089 195790 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 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5038 5038 0 0.00 0 9731 170 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 1 2364 96 0 0
t$perf-norm:sp_cancel_client_order 1694 1694 0 0.00 0 2561 12 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 8222 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 639 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 754 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 1969 23 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 19919 1379 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 3191 13 0 0
t$perf-norm:sp_client_order 20789 20789 0 0.00 0 7241 19 0 0
t$perf-norm:sp_customer_reserve 1059 1059 0 0.00 0 2318 10 0 0
t$perf-norm:sp_pay_from_customer 7088 7088 0 0.00 0 3171 7 0 0
t$perf-norm:sp_pay_to_supplier 5899 5899 0 0.00 0 7035 11 0 0
t$perf-norm:sp_reserve_write_off 10011 10011 0 0.00 0 14229 12 0 0
t$perf-norm:sp_supplier_invoice 5422 5422 0 0.00 0 24976 24 0 0
t$perf-norm:sp_supplier_order 5627 5627 0 0.00 0 8903 19 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 533 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 354 7 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 2043 17 0 0
t$perf-norm:srv_make_money_saldo 743 743 0 0.00 0 909 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10202 10050 152 1.48 0 2751 14 0 152
v_all_customers 37652 37652 0 0.00 0 211 1 0 0
v_all_suppliers 30620 30620 0 0.00 0 18 1 0 0
v_all_wares 41578 41578 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 1744 9 0 0
v_cancel_client_order 3388 3388 0 0.00 0 5473 7 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 5474 8 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 1886 7 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 2776 13 0 0
v_cancel_supplier_invoice 776 770 6 0.77 0 1937 18 0 6
v_cancel_supplier_order 770 770 0 0.00 0 2483 8 0 0
v_cancel_write_off 2064 2064 0 0.00 0 3698 16 0 0
v_max_id_clo_ord 5620 5620 0 0.00 0 364 1 0 0
v_max_id_clo_res 1059 1059 0 0.00 0 1466 12 0 0
v_max_id_ord_sup 5413 5413 0 0.00 0 7786 7 0 0
v_max_non_paid_invoice 1622 1622 0 0.00 0 806 2 0 0
v_max_non_paid_realizn 3846 3846 0 0.00 0 1536 4 0 0
v_min_id_clo_ord 5620 5620 0 0.00 0 5632 14 0 0
v_min_id_clo_res 1059 1059 0 0.00 0 7699 35 0 0
v_min_id_ord_sup 5413 5413 0 0.00 0 9284 16 0 0
v_min_non_paid_invoice 5899 5899 0 0.00 0 12439 16 0 0
v_min_non_paid_realizn 7088 7088 0 0.00 0 7236 24 0 0
v_our_firm 10410 10410 0 0.00 0 28 1 0 0
v_reserve_write_off 20022 20022 0 0.00 0 5258 6 0 0
x_lock_depdocs_on_canc_invoice 10273 10273 0 0.00 0 13708 116 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 10247 342 0 0
x_make_qstorno_1000_1200 5627 5627 0 0.00 2 118866 1436 0 0
x_make_qstorno_1200_2000 5422 5422 0 0.00 3 181714 3798 0 0
x_make_qstorno_2100_3300 457691 457691 0 0.00 0 70671 188 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-09-2021 04:38:42 to 10-09-2021 04:38:46.

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
02:07:09 4 8192000000 8800800848 8812945408 6437744 0.078 2 2 3 0 6603024 103904 61376
02:08:09 20051 8192000000 9718765888 10013696000 797369248 8.870 100 90 180 0 826743568 7482112 21892208
02:09:37 26500 8192000000 9759289584 10062643200 826875824 9.168 100 87 174 0 860026192 12531120 20619248
02:11:20 24702 8192000000 9801147760 10123542528 871110832 9.611 100 78 157 1 912986512 24586688 17288992
02:12:51 9980 8192000000 9864554240 10194485248 908920976 9.987 100 82 164 0 972812336 46219840 17671520
02:14:06 13148 8192000000 9916415024 10254598144 945710912 10.349 100 81 162 0 1026898176 63958672 17228592
02:15:22 6656 8192000000 9938367488 10285785088 981460864 10.698 100 77 153 0 1047283344 50439504 15382976
02:16:32 3826 8192000000 9980443376 10339778560 1012877040 11.003 100 87 174 0 1093219248 62627440 17714768
02:17:38 2803 8192000000 10016680048 10379366400 1028301360 11.152 100 84 169 1 1122470752 77407168 16762224
02:18:42 3038 8192000000 10027389904 10395893760 1056843872 11.426 100 90 180 0 1143617104 68712736 18060496
02:19:49 23727 8192000000 10021854112 10401021952 1065298368 11.507 100 58 115 0 1130194960 52164528 12732064
02:21:16 5347 8192000000 10014142496 10414682112 1075752336 11.607 100 40 80 0 1134472704 51073520 7646848
02:22:23 43379 8192000000 9995234848 10385272832 1076178784 11.611 100 42 81 1 1107994448 22127984 9687680
02:24:07 3583 8192000000 10030723664 10426916864 1083551008 11.681 100 48 93 2 1135989184 41659456 10778720
02:25:13 4966 8192000000 10008491680 10403172352 1093716608 11.778 100 40 80 0 1118491632 15900896 8874128
02:26:20 11406 8192000000 10006633328 10399182848 1087898464 11.723 99 35 70 1 1103760896 8558432 7304000
02:27:35 11806 8192000000 10026251248 10413203456 1095200032 11.792 100 73 147 2 1120840048 9271952 16368064
02:28:47 6912 8192000000 9993839488 10397372416 1057499216 11.433 97 41 82 0 1081049040 14049760 9500064
02:29:57 62742 8192000000 9981207232 10391330816 997940448 10.859 92 19 38 2 1029530896 27685328 3905120
02:32:04 3826 8192000000 9946876960 10320277504 1008346208 10.959 98 45 89 2 1042023168 22697168 10979792
02:33:09 8106 8192000000 9933079088 10322841600 1016786992 11.041 100 43 85 0 1048890720 22771024 9332704
02:34:18 57977 8192000000 9939702016 10341707776 1016704000 11.040 98 33 66 1 1043372352 19657712 7010640
02:36:16 9003 8192000000 9963520576 10371100672 1051985904 11.380 100 37 73 1 1083548368 23217840 8344624
02:37:29 24330 8192000000 10000707408 10392010752 1078139856 11.630 100 54 106 3 1105972240 16301392 11530992
02:38:58 35461 8192000000 10021794432 10426507264 1089572224 11.739 100 54 104 3 1182259536 81025504 11661808
02:40:39 65176 8192000000 10054472640 10463739904 1100599936 11.843 100 38 75 0 1163014368 54303744 8110688
02:42:49 6027 8192000000 10109311760 10503905280 1123453728 12.060 100 63 126 1 1205649424 68936688 13259008
02:43:57 18564 8192000000 10130814112 10545827840 1135583408 12.174 100 45 90 0 1241085392 95916688 9585296
02:45:19 6628 8192000000 10183598000 10592247808 1154044256 12.347 100 49 98 1 1292168096 127316160 10807680
02:46:28 8366 8192000000 10203490992 10614714368 1157698048 12.382 100 51 100 2 1316079504 146793904 11587552
02:47:42 4936 8192000000 10264561872 10673082368 1168494880 12.483 100 66 132 0 1369143232 186101056 14547296
02:48:52 3286 8192000000 10326966560 10727657472 1172249344 12.518 100 79 157 1 1423500048 234677280 16573424
02:49:59 1549 8192000000 10342986384 10751139840 1181366992 12.603 100 88 174 0 1440325312 238453552 20504768
02:51:06 3226 8192000000 10386928752 10795958272 1179154384 12.582 99 85 170 1 1482074144 282953984 19965776
02:52:14 3347 8192000000 10420389824 10849140736 1188419872 12.669 100 85 169 0 1520482928 311843024 20220032
02:53:23 1684 8192000000 10445356704 10867859456 1186331280 12.649 100 77 155 1 1553894608 349465760 18097568
02:54:31 3724 8192000000 10441746128 10879954944 1166780096 12.467 99 82 164 1 1534731088 348836848 19114144
02:55:40 1330 8192000000 10433681760 10853187584 1180118000 12.591 100 64 129 0 1533712576 338534256 15060320
02:56:44 5294 8192000000 10410260992 10847965184 1154092160 12.348 100 71 139 0 1505432528 334622208 16718160
02:57:53 9855 8192000000 10335100144 10760736768 1150806000 12.317 100 40 78 0 1432611584 272482704 9322880
02:59:11 13823 8192000000 10334493392 10800381952 1141504176 12.230 100 41 82 1 1449236320 297544960 10187184
03:00:34 29989 8192000000 10301555504 10764824576 1098337216 11.822 97 23 46 1 1406811200 301917920 6556064
03:02:25 13145 8192000000 10245897552 10728292352 1102814720 11.864 99 23 47 1 1350822784 241861296 6146768
03:03:39 52810 8192000000 10027327056 10525626368 1067515648 11.528 96 10 20 0 1127336016 57466848 2353520
03:05:33 4298 8192000000 10065138720 10490478592 1122429728 12.050 100 47 93 2 1166051616 32944384 10677504
03:06:43 108180 8192000000 10139159104 10598760448 1125412032 12.078 100 36 73 0 1205827216 72747824 7667360
03:10:20 49645 8192000000 10065831968 10561691648 1131369264 12.134 100 24 48 0 1209428672 73087184 4972224
03:12:12 34766 8192000000 10139417008 10576359424 1145058304 12.263 100 49 96 1 1231814912 77131120 9625488
03:13:58 17084 8192000000 10061819184 10536927232 1122060096 12.046 99 18 36 0 1156849520 31081728 3707696
03:15:19 38054 8192000000 10041590928 10494341120 1079281456 11.641 96 18 33 1 1122086800 39451952 3353392
03:17:06 49910 8192000000 10068023072 10538622976 1128242512 12.105 100 19 37 0 1172267728 40297616 3727600
03:18:57 43701 8192000000 10073761680 10527850496 1040637504 11.271 92 40 79 0 1083972368 34632672 8702192
03:20:46 72014 8192000000 10106198768 10572767232 1112409888 11.955 99 30 60 0 1217862512 98488640 6963984
03:23:03 22684 8192000000 10105949520 10540167168 1110894688 11.941 98 39 77 0 1171174576 52166496 8113392
03:24:32 63342 8192000000 10102635936 10535837696 1096244336 11.802 97 19 38 0 1177891488 77580832 4066320
03:26:48 100458 8192000000 10097398800 10568105984 1138881696 12.205 100 17 33 2 1186522144 44132128 3508320
03:29:29 77264 8192000000 10087518272 10530418688 1142532336 12.239 100 20 38 1 1191974832 45541152 3901344
03:31:47 76731 8192000000 10128187088 10597638144 1137435936 12.191 99 14 28 0 1209095024 68698976 2960112
03:35:07 57831 8192000000 10154317264 10615775232 1152222592 12.330 100 13 26 0 1253795936 98798480 2774864
03:37:35 33579 8192000000 10134095424 10620530688 1146955248 12.281 100 9 18 0 1285116512 136144032 2017232
03:39:17 76373 8192000000 10104003984 10549243904 1081902160 11.666 94 13 26 1 1142508960 57668992 2937808
03:41:38 52428 8192000000 10078698096 10527760384 1108095568 11.914 97 17 34 0 1149737312 38498944 3142800
03:43:38 22750 8192000000 10065235312 10489344000 1116279152 11.992 100 35 70 1 1163963696 40515088 7169456
03:45:03 90627 8192000000 10094214848 10553860096 1113600304 11.966 99 13 26 0 1175836544 59494496 2741744
03:47:38 28500 8192000000 10064474432 10504900608 1113171760 11.962 99 21 42 0 1151070736 33576720 4322256
03:49:09 100400 8192000000 10052098144 10482425856 1097581200 11.815 98 31 61 1 1139745536 35562464 6601872
03:51:53 25828 8192000000 10115849856 10536800256 1091967680 11.761 97 48 96 0 1168164864 66580672 9616512
03:53:19 131193 8192000000 10023417888 10458050560 1072738976 11.578 96 14 28 2 1129394736 53801024 2854736
03:56:34 72861 8192000000 10091766496 10556469248 1124958224 12.074 100 12 24 0 1198005152 70412512 2634416
03:58:48 44433 8192000000 10124176096 10564808704 1111058384 11.942 98 20 40 1 1217218608 101704432 4455792
04:00:35 53249 8192000000 10107111552 10636267520 1121541232 12.042 100 13 26 0 1210558432 86105008 2912192
04:02:36 136394 8192000000 10130678848 10568994816 1049536272 11.356 92 11 22 1 1122328944 70221536 2571136
04:05:57 39615 8192000000 10078031888 10513338368 1098983520 11.828 98 38 76 1 1174982640 66926656 9072464
04:07:39 38721 8192000000 10086939328 10530312192 1106456048 11.899 99 16 32 0 1181577744 71700832 3420864
04:09:27 15645 8192000000 10096877648 10509500416 1115929600 11.989 100 65 129 0 1178079936 48627616 13522720
04:10:43 38576 8192000000 10112317168 10552557568 1110902128 11.941 99 46 92 0 1194443264 74683040 8858096
04:12:32 63793 8192000000 10084994864 10513981440 1120514496 12.032 99 39 78 0 1189216016 61104272 7597248
04:14:43 80135 8192000000 10065170992 10540371968 1130581696 12.127 100 9 18 0 1171641984 38892560 2167728
04:17:04 56666 8192000000 10054583344 10534596608 1113487760 11.965 98 9 16 0 1166479632 50970208 2021664
04:19:10 61148 8192000000 10106590656 10561601536 1112942288 11.960 98 33 66 0 1206109328 86518656 6648384
04:21:11 31971 8192000000 10075833376 10518315008 1136641504 12.184 100 23 46 1 1171757264 29530000 5585760
04:22:44 44601 8192000000 10059561744 10499747840 1104041504 11.876 97 16 33 0 1132197072 25287104 2868464
04:24:36 63045 8192000000 10060023136 10535907328 1094493184 11.785 97 25 50 0 1131422112 31820240 5108688
04:26:40 25406 8192000000 10116746880 10545504256 1125291712 12.077 99 47 95 0 1194062688 59083008 9687968
04:28:06 75455 8192000000 10033380736 10489171968 1073305600 11.584 95 22 44 0 1130044400 51538336 5200464
04:30:24 87538 8192000000 10065986128 10527621120 1123313360 12.058 100 30 60 0 1186897648 57524192 6060096
04:33:08 57958 8192000000 10061034688 10519793664 1116299584 11.992 99 7 14 0 1151586608 33829936 1457088
04:35:17 44400 8192000000 10079235488 10514104320 1107761232 11.911 99 23 45 1 1148624624 35437632 5425760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-09-2021 04:38:48 to 10-09-2021 04:39:03.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-09-2021 04:39:04 to 10-09-2021 04:39:06.

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          224898
MON$OLDEST_ACTIVE               228553
MON$OLDEST_SNAPSHOT             228553
MON$NEXT_TRANSACTION            228553
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                       2002560
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2586 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2586 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2586 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2586 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
--- end of diff output ---

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

10.09.21 04:39:07. 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-09-10 04:39:09 No severe PSQL-related problems occured
10.09.21 04:39:10. End of report.