Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.09.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 16139204 kB
MemAvailable 30855092 kB
Buffers 0 kB
Cached 15574824 kB
SwapCached 416 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-16 19:37:49
DTS_END                         2021-09-16 19:37:49.5440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56842
TRN_ID                          235032
ATT_ID                          692
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: 9376.77 20048 1125212
customer order: creation 168.94 366 20273
customer order: refuse 14.53 369 1743
order to supplier: creation 44.89 1214 5387
order to supplier: removal 3.34 107366 401
invoice (draft): creation 44.90 3130 5388
invoice (draft): removal 52.99 742 6359
invoice accept: apply 41.03 18487 4923
invoice accept: cancel 48.88 827 5866
customer reserve: creation 3852.75 181 462330
customer reserve: removal 4811.21 60 577345
realization accept: apply 81.20 184 9744
realization accept: cancel 86.33 109 10359
payment from customer: creation 57.56 118 6907
payment from customer: removal 5.58 87 670
payment to supplier: creation 47.38 131 5686
payment to supplier: removal 3.43 100 412
service: total inventory turnovers 4.63 1133 555
service: total monetary turnovers 6.31 30 757
service: aggregate perf. data 0.86 18541 103
service: refresh index statistics 0.03 247777 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-09-2021 19:40:14 to 16-09-2021 19:40:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4636
TEST_TIME 2 4744
TEST_TIME 3 5017
TEST_TIME 4 4932
TEST_TIME 5 4902
TEST_TIME 6 5777
TEST_TIME 7 5356
TEST_TIME 8 5632
TEST_TIME 9 5548
TEST_TIME 10 5678
TEST_TIME 11 5747
TEST_TIME 12 5636
TEST_TIME 13 5627
TEST_TIME 14 5688
TEST_TIME 15 6196
TEST_TIME 16 6224
TEST_TIME 17 6508
TEST_TIME 18 6805
TEST_TIME 19 6880
TEST_TIME 20 7426
TEST_TIME 21 7530
TEST_TIME 22 8059
TEST_TIME 23 8392
TEST_TIME 24 8434
TEST_TIME 25 8342
TEST_TIME 26 8548
TEST_TIME 27 9084
TEST_TIME 28 9441
TEST_TIME 29 10169
TEST_TIME 30 10209
TEST_TIME 31 10263
TEST_TIME 32 10212
TEST_TIME 33 10245
TEST_TIME 34 10355
TEST_TIME 35 10196
TEST_TIME 36 10147
TEST_TIME 37 10061
TEST_TIME 38 9918
TEST_TIME 39 9834
TEST_TIME 40 9911
TEST_TIME 41 9794
TEST_TIME 42 9693
TEST_TIME 43 9645
TEST_TIME 44 9499
TEST_TIME 45 9695
TEST_TIME 46 9894
TEST_TIME 47 9814
TEST_TIME 48 9704
TEST_TIME 49 9635
TEST_TIME 50 9476
TEST_TIME 51 9678
TEST_TIME 52 9819
TEST_TIME 53 9744
TEST_TIME 54 9708
TEST_TIME 55 9828
TEST_TIME 56 9731
TEST_TIME 57 9763
TEST_TIME 58 9789
TEST_TIME 59 9826
TEST_TIME 60 9838
TEST_TIME 61 9833
TEST_TIME 62 9924
TEST_TIME 63 9949
TEST_TIME 64 9950
TEST_TIME 65 9865
TEST_TIME 66 9763
TEST_TIME 67 9680
TEST_TIME 68 9711
TEST_TIME 69 9763
TEST_TIME 70 9687
TEST_TIME 71 9690
TEST_TIME 72 9614
TEST_TIME 73 9650
TEST_TIME 74 9560
TEST_TIME 75 9568
TEST_TIME 76 9566
TEST_TIME 77 9591
TEST_TIME 78 9589
TEST_TIME 79 9550
TEST_TIME 80 9515
TEST_TIME 81 9561
TEST_TIME 82 9640
TEST_TIME 83 9634
TEST_TIME 84 9600
TEST_TIME 85 9617
TEST_TIME 86 9678
TEST_TIME 87 9708
TEST_TIME 88 9710
TEST_TIME 89 9729
TEST_TIME 90 9831
TEST_TIME 91 9775
TEST_TIME 92 9688
TEST_TIME 93 9610
TEST_TIME 94 9606
TEST_TIME 95 9619
TEST_TIME 96 9557
TEST_TIME 97 9504
TEST_TIME 98 9486
TEST_TIME 99 9474
TEST_TIME 100 9526
TEST_TIME 101 9510
TEST_TIME 102 9507
TEST_TIME 103 9527
TEST_TIME 104 9549
TEST_TIME 105 9543
TEST_TIME 106 9508
TEST_TIME 107 9549
TEST_TIME 108 9596
TEST_TIME 109 9557
TEST_TIME 110 9603
TEST_TIME 111 9633
TEST_TIME 112 9633
TEST_TIME 113 9564
TEST_TIME 114 9537
TEST_TIME 115 9489
TEST_TIME 116 9486
TEST_TIME 117 9497
TEST_TIME 118 9453
TEST_TIME 119 9439
TEST_TIME 120 9383

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-09-2021 19:40:18 to 16-09-2021 19:40:21.

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 1552657 1552657 0 0.00 0 20735 6 0 0
doc_list_biud 1552652 1552652 0 0.00 0 58309 28 0 0
sp_add_invoice_to_stock 5023 4923 100 1.99 39 166726 18487 0 100
sp_cancel_adding_invoice 5866 5866 0 0.00 5 46750 828 0 0
sp_cancel_client_order 1743 1743 0 0.00 4 17741 369 0 0
sp_cancel_customer_reserve 577345 577345 0 0.00 1 58310 60 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 6303 88 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 14204 101 0 0
sp_cancel_supplier_invoice 6364 6359 5 0.07 2 13123 742 0 5
sp_cancel_supplier_order 401 401 0 0.00 7 848370 107367 0 0
sp_cancel_write_off 10359 10359 0 0.00 1 12742 109 0 0
sp_client_order 20273 20273 0 0.00 3 60123 366 0 0
sp_customer_reserve 462330 462330 0 0.00 0 93083 181 0 0
sp_fill_shopping_cart 31037 31037 0 0.00 1 12375 37 0 0
sp_fill_shopping_cart_clo_res 462323 462323 0 0.00 0 15237 14 0 0
sp_get_clo_for_invoice 4915 4915 0 0.00 0 27100 277 0 0
sp_kill_cost_storno 17307 17307 0 0.00 0 16814 55 0 0
sp_kill_qstorno_ret_qs2qd 1171809 1171809 0 0.00 0 58306 32 0 0
sp_kill_qty_storno 616730 616730 0 0.00 0 58309 69 0 0
sp_lock_selected_doc 20398 20398 0 0.00 0 8559 7 0 0
sp_make_cost_storno 27252 27252 0 0.00 0 12923 53 0 0
sp_multiply_rows_for_pdistr 27251 27251 0 0.00 0 6025 26 0 0
sp_multiply_rows_for_qdistr 20272 20272 0 0.00 0 60096 153 0 0
sp_pay_from_customer 6907 6907 0 0.00 4 14545 119 0 0
sp_pay_to_supplier 5686 5686 0 0.00 5 13333 132 0 0
sp_payment_common 12593 12593 0 0.00 1 10829 29 0 0
sp_qd_handle_on_cancel_clo 1743 1743 0 0.00 0 13350 326 0 0
sp_qd_handle_on_invoice_upd_sts 10781 10781 0 0.00 0 13840 316 0 0
sp_qd_handle_on_reserve_upd_sts 9742 9742 0 0.00 0 20795 62 0 0
sp_reserve_write_off 9744 9744 0 0.00 7 20822 185 0 0
sp_supplier_invoice 5388 5388 0 0.00 6 82298 3130 0 0
sp_supplier_order 5387 5387 0 0.00 5 32542 1214 0 0
srv_aggregate_perf_data 103 103 0 0.00 2820 223968 18541 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1469 128768 47344 0 0
srv_find_qd_qs_mism 1077864 1077864 0 0.00 0 20734 5 0 0
srv_make_invnt_saldo 555 555 0 0.00 46 45301 1133 0 0
srv_make_money_saldo 757 757 0 0.00 1 4142 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 57669 389238 247777 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4923 4923 0 0.00 0 14180 178 0 0
t$perf-norm:sp_cancel_adding_invoice 443 443 0 0.00 0 3001 83 0 0
t$perf-norm:sp_cancel_client_order 1743 1743 0 0.00 0 4360 11 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 1979 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 5186 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 818 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 4717 26 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 19445 1704 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 5236 12 0 0
t$perf-norm:sp_client_order 20273 20273 0 0.00 0 10556 19 0 0
t$perf-norm:sp_customer_reserve 999 999 0 0.00 0 2154 6 0 0
t$perf-norm:sp_pay_from_customer 6907 6907 0 0.00 0 7100 15 0 0
t$perf-norm:sp_pay_to_supplier 5686 5686 0 0.00 0 4639 7 0 0
t$perf-norm:sp_reserve_write_off 9744 9744 0 0.00 0 7912 14 0 0
t$perf-norm:sp_supplier_invoice 5388 5388 0 0.00 0 5841 14 0 0
t$perf-norm:sp_supplier_order 5387 5387 0 0.00 0 7164 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 79 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 64 2 0 0
t$perf-norm:srv_make_invnt_saldo 555 555 0 0.00 0 646 15 0 0
t$perf-norm:srv_make_money_saldo 757 757 0 0.00 0 4250 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10028 9828 200 1.99 0 13592 23 0 200
v_all_customers 37050 37050 0 0.00 0 15 1 0 0
v_all_suppliers 29764 29764 0 0.00 0 38 1 0 0
v_all_wares 40544 40544 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 886 886 0 0.00 0 1448 8 0 0
v_cancel_client_order 3486 3486 0 0.00 0 2642 7 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 3032 11 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 1922 6 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 14107 22 0 0
v_cancel_supplier_invoice 806 796 10 1.24 0 2492 31 0 10
v_cancel_supplier_order 802 802 0 0.00 0 2004 7 0 0
v_cancel_write_off 1990 1990 0 0.00 0 12632 21 0 0
v_max_id_clo_ord 5385 5385 0 0.00 0 151 1 0 0
v_max_id_clo_res 999 999 0 0.00 0 3175 15 0 0
v_max_id_ord_sup 5380 5380 0 0.00 0 5587 6 0 0
v_max_non_paid_invoice 1569 1569 0 0.00 0 686 3 0 0
v_max_non_paid_realizn 3526 3526 0 0.00 0 1976 4 0 0
v_min_id_clo_ord 5385 5385 0 0.00 0 3561 9 0 0
v_min_id_clo_res 999 999 0 0.00 0 4199 14 0 0
v_min_id_ord_sup 5380 5380 0 0.00 0 6488 8 0 0
v_min_non_paid_invoice 5686 5686 0 0.00 0 10819 18 0 0
v_min_non_paid_realizn 6907 6907 0 0.00 0 8065 20 0 0
v_our_firm 10256 10256 0 0.00 0 16 1 0 0
v_reserve_write_off 19484 19484 0 0.00 0 14110 10 0 0
x_lock_depdocs_on_canc_invoice 11827 11827 0 0.00 0 13268 126 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 25251 432 0 0
x_make_qstorno_1000_1200 5387 5387 0 0.00 1 32525 1132 0 0
x_make_qstorno_1200_2000 5388 5388 0 0.00 3 82124 3006 0 0
x_make_qstorno_2100_3300 462330 462330 0 0.00 0 93082 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-09-2021 19:40:22 to 16-09-2021 19:40:26.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:52 3 8192000000 8801291696 8812597248 7256432 0.088 2 1 2 0 7375808 56816 62560
17:08:53 32016 8192000000 10374345472 10696216576 1425947136 14.825 100 86 171 1 1460977088 6192288 28837664
17:10:29 11280 8192000000 10441265184 10773336064 1500729216 15.483 100 87 174 0 1536919536 8601376 27588944
17:11:51 9830 8192000000 10482285680 10822701056 1540987696 15.832 100 83 166 0 1576344848 9702128 25655024
17:13:03 18040 8192000000 10529594144 10883592192 1593900016 16.287 100 79 158 0 1627789856 10130192 23759648
17:14:25 12413 8192000000 10609025360 10977816576 1673020240 16.959 100 79 157 1 1708393808 11999136 23374432
17:15:42 7829 8192000000 10677118480 11053936640 1736112496 17.486 100 71 140 1 1768239840 12438832 19688512
17:16:53 4445 8192000000 10730768768 11112816640 1798239552 17.999 100 80 160 1 1833484512 13008688 22236272
17:18:00 2379 8192000000 10778513104 11162275840 1834831376 18.299 100 86 172 1 1873698720 13464848 25402496
17:19:04 2827 8192000000 10811080512 11199647744 1862016688 18.520 100 88 176 0 1899693744 13084144 24592912
17:20:09 1143 8192000000 10824885392 11213578240 1879642672 18.662 100 93 187 0 1919454960 13601904 26210384
17:21:13 5819 8192000000 10783756016 11208175616 1874763040 18.623 100 33 67 1 1895447824 9617952 11066832
17:22:24 8234 8192000000 10802860976 11226505216 1886298624 18.716 100 22 44 2 1903568592 10306464 6963504
17:23:35 9608 8192000000 10801136864 11234906112 1896540400 18.798 100 28 54 2 1912236992 8196288 7500304
17:24:47 6208 8192000000 10830016608 11248254976 1911861760 18.922 100 40 82 5 1929296448 5324848 12109840
17:25:54 22910 8192000000 10872057664 11273093120 1922923472 19.010 100 72 142 1 1949476176 5204576 21348128
17:27:17 7292 8192000000 10833646832 11269738496 1918706800 18.976 100 28 56 1 1931892608 5502000 7683808
17:28:25 10282 8192000000 10813356208 11251187712 1881674160 18.679 99 18 36 0 1896567680 9677248 5216272
17:29:38 50624 8192000000 10767690352 11167334400 1764575120 17.722 95 48 98 2 1783592496 4996608 14020768
17:31:30 6579 8192000000 10669446400 11076005888 1699942688 17.185 94 22 44 1 1712291696 5975296 6373712
17:32:36 5380 8192000000 10728257616 11138654208 1777366448 17.828 98 26 53 1 1790436288 5140368 7929472
17:33:43 9351 8192000000 10765169360 11179646976 1840146944 18.342 100 40 80 0 1858338896 5757392 12434560
17:34:52 42984 8192000000 10772021712 11200782336 1857684112 18.485 100 36 70 1 1873475696 4879168 10912416
17:36:38 9372 8192000000 10806111840 11223928832 1877629264 18.646 100 35 69 1 1893020448 6344144 9047040
17:37:55 2947 8192000000 10835672880 11251519488 1907874672 18.890 100 40 77 1 1922620768 4808656 9937440
17:39:03 14868 8192000000 10872997520 11286536192 1934477664 19.103 100 66 130 0 1965573328 11652192 19443472
17:40:23 11559 8192000000 10874893296 11303763968 1940130256 19.148 100 49 95 4 1967004832 12902912 13971664
17:41:38 25476 8192000000 10894734128 11319861248 1957246048 19.284 100 50 98 2 1982525984 11374128 13905808
17:43:06 43285 8192000000 10896265520 11332022272 1964329600 19.340 100 30 60 2 1984816032 11490416 8996016
17:44:54 6424 8192000000 10923486864 11353350144 1981977472 19.480 100 52 102 1 2012120784 15449312 14694000
17:46:02 10863 8192000000 10942947088 11376111616 1982820912 19.487 99 52 104 0 2018732608 20442080 15469616
17:47:25 15736 8192000000 10949843936 11388010496 2000561152 19.627 100 48 96 3 2039252656 24375680 14315824
17:48:57 7245 8192000000 10961005360 11395379200 1994366400 19.578 99 56 110 4 2034529136 23918272 16244464
17:50:19 17634 8192000000 10973469920 11398434816 1996710608 19.597 99 67 134 0 2047905776 30818960 20376208
17:51:42 6902 8192000000 10956602512 11388731392 1998451776 19.611 100 47 93 0 2040190016 27445968 14292272
17:52:55 2766 8192000000 10963694896 11386085376 1996312976 19.594 100 69 138 0 2047363152 29444560 21605616
17:54:02 6733 8192000000 10973028304 11400364032 1997092176 19.600 99 60 120 0 2051457568 35141840 19223552
17:55:14 8714 8192000000 10968785264 11400650752 2002107280 19.639 100 63 126 0 2061232704 39288704 19836720
17:56:27 1468 8192000000 10976898944 11393822720 1991972992 19.559 100 74 148 0 2055788256 41369392 22445872
17:57:32 2815 8192000000 10997806432 11412840448 2005565600 19.667 100 79 158 0 2076350544 45836048 24948896
17:58:40 1939 8192000000 10978494192 11405291520 1994367952 19.578 100 66 132 1 2058991408 42728576 21894880
17:59:51 10746 8192000000 10951140976 11392212992 1977737632 19.447 100 36 70 1 2029610416 40087472 11785312
18:01:11 17972 8192000000 10907388272 11348013056 1934351520 19.102 99 32 63 1 1982040704 37178352 10510832
18:02:34 26285 8192000000 10895984592 11337596928 1911635184 18.920 99 23 46 1 1959959440 40020128 8304128
18:04:15 12541 8192000000 10878913552 11344379904 1919911376 18.986 100 20 40 0 1969982816 43055488 7015952
18:05:46 39375 8192000000 10863818416 11323883520 1911047024 18.915 100 15 30 0 1960382432 43984048 5351360
18:07:28 48653 8192000000 10844999568 11301249024 1888384544 18.733 98 7 14 0 1905178400 15199760 1594096
18:09:30 43616 8192000000 10879109280 11325956096 1937564336 19.127 100 15 30 0 1949202640 7623952 4014352
18:11:19 51583 8192000000 10865534240 11310342144 1881312000 18.676 97 5 10 0 1895078672 12689104 1077568
18:13:15 51237 8192000000 10885010704 11314671616 1896201552 18.796 98 31 60 0 1915482048 10194896 9085600
18:15:14 121523 8192000000 10874696080 11332427776 1923924016 19.018 99 33 66 1 1948447856 15056304 9467536
18:18:18 124689 8192000000 10900841200 11363131392 1927367648 19.046 99 12 24 0 1941240208 10499808 3372752
18:21:24 50861 8192000000 10912900528 11384561664 1971086608 19.394 100 26 52 0 1999004912 19810928 8107376
18:23:18 43845 8192000000 10887088656 11378630656 1965199616 19.347 100 13 26 1 1988139296 19129360 3810320
18:25:04 41320 8192000000 10901605504 11372064768 1961740448 19.320 99 10 20 1 1978677584 14306624 2630512
18:26:46 83377 8192000000 10918525200 11394527232 1984912992 19.504 100 15 30 1 2014172896 24655296 4604608
18:29:28 53727 8192000000 10885520912 11350978560 1915103504 18.948 97 10 20 1 1929738336 11302320 3332512
18:31:27 26548 8192000000 10915713920 11378749440 1984682960 19.502 100 34 68 1 2006515712 11815376 10017376
18:32:56 30875 8192000000 10923661808 11387736064 1995127456 19.584 100 21 43 0 2007865856 7019376 5719024
18:34:31 91011 8192000000 10930799664 11411066880 2004573104 19.659 100 14 28 1 2021210416 12070192 4567120
18:37:02 62788 8192000000 10918794576 11394097152 1965421088 19.349 98 17 34 1 1982108064 11082368 5604608
18:39:05 67122 8192000000 10924321312 11396935680 1974241040 19.419 99 10 20 1 1990837408 13838208 2758160
18:41:27 103047 8192000000 10877524640 11352248320 1936996608 19.123 98 15 30 1 1957305600 15684304 4624688
18:44:14 56688 8192000000 10911990512 11379830784 1976713216 19.439 100 30 60 1 1998294336 13248192 8332928
18:46:12 41344 8192000000 10908954448 11386990592 1962831872 19.329 99 13 26 0 1976252096 9417760 4002464
18:47:54 45409 8192000000 10913440096 11383361536 1982372304 19.483 100 15 28 0 1997083296 9955120 4755872
18:49:40 86209 8192000000 10890878864 11373948928 1940514800 19.151 98 14 28 1 1960357968 15750304 4092864
18:52:09 57002 8192000000 10870546304 11343863808 1946983472 19.202 100 14 28 0 1964806496 13401680 4421344
18:54:13 76009 8192000000 10857294016 11345235968 1925976768 19.035 99 5 10 0 1940854608 12950016 1927824
18:56:30 43263 8192000000 10847680880 11303256064 1875703696 18.630 98 26 52 1 1893638880 10586432 7348752
18:58:15 36701 8192000000 10844282448 11293859840 1852640720 18.444 97 26 50 3 1873344032 13556160 7147152
19:00:05 63619 8192000000 10826743360 11283939328 1835091232 18.301 96 22 44 1 1851501104 10744256 5665616
19:02:17 27125 8192000000 10879797696 11338547200 1936036064 19.115 100 44 88 2 1963770288 15707968 12026256
19:03:47 69122 8192000000 10846065072 11304644608 1837142000 18.318 95 14 28 1 1853365216 12504928 3718288
19:05:59 75244 8192000000 10886899120 11356172288 1935783584 19.113 100 19 38 2 1956738608 15334464 5620560
19:08:14 109244 8192000000 10900636544 11365048320 1961941648 19.321 100 25 48 2 1982273920 13257136 7075136
19:11:07 38368 8192000000 10882782464 11348058112 1945779712 19.193 99 19 38 0 1962554512 11531744 5243056
19:12:46 68819 8192000000 10901787552 11375202304 1958785072 19.296 99 14 28 0 1972309600 9517760 4006768
19:15:36 61599 8192000000 10903560880 11388686336 1968297184 19.372 99 11 22 0 1983258304 11541408 3419712
19:17:43 118095 8192000000 10918130272 11413078016 1938185216 19.132 97 16 32 0 1958479440 16157584 4136640
19:20:41 58056 8192000000 10908784960 11394543616 1960479280 19.310 98 16 31 3 1976324000 11481376 4363344
19:22:42 75778 8192000000 10891635520 11349397504 1893171760 18.771 95 16 32 1 1911281584 13919584 4190240
19:24:59 67139 8192000000 10926075344 11377614848 1949527504 19.223 98 20 40 0 1964672960 9521904 5623552
19:27:07 45805 8192000000 10897236256 11376549888 1949151600 19.220 98 20 40 1 1967744656 13288032 5305024
19:28:57 128768 8192000000 10910778352 11361312768 1899512448 18.822 95 40 80 1 1921537392 10943952 11080992
19:32:17 47471 8192000000 10924539632 11385012224 1989232816 19.538 100 38 76 2 2008222208 7859824 11129568
19:34:04 62000 8192000000 10892704400 11380838400 1962106272 19.323 99 10 18 2 1973646048 8379072 3160704
19:36:06 80990 8192000000 10920157808 11399483392 1981745056 19.479 99 11 22 0 1993935808 8728768 3461984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-09-2021 19:40:28 to 16-09-2021 19:40:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-09-2021 19:40:44 to 16-09-2021 19:40:46.

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          233402
MON$OLDEST_ACTIVE               235221
MON$OLDEST_SNAPSHOT             235221
MON$NEXT_TRANSACTION            235221
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                       2029152
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.33497 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33497 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33497 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33497 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 ---
--- end of diff output ---

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

16.09.21 19:40:47. 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-16 19:40:49 No severe PSQL-related problems occured
16.09.21 19:40:50. End of report.