Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.01.2021 13: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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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 4794.00
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 16339052 kB
MemAvailable 31256152 kB
Buffers 0 kB
Cached 15201096 kB
SwapCached 4864 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.0
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-01-09 13:26:54
DTS_END                         2021-01-09 13:26:54.8750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35868
TRN_ID                          236970
ATT_ID                          669
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: 9245.98 21841 1109518
customer order: creation 167.18 433 20062
customer order: refuse 14.87 300 1784
order to supplier: creation 44.79 1348 5375
order to supplier: removal 3.66 116137 439
invoice (draft): creation 43.79 3109 5255
invoice (draft): removal 55.32 863 6638
invoice accept: apply 39.33 17430 4719
invoice accept: cancel 49.78 861 5974
customer reserve: creation 3689.18 168 442701
customer reserve: removal 4838.88 71 580666
realization accept: apply 80.44 186 9653
realization accept: cancel 93.88 117 11265
payment from customer: creation 57.53 119 6903
payment from customer: removal 5.48 91 657
payment to supplier: creation 48.13 127 5775
payment to supplier: removal 3.07 67 368
service: total inventory turnovers 4.12 2969 494
service: total monetary turnovers 5.76 513 691
service: aggregate perf. data 0.79 18232 95
service: refresh index statistics 0.03 273683 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-01-2021 13:27:39 to 09-01-2021 13:27:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2106
TEST_TIME 2 2832
TEST_TIME 3 3303
TEST_TIME 4 4320
TEST_TIME 5 4558
TEST_TIME 6 4485
TEST_TIME 7 4454
TEST_TIME 8 4585
TEST_TIME 9 4780
TEST_TIME 10 5003
TEST_TIME 11 5218
TEST_TIME 12 5453
TEST_TIME 13 5835
TEST_TIME 14 5894
TEST_TIME 15 6169
TEST_TIME 16 6259
TEST_TIME 17 6582
TEST_TIME 18 6762
TEST_TIME 19 7177
TEST_TIME 20 7649
TEST_TIME 21 7950
TEST_TIME 22 8549
TEST_TIME 23 9045
TEST_TIME 24 9227
TEST_TIME 25 10199
TEST_TIME 26 10864
TEST_TIME 27 10638
TEST_TIME 28 10665
TEST_TIME 29 10772
TEST_TIME 30 10573
TEST_TIME 31 10409
TEST_TIME 32 10553
TEST_TIME 33 10407
TEST_TIME 34 10274
TEST_TIME 35 10463
TEST_TIME 36 10341
TEST_TIME 37 10346
TEST_TIME 38 10343
TEST_TIME 39 10393
TEST_TIME 40 10388
TEST_TIME 41 10393
TEST_TIME 42 10329
TEST_TIME 43 10110
TEST_TIME 44 9968
TEST_TIME 45 9897
TEST_TIME 46 9798
TEST_TIME 47 9796
TEST_TIME 48 9787
TEST_TIME 49 9754
TEST_TIME 50 9648
TEST_TIME 51 9629
TEST_TIME 52 9629
TEST_TIME 53 9589
TEST_TIME 54 9620
TEST_TIME 55 9669
TEST_TIME 56 9696
TEST_TIME 57 9677
TEST_TIME 58 9660
TEST_TIME 59 9687
TEST_TIME 60 9685
TEST_TIME 61 9690
TEST_TIME 62 9727
TEST_TIME 63 9745
TEST_TIME 64 9880
TEST_TIME 65 9842
TEST_TIME 66 9913
TEST_TIME 67 9885
TEST_TIME 68 9859
TEST_TIME 69 9733
TEST_TIME 70 9680
TEST_TIME 71 9596
TEST_TIME 72 9497
TEST_TIME 73 9541
TEST_TIME 74 9493
TEST_TIME 75 9430
TEST_TIME 76 9395
TEST_TIME 77 9335
TEST_TIME 78 9335
TEST_TIME 79 9355
TEST_TIME 80 9348
TEST_TIME 81 9338
TEST_TIME 82 9378
TEST_TIME 83 9407
TEST_TIME 84 9417
TEST_TIME 85 9555
TEST_TIME 86 9551
TEST_TIME 87 9601
TEST_TIME 88 9533
TEST_TIME 89 9462
TEST_TIME 90 9407
TEST_TIME 91 9385
TEST_TIME 92 9331
TEST_TIME 93 9294
TEST_TIME 94 9330
TEST_TIME 95 9326
TEST_TIME 96 9311
TEST_TIME 97 9278
TEST_TIME 98 9279
TEST_TIME 99 9396
TEST_TIME 100 9391
TEST_TIME 101 9496
TEST_TIME 102 9463
TEST_TIME 103 9446
TEST_TIME 104 9481
TEST_TIME 105 9458
TEST_TIME 106 9385
TEST_TIME 107 9374
TEST_TIME 108 9322
TEST_TIME 109 9298
TEST_TIME 110 9254
TEST_TIME 111 9223
TEST_TIME 112 9211
TEST_TIME 113 9232
TEST_TIME 114 9194
TEST_TIME 115 9241
TEST_TIME 116 9267
TEST_TIME 117 9278
TEST_TIME 118 9256
TEST_TIME 119 9274
TEST_TIME 120 9249

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-01-2021 13:27:42 to 09-01-2021 13:27:46.

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 1521505 1521505 0 0.00 0 32797 6 0 0
doc_list_biud 1521505 1521505 0 0.00 0 47024 33 0 0
sp_add_invoice_to_stock 4865 4719 146 3.00 25 167418 17431 0 146
sp_cancel_adding_invoice 5974 5974 0 0.00 6 67949 862 0 0
sp_cancel_client_order 1784 1784 0 0.00 5 11278 301 0 0
sp_cancel_customer_reserve 580666 580666 0 0.00 1 47025 71 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 2 8278 92 0 0
sp_cancel_pay_to_supplier 368 368 0 0.00 3 1819 67 0 0
sp_cancel_supplier_invoice 6646 6638 8 0.12 2 23083 864 0 8
sp_cancel_supplier_order 439 439 0 0.00 13 1166578 116137 0 0
sp_cancel_write_off 11265 11265 0 0.00 1 11539 118 0 0
sp_client_order 20062 20062 0 0.00 4 25704 433 0 0
sp_customer_reserve 442701 442701 0 0.00 0 79679 168 0 0
sp_fill_shopping_cart 30690 30690 0 0.00 1 11750 37 0 0
sp_fill_shopping_cart_clo_res 442694 442694 0 0.00 0 29460 19 0 0
sp_get_clo_for_invoice 4712 4712 0 0.00 0 47549 379 0 0
sp_kill_cost_storno 18264 18264 0 0.00 0 11532 57 0 0
sp_kill_qstorno_ret_qs2qd 1179674 1179674 0 0.00 0 47022 38 0 0
sp_kill_qty_storno 621131 621131 0 0.00 0 47024 81 0 0
sp_lock_selected_doc 20108 20108 0 0.00 0 9825 7 0 0
sp_make_cost_storno 27043 27043 0 0.00 0 13812 58 0 0
sp_multiply_rows_for_pdistr 27043 27043 0 0.00 0 8356 32 0 0
sp_multiply_rows_for_qdistr 20062 20062 0 0.00 0 25693 241 0 0
sp_pay_from_customer 6903 6903 0 0.00 4 12231 119 0 0
sp_pay_to_supplier 5775 5775 0 0.00 5 14312 128 0 0
sp_payment_common 12678 12678 0 0.00 1 12064 23 0 0
sp_qd_handle_on_cancel_clo 1784 1784 0 0.00 0 11236 265 0 0
sp_qd_handle_on_invoice_upd_sts 10686 10686 0 0.00 0 32638 357 0 0
sp_qd_handle_on_reserve_upd_sts 9652 9652 0 0.00 0 14910 58 0 0
sp_reserve_write_off 9653 9653 0 0.00 7 20008 187 0 0
sp_supplier_invoice 5255 5255 0 0.00 8 111914 3110 0 0
sp_supplier_order 5375 5375 0 0.00 5 52012 1348 0 0
srv_aggregate_perf_data 96 95 1 1.04 2627 381124 18232 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1482 147592 48473 0 0
srv_find_qd_qs_mism 1064471 1064471 0 0.00 0 32591 5 0 0
srv_make_invnt_saldo 494 494 0 0.00 35 114513 2969 0 0
srv_make_money_saldo 691 691 0 0.00 1 10626 514 0 0
srv_recalc_idx_stat 4 4 0 0.00 200867 399927 273683 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4720 4720 0 0.00 0 16994 152 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 7951 101 0 0
t$perf-norm:sp_cancel_client_order 1784 1784 0 0.00 0 2481 8 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 2850 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 1747 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 368 368 0 0.00 0 5954 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 613 14 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 16421 1262 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 560 3 0 0
t$perf-norm:sp_client_order 20062 20062 0 0.00 0 7866 16 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 4881 11 0 0
t$perf-norm:sp_pay_from_customer 6903 6903 0 0.00 0 6822 8 0 0
t$perf-norm:sp_pay_to_supplier 5775 5775 0 0.00 0 2712 7 0 0
t$perf-norm:sp_reserve_write_off 9653 9653 0 0.00 0 5430 8 0 0
t$perf-norm:sp_supplier_invoice 5255 5255 0 0.00 0 5380 14 0 0
t$perf-norm:sp_supplier_order 5375 5375 0 0.00 0 4326 14 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 232 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 742 26 0 0
t$perf-norm:srv_make_invnt_saldo 494 494 0 0.00 0 843 7 0 0
t$perf-norm:srv_make_money_saldo 691 691 0 0.00 0 4175 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 19 6 0 0
v_add_invoice_to_stock 9714 9422 292 3.00 0 9165 21 0 292
v_all_customers 36670 36670 0 0.00 0 21 1 0 0
v_all_suppliers 29900 29900 0 0.00 0 19 1 0 0
v_all_wares 40124 40124 0 0.00 0 37 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 7984 22 0 0
v_cancel_client_order 3568 3568 0 0.00 0 7568 10 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 4519 10 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 4519 7 0 0
v_cancel_payment_to_supplier 736 736 0 0.00 0 1546 6 0 0
v_cancel_supplier_invoice 860 844 16 1.86 0 2121 17 0 16
v_cancel_supplier_order 878 878 0 0.00 0 1011 4 0 0
v_cancel_write_off 1978 1978 0 0.00 0 2195 15 0 0
v_max_id_clo_ord 5375 5375 0 0.00 0 353 1 0 0
v_max_id_clo_res 985 985 0 0.00 0 8383 29 0 0
v_max_id_ord_sup 5253 5253 0 0.00 0 4507 5 0 0
v_max_non_paid_invoice 1453 1453 0 0.00 0 4347 5 0 0
v_max_non_paid_realizn 3599 3599 0 0.00 0 5814 5 0 0
v_min_id_clo_ord 5375 5375 0 0.00 0 5163 7 0 0
v_min_id_clo_res 985 985 0 0.00 0 9355 17 0 0
v_min_id_ord_sup 5253 5253 0 0.00 0 10023 9 0 0
v_min_non_paid_invoice 5775 5775 0 0.00 0 12058 14 0 0
v_min_non_paid_realizn 6903 6903 0 0.00 0 8324 16 0 0
v_our_firm 10062 10062 0 0.00 0 11 1 0 0
v_reserve_write_off 19304 19304 0 0.00 0 7046 7 0 0
x_lock_depdocs_on_canc_invoice 12190 12190 0 0.00 0 10649 107 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 6313 313 0 0
x_make_qstorno_1000_1200 5375 5375 0 0.00 2 51987 1274 0 0
x_make_qstorno_1200_2000 5255 5255 0 0.00 3 106274 2975 0 0
x_make_qstorno_2100_3300 442701 442701 0 0.00 0 79678 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-01-2021 13:27:47 to 09-01-2021 13:27:51.

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
10:56:58 19 8192000000 8799288720 8810778624 5309888 0.064 2 1 2 0 5407504 61840 35776
10:58:00 25244 8192000000 9731381552 10030092288 806497200 8.962 100 87 173 1 834412768 7518896 20396672
10:59:33 19295 8192000000 9772354960 10079301632 847015296 9.370 100 91 182 0 881544448 12997696 21531456
11:00:56 8064 8192000000 9808531152 10122162176 870654704 9.607 100 88 176 0 916916240 25829184 20432352
11:02:10 13111 8192000000 9854497680 10189025280 905592336 9.954 100 76 152 1 972068816 49965824 16510656
11:03:25 8070 8192000000 9899579008 10239451136 943960400 10.332 100 69 138 0 1001588192 43080912 14546880
11:04:35 4922 8192000000 9962916816 10317434880 990356832 10.785 100 82 162 1 1090095712 82710992 17027888
11:05:42 2562 8192000000 9982483232 10344685568 1020553120 11.077 100 84 166 0 1094966704 57988320 16425264
11:06:45 2573 8192000000 10025046720 10392125440 1046059296 11.323 100 90 179 0 1139684000 74987248 18637456
11:07:50 1552 8192000000 10040445760 10411388928 1067343904 11.527 100 90 181 0 1160195056 74577840 18273312
11:08:52 11954 8192000000 9992763728 10374950912 1068140640 11.534 100 52 103 2 1140378480 60848880 11388960
11:10:07 7361 8192000000 9983987888 10389118976 1076839680 11.617 100 44 86 0 1100361056 14403008 9118368
11:11:18 5911 8192000000 9993150400 10391429120 1085375824 11.699 100 44 89 3 1122781776 28143920 9262032
11:12:27 12591 8192000000 9991473408 10401312768 1092792944 11.769 100 21 42 1 1124397152 27411456 4192752
11:13:44 27875 8192000000 9995918160 10408566784 1094820768 11.788 100 23 45 2 1130448448 31130976 4496704
11:15:14 6326 8192000000 10022829408 10416254976 1099711744 11.835 100 35 70 2 1133737792 25361920 8664128
11:16:21 10038 8192000000 10021216848 10422616064 1069961088 11.552 98 32 60 2 1106368848 30127088 6280672
11:17:36 73311 8192000000 9943321712 10340560896 955347712 10.443 90 34 69 1 988611616 25830576 7433328
11:19:50 6729 8192000000 9944373824 10327470080 1011218752 10.987 97 41 83 0 1031861008 11458272 9183984
11:21:01 5447 8192000000 9932003744 10310279168 1014132224 11.015 100 52 105 2 1037408080 11371120 11904736
11:22:09 63326 8192000000 9977344352 10384490496 1034026800 11.207 99 26 52 1 1073659888 34371888 5261200
11:24:12 5621 8192000000 9985825584 10377289728 1057513136 11.433 100 42 82 2 1096217536 29453488 9250912
11:25:20 9522 8192000000 9989614768 10395381760 1071408144 11.566 100 28 56 0 1101399616 23535184 6456288
11:26:33 14911 8192000000 10010525280 10417610752 1094059776 11.781 100 24 47 3 1127257392 27834704 5362912
11:27:50 64435 8192000000 10068083168 10465181696 1107962880 11.913 100 56 109 2 1175732880 56100752 11669248
11:29:59 6856 8192000000 10094387408 10498539520 1125777568 12.082 100 55 109 3 1192899152 55830576 11291008
11:31:09 12183 8192000000 10111247632 10514415616 1140204848 12.217 100 59 116 1 1217059584 64749920 12104816
11:32:24 9468 8192000000 10144838560 10556420096 1154507296 12.352 100 41 82 2 1248140640 84661488 8971856
11:33:39 9670 8192000000 10209913680 10614251520 1153159008 12.339 99 57 114 0 1303330336 137556368 12614960
11:34:56 19728 8192000000 10237274320 10649038848 1164443440 12.445 99 48 96 3 1335641344 160122384 11075520
11:36:19 8539 8192000000 10282902976 10695532544 1188773552 12.672 100 60 120 0 1394381664 192231536 13376576
11:37:35 6380 8192000000 10322380336 10740240384 1188058976 12.665 100 66 132 0 1428836528 225531504 15246048
11:38:45 6637 8192000000 10372367536 10796126208 1191939376 12.701 100 67 132 1 1483525936 276121008 15465552
11:39:58 2629 8192000000 10388228832 10796269568 1190063744 12.684 100 75 149 1 1494445408 287528192 16853472
11:41:07 5270 8192000000 10438411216 10851307520 1191548112 12.698 100 82 164 0 1542610448 331700816 19361520
11:42:17 3897 8192000000 10461857552 10882654208 1189769744 12.681 100 76 149 0 1557470960 350145568 17555648
11:43:25 3973 8192000000 10499388976 10920542208 1195824960 12.738 100 79 158 1 1597240224 383016096 18399168
11:44:35 1479 8192000000 10532204992 10931494912 1180438912 12.594 100 93 185 0 1632548720 429899168 22210640
11:45:39 8586 8192000000 10555465776 10981621760 1172443680 12.520 100 51 100 0 1651618112 465886976 13287456
11:46:52 6707 8192000000 10463891856 10921648128 1155337248 12.360 99 41 82 0 1598030768 431978512 10715008
11:48:03 19899 8192000000 10430812192 10906537984 1140513136 12.220 99 36 72 1 1555662368 405010176 10139056
11:49:30 27948 8192000000 10343841312 10807980032 1142386656 12.238 100 25 50 1 1496508160 346579808 7541696
11:51:08 34033 8192000000 10261837712 10736726016 1097119760 11.810 98 18 36 0 1361875456 260085376 4670320
11:52:44 51613 8192000000 10082821072 10512404480 1109099792 11.924 100 29 59 0 1180500192 63456768 7943632
11:54:37 77636 8192000000 10037854096 10547982336 1087905120 11.723 98 9 18 2 1159350032 69341424 2103488
11:56:55 49408 8192000000 10096776960 10545958912 1130800960 12.129 100 28 57 0 1200032352 63290656 5940736
11:59:00 88568 8192000000 10080243504 10517307392 1074721328 11.597 95 5 10 0 1112747344 37213264 812752
12:01:36 54482 8192000000 10077679392 10512576512 1119326944 12.021 100 32 59 1 1176815024 51090496 6397584
12:03:32 27663 8192000000 10070238528 10534330368 1122295552 12.049 100 8 16 1 1179381888 55424496 1661840
12:05:11 83717 8192000000 10083024928 10522337280 1114759200 11.977 99 16 32 0 1147258448 28912320 3586928
12:07:40 53237 8192000000 10073407760 10517123072 1140439200 12.220 100 8 16 0 1180458352 38272352 1746800
12:09:34 140777 8192000000 10087844064 10567950336 1150226608 12.312 100 25 50 1 1206040768 50268112 5546048
12:13:00 23840 8192000000 10135342016 10574958592 1154279056 12.350 100 40 80 0 1236520624 73535808 8705760
12:14:38 40733 8192000000 10121224512 10568273920 1156577616 12.371 100 23 46 1 1218021984 56481920 4962448
12:16:20 35834 8192000000 10146502768 10589511680 1146147728 12.273 99 30 59 1 1239096960 86823472 6125760
12:17:57 58399 8192000000 10141589424 10636689408 1138562208 12.202 99 15 30 0 1247821680 105714544 3544928
12:20:01 99022 8192000000 10079624480 10600800256 1124192384 12.067 98 4 8 0 1187531136 62495872 842880
12:22:41 44736 8192000000 10095391536 10530869248 1138894320 12.205 99 22 43 0 1194434896 50312656 5227920
12:24:30 34703 8192000000 10120671056 10573217792 1142829376 12.242 100 20 40 1 1199240736 52007680 4403680
12:26:16 36876 8192000000 10067310032 10521468928 1110990816 11.942 98 28 55 3 1162305392 45911920 5402656
12:27:56 74661 8192000000 10109926528 10571833344 1073832688 11.589 95 12 24 2 1117284800 41247856 2204256
12:30:11 80828 8192000000 10104995696 10568101888 1074247168 11.593 96 13 26 0 1180577872 103354832 2975872
12:32:32 71470 8192000000 10066113664 10525114368 1072514560 11.576 96 18 36 0 1099515824 23534720 3466544
12:35:02 98708 8192000000 10057045248 10513891328 1078265104 11.631 97 19 38 0 1144860608 62811824 3783680
12:37:45 59289 8192000000 10088006288 10535411712 1090145392 11.744 98 12 24 0 1178811824 86086384 2580048
12:39:44 66980 8192000000 10039389296 10472542208 1092805152 11.769 99 18 36 1 1128345120 31604624 3935344
12:42:01 57449 8192000000 10061289280 10530574336 1086063568 11.705 98 18 36 1 1144984864 55432656 3488640
12:43:59 59882 8192000000 10055499632 10491858944 1093991952 11.781 99 7 14 0 1148445168 52845600 1607616
12:46:00 46617 8192000000 10097055728 10542886912 1114634576 11.976 100 19 38 0 1173215248 54187584 4393088
12:47:57 101365 8192000000 10150233440 10596347904 1123292528 12.058 99 28 56 1 1251329520 121661888 6375104
12:50:58 54399 8192000000 10084563408 10594521088 1107000416 11.904 98 14 28 0 1182495392 72529280 2965696
12:52:59 46156 8192000000 10105936480 10593251328 1119344240 12.021 98 22 44 1 1171315776 47751456 4220080
12:54:54 136807 8192000000 10098132112 10556518400 1121811376 12.044 98 10 20 0 1175134048 51394032 1928640
12:58:11 42643 8192000000 10107959072 10577027072 1107884144 11.912 97 8 16 1 1178823536 69449184 1490208
13:00:13 46210 8192000000 10070317296 10513035264 1100904752 11.846 96 20 40 0 1149849696 44855664 4089280
13:02:04 27394 8192000000 10129077136 10571960320 1156538432 12.371 100 20 40 0 1216327616 55616304 4172880
13:03:35 22882 8192000000 10183090560 10619674624 1166571088 12.465 100 46 92 2 1256876320 80131952 10173280
13:05:03 109785 8192000000 10177432304 10699726848 1158304416 12.387 100 12 24 0 1256885968 95958096 2623456
13:07:54 44751 8192000000 10095023456 10522914816 1125822336 12.082 97 28 56 1 1154258512 22478672 5957504
13:09:42 23499 8192000000 10113182544 10580615168 1142451616 12.239 99 42 84 1 1205129984 54319248 8359120
13:11:14 147592 8192000000 10089991280 10571227136 1151434528 12.323 100 46 92 2 1199023088 37904512 9684048
13:14:51 41600 8192000000 10082339280 10557812736 1153810288 12.345 100 5 10 0 1185079728 30202240 1067200
13:16:37 58365 8192000000 10110085184 10559696896 1107361888 11.907 96 24 48 2 1161499056 49460224 4676944
13:18:36 113512 8192000000 10142191872 10643197952 1126132464 12.085 98 21 42 0 1224840272 93662096 5045712
13:21:45 43840 8192000000 10096252640 10572156928 1122270592 12.048 98 14 28 0 1177090848 51892112 2928144
13:23:34 71608 8192000000 10055176896 10499448832 1084763712 11.693 96 20 40 0 1130593648 41696608 4133328
13:25:54 45857 8192000000 10074667520 10514378752 1119965616 12.027 99 20 40 1 1157540720 33156736 4418368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-01-2021 13:27:53 to 09-01-2021 13:28:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 146 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

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

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          235347
MON$OLDEST_ACTIVE               237482
MON$OLDEST_SNAPSHOT             237482
MON$NEXT_TRANSACTION            237482
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                       2126720
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             803
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1/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 ---
:16: 
:17: mscs-test-161.budzdorov.ru    Sat Jan  9 13:26:56 2021
:18:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:19: 
:20: 
:21: mscs-test-161.budzdorov.ru    Sat Jan  9 13:27:01 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
--- end of diff output ---

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

09.01.21 13:28:12. 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-01-09 13:28:14 No severe PSQL-related problems occured
09.01.21 13:28:15. End of report.