Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.09.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 16060316 kB
MemAvailable 30908936 kB
Buffers 0 kB
Cached 15622904 kB
SwapCached 336 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-09-01 01:37:44
DTS_END                         2021-09-01 01:37:44.4760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35812
TRN_ID                          242817
ATT_ID                          717
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: 9293.25 19906 1115190
customer order: creation 174.29 335 20915
customer order: refuse 14.40 366 1728
order to supplier: creation 45.07 1277 5408
order to supplier: removal 3.40 107603 408
invoice (draft): creation 46.43 3811 5571
invoice (draft): removal 52.61 826 6313
invoice accept: apply 41.91 19198 5029
invoice accept: cancel 47.26 815 5671
customer reserve: creation 3835.73 192 460287
customer reserve: removal 4731.90 60 567828
realization accept: apply 85.05 179 10206
realization accept: cancel 84.21 118 10105
payment from customer: creation 59.64 105 7157
payment from customer: removal 5.86 75 703
payment to supplier: creation 49.93 127 5991
payment to supplier: removal 3.65 90 438
service: total inventory turnovers 4.54 1027 545
service: total monetary turnovers 6.51 44 781
service: aggregate perf. data 0.85 18258 102
service: refresh index statistics 0.03 243613 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-09-2021 01:39:07 to 01-09-2021 01:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5809
TEST_TIME 2 4887
TEST_TIME 3 4623
TEST_TIME 4 4271
TEST_TIME 5 4899
TEST_TIME 6 4897
TEST_TIME 7 5396
TEST_TIME 8 5487
TEST_TIME 9 5334
TEST_TIME 10 5307
TEST_TIME 11 5174
TEST_TIME 12 5181
TEST_TIME 13 5255
TEST_TIME 14 5609
TEST_TIME 15 5771
TEST_TIME 16 5932
TEST_TIME 17 6329
TEST_TIME 18 6500
TEST_TIME 19 7120
TEST_TIME 20 7135
TEST_TIME 21 7482
TEST_TIME 22 7761
TEST_TIME 23 7808
TEST_TIME 24 8250
TEST_TIME 25 8542
TEST_TIME 26 9245
TEST_TIME 27 9439
TEST_TIME 28 9445
TEST_TIME 29 9781
TEST_TIME 30 10430
TEST_TIME 31 10457
TEST_TIME 32 10216
TEST_TIME 33 10456
TEST_TIME 34 10389
TEST_TIME 35 10261
TEST_TIME 36 10122
TEST_TIME 37 9991
TEST_TIME 38 9933
TEST_TIME 39 9885
TEST_TIME 40 9812
TEST_TIME 41 9852
TEST_TIME 42 9737
TEST_TIME 43 9707
TEST_TIME 44 9614
TEST_TIME 45 9695
TEST_TIME 46 9623
TEST_TIME 47 9673
TEST_TIME 48 9583
TEST_TIME 49 9408
TEST_TIME 50 9617
TEST_TIME 51 9607
TEST_TIME 52 9619
TEST_TIME 53 9595
TEST_TIME 54 9579
TEST_TIME 55 9674
TEST_TIME 56 9660
TEST_TIME 57 9705
TEST_TIME 58 9674
TEST_TIME 59 9800
TEST_TIME 60 9873
TEST_TIME 61 9817
TEST_TIME 62 9892
TEST_TIME 63 9924
TEST_TIME 64 9790
TEST_TIME 65 9727
TEST_TIME 66 9689
TEST_TIME 67 9594
TEST_TIME 68 9577
TEST_TIME 69 9548
TEST_TIME 70 9490
TEST_TIME 71 9582
TEST_TIME 72 9533
TEST_TIME 73 9513
TEST_TIME 74 9541
TEST_TIME 75 9557
TEST_TIME 76 9578
TEST_TIME 77 9537
TEST_TIME 78 9533
TEST_TIME 79 9580
TEST_TIME 80 9622
TEST_TIME 81 9632
TEST_TIME 82 9612
TEST_TIME 83 9508
TEST_TIME 84 9516
TEST_TIME 85 9441
TEST_TIME 86 9421
TEST_TIME 87 9438
TEST_TIME 88 9421
TEST_TIME 89 9401
TEST_TIME 90 9381
TEST_TIME 91 9329
TEST_TIME 92 9295
TEST_TIME 93 9321
TEST_TIME 94 9347
TEST_TIME 95 9319
TEST_TIME 96 9379
TEST_TIME 97 9444
TEST_TIME 98 9410
TEST_TIME 99 9386
TEST_TIME 100 9387
TEST_TIME 101 9479
TEST_TIME 102 9532
TEST_TIME 103 9506
TEST_TIME 104 9535
TEST_TIME 105 9541
TEST_TIME 106 9589
TEST_TIME 107 9541
TEST_TIME 108 9469
TEST_TIME 109 9434
TEST_TIME 110 9442
TEST_TIME 111 9423
TEST_TIME 112 9386
TEST_TIME 113 9384
TEST_TIME 114 9384
TEST_TIME 115 9338
TEST_TIME 116 9314
TEST_TIME 117 9275
TEST_TIME 118 9318
TEST_TIME 119 9314
TEST_TIME 120 9296

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-09-2021 01:39:11 to 01-09-2021 01:39:14.

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 1543324 1543324 0 0.00 0 31496 6 0 0
doc_list_biud 1543324 1543324 0 0.00 0 34166 28 0 0
sp_add_invoice_to_stock 5083 5029 54 1.06 37 183767 19199 0 54
sp_cancel_adding_invoice 5671 5671 0 0.00 5 61381 815 0 0
sp_cancel_client_order 1728 1728 0 0.00 4 16861 367 0 0
sp_cancel_customer_reserve 567828 567828 0 0.00 1 34167 60 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 3571 75 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 8439 91 0 0
sp_cancel_supplier_invoice 6315 6313 2 0.03 1 13005 827 0 2
sp_cancel_supplier_order 408 408 0 0.00 6 1303527 107603 0 0
sp_cancel_write_off 10105 10105 0 0.00 1 13843 119 0 0
sp_client_order 20915 20915 0 0.00 4 27896 336 0 0
sp_customer_reserve 460287 460287 0 0.00 0 77536 192 0 0
sp_fill_shopping_cart 31874 31874 0 0.00 1 11236 41 0 0
sp_fill_shopping_cart_clo_res 460278 460278 0 0.00 0 12832 14 0 0
sp_get_clo_for_invoice 5020 5020 0 0.00 0 22797 312 0 0
sp_kill_cost_storno 16917 16917 0 0.00 0 10970 61 0 0
sp_kill_qstorno_ret_qs2qd 1152482 1152482 0 0.00 0 34164 32 0 0
sp_kill_qty_storno 607279 607279 0 0.00 0 34166 70 0 0
sp_lock_selected_doc 20952 20952 0 0.00 0 13216 4 0 0
sp_make_cost_storno 28374 28374 0 0.00 0 31480 54 0 0
sp_multiply_rows_for_pdistr 28374 28374 0 0.00 0 13113 30 0 0
sp_multiply_rows_for_qdistr 20915 20915 0 0.00 0 11878 151 0 0
sp_pay_from_customer 7157 7157 0 0.00 4 8415 105 0 0
sp_pay_to_supplier 5991 5991 0 0.00 5 31507 128 0 0
sp_payment_common 13148 13148 0 0.00 2 20095 26 0 0
sp_qd_handle_on_cancel_clo 1728 1728 0 0.00 0 16852 333 0 0
sp_qd_handle_on_invoice_upd_sts 10691 10691 0 0.00 0 16742 339 0 0
sp_qd_handle_on_reserve_upd_sts 10206 10206 0 0.00 0 21749 60 0 0
sp_reserve_write_off 10206 10206 0 0.00 7 21790 179 0 0
sp_supplier_invoice 5571 5571 0 0.00 7 100488 3811 0 0
sp_supplier_order 5408 5408 0 0.00 6 34687 1277 0 0
srv_aggregate_perf_data 102 102 0 0.00 3119 272068 18259 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2296 124882 43303 0 0
srv_find_qd_qs_mism 1068171 1068171 0 0.00 0 28297 5 0 0
srv_make_invnt_saldo 545 545 0 0.00 40 21092 1028 0 0
srv_make_money_saldo 781 781 0 0.00 1 7709 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 78058 364493 243613 0 0
t$perf-abend:sp_add_invoice_to_stock 54 54 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 5029 5029 0 0.00 0 26684 160 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 2564 90 0 0
t$perf-norm:sp_cancel_client_order 1728 1728 0 0.00 0 2198 14 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 12933 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 5646 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 2062 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 1809 17 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 30048 1756 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 2151 12 0 0
t$perf-norm:sp_client_order 20915 20915 0 0.00 0 8344 17 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 1886 8 0 0
t$perf-norm:sp_pay_from_customer 7157 7157 0 0.00 0 12184 10 0 0
t$perf-norm:sp_pay_to_supplier 5991 5991 0 0.00 0 6597 11 0 0
t$perf-norm:sp_reserve_write_off 10206 10206 0 0.00 0 9222 12 0 0
t$perf-norm:sp_supplier_invoice 5571 5571 0 0.00 0 24461 23 0 0
t$perf-norm:sp_supplier_order 5408 5408 0 0.00 0 5870 17 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 234 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 5977 96 0 0
t$perf-norm:srv_make_invnt_saldo 545 545 0 0.00 0 1267 34 0 0
t$perf-norm:srv_make_money_saldo 781 781 0 0.00 0 1677 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 40 13 0 0
v_add_invoice_to_stock 10144 10036 108 1.06 0 11125 19 0 108
v_all_customers 37496 37496 0 0.00 0 24 1 0 0
v_all_suppliers 30340 30340 0 0.00 0 30 1 0 0
v_all_wares 41830 41830 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 621 8 0 0
v_cancel_client_order 3456 3456 0 0.00 0 3194 6 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 3137 8 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 3134 8 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 3506 8 0 0
v_cancel_supplier_invoice 814 810 4 0.49 0 821 14 0 4
v_cancel_supplier_order 816 816 0 0.00 0 1834 7 0 0
v_cancel_write_off 1958 1958 0 0.00 0 4229 18 0 0
v_max_id_clo_ord 5403 5403 0 0.00 0 1441 2 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 1793 9 0 0
v_max_id_ord_sup 5556 5556 0 0.00 0 4361 4 0 0
v_max_non_paid_invoice 1780 1780 0 0.00 0 427 2 0 0
v_max_non_paid_realizn 3983 3983 0 0.00 0 2558 3 0 0
v_min_id_clo_ord 5403 5403 0 0.00 0 1586 5 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 4293 13 0 0
v_min_id_ord_sup 5556 5556 0 0.00 0 7404 12 0 0
v_min_non_paid_invoice 5991 5991 0 0.00 0 20091 19 0 0
v_min_non_paid_realizn 7157 7157 0 0.00 0 6083 17 0 0
v_our_firm 10682 10682 0 0.00 0 15 1 0 0
v_reserve_write_off 20408 20408 0 0.00 0 4641 7 0 0
x_lock_depdocs_on_canc_invoice 11579 11579 0 0.00 0 16864 129 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 11868 372 0 0
x_make_qstorno_1000_1200 5408 5408 0 0.00 2 34669 1193 0 0
x_make_qstorno_1200_2000 5571 5571 0 0.00 3 100402 3663 0 0
x_make_qstorno_2100_3300 460287 460287 0 0.00 0 77532 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-09-2021 01:39:15 to 01-09-2021 01:39:19.

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:48 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:48 27562 8192000000 10376536448 10694393856 1425487056 14.821 100 92 183 1 1462113216 6195696 30430464
23:10:21 12044 8192000000 10416347888 10744774656 1476474176 15.271 100 88 176 0 1513044960 8037648 28533136
23:11:37 15015 8192000000 10477806624 10815528960 1534676304 15.778 100 88 176 1 1570972192 9167520 27128368
23:13:04 11596 8192000000 10559258288 10912931840 1616605120 16.481 100 84 168 0 1652713072 10805600 25302352
23:14:22 9222 8192000000 10613354512 10982215680 1675842560 16.982 100 75 150 1 1708400512 11238736 21319216
23:15:35 4904 8192000000 10691641664 11066380288 1753823680 17.633 100 78 156 1 1788742928 12900432 22018816
23:16:41 3132 8192000000 10748113024 11125989376 1802268592 18.033 100 90 180 2 1841105952 13964544 24872816
23:17:46 2375 8192000000 10785145632 11167420416 1839067200 18.333 100 90 180 0 1879738192 15631280 25039712
23:18:50 3263 8192000000 10797721600 11194720256 1862645152 18.525 100 83 166 1 1898392592 12928464 22818976
23:19:56 10577 8192000000 10784982544 11196940288 1864029776 18.536 100 35 72 0 1881156720 7004944 10122000
23:21:07 4165 8192000000 10772731072 11204743168 1869027056 18.576 100 15 29 0 1878684576 6069040 3588480
23:22:16 7181 8192000000 10798736208 11226861568 1885447920 18.709 100 29 58 2 1905013440 11076784 8488736
23:23:25 9749 8192000000 10819388576 11242733568 1899384480 18.821 100 20 39 2 1914251136 9850320 5016336
23:24:35 32111 8192000000 10820130256 11251228672 1906414896 18.878 100 29 58 3 1919406912 5241104 7750912
23:26:09 5028 8192000000 10820810416 11256217600 1909740272 18.905 100 28 55 0 1923620224 6654896 7225056
23:27:16 8342 8192000000 10809620928 11237990400 1868033136 18.568 99 16 32 0 1883154608 9740224 5381248
23:28:28 36742 8192000000 10736164112 11167129600 1725232528 17.396 92 38 77 1 1740942192 4853520 10856144
23:30:28 6233 8192000000 10719791392 11132555264 1776803376 17.823 98 39 76 4 1799501408 11834208 10863824
23:31:40 5297 8192000000 10693831888 11104321536 1739006080 17.510 97 26 53 0 1756532832 10192928 7333824
23:32:45 9942 8192000000 10742839344 11158888448 1820956144 18.185 100 23 47 0 1834179056 5998256 7224656
23:33:59 35659 8192000000 10755842272 11177291776 1841539200 18.353 100 19 37 0 1852176704 5352048 5285456
23:35:37 5733 8192000000 10789435440 11207712768 1865930416 18.551 100 30 60 1 1881261136 6748544 8582176
23:36:43 12671 8192000000 10787506032 11215810560 1851438224 18.434 99 13 26 0 1864530944 9317312 3775408
23:37:57 4058 8192000000 10843413616 11256672256 1914619808 18.944 100 57 112 1 1937615248 5390016 17605424
23:39:26 40128 8192000000 10853055632 11281948672 1929364784 19.062 100 49 98 1 1951812048 7937168 14510096
23:41:10 21794 8192000000 10877774336 11305631744 1942786864 19.169 100 48 96 1 1970494432 14800176 12907392
23:42:42 11907 8192000000 10895935456 11320168448 1955069744 19.267 100 69 138 1 1993182608 18747456 19365408
23:44:03 35396 8192000000 10906757024 11345690624 1959320304 19.301 99 47 94 2 1994747728 21831648 13595776
23:45:43 4575 8192000000 10942118624 11366998016 1993253088 19.569 100 71 142 0 2035225216 21504528 20467600
23:47:03 11247 8192000000 10926842256 11361837056 1983974720 19.496 100 44 84 1 2018974560 21945584 13054256
23:48:45 9990 8192000000 10917634256 11341991936 1939255536 19.141 98 54 108 1 1985638912 29809440 16573936
23:49:58 6638 8192000000 10945301168 11363397632 1978989040 19.457 100 73 148 2 2030302528 28800032 22513456
23:51:09 6368 8192000000 10952220528 11369705472 1981246032 19.475 100 71 142 1 2037006096 34135104 21624960
23:52:23 4174 8192000000 10959766944 11376291840 1992540800 19.564 100 85 168 0 2051045600 33297616 25207184
23:53:34 3479 8192000000 10970486880 11382042624 1989878912 19.543 100 68 134 0 2049740464 38163680 21697872
23:54:44 2337 8192000000 10969982544 11378024448 1984137600 19.497 100 86 170 0 2049103968 38313984 26652384
23:55:52 5332 8192000000 10985381904 11400085504 1999334672 19.617 100 89 176 2 2073192416 47031856 26825888
23:57:03 2296 8192000000 10961784464 11375276032 1968421600 19.373 99 79 159 0 2036956592 43755104 24779888
23:58:11 4119 8192000000 10982506752 11404185600 1989988192 19.544 100 71 142 2 2063914384 51808752 22117440
23:59:18 10975 8192000000 10960338240 11406340096 1989003376 19.536 100 40 80 2 2047925648 45700784 13221488
00:00:35 19340 8192000000 10968143104 11414228992 1986230272 19.514 100 34 69 0 2057252352 58357440 12664640
00:02:07 12773 8192000000 10924872672 11371708416 1931162656 19.076 98 31 62 1 2000678288 58650048 10865584
00:03:29 25212 8192000000 10916819696 11378360320 1956385552 19.277 100 35 69 2 2011599424 43933312 11280560
00:05:22 34917 8192000000 10904018416 11363422208 1940642448 19.152 100 19 37 1 1991480368 44417952 6419968
00:07:00 31173 8192000000 10876181376 11331407872 1883614576 18.694 97 10 20 0 1911107696 24327344 3165776
00:08:32 47130 8192000000 10894404544 11349905408 1910576448 18.911 97 14 28 1 1924196656 9621216 3998992
00:10:20 20736 8192000000 10907236416 11371626496 1977987584 19.449 100 13 26 0 1992032496 10797712 3247200
00:11:47 65695 8192000000 10928225168 11369742336 1961309040 19.316 99 35 70 3 1989305200 17540528 10455632
00:13:53 92887 8192000000 10887662960 11352670208 1963811568 19.336 99 36 71 0 1983776032 9696656 10267808
00:16:38 34166 8192000000 10901680704 11378081792 1977015088 19.441 100 15 30 1 1996950240 15599056 4336096
00:18:25 25044 8192000000 10913639200 11377713152 1985096816 19.505 100 25 51 1 2000644416 8706720 6840880
00:19:53 12263 8192000000 10947470240 11390029824 1992881216 19.567 100 63 127 1 2020859856 10349456 17629184
00:21:08 76928 8192000000 10915071904 11377250304 1947414496 19.206 97 26 51 0 1968769504 13618000 7737008
00:23:26 25752 8192000000 10911836960 11407753216 1990284352 19.546 100 7 13 1 2006841312 14297392 2259568
00:24:54 113467 8192000000 10919078512 11386425344 1950376464 19.229 98 22 44 1 1969561600 12463024 6722112
00:28:11 53657 8192000000 10946882512 11388821504 1978664352 19.454 99 41 80 2 1998360192 8743440 10952400
00:30:08 59430 8192000000 10922603152 11388145664 1978644096 19.454 99 28 55 1 1997287312 10936848 7706368
00:32:49 43434 8192000000 10919535472 11387256832 1956345472 19.277 98 29 58 0 1975606960 11377760 7883728
00:34:34 62717 8192000000 10903096368 11362775040 1901082880 18.835 96 20 40 1 1920196144 13075712 6037552
00:36:45 46388 8192000000 10913704688 11384705024 1960051360 19.306 99 15 30 0 1981077728 16896112 4130256
00:38:36 36462 8192000000 10869632128 11344781312 1860825632 18.510 94 17 34 1 1876811408 11580048 4405728
00:40:14 109385 8192000000 10842732144 11307827200 1885368416 18.708 97 14 29 0 1905972144 16838768 3764960
00:43:08 44652 8192000000 10861262288 11335712768 1936525952 19.119 100 8 16 0 1945556880 6835680 2195248
00:45:00 44864 8192000000 10872996032 11344273408 1914616032 18.944 98 15 30 1 1930476512 11387632 4472848
00:46:52 57303 8192000000 10894308848 11355320320 1932741280 19.089 99 24 48 0 1954590576 14997824 6851472
00:48:49 55735 8192000000 10858703520 11322122240 1883311136 18.692 97 16 32 1 1899904992 12080096 4513760
00:50:46 46438 8192000000 10842459520 11316867072 1875376464 18.628 97 13 26 0 1888794704 9900272 3517968
00:52:34 53939 8192000000 10876303120 11336851456 1889127344 18.739 97 32 64 1 1910414768 12186240 9101184
00:54:29 37458 8192000000 10906780880 11350614016 1952673888 19.248 100 29 56 1 1969638944 9178848 7786208
00:56:08 60342 8192000000 10887503344 11348905984 1907546816 18.887 97 33 66 0 1928965184 12148848 9269520
00:58:11 74903 8192000000 10877453264 11362222080 1897165344 18.803 97 14 28 0 1921407360 19882368 4359648
01:00:28 76776 8192000000 10895614848 11361304576 1939839344 19.145 99 21 42 0 1956616400 10951632 5825424
01:02:48 19306 8192000000 10919452336 11378647040 1975535984 19.429 100 45 90 0 2002744352 14314784 12893584
01:04:11 33248 8192000000 10884216880 11361583104 1951390240 19.238 99 15 30 0 1964985040 9267328 4327472
01:05:47 55014 8192000000 10877589264 11356770304 1912556192 18.927 97 9 15 1 1922071424 7224624 2290608
01:07:45 33807 8192000000 10881344896 11367706624 1970009008 19.386 100 6 12 0 1976831712 5561600 1261104
01:09:19 74308 8192000000 10888222672 11377176576 1957619104 19.287 99 18 35 0 1971502400 9170304 4712992
01:11:38 66682 8192000000 10923613232 11403005952 1991423616 19.555 100 11 22 0 2005917728 11768832 2725280
01:13:54 65468 8192000000 10901364160 11378130944 1949490048 19.222 98 9 18 1 1963398304 10944832 2963424
01:16:00 25918 8192000000 10951094272 11410911232 1990080160 19.544 99 29 58 1 2012757376 14951968 7725248
01:17:27 87524 8192000000 10909530512 11389837312 1933370560 19.094 97 17 34 1 1955630304 18001360 4258384
01:20:36 120126 8192000000 10900623488 11379052544 1836428816 18.312 92 20 38 0 1858570832 16858272 5283744
01:23:47 124881 8192000000 10902985968 11373535232 1943746752 19.177 98 24 48 0 1961231280 10949744 6534784
01:27:06 57068 8192000000 10893860192 11377741824 1939911840 19.146 99 12 24 0 1959409264 16089568 3407856
01:29:09 56602 8192000000 10875232096 11355541504 1934831088 19.105 99 11 22 1 1950062144 11652256 3578800
01:31:11 61660 8192000000 10855683184 11335446528 1904927600 18.866 98 11 22 0 1917772512 9746928 3097984
01:33:22 83407 8192000000 10882107968 11365556224 1946390336 19.198 99 13 26 0 1963100032 12803552 3906144
01:36:08 63348 8192000000 10854661056 11326050304 1832829312 18.282 94 8 16 0 1846824528 12367840 1627376

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-09-2021 01:39:21 to 01-09-2021 01:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 54 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 01-09-2021 01:39:37 to 01-09-2021 01:39:39.

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          241637
MON$OLDEST_ACTIVE               242957
MON$OLDEST_SNAPSHOT             242957
MON$NEXT_TRANSACTION            242957
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                       2045600
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.33490 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33490 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33490 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33490 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 ---
:53: 
:54: mscs-test-161.budzdorov.ru    Wed Sep  1 01:37:45 2021
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Wed Sep  1 01:37:45 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Wed Sep  1 01:37:53 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
--- end of diff output ---

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

01.09.21 01:39:40. 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-09-01 01:39:42 No severe PSQL-related problems occured
01.09.21 01:39:43. End of report.