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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 15750396 kB
MemAvailable 30677296 kB
Buffers 0 kB
Cached 15982968 kB
SwapCached 3716 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-10-20 13:37:09
DTS_END                         2021-10-20 13:37:09.2510
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/52580
TRN_ID                          228444
ATT_ID                          659
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: 9069.33 21980 1088319
customer order: creation 164.04 509 19685
customer order: refuse 13.88 364 1665
order to supplier: creation 43.79 1639 5255
order to supplier: removal 3.50 121643 420
invoice (draft): creation 42.68 3605 5122
invoice (draft): removal 53.13 904 6375
invoice accept: apply 39.49 20884 4739
invoice accept: cancel 48.44 831 5813
customer reserve: creation 3638.75 207 436650
customer reserve: removal 4731.53 72 567784
realization accept: apply 78.36 192 9403
realization accept: cancel 91.70 118 11004
payment from customer: creation 55.15 128 6618
payment from customer: removal 5.57 121 668
payment to supplier: creation 45.49 140 5459
payment to supplier: removal 3.19 117 383
service: total inventory turnovers 3.67 3606 440
service: total monetary turnovers 6.05 434 726
service: aggregate perf. data 0.88 15312 106
service: refresh index statistics 0.03 268769 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-10-2021 13:39:21 to 20-10-2021 13:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4575
TEST_TIME 2 4844
TEST_TIME 3 5381
TEST_TIME 4 6223
TEST_TIME 5 6424
TEST_TIME 6 6301
TEST_TIME 7 6008
TEST_TIME 8 6124
TEST_TIME 9 5977
TEST_TIME 10 5907
TEST_TIME 11 6365
TEST_TIME 12 6423
TEST_TIME 13 6562
TEST_TIME 14 6701
TEST_TIME 15 6886
TEST_TIME 16 7291
TEST_TIME 17 7325
TEST_TIME 18 8187
TEST_TIME 19 8497
TEST_TIME 20 8348
TEST_TIME 21 8378
TEST_TIME 22 8601
TEST_TIME 23 9318
TEST_TIME 24 9524
TEST_TIME 25 9710
TEST_TIME 26 10735
TEST_TIME 27 11296
TEST_TIME 28 11218
TEST_TIME 29 11110
TEST_TIME 30 11127
TEST_TIME 31 11142
TEST_TIME 32 11145
TEST_TIME 33 10969
TEST_TIME 34 10727
TEST_TIME 35 10480
TEST_TIME 36 10315
TEST_TIME 37 10361
TEST_TIME 38 10374
TEST_TIME 39 10288
TEST_TIME 40 10279
TEST_TIME 41 10220
TEST_TIME 42 10073
TEST_TIME 43 10016
TEST_TIME 44 10089
TEST_TIME 45 9990
TEST_TIME 46 9976
TEST_TIME 47 10004
TEST_TIME 48 9969
TEST_TIME 49 9990
TEST_TIME 50 10048
TEST_TIME 51 10237
TEST_TIME 52 10251
TEST_TIME 53 10239
TEST_TIME 54 10150
TEST_TIME 55 10269
TEST_TIME 56 10292
TEST_TIME 57 10302
TEST_TIME 58 10212
TEST_TIME 59 10095
TEST_TIME 60 10077
TEST_TIME 61 9984
TEST_TIME 62 9991
TEST_TIME 63 9965
TEST_TIME 64 9867
TEST_TIME 65 9837
TEST_TIME 66 9783
TEST_TIME 67 9747
TEST_TIME 68 9699
TEST_TIME 69 9702
TEST_TIME 70 9685
TEST_TIME 71 9696
TEST_TIME 72 9740
TEST_TIME 73 9806
TEST_TIME 74 9796
TEST_TIME 75 9870
TEST_TIME 76 9831
TEST_TIME 77 9770
TEST_TIME 78 9728
TEST_TIME 79 9631
TEST_TIME 80 9583
TEST_TIME 81 9544
TEST_TIME 82 9539
TEST_TIME 83 9518
TEST_TIME 84 9480
TEST_TIME 85 9456
TEST_TIME 86 9408
TEST_TIME 87 9423
TEST_TIME 88 9549
TEST_TIME 89 9550
TEST_TIME 90 9512
TEST_TIME 91 9582
TEST_TIME 92 9629
TEST_TIME 93 9589
TEST_TIME 94 9613
TEST_TIME 95 9536
TEST_TIME 96 9487
TEST_TIME 97 9506
TEST_TIME 98 9459
TEST_TIME 99 9421
TEST_TIME 100 9366
TEST_TIME 101 9348
TEST_TIME 102 9342
TEST_TIME 103 9405
TEST_TIME 104 9365
TEST_TIME 105 9326
TEST_TIME 106 9320
TEST_TIME 107 9377
TEST_TIME 108 9355
TEST_TIME 109 9334
TEST_TIME 110 9425
TEST_TIME 111 9438
TEST_TIME 112 9377
TEST_TIME 113 9339
TEST_TIME 114 9304
TEST_TIME 115 9289
TEST_TIME 116 9251
TEST_TIME 117 9196
TEST_TIME 118 9187
TEST_TIME 119 9149
TEST_TIME 120 9073

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-10-2021 13:39:25 to 20-10-2021 13:39:28.

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 1493897 1493897 0 0.00 0 24953 7 0 0
doc_list_biud 1493894 1493894 0 0.00 0 55205 34 0 0
sp_add_invoice_to_stock 4939 4739 200 4.04 31 253112 20885 0 200
sp_cancel_adding_invoice 5813 5813 0 0.00 5 54773 831 0 0
sp_cancel_client_order 1665 1665 0 0.00 5 27594 364 0 0
sp_cancel_customer_reserve 567784 567784 0 0.00 1 55207 72 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 2 7884 122 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 4400 117 0 0
sp_cancel_supplier_invoice 6391 6375 16 0.25 1 21774 905 0 16
sp_cancel_supplier_order 420 420 0 0.00 9 1139454 121643 0 0
sp_cancel_write_off 11004 11004 0 0.00 1 15475 118 0 0
sp_client_order 19685 19685 0 0.00 4 37033 509 0 0
sp_customer_reserve 436650 436650 0 0.00 0 114370 208 0 0
sp_fill_shopping_cart 30024 30024 0 0.00 1 25673 46 0 0
sp_fill_shopping_cart_clo_res 436640 436640 0 0.00 0 25579 21 0 0
sp_get_clo_for_invoice 4729 4729 0 0.00 0 21921 406 0 0
sp_kill_cost_storno 17868 17868 0 0.00 0 15474 61 0 0
sp_kill_qstorno_ret_qs2qd 1153367 1153367 0 0.00 0 55202 39 0 0
sp_kill_qty_storno 607193 607193 0 0.00 0 55204 82 0 0
sp_lock_selected_doc 19648 19648 0 0.00 0 7510 6 0 0
sp_make_cost_storno 26209 26209 0 0.00 0 21806 64 0 0
sp_multiply_rows_for_pdistr 26209 26209 0 0.00 0 9889 34 0 0
sp_multiply_rows_for_qdistr 19685 19685 0 0.00 0 31433 263 0 0
sp_pay_from_customer 6618 6618 0 0.00 4 14233 129 0 0
sp_pay_to_supplier 5459 5459 0 0.00 5 10584 140 0 0
sp_payment_common 12076 12076 0 0.00 1 9604 29 0 0
sp_qd_handle_on_cancel_clo 1665 1665 0 0.00 0 27584 321 0 0
sp_qd_handle_on_invoice_upd_sts 10542 10542 0 0.00 0 21008 338 0 0
sp_qd_handle_on_reserve_upd_sts 9403 9403 0 0.00 0 9614 46 0 0
sp_reserve_write_off 9403 9403 0 0.00 7 15779 193 0 0
sp_supplier_invoice 5122 5122 0 0.00 8 77069 3606 0 0
sp_supplier_order 5255 5255 0 0.00 6 44507 1640 0 0
srv_aggregate_perf_data 106 106 0 0.00 3106 150035 15312 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1996 162998 46209 0 0
srv_find_qd_qs_mism 1043999 1043999 0 0.00 0 24952 6 0 0
srv_make_invnt_saldo 440 440 0 0.00 52 90949 3607 0 0
srv_make_money_saldo 726 726 0 0.00 1 13934 435 0 0
srv_recalc_idx_stat 4 4 0 0.00 150729 389393 268769 0 0
t$perf-abend:sp_add_invoice_to_stock 200 200 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4739 4739 0 0.00 0 13096 150 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 0 981 58 0 0
t$perf-norm:sp_cancel_client_order 1665 1665 0 0.00 0 3235 16 0 0
t$perf-norm:sp_cancel_customer_reserve 638 638 0 0.00 0 2765 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 4068 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 3167 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 2136 20 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 15282 1325 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 5025 11 0 0
t$perf-norm:sp_client_order 19685 19685 0 0.00 0 6012 18 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 2557 8 0 0
t$perf-norm:sp_pay_from_customer 6618 6618 0 0.00 0 4095 11 0 0
t$perf-norm:sp_pay_to_supplier 5459 5459 0 0.00 0 4059 12 0 0
t$perf-norm:sp_reserve_write_off 9403 9403 0 0.00 0 12198 12 0 0
t$perf-norm:sp_supplier_invoice 5122 5122 0 0.00 0 6429 18 0 0
t$perf-norm:sp_supplier_order 5255 5255 0 0.00 0 8790 19 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 159 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 953 18 0 0
t$perf-norm:srv_make_invnt_saldo 440 440 0 0.00 0 499 6 0 0
t$perf-norm:srv_make_money_saldo 726 726 0 0.00 0 1541 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9858 9458 400 4.05 0 8301 25 0 400
v_all_customers 35764 35764 0 0.00 0 17 1 0 0
v_all_suppliers 28534 28534 0 0.00 0 76 1 0 0
v_all_wares 39362 39362 0 0.00 0 116 1 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 1118 9 0 0
v_cancel_client_order 3330 3330 0 0.00 0 4991 9 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 3497 9 0 0
v_cancel_customer_reserve 1276 1276 0 0.00 0 4876 11 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 2534 13 0 0
v_cancel_supplier_invoice 858 826 32 3.72 0 1962 18 0 32
v_cancel_supplier_order 840 840 0 0.00 0 1837 13 0 0
v_cancel_write_off 1880 1880 0 0.00 0 3349 20 0 0
v_max_id_clo_ord 5235 5235 0 0.00 0 1263 2 0 0
v_max_id_clo_res 967 967 0 0.00 0 4166 14 0 0
v_max_id_ord_sup 5107 5107 0 0.00 0 10962 9 0 0
v_max_non_paid_invoice 1533 1533 0 0.00 0 990 3 0 0
v_max_non_paid_realizn 3492 3492 0 0.00 0 3554 5 0 0
v_min_id_clo_ord 5235 5235 0 0.00 0 25657 13 0 0
v_min_id_clo_res 967 967 0 0.00 0 11239 31 0 0
v_min_id_ord_sup 5107 5107 0 0.00 0 8377 7 0 0
v_min_non_paid_invoice 5458 5458 0 0.00 0 6663 18 0 0
v_min_non_paid_realizn 6618 6618 0 0.00 0 9589 24 0 0
v_our_firm 9848 9848 0 0.00 0 15 1 0 0
v_reserve_write_off 18805 18805 0 0.00 0 5075 11 0 0
x_lock_depdocs_on_canc_invoice 11775 11775 0 0.00 0 38445 125 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 19992 390 0 0
x_make_qstorno_1000_1200 5255 5255 0 0.00 2 44477 1554 0 0
x_make_qstorno_1200_2000 5122 5122 0 0.00 4 77063 3434 0 0
x_make_qstorno_2100_3300 436650 436650 0 0.00 0 114369 185 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-10-2021 13:39:29 to 20-10-2021 13:39:33.

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
11:07:12 3 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 33221 8192000000 9729398544 10027257856 799174320 8.888 100 84 168 0 827396896 7835680 20386896
11:09:51 24421 8192000000 9776368560 10084507648 850408800 9.404 100 89 179 0 883666608 12074144 21183664
11:11:20 18967 8192000000 9812250384 10130698240 872494800 9.625 100 84 168 0 916980608 24888112 19597696
11:12:40 14848 8192000000 9875083520 10208645120 922201008 10.118 100 79 158 1 980306912 40682080 17423824
11:13:57 8260 8192000000 9927478272 10269937664 952620272 10.417 100 88 176 0 1034895504 63158176 19117056
11:15:09 7709 8192000000 9958943584 10311573504 993372928 10.814 100 74 146 2 1068633536 60179296 15081312
11:16:19 10297 8192000000 9985502160 10344468480 1023276816 11.104 100 80 156 1 1095839856 57618496 14944544
11:17:30 1594 8192000000 10030195648 10396340224 1051504048 11.375 100 91 179 2 1145260192 74806576 18949568
11:18:33 9142 8192000000 10001893184 10400743424 1062025488 11.476 100 44 88 0 1139711312 69033952 8651872
11:19:45 4897 8192000000 9989011040 10377830400 1071000864 11.562 100 49 98 1 1104533072 23328592 10203616
11:20:50 19704 8192000000 9996671776 10379915264 1078045120 11.629 100 68 135 1 1102263552 9263696 14954736
11:22:10 6541 8192000000 9991410080 10384707584 1079727216 11.645 100 37 75 1 1106832784 18903664 8201904
11:23:22 4209 8192000000 10002723472 10393550848 1090712816 11.749 100 60 123 0 1112915824 9293904 12909104
11:24:26 36234 8192000000 10003914160 10421477376 1089711696 11.740 100 29 58 1 1116493872 20511360 6270816
11:26:06 5524 8192000000 10001276400 10403258368 1074734992 11.597 98 43 85 2 1104744416 19619216 10390208
11:27:12 7258 8192000000 10014875664 10420543488 1102174352 11.858 100 28 56 2 1129914096 21594208 6145536
11:28:25 14189 8192000000 9964843248 10355249152 980561200 10.690 91 29 58 1 1014977536 28519632 5896704
11:29:44 23203 8192000000 9964739600 10363318272 990858816 10.790 93 25 48 2 1019008400 23460192 4689392
11:31:10 5595 8192000000 9937396624 10320834560 1005095472 10.928 98 30 60 0 1044436688 32895120 6446096
11:32:17 7075 8192000000 9940717792 10329182208 1020466016 11.077 99 47 94 1 1045122128 13913008 10743104
11:33:25 11478 8192000000 9973476000 10362806272 1036579712 11.232 98 37 72 1 1075341424 31460192 7301520
11:34:41 16119 8192000000 9980703744 10385399808 1052165200 11.381 100 28 56 1 1099173744 41343280 5665264
11:35:58 5441 8192000000 9979681776 10370527232 1062850512 11.484 100 56 113 1 1091029744 15741792 12437440
11:37:03 6732 8192000000 10001745056 10389151744 1084637616 11.692 100 38 73 3 1112893808 18758608 9497584
11:38:23 2975 8192000000 10039114880 10427604992 1107043936 11.904 100 67 134 1 1154358976 33727600 13587440
11:39:34 81665 8192000000 10069883808 10484047872 1114913552 11.979 100 37 74 0 1173842880 50705040 8224288
11:42:01 12877 8192000000 10097360640 10519560192 1133926272 12.158 100 41 82 0 1208344192 65624880 8793040
11:43:23 9891 8192000000 10153574528 10548756480 1149153312 12.302 100 66 131 1 1253838112 89794544 14890256
11:44:42 9898 8192000000 10190907056 10604699648 1151834720 12.327 100 44 88 1 1283887360 122796224 9256416
11:45:59 3857 8192000000 10183519360 10588770304 1158513856 12.389 99 55 109 1 1286478208 116536432 11427920
11:47:08 17639 8192000000 10258485504 10665619456 1172971936 12.525 100 62 123 0 1357989456 171537008 13480512
11:48:40 1995 8192000000 10291808832 10696220672 1180118688 12.591 100 74 148 1 1397590656 200877824 16594144
11:49:51 2442 8192000000 10333393472 10742886400 1182789648 12.616 100 70 139 0 1429133984 230512624 15831712
11:50:59 2222 8192000000 10375899632 10775093248 1173665888 12.531 99 85 166 0 1466910880 274630528 18614464
11:52:06 3228 8192000000 10417482208 10825818112 1189018128 12.674 100 73 145 1 1513788512 307802240 16968144
11:53:13 2319 8192000000 10463040608 10869190656 1168738560 12.485 99 73 144 1 1548785648 362618320 17428768
11:54:25 3077 8192000000 10465601824 10879602688 1175629632 12.549 100 76 153 3 1567526512 373054640 18842240
11:55:35 4200 8192000000 10461933584 10895368192 1181910976 12.608 100 72 143 0 1575881216 376299360 17670880
11:56:53 12708 8192000000 10430310960 10869280768 1182152288 12.610 100 47 94 0 1542224416 347952144 12119984
11:58:17 6512 8192000000 10491938912 10920779776 1174171664 12.536 100 48 96 0 1589574080 402773680 12628736
11:59:43 9744 8192000000 10479928480 10908721152 1161024560 12.413 100 36 71 0 1579305376 408257232 10023584
12:01:09 15049 8192000000 10369445744 10841268224 1144722544 12.260 100 34 66 0 1464744144 310675248 9346352
12:02:26 13437 8192000000 10376486912 10856239104 1124948400 12.074 100 25 50 1 1485790432 353831360 7010672
12:04:10 47155 8192000000 10077610816 10555383808 1112754528 11.958 99 7 15 0 1167010288 52614256 1641504
12:06:01 15525 8192000000 10106673280 10540830720 1130784096 12.129 100 30 58 1 1185475232 48427872 6263264
12:07:19 76246 8192000000 10087791440 10502033408 1079572000 11.643 95 36 68 2 1144712640 57248384 7892256
12:09:36 141780 8192000000 10103481216 10541600768 1119294224 12.020 98 28 56 0 1158875808 34009360 5572224
12:12:58 80734 8192000000 10095009376 10542338048 1106698784 11.901 97 22 44 0 1182098656 70728256 4671616
12:15:20 45748 8192000000 10076673360 10504982528 1106228208 11.897 98 47 92 1 1200288032 83858704 10201120
12:17:11 32994 8192000000 10101131648 10541031424 1122344528 12.049 100 15 30 0 1205420624 79784736 3291360
12:18:44 162998 8192000000 10122115648 10595377152 1095104752 11.791 96 22 44 1 1205274944 105275024 4895168
12:22:36 65317 8192000000 10136555040 10641731584 1134828080 12.167 100 24 49 0 1265342976 125142496 5372400
12:24:45 45509 8192000000 10127724864 10570518528 1143218704 12.246 100 20 39 3 1267783136 119939632 4624800
12:26:32 62749 8192000000 10150087344 10647646208 1134301232 12.162 99 15 30 0 1289729552 151682448 3745872
12:28:35 51326 8192000000 10100782656 10562142208 1140824960 12.223 100 16 33 0 1211901568 67602144 3474464
12:30:31 26511 8192000000 10081455776 10506727424 1147399440 12.285 100 25 49 1 1186460896 34393680 4667776
12:32:04 37169 8192000000 10095274320 10561941504 1125619936 12.080 98 23 46 0 1200453648 70151312 4682400
12:33:42 55047 8192000000 10096513552 10542657536 1117747072 12.006 97 14 28 0 1164104656 43331296 3026288
12:35:37 33462 8192000000 10091221312 10523361280 1109999888 11.932 98 54 108 2 1162933504 42120800 10812816
12:37:35 78291 8192000000 10094207648 10559721472 1106228992 11.897 97 27 54 0 1168880240 57150800 5500448
12:39:55 46203 8192000000 10041190928 10512244736 1105984672 11.894 98 14 28 0 1159675088 51051584 2638832
12:41:42 23629 8192000000 10049590960 10518634496 1111686144 11.948 99 3 6 0 1161037376 49003504 347728
12:43:05 42835 8192000000 10047624928 10505482240 1094372784 11.784 97 9 18 0 1121127984 25183264 1571936
12:45:06 33623 8192000000 10110861984 10553978880 1113791264 11.968 97 31 62 1 1190889024 70193008 6904752
12:46:43 74354 8192000000 10144951808 10604040192 1127040480 12.093 98 25 50 0 1210468000 77888032 5539488
12:48:57 34655 8192000000 10070273728 10539544576 1107610704 11.910 97 27 54 0 1183928080 70106848 6210528
12:50:33 102179 8192000000 10101405376 10586562560 1132819632 12.148 99 12 24 0 1230140064 94788800 2531632
12:53:16 26276 8192000000 10089812752 10508976128 1118116704 12.009 98 47 94 0 1146693232 19030720 9545808
12:54:42 104039 8192000000 10058290672 10505662464 1118273696 12.011 99 38 76 1 1182391680 55958976 8159008
12:57:31 44180 8192000000 10082568368 10537512960 1132117664 12.141 100 8 15 1 1180569280 46303536 2148080
12:59:26 46123 8192000000 10063424320 10582872064 1120380352 12.031 99 5 10 0 1162348112 41283136 684624
13:01:15 69753 8192000000 10126231456 10591305728 1157280528 12.378 100 21 42 2 1241317056 79405584 4630944
13:03:31 47566 8192000000 10193360032 10655703040 1141319456 12.228 99 21 42 0 1267981312 121840944 4820912
13:05:48 55905 8192000000 10076981968 10581626880 1115227600 11.982 98 11 21 1 1181627152 64114192 2285360
13:07:44 43883 8192000000 10128591328 10588549120 1117644432 12.005 97 39 78 1 1195155120 69541792 7968896
13:09:38 40538 8192000000 10082768400 10529202176 1136834896 12.186 100 30 60 0 1165605104 22817312 5952896
13:11:23 136656 8192000000 10101261344 10529894400 1107679104 11.910 97 35 70 1 1174676400 59956880 7040416
13:14:42 63308 8192000000 10054664896 10542587904 1116737392 11.996 100 12 24 0 1159832128 40678448 2416288
13:16:46 64097 8192000000 10083860000 10513215488 1115026240 11.980 99 25 46 1 1148388368 28503408 4858720
13:18:50 47555 8192000000 10114752112 10580881408 1131386224 12.134 99 30 60 1 1214718192 76932464 6399504
13:20:46 61422 8192000000 10061034768 10500169728 1117274256 12.001 99 10 20 0 1148497280 29120752 2102272
13:22:53 65422 8192000000 10096424560 10550685696 1127637904 12.099 99 19 37 1 1208792976 76653248 4501824
13:25:05 72168 8192000000 10083150592 10516811776 1088121376 11.725 96 24 48 2 1131087936 37930240 5036320
13:27:26 116793 8192000000 10069632784 10544410624 1084015552 11.686 96 8 15 0 1122639056 37377472 1246032
13:30:29 81683 8192000000 10030761648 10470002688 1089784144 11.741 96 23 47 0 1117867824 22978160 5105520
13:33:27 64723 8192000000 10042912096 10504052736 1102878416 11.865 98 13 26 1 1135682928 29921936 2882576
13:35:32 66699 8192000000 10031074592 10496241664 1091101440 11.753 97 26 51 1 1128867712 31346400 6419872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-10-2021 13:39:35 to 20-10-2021 13:39:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 200 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-10-2021 13:39:51 to 20-10-2021 13:39:53.

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          227282
MON$OLDEST_ACTIVE               228820
MON$OLDEST_SNAPSHOT             228820
MON$NEXT_TRANSACTION            228820
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                       2052384
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.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:400: 
:401: mscs-test-161.budzdorov.ru    Wed Oct 20 13:37:26 2021
:402:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:403: 
:404: 
:405: mscs-test-161.budzdorov.ru    Wed Oct 20 13:37:27 2021
:406:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:407: 
:408: 
:409: mscs-test-161.budzdorov.ru    Wed Oct 20 13:37:31 2021
:410:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:411: 
--- end of diff output ---

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

20.10.21 13:39:54. 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-10-20 13:39:56 No severe PSQL-related problems occured
20.10.21 13:39:58. End of report.