Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.11.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 16106260 kB
MemAvailable 30763668 kB
Buffers 0 kB
Cached 15578948 kB
SwapCached 2456 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-10 01:37:58
DTS_END                         2021-11-10 01:37:58.2830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_PAY_FROM_CUSTOMER(18:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55856
TRN_ID                          247493
ATT_ID                          659
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: 9529.54 21597 1143545
customer order: creation 186.35 362 22362
customer order: refuse 15.93 347 1911
order to supplier: creation 49.18 1314 5901
order to supplier: removal 3.56 103889 427
invoice (draft): creation 50.13 3088 6016
invoice (draft): removal 51.59 757 6191
invoice accept: apply 44.68 17861 5362
invoice accept: cancel 46.48 766 5578
customer reserve: creation 4140.20 176 496824
customer reserve: removal 4633.18 65 555982
realization accept: apply 89.65 152 10758
realization accept: cancel 79.99 130 9599
payment from customer: creation 63.56 105 7627
payment from customer: removal 6.08 80 730
payment to supplier: creation 52.39 119 6287
payment to supplier: removal 3.47 66 416
service: total inventory turnovers 5.01 981 601
service: total monetary turnovers 7.22 31 866
service: aggregate perf. data 0.86 20132 103
service: refresh index statistics 0.03 281521 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-11-2021 01:38:38 to 10-11-2021 01:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2193
TEST_TIME 2 3694
TEST_TIME 3 3813
TEST_TIME 4 4297
TEST_TIME 5 4412
TEST_TIME 6 4446
TEST_TIME 7 4476
TEST_TIME 8 4483
TEST_TIME 9 4456
TEST_TIME 10 5084
TEST_TIME 11 5306
TEST_TIME 12 5648
TEST_TIME 13 5897
TEST_TIME 14 6621
TEST_TIME 15 6507
TEST_TIME 16 6878
TEST_TIME 17 6886
TEST_TIME 18 7462
TEST_TIME 19 7601
TEST_TIME 20 7723
TEST_TIME 21 8075
TEST_TIME 22 8555
TEST_TIME 23 8740
TEST_TIME 24 9088
TEST_TIME 25 9362
TEST_TIME 26 9857
TEST_TIME 27 9805
TEST_TIME 28 10958
TEST_TIME 29 10792
TEST_TIME 30 10800
TEST_TIME 31 10637
TEST_TIME 32 10524
TEST_TIME 33 10373
TEST_TIME 34 10261
TEST_TIME 35 10205
TEST_TIME 36 10116
TEST_TIME 37 10012
TEST_TIME 38 9921
TEST_TIME 39 9922
TEST_TIME 40 9889
TEST_TIME 41 9777
TEST_TIME 42 9913
TEST_TIME 43 9805
TEST_TIME 44 9690
TEST_TIME 45 9650
TEST_TIME 46 9698
TEST_TIME 47 10028
TEST_TIME 48 9969
TEST_TIME 49 9880
TEST_TIME 50 9899
TEST_TIME 51 9958
TEST_TIME 52 9947
TEST_TIME 53 9961
TEST_TIME 54 9978
TEST_TIME 55 10007
TEST_TIME 56 9936
TEST_TIME 57 9964
TEST_TIME 58 10109
TEST_TIME 59 10116
TEST_TIME 60 9976
TEST_TIME 61 9910
TEST_TIME 62 9832
TEST_TIME 63 9770
TEST_TIME 64 9699
TEST_TIME 65 9619
TEST_TIME 66 9611
TEST_TIME 67 9616
TEST_TIME 68 9620
TEST_TIME 69 9584
TEST_TIME 70 9573
TEST_TIME 71 9593
TEST_TIME 72 9609
TEST_TIME 73 9638
TEST_TIME 74 9622
TEST_TIME 75 9660
TEST_TIME 76 9611
TEST_TIME 77 9703
TEST_TIME 78 9648
TEST_TIME 79 9674
TEST_TIME 80 9605
TEST_TIME 81 9560
TEST_TIME 82 9511
TEST_TIME 83 9479
TEST_TIME 84 9460
TEST_TIME 85 9417
TEST_TIME 86 9380
TEST_TIME 87 9393
TEST_TIME 88 9367
TEST_TIME 89 9449
TEST_TIME 90 9430
TEST_TIME 91 9388
TEST_TIME 92 9406
TEST_TIME 93 9507
TEST_TIME 94 9575
TEST_TIME 95 9561
TEST_TIME 96 9564
TEST_TIME 97 9615
TEST_TIME 98 9635
TEST_TIME 99 9610
TEST_TIME 100 9627
TEST_TIME 101 9633
TEST_TIME 102 9666
TEST_TIME 103 9582
TEST_TIME 104 9534
TEST_TIME 105 9497
TEST_TIME 106 9537
TEST_TIME 107 9500
TEST_TIME 108 9502
TEST_TIME 109 9475
TEST_TIME 110 9491
TEST_TIME 111 9448
TEST_TIME 112 9453
TEST_TIME 113 9445
TEST_TIME 114 9413
TEST_TIME 115 9462
TEST_TIME 116 9505
TEST_TIME 117 9520
TEST_TIME 118 9495
TEST_TIME 119 9542
TEST_TIME 120 9532

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-11-2021 01:38:42 to 10-11-2021 01:38:45.

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 1602232 1602232 0 0.00 0 16832 6 0 0
doc_list_biud 1602232 1602232 0 0.00 0 77103 28 0 0
sp_add_invoice_to_stock 5454 5362 92 1.68 21 161233 17861 0 92
sp_cancel_adding_invoice 5578 5578 0 0.00 4 77226 767 0 0
sp_cancel_client_order 1911 1911 0 0.00 4 13253 348 0 0
sp_cancel_customer_reserve 555982 555982 0 0.00 1 50888 65 0 0
sp_cancel_pay_from_customer 730 730 0 0.00 2 5719 80 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 3349 66 0 0
sp_cancel_supplier_invoice 6198 6191 7 0.11 1 62664 758 0 7
sp_cancel_supplier_order 427 427 0 0.00 9 1123696 103890 0 0
sp_cancel_write_off 9599 9599 0 0.00 1 77120 130 0 0
sp_client_order 22362 22362 0 0.00 3 48093 362 0 0
sp_customer_reserve 496824 496824 0 0.00 0 36280 177 0 0
sp_fill_shopping_cart 34279 34279 0 0.00 1 15414 37 0 0
sp_fill_shopping_cart_clo_res 496821 496821 0 0.00 0 25609 14 0 0
sp_get_clo_for_invoice 5350 5350 0 0.00 0 38558 309 0 0
sp_kill_cost_storno 16323 16323 0 0.00 0 6210 55 0 0
sp_kill_qstorno_ret_qs2qd 1128181 1128181 0 0.00 0 77102 35 0 0
sp_kill_qty_storno 595795 595795 0 0.00 0 77102 74 0 0
sp_lock_selected_doc 22249 22249 0 0.00 0 6670 4 0 0
sp_make_cost_storno 30021 30021 0 0.00 0 13866 45 0 0
sp_multiply_rows_for_pdistr 30021 30021 0 0.00 0 7783 23 0 0
sp_multiply_rows_for_qdistr 22362 22362 0 0.00 0 48087 166 0 0
sp_pay_from_customer 7627 7627 0 0.00 4 13316 106 0 0
sp_pay_to_supplier 6287 6287 0 0.00 5 13689 119 0 0
sp_payment_common 13914 13914 0 0.00 2 10954 26 0 0
sp_qd_handle_on_cancel_clo 1911 1911 0 0.00 0 10850 314 0 0
sp_qd_handle_on_invoice_upd_sts 10927 10927 0 0.00 0 29039 309 0 0
sp_qd_handle_on_reserve_upd_sts 10758 10758 0 0.00 0 22210 52 0 0
sp_reserve_write_off 10758 10758 0 0.00 6 22250 152 0 0
sp_supplier_invoice 6016 6016 0 0.00 7 96341 3089 0 0
sp_supplier_order 5901 5901 0 0.00 5 46299 1314 0 0
srv_aggregate_perf_data 103 103 0 0.00 2739 459511 20132 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1962 143986 44885 0 0
srv_find_qd_qs_mism 1091485 1091485 0 0.00 0 16832 6 0 0
srv_make_invnt_saldo 601 601 0 0.00 35 23140 981 0 0
srv_make_money_saldo 866 866 0 0.00 0 2503 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 174688 374550 281521 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5362 5362 0 0.00 0 20876 157 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 0 7043 95 0 0
t$perf-norm:sp_cancel_client_order 1911 1911 0 0.00 0 8345 16 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 2142 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 730 730 0 0.00 0 2992 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 1003 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 443 443 0 0.00 0 5727 45 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 27958 1691 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 3917 12 0 0
t$perf-norm:sp_client_order 22362 22362 0 0.00 0 15260 16 0 0
t$perf-norm:sp_customer_reserve 1064 1064 0 0.00 0 1893 5 0 0
t$perf-norm:sp_pay_from_customer 7627 7627 0 0.00 0 3317 7 0 0
t$perf-norm:sp_pay_to_supplier 6287 6287 0 0.00 0 9004 10 0 0
t$perf-norm:sp_reserve_write_off 10758 10758 0 0.00 0 6834 11 0 0
t$perf-norm:sp_supplier_invoice 6016 6016 0 0.00 0 5735 18 0 0
t$perf-norm:sp_supplier_order 5901 5901 0 0.00 0 3005 11 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 83 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 243 5 0 0
t$perf-norm:srv_make_invnt_saldo 601 601 0 0.00 0 4583 21 0 0
t$perf-norm:srv_make_money_saldo 866 866 0 0.00 0 1192 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10882 10698 184 1.69 0 12975 21 0 184
v_all_customers 40250 40250 0 0.00 0 14 1 0 0
v_all_suppliers 33206 33206 0 0.00 0 17 1 0 0
v_all_wares 44724 44724 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 14358 26 0 0
v_cancel_client_order 3822 3822 0 0.00 0 12927 8 0 0
v_cancel_customer_prepayment 1460 1460 0 0.00 0 3336 9 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 2166 5 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 3337 11 0 0
v_cancel_supplier_invoice 900 886 14 1.55 0 14454 40 0 14
v_cancel_supplier_order 854 854 0 0.00 0 1523 7 0 0
v_cancel_write_off 2112 2112 0 0.00 0 14430 29 0 0
v_max_id_clo_ord 5901 5901 0 0.00 0 1302 1 0 0
v_max_id_clo_res 1064 1064 0 0.00 0 1906 17 0 0
v_max_id_ord_sup 6016 6016 0 0.00 0 4923 4 0 0
v_max_non_paid_invoice 1601 1601 0 0.00 0 192 2 0 0
v_max_non_paid_realizn 4200 4200 0 0.00 0 10332 6 0 0
v_min_id_clo_ord 5901 5901 0 0.00 0 1889 5 0 0
v_min_id_clo_res 1064 1064 0 0.00 0 2253 7 0 0
v_min_id_ord_sup 6016 6016 0 0.00 0 6800 10 0 0
v_min_non_paid_invoice 6287 6287 0 0.00 0 10111 15 0 0
v_min_non_paid_realizn 7627 7627 0 0.00 0 10946 18 0 0
v_our_firm 11328 11328 0 0.00 0 25 1 0 0
v_reserve_write_off 21516 21516 0 0.00 0 5709 7 0 0
x_lock_depdocs_on_canc_invoice 11326 11326 0 0.00 0 16944 117 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 11846 300 0 0
x_make_qstorno_1000_1200 5901 5901 0 0.00 2 46293 1245 0 0
x_make_qstorno_1200_2000 6016 6016 0 0.00 3 88506 2947 0 0
x_make_qstorno_2100_3300 496824 496824 0 0.00 0 36279 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-11-2021 01:38:46 to 10-11-2021 01:38:50.

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:08:02 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:03 23665 8192000000 9898836512 10208129024 976523568 10.650 100 82 164 0 1003267904 5235072 21509264
23:10:31 29118 8192000000 9958443040 10282795008 1036461824 11.231 100 85 170 1 1065696576 7495136 21739616
23:12:01 18754 8192000000 9993338128 10327994368 1077815472 11.627 100 86 172 0 1108882576 9750112 21316992
23:13:29 12566 8192000000 10062717664 10414043136 1137698288 12.194 100 81 162 0 1168182304 11769328 18714688
23:14:47 9975 8192000000 10088293360 10448666624 1171747696 12.513 100 83 166 1 1203421808 12657360 19016752
23:15:59 5537 8192000000 10128520976 10498457600 1219389840 12.956 100 80 160 1 1250298208 13023808 17884560
23:17:08 3633 8192000000 10161192912 10537852928 1248743872 13.227 100 84 168 3 1281343216 13249968 19349376
23:18:14 2234 8192000000 10194964752 10576445440 1280737024 13.520 100 91 182 2 1316774016 15486080 20550912
23:19:18 2799 8192000000 10219138176 10602835968 1298452064 13.681 100 86 172 2 1331423696 14562304 18409328
23:20:21 1531 8192000000 10217698880 10613460992 1307637104 13.765 100 86 169 1 1336159600 8435024 20087472
23:21:24 3427 8192000000 10209392608 10617987072 1314963328 13.831 100 41 82 3 1332920800 7673952 10283520
23:22:29 7685 8192000000 10201121808 10623242240 1320177952 13.878 100 23 44 0 1331110896 6383136 4549808
23:23:39 21825 8192000000 10222106160 10643644416 1326275152 13.933 100 38 76 3 1346175936 11649072 8251712
23:25:04 5453 8192000000 10225016528 10645274624 1329165136 13.960 100 22 44 1 1340042304 6235824 4641344
23:26:12 7940 8192000000 10217800304 10635157504 1316233760 13.843 99 19 38 0 1326068768 5726976 4108032
23:27:20 62157 8192000000 10208218896 10626531328 1297293296 13.671 97 41 83 0 1312462592 5106448 10062848
23:29:23 3067 8192000000 10181874240 10591064064 1279062832 13.504 99 25 50 0 1291499712 6105600 6331280
23:30:31 8863 8192000000 10140479920 10540171264 1227929552 13.035 97 28 56 0 1244450016 9819680 6700784
23:31:41 10782 8192000000 10143425920 10548895744 1219626304 12.958 96 19 38 1 1229586320 5493792 4466224
23:32:56 30716 8192000000 10139458464 10540564480 1166110864 12.460 92 10 19 1 1176452336 8510864 1830608
23:34:27 7398 8192000000 10176762128 10583220224 1282423344 13.535 100 28 55 2 1294784528 5939808 6421376
23:35:36 7523 8192000000 10205906864 10612809728 1305993200 13.750 100 39 78 2 1321327248 5037904 10296144
23:36:45 26865 8192000000 10196310192 10617376768 1312724080 13.811 100 19 37 2 1322261232 5232128 4305024
23:38:15 32654 8192000000 10230097328 10640408576 1328669376 13.955 100 47 95 1 1346247744 7580816 9997552
23:39:50 14525 8192000000 10257529312 10674487296 1351081520 14.157 100 49 96 1 1370141968 8362576 10697872
23:41:10 12941 8192000000 10281780880 10699345920 1372040896 14.345 100 66 129 2 1398531312 11573504 14916912
23:42:37 13271 8192000000 10298300304 10719817728 1385830064 14.469 100 52 104 1 1409151456 11865360 11456032
23:44:00 10855 8192000000 10308154848 10733965312 1397074080 14.569 100 40 80 2 1421009856 14543648 9392128
23:45:22 10335 8192000000 10326113664 10747641856 1391315344 14.518 99 44 88 3 1427448448 24751968 11381136
23:46:37 5196 8192000000 10341877472 10765750272 1415492912 14.733 100 59 118 2 1454579360 24695520 14390928
23:47:46 10261 8192000000 10344697264 10772295680 1413302576 14.713 99 57 112 1 1451553408 24314960 13935872
23:49:00 8475 8192000000 10360348848 10785509376 1427235616 14.837 100 53 106 0 1470573312 29799408 13538288
23:50:13 2248 8192000000 10372674720 10792632320 1428966528 14.852 100 64 126 1 1477981280 33591488 15423264
23:51:18 2394 8192000000 10374039584 10790367232 1426178176 14.827 100 80 158 1 1473209056 28210128 18820752
23:52:28 2556 8192000000 10377809248 10797727744 1426809648 14.833 100 78 155 2 1481758880 35231952 19717280
23:53:33 5646 8192000000 10374596288 10795020288 1422121648 14.792 100 74 144 2 1479312480 38507984 18682848
23:54:48 2681 8192000000 10391025840 10806464512 1431874368 14.878 100 84 166 0 1496656352 43924304 20857680
23:55:59 1961 8192000000 10381418704 10789519360 1420434608 14.777 100 85 169 2 1479067888 38089184 20544096
23:57:06 11033 8192000000 10379836048 10803310592 1413133936 14.712 99 58 116 5 1479151232 50712288 15305008
23:58:21 8848 8192000000 10369657696 10791030784 1400273984 14.597 99 47 94 0 1463976672 50783536 12919152
23:59:36 7908 8192000000 10352231760 10773168128 1389876928 14.505 99 54 110 2 1448709088 44632784 14199376
00:00:53 9188 8192000000 10344600464 10777276416 1395020448 14.551 100 30 61 1 1450446160 46698448 8727264
00:03:07 29781 8192000000 10318255248 10768207872 1376065568 14.381 100 17 34 0 1428241472 47191360 4984544
00:05:08 68657 8192000000 10267888752 10712592384 1315729472 13.838 96 8 17 1 1337588800 19949232 1910096
00:07:39 45667 8192000000 10249222496 10679574528 1315499360 13.836 98 15 30 1 1326963136 8262304 3201472
00:09:26 101165 8192000000 10260096928 10701271040 1349866144 14.146 100 22 44 2 1363076672 8223040 4987488
00:12:31 36694 8192000000 10250571760 10685820928 1300790608 13.702 96 29 58 0 1315186992 7154688 7241696
00:14:09 102242 8192000000 10264862336 10706620416 1344612000 14.099 99 24 47 1 1359787664 9579344 5596320
00:16:56 30671 8192000000 10254427584 10694934528 1328145008 13.950 97 23 46 2 1347654688 13743040 5766640
00:18:33 17749 8192000000 10304581232 10732601344 1371959744 14.345 99 50 99 1 1405424976 21403296 12061936
00:19:53 66099 8192000000 10293246688 10749095936 1346146192 14.113 97 18 36 0 1376001376 25095232 4759952
00:22:00 32023 8192000000 10281411200 10733309952 1359248112 14.231 99 12 24 1 1393176832 30754880 3173840
00:23:48 18292 8192000000 10286848576 10732871680 1367340784 14.303 100 31 61 1 1398552816 23705392 7506640
00:25:29 57728 8192000000 10302386272 10741051392 1387403152 14.483 100 29 59 0 1401166816 7905664 5858000
00:27:30 30743 8192000000 10296295024 10746146816 1393385472 14.536 100 24 48 1 1408868848 9149296 6334080
00:29:01 42217 8192000000 10294786256 10739748864 1362636384 14.261 98 23 46 1 1375714768 7990336 5088048
00:30:55 74969 8192000000 10299731968 10757804032 1382144608 14.436 99 17 32 2 1398884544 12794208 3945728
00:33:11 32665 8192000000 10294217184 10734129152 1360269728 14.240 98 28 56 0 1375165296 9161248 5734320
00:34:46 12089 8192000000 10292351872 10727636992 1352730032 14.172 98 43 86 0 1374661136 12738320 9192784
00:36:01 138661 8192000000 10280404688 10730643456 1365214800 14.284 99 8 16 0 1382237344 14990608 2031936
00:39:20 57588 8192000000 10283498224 10739183616 1352623200 14.171 98 17 34 1 1368338224 12249808 3465216
00:41:24 50253 8192000000 10262638352 10720415744 1349783952 14.146 98 13 26 0 1358905536 6429296 2692288
00:43:14 60596 8192000000 10251697888 10705240064 1323980976 13.913 96 14 28 2 1335148480 7665520 3501984
00:45:15 39638 8192000000 10266405760 10714296320 1354470384 14.188 99 31 62 1 1371619008 10057840 7090784
00:47:07 48413 8192000000 10266057888 10714537984 1334303200 14.006 98 40 80 1 1355685568 11281472 10100896
00:48:56 121684 8192000000 10263592032 10715279360 1276555040 13.482 93 12 22 1 1291428752 12334864 2538848
00:52:06 41856 8192000000 10270648592 10715627520 1344613520 14.099 99 28 56 1 1365051888 14716320 5722048
00:53:48 49497 8192000000 10273998480 10723422208 1364568784 14.278 100 34 68 1 1384897616 13049248 7279584
00:55:37 51720 8192000000 10260121296 10707652608 1303431328 13.726 95 32 62 1 1319908480 9068032 7409120
00:57:34 135095 8192000000 10263123264 10708770816 1320329968 13.880 97 28 56 1 1334014128 7289920 6394240
01:00:51 24845 8192000000 10269268592 10716340224 1363344688 14.267 100 38 76 0 1384706624 12028688 9333248
01:02:25 46420 8192000000 10268199504 10724798464 1361389856 14.250 100 9 18 0 1377413824 13787968 2236000
01:04:26 58109 8192000000 10275209776 10736058368 1322335248 13.898 96 21 39 1 1346076176 18844720 4896208
01:06:24 64914 8192000000 10254591120 10703724544 1322136976 13.896 97 7 13 0 1343215920 19598960 1479984
01:08:29 27688 8192000000 10293501216 10733236224 1366661584 14.297 99 38 76 0 1385382672 10467904 8253184
01:10:03 143986 8192000000 10281456112 10742423552 1350651536 14.153 99 16 33 0 1382596368 27908240 4036592
01:13:27 16966 8192000000 10305445456 10737033216 1368503376 14.314 99 34 65 3 1382053136 6064976 7484784
01:14:48 61640 8192000000 10283290672 10745634816 1352901536 14.174 98 10 20 1 1368040928 12979024 2160368
01:17:34 78462 8192000000 10285534816 10727837696 1349044048 14.139 98 16 32 0 1363970400 10812064 4114288
01:19:57 135193 8192000000 10279024784 10730094592 1360904432 14.245 99 19 38 1 1372671888 7087120 4680336
01:24:12 48634 8192000000 10281660896 10752270336 1380763728 14.423 100 6 12 0 1394489808 12638064 1088016
01:26:01 32929 8192000000 10281348816 10735624192 1371786064 14.343 99 25 49 0 1385103968 7673920 5643984
01:27:46 53361 8192000000 10248888176 10699018240 1311511840 13.800 95 16 33 0 1322694224 7041008 4141376
01:29:45 87054 8192000000 10272857856 10718334976 1295047952 13.650 94 22 42 0 1310549216 10427312 5073952
01:32:13 44813 8192000000 10274149168 10712784896 1336771744 14.028 98 25 50 0 1359261648 15669648 6820256
01:34:06 81549 8192000000 10263061392 10713088000 1304286048 13.734 96 17 34 0 1319637472 10727920 4623504
01:36:28 98584 8192000000 9268654816 9542107136 394695968 4.596 30 4 8 0 399158160 3692576 769616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-11-2021 01:38:52 to 10-11-2021 01:39:07.

Exceptions occured during test was in run

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

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

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          247775
MON$OLDEST_ACTIVE               247776
MON$OLDEST_SNAPSHOT             247776
MON$NEXT_TRANSACTION            247776
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                       2018752
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:57: 
:58: mscs-test-161.budzdorov.ru    Wed Nov 10 01:38:08 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Wed Nov 10 01:38:11 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'

10.11.21 01:39:11. 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-10 01:39:14 No severe PSQL-related problems occured
10.11.21 01:39:15. End of report.