Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.08.2021 01: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 15937636 kB
MemAvailable 30946004 kB
Buffers 0 kB
Cached 15761024 kB
SwapCached 1324 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-08-29 01:37:47
DTS_END                         2021-08-29 01:37:47.2600
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(57:17)
IP                              ::1/44426
TRN_ID                          241937
ATT_ID                          596
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: 9842.24 21008 1181069
customer order: creation 176.22 336 21146
customer order: refuse 14.85 365 1782
order to supplier: creation 46.98 1349 5637
order to supplier: removal 3.78 110138 454
invoice (draft): creation 47.46 3137 5695
invoice (draft): removal 57.15 832 6858
invoice accept: apply 43.53 18558 5223
invoice accept: cancel 51.28 811 6153
customer reserve: creation 4035.87 185 484304
customer reserve: removal 5057.25 64 606870
realization accept: apply 84.85 170 10182
realization accept: cancel 91.33 118 10959
payment from customer: creation 59.27 103 7112
payment from customer: removal 5.88 96 706
payment to supplier: creation 50.18 112 6021
payment to supplier: removal 3.40 70 408
service: total inventory turnovers 5.23 1150 627
service: total monetary turnovers 6.94 24 833
service: aggregate perf. data 0.79 24877 95
service: refresh index statistics 0.03 257666 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-08-2021 01:39:47 to 29-08-2021 01:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5163
TEST_TIME 2 5509
TEST_TIME 3 5254
TEST_TIME 4 4807
TEST_TIME 5 4484
TEST_TIME 6 4814
TEST_TIME 7 4810
TEST_TIME 8 5103
TEST_TIME 9 5412
TEST_TIME 10 5420
TEST_TIME 11 6171
TEST_TIME 12 6055
TEST_TIME 13 6264
TEST_TIME 14 6200
TEST_TIME 15 6207
TEST_TIME 16 6288
TEST_TIME 17 6906
TEST_TIME 18 6942
TEST_TIME 19 7234
TEST_TIME 20 7702
TEST_TIME 21 8110
TEST_TIME 22 8206
TEST_TIME 23 8294
TEST_TIME 24 8458
TEST_TIME 25 8321
TEST_TIME 26 8631
TEST_TIME 27 9366
TEST_TIME 28 9408
TEST_TIME 29 10414
TEST_TIME 30 10995
TEST_TIME 31 10983
TEST_TIME 32 11068
TEST_TIME 33 10956
TEST_TIME 34 11143
TEST_TIME 35 11215
TEST_TIME 36 11279
TEST_TIME 37 11238
TEST_TIME 38 11048
TEST_TIME 39 10946
TEST_TIME 40 10818
TEST_TIME 41 10752
TEST_TIME 42 10548
TEST_TIME 43 10655
TEST_TIME 44 10630
TEST_TIME 45 10575
TEST_TIME 46 10392
TEST_TIME 47 10248
TEST_TIME 48 10398
TEST_TIME 49 10322
TEST_TIME 50 10438
TEST_TIME 51 10430
TEST_TIME 52 10307
TEST_TIME 53 10297
TEST_TIME 54 10270
TEST_TIME 55 10229
TEST_TIME 56 10120
TEST_TIME 57 10118
TEST_TIME 58 10096
TEST_TIME 59 10075
TEST_TIME 60 10056
TEST_TIME 61 10032
TEST_TIME 62 10161
TEST_TIME 63 10108
TEST_TIME 64 10176
TEST_TIME 65 10163
TEST_TIME 66 10263
TEST_TIME 67 10276
TEST_TIME 68 10300
TEST_TIME 69 10227
TEST_TIME 70 10267
TEST_TIME 71 10230
TEST_TIME 72 10236
TEST_TIME 73 10110
TEST_TIME 74 10099
TEST_TIME 75 10123
TEST_TIME 76 10053
TEST_TIME 77 10005
TEST_TIME 78 10008
TEST_TIME 79 9956
TEST_TIME 80 9925
TEST_TIME 81 9910
TEST_TIME 82 9970
TEST_TIME 83 9930
TEST_TIME 84 9927
TEST_TIME 85 9901
TEST_TIME 86 9911
TEST_TIME 87 9984
TEST_TIME 88 9989
TEST_TIME 89 9993
TEST_TIME 90 10024
TEST_TIME 91 10048
TEST_TIME 92 10068
TEST_TIME 93 10092
TEST_TIME 94 10141
TEST_TIME 95 10173
TEST_TIME 96 10120
TEST_TIME 97 10050
TEST_TIME 98 10029
TEST_TIME 99 9980
TEST_TIME 100 9967
TEST_TIME 101 9970
TEST_TIME 102 9967
TEST_TIME 103 9916
TEST_TIME 104 9878
TEST_TIME 105 9809
TEST_TIME 106 9846
TEST_TIME 107 9896
TEST_TIME 108 9871
TEST_TIME 109 9865
TEST_TIME 110 9867
TEST_TIME 111 9938
TEST_TIME 112 9939
TEST_TIME 113 9899
TEST_TIME 114 9924
TEST_TIME 115 9971
TEST_TIME 116 9947
TEST_TIME 117 9935
TEST_TIME 118 9937
TEST_TIME 119 9895
TEST_TIME 120 9856

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-08-2021 01:39:51 to 29-08-2021 01:39:54.

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 1629006 1629006 0 0.00 0 26351 6 0 0
doc_list_biud 1629006 1629006 0 0.00 0 34046 30 0 0
sp_add_invoice_to_stock 5310 5223 87 1.63 50 230311 18559 0 87
sp_cancel_adding_invoice 6153 6153 0 0.00 4 58366 811 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 8746 365 0 0
sp_cancel_customer_reserve 606870 606870 0 0.00 1 24886 65 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 3 12992 96 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 2840 71 0 0
sp_cancel_supplier_invoice 6867 6858 9 0.13 1 23960 832 0 9
sp_cancel_supplier_order 454 454 0 0.00 9 1146225 110138 0 0
sp_cancel_write_off 10959 10959 0 0.00 1 24753 118 0 0
sp_client_order 21146 21146 0 0.00 3 26509 337 0 0
sp_customer_reserve 484304 484304 0 0.00 0 119224 185 0 0
sp_fill_shopping_cart 32478 32478 0 0.00 1 13504 36 0 0
sp_fill_shopping_cart_clo_res 484282 484282 0 0.00 0 15175 12 0 0
sp_get_clo_for_invoice 5196 5196 0 0.00 0 21949 227 0 0
sp_kill_cost_storno 18226 18226 0 0.00 0 8877 56 0 0
sp_kill_qstorno_ret_qs2qd 1232011 1232011 0 0.00 0 24713 35 0 0
sp_kill_qty_storno 648452 648452 0 0.00 0 34043 75 0 0
sp_lock_selected_doc 21331 21331 0 0.00 0 7726 5 0 0
sp_make_cost_storno 28509 28509 0 0.00 0 15746 46 0 0
sp_multiply_rows_for_pdistr 28509 28509 0 0.00 0 9440 28 0 0
sp_multiply_rows_for_qdistr 21146 21146 0 0.00 0 16466 147 0 0
sp_pay_from_customer 7112 7112 0 0.00 4 17928 103 0 0
sp_pay_to_supplier 6021 6021 0 0.00 5 15781 112 0 0
sp_payment_common 13133 13133 0 0.00 1 17907 27 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 8733 329 0 0
sp_qd_handle_on_invoice_upd_sts 11347 11347 0 0.00 0 34040 342 0 0
sp_qd_handle_on_reserve_upd_sts 10182 10182 0 0.00 0 15089 58 0 0
sp_reserve_write_off 10182 10182 0 0.00 7 15298 170 0 0
sp_supplier_invoice 5695 5695 0 0.00 6 73565 3137 0 0
sp_supplier_order 5637 5637 0 0.00 5 68171 1350 0 0
srv_aggregate_perf_data 95 95 0 0.00 2607 450987 24877 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1864 159264 42810 0 0
srv_find_qd_qs_mism 1131616 1131616 0 0.00 0 26350 6 0 0
srv_make_invnt_saldo 627 627 0 0.00 26 93244 1151 0 0
srv_make_money_saldo 833 833 0 0.00 0 1406 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 109044 471091 257667 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5224 5224 0 0.00 0 17151 155 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 0 5288 94 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 2979 13 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 1924 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 382 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 457 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 2298 15 0 0
t$perf-norm:sp_cancel_supplier_order 454 454 0 0.00 0 37504 1689 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 5432 13 0 0
t$perf-norm:sp_client_order 21146 21146 0 0.00 0 7117 18 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 3059 7 0 0
t$perf-norm:sp_pay_from_customer 7112 7112 0 0.00 0 3517 6 0 0
t$perf-norm:sp_pay_to_supplier 6021 6021 0 0.00 0 4574 10 0 0
t$perf-norm:sp_reserve_write_off 10182 10182 0 0.00 0 8534 14 0 0
t$perf-norm:sp_supplier_invoice 5695 5695 0 0.00 0 6645 14 0 0
t$perf-norm:sp_supplier_order 5637 5637 0 0.00 0 3839 16 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 260 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 359 6 0 0
t$perf-norm:srv_make_invnt_saldo 627 627 0 0.00 0 5480 33 0 0
t$perf-norm:srv_make_money_saldo 833 833 0 0.00 0 1360 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10562 10388 174 1.64 0 8019 16 0 174
v_all_customers 38890 38890 0 0.00 0 43 1 0 0
v_all_suppliers 31690 31690 0 0.00 0 20 1 0 0
v_all_wares 42292 42292 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 977 8 0 0
v_cancel_client_order 3564 3564 0 0.00 0 5469 6 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 5744 16 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 1582 5 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 306 3 0 0
v_cancel_supplier_invoice 908 890 18 1.98 0 3589 23 0 18
v_cancel_supplier_order 908 908 0 0.00 0 1708 5 0 0
v_cancel_write_off 2024 2024 0 0.00 0 7075 17 0 0
v_max_id_clo_ord 5637 5637 0 0.00 0 248 1 0 0
v_max_id_clo_res 993 993 0 0.00 0 1226 6 0 0
v_max_id_ord_sup 5695 5695 0 0.00 0 3851 5 0 0
v_max_non_paid_invoice 1508 1508 0 0.00 0 702 2 0 0
v_max_non_paid_realizn 3598 3598 0 0.00 0 3274 4 0 0
v_min_id_clo_ord 5637 5637 0 0.00 0 4621 11 0 0
v_min_id_clo_res 993 993 0 0.00 0 5871 16 0 0
v_min_id_ord_sup 5695 5695 0 0.00 0 11074 12 0 0
v_min_non_paid_invoice 6021 6021 0 0.00 0 8399 14 0 0
v_min_non_paid_realizn 7112 7112 0 0.00 0 6210 18 0 0
v_our_firm 10430 10430 0 0.00 0 16 1 0 0
v_reserve_write_off 20362 20362 0 0.00 0 4895 6 0 0
x_lock_depdocs_on_canc_invoice 12566 12566 0 0.00 0 14694 117 0 0
x_lock_depdocs_on_canc_sup_ord 454 454 0 0.00 0 6584 321 0 0
x_make_qstorno_1000_1200 5637 5637 0 0.00 1 68065 1261 0 0
x_make_qstorno_1200_2000 5695 5695 0 0.00 3 72903 3019 0 0
x_make_qstorno_2100_3300 484304 484304 0 0.00 0 119222 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-08-2021 01:39:55 to 29-08-2021 01:39:59.

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
23:07:51 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:52 36512 8192000000 10389417632 10714783744 1446078144 15.003 100 90 181 0 1481087744 6331168 28678432
23:10:32 22814 8192000000 10465764176 10799308800 1517123824 15.625 100 89 178 1 1554771408 8912608 28734976
23:12:01 19493 8192000000 10515214496 10858348544 1571120048 16.092 100 87 174 0 1608421360 10421840 26879472
23:13:24 13988 8192000000 10580947504 10936860672 1632120912 16.613 100 82 164 0 1667555248 10860704 24573632
23:14:42 8802 8192000000 10650296448 11017826304 1708664976 17.258 100 86 170 3 1746561120 13495968 24400176
23:15:52 4752 8192000000 10709221088 11089313792 1769105488 17.760 100 77 154 0 1802665792 12168112 21392192
23:16:59 2470 8192000000 10748214048 11132436480 1814864704 18.136 100 88 174 0 1853533584 13881616 24787264
23:18:04 2058 8192000000 10801513344 11186204672 1849703568 18.420 100 94 190 1 1890560256 13762688 27094000
23:19:08 1408 8192000000 10812723632 11201036288 1869653088 18.581 100 92 182 0 1908643216 12876144 26113984
23:20:10 1494 8192000000 10816854016 11218505728 1881645872 18.678 100 77 154 0 1916404096 11641552 23116672
23:21:15 9891 8192000000 10800677568 11213271040 1878300640 18.651 100 21 41 3 1890739744 6411040 6028064
23:22:27 26205 8192000000 10809254912 11230412800 1886717232 18.719 100 46 91 3 1906117312 6376240 13023840
23:23:53 6350 8192000000 10795402688 11231424512 1889904016 18.745 100 31 62 0 1905972800 7378272 8690512
23:25:02 7118 8192000000 10820228272 11246841856 1901347728 18.837 100 21 41 0 1912457856 5202896 5907232
23:26:12 60917 8192000000 10847683376 11256528896 1891147360 18.755 99 39 77 0 1909197088 6695056 11354672
23:28:14 5426 8192000000 10789602656 11216302080 1867355952 18.563 99 26 53 4 1884707952 10485584 6866416
23:29:22 7573 8192000000 10770953984 11196751872 1858012352 18.487 100 25 50 2 1871570160 6357776 7200032
23:30:30 9090 8192000000 10740072528 11149512704 1788391760 17.919 98 24 49 2 1801125024 5347504 7385760
23:31:45 25500 8192000000 10687605104 11101306880 1705855120 17.234 94 21 43 0 1715951968 4339968 5756880
23:33:10 7240 8192000000 10724568976 11146096640 1811587840 18.109 100 33 67 3 1828044992 5905760 10551392
23:34:18 8761 8192000000 10778292592 11197571072 1840828336 18.348 99 36 70 2 1856675184 5236272 10610576
23:35:29 2473 8192000000 10795702304 11206488064 1870235136 18.586 100 66 132 3 1894412320 5119312 19057872
23:36:31 4879 8192000000 10830662752 11224600576 1879793616 18.663 100 74 146 7 1906834432 5799424 21241392
23:37:36 4187 8192000000 10842371952 11247345664 1904130672 18.860 100 54 111 2 1923918720 4353344 15434704
23:38:45 4484 8192000000 10875050848 11289436160 1933718640 19.097 100 56 110 0 1958258528 9055568 15484320
23:39:52 26270 8192000000 10881037888 11308871680 1955314304 19.269 100 53 106 1 1981152832 11112800 14725728
23:41:28 20326 8192000000 10879186672 11303510016 1940512928 19.151 99 47 92 1 1968177584 14422464 13242192
23:43:01 5586 8192000000 10909551296 11334647808 1972274592 19.403 100 47 94 2 2003189552 17688544 13226416
23:44:10 21038 8192000000 10930674096 11371552768 1990520144 19.548 100 48 94 4 2025554480 20714000 14320336
23:45:40 19701 8192000000 10956984608 11389935616 1998245472 19.609 100 42 84 1 2036694480 25633712 12815296
23:47:04 5437 8192000000 10974962656 11397910528 1994637952 19.580 99 69 138 1 2047908736 32179456 21091328
23:48:14 1860 8192000000 10972087264 11387437056 1993766576 19.574 99 90 180 2 2054516416 33751968 26997872
23:49:23 2555 8192000000 10919848240 11347660800 1955141264 19.267 98 56 112 2 2007219536 34663248 17415024
23:50:36 7009 8192000000 10943407488 11374817280 1969190960 19.379 99 52 103 0 2018385760 33462960 15731840
23:51:50 5037 8192000000 10984481408 11410026496 2009069488 19.694 100 77 154 3 2076017248 43783424 23164336
23:53:06 9750 8192000000 10990962640 11422121984 1990888896 19.551 99 52 104 1 2059383808 52387680 16107232
23:54:31 3411 8192000000 11000792768 11424911360 2016558560 19.753 100 80 160 1 2088835504 47796064 24480880
23:55:43 3956 8192000000 11006639664 11420749824 2004152848 19.655 100 83 167 1 2083858048 54275152 25430048
23:56:54 4831 8192000000 11000525168 11419881472 1996940368 19.599 100 82 164 1 2079515328 55579520 26995440
23:58:04 6812 8192000000 10996254272 11423637504 1987475632 19.524 99 57 114 0 2063839040 57427488 18935920
23:59:14 2275 8192000000 11010248816 11433476096 2013436496 19.729 100 82 164 4 2092634128 53630320 25567312
00:00:22 3544 8192000000 11018385872 11456745472 2028013072 19.843 100 57 114 0 2103810352 56859088 18938192
00:01:31 13482 8192000000 10996375264 11442270208 1993382048 19.571 99 40 81 1 2068674720 61557584 13735088
00:02:47 5859 8192000000 11014808448 11454263296 2005838944 19.669 100 41 80 2 2089741456 69326288 14576224
00:04:27 33050 8192000000 10971404560 11429253120 1994097360 19.576 100 29 56 1 2063156320 58707280 10351680
00:06:15 25929 8192000000 10924008256 11410956288 1957691328 19.288 100 22 43 1 2025525152 59301760 8532064
00:07:42 87316 8192000000 10901633312 11374968832 1953631872 19.255 99 11 22 1 1980205696 23792032 2781792
00:10:26 23449 8192000000 10923450832 11381825536 1982026080 19.481 100 22 44 1 2000990208 12809968 6154160
00:12:10 159264 8192000000 10906393360 11376496640 1888308816 18.732 95 4 8 0 1908629440 19984848 335776
00:15:49 55464 8192000000 10894819824 11359641600 1927110704 19.044 97 45 89 1 1948169200 7155488 13903008
00:17:58 92126 8192000000 10920659648 11371823104 1917434544 18.966 97 22 44 0 1936655696 12729136 6492016
00:20:32 60386 8192000000 10901842288 11365281792 1955925232 19.274 99 22 45 1 1978320784 16233616 6161936
00:22:38 75235 8192000000 10912850736 11366854656 1938540000 19.135 98 25 50 1 1954529712 8271312 7718400
00:24:58 7192 8192000000 10942400368 11386163200 1985754304 19.510 100 75 150 0 2025597440 17291920 22551216
00:26:08 32757 8192000000 10934998016 11387764736 1972155088 19.403 99 25 49 1 1991382304 12289248 6937968
00:27:43 38569 8192000000 10879606480 11362512896 1931013424 19.075 98 24 48 0 1948247504 10765632 6468448
00:30:18 24607 8192000000 10874770208 11353358336 1940341360 19.149 99 10 20 0 1953188384 9992352 2854672
00:31:43 85429 8192000000 10882084624 11362451456 1934439872 19.102 98 13 26 0 1951619072 13403232 3775968
00:34:36 59396 8192000000 10858821152 11343040512 1920855888 18.994 98 11 22 1 1937313904 13639120 2818896
00:36:41 35576 8192000000 10894544896 11352879104 1925609216 19.032 98 17 34 2 1937154448 6897024 4648208
00:38:23 56283 8192000000 10847006672 11327520768 1857446912 18.483 95 15 30 1 1873097552 11850560 3800080
00:40:22 34293 8192000000 10872824080 11331092480 1904947040 18.866 98 24 48 1 1927886976 16243648 6696288
00:41:59 70041 8192000000 10850438800 11321081856 1865007296 18.544 96 17 34 0 1891010640 20890096 5113248
00:44:16 106372 8192000000 10884373056 11339165696 1827837296 18.242 93 20 40 0 1844822128 11628832 5356000
00:47:06 21382 8192000000 10879889088 11335319552 1913076800 18.931 98 40 80 2 1935438128 10826480 11534848
00:48:28 85451 8192000000 10878151440 11340029952 1888983520 18.738 96 22 44 1 1905582192 11015920 5582752
00:50:56 83727 8192000000 10887186304 11355324416 1926164960 19.036 98 24 48 1 1942926416 10093632 6667824
00:53:44 28986 8192000000 10879897616 11345588224 1934486544 19.103 99 31 60 1 1956079360 13381904 8210912
00:55:14 37879 8192000000 10866339808 11346255872 1907254192 18.885 98 7 14 0 1927350416 18150032 1946192
00:56:53 54953 8192000000 10845737920 11309993984 1830846320 18.266 94 17 33 1 1851211168 15702896 4661952
00:58:48 54599 8192000000 10841079040 11329527808 1920734896 18.993 100 15 30 1 1938460048 12920336 4804816
01:00:43 21684 8192000000 10876823824 11341758464 1946584752 19.199 100 38 77 2 1967017136 9292480 11139904
01:02:06 60079 8192000000 10883387520 11354091520 1900590384 18.831 97 23 45 0 1916649424 10067792 5991248
01:04:09 62188 8192000000 10861756992 11342028800 1914558416 18.943 99 15 30 1 1932725232 14080064 4086752
01:06:12 49448 8192000000 10888508528 11354435584 1935725888 19.113 99 24 47 4 1952748240 10570320 6452032
01:08:02 61851 8192000000 10924327936 11380105216 1960182224 19.307 98 35 71 1 1976932704 8103248 8647232
01:10:05 65149 8192000000 10938191680 11409743872 1998437424 19.610 100 27 54 1 2018573328 12628192 7507712
01:12:12 89179 8192000000 10905609408 11390660608 1968091328 19.370 99 19 37 2 1983334112 10855600 4387184
01:14:42 57045 8192000000 10900739360 11364679680 1892780592 18.768 95 19 35 6 1904694160 6808256 5105312
01:16:39 29673 8192000000 10919579488 11384803328 1981055104 19.473 100 38 76 2 2007497984 16072464 10370416
01:18:09 43495 8192000000 10884051056 11359739904 1909584464 18.903 97 14 29 1 1924532736 10853952 4094320
01:20:27 40271 8192000000 10879950784 11349307392 1930265824 19.069 98 20 40 3 1949411712 13367920 5777968
01:22:17 77256 8192000000 10870253248 11358248960 1915384672 18.950 98 18 36 1 1940111712 19133104 5593936
01:24:39 49889 8192000000 10884414048 11347755008 1926437584 19.038 99 12 24 0 1946583904 16759712 3386608
01:26:29 55698 8192000000 10894657168 11372482560 1931614928 19.080 98 29 58 0 1955886928 15883824 8388176
01:28:38 70937 8192000000 10891919040 11358793728 1939054032 19.139 99 16 32 1 1959695776 15831312 4810432
01:30:57 44789 8192000000 10908414576 11376734208 1934250896 19.101 98 28 56 0 1956422272 14038672 8132704
01:32:46 63797 8192000000 10916071152 11362570240 1885438080 18.709 96 17 34 0 1908648336 18410256 4800000
01:34:51 137156 8192000000 10878427008 11355045888 1836561248 18.313 93 33 66 2 1858778096 13122448 9094400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-08-2021 01:40:01 to 29-08-2021 01:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-08-2021 01:40:17 to 29-08-2021 01:40:19.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          241497
MON$OLDEST_ACTIVE               242120
MON$OLDEST_SNAPSHOT             242120
MON$NEXT_TRANSACTION            242120
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-03 16:41:55.3470
MON$PAGES                       2060960
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:669: 
:670: mscs-test-161.budzdorov.ru    Sun Aug 29 01:37:53 2021
:671:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:672: 
:673: 
:674: mscs-test-161.budzdorov.ru    Sun Aug 29 01:37:59 2021
:675:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:676: 
:677: 
:678: mscs-test-161.budzdorov.ru    Sun Aug 29 01:38:03 2021
:679:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:680: 
:681: 
:682: mscs-test-161.budzdorov.ru    Sun Aug 29 01:38:03 2021
:683:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:684: 
:685: 
:686: mscs-test-161.budzdorov.ru    Sun Aug 29 01:38:03 2021
:687:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:688: 
:689: 
:690: mscs-test-161.budzdorov.ru    Sun Aug 29 01:38:03 2021
:691:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:692: 
:693: 
:694: mscs-test-161.budzdorov.ru    Sun Aug 29 01:38:03 2021
:695:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:696: 
--- end of diff output ---

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

29.08.21 01:40:20. There are 3 logs in /var/tmp/logs-oltp30 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-08-29 01:40:22 No severe PSQL-related problems occured
29.08.21 01:40:24. End of report.