Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.09.2021 01:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15897884 kB
MemAvailable 30871440 kB
Buffers 0 kB
Cached 15759916 kB
SwapCached 340 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-05 01:37:45
DTS_END                         2021-09-05 01:37:45.8710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42632
TRN_ID                          242077
ATT_ID                          617
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: 9525.57 19018 1143068
customer order: creation 175.33 343 21039
customer order: refuse 14.92 345 1790
order to supplier: creation 46.77 1260 5612
order to supplier: removal 3.53 94357 423
invoice (draft): creation 46.41 3156 5569
invoice (draft): removal 54.70 780 6564
invoice accept: apply 42.15 17525 5058
invoice accept: cancel 49.75 811 5970
customer reserve: creation 3902.80 172 468336
customer reserve: removal 4885.72 52 586286
realization accept: apply 84.49 183 10139
realization accept: cancel 86.88 106 10426
payment from customer: creation 60.41 132 7249
payment from customer: removal 6.39 76 767
payment to supplier: creation 49.79 146 5975
payment to supplier: removal 3.54 73 425
service: total inventory turnovers 4.60 1257 552
service: total monetary turnovers 6.58 32 789
service: aggregate perf. data 0.79 24471 95
service: refresh index statistics 0.03 235090 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-09-2021 01:38:55 to 05-09-2021 01:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3108
TEST_TIME 2 4858
TEST_TIME 3 3423
TEST_TIME 4 3907
TEST_TIME 5 4434
TEST_TIME 6 4307
TEST_TIME 7 4625
TEST_TIME 8 5231
TEST_TIME 9 5405
TEST_TIME 10 5698
TEST_TIME 11 5912
TEST_TIME 12 5945
TEST_TIME 13 6245
TEST_TIME 14 6717
TEST_TIME 15 6777
TEST_TIME 16 7233
TEST_TIME 17 7262
TEST_TIME 18 7789
TEST_TIME 19 8122
TEST_TIME 20 8405
TEST_TIME 21 8441
TEST_TIME 22 8451
TEST_TIME 23 8606
TEST_TIME 24 8922
TEST_TIME 25 9365
TEST_TIME 26 9527
TEST_TIME 27 9797
TEST_TIME 28 10041
TEST_TIME 29 10431
TEST_TIME 30 10928
TEST_TIME 31 10930
TEST_TIME 32 10791
TEST_TIME 33 10734
TEST_TIME 34 10818
TEST_TIME 35 10721
TEST_TIME 36 10657
TEST_TIME 37 10518
TEST_TIME 38 10508
TEST_TIME 39 10596
TEST_TIME 40 10476
TEST_TIME 41 10489
TEST_TIME 42 10310
TEST_TIME 43 10176
TEST_TIME 44 10154
TEST_TIME 45 10122
TEST_TIME 46 10008
TEST_TIME 47 9959
TEST_TIME 48 9854
TEST_TIME 49 9839
TEST_TIME 50 10036
TEST_TIME 51 10139
TEST_TIME 52 10060
TEST_TIME 53 10087
TEST_TIME 54 9970
TEST_TIME 55 9861
TEST_TIME 56 9959
TEST_TIME 57 9933
TEST_TIME 58 9899
TEST_TIME 59 9888
TEST_TIME 60 9843
TEST_TIME 61 9783
TEST_TIME 62 9813
TEST_TIME 63 9925
TEST_TIME 64 9921
TEST_TIME 65 9992
TEST_TIME 66 9968
TEST_TIME 67 9983
TEST_TIME 68 9951
TEST_TIME 69 9999
TEST_TIME 70 9949
TEST_TIME 71 10062
TEST_TIME 72 10043
TEST_TIME 73 10120
TEST_TIME 74 10037
TEST_TIME 75 9909
TEST_TIME 76 9942
TEST_TIME 77 9921
TEST_TIME 78 9845
TEST_TIME 79 9924
TEST_TIME 80 9838
TEST_TIME 81 9787
TEST_TIME 82 9753
TEST_TIME 83 9732
TEST_TIME 84 9735
TEST_TIME 85 9835
TEST_TIME 86 9788
TEST_TIME 87 9811
TEST_TIME 88 9849
TEST_TIME 89 9828
TEST_TIME 90 9882
TEST_TIME 91 9918
TEST_TIME 92 9900
TEST_TIME 93 9930
TEST_TIME 94 9936
TEST_TIME 95 9988
TEST_TIME 96 10050
TEST_TIME 97 10019
TEST_TIME 98 9958
TEST_TIME 99 9901
TEST_TIME 100 9854
TEST_TIME 101 9800
TEST_TIME 102 9743
TEST_TIME 103 9754
TEST_TIME 104 9781
TEST_TIME 105 9761
TEST_TIME 106 9694
TEST_TIME 107 9669
TEST_TIME 108 9688
TEST_TIME 109 9651
TEST_TIME 110 9651
TEST_TIME 111 9647
TEST_TIME 112 9606
TEST_TIME 113 9550
TEST_TIME 114 9531
TEST_TIME 115 9527
TEST_TIME 116 9509
TEST_TIME 117 9520
TEST_TIME 118 9494
TEST_TIME 119 9550
TEST_TIME 120 9531

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-09-2021 01:38:59 to 05-09-2021 01:39:02.

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 1577760 1577760 0 0.00 0 36353 6 0 0
doc_list_biud 1577759 1577759 0 0.00 0 29945 25 0 0
sp_add_invoice_to_stock 5158 5058 100 1.93 40 200378 17525 0 100
sp_cancel_adding_invoice 5970 5970 0 0.00 6 52617 811 0 0
sp_cancel_client_order 1790 1790 0 0.00 4 7870 345 0 0
sp_cancel_customer_reserve 586286 586286 0 0.00 1 29946 52 0 0
sp_cancel_pay_from_customer 767 767 0 0.00 3 6009 76 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 2 2933 74 0 0
sp_cancel_supplier_invoice 6569 6564 5 0.07 2 16824 780 0 5
sp_cancel_supplier_order 423 423 0 0.00 7 1080170 94357 0 0
sp_cancel_write_off 10426 10426 0 0.00 1 17560 106 0 0
sp_client_order 21039 21039 0 0.00 4 30014 344 0 0
sp_customer_reserve 468336 468336 0 0.00 0 70876 172 0 0
sp_fill_shopping_cart 32212 32212 0 0.00 1 10083 38 0 0
sp_fill_shopping_cart_clo_res 468323 468323 0 0.00 0 18593 15 0 0
sp_get_clo_for_invoice 5045 5045 0 0.00 0 23678 270 0 0
sp_kill_cost_storno 17588 17588 0 0.00 0 12969 57 0 0
sp_kill_qstorno_ret_qs2qd 1189985 1189985 0 0.00 0 29100 29 0 0
sp_kill_qty_storno 626643 626643 0 0.00 0 29943 63 0 0
sp_lock_selected_doc 21100 21100 0 0.00 0 8798 6 0 0
sp_make_cost_storno 28408 28408 0 0.00 0 36347 56 0 0
sp_multiply_rows_for_pdistr 28408 28408 0 0.00 0 7949 32 0 0
sp_multiply_rows_for_qdistr 21039 21039 0 0.00 0 15203 143 0 0
sp_pay_from_customer 7249 7249 0 0.00 4 29307 132 0 0
sp_pay_to_supplier 5975 5975 0 0.00 4 36376 146 0 0
sp_payment_common 13224 13224 0 0.00 1 22623 44 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 7864 320 0 0
sp_qd_handle_on_invoice_upd_sts 11015 11015 0 0.00 0 28812 345 0 0
sp_qd_handle_on_reserve_upd_sts 10139 10139 0 0.00 0 14581 50 0 0
sp_reserve_write_off 10139 10139 0 0.00 6 14622 184 0 0
sp_supplier_invoice 5569 5569 0 0.00 7 83376 3157 0 0
sp_supplier_order 5612 5612 0 0.00 5 34731 1260 0 0
srv_aggregate_perf_data 95 95 0 0.00 2884 704439 24472 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1905 118456 48727 0 0
srv_find_qd_qs_mism 1095002 1095002 0 0.00 0 17659 5 0 0
srv_make_invnt_saldo 552 552 0 0.00 37 117088 1257 0 0
srv_make_money_saldo 789 789 0 0.00 1 6750 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 78715 478058 235091 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 5059 5059 0 0.00 0 41070 187 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 2689 88 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 23430 25 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 2927 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 767 767 0 0.00 0 6045 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 2659 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 849 16 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 51009 1761 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 6751 17 0 0
t$perf-norm:sp_client_order 21039 21039 0 0.00 0 25813 21 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 1744 10 0 0
t$perf-norm:sp_pay_from_customer 7249 7249 0 0.00 0 8372 10 0 0
t$perf-norm:sp_pay_to_supplier 5975 5975 0 0.00 0 16388 14 0 0
t$perf-norm:sp_reserve_write_off 10139 10139 0 0.00 0 8865 16 0 0
t$perf-norm:sp_supplier_invoice 5569 5569 0 0.00 0 9691 19 0 0
t$perf-norm:sp_supplier_order 5612 5612 0 0.00 0 10429 17 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 3534 46 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1995 34 0 0
t$perf-norm:srv_make_invnt_saldo 552 552 0 0.00 0 2502 24 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 692 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2524 633 0 0
v_add_invoice_to_stock 10290 10090 200 1.94 0 6226 20 0 200
v_all_customers 38746 38746 0 0.00 0 18 1 0 0
v_all_suppliers 31159 31159 0 0.00 0 22 1 0 0
v_all_wares 42078 42078 0 0.00 0 47 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 1475 8 0 0
v_cancel_client_order 3580 3580 0 0.00 0 3589 7 0 0
v_cancel_customer_prepayment 1534 1534 0 0.00 0 5655 9 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 1373 4 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 1085 5 0 0
v_cancel_supplier_invoice 800 790 10 1.25 0 2376 17 0 10
v_cancel_supplier_order 846 846 0 0.00 0 825 5 0 0
v_cancel_write_off 1958 1958 0 0.00 0 1155 12 0 0
v_max_id_clo_ord 5611 5611 0 0.00 0 1184 2 0 0
v_max_id_clo_res 993 993 0 0.00 0 6058 25 0 0
v_max_id_ord_sup 5562 5562 0 0.00 0 3331 5 0 0
v_max_non_paid_invoice 1568 1568 0 0.00 0 2868 5 0 0
v_max_non_paid_realizn 3814 3814 0 0.00 0 7747 7 0 0
v_min_id_clo_ord 5611 5611 0 0.00 0 5723 9 0 0
v_min_id_clo_res 993 993 0 0.00 0 5896 9 0 0
v_min_id_ord_sup 5562 5562 0 0.00 0 7618 5 0 0
v_min_non_paid_invoice 5975 5975 0 0.00 0 22617 34 0 0
v_min_non_paid_realizn 7249 7249 0 0.00 0 10129 30 0 0
v_our_firm 10202 10202 0 0.00 0 18 1 0 0
v_reserve_write_off 20278 20278 0 0.00 0 3244 6 0 0
x_lock_depdocs_on_canc_invoice 12139 12139 0 0.00 0 16005 111 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 9476 347 0 0
x_make_qstorno_1000_1200 5612 5612 0 0.00 1 34722 1177 0 0
x_make_qstorno_1200_2000 5569 5569 0 0.00 4 83361 3025 0 0
x_make_qstorno_2100_3300 468336 468336 0 0.00 0 70874 154 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:07:49 5 8192000000 8809612576 8823083008 14327152 0.174 3 3 4 0 14565968 76800 162016
23:08:50 29969 8192000000 10357313856 10674098176 1399616544 14.592 100 89 178 0 1434384560 5607376 29160640
23:10:23 6758 8192000000 10426192192 10751778816 1477828224 15.282 100 89 178 0 1513972400 7524928 28619248
23:11:37 10480 8192000000 10468326816 10805473280 1530204320 15.739 100 86 172 0 1566765488 9636672 26924496
23:12:54 14886 8192000000 10553722416 10908741632 1600358160 16.342 100 79 158 0 1634821296 10586912 23876224
23:14:11 7753 8192000000 10613475312 10977693696 1670915584 16.941 100 73 147 1 1703918576 12185008 20817984
23:15:23 5917 8192000000 10682303408 11055497216 1733949312 17.468 100 78 156 0 1767674992 12082688 21642992
23:16:31 6631 8192000000 10722572704 11108483072 1789969808 17.932 100 76 152 1 1824094976 13222336 20902832
23:17:40 2792 8192000000 10790376384 11174989824 1843954400 18.373 100 94 188 0 1884894576 13899072 27041104
23:18:44 1653 8192000000 10815608800 11202179072 1874349248 18.619 100 92 183 3 1913594736 12933344 26312144
23:19:48 3222 8192000000 10814493584 11215912960 1878688112 18.655 100 74 146 2 1910527664 11505792 20333760
23:20:53 9778 8192000000 10787302608 11218030592 1879591616 18.662 100 26 52 1 1896714704 9805648 7317440
23:22:04 5551 8192000000 10791235136 11220561920 1882662656 18.687 100 27 54 0 1896851584 7041520 7147408
23:23:12 10572 8192000000 10806438016 11236175872 1891412336 18.757 100 11 22 1 1903921888 9683360 2826192
23:24:22 17586 8192000000 10811322816 11242110976 1904332592 18.861 100 25 52 3 1916938704 5619136 6986976
23:25:42 21081 8192000000 10821600672 11258445824 1907825072 18.889 100 27 53 0 1921692048 6467584 7399392
23:27:03 7156 8192000000 10823104368 11249778688 1872018896 18.601 98 31 62 3 1886532208 5601712 8911600
23:28:11 7800 8192000000 10795286672 11226578944 1868196448 18.570 99 23 46 1 1880741744 5487488 7057808
23:29:22 9750 8192000000 10731016688 11162308608 1726153840 17.403 93 14 28 1 1735051520 5322928 3574752
23:30:34 17781 8192000000 10711483792 11132256256 1726015024 17.402 95 44 88 1 1745944240 6776080 13153136
23:31:55 9173 8192000000 10715604112 11129700352 1773568736 17.796 98 34 67 1 1788455520 4828016 10058768
23:33:07 8961 8192000000 10727493632 11142746112 1767107120 17.743 97 25 50 1 1779553776 4565776 7880880
23:34:17 56399 8192000000 10775662048 11205058560 1858507280 18.491 100 40 80 0 1876646048 6679344 11459424
23:36:17 9016 8192000000 10780385856 11213508608 1868050832 18.568 100 26 50 2 1881077776 6414304 6612640
23:37:27 11674 8192000000 10810022096 11238535168 1899315840 18.821 100 19 38 3 1909351488 5123824 4911824
23:38:45 14284 8192000000 10860180176 11279454208 1926373232 19.038 100 42 84 0 1949461136 10849776 12238128
23:40:03 106718 8192000000 10876242592 11314757632 1934212768 19.101 99 45 90 1 1961224320 14642112 12369440
23:43:24 40064 8192000000 10916156352 11348606976 1972488528 19.405 100 40 79 2 2000253136 15921744 11842864
23:45:24 7860 8192000000 10934620320 11370967040 1987497232 19.524 100 42 84 2 2022485136 22210272 12777632
23:46:37 7453 8192000000 10949202496 11376033792 1979865760 19.464 99 63 124 0 2021397280 23008000 18523520
23:47:51 10131 8192000000 10938350752 11382087680 1982122656 19.482 99 33 66 2 2017174544 24900896 10150992
23:49:05 1904 8192000000 10968825104 11388628992 2003119792 19.647 100 86 171 0 2057047904 28649600 25278512
23:50:09 9417 8192000000 10963028096 11395055616 2003423600 19.650 100 75 150 0 2057937472 31773728 22740144
23:51:26 4852 8192000000 10999185696 11425665024 2021259312 19.790 100 72 143 0 2082112032 39548064 21304656
23:52:38 2469 8192000000 10981978272 11405565952 2004228800 19.656 100 79 158 0 2070607696 41901616 24477280
23:53:44 2951 8192000000 10988239968 11413446656 2006763376 19.676 100 78 155 3 2075659104 44854656 24041072
23:54:56 7132 8192000000 10940531536 11360563200 1956348352 19.277 100 74 145 1 2025964720 47088912 22527456
23:56:11 5199 8192000000 10928015568 11350851584 1941897104 19.162 100 58 116 1 2005356144 45411904 18047136
23:57:19 17595 8192000000 10929693088 11365724160 1945585712 19.191 100 34 67 3 2004562112 47932096 11044304
23:58:41 11491 8192000000 10912851344 11357831168 1936295696 19.117 99 26 53 0 1986999520 41952640 8751184
23:59:55 7318 8192000000 10933115968 11371413504 1957428560 19.286 100 42 85 0 2019451888 48135952 13887376
00:01:07 5015 8192000000 10913502768 11336433664 1924696784 19.024 100 59 118 3 1996833680 53560768 18576128
00:02:17 14410 8192000000 10870667392 11323985920 1896214704 18.796 99 28 57 2 1944539824 38987072 9338048
00:04:30 20478 8192000000 10897465024 11355344896 1939882112 19.146 100 20 38 4 1988149008 41016928 7249968
00:06:01 27565 8192000000 10892695888 11360563200 1939006352 19.139 100 21 41 2 1983756640 37820320 6929968
00:07:30 106067 8192000000 10874732336 11328651264 1865299728 18.546 96 6 13 1 1881229664 14829072 1100864
00:10:47 92179 8192000000 10894541392 11372195840 1959300032 19.300 100 5 10 0 1980603328 19834560 1468736
00:13:20 70765 8192000000 10892889264 11358072832 1921170016 18.996 97 20 38 1 1936092736 10262192 4660528
00:15:34 28541 8192000000 10898420912 11355242496 1961380544 19.317 100 24 49 0 1980228944 11511152 7337248
00:17:03 49500 8192000000 10918834800 11367747584 1977365520 19.444 100 30 60 1 1995795056 10272880 8156656
00:18:55 84641 8192000000 10934391504 11385700352 1988691632 19.533 100 25 50 0 2004802400 9241440 6869328
00:21:23 35548 8192000000 10947318448 11394695168 1980538288 19.469 99 52 104 1 2005538064 9849296 15150480
00:23:04 49942 8192000000 10931927760 11404939264 2004373296 19.657 100 23 45 1 2022601360 11946576 6281488
00:24:56 58340 8192000000 10968902224 11427618816 2010337312 19.704 100 21 42 0 2035121344 18355408 6428624
00:26:57 51540 8192000000 10935146112 11417968640 2003940320 19.654 100 20 40 1 2034867360 24278416 6648624
00:28:51 42730 8192000000 10916394384 11391262720 1964609088 19.343 98 13 26 0 1977736336 9161968 3965280
00:30:37 55140 8192000000 10916502720 11400175616 1995926448 19.591 100 7 13 0 2005356400 7696944 1733008
00:33:27 72952 8192000000 10912605744 11379290112 1923756400 19.017 96 17 34 0 1954793136 26162656 4874080
00:35:40 71530 8192000000 10885011792 11376644096 1941194480 19.156 98 7 14 0 1953862896 10646976 2021440
00:37:52 36535 8192000000 10915205280 11380543488 1897794304 18.809 95 21 41 2 1912524896 8508880 6221712
00:39:34 59762 8192000000 10880686000 11347697664 1835370928 18.303 93 20 40 1 1855686768 14374928 5940912
00:41:34 41067 8192000000 10874529712 11338440704 1911503584 18.919 98 27 54 0 1931573696 11854416 8215696
00:43:16 53949 8192000000 10870834368 11328167936 1841261440 18.351 94 27 54 1 1857650448 9029120 7359888
00:45:11 38650 8192000000 10891724912 11350020096 1927293568 19.045 99 22 42 3 1946918880 13744752 5880560
00:46:52 41163 8192000000 10867110400 11319316480 1877021056 18.641 97 25 50 1 1899662176 15086592 7554528
00:48:43 33605 8192000000 10859987120 11324379136 1889595760 18.743 98 24 48 0 1912705568 16646368 6463440
00:50:19 66707 8192000000 10863725488 11301138432 1836591680 18.313 96 18 36 0 1851250912 9577760 5081472
00:52:27 96021 8192000000 10853571216 11316318208 1852775408 18.445 96 35 70 3 1874163904 11683632 9704864
00:55:06 7238 8192000000 10868460384 11327365120 1933647456 19.096 100 35 69 2 1952372080 9551856 9172768
00:56:14 90575 8192000000 10868244896 11356925952 1948740656 19.216 100 3 6 0 1963111392 13601520 769216
00:58:46 80214 8192000000 10877684048 11360940032 1928675424 19.056 99 10 20 1 1951451648 19557232 3218992
01:01:06 61878 8192000000 10885039520 11363799040 1929863568 19.066 99 15 30 1 1951667904 17021872 4782464
01:03:10 33730 8192000000 10888417728 11350409216 1935316128 19.109 99 24 48 0 1951416560 8899152 7201280
01:04:45 47785 8192000000 10910032416 11370512384 1963009984 19.330 100 20 40 2 1979572272 10876128 5686160
01:06:44 32631 8192000000 10903064048 11382132736 1953467824 19.254 99 22 44 2 1971012656 11088272 6456560
01:08:19 118456 8192000000 10914969376 11391950848 1949037968 19.219 98 15 29 1 1965922368 13649616 3234784
01:11:20 85539 8192000000 10947043520 11440041984 1982522192 19.485 99 9 18 0 2007041216 21696928 2822096
01:13:53 115479 8192000000 10939699200 11400876032 1929324192 19.061 96 19 38 1 1951376848 16856208 5196448
01:16:55 69811 8192000000 10912437632 11400167424 1959578480 19.303 98 11 21 1 1973043488 10051120 3413888
01:19:05 72193 8192000000 10917847056 11405750272 1991585488 19.556 100 15 30 2 2011139024 14745040 4808496
01:21:20 102822 8192000000 10891511856 11368976384 1921330320 18.997 97 13 26 1 1936609376 11502960 3776096
01:24:15 99647 8192000000 10876849280 11359121408 1928686688 19.056 97 26 52 0 1950805808 14603440 7515680
01:26:56 44613 8192000000 10897088000 11364323328 1949967488 19.226 99 24 48 1 1964962256 7844320 7150448
01:28:49 54177 8192000000 10861640320 11337076736 1874729312 18.623 95 15 30 0 1887253040 7898960 4624768
01:30:45 92102 8192000000 10838447008 11319390208 1831856544 18.274 94 21 42 0 1847533008 9157216 6519248
01:33:48 56384 8192000000 10847532160 11327275008 1839767792 18.339 95 14 28 1 1855259632 11819120 3672720
01:35:50 94560 8192000000 10842791200 11309223936 1817395104 18.156 94 12 23 1 1832204832 11536752 3272976

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

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 05-09-2021 01:39:25 to 05-09-2021 01:39:27.

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          241053
MON$OLDEST_ACTIVE               242804
MON$OLDEST_SNAPSHOT             242804
MON$NEXT_TRANSACTION            242804
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                       2058016
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.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 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 ---
:181: 
:182: mscs-test-161.budzdorov.ru    Sun Sep  5 01:37:47 2021
:183:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:184: 
:185: 
:186: mscs-test-161.budzdorov.ru    Sun Sep  5 01:37:51 2021
:187:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:188: 
:189: 
:190: mscs-test-161.budzdorov.ru    Sun Sep  5 01:37:51 2021
:191:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:192: 
:193: 
:194: mscs-test-161.budzdorov.ru    Sun Sep  5 01:37:54 2021
:195:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:196: 
:197: 
:198: mscs-test-161.budzdorov.ru    Sun Sep  5 01:37:54 2021
:199:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:200: 
--- end of diff output ---

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

05.09.21 01:39:28. 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-05 01:39:30 No severe PSQL-related problems occured
05.09.21 01:39:32. End of report.