Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.08.2021 19: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 16197144 kB
MemAvailable 31078504 kB
Buffers 0 kB
Cached 15491308 kB
SwapCached 2572 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-03 19:37:46
DTS_END                         2021-08-03 19:37:46.3660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(108:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41480
TRN_ID                          242544
ATT_ID                          704
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: 9542.73 22127 1145127
customer order: creation 178.18 333 21381
customer order: refuse 15.30 348 1836
order to supplier: creation 47.53 1273 5704
order to supplier: removal 3.52 102322 422
invoice (draft): creation 47.91 3342 5749
invoice (draft): removal 56.10 772 6732
invoice accept: apply 42.77 16839 5132
invoice accept: cancel 50.82 710 6098
customer reserve: creation 3886.76 169 466411
customer reserve: removal 4898.68 57 587841
realization accept: apply 84.98 175 10197
realization accept: cancel 97.98 100 11758
payment from customer: creation 59.98 107 7198
payment from customer: removal 5.84 83 701
payment to supplier: creation 50.24 121 6029
payment to supplier: removal 3.23 68 388
service: total inventory turnovers 4.78 943 574
service: total monetary turnovers 7.31 26 877
service: aggregate perf. data 0.79 23556 95
service: refresh index statistics 0.03 291187 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-08-2021 19:39:26 to 03-08-2021 19:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3672
TEST_TIME 2 4429
TEST_TIME 3 4483
TEST_TIME 4 4408
TEST_TIME 5 4660
TEST_TIME 6 4639
TEST_TIME 7 4480
TEST_TIME 8 4837
TEST_TIME 9 5196
TEST_TIME 10 5222
TEST_TIME 11 5549
TEST_TIME 12 5797
TEST_TIME 13 5943
TEST_TIME 14 6271
TEST_TIME 15 6452
TEST_TIME 16 6714
TEST_TIME 17 7290
TEST_TIME 18 7702
TEST_TIME 19 7837
TEST_TIME 20 8312
TEST_TIME 21 8422
TEST_TIME 22 9119
TEST_TIME 23 9336
TEST_TIME 24 9231
TEST_TIME 25 9477
TEST_TIME 26 9840
TEST_TIME 27 9784
TEST_TIME 28 10426
TEST_TIME 29 11231
TEST_TIME 30 11014
TEST_TIME 31 10967
TEST_TIME 32 11080
TEST_TIME 33 10912
TEST_TIME 34 10971
TEST_TIME 35 10969
TEST_TIME 36 11064
TEST_TIME 37 10910
TEST_TIME 38 10699
TEST_TIME 39 10558
TEST_TIME 40 10481
TEST_TIME 41 10534
TEST_TIME 42 10380
TEST_TIME 43 10347
TEST_TIME 44 10321
TEST_TIME 45 10203
TEST_TIME 46 10240
TEST_TIME 47 10182
TEST_TIME 48 10169
TEST_TIME 49 10136
TEST_TIME 50 10136
TEST_TIME 51 10126
TEST_TIME 52 10050
TEST_TIME 53 10044
TEST_TIME 54 10173
TEST_TIME 55 10189
TEST_TIME 56 10149
TEST_TIME 57 10147
TEST_TIME 58 10120
TEST_TIME 59 10194
TEST_TIME 60 10212
TEST_TIME 61 10227
TEST_TIME 62 10272
TEST_TIME 63 10258
TEST_TIME 64 10259
TEST_TIME 65 10360
TEST_TIME 66 10285
TEST_TIME 67 10346
TEST_TIME 68 10236
TEST_TIME 69 10175
TEST_TIME 70 10107
TEST_TIME 71 10022
TEST_TIME 72 9997
TEST_TIME 73 9970
TEST_TIME 74 9952
TEST_TIME 75 9935
TEST_TIME 76 9883
TEST_TIME 77 9808
TEST_TIME 78 9765
TEST_TIME 79 9800
TEST_TIME 80 9803
TEST_TIME 81 9828
TEST_TIME 82 9817
TEST_TIME 83 9905
TEST_TIME 84 9930
TEST_TIME 85 9965
TEST_TIME 86 9932
TEST_TIME 87 9931
TEST_TIME 88 9954
TEST_TIME 89 9936
TEST_TIME 90 9977
TEST_TIME 91 9947
TEST_TIME 92 9862
TEST_TIME 93 9817
TEST_TIME 94 9789
TEST_TIME 95 9762
TEST_TIME 96 9743
TEST_TIME 97 9742
TEST_TIME 98 9695
TEST_TIME 99 9717
TEST_TIME 100 9665
TEST_TIME 101 9626
TEST_TIME 102 9596
TEST_TIME 103 9641
TEST_TIME 104 9609
TEST_TIME 105 9628
TEST_TIME 106 9609
TEST_TIME 107 9628
TEST_TIME 108 9626
TEST_TIME 109 9644
TEST_TIME 110 9717
TEST_TIME 111 9695
TEST_TIME 112 9723
TEST_TIME 113 9750
TEST_TIME 114 9760
TEST_TIME 115 9711
TEST_TIME 116 9643
TEST_TIME 117 9630
TEST_TIME 118 9591
TEST_TIME 119 9548
TEST_TIME 120 9546

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-08-2021 19:39:30 to 03-08-2021 19:39:33.

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 1578044 1578044 0 0.00 0 19185 6 0 0
doc_list_biud 1578042 1578042 0 0.00 0 75076 27 0 0
sp_add_invoice_to_stock 5213 5132 81 1.55 22 190663 16840 0 81
sp_cancel_adding_invoice 6098 6098 0 0.00 5 50350 710 0 0
sp_cancel_client_order 1836 1836 0 0.00 4 12670 348 0 0
sp_cancel_customer_reserve 587841 587841 0 0.00 1 26982 58 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 2 6239 83 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 3373 69 0 0
sp_cancel_supplier_invoice 6734 6732 2 0.02 2 75084 773 0 2
sp_cancel_supplier_order 422 422 0 0.00 8 1174006 102323 0 0
sp_cancel_write_off 11758 11758 0 0.00 1 23291 100 0 0
sp_client_order 21381 21381 0 0.00 3 35733 333 0 0
sp_customer_reserve 466411 466411 0 0.00 0 74050 169 0 0
sp_fill_shopping_cart 32827 32827 0 0.00 1 37211 37 0 0
sp_fill_shopping_cart_clo_res 466409 466409 0 0.00 0 12442 12 0 0
sp_get_clo_for_invoice 5128 5128 0 0.00 0 22334 267 0 0
sp_kill_cost_storno 18945 18945 0 0.00 0 6296 52 0 0
sp_kill_qstorno_ret_qs2qd 1194594 1194594 0 0.00 0 75072 31 0 0
sp_kill_qty_storno 630011 630011 0 0.00 0 75075 68 0 0
sp_lock_selected_doc 21239 21239 0 0.00 0 6426 4 0 0
sp_make_cost_storno 28552 28552 0 0.00 0 17404 52 0 0
sp_multiply_rows_for_pdistr 28552 28552 0 0.00 0 11432 27 0 0
sp_multiply_rows_for_qdistr 21381 21381 0 0.00 0 9210 139 0 0
sp_pay_from_customer 7198 7198 0 0.00 4 17403 108 0 0
sp_pay_to_supplier 6029 6029 0 0.00 5 18423 121 0 0
sp_payment_common 13227 13227 0 0.00 1 13473 27 0 0
sp_qd_handle_on_cancel_clo 1836 1836 0 0.00 0 12655 330 0 0
sp_qd_handle_on_invoice_upd_sts 11225 11225 0 0.00 0 23200 326 0 0
sp_qd_handle_on_reserve_upd_sts 10197 10197 0 0.00 0 18585 75 0 0
sp_reserve_write_off 10197 10197 0 0.00 6 18605 175 0 0
sp_supplier_invoice 5749 5749 0 0.00 6 94854 3343 0 0
sp_supplier_order 5704 5704 0 0.00 5 79340 1273 0 0
srv_aggregate_perf_data 95 95 0 0.00 2949 588259 23556 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1410 183959 45584 0 0
srv_find_qd_qs_mism 1096874 1096874 0 0.00 0 10301 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 45 28723 944 0 0
srv_make_money_saldo 877 877 0 0.00 1 1859 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 165699 429732 291188 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5132 5132 0 0.00 0 12378 162 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 13240 122 0 0
t$perf-norm:sp_cancel_client_order 1836 1836 0 0.00 0 1987 13 0 0
t$perf-norm:sp_cancel_customer_reserve 734 734 0 0.00 0 1603 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 988 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 3942 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 1762 19 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 20051 1641 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 4171 10 0 0
t$perf-norm:sp_client_order 21381 21381 0 0.00 0 6001 16 0 0
t$perf-norm:sp_customer_reserve 1072 1072 0 0.00 0 3742 10 0 0
t$perf-norm:sp_pay_from_customer 7198 7198 0 0.00 0 10641 15 0 0
t$perf-norm:sp_pay_to_supplier 6029 6029 0 0.00 0 3542 8 0 0
t$perf-norm:sp_reserve_write_off 10197 10197 0 0.00 0 4977 10 0 0
t$perf-norm:sp_supplier_invoice 5749 5749 0 0.00 0 8016 19 0 0
t$perf-norm:sp_supplier_order 5704 5704 0 0.00 0 7076 15 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 248 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 216 7 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 1430 20 0 0
t$perf-norm:srv_make_money_saldo 877 877 0 0.00 0 1068 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 6 0 0
v_add_invoice_to_stock 10416 10254 162 1.55 0 6209 17 0 162
v_all_customers 38808 38808 0 0.00 0 18 1 0 0
v_all_suppliers 31562 31562 0 0.00 0 26 1 0 0
v_all_wares 42762 42762 0 0.00 0 111 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 4412 12 0 0
v_cancel_client_order 3672 3672 0 0.00 0 4307 4 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 6225 13 0 0
v_cancel_customer_reserve 1468 1468 0 0.00 0 3428 6 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 3364 9 0 0
v_cancel_supplier_invoice 786 782 4 0.50 0 5622 21 0 4
v_cancel_supplier_order 844 844 0 0.00 0 952 6 0 0
v_cancel_write_off 2058 2058 0 0.00 0 2009 12 0 0
v_max_id_clo_ord 5704 5704 0 0.00 0 1801 2 0 0
v_max_id_clo_res 1072 1072 0 0.00 0 2560 13 0 0
v_max_id_ord_sup 5742 5742 0 0.00 0 5278 5 0 0
v_max_non_paid_invoice 1694 1694 0 0.00 0 1004 4 0 0
v_max_non_paid_realizn 3733 3733 0 0.00 0 1685 3 0 0
v_min_id_clo_ord 5704 5704 0 0.00 0 1138 3 0 0
v_min_id_clo_res 1072 1072 0 0.00 0 2508 5 0 0
v_min_id_ord_sup 5742 5742 0 0.00 0 7383 9 0 0
v_min_non_paid_invoice 6029 6029 0 0.00 0 11134 16 0 0
v_min_non_paid_realizn 7198 7198 0 0.00 0 12798 16 0 0
v_our_firm 10884 10884 0 0.00 0 15 1 0 0
v_reserve_write_off 20394 20394 0 0.00 0 6179 5 0 0
x_lock_depdocs_on_canc_invoice 12439 12439 0 0.00 0 9956 102 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 16886 357 0 0
x_make_qstorno_1000_1200 5704 5704 0 0.00 1 79328 1204 0 0
x_make_qstorno_1200_2000 5749 5749 0 0.00 3 90534 3204 0 0
x_make_qstorno_2100_3300 466411 466411 0 0.00 0 74048 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-08-2021 19:39:34 to 03-08-2021 19:39:38.

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
17:07:49 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:50 37745 8192000000 10358066976 10676600832 1405689216 14.646 100 87 174 1 1440527792 6055328 28783248
17:10:29 19640 8192000000 10422430224 10750513152 1472457984 15.235 100 84 168 0 1507679104 8027616 27193504
17:11:53 20975 8192000000 10472795632 10818842624 1532681744 15.760 100 79 158 0 1566471344 9182624 24606976
17:13:16 13445 8192000000 10584477584 10943393792 1637641792 16.660 100 74 148 0 1670273888 10842560 21789536
17:14:34 11625 8192000000 10646815872 11015053312 1708651504 17.257 100 83 164 1 1744945104 12782272 23511328
17:15:48 6854 8192000000 10689913600 11066679296 1754831472 17.642 100 67 134 1 1785845696 12444832 18569392
17:16:56 4234 8192000000 10731144560 11118080000 1798755376 18.004 100 71 142 2 1831556560 13530672 19270512
17:18:02 1869 8192000000 10786928720 11173097472 1846193536 18.391 100 84 167 0 1883801808 13790544 23817728
17:19:06 1979 8192000000 10813194864 11210362880 1877842160 18.648 100 86 172 1 1915157008 13739888 23574960
17:20:08 4520 8192000000 10798794688 11211628544 1877126672 18.642 100 38 76 3 1895405712 7871776 10407264
17:21:14 3122 8192000000 10804065392 11224330240 1887363552 18.725 100 30 60 3 1902241408 6733408 8144448
17:22:19 40890 8192000000 10817546672 11240353792 1892044400 18.762 100 43 86 1 1911478080 6322960 13110720
17:24:03 8394 8192000000 10810029824 11242807296 1895936608 18.794 100 18 36 1 1907967216 6205344 5825264
17:25:13 8515 8192000000 10826929504 11254276096 1907653264 18.888 100 25 49 5 1919940480 5085840 7201376
17:26:23 9160 8192000000 10814403520 11250774016 1888853280 18.737 99 14 28 0 1897769168 4764432 4151456
17:27:34 22788 8192000000 10803139632 11241119744 1871410720 18.596 98 23 45 3 1883824896 5687456 6726720
17:28:57 7690 8192000000 10749201104 11173056512 1771536944 17.780 95 33 66 1 1786800000 6137424 9125632
17:30:06 5660 8192000000 10715148704 11130568704 1737311936 17.496 96 28 56 0 1751341232 5941120 8088176
17:31:13 20379 8192000000 10724455744 11122659328 1743567200 17.548 96 15 29 1 1753824144 5477264 4779680
17:32:36 17096 8192000000 10713255040 11133198336 1741818640 17.534 96 10 20 0 1750479680 5511200 3149840
17:33:54 9408 8192000000 10745832560 11172147200 1818414480 18.165 99 25 50 1 1831322128 5730688 7176960
17:35:04 9096 8192000000 10770422576 11199827968 1859759184 18.501 100 30 56 0 1873896640 5468704 8668752
17:36:14 38159 8192000000 10784527360 11212681216 1869193968 18.578 100 23 46 1 1885929840 9479152 7256720
17:38:01 8750 8192000000 10841421120 11261902848 1909301840 18.901 100 38 76 2 1926081808 5624016 11155952
17:39:15 11356 8192000000 10864648304 11289714688 1930686992 19.072 100 43 84 0 1951843520 9306816 11849712
17:40:29 22648 8192000000 10890988288 11318120448 1950977488 19.234 100 46 91 1 1976223424 12019120 13226816
17:42:02 21705 8192000000 10897571424 11336335360 1966733040 19.360 100 40 80 1 1995088064 16514496 11840528
17:43:37 6514 8192000000 10932359056 11364220928 1984952480 19.504 100 47 94 2 2017735664 19108128 13675056
17:44:47 24606 8192000000 10939400016 11377160192 1979440512 19.460 99 50 99 0 2021019568 26351904 15227152
17:46:13 8649 8192000000 10979918336 11405148160 2013767520 19.731 100 68 136 4 2066155920 31999264 20389136
17:47:28 3663 8192000000 10974086464 11412561920 2015084448 19.742 100 51 100 1 2062374720 31897616 15392656
17:48:37 3420 8192000000 10988497600 11415912448 2021916512 19.795 100 70 138 1 2077253856 34371632 20965712
17:49:47 3982 8192000000 10975319712 11412230144 2012358848 19.720 100 46 92 1 2061323264 34340480 14623936
17:50:58 1591 8192000000 10994400672 11413893120 2018370528 19.767 100 87 174 0 2080280544 35968112 25941904
17:52:02 3512 8192000000 10984223888 11401064448 1996189088 19.593 100 72 143 0 2061313648 43173904 21950656
17:53:13 5497 8192000000 10991369936 11424149504 2014605312 19.738 100 71 142 1 2083011328 45629488 22776528
17:54:24 1405 8192000000 11000248096 11412697088 1995991472 19.591 100 92 183 1 2076284400 52403872 27889056
17:55:29 6753 8192000000 10930989424 11362664448 1940687008 19.152 99 44 88 1 1995584992 40260784 14637200
17:56:42 7940 8192000000 10922221200 11348488192 1930091840 19.068 99 50 100 1 1996803968 50125152 16586976
17:57:54 6187 8192000000 10944419056 11363811328 1931401296 19.078 99 53 106 0 2004554240 55209680 17943264
17:59:07 14679 8192000000 10935111024 11379625984 1949294448 19.221 100 43 86 2 2027172368 62261184 15616736
18:00:32 14934 8192000000 10920196752 11366318080 1957473040 19.286 100 33 67 0 2008501248 39847712 11180496
18:01:54 13882 8192000000 10910965632 11359088640 1940450560 19.150 100 31 62 2 1997795776 46896000 10449216
18:03:12 28460 8192000000 10914218976 11365875712 1942865744 19.170 100 23 46 4 2001915712 50756032 8293936
18:04:42 18800 8192000000 10912868064 11368927232 1933527968 19.095 100 15 30 0 1991490464 52384352 5578144
18:06:04 42668 8192000000 10828422416 11292733440 1852840464 18.445 96 10 20 2 1889667872 33740064 3087344
18:07:58 48327 8192000000 10881798512 11329245184 1927516400 19.047 99 24 48 3 1951432976 17018992 6897584
18:09:47 183959 8192000000 10884592624 11347349504 1821570736 18.191 93 16 30 2 1843144960 18164000 3410224
18:14:15 174850 8192000000 10879951184 11332096000 1917088064 18.964 98 34 68 2 1937974000 10251088 10634848
18:18:11 39541 8192000000 10896785136 11362156544 1968762928 19.376 100 29 58 2 1986331552 8882032 8686592
18:20:03 9020 8192000000 10909644288 11356442624 1961100848 19.315 100 34 68 0 1976449232 6659200 8689184
18:21:13 28479 8192000000 10929212064 11395166208 1995036512 19.584 100 17 34 0 2010245088 9955040 5253536
18:22:43 19713 8192000000 10950988416 11403198464 1991032000 19.552 99 52 103 1 2018822624 12935152 14855472
18:24:07 48944 8192000000 10938538000 11411496960 2004820816 19.661 100 14 28 1 2026356496 17215456 4320224
18:25:56 31349 8192000000 10944056400 11424702464 2011755600 19.715 100 15 30 0 2035532480 19110912 4665968
18:27:32 47113 8192000000 10944991936 11423891456 1990202784 19.545 99 20 40 0 2022376480 25745296 6428400
18:29:29 50881 8192000000 10910109024 11404906496 1968176848 19.371 99 17 33 0 1991482480 18866832 4438800
18:31:24 83025 8192000000 10933331504 11418841088 1965429328 19.349 98 11 22 0 1986430224 17568224 3432672
18:34:42 35917 8192000000 10906750528 11362971648 1891119712 18.755 95 19 38 0 1903425456 6817120 5488624
18:36:19 66491 8192000000 10915814816 11380756480 1929066000 19.059 97 19 38 0 1942077248 8465536 4545712
18:38:27 59729 8192000000 10878943808 11341172736 1860753120 18.509 94 9 18 0 1874424880 11473088 2198672
18:40:28 73639 8192000000 10920410240 11390222336 1920595872 18.992 97 17 34 2 1940175168 14334304 5244992
18:42:49 39298 8192000000 10929026656 11367649280 1924573120 19.023 97 49 96 2 1950237424 10981648 14682656
18:44:31 133462 8192000000 10901904656 11373043712 1955519216 19.270 100 21 42 0 1979766992 18141232 6106544
18:47:49 45726 8192000000 10845382928 11303628800 1869776272 18.582 97 19 37 1 1888473840 13694688 5002880
18:49:34 53573 8192000000 10847823648 11315003392 1887885952 18.729 98 16 32 1 1905116768 12460016 4770800
18:51:28 74182 8192000000 10855021536 11338072064 1880921264 18.673 97 17 34 1 1902087792 15843600 5322928
18:53:44 77823 8192000000 10872278896 11343020032 1907076432 18.883 98 15 30 0 1922846000 11486448 4283120
18:56:26 58908 8192000000 10873266336 11355054080 1941946128 19.162 100 15 30 0 1960425824 13697008 4782688
18:58:32 31860 8192000000 10868392960 11342188544 1897594576 18.807 97 23 47 0 1919669696 14844256 7230864
19:00:04 61392 8192000000 10878823568 11368505344 1921717200 19.001 99 14 28 0 1949071744 22837616 4516928
19:02:07 55497 8192000000 10879275904 11349528576 1942584928 19.167 100 10 20 0 1961518016 16161312 2771776
19:04:10 44902 8192000000 10910576784 11369414656 1965882464 19.353 100 31 62 2 1985867280 11258176 8726640
19:06:01 35564 8192000000 10917048128 11372711936 1956076016 19.275 99 45 90 1 1980968496 12148832 12743648
19:07:51 39815 8192000000 10940183376 11393671168 1972663488 19.407 99 40 78 1 1994373024 9117472 12592064
19:09:32 110666 8192000000 10933955008 11403186176 1999696256 19.620 100 35 70 2 2018352224 8412608 10243360
19:13:01 65269 8192000000 10904155456 11399868416 1972842832 19.408 99 11 22 2 1989908144 13601872 3463440
19:15:26 118667 8192000000 10910258400 11396100096 1956517568 19.278 98 25 50 1 1974306512 10829264 6959680
19:18:34 82726 8192000000 10910302720 11400634368 1966635520 19.359 99 11 22 0 1982146768 12639280 2871968
19:21:09 39226 8192000000 10900733488 11370196992 1912709184 18.928 96 34 68 2 1932732784 10255616 9767984
19:22:49 36947 8192000000 10906654016 11374108672 1945289104 19.189 98 22 44 2 1961258624 10149680 5819840
19:24:27 37063 8192000000 10893070368 11360845824 1913476464 18.935 97 20 40 0 1932799072 13727392 5595216
19:26:05 82923 8192000000 10873111648 11357929472 1859298880 18.498 94 17 33 2 1881292512 17037120 4956512
19:28:28 59055 8192000000 10887562784 11349028864 1887218592 18.723 96 30 59 3 1907268032 11393440 8656000
19:30:27 149800 8192000000 10885041824 11366457344 1908650160 18.896 97 10 20 3 1926532512 15030624 2851728
19:34:10 48637 8192000000 10890934448 11362951168 1895867056 18.793 96 21 43 0 1911004032 9218512 5918464
19:35:59 51737 8192000000 10878859520 11360428032 1920015712 18.987 98 9 19 0 1935330208 12481296 2833200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-08-2021 19:39:40 to 03-08-2021 19:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-08-2021 19:39:56 to 03-08-2021 19:39:58.

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          241594
MON$OLDEST_ACTIVE               243545
MON$OLDEST_SNAPSHOT             243545
MON$NEXT_TRANSACTION            243545
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                       2047392
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:87: 
:88: mscs-test-161.budzdorov.ru    Tue Aug  3 19:37:48 2021
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:90: 
:91: 
:92: mscs-test-161.budzdorov.ru    Tue Aug  3 19:37:54 2021
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:94: 
:95: 
:96: mscs-test-161.budzdorov.ru    Tue Aug  3 19:37:54 2021
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:98: 
--- end of diff output ---

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

03.08.21 19:39:59. 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-03 19:40:01 No severe PSQL-related problems occured
03.08.21 19:40:02. End of report.