Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.06.2021 10:27
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 16933796 kB
MemAvailable 31338820 kB
Buffers 0 kB
Cached 14696992 kB
SwapCached 2416 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-06-22 10:27:00
DTS_END                         2021-06-22 10:27:00.9340
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(68:17)
IP                              ::1/58768
TRN_ID                          217904
ATT_ID                          607
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: 8552.80 25204 1026336
customer order: creation 154.22 435 18506
customer order: refuse 13.54 352 1625
order to supplier: creation 40.55 1454 4866
order to supplier: removal 3.19 131558 383
invoice (draft): creation 41.47 3662 4976
invoice (draft): removal 49.50 988 5940
invoice accept: apply 37.67 18006 4521
invoice accept: cancel 45.07 884 5408
customer reserve: creation 3487.48 173 418497
customer reserve: removal 4410.19 74 529223
realization accept: apply 74.63 205 8955
realization accept: cancel 79.77 127 9572
payment from customer: creation 54.36 126 6523
payment from customer: removal 5.26 93 631
payment to supplier: creation 43.33 137 5199
payment to supplier: removal 3.00 113 360
service: total inventory turnovers 3.44 3950 413
service: total monetary turnovers 5.25 671 630
service: aggregate perf. data 0.87 16497 104
service: refresh index statistics 0.03 324573 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-06-2021 10:27:37 to 22-06-2021 10:27:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4845
TEST_TIME 2 4632
TEST_TIME 3 5187
TEST_TIME 4 5025
TEST_TIME 5 5162
TEST_TIME 6 5374
TEST_TIME 7 5508
TEST_TIME 8 5958
TEST_TIME 9 6277
TEST_TIME 10 6695
TEST_TIME 11 6671
TEST_TIME 12 6998
TEST_TIME 13 7197
TEST_TIME 14 7091
TEST_TIME 15 7030
TEST_TIME 16 7073
TEST_TIME 17 6998
TEST_TIME 18 7126
TEST_TIME 19 7424
TEST_TIME 20 7753
TEST_TIME 21 8318
TEST_TIME 22 8367
TEST_TIME 23 8624
TEST_TIME 24 8575
TEST_TIME 25 9493
TEST_TIME 26 10283
TEST_TIME 27 10816
TEST_TIME 28 10889
TEST_TIME 29 10572
TEST_TIME 30 10330
TEST_TIME 31 10407
TEST_TIME 32 10257
TEST_TIME 33 10081
TEST_TIME 34 10046
TEST_TIME 35 9900
TEST_TIME 36 9721
TEST_TIME 37 9583
TEST_TIME 38 9549
TEST_TIME 39 9481
TEST_TIME 40 9313
TEST_TIME 41 9311
TEST_TIME 42 9264
TEST_TIME 43 9160
TEST_TIME 44 9044
TEST_TIME 45 8976
TEST_TIME 46 8833
TEST_TIME 47 8862
TEST_TIME 48 8805
TEST_TIME 49 8744
TEST_TIME 50 8867
TEST_TIME 51 8807
TEST_TIME 52 8838
TEST_TIME 53 8891
TEST_TIME 54 8808
TEST_TIME 55 9000
TEST_TIME 56 8966
TEST_TIME 57 8950
TEST_TIME 58 8934
TEST_TIME 59 9061
TEST_TIME 60 9092
TEST_TIME 61 8947
TEST_TIME 62 8817
TEST_TIME 63 8823
TEST_TIME 64 8740
TEST_TIME 65 8688
TEST_TIME 66 8696
TEST_TIME 67 8659
TEST_TIME 68 8704
TEST_TIME 69 8738
TEST_TIME 70 8729
TEST_TIME 71 8734
TEST_TIME 72 8757
TEST_TIME 73 8721
TEST_TIME 74 8697
TEST_TIME 75 8646
TEST_TIME 76 8803
TEST_TIME 77 8862
TEST_TIME 78 8841
TEST_TIME 79 8898
TEST_TIME 80 8888
TEST_TIME 81 8845
TEST_TIME 82 8749
TEST_TIME 83 8740
TEST_TIME 84 8687
TEST_TIME 85 8628
TEST_TIME 86 8593
TEST_TIME 87 8552
TEST_TIME 88 8504
TEST_TIME 89 8475
TEST_TIME 90 8488
TEST_TIME 91 8484
TEST_TIME 92 8461
TEST_TIME 93 8482
TEST_TIME 94 8522
TEST_TIME 95 8620
TEST_TIME 96 8605
TEST_TIME 97 8587
TEST_TIME 98 8593
TEST_TIME 99 8635
TEST_TIME 100 8655
TEST_TIME 101 8694
TEST_TIME 102 8698
TEST_TIME 103 8714
TEST_TIME 104 8711
TEST_TIME 105 8633
TEST_TIME 106 8602
TEST_TIME 107 8566
TEST_TIME 108 8508
TEST_TIME 109 8495
TEST_TIME 110 8523
TEST_TIME 111 8494
TEST_TIME 112 8502
TEST_TIME 113 8497
TEST_TIME 114 8491
TEST_TIME 115 8512
TEST_TIME 116 8518
TEST_TIME 117 8584
TEST_TIME 118 8620
TEST_TIME 119 8630
TEST_TIME 120 8559

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-06-2021 10:27:41 to 22-06-2021 10:27:44.

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 1413949 1413949 0 0.00 0 32033 7 0 0
doc_list_biud 1413947 1413947 0 0.00 0 72719 35 0 0
sp_add_invoice_to_stock 4684 4521 163 3.47 25 174121 18006 0 163
sp_cancel_adding_invoice 5408 5408 0 0.00 5 79439 885 0 0
sp_cancel_client_order 1625 1625 0 0.00 5 9679 352 0 0
sp_cancel_customer_reserve 529223 529223 0 0.00 1 55724 75 0 0
sp_cancel_pay_from_customer 631 631 0 0.00 3 4220 94 0 0
sp_cancel_pay_to_supplier 360 360 0 0.00 3 5139 114 0 0
sp_cancel_supplier_invoice 5957 5940 17 0.28 3 72724 988 0 17
sp_cancel_supplier_order 383 383 0 0.00 7 1313537 131558 0 0
sp_cancel_write_off 9572 9572 0 0.00 1 16333 128 0 0
sp_client_order 18506 18506 0 0.00 4 45931 435 0 0
sp_customer_reserve 418497 418497 0 0.00 0 41177 173 0 0
sp_fill_shopping_cart 28344 28344 0 0.00 1 34742 51 0 0
sp_fill_shopping_cart_clo_res 418485 418485 0 0.00 0 22107 19 0 0
sp_get_clo_for_invoice 4507 4507 0 0.00 0 36829 516 0 0
sp_kill_cost_storno 15971 15971 0 0.00 0 8110 70 0 0
sp_kill_qstorno_ret_qs2qd 1074341 1074341 0 0.00 0 72718 40 0 0
sp_kill_qty_storno 565612 565612 0 0.00 0 72719 86 0 0
sp_lock_selected_doc 18582 18582 0 0.00 0 6928 4 0 0
sp_make_cost_storno 25184 25184 0 0.00 0 24229 62 0 0
sp_multiply_rows_for_pdistr 25183 25183 0 0.00 0 11825 37 0 0
sp_multiply_rows_for_qdistr 18506 18506 0 0.00 0 45923 267 0 0
sp_pay_from_customer 6523 6523 0 0.00 4 24073 127 0 0
sp_pay_to_supplier 5199 5199 0 0.00 5 10153 138 0 0
sp_payment_common 11722 11722 0 0.00 1 7609 23 0 0
sp_qd_handle_on_cancel_clo 1625 1625 0 0.00 0 9671 324 0 0
sp_qd_handle_on_invoice_upd_sts 9915 9915 0 0.00 0 13072 408 0 0
sp_qd_handle_on_reserve_upd_sts 8954 8954 0 0.00 0 12488 64 0 0
sp_reserve_write_off 8955 8955 0 0.00 7 34767 205 0 0
sp_supplier_invoice 4976 4976 0 0.00 7 118949 3662 0 0
sp_supplier_order 4866 4866 0 0.00 5 49140 1455 0 0
srv_aggregate_perf_data 104 104 0 0.00 2526 180189 16498 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1302 141763 50036 0 0
srv_find_qd_qs_mism 983430 983430 0 0.00 0 15947 5 0 0
srv_make_invnt_saldo 413 413 0 0.00 62 112269 3951 0 0
srv_make_money_saldo 630 630 0 0.00 1 13392 672 0 0
srv_recalc_idx_stat 4 4 0 0.00 197587 483876 324574 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4521 4521 0 0.00 0 13597 212 0 0
t$perf-norm:sp_cancel_adding_invoice 348 348 0 0.00 0 3370 89 0 0
t$perf-norm:sp_cancel_client_order 1625 1625 0 0.00 0 5043 15 0 0
t$perf-norm:sp_cancel_customer_reserve 600 600 0 0.00 0 1398 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 631 631 0 0.00 0 1623 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 360 360 0 0.00 0 323 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 340 340 0 0.00 0 2725 23 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 18079 1634 0 0
t$perf-norm:sp_cancel_write_off 834 834 0 0.00 0 691 4 0 0
t$perf-norm:sp_client_order 18506 18506 0 0.00 0 7553 21 0 0
t$perf-norm:sp_customer_reserve 908 908 0 0.00 0 4731 18 0 0
t$perf-norm:sp_pay_from_customer 6523 6523 0 0.00 0 7245 12 0 0
t$perf-norm:sp_pay_to_supplier 5199 5199 0 0.00 0 7549 16 0 0
t$perf-norm:sp_reserve_write_off 8955 8955 0 0.00 0 5884 11 0 0
t$perf-norm:sp_supplier_invoice 4976 4976 0 0.00 0 4205 17 0 0
t$perf-norm:sp_supplier_order 4866 4866 0 0.00 0 8879 24 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 173 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 299 6 0 0
t$perf-norm:srv_make_invnt_saldo 413 413 0 0.00 0 653 9 0 0
t$perf-norm:srv_make_money_saldo 630 630 0 0.00 0 1483 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9338 9012 326 3.49 0 10117 21 0 326
v_all_customers 34054 34054 0 0.00 0 92 1 0 0
v_all_suppliers 27102 27102 0 0.00 0 39 1 0 0
v_all_wares 37010 37010 0 0.00 0 38 1 0 0
v_cancel_adding_invoice 696 696 0 0.00 0 6223 14 0 0
v_cancel_client_order 3250 3250 0 0.00 0 4487 5 0 0
v_cancel_customer_prepayment 1262 1262 0 0.00 0 4206 12 0 0
v_cancel_customer_reserve 1200 1200 0 0.00 0 341 4 0 0
v_cancel_payment_to_supplier 720 720 0 0.00 0 5071 21 0 0
v_cancel_supplier_invoice 714 680 34 4.76 0 4340 31 0 34
v_cancel_supplier_order 766 766 0 0.00 0 113 3 0 0
v_cancel_write_off 1668 1668 0 0.00 0 16203 28 0 0
v_max_id_clo_ord 4864 4864 0 0.00 0 196 1 0 0
v_max_id_clo_res 908 908 0 0.00 0 1903 13 0 0
v_max_id_ord_sup 4972 4972 0 0.00 0 7819 8 0 0
v_max_non_paid_invoice 1483 1483 0 0.00 0 4460 5 0 0
v_max_non_paid_realizn 3395 3395 0 0.00 0 537 3 0 0
v_min_id_clo_ord 4864 4864 0 0.00 0 4046 7 0 0
v_min_id_clo_res 908 908 0 0.00 0 4528 19 0 0
v_min_id_ord_sup 4972 4972 0 0.00 0 9159 14 0 0
v_min_non_paid_invoice 5198 5198 0 0.00 0 6432 15 0 0
v_min_non_paid_realizn 6523 6523 0 0.00 0 7604 16 0 0
v_our_firm 9212 9212 0 0.00 0 39 1 0 0
v_reserve_write_off 17908 17908 0 0.00 0 8927 8 0 0
x_lock_depdocs_on_canc_invoice 11008 11008 0 0.00 0 11295 115 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 12102 377 0 0
x_make_qstorno_1000_1200 4866 4866 0 0.00 1 49134 1375 0 0
x_make_qstorno_1200_2000 4976 4976 0 0.00 3 118918 3452 0 0
x_make_qstorno_2100_3300 418497 418497 0 0.00 0 41176 151 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 22-06-2021 10:27:45 to 22-06-2021 10:27: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
07:57:04 2 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
07:58:05 16086 8192000000 9715701040 10016870400 798134112 8.877 100 83 166 0 827295728 7995040 21166576
07:59:28 32045 8192000000 9767713424 10075766784 838777264 9.287 100 93 186 0 873776928 12707392 22292272
08:01:05 19238 8192000000 9803777312 10126524416 870885072 9.609 100 80 160 0 914836176 25779168 18171936
08:02:27 13754 8192000000 9871246352 10205261824 923569520 10.131 100 84 168 0 979168096 36828880 18769696
08:03:51 7357 8192000000 9913117200 10262605824 962453136 10.513 100 79 158 2 1030268352 51486016 16329200
08:05:04 6382 8192000000 9959165616 10318667776 997200192 10.851 100 78 155 0 1073351072 60723216 15427664
08:06:13 4440 8192000000 10007472736 10371690496 1024081840 11.111 100 85 170 0 1124916480 84191792 16642848
08:07:20 1698 8192000000 10005799840 10375204864 1039262480 11.258 100 86 173 1 1124322528 67070480 17989568
08:08:24 2670 8192000000 10039528160 10411913216 1059910368 11.456 100 90 181 0 1148473728 70124032 18439328
08:09:28 3652 8192000000 10018258560 10405285888 1069521200 11.548 100 64 127 1 1135650384 52932800 13196384
08:10:36 3604 8192000000 10033303168 10420678656 1079256176 11.640 100 63 125 1 1143450864 51366688 12828000
08:11:42 36669 8192000000 9999633584 10388676608 1084877152 11.694 100 49 97 3 1115076192 19918528 10280512
08:13:20 5827 8192000000 10005359008 10407313408 1091789296 11.760 100 41 82 2 1121225040 19382176 10053568
08:14:26 4030 8192000000 10011482976 10407268352 1095483376 11.795 100 39 77 1 1118682016 14920128 8278512
08:15:31 4041 8192000000 10006859328 10407378944 1099694352 11.835 100 56 112 2 1130764272 17951808 13118112
08:16:37 45352 8192000000 10005112784 10413719552 1087250688 11.717 99 35 68 3 1114697936 19680128 7767120
08:18:25 6450 8192000000 9986396080 10385960960 1052057168 11.380 97 36 72 0 1078971328 18879984 8034176
08:19:33 8225 8192000000 9986861168 10381688832 1049076928 11.352 98 36 72 0 1076496688 19418576 8001184
08:20:42 5820 8192000000 9951246816 10333093888 1018951408 11.062 99 41 79 3 1049956000 22673616 8330976
08:21:49 22655 8192000000 9913464368 10310615040 955386112 10.444 93 20 40 1 983601072 23916544 4298416
08:23:14 6445 8192000000 9937328192 10318958592 1010926608 10.984 99 50 100 2 1037966000 15325632 11713760
08:24:25 5987 8192000000 9973764048 10357821440 1047106752 11.333 100 39 78 2 1069030560 12802080 9121728
08:25:33 8579 8192000000 9961018400 10359033856 1053804112 11.397 100 34 66 3 1087929872 26476304 7649456
08:27:18 25485 8192000000 9994586864 10389278720 1068772000 11.540 100 41 81 3 1096886688 20026608 8088080
08:28:47 19463 8192000000 10046795968 10446508032 1095080608 11.791 100 46 91 1 1151215808 47098032 9037168
08:30:16 13523 8192000000 10079688784 10479230976 1116412320 11.993 100 45 90 3 1191399680 64369424 10617936
08:31:36 9533 8192000000 10140310752 10538893312 1128515312 12.107 100 62 122 1 1248813616 107421120 12877184
08:32:54 18047 8192000000 10184212336 10604154880 1144139888 12.254 100 47 91 1 1304918432 150314576 10463968
08:34:15 5828 8192000000 10207967760 10616406016 1148631344 12.297 100 69 134 1 1310490848 145971248 15888256
08:35:30 4441 8192000000 10268776048 10674634752 1160703728 12.410 100 71 141 0 1370636848 194323200 15609920
08:36:38 4565 8192000000 10279005232 10688618496 1153573472 12.343 100 70 139 0 1381820880 212356112 15891296
08:37:48 6338 8192000000 10279011296 10689925120 1159687504 12.400 100 70 138 2 1382858656 207717472 15453680
08:38:57 2701 8192000000 10321936640 10731704320 1168563584 12.483 100 71 143 0 1422924448 238576816 15784048
08:40:09 8229 8192000000 10278810736 10717978624 1165852768 12.458 99 56 112 0 1404194576 224947664 13394144
08:41:27 2592 8192000000 10337978960 10747887616 1187838960 12.663 100 83 166 0 1440277136 233126048 19312128
08:42:35 5840 8192000000 10397341040 10803306496 1180543600 12.595 100 93 181 2 1488847056 286854304 21449152
08:43:49 1299 8192000000 10440283472 10844708864 1169228352 12.490 100 90 179 1 1537340944 347960912 20151680
08:44:57 1982 8192000000 10481321856 10896637952 1171837504 12.514 100 91 181 1 1574312496 380724976 21750016
08:46:07 3820 8192000000 10504454512 10925744128 1183582576 12.624 100 72 144 1 1609286112 408479312 17224224
08:47:20 2622 8192000000 10540948336 10954870784 1180267296 12.593 100 84 168 0 1634722528 434393968 20061264
08:48:31 9833 8192000000 10412015888 10844655616 1152072672 12.329 98 37 73 1 1497835008 336262480 9499856
08:49:46 13077 8192000000 10460745280 10892587008 1152909168 12.337 99 32 65 3 1539291936 378120288 8262480
08:51:06 16222 8192000000 10401905920 10858635264 1100011968 11.838 97 29 58 1 1498176224 390276480 7887776
08:52:35 16466 8192000000 10362764864 10880040960 1126337952 12.087 100 26 52 0 1504560176 370911440 7310784
08:54:01 34536 8192000000 10141086576 10599071744 1092465456 11.766 97 24 48 1 1283677136 185282336 5929344
08:55:37 84941 8192000000 10104248976 10565115904 1119020480 12.018 100 30 60 3 1228116832 103020720 6075632
08:58:15 54046 8192000000 10065084576 10550444032 1090865712 11.751 97 9 18 1 1153682192 61158912 1657568
09:00:10 53896 8192000000 10068547488 10509406208 1087192384 11.716 96 29 59 1 1128537712 35416032 5929296
09:02:05 63814 8192000000 10081211856 10522808320 1115836944 11.988 99 11 22 0 1159630720 41504192 2289584
09:04:10 68891 8192000000 10122858176 10562895872 1113928976 11.970 98 24 48 0 1204899520 85452672 5517872
09:06:34 78025 8192000000 10103927696 10557607936 1132474752 12.145 100 20 38 1 1190077584 53735824 3867008
09:09:11 76487 8192000000 10061862992 10544484352 1128897024 12.111 99 19 38 2 1192536320 59766032 3873264
09:11:28 14840 8192000000 10070764800 10531909632 1147397504 12.285 100 22 44 3 1185587184 33827408 4362272
09:12:43 38552 8192000000 10067073808 10539802624 1126379072 12.087 99 7 14 0 1157084400 28879408 1825920
09:14:22 82565 8192000000 10089734944 10541752320 1099912528 11.837 96 15 30 0 1177922048 75039600 2969920
09:16:46 56550 8192000000 10093835840 10555240448 1128114944 12.104 99 14 28 0 1214260192 82789424 3355824
09:18:43 98829 8192000000 10182743536 10616606720 1162947104 12.431 100 21 42 2 1256383328 88349456 5086768
09:21:29 91012 8192000000 10098800256 10540445696 1128816496 12.110 97 15 30 0 1173853952 42221968 2815488
09:24:00 67345 8192000000 10135244384 10578173952 1152893360 12.337 99 19 38 0 1209605328 52577376 4134592
09:26:11 115488 8192000000 10121740208 10644647936 1134806464 12.167 98 11 22 0 1207107104 70083632 2217008
09:29:46 50890 8192000000 10115706096 10576330752 1148438256 12.295 99 10 20 0 1211003392 60836000 1729136
09:31:37 64824 8192000000 10092280288 10559619072 1119593280 12.023 97 9 18 0 1168262928 47053616 1616032
09:33:42 94656 8192000000 10117864960 10596954112 1150602032 12.315 99 20 40 1 1203759184 49342096 3815056
09:36:19 93068 8192000000 10104863152 10562662400 1133814032 12.157 98 24 48 1 1170702464 31576304 5312128
09:39:08 86795 8192000000 10074151424 10527584256 1089984176 11.743 95 25 48 3 1134992800 39532528 5476096
09:41:37 62997 8192000000 10144942032 10583846912 1109392432 11.927 97 24 48 1 1168157168 53547120 5217616
09:43:40 132940 8192000000 10056727104 10496495616 1050238144 11.363 93 11 22 0 1163226832 110445936 2542752
09:48:00 106292 8192000000 10057835520 10527469568 1117547024 12.004 99 26 52 1 1163111072 39847536 5716512
09:51:03 64843 8192000000 10043574560 10517737472 1110976528 11.942 99 9 18 1 1142767776 30269072 1522176
09:53:08 40675 8192000000 10019916080 10457358336 1091410096 11.756 98 16 32 0 1115894848 21134816 3349936
09:54:49 76179 8192000000 10070001600 10533285888 1115875888 11.988 99 11 22 0 1175711024 57641248 2193888
09:57:08 78207 8192000000 10038543408 10503671808 1085814544 11.703 97 13 26 0 1143760272 55635744 2309984
09:59:29 65815 8192000000 10102719920 10565484544 1100919552 11.846 98 30 60 1 1171804544 64962592 5922400
10:01:35 68944 8192000000 10112203280 10570280960 1112542944 11.956 99 20 40 0 1205906576 89058336 4305296
10:04:53 10995 8192000000 10094485760 10522206208 1118723536 12.015 99 44 88 0 1173560880 45944736 8892608
10:06:10 50517 8192000000 10087354640 10532782080 1118069552 12.009 99 27 54 1 1171169760 47906480 5193728
10:08:07 76059 8192000000 10112249184 10569498624 1109880880 11.931 98 18 36 0 1179954560 66301920 3771760
10:10:26 141763 8192000000 10066662800 10572042240 1120387984 12.031 99 7 14 0 1167268608 45403424 1477200
10:13:54 52033 8192000000 10075971472 10542641152 1144246816 12.255 100 8 16 1 1176411616 30800592 1364208
10:15:46 50260 8192000000 10089387216 10595627008 1144282560 12.256 100 6 12 0 1184120112 38651616 1185936
10:17:37 78902 8192000000 10095584608 10542796800 1131880688 12.139 98 17 32 0 1154370320 19548896 2940736
10:20:08 86031 8192000000 10128840128 10608951296 1147474080 12.286 100 15 30 0 1227757264 76954688 3328496
10:22:35 68533 8192000000 10110211872 10575450112 1087866464 11.722 95 22 44 2 1173287920 80436016 4985440
10:25:06 78195 8192000000 10082143008 10578120704 1112906320 11.960 98 11 22 0 1176808496 61549616 2352560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-06-2021 10:27:51 to 22-06-2021 10:28:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-06-2021 10:28:07 to 22-06-2021 10:28:09.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          217140
MON$OLDEST_ACTIVE               218096
MON$OLDEST_SNAPSHOT             218096
MON$NEXT_TRANSACTION            218096
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-30 07:30:08.6030 GMT
MON$PAGES                       2069984
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2519 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2519 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2519 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2519 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:183: 
:184: mscs-test-161.budzdorov.ru    Tue Jun 22 10:27:03 2021
:185:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:186: 
:187: 
:188: mscs-test-161.budzdorov.ru    Tue Jun 22 10:27:05 2021
:189:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:190: 
--- end of diff output ---

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

22.06.21 10:28:10. There are 3 logs in /var/tmp/logs-oltp40 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-06-22 10:28:13 No severe PSQL-related problems occured
22.06.21 10:28:14. End of report.