builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-064 starttime: 1501735701.34 results: success (0) revision: d942ef54fdf726840a698d2ddcaf989d2c00edbc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 21:48:21.344779) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 21:48:21.345202) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 21:48:21.345510) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 21:48:21.391511) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 21:48:21.392186) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-02 21:48:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 228M=0s 2017-08-02 21:48:21 (228 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.262809 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 21:48:21.689209) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 21:48:21.689530) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017273 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 21:48:21.740727) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 21:48:21.741064) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d942ef54fdf726840a698d2ddcaf989d2c00edbc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev d942ef54fdf726840a698d2ddcaf989d2c00edbc --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-02 21:48:21,906 truncating revision to first 12 chars 2017-08-02 21:48:21,906 Setting DEBUG logging. 2017-08-02 21:48:21,906 attempt 1/10 2017-08-02 21:48:21,906 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d942ef54fdf7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-02 21:48:22,233 unpacking tar archive at: mozilla-central-d942ef54fdf7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.669728 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 21:48:22.423645) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 21:48:22.423990) ========= script_repo_revision: d942ef54fdf726840a698d2ddcaf989d2c00edbc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 21:48:22.424703) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 21:48:22.425030) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 21:48:22.434994) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-08-02 21:48:22.435297) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:48:22 INFO - MultiFileLogger online at 20170802 21:48:22 in /builds/slave/test 21:48:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 21:48:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:48:22 INFO - {'append_to_log': False, 21:48:22 INFO - 'base_work_dir': '/builds/slave/test', 21:48:22 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 21:48:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:48:22 INFO - 'branch': 'Firefox-Non-PGO', 21:48:22 INFO - 'buildbot_json_path': 'buildprops.json', 21:48:22 INFO - 'code_coverage': False, 21:48:22 INFO - 'config_files': ('talos/linux_config.py',), 21:48:22 INFO - 'default_actions': ('clobber', 21:48:22 INFO - 'read-buildbot-config', 21:48:22 INFO - 'download-and-extract', 21:48:22 INFO - 'populate-webroot', 21:48:22 INFO - 'create-virtualenv', 21:48:22 INFO - 'install', 21:48:22 INFO - 'setup-mitmproxy', 21:48:22 INFO - 'run-tests'), 21:48:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:48:22 INFO - 'disable_ccov_upload': False, 21:48:22 INFO - 'download_minidump_stackwalk': True, 21:48:22 INFO - 'download_symbols': 'ondemand', 21:48:22 INFO - 'enable_stylo': False, 21:48:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:48:22 INFO - 'tooltool.py': '/tools/tooltool.py', 21:48:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:48:22 INFO - '/tools/misc-python/virtualenv.py')}, 21:48:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:48:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:48:22 INFO - 'gecko_profile': False, 21:48:22 INFO - 'gecko_profile_interval': 0, 21:48:22 INFO - 'installer_path': 'installer.exe', 21:48:22 INFO - 'log_level': 'info', 21:48:22 INFO - 'log_name': 'talos', 21:48:22 INFO - 'log_to_console': True, 21:48:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:48:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:48:22 INFO - 'opt_config_files': (), 21:48:22 INFO - 'pip_index': False, 21:48:22 INFO - 'suite': 'dromaeojs-e10s', 21:48:22 INFO - 'system_bits': '32', 21:48:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:48:22 INFO - 'title': 'talos-linux64-ix-064', 21:48:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:48:22 INFO - 'use_talos_json': True, 21:48:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:48:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:48:22 INFO - 'work_dir': 'build'} 21:48:22 INFO - [mozharness: 2017-08-03 04:48:22.790617Z] Running clobber step. 21:48:22 INFO - Running pre-action listener: _resource_record_pre_action 21:48:22 INFO - Running main action method: clobber 21:48:22 INFO - rmtree: /builds/slave/test/build 21:48:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:48:23 INFO - Running post-action listener: _resource_record_post_action 21:48:23 INFO - [mozharness: 2017-08-03 04:48:23.782701Z] Finished clobber step (success) 21:48:23 INFO - [mozharness: 2017-08-03 04:48:23.782804Z] Running read-buildbot-config step. 21:48:23 INFO - Running pre-action listener: _resource_record_pre_action 21:48:23 INFO - Running main action method: read_buildbot_config 21:48:23 INFO - Using buildbot properties: 21:48:23 INFO - { 21:48:23 INFO - "project": "", 21:48:23 INFO - "product": "firefox", 21:48:23 INFO - "who": "mh@glandium.org", 21:48:23 INFO - "installer_path": "public/build/target.tar.bz2", 21:48:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 21:48:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 21:48:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 21:48:23 INFO - "stage_platform": "linux64", 21:48:23 INFO - "basedir": "/builds/slave/test", 21:48:23 INFO - "buildnumber": 1055, 21:48:23 INFO - "platform": "ubuntu64_hw", 21:48:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:48:23 INFO - "slavebuilddir": "test", 21:48:23 INFO - "taskId": "N6md0_rkT0utRZjnYxe5hA", 21:48:23 INFO - "branch": "mozilla-central", 21:48:23 INFO - "script_repo_revision": "production", 21:48:23 INFO - "revision": "d942ef54fdf726840a698d2ddcaf989d2c00edbc", 21:48:23 INFO - "slavename": "talos-linux64-ix-064", 21:48:23 INFO - "repo_path": "mozilla-central" 21:48:23 INFO - } 21:48:23 INFO - Finding installer, test and symbols from parent task. 21:48:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N6md0_rkT0utRZjnYxe5hA'}, attempt #1 21:48:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N6md0_rkT0utRZjnYxe5hA'}, attempt #1 21:48:24 INFO - Task dependencies: V11kJWdpTmaYDM72zqhqGw 21:48:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw'}, attempt #1 21:48:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.tar.bz2 21:48:25 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.test_packages.json 21:48:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.crashreporter-symbols.zip 21:48:25 INFO - Running post-action listener: _resource_record_post_action 21:48:25 INFO - [mozharness: 2017-08-03 04:48:25.115377Z] Finished read-buildbot-config step (success) 21:48:25 INFO - [mozharness: 2017-08-03 04:48:25.115602Z] Running download-and-extract step. 21:48:25 INFO - Running pre-action listener: _resource_record_pre_action 21:48:25 INFO - Running main action method: download_and_extract 21:48:25 INFO - mkdir: /builds/slave/test/build/tests 21:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:25 INFO - trying https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.test_packages.json 21:48:25 INFO - Downloading https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 21:48:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 21:48:25 INFO - Downloaded 989 bytes. 21:48:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 21:48:25 INFO - Using the following test package requirements: 21:48:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 21:48:25 INFO - u'common': [u'target.common.tests.zip'], 21:48:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 21:48:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 21:48:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 21:48:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 21:48:25 INFO - u'mozbase': [u'target.common.tests.zip'], 21:48:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 21:48:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 21:48:25 INFO - u'web-platform': [u'target.common.tests.zip', 21:48:25 INFO - u'target.web-platform.tests.tar.gz'], 21:48:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 21:48:25 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 21:48:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.common.tests.zip 21:48:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:48:25 INFO - Fetch https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.common.tests.zip into memory 21:48:28 INFO - Content-Length response header: 54278207 21:48:28 INFO - Bytes received: 54278207 21:48:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 21:48:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.common.tests.zip 21:48:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:48:39 INFO - Fetch https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.common.tests.zip into memory 21:48:41 INFO - Content-Length response header: 54278207 21:48:41 INFO - Bytes received: 54278207 21:48:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.talos.tests.zip 21:48:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 21:48:53 INFO - Fetch https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.talos.tests.zip into memory 21:48:54 INFO - Content-Length response header: 14496464 21:48:54 INFO - Bytes received: 14496464 21:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:54 INFO - trying https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.tar.bz2 21:48:54 INFO - Downloading https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 21:48:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 21:48:58 INFO - Downloaded 62184165 bytes. 21:48:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.tar.bz2 21:48:58 INFO - mkdir: /builds/slave/test/properties 21:48:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:48:58 INFO - Writing to file /builds/slave/test/properties/build_url 21:48:58 INFO - Contents: 21:48:58 INFO - build_url:https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.tar.bz2 21:48:58 INFO - Running post-action listener: _resource_record_post_action 21:48:58 INFO - Running post-action listener: set_extra_try_arguments 21:48:58 INFO - [mozharness: 2017-08-03 04:48:58.196196Z] Finished download-and-extract step (success) 21:48:58 INFO - [mozharness: 2017-08-03 04:48:58.196427Z] Running populate-webroot step. 21:48:58 INFO - Running pre-action listener: _resource_record_pre_action 21:48:58 INFO - Running main action method: populate_webroot 21:48:58 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:48:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:48:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:48:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:48:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:48:58 INFO - 'tests': ['damp', 'tps']}, 21:48:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:48:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:48:58 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 21:48:58 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 21:48:58 INFO - 'other-e10s': {'tests': ['a11yr', 21:48:58 INFO - 'ts_paint', 21:48:58 INFO - 'tpaint', 21:48:58 INFO - 'sessionrestore', 21:48:58 INFO - 'sessionrestore_many_windows', 21:48:58 INFO - 'sessionrestore_no_auto_restore', 21:48:58 INFO - 'tabpaint']}, 21:48:58 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 21:48:58 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 21:48:58 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:48:58 INFO - 'talos_options': ['--mitmproxy', 21:48:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:48:58 INFO - '--firstNonBlankPaint'], 21:48:58 INFO - 'tests': ['quantum_pageload_google', 21:48:58 INFO - 'quantum_pageload_youtube', 21:48:58 INFO - 'quantum_pageload_amazon', 21:48:58 INFO - 'quantum_pageload_facebook']}, 21:48:58 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:48:58 INFO - 'talos_options': ['--stylo', 21:48:58 INFO - '--mitmproxy', 21:48:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:48:58 INFO - '--firstNonBlankPaint'], 21:48:58 INFO - 'tests': ['quantum_pageload_google', 21:48:58 INFO - 'quantum_pageload_youtube', 21:48:58 INFO - 'quantum_pageload_amazon', 21:48:58 INFO - 'quantum_pageload_facebook']}, 21:48:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:48:58 INFO - 'tsvgr_opacity', 21:48:58 INFO - 'tart', 21:48:58 INFO - 'tscrollx', 21:48:58 INFO - 'cart', 21:48:58 INFO - 'tsvg_static']}, 21:48:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:48:58 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:48:58 INFO - 'talos_options': ['--stylo', 21:48:58 INFO - '--stylo-threads=1', 21:48:58 INFO - '--mitmproxy', 21:48:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:48:58 INFO - '--firstNonBlankPaint'], 21:48:58 INFO - 'tests': ['tp6_google', 21:48:58 INFO - 'tp6_youtube', 21:48:58 INFO - 'tp6_amazon', 21:48:58 INFO - 'tp6_facebook']}, 21:48:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:48:58 INFO - 'talos_options': ['--xperf_path', 21:48:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:48:58 INFO - 'tests': ['tp5n']}}} 21:48:58 INFO - Running post-action listener: _resource_record_post_action 21:48:58 INFO - [mozharness: 2017-08-03 04:48:58.213154Z] Finished populate-webroot step (success) 21:48:58 INFO - [mozharness: 2017-08-03 04:48:58.213245Z] Running create-virtualenv step. 21:48:58 INFO - Running pre-action listener: _resource_record_pre_action 21:48:58 INFO - Running main action method: create_virtualenv 21:48:58 INFO - Creating virtualenv /builds/slave/test/build/venv 21:48:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:48:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:48:58 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 21:48:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:48:58 INFO - Using real prefix '/usr' 21:48:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:48:58 INFO - Installing distribute.............................................................................................................................................................................................done. 21:49:00 INFO - Installing pip.................done. 21:49:00 INFO - Return code: 0 21:49:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:49:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:49:01 INFO - Reading from file tmpfile_stdout 21:49:01 INFO - Output received: 21:49:01 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:49:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:49:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bdeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13bf240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:49:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:49:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:49:01 INFO - Using env: {'DISPLAY': ':0', 21:49:01 INFO - 'HOME': '/home/cltbld', 21:49:01 INFO - 'LANG': 'en_US.UTF-8', 21:49:01 INFO - 'LANGUAGE': 'en_US:en', 21:49:01 INFO - 'LOGNAME': 'cltbld', 21:49:01 INFO - 'MAIL': '/var/mail/cltbld', 21:49:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:01 INFO - 'MOZ_NO_REMOTE': '1', 21:49:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:01 INFO - 'NO_EM_RESTART': '1', 21:49:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:01 INFO - 'PWD': '/builds/slave/test', 21:49:01 INFO - 'SHELL': '/bin/bash', 21:49:01 INFO - 'SHLVL': '1', 21:49:01 INFO - 'TERM': 'linux', 21:49:01 INFO - 'TMOUT': '86400', 21:49:01 INFO - 'USER': 'cltbld', 21:49:01 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 21:49:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:01 INFO - '_': '/tools/buildbot/bin/python'} 21:49:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:49:01 INFO - Cleaning up... 21:49:01 INFO - Return code: 0 21:49:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:49:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bdeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13bf240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:49:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:49:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:49:01 INFO - Using env: {'DISPLAY': ':0', 21:49:01 INFO - 'HOME': '/home/cltbld', 21:49:01 INFO - 'LANG': 'en_US.UTF-8', 21:49:01 INFO - 'LANGUAGE': 'en_US:en', 21:49:01 INFO - 'LOGNAME': 'cltbld', 21:49:01 INFO - 'MAIL': '/var/mail/cltbld', 21:49:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:01 INFO - 'MOZ_NO_REMOTE': '1', 21:49:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:01 INFO - 'NO_EM_RESTART': '1', 21:49:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:01 INFO - 'PWD': '/builds/slave/test', 21:49:01 INFO - 'SHELL': '/bin/bash', 21:49:01 INFO - 'SHLVL': '1', 21:49:01 INFO - 'TERM': 'linux', 21:49:01 INFO - 'TMOUT': '86400', 21:49:01 INFO - 'USER': 'cltbld', 21:49:01 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 21:49:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:01 INFO - '_': '/tools/buildbot/bin/python'} 21:49:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:01 INFO - Downloading/unpacking psutil>=3.1.1 21:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:49:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:49:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:49:02 INFO - Installing collected packages: psutil 21:49:02 INFO - Running setup.py install for psutil 21:49:02 INFO - building 'psutil._psutil_linux' extension 21:49:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 21:49:03 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:49:04 INFO - building 'psutil._psutil_posix' extension 21:49:04 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 21:49:04 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 21:49:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:49:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:49:04 INFO - Successfully installed psutil 21:49:04 INFO - Cleaning up... 21:49:04 INFO - Return code: 0 21:49:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:49:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bdeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13bf240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:49:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:49:04 INFO - Using env: {'DISPLAY': ':0', 21:49:04 INFO - 'HOME': '/home/cltbld', 21:49:04 INFO - 'LANG': 'en_US.UTF-8', 21:49:04 INFO - 'LANGUAGE': 'en_US:en', 21:49:04 INFO - 'LOGNAME': 'cltbld', 21:49:04 INFO - 'MAIL': '/var/mail/cltbld', 21:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:04 INFO - 'MOZ_NO_REMOTE': '1', 21:49:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:04 INFO - 'NO_EM_RESTART': '1', 21:49:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:04 INFO - 'PWD': '/builds/slave/test', 21:49:04 INFO - 'SHELL': '/bin/bash', 21:49:04 INFO - 'SHLVL': '1', 21:49:04 INFO - 'TERM': 'linux', 21:49:04 INFO - 'TMOUT': '86400', 21:49:04 INFO - 'USER': 'cltbld', 21:49:04 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 21:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:04 INFO - '_': '/tools/buildbot/bin/python'} 21:49:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:49:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:49:05 INFO - Installing collected packages: mozsystemmonitor 21:49:05 INFO - Running setup.py install for mozsystemmonitor 21:49:05 INFO - Successfully installed mozsystemmonitor 21:49:05 INFO - Cleaning up... 21:49:05 INFO - Return code: 0 21:49:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bdeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13bf240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:49:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:49:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:49:05 INFO - Using env: {'DISPLAY': ':0', 21:49:05 INFO - 'HOME': '/home/cltbld', 21:49:05 INFO - 'LANG': 'en_US.UTF-8', 21:49:05 INFO - 'LANGUAGE': 'en_US:en', 21:49:05 INFO - 'LOGNAME': 'cltbld', 21:49:05 INFO - 'MAIL': '/var/mail/cltbld', 21:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:05 INFO - 'MOZ_NO_REMOTE': '1', 21:49:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:05 INFO - 'NO_EM_RESTART': '1', 21:49:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:05 INFO - 'PWD': '/builds/slave/test', 21:49:05 INFO - 'SHELL': '/bin/bash', 21:49:05 INFO - 'SHLVL': '1', 21:49:05 INFO - 'TERM': 'linux', 21:49:05 INFO - 'TMOUT': '86400', 21:49:05 INFO - 'USER': 'cltbld', 21:49:05 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 21:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:05 INFO - '_': '/tools/buildbot/bin/python'} 21:49:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:06 INFO - Downloading/unpacking jsonschema==2.5.1 21:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:49:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:07 INFO - Downloading functools32-3.2.3-2.tar.gz 21:49:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:49:07 INFO - warning: no files found matching '*.txt' 21:49:07 INFO - no previously-included directories found matching 'build' 21:49:07 INFO - no previously-included directories found matching 'dist' 21:49:07 INFO - no previously-included directories found matching '.git*' 21:49:07 INFO - Installing collected packages: jsonschema, functools32 21:49:07 INFO - Running setup.py install for functools32 21:49:07 INFO - warning: no files found matching '*.txt' 21:49:07 INFO - no previously-included directories found matching 'build' 21:49:07 INFO - no previously-included directories found matching 'dist' 21:49:07 INFO - no previously-included directories found matching '.git*' 21:49:07 INFO - Successfully installed jsonschema functools32 21:49:07 INFO - Cleaning up... 21:49:07 INFO - Return code: 0 21:49:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bdeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13bf240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:49:07 INFO - Using env: {'DISPLAY': ':0', 21:49:07 INFO - 'HOME': '/home/cltbld', 21:49:07 INFO - 'LANG': 'en_US.UTF-8', 21:49:07 INFO - 'LANGUAGE': 'en_US:en', 21:49:07 INFO - 'LOGNAME': 'cltbld', 21:49:07 INFO - 'MAIL': '/var/mail/cltbld', 21:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:07 INFO - 'MOZ_NO_REMOTE': '1', 21:49:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:07 INFO - 'NO_EM_RESTART': '1', 21:49:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:07 INFO - 'PWD': '/builds/slave/test', 21:49:07 INFO - 'SHELL': '/bin/bash', 21:49:07 INFO - 'SHLVL': '1', 21:49:07 INFO - 'TERM': 'linux', 21:49:07 INFO - 'TMOUT': '86400', 21:49:07 INFO - 'USER': 'cltbld', 21:49:07 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 21:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:07 INFO - '_': '/tools/buildbot/bin/python'} 21:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:49:07 INFO - Cleaning up... 21:49:07 INFO - Return code: 0 21:49:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bdeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13bf240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:49:07 INFO - Using env: {'DISPLAY': ':0', 21:49:07 INFO - 'HOME': '/home/cltbld', 21:49:07 INFO - 'LANG': 'en_US.UTF-8', 21:49:07 INFO - 'LANGUAGE': 'en_US:en', 21:49:07 INFO - 'LOGNAME': 'cltbld', 21:49:07 INFO - 'MAIL': '/var/mail/cltbld', 21:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:07 INFO - 'MOZ_NO_REMOTE': '1', 21:49:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:07 INFO - 'NO_EM_RESTART': '1', 21:49:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:07 INFO - 'PWD': '/builds/slave/test', 21:49:07 INFO - 'SHELL': '/bin/bash', 21:49:07 INFO - 'SHLVL': '1', 21:49:07 INFO - 'TERM': 'linux', 21:49:07 INFO - 'TMOUT': '86400', 21:49:07 INFO - 'USER': 'cltbld', 21:49:07 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 21:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:07 INFO - '_': '/tools/buildbot/bin/python'} 21:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:07 INFO - Downloading/unpacking blobuploader==1.2.4 21:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:09 INFO - Downloading blobuploader-1.2.4.tar.gz 21:49:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:49:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:49:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:09 INFO - Downloading docopt-0.6.1.tar.gz 21:49:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:49:09 INFO - Installing collected packages: blobuploader, requests, docopt 21:49:09 INFO - Running setup.py install for blobuploader 21:49:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:49:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:49:09 INFO - Running setup.py install for requests 21:49:10 INFO - Running setup.py install for docopt 21:49:10 INFO - Successfully installed blobuploader requests docopt 21:49:10 INFO - Cleaning up... 21:49:10 INFO - Return code: 0 21:49:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bdeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13bf240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:49:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:49:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:49:10 INFO - Using env: {'DISPLAY': ':0', 21:49:10 INFO - 'HOME': '/home/cltbld', 21:49:10 INFO - 'LANG': 'en_US.UTF-8', 21:49:10 INFO - 'LANGUAGE': 'en_US:en', 21:49:10 INFO - 'LOGNAME': 'cltbld', 21:49:10 INFO - 'MAIL': '/var/mail/cltbld', 21:49:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:10 INFO - 'MOZ_NO_REMOTE': '1', 21:49:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:10 INFO - 'NO_EM_RESTART': '1', 21:49:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:10 INFO - 'PWD': '/builds/slave/test', 21:49:10 INFO - 'SHELL': '/bin/bash', 21:49:10 INFO - 'SHLVL': '1', 21:49:10 INFO - 'TERM': 'linux', 21:49:10 INFO - 'TMOUT': '86400', 21:49:10 INFO - 'USER': 'cltbld', 21:49:10 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 21:49:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:10 INFO - '_': '/tools/buildbot/bin/python'} 21:49:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:49:10 INFO - Running setup.py (path:/tmp/pip-t8N_xO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:49:10 INFO - Running setup.py (path:/tmp/pip-vKOpsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:49:10 INFO - Running setup.py (path:/tmp/pip-IKwQxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:49:10 INFO - Running setup.py (path:/tmp/pip-Lk7kJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:49:10 INFO - Running setup.py (path:/tmp/pip-3evNNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:49:10 INFO - Running setup.py (path:/tmp/pip-fzV6y4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:49:10 INFO - Running setup.py (path:/tmp/pip-PeMwNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:49:10 INFO - Running setup.py (path:/tmp/pip-hWqTpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:49:11 INFO - Running setup.py (path:/tmp/pip-nDaNqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:49:11 INFO - Running setup.py (path:/tmp/pip-iCiLWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:49:11 INFO - Running setup.py (path:/tmp/pip-q5x_Pg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:49:11 INFO - Running setup.py (path:/tmp/pip-WYxH8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:49:11 INFO - Running setup.py (path:/tmp/pip-8j9jf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:49:11 INFO - Running setup.py (path:/tmp/pip-7Q2xrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:49:11 INFO - Running setup.py (path:/tmp/pip-1JtWiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:49:11 INFO - Running setup.py (path:/tmp/pip-o4Vk0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:49:11 INFO - Running setup.py (path:/tmp/pip-t1n5Nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:49:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:49:11 INFO - Running setup.py install for manifestparser 21:49:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:49:11 INFO - Running setup.py install for mozcrash 21:49:11 INFO - Running setup.py install for mozdebug 21:49:11 INFO - Running setup.py install for mozdevice 21:49:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:49:12 INFO - Running setup.py install for mozfile 21:49:12 INFO - Running setup.py install for mozhttpd 21:49:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:49:12 INFO - Running setup.py install for mozinfo 21:49:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:49:12 INFO - Running setup.py install for mozInstall 21:49:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:49:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:49:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:49:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:49:12 INFO - Running setup.py install for mozleak 21:49:12 INFO - Running setup.py install for mozlog 21:49:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:49:12 INFO - Running setup.py install for moznetwork 21:49:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:49:12 INFO - Running setup.py install for mozprocess 21:49:12 INFO - Running setup.py install for mozprofile 21:49:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:49:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:49:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:49:13 INFO - Running setup.py install for mozrunner 21:49:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:49:13 INFO - Running setup.py install for mozscreenshot 21:49:13 INFO - Running setup.py install for moztest 21:49:13 INFO - Running setup.py install for mozversion 21:49:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:49:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:49:13 INFO - Cleaning up... 21:49:13 INFO - Return code: 0 21:49:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bdeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13bf240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:49:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:49:13 INFO - Using env: {'DISPLAY': ':0', 21:49:13 INFO - 'HOME': '/home/cltbld', 21:49:13 INFO - 'LANG': 'en_US.UTF-8', 21:49:13 INFO - 'LANGUAGE': 'en_US:en', 21:49:13 INFO - 'LOGNAME': 'cltbld', 21:49:13 INFO - 'MAIL': '/var/mail/cltbld', 21:49:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:13 INFO - 'MOZ_NO_REMOTE': '1', 21:49:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:13 INFO - 'NO_EM_RESTART': '1', 21:49:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:13 INFO - 'PWD': '/builds/slave/test', 21:49:13 INFO - 'SHELL': '/bin/bash', 21:49:13 INFO - 'SHLVL': '1', 21:49:13 INFO - 'TERM': 'linux', 21:49:13 INFO - 'TMOUT': '86400', 21:49:13 INFO - 'USER': 'cltbld', 21:49:13 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 21:49:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:13 INFO - '_': '/tools/buildbot/bin/python'} 21:49:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:49:13 INFO - Running setup.py (path:/tmp/pip-VJXN34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:49:13 INFO - Running setup.py (path:/tmp/pip-iV3ux0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:49:13 INFO - Running setup.py (path:/tmp/pip-_QjZyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:49:13 INFO - Running setup.py (path:/tmp/pip-aLfwvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:49:13 INFO - Running setup.py (path:/tmp/pip-yNswGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:49:14 INFO - Running setup.py (path:/tmp/pip-Z84YBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:49:14 INFO - Running setup.py (path:/tmp/pip-pW9mw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:49:14 INFO - Running setup.py (path:/tmp/pip-nQwU07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:49:14 INFO - Running setup.py (path:/tmp/pip-A2LzmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:49:14 INFO - Running setup.py (path:/tmp/pip-uL2hIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:49:14 INFO - Running setup.py (path:/tmp/pip-n8TGD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 21:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:49:14 INFO - Running setup.py (path:/tmp/pip-qZ8tRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:49:14 INFO - Running setup.py (path:/tmp/pip-HuGGNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:49:14 INFO - Running setup.py (path:/tmp/pip-naZFE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:49:14 INFO - Running setup.py (path:/tmp/pip-MNCzNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:49:14 INFO - Running setup.py (path:/tmp/pip-r4IWso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:49:14 INFO - Running setup.py (path:/tmp/pip-6Uyz28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:49:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:15 INFO - Downloading blessings-1.6.tar.gz 21:49:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:49:16 INFO - Installing collected packages: blessings 21:49:16 INFO - Running setup.py install for blessings 21:49:16 INFO - Successfully installed blessings 21:49:16 INFO - Cleaning up... 21:49:16 INFO - Return code: 0 21:49:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:49:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:49:16 INFO - Reading from file tmpfile_stdout 21:49:16 INFO - Current package versions: 21:49:16 INFO - argparse == 1.2.1 21:49:16 INFO - blessings == 1.6 21:49:16 INFO - blobuploader == 1.2.4 21:49:16 INFO - docopt == 0.6.1 21:49:16 INFO - functools32 == 3.2.3-2 21:49:16 INFO - jsonschema == 2.5.1 21:49:16 INFO - manifestparser == 1.1 21:49:16 INFO - mozInstall == 1.13 21:49:16 INFO - mozcrash == 1.0 21:49:16 INFO - mozdebug == 0.1 21:49:16 INFO - mozdevice == 0.51 21:49:16 INFO - mozfile == 1.2 21:49:16 INFO - mozhttpd == 0.7 21:49:16 INFO - mozinfo == 0.10 21:49:16 INFO - mozleak == 0.1 21:49:16 INFO - mozlog == 3.5 21:49:16 INFO - moznetwork == 0.27 21:49:16 INFO - mozprocess == 0.25 21:49:16 INFO - mozprofile == 0.28 21:49:16 INFO - mozrunner == 6.13 21:49:16 INFO - mozscreenshot == 0.1 21:49:16 INFO - mozsystemmonitor == 0.3 21:49:16 INFO - moztest == 0.8 21:49:16 INFO - mozversion == 1.4 21:49:16 INFO - psutil == 3.1.1 21:49:16 INFO - requests == 1.2.3 21:49:16 INFO - wsgiref == 0.1.2 21:49:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bdeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13bf240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:49:16 INFO - Using env: {'DISPLAY': ':0', 21:49:16 INFO - 'HOME': '/home/cltbld', 21:49:16 INFO - 'LANG': 'en_US.UTF-8', 21:49:16 INFO - 'LANGUAGE': 'en_US:en', 21:49:16 INFO - 'LOGNAME': 'cltbld', 21:49:16 INFO - 'MAIL': '/var/mail/cltbld', 21:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:16 INFO - 'MOZ_NO_REMOTE': '1', 21:49:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:16 INFO - 'NO_EM_RESTART': '1', 21:49:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:16 INFO - 'PWD': '/builds/slave/test', 21:49:16 INFO - 'SHELL': '/bin/bash', 21:49:16 INFO - 'SHLVL': '1', 21:49:16 INFO - 'TERM': 'linux', 21:49:16 INFO - 'TMOUT': '86400', 21:49:16 INFO - 'USER': 'cltbld', 21:49:16 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 21:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:16 INFO - '_': '/tools/buildbot/bin/python'} 21:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:49:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:49:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:49:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:49:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:49:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:49:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:49:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:49:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:49:16 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 21:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 21:49:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:49:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:49:17 INFO - Installing collected packages: simplejson 21:49:17 INFO - Running setup.py install for simplejson 21:49:17 INFO - building 'simplejson._speedups' extension 21:49:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 21:49:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 21:49:18 INFO - Successfully installed simplejson 21:49:18 INFO - Cleaning up... 21:49:18 INFO - Return code: 0 21:49:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bdeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13bf240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:49:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:49:18 INFO - Using env: {'DISPLAY': ':0', 21:49:18 INFO - 'HOME': '/home/cltbld', 21:49:18 INFO - 'LANG': 'en_US.UTF-8', 21:49:18 INFO - 'LANGUAGE': 'en_US:en', 21:49:18 INFO - 'LOGNAME': 'cltbld', 21:49:18 INFO - 'MAIL': '/var/mail/cltbld', 21:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:18 INFO - 'MOZ_NO_REMOTE': '1', 21:49:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:18 INFO - 'NO_EM_RESTART': '1', 21:49:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:18 INFO - 'PWD': '/builds/slave/test', 21:49:18 INFO - 'SHELL': '/bin/bash', 21:49:18 INFO - 'SHLVL': '1', 21:49:18 INFO - 'TERM': 'linux', 21:49:18 INFO - 'TMOUT': '86400', 21:49:18 INFO - 'USER': 'cltbld', 21:49:18 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 21:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:18 INFO - '_': '/tools/buildbot/bin/python'} 21:49:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:49:19 INFO - Cleaning up... 21:49:19 INFO - Return code: 0 21:49:19 INFO - Running post-action listener: _resource_record_post_action 21:49:19 INFO - Running post-action listener: _start_resource_monitoring 21:49:19 INFO - Starting resource monitoring. 21:49:19 INFO - [mozharness: 2017-08-03 04:49:19.190761Z] Finished create-virtualenv step (success) 21:49:19 INFO - [mozharness: 2017-08-03 04:49:19.191749Z] Running install step. 21:49:19 INFO - Running pre-action listener: _resource_record_pre_action 21:49:19 INFO - Running main action method: install 21:49:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:49:19 INFO - Reading from file tmpfile_stdout 21:49:19 INFO - Detecting whether we're running mozinstall >=1.0... 21:49:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:49:19 INFO - Reading from file tmpfile_stdout 21:49:19 INFO - Output received: 21:49:19 INFO - Usage: mozinstall [options] installer 21:49:19 INFO - Options: 21:49:19 INFO - -h, --help show this help message and exit 21:49:19 INFO - -d DEST, --destination=DEST 21:49:19 INFO - Directory to install application into. [default: 21:49:19 INFO - "/builds/slave/test"] 21:49:19 INFO - --app=APP Application being installed. [default: firefox] 21:49:19 INFO - mkdir: /builds/slave/test/build/application 21:49:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 21:49:37 INFO - Reading from file tmpfile_stdout 21:49:37 INFO - Output received: 21:49:37 INFO - /builds/slave/test/build/application/firefox/firefox 21:49:37 INFO - Running post-action listener: _resource_record_post_action 21:49:37 INFO - [mozharness: 2017-08-03 04:49:37.535353Z] Finished install step (success) 21:49:37 INFO - [mozharness: 2017-08-03 04:49:37.535637Z] Running setup-mitmproxy step. 21:49:37 INFO - Running pre-action listener: _resource_record_pre_action 21:49:37 INFO - Running main action method: setup_mitmproxy 21:49:37 INFO - Skipping: mitmproxy is not required 21:49:37 INFO - Running post-action listener: _resource_record_post_action 21:49:37 INFO - [mozharness: 2017-08-03 04:49:37.536777Z] Finished setup-mitmproxy step (success) 21:49:37 INFO - [mozharness: 2017-08-03 04:49:37.536999Z] Running run-tests step. 21:49:37 INFO - Running pre-action listener: _resource_record_pre_action 21:49:37 INFO - Running pre-action listener: _set_gcov_prefix 21:49:37 INFO - Running main action method: run_tests 21:49:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:49:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:49:37 INFO - Python 2.7.3 21:49:37 INFO - Return code: 0 21:49:37 INFO - grabbing minidump binary from tooltool 21:49:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:37 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bdeb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13bf240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:49:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:49:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:49:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:49:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:49:37 INFO - Return code: 0 21:49:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:49:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:49:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:49:37 INFO - ENV: RUST_BACKTRACE is now 1 21:49:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:49:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:49:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:49:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-064 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:49:37 INFO - Using env: {'DISPLAY': ':0', 21:49:37 INFO - 'HOME': '/home/cltbld', 21:49:37 INFO - 'LANG': 'en_US.UTF-8', 21:49:37 INFO - 'LANGUAGE': 'en_US:en', 21:49:37 INFO - 'LOGNAME': 'cltbld', 21:49:37 INFO - 'MAIL': '/var/mail/cltbld', 21:49:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:49:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:49:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:37 INFO - 'MOZ_NO_REMOTE': '1', 21:49:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:49:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:49:37 INFO - 'NO_EM_RESTART': '1', 21:49:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:49:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:37 INFO - 'PWD': '/builds/slave/test', 21:49:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:49:37 INFO - 'RUST_BACKTRACE': '1', 21:49:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:49:37 INFO - 'SHELL': '/bin/bash', 21:49:37 INFO - 'SHLVL': '1', 21:49:37 INFO - 'TERM': 'linux', 21:49:37 INFO - 'TMOUT': '86400', 21:49:37 INFO - 'USER': 'cltbld', 21:49:37 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014', 21:49:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:37 INFO - '_': '/tools/buildbot/bin/python'} 21:49:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:49:38 INFO - mozversion application_buildid: 20170803031846 21:49:38 INFO - mozversion application_changeset: d942ef54fdf726840a698d2ddcaf989d2c00edbc 21:49:38 INFO - mozversion application_display_name: Nightly 21:49:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:49:38 INFO - mozversion application_name: Firefox 21:49:38 INFO - mozversion application_remotingname: firefox 21:49:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:49:38 INFO - mozversion application_vendor: Mozilla 21:49:38 INFO - mozversion application_version: 57.0a1 21:49:38 INFO - mozversion platform_buildid: 20170803031846 21:49:38 INFO - mozversion platform_changeset: d942ef54fdf726840a698d2ddcaf989d2c00edbc 21:49:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:49:38 INFO - mozversion platform_version: 57.0a1 21:49:38 INFO - using testdate: 1501735778 21:49:38 INFO - actual date: 1501735778 21:49:38 INFO - starting webserver on 'localhost:55041' 21:49:38 INFO - SUITE-START | Running 2 tests 21:49:38 INFO - TEST-START | dromaeo_css 21:49:38 INFO - Initialising browser for dromaeo_css test... 21:49:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55041/getInfo.html -profile /tmp/tmp79x1X_/profile 21:49:38 INFO - TEST-INFO | started process 3321 (/builds/slave/test/build/application/firefox/firefox http://localhost:55041/getInfo.html) 21:49:46 INFO - TEST-INFO | 3321: exit 0 21:49:46 INFO - Browser initialized. 21:49:46 INFO - Running cycle 1/1 for dromaeo_css test... 21:49:46 INFO - TEST-INFO | started process 3531 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp79x1X_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:49:47 INFO - PID 3531 | 21:49:47 INFO - PID 3531 | (/builds/slave/test/build/application/firefox/firefox:3579): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:49:47 INFO - PID 3531 | 21:49:58 INFO - PID 3531 | RSS: Main: 171749376 21:49:58 INFO - PID 3531 | 21:52:57 INFO - PID 3531 | Cycle 1(1): loaded http://localhost:55041/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55041/tests/dromaeo/cssquery-ext.html) 21:52:59 INFO - PID 3531 | RSS: Main: 173473792 21:52:59 INFO - PID 3531 | 21:56:00 INFO - PID 3531 | Cycle 1(1): loaded http://localhost:55041/tests/dromaeo/cssquery-ext.html (next: http://localhost:55041/tests/dromaeo/cssquery-jquery.html) 21:56:01 INFO - PID 3531 | RSS: Main: 171458560 21:56:01 INFO - PID 3531 | 21:59:03 INFO - PID 3531 | Cycle 1(1): loaded http://localhost:55041/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55041/tests/dromaeo/cssquery-mootools.html) 21:59:03 INFO - PID 3531 | RSS: Main: 173314048 21:59:03 INFO - PID 3531 | 22:02:05 INFO - PID 3531 | Cycle 1(1): loaded http://localhost:55041/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55041/tests/dromaeo/cssquery-prototype.html) 22:02:06 INFO - PID 3531 | RSS: Main: 171642880 22:02:06 INFO - PID 3531 | 22:05:09 INFO - PID 3531 | Cycle 1(1): loaded http://localhost:55041/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55041/tests/dromaeo/cssquery-yui.html) 22:05:10 INFO - PID 3531 | RSS: Main: 175644672 22:05:10 INFO - PID 3531 | 22:08:11 INFO - PID 3531 | Cycle 1(1): loaded http://localhost:55041/tests/dromaeo/cssquery-yui.html (next: http://localhost:55041/tests/dromaeo/cssquery-dojo.html) 22:08:12 INFO - PID 3531 | RSS: Main: 175521792 22:08:12 INFO - PID 3531 | 22:08:12 INFO - PID 3531 | __start_tp_report 22:08:12 INFO - PID 3531 | _x_x_mozilla_page_load 22:08:12 INFO - PID 3531 | _x_x_mozilla_page_load_details 22:08:12 INFO - PID 3531 | |i|pagename|runs| 22:08:12 INFO - PID 3531 | |0;dojo.html;3051.0999255219695;3138.2781960149164;3144.025352140835;3155.053483954813;3194.1056504178823;6064.029755239163;6123.724432400541;6131.570790044704;6158.876822463548;6247.812565623027;6175.258968923724;6232.189815324013;6274.811755647326;6280.717367718452;6281;5669.064604340289;5677.6309539879985;5744.4829965303115;5774.6535207887455;5799.130130480441;5587.552995760349;5642.181883626871;5675.34733505648;5720.170575266605;5721.370649228582;5592.5805564582815;5615.438456154393;5634.774609015634;5674.971625141869;5680.176374425706;5583.637063590853;5658.264425624642;5674.205611214434;5704.543636509069;5709.8287051388525;5504.614676972614;5509.090999984997;5531.612787104904;5559.110542313251;5589.35722391922;5972.104184372335;6026.789062382795;6036.245469316335;6041.184440100574;6050.394960503959;6463.93536064638;6471.935280647181;6482.708278127496;6492.8312903677315;6534.836629084263;6463.159789227418;6533.7713180038945;6534.281229064799;6539.051837483538;6545.705443255065;6057.515398768132;6069.848253793691;6071.241094863142;6102.572819902609;6109.541784366234;6841.247462779091;6859.622720750307;6863;6933.965330173418;6939.757108501179;6406.583572067801;6478.514111441661;6508.153939987866;6536.738530458823;6591.373819487141;6644.66776661165;6652.83367915806;6676.499262555328;6729.3270672933295;6730.7307707692125;6419.390157934989;6484.221893372825;6494.350564943468;6555.377239162367;6620.86758264832;6608.843452395811;6672.065910772496;6674.499412544079;6689.063531105649;6689.1972963244725;5305.549028332558;5366.7048312343195;5371.4897084776885;5399.055165346049;5401.405845357008;4554.385158003627;4609.516000819933;4612.3542704021465;4620.722756634613;4636.4899861015265;5781.913271300934;5788.131780232999;5873;5874.823755287262;5880.411958804171;6557.377049180321;6580.045893345438;6631.900521492182;6656;6752.797416077526;5750.798722044792;5752.597318187687;5771.94228057714;5776.1624564437925;5783.971080144656;5758.222639943554;5769.59612827098;5770.538356931519;5775.595708300462;5864.67744274069;6386.329435409308;6403.231612206565;6434.453071488976;6457.644829534421;6495.8700825983215;6639.601623902581;6650.900236496554;6723.0923825283935;6738.6967586459705;6741.662916854236;6242.469390101892;6386.808395748135;6404.423601875667;6476.028595710681;6501.934980650133;6509.837254068686;6521.836954076186;6554.016897465418;6554.344565543408;6569.277379488348;6701.564398314094;6709.664516774044;6711.865762684622;6712.228093769279;6721.966390168018;6491.253505846699;6523.478121750154;6524.021396790519;6529.804105876831;6530.869382612416;6383.904241436289;6426.903596445964;6433.806985790434;6480.837979050561;6537.313582074004;6371.362863713592;6380.712867921047;6383.1382763326355;6384.808455746335;6388.968055159695;6436.742530298923;6458.677066146768;6487.183940845178;6518.771842985473;6560.606363618198;6290.370962903673;6312.337204593451;6347.174867267226;6405.839854003687;6411.551191416556 22:08:12 INFO - PID 3531 | |1;ext.html;815.2581151152449;841.5750046226655;874.8206617643383;902.8058967322027;903.421810041573;40014.79992600036;40918.795406023004;40923.18153636933;41055.79472102639;41083.58916410835;37007.814960925156;37061.44407833885;37319.253614927686;37320;37399.25201495968;6694.665266736668;6755.054292399068;6802.38778509934;6841.726330946756;6855.348741869514;6674.599524028549;6785.016172654962;6785.287544807798;6788.913773796194;6790.185177778673;7287.526310789808;7443.665035073408;7453.627318634074;7456.739014134507;7473.290037446434;7317.817054573625;7353.7058517659225;7418.2581741825925;7429.294217049344;7498.287662672037;7340.376068034224;7420.888686669704;7423.220561841037;7428.071491063617;7430.851382972311;38314;38674;38685;38693.80653096745;38852.41721374181;3520.612732599412;3542.6634469725336;3544.929101417957;3557.092941299952;3565.1978304881195;31020;31301.686983130105;31318.843405783053;31333;31411.84294078515;4821.710697358169;4836.879078023042;4849.442314133849;4861.902761944777;4880.414350277953;182706;189337;191334;192753;195864;48700.5129948703;49110.75444622754;49270.75364623154;49273.753631231615;49285;51102.23346649803;51129.23306150411;51594.22608660873;51648.483515164364;51687.74156129195;15518;15663.37346506127;15677.451289204822;15705.764413533807;15711.057336559843;12796.936015319863;12847.100702950704;12847.907927826052;12878;12954.80567791484;14571.781423278659;14619.122852628876;14719.779203311959;14753;14921.403143874124;5639.295088113986;5674.319081710221;5719.599628025998;5836.824895253149;5868.765249390062;975.492743773234;995.4127065031521;1007.9445630490246;1008.369768894441;1008.5814387029448;22127.44681382978;22489.887550562144;22949.311520654406;29471;29567;1094.5512339940585;1099.0438318662655;1104.276698762312;1107.2304748199924;1107.5625128074525;12236.388180590935;12776.233425994471;12811.423485943154;12813.743725125445;12842.614721558368;229919;232303;233002;233477;233616;52244.47755522472;52287.47712522902;52394;52442.73778631083;52644.210336844975;1424.8005279260913;1454.2510607037384;1462.8464011278875;1462.8537146285287;1464.7656374979952;22847.200347987742;22857.428564286027;22889.542209156058;22919.770802291765;23196;38207.23585528218;38311.61688383081;38350.80824595859;38355.616443835206;38566.22867542689;208280;208693;208849;208927;210059;34554.82722586371;36523;38377;38549.6145038546;38605.806970964964;46247.075058499315;47616.7619161902;47823;48030;48186.27720584124;33144.83427582846;33431.66568334286;33456.33087338288;33898.322033559685;34000.489992650626;33532;33726.66273337235;34217.144571385914;34237.657623423445;34531.827340863136;37432.625673742914;37613.435798462495;37678;37685.246295074496;38138.61861381351;32385.838070809496;32618.51072233871;32834;32981;33022.33955320928;23671.763282367643;24411.901464434497;24573.26280211525;24780.628290576016;25041.74958250394 22:08:12 INFO - PID 3531 | |2;jquery.html;576.8154190658988;577.3937365765945;583.9708014599274;597.1013624495134;599.439524045018;21748.34754957352;22267.331980040584;22351.66472502914;22393.440163995907;22418.88790556049;26657.333566660844;27883.302917427074;27886.721132788665;27888.721112788866;27897;6825.897611535831;6952.826179345531;6956.547824391424;6959.060526828889;6971.895421568681;6384.840378990539;6676.899846502306;6707.295733947938;6725.192976842761;6740.696668649923;6695.631740254284;6700.329967003309;6707.698153583076;6713.395794378505;6717;6642.402183803457;6680.298568650294;6698.799036028927;6701.396874281338;6707.966460167717;6565.737370505174;6583.440407565363;6593.472522198213;6599.274079851212;6615.17310336208;30508;31174;32667.509987350208;32731.836340818383;33049;1975.0519750519688;1999.3202311214256;2001.7497812773404;2009.8291645210174;2015.5666531695608;19682.50793730154;20434.284800031928;20617.690734638993;20680.79319206804;20717.0677319521;2389.6415537669313;2439.5730747119183;2530.683664541932;2543.9872800636062;2546.363409147713;146822;148277;150487.2378190554;152665;153088.2345588276;19808.50478738014;21626.810525420937;21892.45268868291;21908;22032.44918877041;23370;24157.15449959243;45062.54937450649;45165.54834451614;45714;9993.050660187417;10129.746756331004;10200.897991020143;10265.79285453631;10409.219308551737;9170.816583668422;9237.676681316121;9303.58133883977;9316.44101353921;9356.345055846026;9321.813563728687;9335.719928402159;9470.337076404723;9474.857877131848;9685.661001865043;2624.13403576823;2660.9733902661114;2707.5261829179813;2718.5106680797253;2774.389634280455;930.3766476460787;933.2580598424133;939.2248650051408;951.785848184153;953.5661274120258;19463.80536194648;19796.307129250406;19948.102335394942;19959.900200498905;20421.387358379274;976.3751199232497;987.8172538080335;988.0712130597216;990.6730778842965;1036.186593524095;10690.412027338416;10804.405757683402;10971.561137554569;11055.889441105646;11060.889391105986;523296;523537;526627;537732;545420;114751;117065.4146729278;118834.4058279703;119061.40469297598;120238;1275.0819410024767;1282.0192552697195;1291.8297260602278;1294.9676258093623;1297.6690943809458;17301.480955571355;17345.65308693794;17424.303027878745;17703.557411064827;17834.643307134043;49626.50373496363;50332.49667503424;50628.74685626695;51015;52029;68367.65816170887;68723;68858.3114168872;68895.65552172408;69151.65424172847;14390.06464579799;14708.338124784197;14871.330790114254;14889.329980150702;14905.701885962437;20763.27328543554;21995.560088798455;23461.648075279227;23560.88219558891;23567.764322356557;18681.81318186855;18988.525286867938;19388.903055484632;19588.90205548963;19729.901350493157;16389.34442622282;17784.55538611545;18011.459656210336;18176.90911545434;18188.636227275645;19568.315108971117;19780.208791648747;20027.49931251672;20148.09333580022;20292.391228263175;14764.704705905608;14976.550703478913;15012.849871501581;15066;15112.622184445478;18526.258949641862;18675.813241867952;18766.061696915374;18811.153498092892;18957.526061848013 22:08:12 INFO - PID 3531 | |3;mootools.html;1377.6073818961597;1404.8033275341447;1421.4456362018814;1425.4440768100437;1435.885129189665;13331.666708332297;13779.793303100461;13811.240381778998;13908.721825563483;14351.569452916403;14352.928235358835;14624.488142915001;14639.487617933348;14652.706945861102;14655.120692758415;2643.590243512259;2656.428867793424;2667.213172114231;2670.7996900232465;2673.31830383252;2543.872806359684;2702.7837772978214;2760.9447811043765;2764.060219525371;2768.529350010493;2678.035907073452;2687.261003224119;2732.2622891819196;2734.576140698185;2735.179446166142;2586.314626623946;2619.724928882478;2621.606758986148;2642.2205449392477;2678.5848193530032;2561.2316305108393;2581.8579978101197;2586.456844062749;2589.741025897414;2590.2617753940226;13628.659283517893;13924.443022279096;13926.373313200931;13933.094348867291;13934.30328483582;7350.96324518374;7571.053618297713;7576.886346704804;7632.465727399084;7636.31273185416;4892.9755351223375;5064.518870707277;5081.74591270435;5185.662931909452;5210.947890521084;1733.1767410479922;1751.5883767314672;1755.2101554300489;1804.3955274982895;1812.7552780374613;22328.106875724952;22566.887165564232;22599.20902768395;22638.20766273189;22669.2065777697;6291.653959032205;6355.110284560165;6407.647579383085;6457.806265812033;6598.868022639616;15497.535073947574;15555.688886222439;15577.610559735871;15603.297851596706;15619.219039048003;7374.00450939127;7455.962720186473;7474.588897610575;7474.887876681854;7474.887876681854;6807.285235050347;6826.317368263233;6945.617991010442;6999.300069993062;7022.192447868459;5026.723530205801;5068.721220332844;5084.923726144111;5097.770600322994;5143.228515722672;1508.3287936868207;1539.4611885839865;1543.5060780550116;1545.5054382597687;1555.8366371530826;794.8251384695474;806.2139414071233;807.4226927746572;820.0856045509214;824.3528829868644;6569.704363303663;6579.374959378852;6642.070110184482;6699.941544134147;6742.359475849787;670.9429698475586;684;685.2907241005635;687.2474640268897;692.8129405060605;4400.4939431965095;4417.514073451917;4508.301213311942;4517.480489743656;4573.5426457354;18689.626207475776;19281.710774338397;19601.31395401154;19661.213551458066;19663.901680491792;18268.177931993094;19032.619347613247;19286.61426771485;19307.22771089169;19358.516037099183;9863.457509836884;10147.289689721796;10421.5831366746;10482.528286226985;10482.63310784119;7354.485186036926;7360.227176146642;7372;7618.771436856903;7635.121960974559;16494.67010659804;16680.24938877729;16826.242819072926;16832.915835421234;17045.48863534096;17276;17407;17409.56476088108;17471;17555.122243887497;10899.400532970763;11143.33140011602;11201.43992800373;11227;11251.212415130862;12821.487140514275;13051.086423950612;13063.150895191782;13066.281354525212;13070.869291307346;10655.360678359324;10835.187360947803;10933.890661093605;10989.560417583527;11000.834987475355;8096.230858068474;8116.228958248957;8241.670333186605;8273.089960104264;8305.712614544513;9359.812803744024;9544.809103818023;9555.23558115363;9556.90443095588;9564.330496865152;6041.969790151021;6077.4226448487325;6147.139400483847;6169.259688837368;6210.347913469202;6505.447037001719;6528.314526974789;6588.209414870246;6656.167979002625;6756.155480564929 22:08:12 INFO - PID 3531 | |4;prototype.html;498.7705655398514;501.1180322632167;527.3539913605831;528.0442399257345;531.6066111077802;6278.497720182386;6287.793225455017;6289.622622642644;6367.904481432776;6380.872382552352;5965.2245208122995;6084.5132389408855;6111.419415155564;6175.7529698811995;6192.969035154829;4951.9009619807675;4961.355023846895;4974.97512512437;4984.950150498503;5009.699418034911;4927.063857867011;4954.479779623141;4962.900741985158;4976.87557811054;5007.223880298559;4799.6400269979895;4868.148074087038;4899.730514821684;4909.705417674951;4917.803287868481;4838;4873.634477414173;4882.218844984804;4898.755062246909;4933.975330123344;4409.294512877957;4425.712328698656;4454.171601033368;4458.420405347317;4476.574725401082;5890.145928840337;5995.400459954013;6017.969910150465;6070.57505974582;6070.605410648293;5573.219749235111;5587.608867379285;5614.971925140389;5678.949394362056;5682.829515114553;6590.703418346186;6632.734690612369;6665.9666701666665;6668.566543174727;6676.799696009128;4733.668643194977;4813.879653008667;4824.638152138568;4842.782074806607;4849.830255941025;6919.550229235134;6943.340382663639;6966.79099627012;6976;7023;4856.3201151838775;4915.3855768029;4930.211166213388;4974.37820272466;4984.451710311862;4914.238293064581;4920.75396230187;4934.9753251233515;4939.703617782944;4944.159492886146;5586.486081263448;5639.182318563868;5639.351474580476;5656.632318899341;5657.15142728594;6622.503312251601;6673.599584024974;6678.265390807104;6729.8654026919185;6776.15298087739;1622.5862405086627;1720.1055451165323;1726.8359505846925;1729.939452119195;1739.6520695860625;1126.6563698072002;1150.263831148066;1186.6440067979572;1189.2567145534042;1203.3080978437433;5749.798757043567;5757.193992841006;5771.365149833515;5780.1907732917425;5798.159266906275;6584.6378449184795;6609.702563384659;6672.466202703772;6733.629650369178;6736.932630673728;5749.818944634535;5793.594448388572;5803.187553742479;5810.709464526757;5846.070796637099;3546.3971124908826;3552.6802587767224;3578.2127931854952;3620.4388319810473;3633.091727068233;6524.347565243438;6544.869102617921;6557.245916719543;6562.639054852029;6567.474602031826;6831.590104593641;6898.379145876896;6904;6957.965210173917;6981.441484681213;6355.396237357536;6490.448311893448;6492.31830657793;6502.284748677642;6513.772017979348;6573.014047892854;6649.867002660017;6672.532922695559;6724.260331363644;6781.6270105144695;6542.191489255299;6552.6723663815665;6622.933770662232;6642.682256533292;6666.733320669057;6397.456216221487;6491.642959637265;6501.73993040292;6502.154719886385;6516.934830651633;4925.630577706758;4934.407871055496;4935.925961110514;4952.10862044821;4955.4301255356095;6591.802245932629;6657.567258128206;6696.464282857457;6768.830779230559;6792.694328755119;6530.281669016499;6535.150430444012;6539.95360742298;6558.311377305315;6559.147310849559;6451.54841160356;6483.740650373932;6483.740650373932;6491.123698300669;6537.411632953057;5995.850103747267;6412.2625898022325;6421.165248517849;6487.805365839033;6545.14913061299;6631.469482441489;6638.634875081917;6640.701168447335;6663.233728121134;6697;6445.935540644534;6453.612783233021;6458.418742313214;6460.12788273577;6467.059882035276 22:08:12 INFO - PID 3531 | |5;yui.html;866.1598249697795;884.9070847561006;889.3418870036172;897.0670502677216;903.1781079217911;17036.31854725811;18102.185401656923;18108.909455452736;18125.81874181256;22166.55666886661;19671.803281967175;20955.68566471504;21076.051577679;21098.57802843946;21120.788792112075;1029.135526158027;1052.5737076484036;1053.4206186597341;1054.1672079057573;1054.8470471781586;1006.6677996261217;1050.6270274052677;1053.4943227250924;1053.5680371047872;1057.2440704895998;1117.4692021289864;1117.6423544465774;1117.8658560972694;1118.356944756764;1119.28925132541;1103.47584897174;1103.6688993301977;1107.2194103157256;1108.141190577305;1109.8834622364657;802.3420794948145;804.5052292839862;805.3557154276556;806.1857523900844;809.9473534220257;19078.713819292723;19099;19305.51736206592;19386.70919936202;19767.90116049425;18526.166322515386;18576;18703.251869925185;19120.521986950298;19142.904285478482;15747.92126039374;16021.519354419386;16110.83889161105;16193.10937898415;16212.351505939749;3548.4854696068924;3559.1458050068054;3596.3706351388664;3599.1721903961975;3602.873899413508;120748.39625801815;124668;124925;125403.37298313541;125432.37283813523;14635;14821.481248156262;14982.325795339018;15034.398624055135;15042.849571504365;27647.723522764918;28857.134285971453;28896.855515722706;28925.132246032652;29163.854180729384;8390.958045209734;8548.700795472128;8585.227329540372;8658.134186581417;8661.393702440895;3883.941740873889;3983.2630963271886;3996.8800935971385;4020.7587544746825;4021.8994525137105;4978.850634480971;5274.3143391359645;5387.111126664135;5393.83818485446;5400.297961265011;912.3066469483108;928.2017464980112;950.2872845365976;950.7718147644515;951.9428834269827;675.378651640492;689.0525527399825;694.9548279361825;695.8921367188094;703.7396163419568;11966.760664786656;12070.517179312728;12222.816657750142;12276.508939642494;12596.937015314865;693.3724978894109;701.6772284749072;704.3027402871121;706.9222385537587;722.9168645605718;5501;5540.141278101901;5549.750261238173;5582.609217354746;5637.661740295595;102584;105422.4728876366;106584;108503;110098.44950775195;94585.5270723642;95120;96512;97471.51264243634;97515;80817;82552.17447825565;83939;84358.5782071098;84816.15183848207;10987.61543345979;11082.556697732;11082.556697732323;11173.553057877594;11504.654860354203;36260.09349766277;36408.27183456263;36813.63186368102;37385.43921841229;38682;46875.765621171675;47075.82310442266;47416.52583474259;48514;48617.5138248613;15756.290966906747;16089.597760056093;16273.186340683154;16278.348866045226;16305.103219323051;17057.48827535176;17339.47981560555;17381.652366952843;17436.82563174403;17454.389096381565;18866.622667546846;19332.903335483796;19382.8061719381;19593.31423400174;19695.606087877877;17430.945564455775;19289.131989060246;19459;19566.608667826848;19731.506712332306;17658.02880841566;17858.642827143125;18456.169472373505;19097.331593394163;19243;13724.794128088286;13801.447942082204;14014.229217393142;14431.134131952116;14645.414183432544;14217.64455888611;14738.336774845373;14934.925325373304;14969.326380312694;14978.400863965318 22:08:12 INFO - PID 3531 | __end_tp_report 22:08:12 INFO - PID 3531 | __start_cc_report 22:08:12 INFO - PID 3531 | _x_x_mozilla_cycle_collect,365 22:08:12 INFO - PID 3531 | __end_cc_report 22:08:12 INFO - PID 3531 | __startTimestamp1501736892246__endTimestamp 22:08:12 INFO - PID 3531 | 22:08:12 INFO - PID 3531 | ------- Summary: start ------- 22:08:12 INFO - PID 3531 | Number of tests: 6 22:08:12 INFO - PID 3531 | 22:08:12 INFO - PID 3531 | [#0] dojo.html Cycles:165 Average:6095.55 Median:6383.90 stddev:718.19 (11.3%) stddev-sans-first:679.53 22:08:12 INFO - PID 3531 | Values: 3051.1 3138.3 3144.0 3155.1 3194.1 6064.0 6123.7 6131.6 6158.9 6247.8 6175.3 6232.2 6274.8 6280.7 6281.0 5669.1 5677.6 5744.5 5774.7 5799.1 5587.6 5642.2 5675.3 5720.2 5721.4 5592.6 5615.4 5634.8 5675.0 5680.2 5583.6 5658.3 5674.2 5704.5 5709.8 5504.6 5509.1 5531.6 5559.1 5589.4 5972.1 6026.8 6036.2 6041.2 6050.4 6463.9 6471.9 6482.7 6492.8 6534.8 6463.2 6533.8 6534.3 6539.1 6545.7 6057.5 6069.8 6071.2 6102.6 6109.5 6841.2 6859.6 6863.0 6934.0 6939.8 6406.6 6478.5 6508.2 6536.7 6591.4 6644.7 6652.8 6676.5 6729.3 6730.7 6419.4 6484.2 6494.4 6555.4 6620.9 6608.8 6672.1 6674.5 6689.1 6689.2 5305.5 5366.7 5371.5 5399.1 5401.4 4554.4 4609.5 4612.4 4620.7 4636.5 5781.9 5788.1 5873.0 5874.8 5880.4 6557.4 6580.0 6631.9 6656.0 6752.8 5750.8 5752.6 5771.9 5776.2 5784.0 5758.2 5769.6 5770.5 5775.6 5864.7 6386.3 6403.2 6434.5 6457.6 6495.9 6639.6 6650.9 6723.1 6738.7 6741.7 6242.5 6386.8 6404.4 6476.0 6501.9 6509.8 6521.8 6554.0 6554.3 6569.3 6701.6 6709.7 6711.9 6712.2 6722.0 6491.3 6523.5 6524.0 6529.8 6530.9 6383.9 6426.9 6433.8 6480.8 6537.3 6371.4 6380.7 6383.1 6384.8 6389.0 6436.7 6458.7 6487.2 6518.8 6560.6 6290.4 6312.3 6347.2 6405.8 6411.6 22:08:12 INFO - PID 3531 | 22:08:12 INFO - PID 3531 | [#1] ext.html Cycles:180 Average:38254.21 Median:37034.63 stddev:54781.45 (147.9%) stddev-sans-first:54863.00 22:08:12 INFO - PID 3531 | Values: 815.3 841.6 874.8 902.8 903.4 40014.8 40918.8 40923.2 41055.8 41083.6 37007.8 37061.4 37319.3 37320.0 37399.3 6694.7 6755.1 6802.4 6841.7 6855.3 6674.6 6785.0 6785.3 6788.9 6790.2 7287.5 7443.7 7453.6 7456.7 7473.3 7317.8 7353.7 7418.3 7429.3 7498.3 7340.4 7420.9 7423.2 7428.1 7430.9 38314.0 38674.0 38685.0 38693.8 38852.4 3520.6 3542.7 3544.9 3557.1 3565.2 31020.0 31301.7 31318.8 31333.0 31411.8 4821.7 4836.9 4849.4 4861.9 4880.4 182706.0 189337.0 191334.0 192753.0 195864.0 48700.5 49110.8 49270.8 49273.8 49285.0 51102.2 51129.2 51594.2 51648.5 51687.7 15518.0 15663.4 15677.5 15705.8 15711.1 12796.9 12847.1 12847.9 12878.0 12954.8 14571.8 14619.1 14719.8 14753.0 14921.4 5639.3 5674.3 5719.6 5836.8 5868.8 975.5 995.4 1007.9 1008.4 1008.6 22127.4 22489.9 22949.3 29471.0 29567.0 1094.6 1099.0 1104.3 1107.2 1107.6 12236.4 12776.2 12811.4 12813.7 12842.6 229919.0 232303.0 233002.0 233477.0 233616.0 52244.5 52287.5 52394.0 52442.7 52644.2 1424.8 1454.3 1462.8 1462.9 1464.8 22847.2 22857.4 22889.5 22919.8 23196.0 38207.2 38311.6 38350.8 38355.6 38566.2 208280.0 208693.0 208849.0 208927.0 210059.0 34554.8 36523.0 38377.0 38549.6 38605.8 46247.1 47616.8 47823.0 48030.0 48186.3 33144.8 33431.7 33456.3 33898.3 34000.5 33532.0 33726.7 34217.1 34237.7 34531.8 37432.6 37613.4 37678.0 37685.2 38138.6 32385.8 32618.5 32834.0 32981.0 33022.3 23671.8 24411.9 24573.3 24780.6 25041.7 22:08:12 INFO - PID 3531 | 22:08:12 INFO - PID 3531 | [#2] jquery.html Cycles:180 Average:36931.83 Median:12978.70 stddev:89296.11 (688.0%) stddev-sans-first:89504.89 22:08:12 INFO - PID 3531 | Values: 576.8 577.4 584.0 597.1 599.4 21748.3 22267.3 22351.7 22393.4 22418.9 26657.3 27883.3 27886.7 27888.7 27897.0 6825.9 6952.8 6956.5 6959.1 6971.9 6384.8 6676.9 6707.3 6725.2 6740.7 6695.6 6700.3 6707.7 6713.4 6717.0 6642.4 6680.3 6698.8 6701.4 6708.0 6565.7 6583.4 6593.5 6599.3 6615.2 30508.0 31174.0 32667.5 32731.8 33049.0 1975.1 1999.3 2001.7 2009.8 2015.6 19682.5 20434.3 20617.7 20680.8 20717.1 2389.6 2439.6 2530.7 2544.0 2546.4 146822.0 148277.0 150487.2 152665.0 153088.2 19808.5 21626.8 21892.5 21908.0 22032.4 23370.0 24157.2 45062.5 45165.5 45714.0 9993.1 10129.7 10200.9 10265.8 10409.2 9170.8 9237.7 9303.6 9316.4 9356.3 9321.8 9335.7 9470.3 9474.9 9685.7 2624.1 2661.0 2707.5 2718.5 2774.4 930.4 933.3 939.2 951.8 953.6 19463.8 19796.3 19948.1 19959.9 20421.4 976.4 987.8 988.1 990.7 1036.2 10690.4 10804.4 10971.6 11055.9 11060.9 523296.0 523537.0 526627.0 537732.0 545420.0 114751.0 117065.4 118834.4 119061.4 120238.0 1275.1 1282.0 1291.8 1295.0 1297.7 17301.5 17345.7 17424.3 17703.6 17834.6 49626.5 50332.5 50628.7 51015.0 52029.0 68367.7 68723.0 68858.3 68895.7 69151.7 14390.1 14708.3 14871.3 14889.3 14905.7 20763.3 21995.6 23461.6 23560.9 23567.8 18681.8 18988.5 19388.9 19588.9 19729.9 16389.3 17784.6 18011.5 18176.9 18188.6 19568.3 19780.2 20027.5 20148.1 20292.4 14764.7 14976.6 15012.8 15066.0 15112.6 18526.3 18675.8 18766.1 18811.2 18957.5 22:08:12 INFO - PID 3531 | 22:08:12 INFO - PID 3531 | [#3] mootools.html Cycles:180 Average:8485.05 Median:2661.82 stddev:5919.84 (222.4%) stddev-sans-first:5912.36 22:08:12 INFO - PID 3531 | Values: 1377.6 1404.8 1421.4 1425.4 1435.9 13331.7 13779.8 13811.2 13908.7 14351.6 14352.9 14624.5 14639.5 14652.7 14655.1 2643.6 2656.4 2667.2 2670.8 2673.3 2543.9 2702.8 2760.9 2764.1 2768.5 2678.0 2687.3 2732.3 2734.6 2735.2 2586.3 2619.7 2621.6 2642.2 2678.6 2561.2 2581.9 2586.5 2589.7 2590.3 13628.7 13924.4 13926.4 13933.1 13934.3 7351.0 7571.1 7576.9 7632.5 7636.3 4893.0 5064.5 5081.7 5185.7 5210.9 1733.2 1751.6 1755.2 1804.4 1812.8 22328.1 22566.9 22599.2 22638.2 22669.2 6291.7 6355.1 6407.6 6457.8 6598.9 15497.5 15555.7 15577.6 15603.3 15619.2 7374.0 7456.0 7474.6 7474.9 7474.9 6807.3 6826.3 6945.6 6999.3 7022.2 5026.7 5068.7 5084.9 5097.8 5143.2 1508.3 1539.5 1543.5 1545.5 1555.8 794.8 806.2 807.4 820.1 824.4 6569.7 6579.4 6642.1 6699.9 6742.4 670.9 684.0 685.3 687.2 692.8 4400.5 4417.5 4508.3 4517.5 4573.5 18689.6 19281.7 19601.3 19661.2 19663.9 18268.2 19032.6 19286.6 19307.2 19358.5 9863.5 10147.3 10421.6 10482.5 10482.6 7354.5 7360.2 7372.0 7618.8 7635.1 16494.7 16680.2 16826.2 16832.9 17045.5 17276.0 17407.0 17409.6 17471.0 17555.1 10899.4 11143.3 11201.4 11227.0 11251.2 12821.5 13051.1 13063.2 13066.3 13070.9 10655.4 10835.2 10933.9 10989.6 11000.8 8096.2 8116.2 8241.7 8273.1 8305.7 9359.8 9544.8 9555.2 9556.9 9564.3 6042.0 6077.4 6147.1 6169.3 6210.3 6505.4 6528.3 6588.2 6656.2 6756.2 22:08:12 INFO - PID 3531 | 22:08:12 INFO - PID 3531 | [#4] prototype.html Cycles:180 Average:5497.83 Median:6070.59 stddev:1563.21 (25.8%) stddev-sans-first:1521.90 22:08:12 INFO - PID 3531 | Values: 498.8 501.1 527.4 528.0 531.6 6278.5 6287.8 6289.6 6367.9 6380.9 5965.2 6084.5 6111.4 6175.8 6193.0 4951.9 4961.4 4975.0 4985.0 5009.7 4927.1 4954.5 4962.9 4976.9 5007.2 4799.6 4868.1 4899.7 4909.7 4917.8 4838.0 4873.6 4882.2 4898.8 4934.0 4409.3 4425.7 4454.2 4458.4 4476.6 5890.1 5995.4 6018.0 6070.6 6070.6 5573.2 5587.6 5615.0 5678.9 5682.8 6590.7 6632.7 6666.0 6668.6 6676.8 4733.7 4813.9 4824.6 4842.8 4849.8 6919.6 6943.3 6966.8 6976.0 7023.0 4856.3 4915.4 4930.2 4974.4 4984.5 4914.2 4920.8 4935.0 4939.7 4944.2 5586.5 5639.2 5639.4 5656.6 5657.2 6622.5 6673.6 6678.3 6729.9 6776.2 1622.6 1720.1 1726.8 1729.9 1739.7 1126.7 1150.3 1186.6 1189.3 1203.3 5749.8 5757.2 5771.4 5780.2 5798.2 6584.6 6609.7 6672.5 6733.6 6736.9 5749.8 5793.6 5803.2 5810.7 5846.1 3546.4 3552.7 3578.2 3620.4 3633.1 6524.3 6544.9 6557.2 6562.6 6567.5 6831.6 6898.4 6904.0 6958.0 6981.4 6355.4 6490.4 6492.3 6502.3 6513.8 6573.0 6649.9 6672.5 6724.3 6781.6 6542.2 6552.7 6622.9 6642.7 6666.7 6397.5 6491.6 6501.7 6502.2 6516.9 4925.6 4934.4 4935.9 4952.1 4955.4 6591.8 6657.6 6696.5 6768.8 6792.7 6530.3 6535.2 6540.0 6558.3 6559.1 6451.5 6483.7 6483.7 6491.1 6537.4 5995.9 6412.3 6421.2 6487.8 6545.1 6631.5 6638.6 6640.7 6663.2 6697.0 6445.9 6453.6 6458.4 6460.1 6467.1 22:08:12 INFO - PID 3531 | 22:08:12 INFO - PID 3531 | [#5] yui.html Cycles:180 Average:22514.80 Median:19357.85 stddev:30702.21 (158.6%) stddev-sans-first:30745.30 22:08:12 INFO - PID 3531 | Values: 866.2 884.9 889.3 897.1 903.2 17036.3 18102.2 18108.9 18125.8 22166.6 19671.8 20955.7 21076.1 21098.6 21120.8 1029.1 1052.6 1053.4 1054.2 1054.8 1006.7 1050.6 1053.5 1053.6 1057.2 1117.5 1117.6 1117.9 1118.4 1119.3 1103.5 1103.7 1107.2 1108.1 1109.9 802.3 804.5 805.4 806.2 809.9 19078.7 19099.0 19305.5 19386.7 19767.9 18526.2 18576.0 18703.3 19120.5 19142.9 15747.9 16021.5 16110.8 16193.1 16212.4 3548.5 3559.1 3596.4 3599.2 3602.9 120748.4 124668.0 124925.0 125403.4 125432.4 14635.0 14821.5 14982.3 15034.4 15042.8 27647.7 28857.1 28896.9 28925.1 29163.9 8391.0 8548.7 8585.2 8658.1 8661.4 3883.9 3983.3 3996.9 4020.8 4021.9 4978.9 5274.3 5387.1 5393.8 5400.3 912.3 928.2 950.3 950.8 951.9 675.4 689.1 695.0 695.9 703.7 11966.8 12070.5 12222.8 12276.5 12596.9 693.4 701.7 704.3 706.9 722.9 5501.0 5540.1 5549.8 5582.6 5637.7 102584.0 105422.5 106584.0 108503.0 110098.4 94585.5 95120.0 96512.0 97471.5 97515.0 80817.0 82552.2 83939.0 84358.6 84816.2 10987.6 11082.6 11082.6 11173.6 11504.7 36260.1 36408.3 36813.6 37385.4 38682.0 46875.8 47075.8 47416.5 48514.0 48617.5 15756.3 16089.6 16273.2 16278.3 16305.1 17057.5 17339.5 17381.7 17436.8 17454.4 18866.6 19332.9 19382.8 19593.3 19695.6 17430.9 19289.1 19459.0 19566.6 19731.5 17658.0 17858.6 18456.2 19097.3 19243.0 13724.8 13801.4 14014.2 14431.1 14645.4 14217.6 14738.3 14934.9 14969.3 14978.4 22:08:12 INFO - PID 3531 | -------- Summary: end -------- 22:08:12 INFO - PID 3531 | 22:08:12 INFO - TEST-INFO | 3531: exit 0 22:08:12 INFO - TEST-OK | dromaeo_css | took 1114816ms 22:08:12 INFO - TEST-START | kraken 22:08:12 INFO - Initialising browser for kraken test... 22:08:12 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55041/getInfo.html -profile /tmp/tmph8gYJn/profile 22:08:13 INFO - TEST-INFO | started process 3894 (/builds/slave/test/build/application/firefox/firefox http://localhost:55041/getInfo.html) 22:08:19 INFO - TEST-INFO | 3894: exit 0 22:08:19 INFO - Browser initialized. 22:08:19 INFO - Running cycle 1/1 for kraken test... 22:08:19 INFO - TEST-INFO | started process 4099 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmph8gYJn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 22:08:20 INFO - PID 4099 | 22:08:20 INFO - PID 4099 | (/builds/slave/test/build/application/firefox/firefox:4147): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 22:08:20 INFO - PID 4099 | 22:08:30 INFO - PID 4099 | RSS: Main: 171827200 22:08:30 INFO - PID 4099 | 22:08:33 INFO - PID 4099 | Cycle 1(1): loaded http://localhost:55041/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55041/tests/kraken/driver.html?testName=audio-beat-detection) 22:08:34 INFO - PID 4099 | RSS: Main: 177176576 22:08:34 INFO - PID 4099 | 22:08:36 INFO - PID 4099 | Cycle 1(1): loaded http://localhost:55041/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55041/tests/kraken/driver.html?testName=audio-dft) 22:08:37 INFO - PID 4099 | RSS: Main: 177455104 22:08:37 INFO - PID 4099 | 22:08:40 INFO - PID 4099 | Cycle 1(1): loaded http://localhost:55041/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55041/tests/kraken/driver.html?testName=audio-fft) 22:08:40 INFO - PID 4099 | RSS: Main: 172609536 22:08:40 INFO - PID 4099 | 22:08:43 INFO - PID 4099 | Cycle 1(1): loaded http://localhost:55041/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55041/tests/kraken/driver.html?testName=audio-oscillator) 22:08:43 INFO - PID 4099 | RSS: Main: 172990464 22:08:43 INFO - PID 4099 | 22:08:45 INFO - PID 4099 | Cycle 1(1): loaded http://localhost:55041/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55041/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:08:46 INFO - PID 4099 | RSS: Main: 173133824 22:08:46 INFO - PID 4099 | 22:08:49 INFO - PID 4099 | Cycle 1(1): loaded http://localhost:55041/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55041/tests/kraken/driver.html?testName=imaging-darkroom) 22:08:50 INFO - PID 4099 | RSS: Main: 175923200 22:08:50 INFO - PID 4099 | 22:08:53 INFO - PID 4099 | Cycle 1(1): loaded http://localhost:55041/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55041/tests/kraken/driver.html?testName=imaging-desaturate) 22:08:54 INFO - PID 4099 | RSS: Main: 176250880 22:08:54 INFO - PID 4099 | 22:08:57 INFO - PID 4099 | Cycle 1(1): loaded http://localhost:55041/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55041/tests/kraken/driver.html?testName=json-parse-financial) 22:08:57 INFO - PID 4099 | RSS: Main: 176033792 22:08:57 INFO - PID 4099 | 22:08:59 INFO - PID 4099 | Cycle 1(1): loaded http://localhost:55041/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55041/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:09:00 INFO - PID 4099 | RSS: Main: 176193536 22:09:00 INFO - PID 4099 | 22:09:01 INFO - PID 4099 | Cycle 1(1): loaded http://localhost:55041/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55041/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:09:02 INFO - PID 4099 | RSS: Main: 176652288 22:09:02 INFO - PID 4099 | 22:09:04 INFO - PID 4099 | Cycle 1(1): loaded http://localhost:55041/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55041/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:09:05 INFO - PID 4099 | RSS: Main: 173359104 22:09:05 INFO - PID 4099 | 22:09:08 INFO - PID 4099 | Cycle 1(1): loaded http://localhost:55041/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55041/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:09:08 INFO - PID 4099 | RSS: Main: 174424064 22:09:08 INFO - PID 4099 | 22:09:11 INFO - PID 4099 | Cycle 1(1): loaded http://localhost:55041/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55041/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:09:12 INFO - PID 4099 | RSS: Main: 174014464 22:09:12 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | Cycle 1(1): loaded http://localhost:55041/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55041/tests/kraken/driver.html?testName=ai-astar) 22:09:14 INFO - PID 4099 | RSS: Main: 175570944 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | __start_tp_report 22:09:14 INFO - PID 4099 | _x_x_mozilla_page_load 22:09:14 INFO - PID 4099 | _x_x_mozilla_page_load_details 22:09:14 INFO - PID 4099 | |i|pagename|runs| 22:09:14 INFO - PID 4099 | |0;ai-astar;98;108;100;109;107;108;110;104;111;111 22:09:14 INFO - PID 4099 | |1;audio-beat-detection;151;119;145;169;119;149;157;117;169;118 22:09:14 INFO - PID 4099 | |2;audio-dft;164;163;164;162;163;163;163;169;163;162 22:09:14 INFO - PID 4099 | |3;audio-fft;75;75;73;183;78;75;76;76;158;77 22:09:14 INFO - PID 4099 | |4;audio-oscillator;79;79;138;80;79;79;126;79;78;131 22:09:14 INFO - PID 4099 | |5;imaging-gaussian-blur;114;114;114;114;118;113;114;114;114;114 22:09:14 INFO - PID 4099 | |6;imaging-darkroom;150;151;151;151;152;150;151;151;151;151 22:09:14 INFO - PID 4099 | |7;imaging-desaturate;94;94;94;95;95;95;95;94;94;94 22:09:14 INFO - PID 4099 | |8;json-parse-financial;55;54;54;54;54;54;55;55;53;54 22:09:14 INFO - PID 4099 | |9;json-stringify-tinderbox;54;54;54;54;56;54;55;54;54;58 22:09:14 INFO - PID 4099 | |10;stanford-crypto-aes;84;78;78;96;89;79;77;86;78;80 22:09:14 INFO - PID 4099 | |11;stanford-crypto-ccm;132;128;138;142;132;131;128;124;132;170 22:09:14 INFO - PID 4099 | |12;stanford-crypto-pbkdf2;179;171;184;185;175;177;182;180;169;178 22:09:14 INFO - PID 4099 | |13;stanford-crypto-sha256-iterative;67;67;72;66;76;68;65;69;67;66 22:09:14 INFO - PID 4099 | __end_tp_report 22:09:14 INFO - PID 4099 | __start_cc_report 22:09:14 INFO - PID 4099 | _x_x_mozilla_cycle_collect,985 22:09:14 INFO - PID 4099 | __end_cc_report 22:09:14 INFO - PID 4099 | __startTimestamp1501736954527__endTimestamp 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | ------- Summary: start ------- 22:09:14 INFO - PID 4099 | Number of tests: 14 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | [#0] ai-astar Cycles:10 Average:106.60 Median:109.50 stddev:4.53 (4.1%) stddev-sans-first:3.57 22:09:14 INFO - PID 4099 | Values: 98.0 108.0 100.0 109.0 107.0 108.0 110.0 104.0 111.0 111.0 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:150.00 stddev:21.28 (14.2%) stddev-sans-first:22.28 22:09:14 INFO - PID 4099 | Values: 151.0 119.0 145.0 169.0 119.0 149.0 157.0 117.0 169.0 118.0 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | [#2] audio-dft Cycles:10 Average:163.60 Median:163.00 stddev:2.01 (1.2%) stddev-sans-first:2.13 22:09:14 INFO - PID 4099 | Values: 164.0 163.0 164.0 162.0 163.0 163.0 163.0 169.0 163.0 162.0 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | [#3] audio-fft Cycles:10 Average:94.60 Median:75.50 stddev:40.46 (53.6%) stddev-sans-first:42.28 22:09:14 INFO - PID 4099 | Values: 75.0 75.0 73.0 183.0 78.0 75.0 76.0 76.0 158.0 77.0 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:79.00 stddev:25.60 (32.4%) stddev-sans-first:26.51 22:09:14 INFO - PID 4099 | Values: 79.0 79.0 138.0 80.0 79.0 79.0 126.0 79.0 78.0 131.0 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:1.34 (1.2%) stddev-sans-first:1.41 22:09:14 INFO - PID 4099 | Values: 114.0 114.0 114.0 114.0 118.0 113.0 114.0 114.0 114.0 114.0 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 22:09:14 INFO - PID 4099 | Values: 150.0 151.0 151.0 151.0 152.0 150.0 151.0 151.0 151.0 151.0 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 22:09:14 INFO - PID 4099 | Values: 94.0 94.0 94.0 95.0 95.0 95.0 95.0 94.0 94.0 94.0 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.60 22:09:14 INFO - PID 4099 | Values: 55.0 54.0 54.0 54.0 54.0 54.0 55.0 55.0 53.0 54.0 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | [#9] json-stringify-tinderbox Cycles:10 Average:54.70 Median:54.00 stddev:1.34 (2.5%) stddev-sans-first:1.39 22:09:14 INFO - PID 4099 | Values: 54.0 54.0 54.0 54.0 56.0 54.0 55.0 54.0 54.0 58.0 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:82.00 stddev:6.22 (7.6%) stddev-sans-first:6.58 22:09:14 INFO - PID 4099 | Values: 84.0 78.0 78.0 96.0 89.0 79.0 77.0 86.0 78.0 80.0 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | [#11] stanford-crypto-ccm Cycles:10 Average:135.70 Median:132.00 stddev:13.08 (9.9%) stddev-sans-first:13.81 22:09:14 INFO - PID 4099 | Values: 132.0 128.0 138.0 142.0 132.0 131.0 128.0 124.0 132.0 170.0 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.00 Median:179.50 stddev:5.23 (2.9%) stddev-sans-first:5.53 22:09:14 INFO - PID 4099 | Values: 179.0 171.0 184.0 185.0 175.0 177.0 182.0 180.0 169.0 178.0 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:67.50 stddev:3.33 (4.9%) stddev-sans-first:3.50 22:09:14 INFO - PID 4099 | Values: 67.0 67.0 72.0 66.0 76.0 68.0 65.0 69.0 67.0 66.0 22:09:14 INFO - PID 4099 | -------- Summary: end -------- 22:09:14 INFO - PID 4099 | 22:09:14 INFO - PID 4099 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 22:09:15 INFO - TEST-INFO | 4099: exit 0 22:09:15 INFO - TEST-OK | kraken | took 62215ms 22:09:15 INFO - SUITE-END | took 1177s 22:09:15 INFO - Completed test suite (00:19:37) 22:09:15 INFO - javascript benchmark 22:09:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8422.45251973519, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3051.0999255219695, 3138.2781960149164, 3144.025352140835, 3155.053483954813, 3194.1056504178823, 6064.029755239163, 6123.724432400541, 6131.570790044704, 6158.876822463548, 6247.812565623027, 6175.258968923724, 6232.189815324013, 6274.811755647326, 6280.717367718452, 6281.0, 5669.064604340289, 5677.6309539879985, 5744.4829965303115, 5774.6535207887455, 5799.130130480441, 5587.552995760349, 5642.181883626871, 5675.34733505648, 5720.170575266605, 5721.370649228582, 5592.5805564582815, 5615.438456154393, 5634.774609015634, 5674.971625141869, 5680.176374425706, 5583.637063590853, 5658.264425624642, 5674.205611214434, 5704.543636509069, 5709.8287051388525, 5504.614676972614, 5509.090999984997, 5531.612787104904, 5559.110542313251, 5589.35722391922, 5972.104184372335, 6026.789062382795, 6036.245469316335, 6041.184440100574, 6050.394960503959, 6463.93536064638, 6471.935280647181, 6482.708278127496, 6492.8312903677315, 6534.836629084263, 6463.159789227418, 6533.7713180038945, 6534.281229064799, 6539.051837483538, 6545.705443255065, 6057.515398768132, 6069.848253793691, 6071.241094863142, 6102.572819902609, 6109.541784366234, 6841.247462779091, 6859.622720750307, 6863.0, 6933.965330173418, 6939.757108501179, 6406.583572067801, 6478.514111441661, 6508.153939987866, 6536.738530458823, 6591.373819487141, 6644.66776661165, 6652.83367915806, 6676.499262555328, 6729.3270672933295, 6730.7307707692125, 6419.390157934989, 6484.221893372825, 6494.350564943468, 6555.377239162367, 6620.86758264832, 6608.843452395811, 6672.065910772496, 6674.499412544079, 6689.063531105649, 6689.1972963244725, 5305.549028332558, 5366.7048312343195, 5371.4897084776885, 5399.055165346049, 5401.405845357008, 4554.385158003627, 4609.516000819933, 4612.3542704021465, 4620.722756634613, 4636.4899861015265, 5781.913271300934, 5788.131780232999, 5873.0, 5874.823755287262, 5880.411958804171, 6557.377049180321, 6580.045893345438, 6631.900521492182, 6656.0, 6752.797416077526, 5750.798722044792, 5752.597318187687, 5771.94228057714, 5776.1624564437925, 5783.971080144656, 5758.222639943554, 5769.59612827098, 5770.538356931519, 5775.595708300462, 5864.67744274069, 6386.329435409308, 6403.231612206565, 6434.453071488976, 6457.644829534421, 6495.8700825983215, 6639.601623902581, 6650.900236496554, 6723.0923825283935, 6738.6967586459705, 6741.662916854236, 6242.469390101892, 6386.808395748135, 6404.423601875667, 6476.028595710681, 6501.934980650133, 6509.837254068686, 6521.836954076186, 6554.016897465418, 6554.344565543408, 6569.277379488348, 6701.564398314094, 6709.664516774044, 6711.865762684622, 6712.228093769279, 6721.966390168018, 6491.253505846699, 6523.478121750154, 6524.021396790519, 6529.804105876831, 6530.869382612416, 6383.904241436289, 6426.903596445964, 6433.806985790434, 6480.837979050561, 6537.313582074004, 6371.362863713592, 6380.712867921047, 6383.1382763326355, 6384.808455746335, 6388.968055159695, 6436.742530298923, 6458.677066146768, 6487.183940845178, 6518.771842985473, 6560.606363618198, 6290.370962903673, 6312.337204593451, 6347.174867267226, 6405.839854003687, 6411.551191416556], "value": 6041.77254706927, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [815.2581151152449, 841.5750046226655, 874.8206617643383, 902.8058967322027, 903.421810041573, 40014.79992600036, 40918.795406023004, 40923.18153636933, 41055.79472102639, 41083.58916410835, 37007.814960925156, 37061.44407833885, 37319.253614927686, 37320.0, 37399.25201495968, 6694.665266736668, 6755.054292399068, 6802.38778509934, 6841.726330946756, 6855.348741869514, 6674.599524028549, 6785.016172654962, 6785.287544807798, 6788.913773796194, 6790.185177778673, 7287.526310789808, 7443.665035073408, 7453.627318634074, 7456.739014134507, 7473.290037446434, 7317.817054573625, 7353.7058517659225, 7418.2581741825925, 7429.294217049344, 7498.287662672037, 7340.376068034224, 7420.888686669704, 7423.220561841037, 7428.071491063617, 7430.851382972311, 38314.0, 38674.0, 38685.0, 38693.80653096745, 38852.41721374181, 3520.612732599412, 3542.6634469725336, 3544.929101417957, 3557.092941299952, 3565.1978304881195, 31020.0, 31301.686983130105, 31318.843405783053, 31333.0, 31411.84294078515, 4821.710697358169, 4836.879078023042, 4849.442314133849, 4861.902761944777, 4880.414350277953, 182706.0, 189337.0, 191334.0, 192753.0, 195864.0, 48700.5129948703, 49110.75444622754, 49270.75364623154, 49273.753631231615, 49285.0, 51102.23346649803, 51129.23306150411, 51594.22608660873, 51648.483515164364, 51687.74156129195, 15518.0, 15663.37346506127, 15677.451289204822, 15705.764413533807, 15711.057336559843, 12796.936015319863, 12847.100702950704, 12847.907927826052, 12878.0, 12954.80567791484, 14571.781423278659, 14619.122852628876, 14719.779203311959, 14753.0, 14921.403143874124, 5639.295088113986, 5674.319081710221, 5719.599628025998, 5836.824895253149, 5868.765249390062, 975.492743773234, 995.4127065031521, 1007.9445630490246, 1008.369768894441, 1008.5814387029448, 22127.44681382978, 22489.887550562144, 22949.311520654406, 29471.0, 29567.0, 1094.5512339940585, 1099.0438318662655, 1104.276698762312, 1107.2304748199924, 1107.5625128074525, 12236.388180590935, 12776.233425994471, 12811.423485943154, 12813.743725125445, 12842.614721558368, 229919.0, 232303.0, 233002.0, 233477.0, 233616.0, 52244.47755522472, 52287.47712522902, 52394.0, 52442.73778631083, 52644.210336844975, 1424.8005279260913, 1454.2510607037384, 1462.8464011278875, 1462.8537146285287, 1464.7656374979952, 22847.200347987742, 22857.428564286027, 22889.542209156058, 22919.770802291765, 23196.0, 38207.23585528218, 38311.61688383081, 38350.80824595859, 38355.616443835206, 38566.22867542689, 208280.0, 208693.0, 208849.0, 208927.0, 210059.0, 34554.82722586371, 36523.0, 38377.0, 38549.6145038546, 38605.806970964964, 46247.075058499315, 47616.7619161902, 47823.0, 48030.0, 48186.27720584124, 33144.83427582846, 33431.66568334286, 33456.33087338288, 33898.322033559685, 34000.489992650626, 33532.0, 33726.66273337235, 34217.144571385914, 34237.657623423445, 34531.827340863136, 37432.625673742914, 37613.435798462495, 37678.0, 37685.246295074496, 38138.61861381351, 32385.838070809496, 32618.51072233871, 32834.0, 32981.0, 33022.33955320928, 23671.763282367643, 24411.901464434497, 24573.26280211525, 24780.628290576016, 25041.74958250394], "value": 17195.937073671936, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.8154190658988, 577.3937365765945, 583.9708014599274, 597.1013624495134, 599.439524045018, 21748.34754957352, 22267.331980040584, 22351.66472502914, 22393.440163995907, 22418.88790556049, 26657.333566660844, 27883.302917427074, 27886.721132788665, 27888.721112788866, 27897.0, 6825.897611535831, 6952.826179345531, 6956.547824391424, 6959.060526828889, 6971.895421568681, 6384.840378990539, 6676.899846502306, 6707.295733947938, 6725.192976842761, 6740.696668649923, 6695.631740254284, 6700.329967003309, 6707.698153583076, 6713.395794378505, 6717.0, 6642.402183803457, 6680.298568650294, 6698.799036028927, 6701.396874281338, 6707.966460167717, 6565.737370505174, 6583.440407565363, 6593.472522198213, 6599.274079851212, 6615.17310336208, 30508.0, 31174.0, 32667.509987350208, 32731.836340818383, 33049.0, 1975.0519750519688, 1999.3202311214256, 2001.7497812773404, 2009.8291645210174, 2015.5666531695608, 19682.50793730154, 20434.284800031928, 20617.690734638993, 20680.79319206804, 20717.0677319521, 2389.6415537669313, 2439.5730747119183, 2530.683664541932, 2543.9872800636062, 2546.363409147713, 146822.0, 148277.0, 150487.2378190554, 152665.0, 153088.2345588276, 19808.50478738014, 21626.810525420937, 21892.45268868291, 21908.0, 22032.44918877041, 23370.0, 24157.15449959243, 45062.54937450649, 45165.54834451614, 45714.0, 9993.050660187417, 10129.746756331004, 10200.897991020143, 10265.79285453631, 10409.219308551737, 9170.816583668422, 9237.676681316121, 9303.58133883977, 9316.44101353921, 9356.345055846026, 9321.813563728687, 9335.719928402159, 9470.337076404723, 9474.857877131848, 9685.661001865043, 2624.13403576823, 2660.9733902661114, 2707.5261829179813, 2718.5106680797253, 2774.389634280455, 930.3766476460787, 933.2580598424133, 939.2248650051408, 951.785848184153, 953.5661274120258, 19463.80536194648, 19796.307129250406, 19948.102335394942, 19959.900200498905, 20421.387358379274, 976.3751199232497, 987.8172538080335, 988.0712130597216, 990.6730778842965, 1036.186593524095, 10690.412027338416, 10804.405757683402, 10971.561137554569, 11055.889441105646, 11060.889391105986, 523296.0, 523537.0, 526627.0, 537732.0, 545420.0, 114751.0, 117065.4146729278, 118834.4058279703, 119061.40469297598, 120238.0, 1275.0819410024767, 1282.0192552697195, 1291.8297260602278, 1294.9676258093623, 1297.6690943809458, 17301.480955571355, 17345.65308693794, 17424.303027878745, 17703.557411064827, 17834.643307134043, 49626.50373496363, 50332.49667503424, 50628.74685626695, 51015.0, 52029.0, 68367.65816170887, 68723.0, 68858.3114168872, 68895.65552172408, 69151.65424172847, 14390.06464579799, 14708.338124784197, 14871.330790114254, 14889.329980150702, 14905.701885962437, 20763.27328543554, 21995.560088798455, 23461.648075279227, 23560.88219558891, 23567.764322356557, 18681.81318186855, 18988.525286867938, 19388.903055484632, 19588.90205548963, 19729.901350493157, 16389.34442622282, 17784.55538611545, 18011.459656210336, 18176.90911545434, 18188.636227275645, 19568.315108971117, 19780.208791648747, 20027.49931251672, 20148.09333580022, 20292.391228263175, 14764.704705905608, 14976.550703478913, 15012.849871501581, 15066.0, 15112.622184445478, 18526.258949641862, 18675.813241867952, 18766.061696915374, 18811.153498092892, 18957.526061848013], "value": 12486.347501593727, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.6073818961597, 1404.8033275341447, 1421.4456362018814, 1425.4440768100437, 1435.885129189665, 13331.666708332297, 13779.793303100461, 13811.240381778998, 13908.721825563483, 14351.569452916403, 14352.928235358835, 14624.488142915001, 14639.487617933348, 14652.706945861102, 14655.120692758415, 2643.590243512259, 2656.428867793424, 2667.213172114231, 2670.7996900232465, 2673.31830383252, 2543.872806359684, 2702.7837772978214, 2760.9447811043765, 2764.060219525371, 2768.529350010493, 2678.035907073452, 2687.261003224119, 2732.2622891819196, 2734.576140698185, 2735.179446166142, 2586.314626623946, 2619.724928882478, 2621.606758986148, 2642.2205449392477, 2678.5848193530032, 2561.2316305108393, 2581.8579978101197, 2586.456844062749, 2589.741025897414, 2590.2617753940226, 13628.659283517893, 13924.443022279096, 13926.373313200931, 13933.094348867291, 13934.30328483582, 7350.96324518374, 7571.053618297713, 7576.886346704804, 7632.465727399084, 7636.31273185416, 4892.9755351223375, 5064.518870707277, 5081.74591270435, 5185.662931909452, 5210.947890521084, 1733.1767410479922, 1751.5883767314672, 1755.2101554300489, 1804.3955274982895, 1812.7552780374613, 22328.106875724952, 22566.887165564232, 22599.20902768395, 22638.20766273189, 22669.2065777697, 6291.653959032205, 6355.110284560165, 6407.647579383085, 6457.806265812033, 6598.868022639616, 15497.535073947574, 15555.688886222439, 15577.610559735871, 15603.297851596706, 15619.219039048003, 7374.00450939127, 7455.962720186473, 7474.588897610575, 7474.887876681854, 7474.887876681854, 6807.285235050347, 6826.317368263233, 6945.617991010442, 6999.300069993062, 7022.192447868459, 5026.723530205801, 5068.721220332844, 5084.923726144111, 5097.770600322994, 5143.228515722672, 1508.3287936868207, 1539.4611885839865, 1543.5060780550116, 1545.5054382597687, 1555.8366371530826, 794.8251384695474, 806.2139414071233, 807.4226927746572, 820.0856045509214, 824.3528829868644, 6569.704363303663, 6579.374959378852, 6642.070110184482, 6699.941544134147, 6742.359475849787, 670.9429698475586, 684.0, 685.2907241005635, 687.2474640268897, 692.8129405060605, 4400.4939431965095, 4417.514073451917, 4508.301213311942, 4517.480489743656, 4573.5426457354, 18689.626207475776, 19281.710774338397, 19601.31395401154, 19661.213551458066, 19663.901680491792, 18268.177931993094, 19032.619347613247, 19286.61426771485, 19307.22771089169, 19358.516037099183, 9863.457509836884, 10147.289689721796, 10421.5831366746, 10482.528286226985, 10482.63310784119, 7354.485186036926, 7360.227176146642, 7372.0, 7618.771436856903, 7635.121960974559, 16494.67010659804, 16680.24938877729, 16826.242819072926, 16832.915835421234, 17045.48863534096, 17276.0, 17407.0, 17409.56476088108, 17471.0, 17555.122243887497, 10899.400532970763, 11143.33140011602, 11201.43992800373, 11227.0, 11251.212415130862, 12821.487140514275, 13051.086423950612, 13063.150895191782, 13066.281354525212, 13070.869291307346, 10655.360678359324, 10835.187360947803, 10933.890661093605, 10989.560417583527, 11000.834987475355, 8096.230858068474, 8116.228958248957, 8241.670333186605, 8273.089960104264, 8305.712614544513, 9359.812803744024, 9544.809103818023, 9555.23558115363, 9556.90443095588, 9564.330496865152, 6041.969790151021, 6077.4226448487325, 6147.139400483847, 6169.259688837368, 6210.347913469202, 6505.447037001719, 6528.314526974789, 6588.209414870246, 6656.167979002625, 6756.155480564929], "value": 6129.983444789023, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.7705655398514, 501.1180322632167, 527.3539913605831, 528.0442399257345, 531.6066111077802, 6278.497720182386, 6287.793225455017, 6289.622622642644, 6367.904481432776, 6380.872382552352, 5965.2245208122995, 6084.5132389408855, 6111.419415155564, 6175.7529698811995, 6192.969035154829, 4951.9009619807675, 4961.355023846895, 4974.97512512437, 4984.950150498503, 5009.699418034911, 4927.063857867011, 4954.479779623141, 4962.900741985158, 4976.87557811054, 5007.223880298559, 4799.6400269979895, 4868.148074087038, 4899.730514821684, 4909.705417674951, 4917.803287868481, 4838.0, 4873.634477414173, 4882.218844984804, 4898.755062246909, 4933.975330123344, 4409.294512877957, 4425.712328698656, 4454.171601033368, 4458.420405347317, 4476.574725401082, 5890.145928840337, 5995.400459954013, 6017.969910150465, 6070.57505974582, 6070.605410648293, 5573.219749235111, 5587.608867379285, 5614.971925140389, 5678.949394362056, 5682.829515114553, 6590.703418346186, 6632.734690612369, 6665.9666701666665, 6668.566543174727, 6676.799696009128, 4733.668643194977, 4813.879653008667, 4824.638152138568, 4842.782074806607, 4849.830255941025, 6919.550229235134, 6943.340382663639, 6966.79099627012, 6976.0, 7023.0, 4856.3201151838775, 4915.3855768029, 4930.211166213388, 4974.37820272466, 4984.451710311862, 4914.238293064581, 4920.75396230187, 4934.9753251233515, 4939.703617782944, 4944.159492886146, 5586.486081263448, 5639.182318563868, 5639.351474580476, 5656.632318899341, 5657.15142728594, 6622.503312251601, 6673.599584024974, 6678.265390807104, 6729.8654026919185, 6776.15298087739, 1622.5862405086627, 1720.1055451165323, 1726.8359505846925, 1729.939452119195, 1739.6520695860625, 1126.6563698072002, 1150.263831148066, 1186.6440067979572, 1189.2567145534042, 1203.3080978437433, 5749.798757043567, 5757.193992841006, 5771.365149833515, 5780.1907732917425, 5798.159266906275, 6584.6378449184795, 6609.702563384659, 6672.466202703772, 6733.629650369178, 6736.932630673728, 5749.818944634535, 5793.594448388572, 5803.187553742479, 5810.709464526757, 5846.070796637099, 3546.3971124908826, 3552.6802587767224, 3578.2127931854952, 3620.4388319810473, 3633.091727068233, 6524.347565243438, 6544.869102617921, 6557.245916719543, 6562.639054852029, 6567.474602031826, 6831.590104593641, 6898.379145876896, 6904.0, 6957.965210173917, 6981.441484681213, 6355.396237357536, 6490.448311893448, 6492.31830657793, 6502.284748677642, 6513.772017979348, 6573.014047892854, 6649.867002660017, 6672.532922695559, 6724.260331363644, 6781.6270105144695, 6542.191489255299, 6552.6723663815665, 6622.933770662232, 6642.682256533292, 6666.733320669057, 6397.456216221487, 6491.642959637265, 6501.73993040292, 6502.154719886385, 6516.934830651633, 4925.630577706758, 4934.407871055496, 4935.925961110514, 4952.10862044821, 4955.4301255356095, 6591.802245932629, 6657.567258128206, 6696.464282857457, 6768.830779230559, 6792.694328755119, 6530.281669016499, 6535.150430444012, 6539.95360742298, 6558.311377305315, 6559.147310849559, 6451.54841160356, 6483.740650373932, 6483.740650373932, 6491.123698300669, 6537.411632953057, 5995.850103747267, 6412.2625898022325, 6421.165248517849, 6487.805365839033, 6545.14913061299, 6631.469482441489, 6638.634875081917, 6640.701168447335, 6663.233728121134, 6697.0, 6445.935540644534, 6453.612783233021, 6458.418742313214, 6460.12788273577, 6467.059882035276], "value": 5035.138246864319, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [866.1598249697795, 884.9070847561006, 889.3418870036172, 897.0670502677216, 903.1781079217911, 17036.31854725811, 18102.185401656923, 18108.909455452736, 18125.81874181256, 22166.55666886661, 19671.803281967175, 20955.68566471504, 21076.051577679, 21098.57802843946, 21120.788792112075, 1029.135526158027, 1052.5737076484036, 1053.4206186597341, 1054.1672079057573, 1054.8470471781586, 1006.6677996261217, 1050.6270274052677, 1053.4943227250924, 1053.5680371047872, 1057.2440704895998, 1117.4692021289864, 1117.6423544465774, 1117.8658560972694, 1118.356944756764, 1119.28925132541, 1103.47584897174, 1103.6688993301977, 1107.2194103157256, 1108.141190577305, 1109.8834622364657, 802.3420794948145, 804.5052292839862, 805.3557154276556, 806.1857523900844, 809.9473534220257, 19078.713819292723, 19099.0, 19305.51736206592, 19386.70919936202, 19767.90116049425, 18526.166322515386, 18576.0, 18703.251869925185, 19120.521986950298, 19142.904285478482, 15747.92126039374, 16021.519354419386, 16110.83889161105, 16193.10937898415, 16212.351505939749, 3548.4854696068924, 3559.1458050068054, 3596.3706351388664, 3599.1721903961975, 3602.873899413508, 120748.39625801815, 124668.0, 124925.0, 125403.37298313541, 125432.37283813523, 14635.0, 14821.481248156262, 14982.325795339018, 15034.398624055135, 15042.849571504365, 27647.723522764918, 28857.134285971453, 28896.855515722706, 28925.132246032652, 29163.854180729384, 8390.958045209734, 8548.700795472128, 8585.227329540372, 8658.134186581417, 8661.393702440895, 3883.941740873889, 3983.2630963271886, 3996.8800935971385, 4020.7587544746825, 4021.8994525137105, 4978.850634480971, 5274.3143391359645, 5387.111126664135, 5393.83818485446, 5400.297961265011, 912.3066469483108, 928.2017464980112, 950.2872845365976, 950.7718147644515, 951.9428834269827, 675.378651640492, 689.0525527399825, 694.9548279361825, 695.8921367188094, 703.7396163419568, 11966.760664786656, 12070.517179312728, 12222.816657750142, 12276.508939642494, 12596.937015314865, 693.3724978894109, 701.6772284749072, 704.3027402871121, 706.9222385537587, 722.9168645605718, 5501.0, 5540.141278101901, 5549.750261238173, 5582.609217354746, 5637.661740295595, 102584.0, 105422.4728876366, 106584.0, 108503.0, 110098.44950775195, 94585.5270723642, 95120.0, 96512.0, 97471.51264243634, 97515.0, 80817.0, 82552.17447825565, 83939.0, 84358.5782071098, 84816.15183848207, 10987.61543345979, 11082.556697732, 11082.556697732323, 11173.553057877594, 11504.654860354203, 36260.09349766277, 36408.27183456263, 36813.63186368102, 37385.43921841229, 38682.0, 46875.765621171675, 47075.82310442266, 47416.52583474259, 48514.0, 48617.5138248613, 15756.290966906747, 16089.597760056093, 16273.186340683154, 16278.348866045226, 16305.103219323051, 17057.48827535176, 17339.47981560555, 17381.652366952843, 17436.82563174403, 17454.389096381565, 18866.622667546846, 19332.903335483796, 19382.8061719381, 19593.31423400174, 19695.606087877877, 17430.945564455775, 19289.131989060246, 19459.0, 19566.608667826848, 19731.506712332306, 17658.02880841566, 17858.642827143125, 18456.169472373505, 19097.331593394163, 19243.0, 13724.794128088286, 13801.447942082204, 14014.229217393142, 14431.134131952116, 14645.414183432544, 14217.64455888611, 14738.336774845373, 14934.925325373304, 14969.326380312694, 14978.400863965318], "value": 8914.700260429085, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1533.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 100.0, 109.0, 107.0, 108.0, 110.0, 104.0, 111.0, 111.0], "value": 106.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 119.0, 145.0, 169.0, 119.0, 149.0, 157.0, 117.0, 169.0, 118.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 162.0, 163.0, 163.0, 163.0, 169.0, 163.0, 162.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 73.0, 183.0, 78.0, 75.0, 76.0, 76.0, 158.0, 77.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 138.0, 80.0, 79.0, 79.0, 126.0, 79.0, 78.0, 131.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 118.0, 113.0, 114.0, 114.0, 114.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 151.0, 151.0, 152.0, 150.0, 151.0, 151.0, 151.0, 151.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 54.0, 54.0, 55.0, 55.0, 53.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 56.0, 54.0, 55.0, 54.0, 54.0, 58.0], "value": 54.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 78.0, 78.0, 96.0, 89.0, 79.0, 77.0, 86.0, 78.0, 80.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 128.0, 138.0, 142.0, 132.0, 131.0, 128.0, 124.0, 132.0, 170.0], "value": 135.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 171.0, 184.0, 185.0, 175.0, 177.0, 182.0, 180.0, 169.0, 178.0], "value": 178.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 72.0, 66.0, 76.0, 68.0, 65.0, 69.0, 67.0, 66.0], "value": 68.3, "unit": "score"}]}]} 22:09:15 INFO - Return code: 0 22:09:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:09:15 INFO - # TBPL SUCCESS # 22:09:15 INFO - Running post-action listener: _package_coverage_data 22:09:15 INFO - Running post-action listener: _resource_record_post_action 22:09:15 INFO - [mozharness: 2017-08-03 05:09:15.815819Z] Finished run-tests step (success) 22:09:15 INFO - Running post-run listener: _resource_record_post_run 22:09:15 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 5816320; Write bytes: 442859520; Read time: 3608; Write time: 393728 22:09:15 INFO - TinderboxPrint: CPU usage
13.1% 22:09:15 INFO - TinderboxPrint: I/O read bytes / time
5,816,320 / 3,608 22:09:15 INFO - TinderboxPrint: I/O write bytes / time
442,859,520 / 393,728 22:09:15 INFO - TinderboxPrint: CPU idle
8,280.7 (86.8%) 22:09:15 INFO - TinderboxPrint: CPU user
1,201.3 (12.6%) 22:09:15 INFO - TinderboxPrint: Swap in / out
0 / 0 22:09:15 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 119091200; Read time: 0; Write time: 87164 22:09:15 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 22:09:15 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 3629056; Write bytes: 283619328; Read time: 3288; Write time: 283220 22:09:16 INFO - Running post-run listener: _upload_blobber_files 22:09:16 INFO - Blob upload gear active. 22:09:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:09:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:09:16 INFO - (blobuploader) - INFO - Open directory for files ... 22:09:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:09:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:09:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:17 INFO - (blobuploader) - INFO - Done attempting. 22:09:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:09:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:09:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:18 INFO - (blobuploader) - INFO - Done attempting. 22:09:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:09:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:09:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:24 INFO - (blobuploader) - INFO - Done attempting. 22:09:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:09:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:09:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:25 INFO - (blobuploader) - INFO - Done attempting. 22:09:25 INFO - (blobuploader) - INFO - Iteration through files over. 22:09:25 INFO - Return code: 0 22:09:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:09:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:09:25 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92eb00269d8e209ea1c7adfff151476bfba6b7542e56e57b1fd6dfdf9490b76ba4cd8618626bb782625170ee1a8d16898da526c3ac11d18d5bba0cf3db83c3e1", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/736a81bd7b4c30a24e63df4c6119518afe69a4974fd607a2b42a4df322b1a152b1734c9c71728898d320d24fa85613436f6a58c18a8f01833908c26be2c6d4ae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0c27fbaf8a876a360834a24e4256be72ee5f93831ecd3b2ffd44be681fa5e18197c35afc538b8881cada83c640010fb56a661d30eca9bd65a340a21af987377", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/62e3ad6c8d8e988ea1661987f908381578c06902d7bde02419bac508afc7c9e3ff857c7daf5979e4639ab8d01788a6469c4beb48fa0579fbd42d6354d31c333a"} 22:09:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:09:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:09:25 INFO - Contents: 22:09:25 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92eb00269d8e209ea1c7adfff151476bfba6b7542e56e57b1fd6dfdf9490b76ba4cd8618626bb782625170ee1a8d16898da526c3ac11d18d5bba0cf3db83c3e1", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/736a81bd7b4c30a24e63df4c6119518afe69a4974fd607a2b42a4df322b1a152b1734c9c71728898d320d24fa85613436f6a58c18a8f01833908c26be2c6d4ae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0c27fbaf8a876a360834a24e4256be72ee5f93831ecd3b2ffd44be681fa5e18197c35afc538b8881cada83c640010fb56a661d30eca9bd65a340a21af987377", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/62e3ad6c8d8e988ea1661987f908381578c06902d7bde02419bac508afc7c9e3ff857c7daf5979e4639ab8d01788a6469c4beb48fa0579fbd42d6354d31c333a"} 22:09:25 INFO - Running post-run listener: copy_logs_to_upload_dir 22:09:25 INFO - Copying logs to upload dir... 22:09:25 INFO - mkdir: /builds/slave/test/build/upload/logs 22:09:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.140975 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-08-02 22:09:25.567315) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 22:09:25.571151) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92eb00269d8e209ea1c7adfff151476bfba6b7542e56e57b1fd6dfdf9490b76ba4cd8618626bb782625170ee1a8d16898da526c3ac11d18d5bba0cf3db83c3e1", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/736a81bd7b4c30a24e63df4c6119518afe69a4974fd607a2b42a4df322b1a152b1734c9c71728898d320d24fa85613436f6a58c18a8f01833908c26be2c6d4ae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0c27fbaf8a876a360834a24e4256be72ee5f93831ecd3b2ffd44be681fa5e18197c35afc538b8881cada83c640010fb56a661d30eca9bd65a340a21af987377", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/62e3ad6c8d8e988ea1661987f908381578c06902d7bde02419bac508afc7c9e3ff857c7daf5979e4639ab8d01788a6469c4beb48fa0579fbd42d6354d31c333a"} build_url:https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011145 build_url: 'https://queue.taskcluster.net/v1/task/V11kJWdpTmaYDM72zqhqGw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92eb00269d8e209ea1c7adfff151476bfba6b7542e56e57b1fd6dfdf9490b76ba4cd8618626bb782625170ee1a8d16898da526c3ac11d18d5bba0cf3db83c3e1", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/736a81bd7b4c30a24e63df4c6119518afe69a4974fd607a2b42a4df322b1a152b1734c9c71728898d320d24fa85613436f6a58c18a8f01833908c26be2c6d4ae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0c27fbaf8a876a360834a24e4256be72ee5f93831ecd3b2ffd44be681fa5e18197c35afc538b8881cada83c640010fb56a661d30eca9bd65a340a21af987377", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/62e3ad6c8d8e988ea1661987f908381578c06902d7bde02419bac508afc7c9e3ff857c7daf5979e4639ab8d01788a6469c4beb48fa0579fbd42d6354d31c333a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 22:09:25.595994) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 22:09:25.596388) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a12cd81e0ef515edd9e0d800000001bb-1501734073.16767-346985014 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004369 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 22:09:25.678994) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 22:09:25.679399) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 22:09:25.679750) ========= ========= Total master_lag: 0.16 =========