Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.11.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 15951408 kB
MemAvailable 30816032 kB
Buffers 0 kB
Cached 15771144 kB
SwapCached 2448 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-11-08 19:37:59
DTS_END                         2021-11-08 19:37:59.8610
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36628
TRN_ID                          240279
ATT_ID                          716
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: 9401.64 23261 1128197
customer order: creation 168.28 383 20194
customer order: refuse 14.66 368 1759
order to supplier: creation 44.73 1323 5368
order to supplier: removal 3.73 105275 448
invoice (draft): creation 44.88 3819 5386
invoice (draft): removal 54.00 939 6480
invoice accept: apply 41.10 17848 4932
invoice accept: cancel 48.53 826 5824
customer reserve: creation 3868.58 173 464229
customer reserve: removal 4815.33 64 577840
realization accept: apply 84.64 208 10157
realization accept: cancel 85.19 109 10223
payment from customer: creation 58.11 120 6973
payment from customer: removal 5.97 103 716
payment to supplier: creation 48.40 148 5808
payment to supplier: removal 3.28 109 394
service: total inventory turnovers 5.00 935 600
service: total monetary turnovers 6.47 22 776
service: aggregate perf. data 0.72 24329 86
service: refresh index statistics 0.03 308113 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-11-2021 19:40:19 to 08-11-2021 19:40:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2882
TEST_TIME 2 2904
TEST_TIME 3 3894
TEST_TIME 4 3809
TEST_TIME 5 3858
TEST_TIME 6 4059
TEST_TIME 7 4121
TEST_TIME 8 4259
TEST_TIME 9 4557
TEST_TIME 10 4705
TEST_TIME 11 4862
TEST_TIME 12 4953
TEST_TIME 13 5370
TEST_TIME 14 5408
TEST_TIME 15 5297
TEST_TIME 16 5763
TEST_TIME 17 5865
TEST_TIME 18 6149
TEST_TIME 19 6721
TEST_TIME 20 6844
TEST_TIME 21 6959
TEST_TIME 22 7649
TEST_TIME 23 7837
TEST_TIME 24 7708
TEST_TIME 25 7834
TEST_TIME 26 7973
TEST_TIME 27 8395
TEST_TIME 28 9201
TEST_TIME 29 9736
TEST_TIME 30 9763
TEST_TIME 31 10365
TEST_TIME 32 10213
TEST_TIME 33 10048
TEST_TIME 34 10158
TEST_TIME 35 10128
TEST_TIME 36 10338
TEST_TIME 37 10212
TEST_TIME 38 10118
TEST_TIME 39 9985
TEST_TIME 40 9862
TEST_TIME 41 9789
TEST_TIME 42 9646
TEST_TIME 43 9729
TEST_TIME 44 9672
TEST_TIME 45 9567
TEST_TIME 46 9725
TEST_TIME 47 9738
TEST_TIME 48 9692
TEST_TIME 49 9620
TEST_TIME 50 9509
TEST_TIME 51 9431
TEST_TIME 52 9420
TEST_TIME 53 9438
TEST_TIME 54 9472
TEST_TIME 55 9504
TEST_TIME 56 9454
TEST_TIME 57 9555
TEST_TIME 58 9523
TEST_TIME 59 9604
TEST_TIME 60 9687
TEST_TIME 61 9715
TEST_TIME 62 9774
TEST_TIME 63 9802
TEST_TIME 64 9897
TEST_TIME 65 9873
TEST_TIME 66 9852
TEST_TIME 67 10009
TEST_TIME 68 9937
TEST_TIME 69 9830
TEST_TIME 70 9777
TEST_TIME 71 9819
TEST_TIME 72 9774
TEST_TIME 73 9734
TEST_TIME 74 9666
TEST_TIME 75 9615
TEST_TIME 76 9633
TEST_TIME 77 9579
TEST_TIME 78 9550
TEST_TIME 79 9570
TEST_TIME 80 9526
TEST_TIME 81 9528
TEST_TIME 82 9580
TEST_TIME 83 9538
TEST_TIME 84 9648
TEST_TIME 85 9630
TEST_TIME 86 9652
TEST_TIME 87 9733
TEST_TIME 88 9740
TEST_TIME 89 9714
TEST_TIME 90 9634
TEST_TIME 91 9659
TEST_TIME 92 9624
TEST_TIME 93 9591
TEST_TIME 94 9599
TEST_TIME 95 9540
TEST_TIME 96 9533
TEST_TIME 97 9537
TEST_TIME 98 9506
TEST_TIME 99 9537
TEST_TIME 100 9551
TEST_TIME 101 9586
TEST_TIME 102 9549
TEST_TIME 103 9571
TEST_TIME 104 9633
TEST_TIME 105 9648
TEST_TIME 106 9614
TEST_TIME 107 9666
TEST_TIME 108 9654
TEST_TIME 109 9657
TEST_TIME 110 9639
TEST_TIME 111 9603
TEST_TIME 112 9580
TEST_TIME 113 9555
TEST_TIME 114 9541
TEST_TIME 115 9551
TEST_TIME 116 9514
TEST_TIME 117 9506
TEST_TIME 118 9497
TEST_TIME 119 9442
TEST_TIME 120 9404

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-11-2021 19:40:23 to 08-11-2021 19:40:26.

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 1559219 1559219 0 0.00 0 30560 6 0 0
doc_list_biud 1559217 1559217 0 0.00 0 29080 30 0 0
sp_add_invoice_to_stock 5057 4932 125 2.47 40 178596 17849 0 125
sp_cancel_adding_invoice 5824 5824 0 0.00 4 62541 826 0 0
sp_cancel_client_order 1759 1759 0 0.00 4 14816 369 0 0
sp_cancel_customer_reserve 577840 577840 0 0.00 1 28564 64 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 3 12559 103 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 5931 110 0 0
sp_cancel_supplier_invoice 6491 6480 11 0.16 2 20055 939 0 11
sp_cancel_supplier_order 448 448 0 0.00 6 1038866 105276 0 0
sp_cancel_write_off 10223 10223 0 0.00 1 13112 110 0 0
sp_client_order 20194 20194 0 0.00 3 29024 383 0 0
sp_customer_reserve 464229 464229 0 0.00 0 42223 173 0 0
sp_fill_shopping_cart 30942 30942 0 0.00 1 14263 39 0 0
sp_fill_shopping_cart_clo_res 464228 464228 0 0.00 0 16673 14 0 0
sp_get_clo_for_invoice 4929 4929 0 0.00 0 14065 278 0 0
sp_kill_cost_storno 17157 17157 0 0.00 0 11656 60 0 0
sp_kill_qstorno_ret_qs2qd 1172831 1172831 0 0.00 0 28548 35 0 0
sp_kill_qty_storno 617658 617658 0 0.00 0 29079 75 0 0
sp_lock_selected_doc 20943 20943 0 0.00 0 7550 5 0 0
sp_make_cost_storno 27866 27866 0 0.00 0 30558 81 0 0
sp_multiply_rows_for_pdistr 27866 27866 0 0.00 0 9923 31 0 0
sp_multiply_rows_for_qdistr 20194 20194 0 0.00 0 16246 174 0 0
sp_pay_from_customer 6973 6973 0 0.00 4 26667 121 0 0
sp_pay_to_supplier 5808 5808 0 0.00 5 28891 148 0 0
sp_payment_common 12781 12781 0 0.00 1 12595 29 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 14807 340 0 0
sp_qd_handle_on_invoice_upd_sts 10753 10753 0 0.00 0 29078 322 0 0
sp_qd_handle_on_reserve_upd_sts 10155 10155 0 0.00 0 16209 61 0 0
sp_reserve_write_off 10157 10157 0 0.00 6 30610 209 0 0
sp_supplier_invoice 5386 5386 0 0.00 5 114487 3819 0 0
sp_supplier_order 5368 5368 0 0.00 4 56837 1324 0 0
srv_aggregate_perf_data 86 86 0 0.00 3254 190713 24330 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1845 120005 43249 0 0
srv_find_qd_qs_mism 1081495 1081495 0 0.00 0 16181 4 0 0
srv_make_invnt_saldo 600 600 0 0.00 6 27094 936 0 0
srv_make_money_saldo 776 776 0 0.00 0 1059 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 245699 383058 308113 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 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 4932 4932 0 0.00 0 19818 159 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 4469 97 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 5254 17 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 1372 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 6706 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 3184 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 1081 17 0 0
t$perf-norm:sp_cancel_supplier_order 448 448 0 0.00 0 25069 1817 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 4732 14 0 0
t$perf-norm:sp_client_order 20195 20195 0 0.00 0 10896 17 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 1521 4 0 0
t$perf-norm:sp_pay_from_customer 6973 6973 0 0.00 0 6235 11 0 0
t$perf-norm:sp_pay_to_supplier 5808 5808 0 0.00 0 12981 13 0 0
t$perf-norm:sp_reserve_write_off 10157 10157 0 0.00 0 4918 10 0 0
t$perf-norm:sp_supplier_invoice 5386 5386 0 0.00 0 8330 18 0 0
t$perf-norm:sp_supplier_order 5368 5368 0 0.00 0 5534 17 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 335 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 5 1 0 0
t$perf-norm:srv_make_invnt_saldo 600 600 0 0.00 0 1444 24 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 4406 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10106 9856 250 2.47 0 11854 20 0 250
v_all_customers 36062 36062 0 0.00 0 94 1 0 0
v_all_suppliers 30168 30168 0 0.00 0 126 1 0 0
v_all_wares 40382 40382 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 982 8 0 0
v_cancel_client_order 3518 3518 0 0.00 0 3904 5 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 6708 13 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 1245 6 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 4656 19 0 0
v_cancel_supplier_invoice 874 852 22 2.51 0 707 14 0 22
v_cancel_supplier_order 896 896 0 0.00 0 2721 7 0 0
v_cancel_write_off 2058 2058 0 0.00 0 2610 12 0 0
v_max_id_clo_ord 5366 5366 0 0.00 0 414 1 0 0
v_max_id_clo_res 961 961 0 0.00 0 2768 12 0 0
v_max_id_ord_sup 5385 5385 0 0.00 0 4502 3 0 0
v_max_non_paid_invoice 1475 1475 0 0.00 0 516 3 0 0
v_max_non_paid_realizn 4017 4017 0 0.00 0 3289 5 0 0
v_min_id_clo_ord 5366 5366 0 0.00 0 7332 9 0 0
v_min_id_clo_res 961 961 0 0.00 0 2342 4 0 0
v_min_id_ord_sup 5385 5385 0 0.00 0 4818 6 0 0
v_min_non_paid_invoice 5808 5808 0 0.00 0 12592 20 0 0
v_min_non_paid_realizn 6973 6973 0 0.00 0 9666 21 0 0
v_our_firm 10232 10232 0 0.00 0 18 1 0 0
v_reserve_write_off 20308 20308 0 0.00 0 4018 4 0 0
x_lock_depdocs_on_canc_invoice 11878 11878 0 0.00 0 13381 117 0 0
x_lock_depdocs_on_canc_sup_ord 448 448 0 0.00 0 7201 345 0 0
x_make_qstorno_1000_1200 5368 5368 0 0.00 1 56821 1251 0 0
x_make_qstorno_1200_2000 5386 5386 0 0.00 2 114473 3671 0 0
x_make_qstorno_2100_3300 464229 464229 0 0.00 0 41638 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-11-2021 19:40:27 to 08-11-2021 19:40:31.

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:08:03 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:04 32368 8192000000 9911305936 10226810880 987980528 10.762 100 88 176 1 1017078800 6022208 23076064
17:10:42 23357 8192000000 9954084304 10280968192 1043578320 11.299 100 93 186 1 1076377184 8637840 24161024
17:12:13 15387 8192000000 9989031056 10325512192 1069369408 11.546 100 79 158 0 1098420208 9347040 19703760
17:13:30 10209 8192000000 10043843104 10394099712 1127877056 12.101 100 82 164 0 1162356960 15071632 19408272
17:14:45 7495 8192000000 10082641472 10445844480 1169896016 12.496 100 70 140 1 1197482848 11254464 16332368
17:15:57 4127 8192000000 10140952944 10514489344 1224581264 13.004 100 85 168 1 1256552304 12480720 19490320
17:17:04 3847 8192000000 10164415616 10544128000 1253126768 13.267 100 86 172 1 1285348560 13368528 18853264
17:18:09 1634 8192000000 10191795424 10570502144 1274343488 13.461 100 94 189 1 1309544672 14875856 20325328
17:19:12 1592 8192000000 10209384976 10592460800 1295393392 13.653 100 92 183 1 1329436064 13768096 20274576
17:20:16 18886 8192000000 10198216608 10604990464 1305259760 13.743 100 63 129 2 1331596736 12004688 14332288
17:21:38 4018 8192000000 10203909520 10615939072 1317430656 13.853 100 34 68 2 1331026848 6204464 7391728
17:22:43 6285 8192000000 10215134272 10630078464 1328094160 13.950 100 26 52 1 1341017056 6772176 6150720
17:23:51 10085 8192000000 10211994960 10633166848 1330658992 13.973 100 24 48 2 1343374400 6419680 6295728
17:25:05 26609 8192000000 10223086464 10649427968 1332800160 13.992 100 23 47 0 1343696832 5704352 5192320
17:26:32 12020 8192000000 10234072464 10654101504 1338856192 14.047 100 23 44 2 1348843888 5331376 4656320
17:27:46 4203 8192000000 10214671968 10635575296 1324261408 13.915 100 23 46 0 1336300208 7022064 5016736
17:28:52 49585 8192000000 10161499296 10548613120 1194302640 12.723 93 53 103 2 1210827776 4448576 12076560
17:30:42 6956 8192000000 10149681008 10554245120 1239961552 13.146 98 30 60 1 1253691360 6668752 7061056
17:31:51 5158 8192000000 10152096160 10550542336 1247803552 13.218 99 18 36 0 1257384256 4811824 4768880
17:32:58 11749 8192000000 10176076512 10575527936 1276447840 13.481 100 31 61 5 1289646016 5189216 8008960
17:34:11 15012 8192000000 10165216080 10580217856 1279563392 13.509 100 26 52 0 1291587568 6153712 5870464
17:35:27 6445 8192000000 10181485120 10591531008 1288367440 13.589 100 25 50 1 1300453984 6812528 5274016
17:36:35 6808 8192000000 10185164880 10602737664 1302599408 13.719 100 21 42 1 1313225584 5899568 4726608
17:37:44 10732 8192000000 10196198880 10611187712 1304834176 13.739 100 15 30 1 1313805136 5614512 3356448
17:39:03 13623 8192000000 10236419392 10643648512 1330367072 13.970 100 49 98 0 1349050400 7778592 10904736
17:40:22 60127 8192000000 10249208160 10666758144 1343560640 14.090 100 42 84 1 1360342672 5812112 10969920
17:42:27 24788 8192000000 10283964960 10703470592 1371200528 14.338 100 52 104 0 1394114336 11042048 11871760
17:43:55 11681 8192000000 10296373648 10720321536 1387785056 14.486 100 49 98 3 1415607440 15314080 12508304
17:45:15 14716 8192000000 10315331488 10731745280 1382844048 14.442 99 56 111 1 1409797232 13882704 13070480
17:46:33 29756 8192000000 10328378064 10754195456 1402149296 14.614 99 35 70 2 1426355408 14794064 9412048
17:48:08 5149 8192000000 10338470304 10761297920 1414194944 14.721 100 55 110 4 1447621136 19231280 14194912
17:49:21 7088 8192000000 10341699600 10753032192 1408701328 14.672 100 70 140 2 1451377616 24470400 18205888
17:50:33 5758 8192000000 10356693360 10782314496 1413513184 14.715 99 60 119 4 1455364656 27478048 14373424
17:51:43 5500 8192000000 10349864720 10770280448 1407229088 14.659 100 57 113 0 1456303360 34274720 14799552
17:52:58 18270 8192000000 10356113520 10788777984 1412396848 14.705 100 48 96 0 1463643328 38553952 12692528
17:54:27 2831 8192000000 10381463040 10790801408 1419896768 14.772 100 84 167 3 1483163536 42994416 20272352
17:55:37 6231 8192000000 10361741744 10779152384 1404319920 14.633 100 59 119 1 1466755104 47155184 15280000
17:56:51 12426 8192000000 10325589104 10746499072 1360625216 14.243 98 44 88 0 1418354656 45990224 11739216
17:58:20 1845 8192000000 10349877904 10756337664 1370233760 14.329 99 79 159 0 1441497792 50526480 20737552
17:59:30 6697 8192000000 10352341808 10776391680 1396079792 14.560 100 55 110 0 1460529216 49184000 15265424
18:00:43 16375 8192000000 10328583872 10758729728 1362975776 14.264 98 38 76 2 1420377936 47220880 10181280
18:02:06 7053 8192000000 10356769472 10780545024 1395160608 14.552 100 50 96 2 1457790496 49870000 12759888
18:03:47 12230 8192000000 10334688752 10763370496 1368334592 14.312 100 33 66 2 1438364192 60326768 9702832
18:05:03 17945 8192000000 10342641376 10781446144 1371399344 14.340 100 32 64 1 1448674176 67758880 9515952
18:06:35 59297 8192000000 10302014480 10737364992 1355840944 14.200 99 26 52 0 1388307856 25724448 6742464
18:09:24 57163 8192000000 10281727632 10707034112 1306557360 13.755 95 32 64 1 1323198480 9198240 7442880
18:11:27 60280 8192000000 10286247568 10728353792 1349665648 14.144 98 9 18 1 1361680656 10221488 1793520
18:13:31 87690 8192000000 10307287968 10733662208 1357747904 14.217 98 24 48 0 1372637184 9430992 5458288
18:15:59 103636 8192000000 10309605568 10756239360 1385967024 14.470 100 38 75 1 1402923312 7960384 8995904
18:18:48 48302 8192000000 10317209840 10759520256 1385093984 14.462 100 34 68 2 1404739936 11327328 8318624
18:20:39 40609 8192000000 10311789984 10761646080 1380113136 14.418 99 22 44 1 1401779792 16220496 5446160
18:22:21 35475 8192000000 10323233184 10768412672 1401950432 14.612 100 57 114 2 1430754048 15931072 12872544
18:23:58 11760 8192000000 10299281168 10777489408 1395389072 14.554 100 8 16 0 1408874544 12030128 1455344
18:25:25 23228 8192000000 10291634320 10758500352 1396418256 14.563 100 9 18 0 1404627424 5732640 2476528
18:26:49 64633 8192000000 10296776800 10755325952 1385516368 14.466 99 16 31 0 1398147344 8897616 3733360
18:29:10 79057 8192000000 10286383568 10758733824 1370984928 14.336 98 21 42 0 1386362608 10192912 5184768
18:31:30 45127 8192000000 10305586928 10765389824 1395925376 14.559 100 15 31 0 1408114176 8694368 3494432
18:33:17 79804 8192000000 10313243024 10780286976 1400486416 14.599 100 14 28 0 1422885232 18921040 3477776
18:35:38 42375 8192000000 10330130720 10777845760 1396381456 14.563 99 35 69 0 1417028592 12266176 8380960
18:37:31 51346 8192000000 10294179024 10762539008 1371228656 14.338 98 11 22 1 1383129664 9669424 2231584
18:39:23 69411 8192000000 10291911664 10760200192 1348758032 14.136 97 16 32 1 1369411520 17176368 3477120
18:41:33 55425 8192000000 10304210592 10754445312 1369077184 14.319 98 11 22 1 1385367856 14052480 2238192
18:43:29 35398 8192000000 10288391136 10741600256 1345115920 14.104 98 31 62 0 1366043792 14132064 6795808
18:45:14 96670 8192000000 10242380048 10687832064 1295559952 13.655 96 25 50 0 1312702016 10994304 6147760
18:47:51 44678 8192000000 10261531840 10716495872 1347823504 14.128 100 28 56 1 1368628112 14454576 6350032
18:49:37 58976 8192000000 10252464336 10709512192 1319406960 13.871 97 14 28 0 1339492352 16991632 3093760
18:51:37 37945 8192000000 10257952640 10709299200 1337194688 14.032 99 27 55 0 1358201280 14570112 6436480
18:53:38 39739 8192000000 10258312016 10721595392 1345675392 14.109 99 11 22 0 1364339792 15618864 3045536
18:55:29 44516 8192000000 10260008800 10713903104 1330375040 13.971 97 14 28 0 1344939808 11233616 3331152
18:57:16 50113 8192000000 10255574288 10704580608 1296085200 13.660 95 30 60 2 1316436384 13313536 7037648
18:59:10 63237 8192000000 10257559504 10710945792 1328168512 13.951 98 19 38 0 1345192896 12859424 4164960
19:01:22 42649 8192000000 10242166704 10705252352 1315619808 13.837 97 22 42 0 1334346096 14315632 4410656
19:03:19 60221 8192000000 10284604688 10725072896 1362343472 14.258 100 24 48 0 1387034144 19248640 5442032
19:05:20 83426 8192000000 10286773344 10734321664 1366988832 14.300 99 20 40 0 1381124768 9658928 4477008
19:07:44 120004 8192000000 10300263344 10774896640 1393451248 14.537 100 26 52 2 1417076496 17413536 6211712
19:11:04 62896 8192000000 10276309728 10744385536 1368008304 14.309 98 13 26 1 1380819968 10141232 2670432
19:13:11 42334 8192000000 10296512256 10762350592 1388329888 14.491 99 8 16 0 1396333408 6505520 1498000
19:14:54 24804 8192000000 10312598976 10769756160 1402715472 14.619 100 15 30 2 1414059600 7508608 3835520
19:16:23 71138 8192000000 10309551680 10775470080 1372678896 14.351 98 19 37 1 1387197120 10565680 3952544
19:18:34 30980 8192000000 10295664304 10750152704 1366273600 14.294 98 19 38 0 1380249296 9991632 3984064
19:20:20 45578 8192000000 10303363488 10769661952 1385264144 14.464 99 16 32 0 1400052224 11202240 3585840
19:22:17 101300 8192000000 10321206784 10785067008 1373147232 14.355 98 11 22 0 1391671472 15952160 2572080
19:25:08 33576 8192000000 10300719616 10751995904 1360165424 14.239 97 23 46 1 1376740192 11649344 4925424
19:26:44 36258 8192000000 10280257760 10728054784 1334463168 14.007 97 35 70 4 1361570000 19021552 8085280
19:28:28 51157 8192000000 10233881424 10672709632 1282477120 13.536 94 31 62 0 1295943568 6376512 7089936
19:30:21 40984 8192000000 10244052944 10696949760 1309968928 13.786 97 15 30 0 1322250224 8939616 3341680
19:32:03 65886 8192000000 10258558256 10720817152 1340359632 14.061 99 12 24 0 1354594432 11584688 2650112
19:34:09 39349 8192000000 10226548288 10676887552 1278831248 13.502 95 13 26 2 1289549504 7803024 2915232
19:35:50 92601 8192000000 10247699760 10703020032 1330873088 13.975 99 21 42 1 1350601088 14939472 4788528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-11-2021 19:40:33 to 08-11-2021 19:40:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 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 08-11-2021 19:40:49 to 08-11-2021 19:40:51.

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          238676
MON$OLDEST_ACTIVE               240391
MON$OLDEST_SNAPSHOT             240391
MON$NEXT_TRANSACTION            240391
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                       2038880
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.33528 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33528 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33528 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33528 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 ---
:270: 
:271: mscs-test-161.budzdorov.ru    Mon Nov  8 19:38:00 2021
:272:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:273: 
:274: 
:275: mscs-test-161.budzdorov.ru    Mon Nov  8 19:38:08 2021
:276:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:277: 
:278: 
:279: mscs-test-161.budzdorov.ru    Mon Nov  8 19:38:20 2021
:280:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:281: 
:282: 
:283: mscs-test-161.budzdorov.ru    Mon Nov  8 19:38:27 2021
:284:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:285: 
--- end of diff output ---

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

08.11.21 19:40:52. 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-11-08 19:40:55 No severe PSQL-related problems occured
08.11.21 19:40:56. End of report.