Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.12.2020 10: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 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 16641028 kB
MemAvailable 31391192 kB
Buffers 0 kB
Cached 14966876 kB
SwapCached 4820 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 2020-12-27 10:26:55
DTS_END                         2020-12-27 10:26:55.0770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56332
TRN_ID                          237758
ATT_ID                          604
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: 9130.47 25336 1095656
customer order: creation 165.57 387 19868
customer order: refuse 15.06 323 1807
order to supplier: creation 45.98 1365 5518
order to supplier: removal 3.39 118700 407
invoice (draft): creation 45.48 3633 5457
invoice (draft): removal 51.28 958 6154
invoice accept: apply 41.73 17996 5007
invoice accept: cancel 46.13 1004 5535
customer reserve: creation 3855.42 176 462650
customer reserve: removal 4564.42 69 547730
realization accept: apply 81.89 164 9827
realization accept: cancel 87.96 125 10555
payment from customer: creation 57.71 105 6925
payment from customer: removal 5.78 95 694
payment to supplier: creation 48.30 120 5796
payment to supplier: removal 3.18 110 382
service: total inventory turnovers 4.14 3387 497
service: total monetary turnovers 6.05 539 726
service: aggregate perf. data 0.97 13917 116
service: refresh index statistics 0.04 343539 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-12-2020 10:29:11 to 27-12-2020 10:29:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1356
TEST_TIME 2 2913
TEST_TIME 3 3274
TEST_TIME 4 4137
TEST_TIME 5 4732
TEST_TIME 6 4856
TEST_TIME 7 5104
TEST_TIME 8 5337
TEST_TIME 9 5510
TEST_TIME 10 6113
TEST_TIME 11 6511
TEST_TIME 12 6567
TEST_TIME 13 6545
TEST_TIME 14 6563
TEST_TIME 15 6448
TEST_TIME 16 6550
TEST_TIME 17 6710
TEST_TIME 18 7350
TEST_TIME 19 7491
TEST_TIME 20 7656
TEST_TIME 21 7881
TEST_TIME 22 8205
TEST_TIME 23 8122
TEST_TIME 24 8580
TEST_TIME 25 8726
TEST_TIME 26 9236
TEST_TIME 27 9804
TEST_TIME 28 10917
TEST_TIME 29 10816
TEST_TIME 30 10765
TEST_TIME 31 10608
TEST_TIME 32 10532
TEST_TIME 33 10456
TEST_TIME 34 10291
TEST_TIME 35 10638
TEST_TIME 36 10479
TEST_TIME 37 10243
TEST_TIME 38 10144
TEST_TIME 39 9995
TEST_TIME 40 10003
TEST_TIME 41 9960
TEST_TIME 42 9908
TEST_TIME 43 9883
TEST_TIME 44 9794
TEST_TIME 45 9859
TEST_TIME 46 9719
TEST_TIME 47 9654
TEST_TIME 48 9615
TEST_TIME 49 9610
TEST_TIME 50 9562
TEST_TIME 51 9623
TEST_TIME 52 9553
TEST_TIME 53 9527
TEST_TIME 54 9625
TEST_TIME 55 9696
TEST_TIME 56 9857
TEST_TIME 57 9818
TEST_TIME 58 9731
TEST_TIME 59 9733
TEST_TIME 60 9962
TEST_TIME 61 9970
TEST_TIME 62 9932
TEST_TIME 63 9855
TEST_TIME 64 9756
TEST_TIME 65 9700
TEST_TIME 66 9620
TEST_TIME 67 9554
TEST_TIME 68 9494
TEST_TIME 69 9424
TEST_TIME 70 9477
TEST_TIME 71 9482
TEST_TIME 72 9520
TEST_TIME 73 9480
TEST_TIME 74 9458
TEST_TIME 75 9474
TEST_TIME 76 9538
TEST_TIME 77 9572
TEST_TIME 78 9577
TEST_TIME 79 9609
TEST_TIME 80 9622
TEST_TIME 81 9649
TEST_TIME 82 9637
TEST_TIME 83 9531
TEST_TIME 84 9460
TEST_TIME 85 9445
TEST_TIME 86 9410
TEST_TIME 87 9374
TEST_TIME 88 9392
TEST_TIME 89 9363
TEST_TIME 90 9386
TEST_TIME 91 9434
TEST_TIME 92 9401
TEST_TIME 93 9403
TEST_TIME 94 9435
TEST_TIME 95 9459
TEST_TIME 96 9473
TEST_TIME 97 9489
TEST_TIME 98 9558
TEST_TIME 99 9473
TEST_TIME 100 9385
TEST_TIME 101 9382
TEST_TIME 102 9340
TEST_TIME 103 9292
TEST_TIME 104 9277
TEST_TIME 105 9240
TEST_TIME 106 9228
TEST_TIME 107 9201
TEST_TIME 108 9214
TEST_TIME 109 9223
TEST_TIME 110 9230
TEST_TIME 111 9226
TEST_TIME 112 9231
TEST_TIME 113 9278
TEST_TIME 114 9257
TEST_TIME 115 9237
TEST_TIME 116 9283
TEST_TIME 117 9276
TEST_TIME 118 9201
TEST_TIME 119 9167
TEST_TIME 120 9133

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-12-2020 10:29:14 to 27-12-2020 10:29:18.

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 1524870 1524870 0 0.00 0 16233 7 0 0
doc_list_biud 1524870 1524870 0 0.00 0 31949 31 0 0
sp_add_invoice_to_stock 5186 5007 179 3.45 52 183863 17997 0 179
sp_cancel_adding_invoice 5535 5535 0 0.00 4 79431 1004 0 0
sp_cancel_client_order 1807 1807 0 0.00 4 12082 324 0 0
sp_cancel_customer_reserve 547730 547730 0 0.00 1 31951 70 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 5182 95 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 8860 111 0 0
sp_cancel_supplier_invoice 6166 6154 12 0.19 2 17746 958 0 12
sp_cancel_supplier_order 407 407 0 0.00 11 1231137 118700 0 0
sp_cancel_write_off 10555 10555 0 0.00 1 12187 126 0 0
sp_client_order 19868 19868 0 0.00 4 46264 388 0 0
sp_customer_reserve 462650 462650 0 0.00 0 32965 176 0 0
sp_fill_shopping_cart 30826 30826 0 0.00 1 14057 47 0 0
sp_fill_shopping_cart_clo_res 462644 462644 0 0.00 0 25000 16 0 0
sp_get_clo_for_invoice 5001 5001 0 0.00 0 35602 396 0 0
sp_kill_cost_storno 17166 17166 0 0.00 0 9514 59 0 0
sp_kill_qstorno_ret_qs2qd 1112576 1112576 0 0.00 0 31946 37 0 0
sp_kill_qty_storno 587016 587016 0 0.00 0 31949 80 0 0
sp_lock_selected_doc 20530 20530 0 0.00 0 11373 4 0 0
sp_make_cost_storno 27549 27549 0 0.00 0 11799 50 0 0
sp_multiply_rows_for_pdistr 27549 27549 0 0.00 0 10139 33 0 0
sp_multiply_rows_for_qdistr 19868 19868 0 0.00 0 46254 206 0 0
sp_pay_from_customer 6925 6925 0 0.00 5 13334 106 0 0
sp_pay_to_supplier 5796 5796 0 0.00 5 12747 120 0 0
sp_payment_common 12721 12721 0 0.00 1 6537 19 0 0
sp_qd_handle_on_cancel_clo 1807 1807 0 0.00 0 12049 288 0 0
sp_qd_handle_on_invoice_upd_sts 10536 10536 0 0.00 0 21530 385 0 0
sp_qd_handle_on_reserve_upd_sts 9827 9827 0 0.00 0 14081 48 0 0
sp_reserve_write_off 9827 9827 0 0.00 7 14106 165 0 0
sp_supplier_invoice 5457 5457 0 0.00 7 86574 3633 0 0
sp_supplier_order 5518 5518 0 0.00 6 76250 1366 0 0
srv_aggregate_perf_data 116 116 0 0.00 3198 116021 13917 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1057 223003 44977 0 0
srv_find_qd_qs_mism 1049055 1049055 0 0.00 0 16149 6 0 0
srv_make_invnt_saldo 497 497 0 0.00 61 100448 3387 0 0
srv_make_money_saldo 726 726 0 0.00 1 9944 540 0 0
srv_recalc_idx_stat 5 5 0 0.00 199448 550154 343540 0 0
t$perf-abend:sp_add_invoice_to_stock 179 179 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 5008 5008 0 0.00 0 15817 199 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 4321 108 0 0
t$perf-norm:sp_cancel_client_order 1807 1807 0 0.00 0 4069 19 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 1111 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 1876 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 4025 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 4814 24 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 24027 1691 0 0
t$perf-norm:sp_cancel_write_off 951 951 0 0.00 0 1971 6 0 0
t$perf-norm:sp_client_order 19868 19868 0 0.00 0 7877 22 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 3665 11 0 0
t$perf-norm:sp_pay_from_customer 6925 6925 0 0.00 0 8436 13 0 0
t$perf-norm:sp_pay_to_supplier 5796 5796 0 0.00 0 7012 10 0 0
t$perf-norm:sp_reserve_write_off 9827 9827 0 0.00 0 4543 10 0 0
t$perf-norm:sp_supplier_invoice 5457 5457 0 0.00 0 6705 25 0 0
t$perf-norm:sp_supplier_order 5518 5518 0 0.00 0 4404 15 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 389 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 242 10 0 0
t$perf-norm:srv_make_invnt_saldo 497 497 0 0.00 0 2047 11 0 0
t$perf-norm:srv_make_money_saldo 726 726 0 0.00 0 2020 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 31 8 0 0
v_add_invoice_to_stock 10352 9994 358 3.45 0 5002 18 0 358
v_all_customers 36328 36328 0 0.00 0 24 1 0 0
v_all_suppliers 30384 30384 0 0.00 0 21 1 0 0
v_all_wares 39736 39736 0 0.00 0 129 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 1875 9 0 0
v_cancel_client_order 3614 3614 0 0.00 0 2924 6 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 2350 10 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 919 4 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 4977 14 0 0
v_cancel_supplier_invoice 784 760 24 3.06 0 1954 16 0 24
v_cancel_supplier_order 814 814 0 0.00 0 316 4 0 0
v_cancel_write_off 1902 1902 0 0.00 0 7171 26 0 0
v_max_id_clo_ord 5516 5516 0 0.00 0 3709 2 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 3587 21 0 0
v_max_id_ord_sup 5442 5442 0 0.00 0 8353 7 0 0
v_max_non_paid_invoice 1562 1562 0 0.00 0 1856 4 0 0
v_max_non_paid_realizn 3637 3637 0 0.00 0 3305 4 0 0
v_min_id_clo_ord 5516 5516 0 0.00 0 2250 6 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 2669 7 0 0
v_min_id_ord_sup 5442 5442 0 0.00 0 11450 11 0 0
v_min_non_paid_invoice 5796 5796 0 0.00 0 3344 9 0 0
v_min_non_paid_realizn 6925 6925 0 0.00 0 6533 11 0 0
v_our_firm 9984 9984 0 0.00 0 21 1 0 0
v_reserve_write_off 19650 19650 0 0.00 0 6628 5 0 0
x_lock_depdocs_on_canc_invoice 11309 11309 0 0.00 0 17311 120 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 8244 373 0 0
x_make_qstorno_1000_1200 5518 5518 0 0.00 2 76240 1286 0 0
x_make_qstorno_1200_2000 5457 5457 0 0.00 3 85510 3449 0 0
x_make_qstorno_2100_3300 462650 462650 0 0.00 0 32963 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-12-2020 10:29:19 to 27-12-2020 10:29:23.

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:58 10 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
07:57:59 30030 8192000000 9709495712 10001448960 786354448 8.758 100 84 168 0 814304064 7343488 20606128
07:59:36 11716 8192000000 9740806288 10042355712 821475376 9.113 100 81 162 0 853019152 11976912 19566864
08:00:57 13563 8192000000 9786703552 10100789248 857616768 9.476 100 85 170 2 899093776 21761680 19715328
08:02:15 11649 8192000000 9854924448 10187628544 910803088 10.005 100 65 130 2 966012208 41317056 13892064
08:03:28 6611 8192000000 9906372752 10251284480 959250224 10.482 100 77 153 3 1020344848 45543136 15551488
08:04:36 7137 8192000000 9966273328 10320023552 995886976 10.839 100 83 166 0 1077809888 65635936 16286976
08:05:46 2389 8192000000 10000938464 10367586304 1019967472 11.072 100 83 161 3 1115210640 78797600 16445568
08:06:50 1234 8192000000 10012025600 10380922880 1047703024 11.339 100 84 171 2 1131596880 66285696 17608160
08:07:52 18418 8192000000 10019645536 10413985792 1062769776 11.483 100 63 126 1 1154387504 78486320 13131408
08:09:12 7535 8192000000 10003862832 10401714176 1074725120 11.597 100 33 64 0 1124385040 42933712 6726208
08:10:24 5368 8192000000 9998973760 10389131264 1084928304 11.694 100 41 82 2 1107142720 13310800 8903616
08:11:34 41784 8192000000 9996318624 10409021440 1083711088 11.683 100 36 72 4 1116574672 25331568 7532016
08:13:16 5906 8192000000 10001501136 10406928384 1092637792 11.768 100 28 56 6 1120788976 21250320 6900864
08:14:23 5972 8192000000 10012909968 10416869376 1096143856 11.801 100 29 59 2 1118460464 15717536 6599072
08:15:32 5927 8192000000 10021232672 10426994688 1098137152 11.820 100 38 73 2 1115219552 9402496 7679904
08:16:44 29730 8192000000 10002124368 10417442816 1070031584 11.552 98 38 77 0 1100676032 21900800 8743648
08:18:14 3288 8192000000 9976139344 10368135168 1060763984 11.464 99 35 68 2 1090332064 21266768 8301312
08:19:20 10243 8192000000 9925502384 10313719808 945412016 10.346 92 21 42 1 970251264 20104192 4735056
08:20:32 6955 8192000000 9931419456 10304544768 1002841968 10.906 99 43 86 2 1035912544 23616304 9454272
08:21:39 39003 8192000000 9949121120 10338615296 1004221904 10.919 98 53 105 3 1029173376 13117024 11834448
08:23:23 3568 8192000000 9982405600 10368516096 1041200560 11.276 100 44 86 3 1086242336 34873392 10168384
08:24:27 8376 8192000000 9979295968 10372804608 1059255168 11.449 100 46 92 3 1085579952 16167760 10157024
08:25:38 4574 8192000000 9965499360 10357317632 1060680784 11.463 100 45 90 0 1082228144 11976272 9571088
08:26:47 73914 8192000000 9991932928 10383831040 1068051696 11.533 100 44 88 1 1096981088 19887088 9042304
08:29:09 26891 8192000000 10044075744 10446356480 1088806256 11.731 100 38 76 2 1161954192 65096704 8051232
08:30:38 15163 8192000000 10069445248 10471686144 1098259584 11.821 100 36 72 0 1178442688 72832064 7351040
08:31:57 14620 8192000000 10083964736 10486272000 1110698272 11.939 100 43 86 2 1191835392 72281312 8855808
08:33:15 8802 8192000000 10121627440 10519474176 1131194544 12.133 100 58 116 1 1226591520 83636816 11760160
08:34:28 4927 8192000000 10152828800 10568601600 1139208208 12.208 100 62 121 1 1263712480 111710688 12793584
08:35:36 16906 8192000000 10177228032 10598273024 1139267568 12.209 100 38 76 0 1282221216 133754032 9199616
08:36:58 13800 8192000000 10196390624 10610372608 1155915472 12.365 100 65 128 2 1299324592 128810672 14598448
08:38:14 8860 8192000000 10196656352 10614358016 1169798352 12.495 100 64 125 0 1310557680 127818608 12940720
08:39:27 2177 8192000000 10232228656 10624790528 1151964448 12.328 99 86 170 1 1331862864 160604000 19294416
08:40:33 7287 8192000000 10277575328 10677760000 1171614624 12.512 100 69 137 1 1377645632 190179200 15851808
08:41:45 6612 8192000000 10357134080 10761035776 1183974496 12.627 100 72 144 2 1457501024 256981488 16545040
08:42:56 1055 8192000000 10405366240 10807537664 1179249312 12.583 100 93 186 1 1505881488 305050304 21581872
08:44:04 1490 8192000000 10433916512 10856185856 1180486720 12.595 100 92 183 1 1535912896 333996752 21429424
08:45:08 2556 8192000000 10441041168 10846863360 1188526464 12.670 100 86 173 0 1541158464 332504896 20127104
08:46:16 2985 8192000000 10459415584 10862477312 1186687776 12.653 100 82 164 0 1553631472 348643696 18300000
08:47:26 1666 8192000000 10481288160 10884255744 1161676448 12.419 99 87 174 0 1566580448 384093584 20810416
08:48:31 9582 8192000000 10466896480 10894635008 1153097328 12.339 99 42 84 1 1565680720 402436320 10147072
08:49:49 1259 8192000000 10505767552 10909380608 1171106416 12.507 100 90 177 0 1590184384 398665920 20412048
08:50:52 14284 8192000000 10442688768 10927878144 1165851120 12.458 100 32 64 0 1556405968 381795824 8759024
08:52:11 14726 8192000000 10430892096 10917351424 1150501344 12.314 100 31 62 0 1556132752 396924656 8706752
08:53:50 27764 8192000000 10204230816 10679017472 1104371936 11.879 98 16 32 0 1345091200 236097136 4622128
08:56:43 23582 8192000000 10051704240 10469179392 1118784480 12.016 100 48 96 1 1139493728 9932544 10776704
08:58:12 94048 8192000000 10145730256 10611294208 1120055328 12.028 99 10 20 0 1175701056 53275696 2370032
09:01:00 134603 8192000000 10056646640 10522021888 1107744992 11.911 98 26 52 3 1155423792 41927440 5751360
09:04:20 12618 8192000000 10055688304 10487521280 1109719888 11.930 99 24 48 1 1136592304 21877952 4994464
09:05:34 11426 8192000000 10083403328 10508005376 1119983536 12.027 100 56 108 1 1155108672 23098144 12026992
09:06:52 35501 8192000000 10047492416 10514870272 1118402544 12.012 100 11 21 1 1149316000 28976656 1936800
09:08:51 54279 8192000000 10077586976 10545917952 1127468960 12.097 100 24 48 1 1171744832 38618896 5656976
09:10:51 44432 8192000000 10089206256 10534457344 1120820016 12.035 99 27 55 0 1177558672 50517024 6221632
09:12:37 69015 8192000000 10071877008 10555330560 1132903792 12.149 100 19 38 1 1181953904 45159312 3890800
09:14:56 59551 8192000000 10051137104 10514608128 1106230464 11.897 97 21 42 0 1143113536 32230032 4653040
09:16:58 39204 8192000000 10093002384 10541117440 1140165232 12.217 100 23 46 0 1178313648 33714064 4434352
09:18:39 32838 8192000000 10164900768 10598379520 1136440304 12.182 99 37 74 1 1214384304 69747392 8196608
09:20:21 51903 8192000000 10168417680 10634625024 1141026064 12.225 99 14 28 0 1251775936 107764240 2985632
09:22:14 32671 8192000000 10069924048 10514812928 1142385904 12.238 100 14 28 0 1194108208 49039520 2682784
09:23:49 35999 8192000000 10136338480 10571808768 1134938480 12.168 99 37 74 0 1210039264 67559120 7541664
09:25:43 78262 8192000000 10087022944 10568806400 1075759616 11.607 94 17 34 0 1180430960 100594048 4077296
09:28:01 29113 8192000000 10070828128 10503946240 1096336080 11.803 98 31 62 0 1130413120 28052432 6024608
09:29:31 69442 8192000000 10025386416 10460569600 1106373280 11.898 99 17 34 2 1142792720 32638400 3781040
09:31:41 50546 8192000000 10047653584 10501115904 1108966128 11.923 99 13 26 1 1159221696 47380544 2875024
09:34:17 102437 8192000000 10133042544 10601385984 1124006864 12.065 99 14 28 1 1212914000 85734160 3172976
09:37:03 54044 8192000000 10079430352 10532007936 1108382624 11.917 98 22 43 0 1167356416 54545472 4428320
09:39:01 40022 8192000000 10096501392 10526736384 1136724384 12.185 100 28 56 0 1190408176 47378976 6304816
09:40:44 38182 8192000000 10151369680 10597027840 1146631248 12.278 100 28 56 2 1249317456 96944864 5741344
09:42:29 68721 8192000000 10057901344 10507042816 1105585968 11.891 97 11 22 0 1161757792 53932096 2239728
09:44:38 57274 8192000000 10075071872 10524139520 1055444288 11.413 93 28 56 1 1105053104 44306016 5302800
09:46:39 106578 8192000000 10061853472 10565763072 1097314400 11.812 97 26 52 2 1175742208 73410272 5017536
09:49:26 76424 8192000000 10017706240 10479423488 1057722416 11.435 94 17 33 1 1100921488 39763632 3435440
09:51:50 53269 8192000000 10089950464 10558521344 1118869232 12.016 100 8 16 1 1185103648 64455280 1779136
09:53:55 80069 8192000000 10067522608 10525978624 1109157632 11.924 98 15 30 1 1166979968 54266672 3555664
09:56:22 223003 8192000000 10096959856 10592460800 1039713056 11.262 92 12 24 0 1120645840 78575952 2356832
10:01:06 71312 8192000000 10077375600 10518228992 1075127200 11.601 95 10 20 0 1119988720 42487456 2374064
10:03:26 47133 8192000000 10111149584 10554404864 1132187184 12.142 100 28 56 6 1190914832 53203600 5524048
10:05:40 123526 8192000000 10075816960 10549927936 1125974608 12.083 99 14 28 0 1178453312 49541840 2936864
10:09:41 73076 8192000000 10043857104 10500874240 1105929984 11.894 97 9 18 0 1139027424 31191568 1905872
10:12:09 46288 8192000000 10074163536 10517385216 1126560096 12.089 99 18 36 2 1164867712 34698256 3609360
10:14:01 72784 8192000000 10082229328 10558238720 1106885760 11.903 98 23 46 0 1164546704 52971056 4689888
10:16:14 39359 8192000000 10091434336 10550558720 1137481840 12.192 100 25 50 3 1216868768 74462912 4924016
10:17:58 58696 8192000000 10142528240 10578526208 1126565872 12.089 98 16 32 0 1193668272 63681936 3420464
10:20:41 29548 8192000000 10094768176 10524749824 1133266928 12.152 99 42 84 1 1191499264 49029248 9203088
10:22:17 51166 8192000000 10071783232 10508185600 1103704720 11.873 97 23 45 2 1144646288 36497488 4444080
10:24:09 101827 8192000000 10085800576 10523054080 1128318064 12.106 100 22 45 1 1168865600 35276912 5270624
10:26:51 27374 8192000000 9619171376 10015731712 733282160 8.215 65 1 2 0 748299392 14981744 35488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-12-2020 10:29:25 to 27-12-2020 10:29:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 179 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 27-12-2020 10:29:41 to 27-12-2020 10:29:43.

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          237965
MON$OLDEST_ACTIVE               237966
MON$OLDEST_SNAPSHOT             237966
MON$NEXT_TRANSACTION            237966
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                       2106240
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             802
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2307 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2307 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2307 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.2307 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 ---
:59: 
:60: mscs-test-161.budzdorov.ru    Sun Dec 27 10:27:29 2020
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:62: 
:63: 
:64: mscs-test-161.budzdorov.ru    Sun Dec 27 10:27:30 2020
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Sun Dec 27 10:27:35 2020
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Sun Dec 27 10:27:35 2020
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:74: 
:75: 
:76: mscs-test-161.budzdorov.ru    Sun Dec 27 10:27:35 2020
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:78: 
:79: 
:80: mscs-test-161.budzdorov.ru    Sun Dec 27 10:27:35 2020
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:82: 
:83: 
:84: mscs-test-161.budzdorov.ru    Sun Dec 27 10:27:35 2020
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
:87: 
:88: mscs-test-161.budzdorov.ru    Sun Dec 27 10:27:35 2020
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
:91: 
:92: mscs-test-161.budzdorov.ru    Sun Dec 27 10:27:35 2020
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:94: 
:95: 
:96: mscs-test-161.budzdorov.ru    Sun Dec 27 10:27:35 2020
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:98: 
:99: 
:100: mscs-test-161.budzdorov.ru    Sun Dec 27 10:27:35 2020
:101:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:102: 
--- end of diff output ---

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

27.12.20 10:29:44. 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
2020-12-27 10:29:46 No severe PSQL-related problems occured
27.12.20 10:29:48. End of report.