Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.08.2021 16: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 16537092 kB
MemAvailable 30999052 kB
Buffers 0 kB
Cached 15183500 kB
SwapCached 3640 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-20 16:37:48
DTS_END                         2021-08-20 16:37:48.6810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53734
TRN_ID                          238943
ATT_ID                          697
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: 8789.38 26609 1054726
customer order: creation 165.54 393 19865
customer order: refuse 14.03 321 1684
order to supplier: creation 43.69 1274 5243
order to supplier: removal 3.35 107612 402
invoice (draft): creation 44.10 3437 5292
invoice (draft): removal 49.25 867 5910
invoice accept: apply 40.89 18988 4907
invoice accept: cancel 43.80 860 5256
customer reserve: creation 3818.08 186 458169
customer reserve: removal 4286.98 65 514437
realization accept: apply 80.68 169 9682
realization accept: cancel 74.47 130 8936
payment from customer: creation 57.21 117 6865
payment from customer: removal 5.43 75 652
payment to supplier: creation 46.93 121 5631
payment to supplier: removal 3.32 60 398
service: total inventory turnovers 4.61 928 553
service: total monetary turnovers 6.23 24 747
service: aggregate perf. data 0.78 20920 93
service: refresh index statistics 0.03 375625 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-08-2021 16:40:16 to 20-08-2021 16:40:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3402
TEST_TIME 2 3973
TEST_TIME 3 3683
TEST_TIME 4 3880
TEST_TIME 5 4153
TEST_TIME 6 4199
TEST_TIME 7 4425
TEST_TIME 8 4473
TEST_TIME 9 4931
TEST_TIME 10 5304
TEST_TIME 11 5536
TEST_TIME 12 5517
TEST_TIME 13 5752
TEST_TIME 14 6227
TEST_TIME 15 6364
TEST_TIME 16 6449
TEST_TIME 17 6517
TEST_TIME 18 6708
TEST_TIME 19 6728
TEST_TIME 20 7108
TEST_TIME 21 7320
TEST_TIME 22 7357
TEST_TIME 23 7619
TEST_TIME 24 8210
TEST_TIME 25 8435
TEST_TIME 26 8447
TEST_TIME 27 9121
TEST_TIME 28 9536
TEST_TIME 29 9575
TEST_TIME 30 10005
TEST_TIME 31 9927
TEST_TIME 32 9829
TEST_TIME 33 9574
TEST_TIME 34 9433
TEST_TIME 35 9322
TEST_TIME 36 9265
TEST_TIME 37 9115
TEST_TIME 38 9095
TEST_TIME 39 9174
TEST_TIME 40 9046
TEST_TIME 41 8891
TEST_TIME 42 8985
TEST_TIME 43 9119
TEST_TIME 44 9042
TEST_TIME 45 8973
TEST_TIME 46 8960
TEST_TIME 47 8849
TEST_TIME 48 8929
TEST_TIME 49 8837
TEST_TIME 50 8705
TEST_TIME 51 8630
TEST_TIME 52 8571
TEST_TIME 53 8452
TEST_TIME 54 8612
TEST_TIME 55 8547
TEST_TIME 56 8619
TEST_TIME 57 8602
TEST_TIME 58 8585
TEST_TIME 59 8603
TEST_TIME 60 8584
TEST_TIME 61 8650
TEST_TIME 62 8952
TEST_TIME 63 8960
TEST_TIME 64 8968
TEST_TIME 65 9077
TEST_TIME 66 9080
TEST_TIME 67 9042
TEST_TIME 68 8959
TEST_TIME 69 8914
TEST_TIME 70 8956
TEST_TIME 71 8900
TEST_TIME 72 8927
TEST_TIME 73 8895
TEST_TIME 74 8874
TEST_TIME 75 8858
TEST_TIME 76 8840
TEST_TIME 77 8815
TEST_TIME 78 8819
TEST_TIME 79 8815
TEST_TIME 80 8858
TEST_TIME 81 8827
TEST_TIME 82 8834
TEST_TIME 83 8907
TEST_TIME 84 8952
TEST_TIME 85 8952
TEST_TIME 86 8925
TEST_TIME 87 8973
TEST_TIME 88 8970
TEST_TIME 89 9026
TEST_TIME 90 8976
TEST_TIME 91 8950
TEST_TIME 92 8979
TEST_TIME 93 8961
TEST_TIME 94 8929
TEST_TIME 95 8884
TEST_TIME 96 8901
TEST_TIME 97 8907
TEST_TIME 98 8891
TEST_TIME 99 8918
TEST_TIME 100 8946
TEST_TIME 101 8945
TEST_TIME 102 9062
TEST_TIME 103 9025
TEST_TIME 104 9036
TEST_TIME 105 9033
TEST_TIME 106 9039
TEST_TIME 107 9070
TEST_TIME 108 9096
TEST_TIME 109 9107
TEST_TIME 110 9077
TEST_TIME 111 9025
TEST_TIME 112 9036
TEST_TIME 113 8993
TEST_TIME 114 8984
TEST_TIME 115 8935
TEST_TIME 116 8900
TEST_TIME 117 8890
TEST_TIME 118 8881
TEST_TIME 119 8824
TEST_TIME 120 8791

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-08-2021 16:40:20 to 20-08-2021 16:40:23.

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 1477894 1477894 0 0.00 0 28120 6 0 0
doc_list_biud 1477874 1477874 0 0.00 0 33712 29 0 0
sp_add_invoice_to_stock 5028 4907 121 2.40 14 204461 18988 0 121
sp_cancel_adding_invoice 5256 5256 0 0.00 7 57994 861 0 0
sp_cancel_client_order 1684 1684 0 0.00 4 9653 321 0 0
sp_cancel_customer_reserve 514437 514437 0 0.00 1 33713 66 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 6815 75 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 1315 61 0 0
sp_cancel_supplier_invoice 5919 5910 9 0.15 2 21855 867 0 9
sp_cancel_supplier_order 402 402 0 0.00 10 1025121 107613 0 0
sp_cancel_write_off 8936 8936 0 0.00 1 14980 130 0 0
sp_client_order 19865 19865 0 0.00 3 29211 393 0 0
sp_customer_reserve 458169 458169 0 0.00 0 47437 186 0 0
sp_fill_shopping_cart 30397 30397 0 0.00 1 14343 39 0 0
sp_fill_shopping_cart_clo_res 458166 458166 0 0.00 0 15269 16 0 0
sp_get_clo_for_invoice 4903 4903 0 0.00 0 32216 334 0 0
sp_kill_cost_storno 15242 15242 0 0.00 0 11940 60 0 0
sp_kill_qstorno_ret_qs2qd 1044122 1044122 0 0.00 0 33707 36 0 0
sp_kill_qty_storno 551200 551200 0 0.00 0 33712 77 0 0
sp_lock_selected_doc 20182 20182 0 0.00 0 6348 4 0 0
sp_make_cost_storno 27081 27081 0 0.00 0 16065 57 0 0
sp_multiply_rows_for_pdistr 27062 27062 0 0.00 0 6886 26 0 0
sp_multiply_rows_for_qdistr 19865 19865 0 0.00 0 21273 176 0 0
sp_pay_from_customer 6865 6865 0 0.00 4 10924 117 0 0
sp_pay_to_supplier 5631 5631 0 0.00 5 11440 121 0 0
sp_payment_common 12487 12487 0 0.00 1 9554 36 0 0
sp_qd_handle_on_cancel_clo 1684 1684 0 0.00 0 9645 300 0 0
sp_qd_handle_on_invoice_upd_sts 10154 10154 0 0.00 0 19937 350 0 0
sp_qd_handle_on_reserve_upd_sts 9677 9677 0 0.00 0 11740 54 0 0
sp_reserve_write_off 9682 9682 0 0.00 6 13676 169 0 0
sp_supplier_invoice 5292 5292 0 0.00 7 75048 3438 0 0
sp_supplier_order 5243 5243 0 0.00 5 34758 1274 0 0
srv_aggregate_perf_data 93 93 0 0.00 2428 163588 20921 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2811 176294 47405 0 0
srv_find_qd_qs_mism 1007772 1007772 0 0.00 0 28118 6 0 0
srv_make_invnt_saldo 553 553 0 0.00 44 34476 929 0 0
srv_make_money_saldo 747 747 0 0.00 1 1352 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 131115 547418 375626 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 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 4908 4908 0 0.00 0 11331 169 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 6614 102 0 0
t$perf-norm:sp_cancel_client_order 1684 1684 0 0.00 0 6111 18 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 3183 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 740 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 937 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1928 21 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 36695 1838 0 0
t$perf-norm:sp_cancel_write_off 951 951 0 0.00 0 2031 8 0 0
t$perf-norm:sp_client_order 19865 19865 0 0.00 0 11259 20 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 3111 10 0 0
t$perf-norm:sp_pay_from_customer 6865 6865 0 0.00 0 6151 12 0 0
t$perf-norm:sp_pay_to_supplier 5631 5631 0 0.00 0 4772 13 0 0
t$perf-norm:sp_reserve_write_off 9682 9682 0 0.00 0 9358 14 0 0
t$perf-norm:sp_supplier_invoice 5292 5292 0 0.00 0 7123 19 0 0
t$perf-norm:sp_supplier_order 5243 5243 0 0.00 0 3754 15 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 802 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 955 17 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 3658 26 0 0
t$perf-norm:srv_make_money_saldo 747 747 0 0.00 0 4830 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10038 9796 242 2.41 0 6349 14 0 242
v_all_customers 36154 36154 0 0.00 0 93 1 0 0
v_all_suppliers 29342 29342 0 0.00 0 12 1 0 0
v_all_wares 39728 39728 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 282 6 0 0
v_cancel_client_order 3368 3368 0 0.00 0 1578 3 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 723 5 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 987 4 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 1232 10 0 0
v_cancel_supplier_invoice 866 848 18 2.07 0 1641 13 0 18
v_cancel_supplier_order 804 804 0 0.00 0 282 4 0 0
v_cancel_write_off 1902 1902 0 0.00 0 3026 13 0 0
v_max_id_clo_ord 5242 5242 0 0.00 0 1650 2 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 4958 19 0 0
v_max_id_ord_sup 5291 5291 0 0.00 0 5477 5 0 0
v_max_non_paid_invoice 1490 1490 0 0.00 0 1071 3 0 0
v_max_non_paid_realizn 3738 3738 0 0.00 0 4059 8 0 0
v_min_id_clo_ord 5242 5242 0 0.00 0 2209 5 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 3909 8 0 0
v_min_id_ord_sup 5291 5291 0 0.00 0 6897 7 0 0
v_min_non_paid_invoice 5628 5628 0 0.00 0 7416 19 0 0
v_min_non_paid_realizn 6859 6859 0 0.00 0 8946 25 0 0
v_our_firm 9816 9816 0 0.00 0 14 1 0 0
v_reserve_write_off 19354 19354 0 0.00 0 1962 4 0 0
x_lock_depdocs_on_canc_invoice 10742 10742 0 0.00 0 14119 123 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 7158 372 0 0
x_make_qstorno_1000_1200 5243 5243 0 0.00 2 34749 1197 0 0
x_make_qstorno_1200_2000 5292 5292 0 0.00 3 75042 3291 0 0
x_make_qstorno_2100_3300 458169 458169 0 0.00 0 40209 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-08-2021 16:40:24 to 20-08-2021 16:40:28.

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
14:07:52 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:53 5492 8192000000 10362951664 10677755904 1421220320 14.784 100 88 176 0 1456171664 5810144 29141200
14:10:10 20900 8192000000 10426833824 10754973696 1476938672 15.275 100 88 176 1 1513727344 7991680 28796992
14:11:40 23725 8192000000 10506420832 10845622272 1547886800 15.892 100 86 172 0 1585091584 10251280 26953504
14:13:22 10376 8192000000 10598048000 10955763712 1648794560 16.754 100 84 167 3 1686102256 12637136 24670560
14:14:34 10523 8192000000 10650922416 11022422016 1712394784 17.289 100 86 172 0 1750786256 13487248 24904224
14:15:46 5413 8192000000 10703236816 11086368768 1768807888 17.757 100 82 164 2 1805009328 12633456 23567984
14:16:54 4392 8192000000 10755605712 11137933312 1816788560 18.151 100 83 164 0 1853533216 13641728 23102928
14:18:00 1663 8192000000 10805329888 11198164992 1872496288 18.604 100 87 175 0 1908542416 12011264 24034864
14:19:02 1697 8192000000 10834138688 11226079232 1893084528 18.771 100 91 181 1 1931482592 12344976 26053088
14:20:05 6140 8192000000 10803019888 11229327360 1894161360 18.779 100 43 87 2 1913197904 5773632 13262912
14:21:11 3801 8192000000 10812534992 11246727168 1906455600 18.878 100 23 46 0 1918612064 5328608 6827856
14:22:16 5765 8192000000 10812707696 11253067776 1914255104 18.941 100 13 25 1 1923403472 5493472 3654896
14:23:24 10209 8192000000 10830394160 11269455872 1925386960 19.030 100 32 65 2 1945024416 10253808 9383648
14:24:36 27640 8192000000 10837026688 11280924672 1925439840 19.030 100 22 44 1 1941532288 10126768 5965680
14:26:08 4145 8192000000 10840879488 11277127680 1925705440 19.033 100 33 66 2 1942264560 7369760 9189360
14:27:16 6747 8192000000 10802483200 11234680832 1863329808 18.530 98 18 36 2 1874249664 5725632 5194224
14:28:25 48767 8192000000 10801860832 11225919488 1871940800 18.600 99 46 92 2 1891783696 6249792 13593104
14:30:17 5840 8192000000 10715916544 11130302464 1722610560 17.374 94 24 48 1 1734902816 5896768 6395488
14:31:24 12938 8192000000 10724720624 11141685248 1749794064 17.600 96 24 48 1 1762165712 4978432 7393216
14:32:39 9851 8192000000 10736260448 11159498752 1808791216 18.086 99 19 38 1 1820160864 5495120 5874528
14:33:51 21004 8192000000 10743327840 11174289408 1835230416 18.302 100 17 34 0 1845727520 5531120 4965984
14:35:14 6900 8192000000 10760010640 11192446976 1853574464 18.451 100 29 58 3 1867461888 5514592 8372832
14:36:21 11038 8192000000 10768487648 11206168576 1863123408 18.529 100 14 28 0 1872672096 5810272 3738416
14:37:32 40360 8192000000 10819475888 11245318144 1899539744 18.823 100 24 46 2 1910664304 4539488 6585072
14:39:32 11314 8192000000 10865127120 11285528576 1930167808 19.068 100 59 116 2 1955557104 8547600 16841696
14:40:53 10977 8192000000 10875819904 11301543936 1942981296 19.171 100 37 74 1 1962529536 8456560 11091680
14:42:13 11090 8192000000 10902668848 11326935040 1958817056 19.297 100 56 110 2 1986921808 11245024 16859728
14:43:27 8368 8192000000 10907343488 11327492096 1964869808 19.345 100 51 102 2 1991540800 11080368 15590624
14:44:44 10103 8192000000 10909307552 11330506752 1964249264 19.340 100 60 117 0 1997556384 16544544 16762576
14:46:00 7860 8192000000 10906650064 11341254656 1973957536 19.417 100 50 100 1 2006654784 17560704 15136544
14:47:15 20415 8192000000 10907001760 11334008832 1957153808 19.283 100 57 114 2 1992240688 18137216 16949664
14:48:42 12731 8192000000 10906445056 11337728000 1956573088 19.279 99 54 108 1 1991218368 17504656 17140624
14:50:01 5205 8192000000 10937610752 11361116160 1978896976 19.456 100 53 104 0 2019184608 23789728 16497904
14:51:13 12556 8192000000 10933297392 11375087616 1982121408 19.481 100 48 96 1 2026530912 28557536 15851968
14:52:40 10546 8192000000 10961590864 11394662400 2005856208 19.669 100 63 125 0 2049334496 23879456 19598832
14:53:58 7981 8192000000 10959578144 11380981760 1988789680 19.534 100 70 140 1 2042473024 31586880 22096464
14:55:11 7912 8192000000 10962952160 11384942592 1988454176 19.532 100 69 136 1 2043128720 33318560 21355984
14:56:26 2829 8192000000 10964195168 11374239744 1978000144 19.449 100 87 168 3 2040134352 35500288 26633920
14:57:33 2811 8192000000 10984016240 11407376384 1999619392 19.620 100 81 161 3 2067264416 41089040 26555984
14:58:40 4265 8192000000 10965582368 11391909888 1988546768 19.532 100 68 135 1 2043598112 34253344 20798000
14:59:50 19555 8192000000 10927484992 11363295232 1953394112 19.253 100 38 76 0 2010131872 43198144 13539616
15:01:17 14012 8192000000 10923000528 11364667392 1932975792 19.091 98 37 74 2 1989470912 43525056 12970064
15:02:38 11439 8192000000 10939510432 11384123392 1966361664 19.357 100 42 85 0 2033215712 52425120 14428928
15:03:51 12141 8192000000 10933767600 11377668096 1951679184 19.240 100 42 83 1 2018188992 51756176 14753632
15:05:05 23162 8192000000 10884733856 11347271680 1929489536 19.063 99 31 62 0 1973356192 33800400 10066256
15:06:33 54472 8192000000 10876895984 11318325248 1882545856 18.686 97 37 72 0 1916580752 23420688 10614208
15:08:29 41835 8192000000 10871880864 11317772288 1917821952 18.969 98 37 73 1 1936380880 8080960 10477968
15:10:22 140542 8192000000 10877923872 11336118272 1901163664 18.836 97 27 54 0 1921805232 13143424 7498144
15:14:06 29173 8192000000 10854503856 11306549248 1910280560 18.909 99 15 28 2 1928666272 14466160 3919552
15:15:36 34276 8192000000 10874993648 11335262208 1917485200 18.967 98 33 66 3 1950740800 22948976 10306624
15:17:15 47075 8192000000 10899458880 11356856320 1947522496 19.207 99 16 32 1 1971649856 19421296 4706064
15:19:03 59546 8192000000 10915856272 11389444096 1968835072 19.376 100 25 50 0 2007151216 30241616 8074528
15:22:20 69250 8192000000 10890732800 11368292352 1945215680 19.188 98 24 48 1 1965486240 13349136 6921424
15:24:31 54628 8192000000 10891736480 11369836544 1959640224 19.303 99 27 54 0 1982759776 14138048 8981504
15:26:26 99175 8192000000 10903614656 11368345600 1975465952 19.429 100 22 44 2 1990611344 8522736 6622656
15:29:15 40734 8192000000 10913526048 11379433472 1986671424 19.517 100 10 20 1 1999219504 9538432 3009648
15:30:57 63669 8192000000 10898933936 11395170304 1984796016 19.503 100 8 16 0 2002314288 15053568 2464704
15:33:21 44543 8192000000 10903436560 11370156032 1973640672 19.414 100 9 18 0 1983935136 7745024 2549440
15:35:27 57549 8192000000 10941382864 11402842112 1996401136 19.594 100 24 48 1 2020366816 16654416 7311264
15:37:29 92691 8192000000 10922407392 11403022336 1943415200 19.174 97 14 28 1 1965883072 18422464 4045408
15:40:06 68292 8192000000 10926412528 11391692800 1971230848 19.395 99 17 34 0 1986956352 10984896 4740608
15:42:21 81290 8192000000 10892252144 11369127936 1893481632 18.774 95 15 30 2 1912448224 14888064 4078528
15:44:43 36253 8192000000 10909820752 11347812352 1933481920 19.095 98 35 70 2 1957033424 13230832 10320672
15:46:21 85968 8192000000 10866900384 11327553536 1901424224 18.838 97 32 64 0 1924112464 13475504 9212736
15:48:47 45634 8192000000 10865293872 11335491584 1903543552 18.855 97 40 80 0 1926336704 11610960 11182192
15:50:46 37444 8192000000 10844906176 11319984128 1899380944 18.821 98 5 10 1 1909869808 9148208 1340656
15:52:24 55649 8192000000 10860735168 11353337856 1953015872 19.250 100 7 14 0 1964339312 9178224 2145216
15:54:26 73588 8192000000 10873929264 11341037568 1850519680 18.426 94 14 28 0 1862158336 7781008 3857648
15:56:41 64277 8192000000 10864197280 11345162240 1869693408 18.582 96 15 30 0 1890285952 16283888 4308656
15:58:47 46671 8192000000 10894333216 11351412736 1932525392 19.087 99 27 54 0 1950097968 10157456 7415120
16:00:36 99541 8192000000 10887702704 11335077888 1872712640 18.606 96 14 27 4 1887622016 11477200 3432176
16:03:16 23426 8192000000 10909889936 11357208576 1960228864 19.308 100 39 77 0 1981190576 10690576 10271136
16:04:41 29080 8192000000 10868198336 11324620800 1897890048 18.809 98 17 35 1 1910163200 7537808 4735344
16:06:10 87007 8192000000 10876873392 11344609280 1917557328 18.967 98 16 32 1 1930710672 8736928 4416416
16:08:57 87770 8192000000 10881575280 11351310336 1904894464 18.866 97 29 56 2 1928607264 16155280 7557520
16:11:25 41233 8192000000 10872311952 11346096128 1944654096 19.184 99 15 30 2 1960489808 12364640 3471072
16:13:06 64970 8192000000 10869962416 11357564928 1906303392 18.877 97 8 16 1 1921307936 12368656 2635888
16:15:34 40887 8192000000 10922411360 11393343488 1987673008 19.525 100 42 84 3 2012795792 12741552 12381232
16:17:25 50907 8192000000 10926189872 11380645888 1941137536 19.156 98 19 38 1 1964544240 17634864 5771840
16:19:22 91390 8192000000 10909210288 11379224576 1979320096 19.459 100 17 34 2 1997774480 13572512 4881872
16:22:00 46419 8192000000 10883618416 11352150016 1919475344 18.983 97 22 44 0 1936760528 10228064 7057120
16:23:53 176290 8192000000 10890636144 11359965184 1839089888 18.333 93 8 16 1 1855722912 14694624 1938400
16:28:21 111956 8192000000 10871575776 11352887296 1922782208 19.009 98 18 36 1 1938849408 11061968 5005232
16:31:15 64764 8192000000 10842073888 11317465088 1869982880 18.584 96 8 15 0 1880143552 8320432 1840240
16:33:20 75729 8192000000 10870500320 11340480512 1913849248 18.938 98 11 22 0 1924513376 7631072 3033056
16:35:41 103144 8192000000 10861292944 11342684160 1880276496 18.667 96 9 18 0 1897859296 15161440 2421360

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-08-2021 16:40:30 to 20-08-2021 16:40:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 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:01, from 20-08-2021 16:40:46 to 20-08-2021 16:40:47.

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          237158
MON$OLDEST_ACTIVE               239257
MON$OLDEST_SNAPSHOT             239257
MON$NEXT_TRANSACTION            239257
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                       2006080
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 ---
:154: 
:155: mscs-test-161.budzdorov.ru    Fri Aug 20 16:37:59 2021
:156:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:157: 
:158: 
:159: mscs-test-161.budzdorov.ru    Fri Aug 20 16:38:03 2021
:160:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:161: 
:162: 
:163: mscs-test-161.budzdorov.ru    Fri Aug 20 16:38:11 2021
:164:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:165: 
:166: 
:167: mscs-test-161.budzdorov.ru    Fri Aug 20 16:38:12 2021
:168:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:169: 
:170: 
:171: mscs-test-161.budzdorov.ru    Fri Aug 20 16:38:13 2021
:172:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:173: 
:174: 
:175: mscs-test-161.budzdorov.ru    Fri Aug 20 16:38:13 2021
:176:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:177: 
--- end of diff output ---

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

20.08.21 16:40:48. 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-20 16:40:51 No severe PSQL-related problems occured
20.08.21 16:40:52. End of report.