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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16173240 kB
MemAvailable 30908428 kB
Buffers 0 kB
Cached 15529640 kB
SwapCached 340 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-09-04 10:37:04
DTS_END                         2021-09-04 10:37:04.9420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38740
TRN_ID                          231506
ATT_ID                          679
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: 8634.12 21356 1036094
customer order: creation 171.01 431 20521
customer order: refuse 14.63 387 1755
order to supplier: creation 45.81 1474 5497
order to supplier: removal 3.22 128999 386
invoice (draft): creation 43.97 3719 5276
invoice (draft): removal 46.88 1061 5625
invoice accept: apply 41.49 20901 4979
invoice accept: cancel 42.39 1044 5087
customer reserve: creation 3789.21 208 454705
customer reserve: removal 4158.18 78 498982
realization accept: apply 81.88 183 9826
realization accept: cancel 68.56 151 8227
payment from customer: creation 58.25 114 6990
payment from customer: removal 5.41 121 649
payment to supplier: creation 48.48 130 5817
payment to supplier: removal 3.57 116 428
service: total inventory turnovers 3.84 3996 461
service: total monetary turnovers 6.47 635 776
service: aggregate perf. data 0.86 16784 103
service: refresh index statistics 0.03 246578 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7620
TEST_TIME 2 5715
TEST_TIME 3 6111
TEST_TIME 4 5854
TEST_TIME 5 5859
TEST_TIME 6 5464
TEST_TIME 7 5601
TEST_TIME 8 5744
TEST_TIME 9 6234
TEST_TIME 10 7186
TEST_TIME 11 7104
TEST_TIME 12 7068
TEST_TIME 13 7272
TEST_TIME 14 7558
TEST_TIME 15 7750
TEST_TIME 16 7861
TEST_TIME 17 7899
TEST_TIME 18 7892
TEST_TIME 19 8028
TEST_TIME 20 7939
TEST_TIME 21 8541
TEST_TIME 22 8643
TEST_TIME 23 8506
TEST_TIME 24 8735
TEST_TIME 25 9274
TEST_TIME 26 9223
TEST_TIME 27 9296
TEST_TIME 28 9742
TEST_TIME 29 10506
TEST_TIME 30 11301
TEST_TIME 31 11029
TEST_TIME 32 10764
TEST_TIME 33 10539
TEST_TIME 34 10435
TEST_TIME 35 10466
TEST_TIME 36 10333
TEST_TIME 37 10202
TEST_TIME 38 10073
TEST_TIME 39 9932
TEST_TIME 40 9851
TEST_TIME 41 9724
TEST_TIME 42 9777
TEST_TIME 43 9621
TEST_TIME 44 9453
TEST_TIME 45 9385
TEST_TIME 46 9429
TEST_TIME 47 9386
TEST_TIME 48 9396
TEST_TIME 49 9344
TEST_TIME 50 9308
TEST_TIME 51 9203
TEST_TIME 52 9087
TEST_TIME 53 9070
TEST_TIME 54 9106
TEST_TIME 55 8998
TEST_TIME 56 8970
TEST_TIME 57 8915
TEST_TIME 58 8978
TEST_TIME 59 8896
TEST_TIME 60 8975
TEST_TIME 61 8952
TEST_TIME 62 8931
TEST_TIME 63 8934
TEST_TIME 64 9040
TEST_TIME 65 9009
TEST_TIME 66 9070
TEST_TIME 67 9160
TEST_TIME 68 9190
TEST_TIME 69 9209
TEST_TIME 70 9180
TEST_TIME 71 9316
TEST_TIME 72 9200
TEST_TIME 73 9144
TEST_TIME 74 9057
TEST_TIME 75 9022
TEST_TIME 76 9029
TEST_TIME 77 8954
TEST_TIME 78 8966
TEST_TIME 79 8964
TEST_TIME 80 8878
TEST_TIME 81 8842
TEST_TIME 82 8842
TEST_TIME 83 8898
TEST_TIME 84 8864
TEST_TIME 85 8937
TEST_TIME 86 8906
TEST_TIME 87 8924
TEST_TIME 88 8906
TEST_TIME 89 8961
TEST_TIME 90 8998
TEST_TIME 91 8957
TEST_TIME 92 9030
TEST_TIME 93 9064
TEST_TIME 94 9019
TEST_TIME 95 8931
TEST_TIME 96 8942
TEST_TIME 97 8879
TEST_TIME 98 8882
TEST_TIME 99 8848
TEST_TIME 100 8826
TEST_TIME 101 8796
TEST_TIME 102 8788
TEST_TIME 103 8778
TEST_TIME 104 8804
TEST_TIME 105 8796
TEST_TIME 106 8771
TEST_TIME 107 8778
TEST_TIME 108 8841
TEST_TIME 109 8836
TEST_TIME 110 8835
TEST_TIME 111 8890
TEST_TIME 112 8861
TEST_TIME 113 8794
TEST_TIME 114 8771
TEST_TIME 115 8732
TEST_TIME 116 8743
TEST_TIME 117 8712
TEST_TIME 118 8688
TEST_TIME 119 8686
TEST_TIME 120 8636

Performance per minute, chart


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

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 1456816 1456816 0 0.00 0 22231 8 0 0
doc_list_biud 1456813 1456813 0 0.00 0 45770 34 0 0
sp_add_invoice_to_stock 5083 4979 104 2.04 45 175887 20901 0 104
sp_cancel_adding_invoice 5087 5087 0 0.00 7 74440 1044 0 0
sp_cancel_client_order 1755 1755 0 0.00 4 27245 388 0 0
sp_cancel_customer_reserve 498982 498982 0 0.00 1 45772 79 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 8788 121 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 7084 116 0 0
sp_cancel_supplier_invoice 5632 5625 7 0.12 2 15282 1062 0 7
sp_cancel_supplier_order 386 386 0 0.00 11 1135113 128999 0 0
sp_cancel_write_off 8227 8227 0 0.00 1 10899 151 0 0
sp_client_order 20521 20521 0 0.00 4 41884 431 0 0
sp_customer_reserve 454705 454705 0 0.00 0 67455 209 0 0
sp_fill_shopping_cart 31281 31281 0 0.00 1 15570 48 0 0
sp_fill_shopping_cart_clo_res 454701 454701 0 0.00 0 28766 16 0 0
sp_get_clo_for_invoice 4975 4975 0 0.00 0 22196 371 0 0
sp_kill_cost_storno 14391 14391 0 0.00 0 10721 79 0 0
sp_kill_qstorno_ret_qs2qd 1012202 1012202 0 0.00 0 45762 42 0 0
sp_kill_qty_storno 534863 534863 0 0.00 0 45770 91 0 0
sp_lock_selected_doc 20446 20446 0 0.00 0 8338 4 0 0
sp_make_cost_storno 27608 27608 0 0.00 0 14537 53 0 0
sp_multiply_rows_for_pdistr 27608 27608 0 0.00 0 10972 32 0 0
sp_multiply_rows_for_qdistr 20520 20520 0 0.00 0 40339 221 0 0
sp_pay_from_customer 6990 6990 0 0.00 4 16415 114 0 0
sp_pay_to_supplier 5817 5817 0 0.00 5 16854 131 0 0
sp_payment_common 12807 12807 0 0.00 1 12222 27 0 0
sp_qd_handle_on_cancel_clo 1755 1755 0 0.00 0 27234 365 0 0
sp_qd_handle_on_invoice_upd_sts 10062 10062 0 0.00 0 19611 409 0 0
sp_qd_handle_on_reserve_upd_sts 9826 9826 0 0.00 0 29115 63 0 0
sp_reserve_write_off 9826 9826 0 0.00 7 29160 184 0 0
sp_supplier_invoice 5276 5276 0 0.00 7 99442 3720 0 0
sp_supplier_order 5497 5497 0 0.00 5 63047 1474 0 0
srv_aggregate_perf_data 103 103 0 0.00 3052 183386 16784 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 979 168480 44985 0 0
srv_find_qd_qs_mism 988905 988905 0 0.00 0 22227 7 0 0
srv_make_invnt_saldo 461 461 0 0.00 33 114943 3996 0 0
srv_make_money_saldo 776 776 0 0.00 1 11314 635 0 0
srv_recalc_idx_stat 4 4 0 0.00 137123 480069 246579 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4979 4979 0 0.00 0 22119 196 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 1 7537 121 0 0
t$perf-norm:sp_cancel_client_order 1755 1755 0 0.00 0 4614 17 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 3995 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 2234 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 389 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 1247 20 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 26010 1605 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 1059 5 0 0
t$perf-norm:sp_client_order 20521 20521 0 0.00 0 25188 24 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 1077 7 0 0
t$perf-norm:sp_pay_from_customer 6990 6990 0 0.00 0 15731 10 0 0
t$perf-norm:sp_pay_to_supplier 5817 5817 0 0.00 0 7589 12 0 0
t$perf-norm:sp_reserve_write_off 9826 9826 0 0.00 0 10757 12 0 0
t$perf-norm:sp_supplier_invoice 5276 5276 0 0.00 0 15615 26 0 0
t$perf-norm:sp_supplier_order 5497 5497 0 0.00 0 4929 20 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 443 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 54 2 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 1736 14 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 2099 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 3 0 0
v_add_invoice_to_stock 10158 9950 208 2.04 0 7028 17 0 208
v_all_customers 36974 36974 0 0.00 0 28 1 0 0
v_all_suppliers 29958 29958 0 0.00 0 135 1 0 0
v_all_wares 41028 41028 0 0.00 0 102 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 564 6 0 0
v_cancel_client_order 3510 3510 0 0.00 0 3313 4 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 5741 16 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 140 3 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 1350 7 0 0
v_cancel_supplier_invoice 776 762 14 1.80 0 1532 20 0 14
v_cancel_supplier_order 772 772 0 0.00 0 441 6 0 0
v_cancel_write_off 1966 1966 0 0.00 0 4898 15 0 0
v_max_id_clo_ord 5496 5496 0 0.00 0 624 2 0 0
v_max_id_clo_res 951 951 0 0.00 0 817 5 0 0
v_max_id_ord_sup 5271 5271 0 0.00 0 8441 8 0 0
v_max_non_paid_invoice 1604 1604 0 0.00 0 229 2 0 0
v_max_non_paid_realizn 3728 3728 0 0.00 0 2568 4 0 0
v_min_id_clo_ord 5496 5496 0 0.00 0 6718 9 0 0
v_min_id_clo_res 951 951 0 0.00 0 6745 20 0 0
v_min_id_ord_sup 5271 5271 0 0.00 0 5962 8 0 0
v_min_non_paid_invoice 5817 5817 0 0.00 0 9627 18 0 0
v_min_non_paid_realizn 6990 6990 0 0.00 0 12218 20 0 0
v_our_firm 10578 10578 0 0.00 0 41 1 0 0
v_reserve_write_off 19652 19652 0 0.00 0 3515 4 0 0
x_lock_depdocs_on_canc_invoice 10331 10331 0 0.00 0 26334 140 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 3796 363 0 0
x_make_qstorno_1000_1200 5497 5497 0 0.00 1 62972 1378 0 0
x_make_qstorno_1200_2000 5276 5276 0 0.00 3 96239 3542 0 0
x_make_qstorno_2100_3300 454705 454705 0 0.00 0 67430 189 0 0

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

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
08:07:08 9 8192000000 8800582480 8812548096 5446496 0.066 3 1 2 0 5547664 65392 35776
08:08:10 25006 8192000000 9714826240 10012749824 790677632 8.802 100 80 160 0 817417328 7325232 19414464
08:09:43 14701 8192000000 9760654048 10066104320 837259152 9.272 100 93 186 0 871671376 12022096 22390128
08:11:02 14689 8192000000 9791571472 10104840192 860638192 9.507 100 87 174 1 898612464 17610176 20364096
08:12:23 12247 8192000000 9849508272 10184331264 901723696 9.915 100 79 158 2 960418528 41042576 17652256
08:13:39 10430 8192000000 9898721792 10241261568 945735456 10.349 100 72 144 1 1009482496 48244912 15502128
08:14:51 4723 8192000000 9957431728 10313596928 987108128 10.753 100 77 153 1 1074900576 72596704 15195744
08:16:01 3505 8192000000 9987417280 10348556288 1016023792 11.034 100 91 182 1 1112878400 78530656 18323952
08:17:06 2321 8192000000 9999147808 10369667072 1042246096 11.286 100 87 174 0 1119095744 58822752 18026896
08:18:12 2713 8192000000 10022735328 10392952832 1054844480 11.407 100 82 160 2 1131156736 60394992 15917264
08:19:17 2151 8192000000 10056729728 10429489152 1062573520 11.481 100 90 178 1 1166449232 86280320 17595392
08:20:21 5939 8192000000 10028278336 10420482048 1073087616 11.582 100 54 108 0 1155454608 71964768 10402224
08:21:29 7535 8192000000 10001802864 10394365952 1077069792 11.620 100 35 69 2 1121688688 36578608 8040288
08:22:39 75113 8192000000 9989867440 10387574784 1086596528 11.710 100 45 90 4 1110454784 14095408 9762848
08:24:54 4866 8192000000 10027521040 10422472704 1091132768 11.753 100 48 97 1 1139725008 38552864 10039376
08:26:03 7064 8192000000 10010548944 10407550976 1094218720 11.783 100 41 80 2 1117549248 13567888 9762640
08:27:12 16510 8192000000 10012198240 10410622976 1095399984 11.794 100 54 108 3 1122938192 15296144 12242064
08:28:29 4837 8192000000 10007770512 10397941760 1067460352 11.528 98 38 76 2 1100864160 25425712 7978096
08:29:34 7836 8192000000 9967852400 10361286656 1041661120 11.281 98 30 59 0 1074911936 27066368 6184448
08:30:46 10714 8192000000 9909450464 10289823744 992643184 10.807 97 26 53 1 1035346272 37214800 5488288
08:31:59 26596 8192000000 9927331856 10325581824 983850960 10.722 95 20 40 1 1009592272 21530528 4210784
08:33:26 7233 8192000000 9976388896 10363236352 1033253952 11.200 99 50 99 2 1074673712 30553296 10866464
08:34:36 4095 8192000000 9971352768 10355605504 1050356624 11.364 100 32 66 3 1075570752 17250704 7963424
08:35:43 67269 8192000000 9954554256 10360926208 1062404096 11.479 100 33 64 0 1090841376 21695824 6741456
08:37:57 11793 8192000000 10022988832 10420006912 1087461616 11.719 100 43 84 0 1124444704 27396784 9586304
08:39:14 23392 8192000000 10044584832 10447360000 1110942160 11.941 100 46 90 3 1160038288 39145552 9950576
08:40:43 3607 8192000000 10102667248 10507702272 1130669216 12.128 100 63 126 1 1217173040 72854784 13649040
08:41:50 45124 8192000000 10151607584 10574344192 1136544448 12.183 100 52 102 2 1265000080 116949936 11505696
08:43:43 13842 8192000000 10195209280 10606223360 1149203696 12.302 100 51 101 0 1292099584 131505216 11390672
08:45:01 8719 8192000000 10224901824 10630021120 1157650544 12.381 100 67 132 2 1323855952 151821696 14383712
08:46:27 6739 8192000000 10282988128 10696359936 1161868528 12.421 100 63 126 0 1380069280 204185984 14014768
08:47:42 10701 8192000000 10240475968 10652602368 1161693648 12.419 100 62 123 1 1342945040 167661024 13590368
08:48:58 6279 8192000000 10277346256 10695610368 1171387728 12.510 100 62 121 2 1379945696 193818640 14739328
08:50:09 6460 8192000000 10288445040 10721988608 1170206000 12.499 100 61 122 0 1399526368 215113296 14207072
08:51:25 4957 8192000000 10313248368 10742747136 1178962848 12.581 100 73 145 1 1405825936 211551200 15311888
08:52:37 978 8192000000 10321670160 10751266816 1178486960 12.576 100 72 142 0 1419439984 224876112 16076912
08:53:43 2045 8192000000 10316617856 10732953600 1157734592 12.382 100 86 171 0 1410021120 233046080 19240448
08:54:54 3161 8192000000 10363863648 10762391552 1155163456 12.358 98 84 166 3 1459929072 285872624 18892992
08:56:05 1741 8192000000 10440850112 10844778496 1174134480 12.535 100 95 185 0 1529454800 333312528 22007792
08:57:12 4863 8192000000 10420248144 10818125824 1146931680 12.281 100 89 178 0 1504929504 337408880 20588944
08:58:26 1407 8192000000 10475588592 10900635648 1155096064 12.357 100 92 183 1 1566599552 390086416 21417072
08:59:32 15226 8192000000 10421748624 10882973696 1138053760 12.197 99 34 68 1 1510071712 362196192 9821760
09:00:58 2671 8192000000 10444834144 10883178496 1144098720 12.254 100 70 139 1 1539693824 379067312 16527792
09:02:04 19939 8192000000 10402115648 10882129920 1144128560 12.254 100 35 70 0 1558044176 404020432 9895184
09:03:33 17685 8192000000 10441803040 10895237120 1137320160 12.190 100 31 62 0 1528220688 382517568 8382960
09:05:01 22587 8192000000 10476623856 10926186496 1121955984 12.045 100 25 50 2 1552867744 423318912 7592848
09:06:33 31702 8192000000 10195322128 10679562240 1111607328 11.948 100 20 40 1 1387993008 271164672 5221008
09:08:44 168480 8192000000 10070113376 10550337536 1092281168 11.764 97 23 46 1 1184219872 86641696 5297008
09:12:43 49122 8192000000 10071703440 10553204736 1124336096 12.068 100 11 22 1 1168257824 41822672 2099056
09:14:34 52631 8192000000 10093347584 10518409216 1096109392 11.801 97 31 61 1 1163487360 60098816 7279152
09:16:29 58388 8192000000 10072331440 10525487104 1085412224 11.699 96 11 22 0 1158832912 71103344 2317344
09:18:28 67272 8192000000 10080921232 10572148736 1119179936 12.019 99 10 20 0 1210889296 89397536 2311824
09:20:41 62737 8192000000 10104012208 10544803840 1123152400 12.057 99 24 47 1 1197269824 69277456 4839968
09:22:44 19587 8192000000 10114353920 10560913408 1135120784 12.170 100 33 66 4 1216379536 73949520 7309232
09:24:05 39624 8192000000 10098513920 10553151488 1142305408 12.237 100 24 47 1 1210663344 62820720 5537216
09:25:56 42142 8192000000 10066364944 10531946496 1140267136 12.218 100 5 10 0 1178723344 37063936 1392272
09:27:43 81857 8192000000 10089868752 10537689088 1110238144 11.935 97 19 38 0 1148304320 33932576 4133600
09:30:41 15515 8192000000 10113027024 10598035456 1137401504 12.191 100 19 39 1 1210502032 69396128 3704400
09:32:11 57810 8192000000 10043471808 10523131904 1106474640 11.899 97 2 4 0 1136652656 29938400 239616
09:34:08 53177 8192000000 10094876912 10548953088 1121088096 12.037 98 24 48 2 1157744080 31794800 4861184
09:36:09 63798 8192000000 10121326512 10579206144 1126549488 12.089 99 33 66 1 1187656064 54133072 6973504
09:38:20 35089 8192000000 10072356208 10512293888 1132033088 12.141 99 24 47 2 1165540224 28946560 4560576
09:39:57 23742 8192000000 10143727472 10565562368 1138968896 12.206 100 68 136 0 1221242928 68696000 13578032
09:41:24 75565 8192000000 10119307280 10556981248 1089087248 11.734 95 17 34 0 1195701632 103028448 3585936
09:43:41 65857 8192000000 10058045776 10529644544 1053503296 11.394 93 19 36 1 1121249600 63999136 3747168
09:45:48 72513 8192000000 10086349376 10543468544 1080987504 11.657 96 18 36 0 1119692976 34880576 3824896
09:48:01 85353 8192000000 10080728752 10611400704 1117796368 12.006 99 19 38 0 1230314480 108438480 4079632
09:51:06 68924 8192000000 10078137872 10511917056 1083997296 11.686 97 48 96 1 1156664992 62382016 10285680
09:53:15 80722 8192000000 10078698176 10536865792 1119458464 12.022 100 13 26 0 1185355744 62881904 3015376
09:55:36 48253 8192000000 10061395072 10517950464 1121246144 12.039 100 14 28 0 1191653520 66889568 3517808
09:57:25 52094 8192000000 10077653216 10512166912 1112112608 11.952 99 27 51 1 1165687904 47881056 5694240
09:59:17 116810 8192000000 10058293328 10524606464 1082775584 11.674 96 19 38 0 1154139408 67000432 4363392
10:02:16 64607 8192000000 10068770080 10557935616 1064140704 11.496 95 24 48 0 1118202400 49264864 4796832
10:04:33 24737 8192000000 10064944096 10498977792 1118950144 12.017 100 31 62 1 1169758624 44573504 6234976
10:05:59 103626 8192000000 10067521472 10543538176 1071666480 11.568 95 17 34 1 1136196048 61406832 3122736
10:08:43 32794 8192000000 10134374752 10565517312 1124443440 12.069 99 42 84 0 1186227072 53611568 8172064
10:10:18 40258 8192000000 10069301104 10499883008 1120681872 12.033 99 42 82 2 1184625552 55246400 8697280
10:12:00 137675 8192000000 10066241104 10546700288 1132806800 12.148 99 23 46 0 1198745264 60463680 5474784
10:15:19 67182 8192000000 10065647376 10562031616 1138846928 12.205 100 7 14 0 1186541584 46344400 1350256
10:17:26 73352 8192000000 10076354320 10525769728 1127115344 12.094 98 19 38 0 1158294352 27173472 4005536
10:19:59 86984 8192000000 10074677680 10556096512 1126503616 12.088 98 20 40 0 1170567312 39447664 4616032
10:22:28 54033 8192000000 10092806576 10536214528 1090326384 11.746 95 30 60 1 1164090944 68027760 5736800
10:24:33 90978 8192000000 10077103296 10530967552 1114677728 11.977 98 31 62 2 1184039152 62537216 6824208
10:27:05 11291 8192000000 10091140368 10525192192 1130331808 12.124 99 41 82 2 1174018224 35221696 8464720
10:28:19 106273 8192000000 10086466576 10562289664 1095472224 11.795 96 11 22 0 1136156624 38599056 2085344
10:31:06 49029 8192000000 10071141072 10542587904 1108021408 11.914 97 17 33 0 1160416912 48956448 3439056
10:33:55 48949 8192000000 10036347472 10481721344 1092990672 11.771 97 9 16 1 1126183792 31555600 1637520
10:35:46 64445 8192000000 10109410784 10564345856 1114709568 11.977 98 23 45 2 1192804816 73211808 4883440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

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

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          228666
MON$OLDEST_ACTIVE               231633
MON$OLDEST_SNAPSHOT             231633
MON$NEXT_TRANSACTION            231633
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                       2039328
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2580 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2580 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2580 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2580 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 ---
:55: 
:56: mscs-test-161.budzdorov.ru    Sat Sep  4 10:37:13 2021
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:58: 
:59: 
:60: mscs-test-161.budzdorov.ru    Sat Sep  4 10:37:13 2021
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:62: 
--- end of diff output ---

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

04.09.21 10:38:58. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-09-04 10:39:01 No severe PSQL-related problems occured
04.09.21 10:39:02. End of report.