Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.08.2021 01: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 16142148 kB
MemAvailable 30910724 kB
Buffers 0 kB
Cached 15512076 kB
SwapCached 1320 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-27 01:37:49
DTS_END                         2021-08-27 01:37:49.3480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41626
TRN_ID                          246751
ATT_ID                          726
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: 9465.52 21874 1135862
customer order: creation 179.75 357 21570
customer order: refuse 15.25 367 1830
order to supplier: creation 47.26 1278 5671
order to supplier: removal 3.63 102601 436
invoice (draft): creation 47.65 3337 5718
invoice (draft): removal 54.48 784 6537
invoice accept: apply 42.98 17567 5157
invoice accept: cancel 48.68 758 5842
customer reserve: creation 3940.99 174 472919
customer reserve: removal 4778.63 61 573435
realization accept: apply 85.76 180 10291
realization accept: cancel 86.93 106 10431
payment from customer: creation 60.88 115 7306
payment from customer: removal 5.84 119 701
payment to supplier: creation 50.62 129 6074
payment to supplier: removal 3.67 87 440
service: total inventory turnovers 4.75 903 570
service: total monetary turnovers 6.90 23 828
service: aggregate perf. data 0.85 18856 102
service: refresh index statistics 0.03 289671 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-08-2021 01:38:57 to 27-08-2021 01:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1322
TEST_TIME 2 2198
TEST_TIME 3 3538
TEST_TIME 4 3766
TEST_TIME 5 3805
TEST_TIME 6 4246
TEST_TIME 7 4170
TEST_TIME 8 4176
TEST_TIME 9 4792
TEST_TIME 10 5007
TEST_TIME 11 5349
TEST_TIME 12 5417
TEST_TIME 13 6009
TEST_TIME 14 6210
TEST_TIME 15 6620
TEST_TIME 16 7094
TEST_TIME 17 7946
TEST_TIME 18 7860
TEST_TIME 19 7819
TEST_TIME 20 7738
TEST_TIME 21 8575
TEST_TIME 22 8469
TEST_TIME 23 8688
TEST_TIME 24 9160
TEST_TIME 25 9988
TEST_TIME 26 10059
TEST_TIME 27 10582
TEST_TIME 28 11178
TEST_TIME 29 11012
TEST_TIME 30 10783
TEST_TIME 31 10517
TEST_TIME 32 10276
TEST_TIME 33 10762
TEST_TIME 34 10668
TEST_TIME 35 10499
TEST_TIME 36 10409
TEST_TIME 37 10282
TEST_TIME 38 10200
TEST_TIME 39 10142
TEST_TIME 40 10227
TEST_TIME 41 10315
TEST_TIME 42 10192
TEST_TIME 43 10074
TEST_TIME 44 9890
TEST_TIME 45 9942
TEST_TIME 46 9871
TEST_TIME 47 9873
TEST_TIME 48 9737
TEST_TIME 49 9737
TEST_TIME 50 9671
TEST_TIME 51 9766
TEST_TIME 52 9727
TEST_TIME 53 9734
TEST_TIME 54 9678
TEST_TIME 55 9781
TEST_TIME 56 9868
TEST_TIME 57 9932
TEST_TIME 58 9861
TEST_TIME 59 9876
TEST_TIME 60 9972
TEST_TIME 61 10013
TEST_TIME 62 10065
TEST_TIME 63 9972
TEST_TIME 64 9858
TEST_TIME 65 9845
TEST_TIME 66 9787
TEST_TIME 67 9710
TEST_TIME 68 9628
TEST_TIME 69 9631
TEST_TIME 70 9631
TEST_TIME 71 9748
TEST_TIME 72 9716
TEST_TIME 73 9675
TEST_TIME 74 9641
TEST_TIME 75 9660
TEST_TIME 76 9754
TEST_TIME 77 9722
TEST_TIME 78 9800
TEST_TIME 79 9858
TEST_TIME 80 9855
TEST_TIME 81 9836
TEST_TIME 82 9798
TEST_TIME 83 9769
TEST_TIME 84 9699
TEST_TIME 85 9673
TEST_TIME 86 9673
TEST_TIME 87 9612
TEST_TIME 88 9599
TEST_TIME 89 9531
TEST_TIME 90 9460
TEST_TIME 91 9528
TEST_TIME 92 9490
TEST_TIME 93 9564
TEST_TIME 94 9541
TEST_TIME 95 9527
TEST_TIME 96 9571
TEST_TIME 97 9590
TEST_TIME 98 9615
TEST_TIME 99 9659
TEST_TIME 100 9689
TEST_TIME 101 9724
TEST_TIME 102 9691
TEST_TIME 103 9686
TEST_TIME 104 9715
TEST_TIME 105 9751
TEST_TIME 106 9697
TEST_TIME 107 9636
TEST_TIME 108 9590
TEST_TIME 109 9568
TEST_TIME 110 9569
TEST_TIME 111 9534
TEST_TIME 112 9491
TEST_TIME 113 9463
TEST_TIME 114 9459
TEST_TIME 115 9468
TEST_TIME 116 9481
TEST_TIME 117 9466
TEST_TIME 118 9497
TEST_TIME 119 9485
TEST_TIME 120 9466

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-08-2021 01:39:01 to 27-08-2021 01:39:04.

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 1574098 1574098 0 0.00 0 24541 6 0 0
doc_list_biud 1574098 1574098 0 0.00 0 46572 28 0 0
sp_add_invoice_to_stock 5285 5157 128 2.42 18 179342 17568 0 128
sp_cancel_adding_invoice 5842 5842 0 0.00 5 52801 758 0 0
sp_cancel_client_order 1830 1830 0 0.00 4 24579 367 0 0
sp_cancel_customer_reserve 573435 573435 0 0.00 1 26216 62 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 2 8927 119 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 5272 87 0 0
sp_cancel_supplier_invoice 6548 6537 11 0.16 1 46576 784 0 11
sp_cancel_supplier_order 436 436 0 0.00 8 993896 102602 0 0
sp_cancel_write_off 10431 10431 0 0.00 1 7744 107 0 0
sp_client_order 21570 21570 0 0.00 4 64711 358 0 0
sp_customer_reserve 472919 472919 0 0.00 0 41035 175 0 0
sp_fill_shopping_cart 32938 32938 0 0.00 1 12938 35 0 0
sp_fill_shopping_cart_clo_res 472915 472915 0 0.00 0 26388 16 0 0
sp_get_clo_for_invoice 5153 5153 0 0.00 0 44078 327 0 0
sp_kill_cost_storno 17414 17414 0 0.00 0 16939 55 0 0
sp_kill_qstorno_ret_qs2qd 1164274 1164274 0 0.00 0 46570 33 0 0
sp_kill_qty_storno 613955 613955 0 0.00 0 46571 71 0 0
sp_lock_selected_doc 21388 21388 0 0.00 0 7076 8 0 0
sp_make_cost_storno 28824 28824 0 0.00 0 16241 50 0 0
sp_multiply_rows_for_pdistr 28824 28824 0 0.00 0 15916 29 0 0
sp_multiply_rows_for_qdistr 21570 21570 0 0.00 0 64701 162 0 0
sp_pay_from_customer 7306 7306 0 0.00 4 13600 116 0 0
sp_pay_to_supplier 6074 6074 0 0.00 5 21206 130 0 0
sp_payment_common 13380 13380 0 0.00 1 11310 30 0 0
sp_qd_handle_on_cancel_clo 1830 1830 0 0.00 0 24573 325 0 0
sp_qd_handle_on_invoice_upd_sts 10995 10995 0 0.00 0 16021 314 0 0
sp_qd_handle_on_reserve_upd_sts 10291 10291 0 0.00 0 15702 59 0 0
sp_reserve_write_off 10291 10291 0 0.00 7 25946 181 0 0
sp_supplier_invoice 5718 5718 0 0.00 6 112134 3338 0 0
sp_supplier_order 5671 5671 0 0.00 5 29877 1279 0 0
srv_aggregate_perf_data 102 102 0 0.00 2445 237353 18857 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1749 134384 44140 0 0
srv_find_qd_qs_mism 1086777 1086777 0 0.00 0 24539 5 0 0
srv_make_invnt_saldo 570 570 0 0.00 28 34378 903 0 0
srv_make_money_saldo 828 828 0 0.00 1 1229 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 209308 340296 289671 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5157 5157 0 0.00 0 16827 163 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 6031 77 0 0
t$perf-norm:sp_cancel_client_order 1830 1830 0 0.00 0 2407 10 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 427 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 4414 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 1749 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 1502 13 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 20660 1345 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 1716 8 0 0
t$perf-norm:sp_client_order 21570 21570 0 0.00 0 6333 16 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 2416 7 0 0
t$perf-norm:sp_pay_from_customer 7306 7306 0 0.00 0 4188 8 0 0
t$perf-norm:sp_pay_to_supplier 6074 6074 0 0.00 0 6379 12 0 0
t$perf-norm:sp_reserve_write_off 10291 10291 0 0.00 0 13342 12 0 0
t$perf-norm:sp_supplier_invoice 5718 5718 0 0.00 0 9322 14 0 0
t$perf-norm:sp_supplier_order 5671 5671 0 0.00 0 4346 14 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 543 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1197 24 0 0
t$perf-norm:srv_make_invnt_saldo 570 570 0 0.00 0 1071 17 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 661 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 27 9 0 0
v_add_invoice_to_stock 10562 10306 256 2.42 0 16511 19 0 256
v_all_customers 39546 39546 0 0.00 0 12 1 0 0
v_all_suppliers 31756 31756 0 0.00 0 26 1 0 0
v_all_wares 43140 43140 0 0.00 0 80 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 25595 35 0 0
v_cancel_client_order 3660 3660 0 0.00 0 17527 10 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 7420 18 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 1889 9 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 4159 8 0 0
v_cancel_supplier_invoice 842 820 22 2.61 0 4652 23 0 22
v_cancel_supplier_order 872 872 0 0.00 0 1771 6 0 0
v_cancel_write_off 2056 2056 0 0.00 0 2151 13 0 0
v_max_id_clo_ord 5669 5669 0 0.00 0 531 1 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 2608 19 0 0
v_max_id_ord_sup 5699 5699 0 0.00 0 5410 4 0 0
v_max_non_paid_invoice 1564 1564 0 0.00 0 2018 4 0 0
v_max_non_paid_realizn 3668 3668 0 0.00 0 4423 7 0 0
v_min_id_clo_ord 5669 5669 0 0.00 0 1919 5 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 2341 5 0 0
v_min_id_ord_sup 5699 5699 0 0.00 0 4252 8 0 0
v_min_non_paid_invoice 6074 6074 0 0.00 0 5712 16 0 0
v_min_non_paid_realizn 7306 7306 0 0.00 0 8866 19 0 0
v_our_firm 10870 10870 0 0.00 0 24 1 0 0
v_reserve_write_off 20582 20582 0 0.00 0 25474 8 0 0
x_lock_depdocs_on_canc_invoice 11969 11969 0 0.00 0 12539 127 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 20968 409 0 0
x_make_qstorno_1000_1200 5671 5671 0 0.00 2 29855 1210 0 0
x_make_qstorno_1200_2000 5718 5718 0 0.00 2 112112 3206 0 0
x_make_qstorno_2100_3300 472919 472919 0 0.00 0 37201 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-08-2021 01:39:05 to 27-08-2021 01:39:09.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:07:53 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:53 26318 8192000000 10371606864 10687586304 1423089280 14.800 100 88 176 0 1458378480 6408064 28881136
23:10:29 22111 8192000000 10439675520 10770554880 1490078544 15.390 100 86 172 0 1526450592 8312752 28059296
23:11:56 16925 8192000000 10483035376 10824404992 1541778416 15.839 100 88 177 1 1579202688 9775472 27648800
23:13:20 11424 8192000000 10563687312 10922184704 1617583008 16.489 100 81 162 2 1652271376 10448000 24240368
23:14:39 8750 8192000000 10626289376 10995707904 1691088624 17.110 100 79 158 3 1726373968 12437648 22847696
23:15:50 5923 8192000000 10698826944 11074596864 1756730592 17.657 100 79 157 2 1791239808 12463152 22046064
23:16:58 2597 8192000000 10745717584 11123404800 1806690208 18.069 100 88 173 1 1844904400 14571456 23642736
23:18:03 2100 8192000000 10786499808 11179974656 1853372720 18.450 100 87 173 3 1892580048 14671536 24535792
23:19:06 2217 8192000000 10818513312 11205419008 1871879712 18.599 100 89 178 1 1907106624 9915952 25310960
23:20:08 3953 8192000000 10813420704 11212857344 1878511376 18.653 100 54 109 1 1901384624 6588096 16285152
23:21:14 23442 8192000000 10811313856 11215708160 1885064512 18.706 100 21 42 1 1896175264 5283968 5826784
23:22:39 33118 8192000000 10804277424 11241177088 1892456320 18.766 100 28 56 3 1906165456 5655296 8053840
23:24:14 6169 8192000000 10828244016 11256246272 1905556464 18.871 100 30 60 1 1921047504 6786192 8704848
23:25:20 4572 8192000000 10825762480 11262439424 1913107344 18.932 100 25 49 2 1926884960 6954880 6822736
23:26:25 9584 8192000000 10800888352 11235831808 1862374160 18.523 98 16 32 2 1872326464 5212512 4739792
23:27:36 34128 8192000000 10826183104 11247095808 1854249728 18.457 97 51 101 3 1874391680 5430368 14711584
23:29:11 3656 8192000000 10748227936 11163525120 1767080592 17.743 96 31 62 2 1782731488 5951568 9699328
23:30:15 4666 8192000000 10715456544 11126218752 1782922192 17.874 99 34 65 0 1799424864 6883136 9619536
23:31:24 9050 8192000000 10711984160 11134959616 1731041568 17.444 95 16 32 1 1740954432 5279040 4633824
23:32:35 35043 8192000000 10736745328 11145805824 1749991744 17.602 96 33 66 4 1765317536 6066544 9259248
23:34:13 8502 8192000000 10778956512 11196039168 1845048144 18.382 100 23 46 0 1861553472 9619680 6885648
23:35:22 7942 8192000000 10774953392 11205615616 1860893408 18.511 100 15 29 1 1870164496 5432496 3838592
23:36:30 8501 8192000000 10790717904 11222867968 1871617280 18.597 100 21 42 2 1883111040 5382288 6111472
23:37:40 108687 8192000000 10852736384 11283431424 1918393392 18.974 100 39 79 1 1937656592 8047776 11215424
23:40:33 17366 8192000000 10885889232 11308920832 1943330480 19.173 100 42 84 0 1968884288 13527616 12026192
23:41:54 15607 8192000000 10894950656 11325739008 1959736576 19.304 100 48 94 1 1989187792 15524064 13927152
23:43:13 45777 8192000000 10914042448 11353145344 1975862672 19.432 100 27 54 1 2003038960 17876800 9299488
23:45:04 8689 8192000000 10946981472 11376398336 1997196384 19.601 100 56 112 0 2037363008 23726480 16440144
23:46:23 1748 8192000000 10956351440 11370188800 1987035728 19.520 99 73 147 0 2037611744 28562416 22013600
23:47:29 10920 8192000000 10974251504 11401773056 2001691504 19.636 100 61 118 1 2056950576 35732544 19526528
23:48:55 3972 8192000000 10960178096 11381145600 1965168064 19.347 98 61 122 1 2013206736 29693424 18345248
23:50:02 7050 8192000000 10954016736 11384201216 1986084864 19.513 100 56 108 4 2038922016 35901824 16935328
23:51:12 2227 8192000000 10978241424 11392897024 1997201552 19.601 100 86 174 1 2059094992 35515008 26378432
23:52:20 4338 8192000000 10977285952 11400433664 1994838160 19.582 100 71 142 1 2058033264 41217056 21978048
23:53:30 2851 8192000000 10990645520 11411292160 1998887520 19.614 100 76 152 0 2071493904 48710608 23895776
23:54:38 10437 8192000000 10970813232 11408211968 1998557472 19.611 100 49 98 0 2053022096 38500880 15963744
23:55:56 10129 8192000000 10949124448 11398512640 1985464400 19.508 100 42 84 1 2037204240 38165456 13574384
23:57:20 3203 8192000000 10963560288 11392696320 1969853152 19.384 99 61 122 0 2035448592 46264736 19330704
23:58:34 8387 8192000000 10999916304 11440263168 2003491760 19.650 100 51 102 1 2083803744 62874256 17437728
23:59:54 16791 8192000000 10962307344 11409346560 1983989568 19.496 100 40 79 1 2046436704 48098080 14349056
00:01:21 22440 8192000000 10950476800 11402760192 1969828752 19.384 100 33 66 2 2034193056 52351328 12012976
00:02:46 34325 8192000000 10918643504 11382358016 1960309744 19.309 100 25 50 2 2001274368 32896672 8067952
00:04:23 53981 8192000000 10862249120 11363266560 1862576144 18.524 96 11 22 0 1908615344 42057024 3982176
00:06:22 60336 8192000000 10864763104 11301859328 1881410272 18.676 97 31 62 0 1905577232 15865040 8301920
00:08:29 88290 8192000000 10906275312 11345555456 1941968432 19.162 99 39 78 0 1970530896 17094128 11468336
00:11:02 38151 8192000000 10886606880 11339481088 1919003520 18.979 98 25 48 1 1935610512 9967760 6639232
00:12:41 32749 8192000000 10874097584 11335036928 1919909856 18.986 98 11 22 0 1934275376 11357872 3007648
00:14:17 40033 8192000000 10904530240 11350282240 1905351904 18.869 96 38 76 2 1929265248 12982224 10931120
00:16:03 49644 8192000000 10901139424 11374432256 1971934096 19.401 100 18 36 0 1996194432 18774720 5485616
00:18:20 52882 8192000000 10877726016 11337576448 1908430256 18.894 97 13 26 1 1918280912 5561344 4289312
00:20:14 39853 8192000000 10897734784 11364712448 1975110160 19.426 100 16 32 0 1988448080 9426032 3911888
00:21:55 88219 8192000000 10891907616 11365138432 1960632832 19.311 99 25 50 1 1983109200 15460096 7016272
00:24:40 31256 8192000000 10898275104 11377238016 1988108784 19.529 100 20 40 1 2001569088 7693088 5767216
00:26:21 49226 8192000000 10897527072 11380776960 1979534752 19.461 100 4 8 0 1989902624 9421648 946224
00:28:10 61874 8192000000 10906866464 11383046144 1969581168 19.382 99 14 28 0 1986043216 12151056 4310992
00:30:17 43348 8192000000 10912129568 11389288448 1976002000 19.433 99 33 66 1 1999601376 13742496 9856880
00:32:03 68654 8192000000 10925767120 11401699328 1976471776 19.437 99 20 40 0 1998361376 15607584 6282016
00:34:12 25159 8192000000 10893015072 11359035392 1963280432 19.332 99 18 36 1 1977018224 8680512 5057280
00:35:44 35190 8192000000 10887632944 11350355968 1927182928 19.044 98 31 62 1 1948827520 13085632 8558960
00:37:24 41269 8192000000 10881971648 11337846784 1923025248 19.011 99 12 24 1 1939967984 14277744 2664992
00:40:11 110431 8192000000 10887387520 11341926400 1871779056 18.599 95 33 66 0 1898352528 16876336 9697136
00:43:02 43281 8192000000 10832424240 11302309888 1870254560 18.586 97 19 38 3 1882868224 7732688 4880976
00:44:56 82763 8192000000 10877111936 11336409088 1903900592 18.858 98 16 32 1 1921813024 12661904 5250528
00:47:19 59227 8192000000 10827432432 11306541056 1903927840 18.858 98 25 50 0 1925876784 14624016 7324928
00:49:19 48412 8192000000 10856329680 11303165952 1875551120 18.629 97 25 50 1 1889712064 7571232 6589712
00:51:09 60486 8192000000 10889768880 11344785408 1913098720 18.932 98 34 68 1 1942674304 19834208 9741376
00:53:35 106927 8192000000 10837977552 11317149696 1814868784 18.136 93 18 36 1 1832940672 13181248 4890640
00:56:22 59520 8192000000 10849772480 11303038976 1865913744 18.551 97 21 42 1 1888735376 16811584 6010048
00:58:24 15308 8192000000 10880537344 11338469376 1943047776 19.171 100 22 44 0 1958126400 9132608 5946016
00:59:40 134384 8192000000 10862560720 11334471680 1926082832 19.036 99 27 54 0 1943221872 9913872 7225168
01:02:55 43141 8192000000 10828705760 11315757056 1906742960 18.880 99 12 24 0 1920084224 9666064 3675200
01:04:45 52481 8192000000 10858974064 11338006528 1929159376 19.060 99 9 18 1 1940146032 7784912 3201744
01:06:39 66919 8192000000 10891616352 11363815424 1968784448 19.376 100 23 46 0 1988712848 13655392 6273008
01:08:52 49461 8192000000 10896048272 11378753536 1956461952 19.278 99 15 30 1 1977720848 17452336 3806560
01:10:44 63710 8192000000 10905917808 11377016832 1923802944 19.017 97 18 36 0 1941907024 13363744 4740336
01:12:48 49258 8192000000 10910658496 11394637824 1965457952 19.349 99 15 30 2 1988471600 18487312 4526336
01:14:38 38980 8192000000 10915449488 11382345728 1949589744 19.223 98 28 56 1 1973519200 15124448 8805008
01:16:21 45736 8192000000 10921543904 11397713920 1989534624 19.540 100 21 42 1 2009118928 13614592 5969712
01:18:07 56131 8192000000 10911555600 11389370368 1936597232 19.120 98 20 39 2 1954783248 12613184 5572832
01:20:06 35770 8192000000 10921361296 11380805632 1963624944 19.335 99 39 79 0 1986199872 11608464 10966464
01:21:42 57776 8192000000 10880690272 11363692544 1858299664 18.489 94 14 28 0 1876196400 14084368 3812368
01:23:43 105839 8192000000 10858363728 11333570560 1862216160 18.521 95 20 39 3 1874938800 7495984 5226656
01:26:36 28208 8192000000 10853495520 11323731968 1901479744 18.838 98 29 56 0 1917313296 7649408 8184144
01:28:04 42273 8192000000 10868490752 11346345984 1931787056 19.081 99 17 34 1 1945166304 8754608 4624640
01:29:47 46615 8192000000 10883960864 11365392384 1960289936 19.308 100 8 16 0 1975836496 13034496 2512064
01:31:41 59793 8192000000 10906200048 11387899904 1969358384 19.380 100 3 6 0 1985200624 15069024 773216
01:33:44 39786 8192000000 10871204176 11350937600 1908847824 18.897 97 16 32 0 1921502752 8410640 4244288
01:35:27 41356 8192000000 10864646352 11328315392 1855361536 18.466 95 22 43 0 1870895088 9583088 5950464
01:37:08 34140 8192000000 10847944512 11301343232 1901210624 18.836 99 44 88 1 1926729824 12791392 12727808

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-08-2021 01:39:11 to 27-08-2021 01:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

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

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          244641
MON$OLDEST_ACTIVE               246862
MON$OLDEST_SNAPSHOT             246862
MON$NEXT_TRANSACTION            246862
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                       2032032
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 ---
:565: 
:566: mscs-test-161.budzdorov.ru    Fri Aug 27 01:37:53 2021
:567:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:568: 
:569: 
:570: mscs-test-161.budzdorov.ru    Fri Aug 27 01:37:55 2021
:571:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:572: 
:573: 
:574: mscs-test-161.budzdorov.ru    Fri Aug 27 01:37:58 2021
:575:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:576: 
:577: 
:578: mscs-test-161.budzdorov.ru    Fri Aug 27 01:38:01 2021
:579:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:580: 
:581: 
:582: mscs-test-161.budzdorov.ru    Fri Aug 27 01:38:02 2021
:583:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:584: 
:585: 
:586: mscs-test-161.budzdorov.ru    Fri Aug 27 01:38:03 2021
:587:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:588: 
--- end of diff output ---

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

27.08.21 01:39:30. 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-27 01:39:32 No severe PSQL-related problems occured
27.08.21 01:39:33. End of report.