Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.03.2021 10:29
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16918072 kB
MemAvailable 31626596 kB
Buffers 0 kB
Cached 14771728 kB
SwapCached 0 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-03-11 10:26:56
DTS_END                         2021-03-11 10:26:56.7990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(20:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49824
TRN_ID                          232681
ATT_ID                          685
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: 9176.29 24976 1101155
customer order: creation 165.17 369 19820
customer order: refuse 14.15 311 1698
order to supplier: creation 44.20 1363 5304
order to supplier: removal 3.67 105608 440
invoice (draft): creation 45.98 3570 5517
invoice (draft): removal 52.98 948 6357
invoice accept: apply 41.62 17217 4994
invoice accept: cancel 47.34 875 5681
customer reserve: creation 3854.68 168 462561
customer reserve: removal 4619.96 66 554395
realization accept: apply 81.28 160 9754
realization accept: cancel 79.55 129 9546
payment from customer: creation 57.70 103 6924
payment from customer: removal 5.79 103 695
payment to supplier: creation 48.28 112 5794
payment to supplier: removal 3.38 70 405
service: total inventory turnovers 3.53 3745 423
service: total monetary turnovers 6.16 520 739
service: aggregate perf. data 0.86 15776 103
service: refresh index statistics 0.04 348306 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-03-2021 10:29:27 to 11-03-2021 10:29:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3765
TEST_TIME 2 3223
TEST_TIME 3 4197
TEST_TIME 4 4349
TEST_TIME 5 4515
TEST_TIME 6 4539
TEST_TIME 7 4788
TEST_TIME 8 4805
TEST_TIME 9 4834
TEST_TIME 10 4981
TEST_TIME 11 5793
TEST_TIME 12 5766
TEST_TIME 13 6018
TEST_TIME 14 6141
TEST_TIME 15 6616
TEST_TIME 16 6777
TEST_TIME 17 6965
TEST_TIME 18 7564
TEST_TIME 19 7384
TEST_TIME 20 7617
TEST_TIME 21 7731
TEST_TIME 22 7946
TEST_TIME 23 8585
TEST_TIME 24 8905
TEST_TIME 25 9248
TEST_TIME 26 10149
TEST_TIME 27 10601
TEST_TIME 28 11012
TEST_TIME 29 10986
TEST_TIME 30 10766
TEST_TIME 31 10767
TEST_TIME 32 10636
TEST_TIME 33 10977
TEST_TIME 34 10735
TEST_TIME 35 10461
TEST_TIME 36 10265
TEST_TIME 37 10175
TEST_TIME 38 10102
TEST_TIME 39 10113
TEST_TIME 40 10151
TEST_TIME 41 10044
TEST_TIME 42 9932
TEST_TIME 43 9900
TEST_TIME 44 9930
TEST_TIME 45 9869
TEST_TIME 46 9760
TEST_TIME 47 9786
TEST_TIME 48 9830
TEST_TIME 49 9857
TEST_TIME 50 10020
TEST_TIME 51 9965
TEST_TIME 52 9981
TEST_TIME 53 9988
TEST_TIME 54 9919
TEST_TIME 55 10062
TEST_TIME 56 10050
TEST_TIME 57 10140
TEST_TIME 58 10011
TEST_TIME 59 9921
TEST_TIME 60 9850
TEST_TIME 61 9793
TEST_TIME 62 9690
TEST_TIME 63 9648
TEST_TIME 64 9566
TEST_TIME 65 9616
TEST_TIME 66 9572
TEST_TIME 67 9542
TEST_TIME 68 9536
TEST_TIME 69 9500
TEST_TIME 70 9491
TEST_TIME 71 9590
TEST_TIME 72 9694
TEST_TIME 73 9755
TEST_TIME 74 9796
TEST_TIME 75 9845
TEST_TIME 76 9804
TEST_TIME 77 9798
TEST_TIME 78 9873
TEST_TIME 79 9779
TEST_TIME 80 9712
TEST_TIME 81 9667
TEST_TIME 82 9599
TEST_TIME 83 9555
TEST_TIME 84 9505
TEST_TIME 85 9537
TEST_TIME 86 9531
TEST_TIME 87 9490
TEST_TIME 88 9458
TEST_TIME 89 9460
TEST_TIME 90 9510
TEST_TIME 91 9525
TEST_TIME 92 9494
TEST_TIME 93 9487
TEST_TIME 94 9545
TEST_TIME 95 9561
TEST_TIME 96 9495
TEST_TIME 97 9417
TEST_TIME 98 9379
TEST_TIME 99 9322
TEST_TIME 100 9263
TEST_TIME 101 9233
TEST_TIME 102 9224
TEST_TIME 103 9222
TEST_TIME 104 9194
TEST_TIME 105 9177
TEST_TIME 106 9164
TEST_TIME 107 9212
TEST_TIME 108 9247
TEST_TIME 109 9247
TEST_TIME 110 9350
TEST_TIME 111 9323
TEST_TIME 112 9319
TEST_TIME 113 9291
TEST_TIME 114 9346
TEST_TIME 115 9363
TEST_TIME 116 9343
TEST_TIME 117 9269
TEST_TIME 118 9223
TEST_TIME 119 9190
TEST_TIME 120 9180

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-03-2021 10:29:30 to 11-03-2021 10:29:34.

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 1529228 1529228 0 0.00 0 20200 6 0 0
doc_list_biud 1529228 1529228 0 0.00 0 33944 31 0 0
sp_add_invoice_to_stock 5121 4994 127 2.47 29 159639 17218 0 127
sp_cancel_adding_invoice 5681 5681 0 0.00 5 100092 875 0 0
sp_cancel_client_order 1698 1698 0 0.00 4 9394 311 0 0
sp_cancel_customer_reserve 554395 554395 0 0.00 1 18646 66 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 6144 103 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 1302 70 0 0
sp_cancel_supplier_invoice 6370 6357 13 0.20 2 17504 949 0 13
sp_cancel_supplier_order 440 440 0 0.00 10 1480061 105609 0 0
sp_cancel_write_off 9546 9546 0 0.00 1 7737 130 0 0
sp_client_order 19820 19820 0 0.00 4 48895 369 0 0
sp_customer_reserve 462561 462561 0 0.00 0 39112 169 0 0
sp_fill_shopping_cart 30640 30640 0 0.00 1 14137 40 0 0
sp_fill_shopping_cart_clo_res 462555 462555 0 0.00 0 29127 16 0 0
sp_get_clo_for_invoice 4986 4986 0 0.00 0 28097 372 0 0
sp_kill_cost_storno 16327 16327 0 0.00 0 6139 58 0 0
sp_kill_qstorno_ret_qs2qd 1125133 1125133 0 0.00 0 33943 36 0 0
sp_kill_qty_storno 592856 592856 0 0.00 0 33943 78 0 0
sp_lock_selected_doc 20476 20476 0 0.00 0 6071 5 0 0
sp_make_cost_storno 27457 27457 0 0.00 0 13002 47 0 0
sp_multiply_rows_for_pdistr 27457 27457 0 0.00 0 5563 28 0 0
sp_multiply_rows_for_qdistr 19820 19820 0 0.00 0 48883 197 0 0
sp_pay_from_customer 6924 6924 0 0.00 4 9061 104 0 0
sp_pay_to_supplier 5794 5794 0 0.00 4 11135 113 0 0
sp_payment_common 12718 12718 0 0.00 1 9015 20 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 0 9386 284 0 0
sp_qd_handle_on_invoice_upd_sts 10666 10666 0 0.00 0 15004 363 0 0
sp_qd_handle_on_reserve_upd_sts 9754 9754 0 0.00 0 8538 49 0 0
sp_reserve_write_off 9754 9754 0 0.00 7 11707 161 0 0
sp_supplier_invoice 5517 5517 0 0.00 6 116444 3571 0 0
sp_supplier_order 5304 5304 0 0.00 5 24485 1364 0 0
srv_aggregate_perf_data 103 103 0 0.00 2477 140841 15776 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1561 130481 44767 0 0
srv_find_qd_qs_mism 1054134 1054134 0 0.00 0 20199 6 0 0
srv_make_invnt_saldo 423 423 0 0.00 39 135096 3745 0 0
srv_make_money_saldo 739 739 0 0.00 1 15995 520 0 0
srv_recalc_idx_stat 5 5 0 0.00 242531 536115 348307 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4994 4994 0 0.00 0 10076 172 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 6303 109 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 4169 13 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 1718 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 3287 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 838 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 1566 27 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 19910 1343 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 9108 17 0 0
t$perf-norm:sp_client_order 19820 19820 0 0.00 0 18122 19 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 1425 7 0 0
t$perf-norm:sp_pay_from_customer 6924 6924 0 0.00 0 5406 11 0 0
t$perf-norm:sp_pay_to_supplier 5794 5794 0 0.00 0 8582 11 0 0
t$perf-norm:sp_reserve_write_off 9754 9754 0 0.00 0 11560 11 0 0
t$perf-norm:sp_supplier_invoice 5517 5517 0 0.00 0 22784 26 0 0
t$perf-norm:sp_supplier_order 5304 5304 0 0.00 0 4152 20 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 1002 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2819 52 0 0
t$perf-norm:srv_make_invnt_saldo 423 423 0 0.00 0 467 10 0 0
t$perf-norm:srv_make_money_saldo 739 739 0 0.00 0 7439 22 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10224 9970 254 2.48 0 11639 19 0 254
v_all_customers 36054 36054 0 0.00 0 123 1 0 0
v_all_suppliers 30022 30022 0 0.00 0 28 1 0 0
v_all_wares 39640 39640 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 2378 17 0 0
v_cancel_client_order 3396 3396 0 0.00 0 2998 7 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 3055 17 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 2193 9 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 1150 11 0 0
v_cancel_supplier_invoice 866 840 26 3.00 0 1376 19 0 26
v_cancel_supplier_order 880 880 0 0.00 0 387 4 0 0
v_cancel_write_off 2030 2030 0 0.00 0 2620 15 0 0
v_max_id_clo_ord 5303 5303 0 0.00 0 187 1 0 0
v_max_id_clo_res 995 995 0 0.00 0 1723 9 0 0
v_max_id_ord_sup 5517 5517 0 0.00 0 6657 4 0 0
v_max_non_paid_invoice 1603 1603 0 0.00 0 474 2 0 0
v_max_non_paid_realizn 3740 3740 0 0.00 0 4001 5 0 0
v_min_id_clo_ord 5303 5303 0 0.00 0 5541 6 0 0
v_min_id_clo_res 995 995 0 0.00 0 2578 7 0 0
v_min_id_ord_sup 5517 5517 0 0.00 0 5052 6 0 0
v_min_non_paid_invoice 5794 5794 0 0.00 0 6754 11 0 0
v_min_non_paid_realizn 6924 6924 0 0.00 0 3887 12 0 0
v_our_firm 9954 9954 0 0.00 0 27 1 0 0
v_reserve_write_off 19508 19508 0 0.00 0 3933 7 0 0
x_lock_depdocs_on_canc_invoice 11618 11618 0 0.00 0 10235 100 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 7818 315 0 0
x_make_qstorno_1000_1200 5304 5304 0 0.00 2 24461 1285 0 0
x_make_qstorno_1200_2000 5517 5517 0 0.00 3 116435 3429 0 0
x_make_qstorno_2100_3300 462561 462561 0 0.00 0 39112 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-03-2021 10:29:35 to 11-03-2021 10:29:39.

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:00 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:01 31656 8192000000 9721890000 10014662656 791857232 8.814 100 85 170 0 821070048 8107584 21105232
07:59:37 10674 8192000000 9767128528 10074689536 844787216 9.348 100 88 176 0 878026976 12277792 20961968
08:00:52 20774 8192000000 9811249872 10125328384 865667072 9.557 100 81 162 1 904285376 19997120 18621184
08:02:23 14400 8192000000 9856413008 10185080832 908362288 9.981 100 79 158 0 959742048 34275536 17104224
08:03:41 8556 8192000000 9922152800 10265329664 952445008 10.415 100 87 175 0 1037931568 66991216 18495344
08:04:54 4729 8192000000 9953759616 10302337024 987631360 10.758 100 79 158 1 1061096560 57578560 15886640
08:06:01 3332 8192000000 9992142288 10350387200 1012514560 11.000 100 84 168 2 1110449104 81533792 16400752
08:07:05 1877 8192000000 10020265792 10385985536 1038973808 11.255 100 90 176 2 1123690544 65426752 19289984
08:08:09 990 8192000000 10034293168 10402578432 1064803008 11.502 100 93 187 1 1142536208 59280736 18452464
08:09:10 6801 8192000000 9998540976 10381426688 1065930976 11.513 100 59 118 1 1099653408 21944624 11777808
08:10:25 6669 8192000000 9996269088 10380017664 1073561808 11.586 100 35 69 0 1110513520 28547936 8403776
08:11:34 7641 8192000000 10000294352 10395328512 1082369808 11.670 100 40 79 0 1109510480 18116608 9024064
08:12:43 4042 8192000000 9990274880 10391343104 1092340992 11.765 100 27 53 2 1121542992 23939568 5262432
08:13:48 58455 8192000000 10028056256 10452013056 1098214560 11.821 100 37 72 1 1129880544 23997040 7668944
08:15:52 7367 8192000000 10018070304 10426920960 1097827040 11.817 99 44 87 2 1130083376 22823696 9432640
08:17:03 9834 8192000000 10008422880 10421907456 1093068336 11.772 99 26 52 2 1132918992 34221808 5628848
08:18:13 11509 8192000000 9981374704 10386141184 1030683440 11.175 95 24 49 1 1060059712 23754752 5621520
08:19:29 43355 8192000000 9949622048 10342453248 987381040 10.756 92 38 76 1 1010268576 14308832 8578704
08:21:14 11042 8192000000 9924158368 10313039872 973726848 10.623 95 32 64 1 1021277312 40388800 7161664
08:22:25 6628 8192000000 9950698400 10338676736 1018735568 11.060 98 38 75 2 1038831456 11533696 8562192
08:23:33 7980 8192000000 9960886544 10351321088 1042899664 11.293 100 38 74 1 1067281472 15869024 8512784
08:24:46 54318 8192000000 9966309136 10365566976 1053821104 11.397 100 39 77 2 1080738752 18177248 8740400
08:26:41 4809 8192000000 9981089424 10368188416 1067574720 11.529 100 32 62 3 1083364224 9679408 6110096
08:27:52 4187 8192000000 10013909552 10412859392 1084053856 11.686 100 54 108 2 1123959744 27683264 12222624
08:29:03 14448 8192000000 10044080384 10448474112 1096291408 11.802 100 51 101 0 1155915808 48527696 11096704
08:30:23 18719 8192000000 10100842176 10500087808 1115072720 11.980 100 52 103 1 1198193488 71823472 11297296
08:31:45 14823 8192000000 10116776352 10530357248 1123876320 12.064 100 39 76 1 1227540368 94759648 8904400
08:33:09 20222 8192000000 10175433888 10598289408 1133377872 12.153 100 53 103 1 1273095072 129260768 10456432
08:34:33 11832 8192000000 10198022064 10610266112 1150692848 12.316 100 44 88 0 1295467792 134886016 9888928
08:35:50 9362 8192000000 10279082320 10691067904 1162530032 12.427 100 61 120 0 1386353088 210537312 13285744
08:37:05 8388 8192000000 10278423296 10694500352 1177571904 12.568 100 52 103 1 1385609488 196106672 11930912
08:38:17 3624 8192000000 10322583664 10724044800 1189525008 12.679 100 86 170 2 1414470176 206147328 18797840
08:39:28 1708 8192000000 10368145888 10773315584 1197573968 12.754 100 85 167 1 1471050112 255697472 17778672
08:40:33 2464 8192000000 10377488640 10779967488 1188445456 12.669 100 94 187 0 1477472048 267771040 21255552
08:41:40 2659 8192000000 10377421184 10807791616 1165514704 12.455 99 89 176 1 1474670336 288805104 20350528
08:42:49 1557 8192000000 10392601440 10811543552 1174602208 12.540 100 89 177 1 1489157280 294824192 19730880
08:43:56 1850 8192000000 10457389136 10869436416 1177194960 12.564 100 82 164 1 1557197360 360566192 19436208
08:45:05 14478 8192000000 10387515312 10805153792 1159667152 12.400 100 52 103 0 1474841264 302411488 12762624
08:46:33 5349 8192000000 10443090608 10857996288 1156788704 12.373 100 60 121 0 1543652832 371692208 15171920
08:47:42 19011 8192000000 10445491968 10897108992 1122513568 12.051 98 43 86 0 1550508720 415951456 12043696
08:49:18 8268 8192000000 10393157824 10833518592 1125683792 12.081 99 38 76 0 1497246912 361432768 10130352
08:50:29 14040 8192000000 10387118000 10820960256 1129097840 12.113 99 34 66 1 1473642240 335714032 8830368
08:51:45 23015 8192000000 10339604256 10806423552 1123938944 12.064 100 34 68 0 1441231392 308589664 8702784
08:53:10 29548 8192000000 10122547968 10624012288 1114283152 11.973 99 10 20 0 1242867440 126300704 2283584
08:54:40 54342 8192000000 10050648752 10522103808 1096462288 11.804 97 18 36 1 1169332480 69300304 3569888
08:56:35 21208 8192000000 10073871952 10500984832 1096349408 11.803 97 38 76 0 1135205328 31317184 7538736
08:58:02 14607 8192000000 10090746416 10513809408 1120422128 12.031 100 36 67 1 1172224048 45138272 6663648
08:59:30 129501 8192000000 10050844048 10487439360 1090336656 11.746 97 18 36 0 1138340928 43866176 4138096
09:02:52 38464 8192000000 10064895216 10503045120 1075754560 11.607 96 29 57 1 1131291392 49288512 6248320
09:04:38 76250 8192000000 10111567568 10599018496 1117073280 11.999 100 33 65 1 1210016400 85557824 7385296
09:06:56 31573 8192000000 10076030320 10522857472 1121198800 12.038 100 28 53 2 1172112128 45298896 5614432
09:08:58 37836 8192000000 10108926336 10560905216 1135762896 12.176 100 19 38 0 1206083376 66291232 4029248
09:10:42 61724 8192000000 10061833072 10551820288 1119912272 12.026 98 14 28 1 1163743648 41194784 2636592
09:12:44 22943 8192000000 10120888048 10558316544 1146491296 12.277 100 35 70 0 1211474944 56872704 8110944
09:14:09 35048 8192000000 10141807888 10594058240 1143457936 12.248 99 26 52 1 1234783552 85680912 5644704
09:15:54 80876 8192000000 10080298112 10543677440 1138464864 12.201 99 13 24 1 1213055104 71926432 2663808
09:18:18 57188 8192000000 10114922208 10570571776 1145250704 12.265 99 24 46 2 1212464368 62659616 4554048
09:20:15 61586 8192000000 10119819728 10619363328 1120414544 12.031 97 18 36 1 1205357664 81255536 3687584
09:22:17 86969 8192000000 10123568640 10580336640 1098360320 11.822 96 18 36 0 1150065552 47789696 3915536
09:24:45 98203 8192000000 10084902768 10567606272 1136560560 12.183 99 16 32 1 1176920464 37333472 3026432
09:27:40 75438 8192000000 10062619968 10549211136 1127326176 12.096 100 12 24 0 1174365024 44861728 2177120
09:30:01 82334 8192000000 10114698608 10576642048 1089626176 11.739 96 22 44 0 1156637472 61913152 5098144
09:32:39 38716 8192000000 10083745360 10524049408 1100612624 11.843 98 17 33 0 1160224160 56383968 3227568
09:35:00 40535 8192000000 10141184960 10591281152 1124934896 12.074 99 17 34 1 1244699712 115053184 4711632
09:36:41 56983 8192000000 10155385856 10622001152 1137902960 12.196 100 20 40 0 1244797040 102204752 4689328
09:38:38 69043 8192000000 10081474192 10576576512 1089753824 11.740 96 12 24 2 1169829040 77486992 2588224
09:40:49 52678 8192000000 10070010848 10573889536 1116250128 11.992 99 14 28 0 1175672032 56763552 2658352
09:42:42 13981 8192000000 10092896480 10520223744 1116340448 11.992 99 65 129 3 1165976256 36541552 13094256
09:43:57 37613 8192000000 10130946656 10601058304 1129352016 12.115 100 48 96 1 1204553872 65791648 9410208
09:45:37 42405 8192000000 10070241936 10560606208 1119870256 12.026 99 18 36 0 1168338880 45034352 3434272
09:47:31 61552 8192000000 10036316912 10492305408 1099524336 11.833 98 2 4 0 1123941616 24183712 233568
09:49:34 47566 8192000000 10092139216 10545156096 1115027824 11.980 99 28 56 2 1166920448 46137808 5754816
09:51:26 106299 8192000000 10053282640 10517508096 1112157792 11.953 99 9 18 0 1154169600 40196960 1814848
09:54:19 46085 8192000000 10128405152 10573119488 1133227712 12.152 100 18 37 0 1188224944 51410928 3586304
09:56:16 79428 8192000000 10073766912 10526806016 1113651888 11.967 98 10 20 1 1154594640 38876272 2066480
09:58:45 24928 8192000000 10114641088 10556182528 1122820640 12.054 99 30 60 0 1170451120 41673792 5956688
10:00:17 130480 8192000000 10084360064 10585571328 1131017904 12.131 99 10 20 0 1200641264 67267616 2355744
10:03:30 64539 8192000000 10047574448 10497376256 1131629376 12.137 99 22 44 2 1163049744 26555072 4865296
10:05:35 66518 8192000000 10083287120 10534645760 1137402384 12.191 100 6 12 0 1174594336 36145264 1046688
10:09:34 70172 8192000000 10064259056 10512125952 1099801328 11.836 96 17 34 0 1136523424 33111392 3610704
10:11:44 33312 8192000000 10131265200 10574454784 1151138272 12.320 99 26 52 0 1210142624 53551344 5453008
10:13:22 93401 8192000000 10109662672 10554220544 1109450672 11.927 96 11 22 0 1192914720 81433552 2030496
10:16:02 115215 8192000000 10099961088 10562514944 1081810704 11.665 94 17 35 1 1129632496 44270992 3550800
10:19:10 16862 8192000000 10090259936 10517671936 1125862640 12.082 100 55 110 0 1174042176 36896816 11282720
10:20:33 125695 8192000000 10062984864 10504175616 1057944352 11.437 94 12 25 0 1112414096 52226544 2243200
10:23:39 83072 8192000000 10063276464 10510512128 1101498256 11.852 98 18 36 0 1139315712 34175376 3642080
10:26:14 52188 8192000000 9747681520 10177208320 851396032 9.414 76 6 12 0 887630704 35070656 1164016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-03-2021 10:29:40 to 11-03-2021 10:29:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-03-2021 10:29:56 to 11-03-2021 10:29:58.

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          232843
MON$OLDEST_ACTIVE               232844
MON$OLDEST_SNAPSHOT             232844
MON$NEXT_TRANSACTION            232844
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                       2102912
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2385 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2385 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2385 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.2385 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Thu Mar 11 10:27:41 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Thu Mar 11 10:27:41 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Thu Mar 11 10:27:42 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Thu Mar 11 10:27:42 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Thu Mar 11 10:27:42 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Thu Mar 11 10:27:42 2021
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
:39: 
:40: mscs-test-161.budzdorov.ru    Thu Mar 11 10:27:42 2021
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:42: 
:43: 
:44: mscs-test-161.budzdorov.ru    Thu Mar 11 10:27:42 2021
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:46: 
:47: 
:48: mscs-test-161.budzdorov.ru    Thu Mar 11 10:27:42 2021
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:50: 
:51: 
:52: mscs-test-161.budzdorov.ru    Thu Mar 11 10:27:42 2021
:53:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:54: 
--- end of diff output ---

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

11.03.21 10:29:59. 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-03-11 10:30:01 No severe PSQL-related problems occured
11.03.21 10:30:02. End of report.