builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-034 starttime: 1504345828.82 results: success (0) revision: a46a5879b8781ae9ea99f37b5d34a891f0f75047 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 02:50:28.822091) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 02:50:28.822539) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 02:50:28.822821) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 02:50:28.838068) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 02:50:28.838386) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-02 02:50:28-- 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% 218M=0s 2017-09-02 02:50:29 (218 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.355608 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 02:50:29.210883) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 02:50:29.211198) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020628 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 02:50:29.260466) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 02:50:29.260800) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a46a5879b8781ae9ea99f37b5d34a891f0f75047 --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 a46a5879b8781ae9ea99f37b5d34a891f0f75047 --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=d6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-02 02:50:29,314 truncating revision to first 12 chars 2017-09-02 02:50:29,314 Setting DEBUG logging. 2017-09-02 02:50:29,314 attempt 1/10 2017-09-02 02:50:29,314 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a46a5879b878?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-02 02:50:29,735 unpacking tar archive at: mozilla-central-a46a5879b878/testing/mozharness/ program finished with exit code 0 elapsedTime=0.752477 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 02:50:30.026634) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 02:50:30.026962) ========= script_repo_revision: a46a5879b8781ae9ea99f37b5d34a891f0f75047 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 02:50:30.027321) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 02:50:30.027579) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 02:50:30.038867) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-09-02 02:50:30.039163) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=d6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:50:30 INFO - MultiFileLogger online at 20170902 02:50:30 in /builds/slave/test 02:50:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:50:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:50:30 INFO - {'append_to_log': False, 02:50:30 INFO - 'base_work_dir': '/builds/slave/test', 02:50:30 INFO - 'blob_upload_branch': 'Firefox', 02:50:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:50:30 INFO - 'branch': 'Firefox', 02:50:30 INFO - 'buildbot_json_path': 'buildprops.json', 02:50:30 INFO - 'code_coverage': False, 02:50:30 INFO - 'config_files': ('talos/linux_config.py',), 02:50:30 INFO - 'default_actions': ('clobber', 02:50:30 INFO - 'read-buildbot-config', 02:50:30 INFO - 'download-and-extract', 02:50:30 INFO - 'populate-webroot', 02:50:30 INFO - 'create-virtualenv', 02:50:30 INFO - 'install', 02:50:30 INFO - 'setup-mitmproxy', 02:50:30 INFO - 'run-tests'), 02:50:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:50:30 INFO - 'disable_ccov_upload': False, 02:50:30 INFO - 'disable_stylo': False, 02:50:30 INFO - 'download_minidump_stackwalk': True, 02:50:30 INFO - 'download_symbols': 'ondemand', 02:50:30 INFO - 'enable_stylo': False, 02:50:30 INFO - 'enable_webrender': False, 02:50:30 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:50:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:50:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:50:30 INFO - 'gecko_profile': False, 02:50:30 INFO - 'gecko_profile_interval': 0, 02:50:30 INFO - 'installer_path': 'installer.exe', 02:50:30 INFO - 'log_level': 'info', 02:50:30 INFO - 'log_name': 'talos', 02:50:30 INFO - 'log_to_console': True, 02:50:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:50:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:50:30 INFO - 'opt_config_files': (), 02:50:30 INFO - 'pip_index': False, 02:50:30 INFO - 'suite': 'dromaeojs-e10s', 02:50:30 INFO - 'system_bits': '32', 02:50:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:50:30 INFO - 'title': 'talos-linux64-ix-034', 02:50:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:50:30 INFO - 'use_talos_json': True, 02:50:30 INFO - 'verify': 'False', 02:50:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:50:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:50:30 INFO - 'work_dir': 'build'} 02:50:30 INFO - [mozharness: 2017-09-02 09:50:30.241145Z] Running clobber step. 02:50:30 INFO - Running pre-action listener: _resource_record_pre_action 02:50:30 INFO - Running main action method: clobber 02:50:30 INFO - rmtree: /builds/slave/test/build 02:50:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:50:31 INFO - Running post-action listener: _resource_record_post_action 02:50:31 INFO - [mozharness: 2017-09-02 09:50:31.914895Z] Finished clobber step (success) 02:50:31 INFO - [mozharness: 2017-09-02 09:50:31.915005Z] Running read-buildbot-config step. 02:50:31 INFO - Running pre-action listener: _resource_record_pre_action 02:50:31 INFO - Running main action method: read_buildbot_config 02:50:31 INFO - Using buildbot properties: 02:50:31 INFO - { 02:50:31 INFO - "project": "", 02:50:31 INFO - "product": "firefox", 02:50:31 INFO - "who": "archaeopteryx@coole-files.de", 02:50:31 INFO - "installer_path": "public/build/target.tar.bz2", 02:50:31 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 02:50:31 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:50:31 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 02:50:31 INFO - "stage_platform": "linux64-stylosequential", 02:50:31 INFO - "basedir": "/builds/slave/test", 02:50:31 INFO - "buildnumber": 226, 02:50:31 INFO - "platform": "ubuntu64_hw_styloseq", 02:50:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:50:31 INFO - "slavebuilddir": "test", 02:50:31 INFO - "taskId": "I4-0_jC2TdupJS2xJTWTfA", 02:50:31 INFO - "branch": "mozilla-central", 02:50:31 INFO - "script_repo_revision": "production", 02:50:31 INFO - "revision": "a46a5879b8781ae9ea99f37b5d34a891f0f75047", 02:50:31 INFO - "slavename": "talos-linux64-ix-034", 02:50:31 INFO - "repo_path": "mozilla-central" 02:50:31 INFO - } 02:50:31 INFO - Finding installer, test and symbols from parent task. 02:50:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I4-0_jC2TdupJS2xJTWTfA'}, attempt #1 02:50:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I4-0_jC2TdupJS2xJTWTfA'}, attempt #1 02:50:32 INFO - Task dependencies: elhbt6dOSlyDfLTG7lCKow 02:50:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow'}, attempt #1 02:50:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.tar.bz2 02:50:33 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.test_packages.json 02:50:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.crashreporter-symbols.zip 02:50:33 INFO - Running post-action listener: _resource_record_post_action 02:50:33 INFO - [mozharness: 2017-09-02 09:50:33.334266Z] Finished read-buildbot-config step (success) 02:50:33 INFO - [mozharness: 2017-09-02 09:50:33.334483Z] Running download-and-extract step. 02:50:33 INFO - Running pre-action listener: _resource_record_pre_action 02:50:33 INFO - Running main action method: download_and_extract 02:50:33 INFO - mkdir: /builds/slave/test/build/tests 02:50:33 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')]} 02:50:33 INFO - trying https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.test_packages.json 02:50:33 INFO - Downloading https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:50:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:50:33 INFO - Downloaded 989 bytes. 02:50:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:50:33 INFO - Using the following test package requirements: 02:50:33 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:50:33 INFO - u'common': [u'target.common.tests.zip'], 02:50:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:50:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:50:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:50:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:50:33 INFO - u'mozbase': [u'target.common.tests.zip'], 02:50:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:50:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:50:33 INFO - u'web-platform': [u'target.common.tests.zip', 02:50:33 INFO - u'target.web-platform.tests.tar.gz'], 02:50:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:50:33 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:50:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.common.tests.zip 02:50:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:50:33 INFO - Fetch https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.common.tests.zip into memory 02:50:34 INFO - Content-Length response header: 55022295 02:50:34 INFO - Bytes received: 55022295 02:50:45 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:50:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.common.tests.zip 02:50:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:50:45 INFO - Fetch https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.common.tests.zip into memory 02:50:47 INFO - Content-Length response header: 55022295 02:50:47 INFO - Bytes received: 55022295 02:50:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.talos.tests.zip 02:50:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:50:58 INFO - Fetch https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.talos.tests.zip into memory 02:50:59 INFO - Content-Length response header: 14496650 02:50:59 INFO - Bytes received: 14496650 02:50:59 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')]} 02:50:59 INFO - trying https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.tar.bz2 02:50:59 INFO - Downloading https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:50:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:51:00 INFO - Downloaded 62640288 bytes. 02:51:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.tar.bz2 02:51:00 INFO - mkdir: /builds/slave/test/properties 02:51:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:51:00 INFO - Writing to file /builds/slave/test/properties/build_url 02:51:00 INFO - Contents: 02:51:00 INFO - build_url:https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.tar.bz2 02:51:00 INFO - Running post-action listener: _resource_record_post_action 02:51:00 INFO - Running post-action listener: find_tests_for_verification 02:51:00 INFO - Running post-action listener: set_extra_try_arguments 02:51:00 INFO - [mozharness: 2017-09-02 09:51:00.955306Z] Finished download-and-extract step (success) 02:51:00 INFO - [mozharness: 2017-09-02 09:51:00.955403Z] Running populate-webroot step. 02:51:00 INFO - Running pre-action listener: _resource_record_pre_action 02:51:00 INFO - Running main action method: populate_webroot 02:51:00 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:51:00 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:51:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:51:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:51:00 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:51:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:51:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:51:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:51:00 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:51:00 INFO - 'talos_options': ['--enable-stylo'], 02:51:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:51:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:51:00 INFO - 'tests': ['damp', 'tps']}, 02:51:00 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:51:00 INFO - 'talos_options': ['--enable-stylo'], 02:51:00 INFO - 'tests': ['damp', 'tps']}, 02:51:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:51:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:51:00 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:51:00 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:51:00 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:51:00 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:51:00 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:51:00 INFO - 'talos_options': ['--enable-stylo'], 02:51:00 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:51:00 INFO - 'other-e10s': {'tests': ['a11yr', 02:51:00 INFO - 'ts_paint', 02:51:00 INFO - 'tpaint', 02:51:00 INFO - 'sessionrestore', 02:51:00 INFO - 'sessionrestore_many_windows', 02:51:00 INFO - 'sessionrestore_no_auto_restore', 02:51:00 INFO - 'tabpaint']}, 02:51:00 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:51:00 INFO - 'tests': ['a11yr', 02:51:00 INFO - 'ts_paint', 02:51:00 INFO - 'tpaint', 02:51:00 INFO - 'sessionrestore', 02:51:00 INFO - 'sessionrestore_many_windows', 02:51:00 INFO - 'sessionrestore_no_auto_restore', 02:51:00 INFO - 'tabpaint']}, 02:51:00 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:51:00 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:51:00 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:51:00 INFO - 'tests': ['bloom_basic_singleton']}, 02:51:00 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:51:00 INFO - 'tests': ['bloom_basic']}, 02:51:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:51:00 INFO - 'tsvgr_opacity', 02:51:00 INFO - 'tart', 02:51:00 INFO - 'tscrollx', 02:51:00 INFO - 'tsvg_static']}, 02:51:00 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:51:00 INFO - 'tests': ['tsvgx', 02:51:00 INFO - 'tsvgr_opacity', 02:51:00 INFO - 'tart', 02:51:00 INFO - 'tscrollx', 02:51:00 INFO - 'tsvg_static']}, 02:51:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:51:00 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:51:00 INFO - 'talos_options': ['--enable-stylo'], 02:51:00 INFO - 'tests': ['tp5o']}, 02:51:00 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:51:00 INFO - 'talos_options': ['--mitmproxy', 02:51:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:51:00 INFO - '--firstNonBlankPaint'], 02:51:00 INFO - 'tests': ['tp6_google', 02:51:00 INFO - 'tp6_youtube', 02:51:00 INFO - 'tp6_amazon', 02:51:00 INFO - 'tp6_facebook']}, 02:51:00 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:51:00 INFO - 'talos_options': ['--enable-stylo', 02:51:00 INFO - '--mitmproxy', 02:51:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:51:00 INFO - '--firstNonBlankPaint'], 02:51:00 INFO - 'tests': ['tp6_google', 02:51:00 INFO - 'tp6_youtube', 02:51:00 INFO - 'tp6_amazon', 02:51:00 INFO - 'tp6_facebook']}, 02:51:00 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:51:00 INFO - 'talos_options': ['--enable-stylo', 02:51:00 INFO - '--stylo-threads=1', 02:51:00 INFO - '--mitmproxy', 02:51:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:51:00 INFO - '--firstNonBlankPaint'], 02:51:00 INFO - 'tests': ['tp6_google', 02:51:00 INFO - 'tp6_youtube', 02:51:00 INFO - 'tp6_amazon', 02:51:00 INFO - 'tp6_facebook']}, 02:51:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:51:00 INFO - 'talos_options': ['--xperf_path', 02:51:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:51:00 INFO - 'tests': ['tp5n']}, 02:51:00 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:51:00 INFO - 'talos_options': ['--enable-stylo', 02:51:00 INFO - '--xperf_path', 02:51:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:51:00 INFO - 'tests': ['tp5n']}}} 02:51:00 INFO - Running post-action listener: _resource_record_post_action 02:51:00 INFO - [mozharness: 2017-09-02 09:51:00.969610Z] Finished populate-webroot step (success) 02:51:00 INFO - [mozharness: 2017-09-02 09:51:00.969698Z] Running create-virtualenv step. 02:51:00 INFO - Running pre-action listener: _resource_record_pre_action 02:51:00 INFO - Running main action method: create_virtualenv 02:51:00 INFO - Creating virtualenv /builds/slave/test/build/venv 02:51:00 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:51:00 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:51:00 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:51:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:51:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:51:01 INFO - Installing distribute.............................................................................................................................................................................................done. 02:51:01 INFO - Installing pip...............done. 02:51:01 INFO - Return code: 0 02:51:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:51:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:51:01 INFO - Reading from file tmpfile_stdout 02:51:01 INFO - Output received: 02:51:01 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:51:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:51: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')]} 02:51:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51: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')]} 02:51:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51: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 0xb7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeea090>, '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 0xeead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee4f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', '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 02:51: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 02:51: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 02:51:01 INFO - Using env: {'DISPLAY': ':0', 02:51:01 INFO - 'HOME': '/home/cltbld', 02:51:01 INFO - 'LANG': 'en_US.UTF-8', 02:51:01 INFO - 'LANGUAGE': 'en_US:en', 02:51:01 INFO - 'LOGNAME': 'cltbld', 02:51:01 INFO - 'MAIL': '/var/mail/cltbld', 02:51:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:01 INFO - 'MOZ_NO_REMOTE': '1', 02:51:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:51:01 INFO - 'NO_EM_RESTART': '1', 02:51:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:51:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:01 INFO - 'PWD': '/builds/slave/test', 02:51:01 INFO - 'SHELL': '/bin/bash', 02:51:01 INFO - 'SHLVL': '1', 02:51:01 INFO - 'TERM': 'linux', 02:51:01 INFO - 'TMOUT': '86400', 02:51:01 INFO - 'USER': 'cltbld', 02:51:01 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', 02:51:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:01 INFO - '_': '/tools/buildbot/bin/python'} 02:51:03 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:51:03 INFO - Downloading/unpacking pip>=1.5 02:51:03 INFO - Running setup.py egg_info for package pip 02:51:03 INFO - warning: no files found matching 'pip/cacert.pem' 02:51:03 INFO - warning: no files found matching '*.html' under directory 'docs' 02:51:03 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:51:03 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:51:03 INFO - Installing collected packages: pip 02:51:03 INFO - Found existing installation: pip 1.1 02:51:03 INFO - Uninstalling pip: 02:51:03 INFO - Successfully uninstalled pip 02:51:03 INFO - Running setup.py install for pip 02:51:03 INFO - warning: no files found matching 'pip/cacert.pem' 02:51:03 INFO - warning: no files found matching '*.html' under directory 'docs' 02:51:03 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:51:03 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:51:03 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:51:03 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:51:03 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:51:03 INFO - Successfully installed pip 02:51:03 INFO - Cleaning up... 02:51:03 INFO - Return code: 0 02:51:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:51:03 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')]} 02:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51:03 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')]} 02:51:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51:03 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 0xb7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeea090>, '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 0xeead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee4f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', '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 02:51:03 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 02:51:03 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 02:51:03 INFO - Using env: {'DISPLAY': ':0', 02:51:03 INFO - 'HOME': '/home/cltbld', 02:51:03 INFO - 'LANG': 'en_US.UTF-8', 02:51:03 INFO - 'LANGUAGE': 'en_US:en', 02:51:03 INFO - 'LOGNAME': 'cltbld', 02:51:03 INFO - 'MAIL': '/var/mail/cltbld', 02:51:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:03 INFO - 'MOZ_NO_REMOTE': '1', 02:51:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:51:03 INFO - 'NO_EM_RESTART': '1', 02:51:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:51:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:03 INFO - 'PWD': '/builds/slave/test', 02:51:03 INFO - 'SHELL': '/bin/bash', 02:51:03 INFO - 'SHLVL': '1', 02:51:03 INFO - 'TERM': 'linux', 02:51:03 INFO - 'TMOUT': '86400', 02:51:03 INFO - 'USER': 'cltbld', 02:51:03 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', 02:51:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:03 INFO - '_': '/tools/buildbot/bin/python'} 02:51:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:51:03 INFO - Downloading/unpacking psutil>=3.1.1 02:51:03 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 02:51:03 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 02:51:03 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 02:51:03 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 02:51:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:51:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:51:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:51:04 INFO - Installing collected packages: psutil 02:51:04 INFO - Running setup.py install for psutil 02:51:04 INFO - building 'psutil._psutil_linux' extension 02:51:04 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 02:51: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 02:51:04 INFO - building 'psutil._psutil_posix' extension 02:51: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 02:51:05 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 02:51:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:51:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:51:05 INFO - Successfully installed psutil 02:51:05 INFO - Cleaning up... 02:51:05 INFO - Return code: 0 02:51:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:51: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')]} 02:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51: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')]} 02:51:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeea090>, '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 0xeead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee4f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', '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 02:51: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:51: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 mozsystemmonitor==0.3 02:51:05 INFO - Using env: {'DISPLAY': ':0', 02:51:05 INFO - 'HOME': '/home/cltbld', 02:51:05 INFO - 'LANG': 'en_US.UTF-8', 02:51:05 INFO - 'LANGUAGE': 'en_US:en', 02:51:05 INFO - 'LOGNAME': 'cltbld', 02:51:05 INFO - 'MAIL': '/var/mail/cltbld', 02:51:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:05 INFO - 'MOZ_NO_REMOTE': '1', 02:51:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:51:05 INFO - 'NO_EM_RESTART': '1', 02:51:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:51:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:05 INFO - 'PWD': '/builds/slave/test', 02:51:05 INFO - 'SHELL': '/bin/bash', 02:51:05 INFO - 'SHLVL': '1', 02:51:05 INFO - 'TERM': 'linux', 02:51:05 INFO - 'TMOUT': '86400', 02:51:05 INFO - 'USER': 'cltbld', 02:51:05 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', 02:51:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:05 INFO - '_': '/tools/buildbot/bin/python'} 02:51:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:51:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:51:05 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 02:51:05 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 02:51:05 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 02:51:05 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 02:51:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:51:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:51:06 INFO - Installing collected packages: mozsystemmonitor 02:51:06 INFO - Running setup.py install for mozsystemmonitor 02:51:06 INFO - Successfully installed mozsystemmonitor 02:51:06 INFO - Cleaning up... 02:51:06 INFO - Return code: 0 02:51:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:51:06 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')]} 02:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51:06 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')]} 02:51:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51:06 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 0xb7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeea090>, '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 0xeead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee4f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', '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 02:51:06 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 02:51:06 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 02:51:06 INFO - Using env: {'DISPLAY': ':0', 02:51:06 INFO - 'HOME': '/home/cltbld', 02:51:06 INFO - 'LANG': 'en_US.UTF-8', 02:51:06 INFO - 'LANGUAGE': 'en_US:en', 02:51:06 INFO - 'LOGNAME': 'cltbld', 02:51:06 INFO - 'MAIL': '/var/mail/cltbld', 02:51:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:06 INFO - 'MOZ_NO_REMOTE': '1', 02:51:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:51:06 INFO - 'NO_EM_RESTART': '1', 02:51:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:51:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:06 INFO - 'PWD': '/builds/slave/test', 02:51:06 INFO - 'SHELL': '/bin/bash', 02:51:06 INFO - 'SHLVL': '1', 02:51:06 INFO - 'TERM': 'linux', 02:51:06 INFO - 'TMOUT': '86400', 02:51:06 INFO - 'USER': 'cltbld', 02:51:06 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', 02:51:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:06 INFO - '_': '/tools/buildbot/bin/python'} 02:51:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:51:06 INFO - Downloading/unpacking jsonschema==2.5.1 02:51: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 02:51: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 02:51: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 02:51: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 02:51:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:51:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:51: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 02:51: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 02:51: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 02:51: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 02:51:08 INFO - Downloading functools32-3.2.3-2.tar.gz 02:51:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:51:08 INFO - warning: no files found matching '*.txt' 02:51:08 INFO - no previously-included directories found matching 'build' 02:51:08 INFO - no previously-included directories found matching 'dist' 02:51:08 INFO - no previously-included directories found matching '.git*' 02:51:08 INFO - Installing collected packages: jsonschema, functools32 02:51:08 INFO - Running setup.py install for functools32 02:51:08 INFO - warning: no files found matching '*.txt' 02:51:08 INFO - no previously-included directories found matching 'build' 02:51:08 INFO - no previously-included directories found matching 'dist' 02:51:08 INFO - no previously-included directories found matching '.git*' 02:51:08 INFO - Successfully installed jsonschema functools32 02:51:08 INFO - Cleaning up... 02:51:08 INFO - Return code: 0 02:51:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:51:08 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')]} 02:51:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51:08 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')]} 02:51:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51:08 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 0xb7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeea090>, '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 0xeead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee4f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', '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 02:51:08 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 02:51:08 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 02:51:08 INFO - Using env: {'DISPLAY': ':0', 02:51:08 INFO - 'HOME': '/home/cltbld', 02:51:08 INFO - 'LANG': 'en_US.UTF-8', 02:51:08 INFO - 'LANGUAGE': 'en_US:en', 02:51:08 INFO - 'LOGNAME': 'cltbld', 02:51:08 INFO - 'MAIL': '/var/mail/cltbld', 02:51:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:08 INFO - 'MOZ_NO_REMOTE': '1', 02:51:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:51:08 INFO - 'NO_EM_RESTART': '1', 02:51:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:51:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:08 INFO - 'PWD': '/builds/slave/test', 02:51:08 INFO - 'SHELL': '/bin/bash', 02:51:08 INFO - 'SHLVL': '1', 02:51:08 INFO - 'TERM': 'linux', 02:51:08 INFO - 'TMOUT': '86400', 02:51:08 INFO - 'USER': 'cltbld', 02:51:08 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', 02:51:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:08 INFO - '_': '/tools/buildbot/bin/python'} 02:51:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:51:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:51:08 INFO - Cleaning up... 02:51:08 INFO - Return code: 0 02:51:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:51:08 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')]} 02:51:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51:08 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')]} 02:51:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51:08 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 0xb7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeea090>, '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 0xeead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee4f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', '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 02:51:08 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 02:51:08 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 02:51:08 INFO - Using env: {'DISPLAY': ':0', 02:51:08 INFO - 'HOME': '/home/cltbld', 02:51:08 INFO - 'LANG': 'en_US.UTF-8', 02:51:08 INFO - 'LANGUAGE': 'en_US:en', 02:51:08 INFO - 'LOGNAME': 'cltbld', 02:51:08 INFO - 'MAIL': '/var/mail/cltbld', 02:51:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:08 INFO - 'MOZ_NO_REMOTE': '1', 02:51:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:51:08 INFO - 'NO_EM_RESTART': '1', 02:51:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:51:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:08 INFO - 'PWD': '/builds/slave/test', 02:51:08 INFO - 'SHELL': '/bin/bash', 02:51:08 INFO - 'SHLVL': '1', 02:51:08 INFO - 'TERM': 'linux', 02:51:08 INFO - 'TMOUT': '86400', 02:51:08 INFO - 'USER': 'cltbld', 02:51:08 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', 02:51:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:08 INFO - '_': '/tools/buildbot/bin/python'} 02:51:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:51:08 INFO - Downloading/unpacking blobuploader==1.2.4 02:51:08 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 02:51:08 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 02:51:08 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 02:51:08 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 02:51:09 INFO - Downloading blobuploader-1.2.4.tar.gz 02:51:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:51:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:51: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 02:51: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 02:51: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 02:51: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 02:51:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:51:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:51:10 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 02:51:10 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 02:51:10 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 02:51:10 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 02:51:10 INFO - Downloading docopt-0.6.1.tar.gz 02:51:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:51:10 INFO - Installing collected packages: blobuploader, requests, docopt 02:51:10 INFO - Running setup.py install for blobuploader 02:51:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:51:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:51:10 INFO - Running setup.py install for requests 02:51:10 INFO - Running setup.py install for docopt 02:51:11 INFO - Successfully installed blobuploader requests docopt 02:51:11 INFO - Cleaning up... 02:51:11 INFO - Return code: 0 02:51:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:51:11 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')]} 02:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51:11 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')]} 02:51:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51:11 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 0xb7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeea090>, '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 0xeead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee4f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', '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 02:51:11 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 02:51:11 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 02:51:11 INFO - Using env: {'DISPLAY': ':0', 02:51:11 INFO - 'HOME': '/home/cltbld', 02:51:11 INFO - 'LANG': 'en_US.UTF-8', 02:51:11 INFO - 'LANGUAGE': 'en_US:en', 02:51:11 INFO - 'LOGNAME': 'cltbld', 02:51:11 INFO - 'MAIL': '/var/mail/cltbld', 02:51:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:11 INFO - 'MOZ_NO_REMOTE': '1', 02:51:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:51:11 INFO - 'NO_EM_RESTART': '1', 02:51:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:51:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:11 INFO - 'PWD': '/builds/slave/test', 02:51:11 INFO - 'SHELL': '/bin/bash', 02:51:11 INFO - 'SHLVL': '1', 02:51:11 INFO - 'TERM': 'linux', 02:51:11 INFO - 'TMOUT': '86400', 02:51:11 INFO - 'USER': 'cltbld', 02:51:11 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', 02:51:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:11 INFO - '_': '/tools/buildbot/bin/python'} 02:51:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:51:11 INFO - Running setup.py (path:/tmp/pip-iQS8Cy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:51:11 INFO - Running setup.py (path:/tmp/pip-dTGLCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:51:11 INFO - Running setup.py (path:/tmp/pip-SYNU2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:51:11 INFO - Running setup.py (path:/tmp/pip-tHXO9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:51:11 INFO - Running setup.py (path:/tmp/pip-KBzlKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:51:11 INFO - Running setup.py (path:/tmp/pip-cDTDp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:51:11 INFO - Running setup.py (path:/tmp/pip-0KxgrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:51:11 INFO - Running setup.py (path:/tmp/pip-mE0kKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:51:11 INFO - Running setup.py (path:/tmp/pip-5cF7Si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:51:11 INFO - Running setup.py (path:/tmp/pip-Hd46bd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:51:11 INFO - Running setup.py (path:/tmp/pip-W9C5ej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:51:12 INFO - Running setup.py (path:/tmp/pip-VCmia1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:51:12 INFO - Running setup.py (path:/tmp/pip-HIZari-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:51:12 INFO - Running setup.py (path:/tmp/pip-mzbH7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:51:12 INFO - Running setup.py (path:/tmp/pip-4dCvly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:51:12 INFO - Running setup.py (path:/tmp/pip-yPDteP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:51:12 INFO - Running setup.py (path:/tmp/pip-MXhu5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:51:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:51:12 INFO - Running setup.py install for manifestparser 02:51:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:51:12 INFO - Running setup.py install for mozcrash 02:51:12 INFO - Running setup.py install for mozdebug 02:51:12 INFO - Running setup.py install for mozdevice 02:51:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:51:12 INFO - Running setup.py install for mozfile 02:51:12 INFO - Running setup.py install for mozhttpd 02:51:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:51:13 INFO - Running setup.py install for mozinfo 02:51:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:51:13 INFO - Running setup.py install for mozInstall 02:51:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:51:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:51:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:51:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:51:13 INFO - Running setup.py install for mozleak 02:51:13 INFO - Running setup.py install for mozlog 02:51:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:51:13 INFO - Running setup.py install for moznetwork 02:51:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:51:13 INFO - Running setup.py install for mozprocess 02:51:13 INFO - Running setup.py install for mozprofile 02:51:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:51:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:51:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:51:13 INFO - Running setup.py install for mozrunner 02:51:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:51:13 INFO - Running setup.py install for mozscreenshot 02:51:14 INFO - Running setup.py install for moztest 02:51:14 INFO - Running setup.py install for mozversion 02:51:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:51:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:51:14 INFO - Cleaning up... 02:51:14 INFO - Return code: 0 02:51:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:51:14 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')]} 02:51:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51:14 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')]} 02:51:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51:14 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 0xb7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeea090>, '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 0xeead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee4f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', '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 02:51:14 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 02:51:14 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 02:51:14 INFO - Using env: {'DISPLAY': ':0', 02:51:14 INFO - 'HOME': '/home/cltbld', 02:51:14 INFO - 'LANG': 'en_US.UTF-8', 02:51:14 INFO - 'LANGUAGE': 'en_US:en', 02:51:14 INFO - 'LOGNAME': 'cltbld', 02:51:14 INFO - 'MAIL': '/var/mail/cltbld', 02:51:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:14 INFO - 'MOZ_NO_REMOTE': '1', 02:51:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:51:14 INFO - 'NO_EM_RESTART': '1', 02:51:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:51:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:14 INFO - 'PWD': '/builds/slave/test', 02:51:14 INFO - 'SHELL': '/bin/bash', 02:51:14 INFO - 'SHLVL': '1', 02:51:14 INFO - 'TERM': 'linux', 02:51:14 INFO - 'TMOUT': '86400', 02:51:14 INFO - 'USER': 'cltbld', 02:51:14 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', 02:51:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:14 INFO - '_': '/tools/buildbot/bin/python'} 02:51:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:51:14 INFO - Running setup.py (path:/tmp/pip-d2XjWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:51:14 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)) 02:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:51:14 INFO - Running setup.py (path:/tmp/pip-EI3N6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:51:14 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)) 02:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:51:14 INFO - Running setup.py (path:/tmp/pip-Ykhti2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:51:14 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)) 02:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:51:14 INFO - Running setup.py (path:/tmp/pip-Dla906-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:51:14 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)) 02:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:51:14 INFO - Running setup.py (path:/tmp/pip-YQSdlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:51: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)) 02:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:51:14 INFO - Running setup.py (path:/tmp/pip-yxCo5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:51: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)) 02:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:51:14 INFO - Running setup.py (path:/tmp/pip-ag7OGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:51: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)) 02:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:51:14 INFO - Running setup.py (path:/tmp/pip-lwMY_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:51:15 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)) 02:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:51:15 INFO - Running setup.py (path:/tmp/pip-O95z9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:51:15 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)) 02:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:51:15 INFO - Running setup.py (path:/tmp/pip-UFjBKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:51:15 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)) 02:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:51:15 INFO - Running setup.py (path:/tmp/pip-aZEiUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:51:15 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)) 02:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:51:15 INFO - Running setup.py (path:/tmp/pip-lDYmUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:51:15 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)) 02:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:51:15 INFO - Running setup.py (path:/tmp/pip-uerZso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:51:15 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)) 02:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:51:15 INFO - Running setup.py (path:/tmp/pip-pNjpJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:51:15 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)) 02:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:51:15 INFO - Running setup.py (path:/tmp/pip-wvNHQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:51:15 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)) 02:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:51:15 INFO - Running setup.py (path:/tmp/pip-oCV_fX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:51:15 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)) 02:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:51:15 INFO - Running setup.py (path:/tmp/pip-dPIWhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:51:15 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)) 02:51:15 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)) 02:51:15 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)) 02:51:15 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)) 02:51:15 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)) 02:51:15 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)) 02:51:15 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)) 02:51:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:51:15 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 02:51:15 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 02:51:15 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 02:51:15 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 02:51:16 INFO - Downloading blessings-1.6.tar.gz 02:51:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:51:16 INFO - Installing collected packages: blessings 02:51:16 INFO - Running setup.py install for blessings 02:51:16 INFO - Successfully installed blessings 02:51:16 INFO - Cleaning up... 02:51:17 INFO - Return code: 0 02:51:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:51:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:51:17 INFO - Reading from file tmpfile_stdout 02:51:17 INFO - Current package versions: 02:51:17 INFO - argparse == 1.2.1 02:51:17 INFO - blessings == 1.6 02:51:17 INFO - blobuploader == 1.2.4 02:51:17 INFO - docopt == 0.6.1 02:51:17 INFO - functools32 == 3.2.3-2 02:51:17 INFO - jsonschema == 2.5.1 02:51:17 INFO - manifestparser == 1.1 02:51:17 INFO - mozInstall == 1.13 02:51:17 INFO - mozcrash == 1.0 02:51:17 INFO - mozdebug == 0.1 02:51:17 INFO - mozdevice == 0.51 02:51:17 INFO - mozfile == 1.2 02:51:17 INFO - mozhttpd == 0.7 02:51:17 INFO - mozinfo == 0.10 02:51:17 INFO - mozleak == 0.1 02:51:17 INFO - mozlog == 3.5 02:51:17 INFO - moznetwork == 0.27 02:51:17 INFO - mozprocess == 0.25 02:51:17 INFO - mozprofile == 0.28 02:51:17 INFO - mozrunner == 6.13 02:51:17 INFO - mozscreenshot == 0.1 02:51:17 INFO - mozsystemmonitor == 0.3 02:51:17 INFO - moztest == 0.8 02:51:17 INFO - mozversion == 1.4 02:51:17 INFO - psutil == 3.1.1 02:51:17 INFO - requests == 1.2.3 02:51:17 INFO - wsgiref == 0.1.2 02:51:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:51:17 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')]} 02:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51:17 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')]} 02:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51:17 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 0xb7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeea090>, '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 0xeead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee4f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', '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 02:51:17 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 02:51:17 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 02:51:17 INFO - Using env: {'DISPLAY': ':0', 02:51:17 INFO - 'HOME': '/home/cltbld', 02:51:17 INFO - 'LANG': 'en_US.UTF-8', 02:51:17 INFO - 'LANGUAGE': 'en_US:en', 02:51:17 INFO - 'LOGNAME': 'cltbld', 02:51:17 INFO - 'MAIL': '/var/mail/cltbld', 02:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:17 INFO - 'MOZ_NO_REMOTE': '1', 02:51:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:51:17 INFO - 'NO_EM_RESTART': '1', 02:51:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:17 INFO - 'PWD': '/builds/slave/test', 02:51:17 INFO - 'SHELL': '/bin/bash', 02:51:17 INFO - 'SHLVL': '1', 02:51:17 INFO - 'TERM': 'linux', 02:51:17 INFO - 'TMOUT': '86400', 02:51:17 INFO - 'USER': 'cltbld', 02:51:17 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', 02:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:17 INFO - '_': '/tools/buildbot/bin/python'} 02:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:51:17 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)) 02:51:17 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)) 02:51:17 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)) 02:51:17 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)) 02:51:17 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)) 02:51:17 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)) 02:51:17 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)) 02:51:17 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)) 02:51:17 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)) 02:51:17 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:51:17 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 02:51:17 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 02:51:17 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 02:51:17 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 02:51:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:51:18 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)) 02:51:18 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)) 02:51:18 INFO - Installing collected packages: simplejson 02:51:18 INFO - Running setup.py install for simplejson 02:51:18 INFO - building 'simplejson._speedups' extension 02:51:18 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 02:51:19 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 02:51:19 INFO - Successfully installed simplejson 02:51:19 INFO - Cleaning up... 02:51:19 INFO - Return code: 0 02:51:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:51:19 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')]} 02:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51:19 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')]} 02:51:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51:19 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 0xb7a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd43a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeea090>, '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 0xeead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee4f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', '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 02:51:19 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 02:51:19 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 02:51:19 INFO - Using env: {'DISPLAY': ':0', 02:51:19 INFO - 'HOME': '/home/cltbld', 02:51:19 INFO - 'LANG': 'en_US.UTF-8', 02:51:19 INFO - 'LANGUAGE': 'en_US:en', 02:51:19 INFO - 'LOGNAME': 'cltbld', 02:51:19 INFO - 'MAIL': '/var/mail/cltbld', 02:51:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:19 INFO - 'MOZ_NO_REMOTE': '1', 02:51:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:51:19 INFO - 'NO_EM_RESTART': '1', 02:51:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:51:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:19 INFO - 'PWD': '/builds/slave/test', 02:51:19 INFO - 'SHELL': '/bin/bash', 02:51:19 INFO - 'SHLVL': '1', 02:51:19 INFO - 'TERM': 'linux', 02:51:19 INFO - 'TMOUT': '86400', 02:51:19 INFO - 'USER': 'cltbld', 02:51:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', 02:51:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:19 INFO - '_': '/tools/buildbot/bin/python'} 02:51:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:51:19 INFO - Cleaning up... 02:51:19 INFO - Return code: 0 02:51:19 INFO - Running post-action listener: _resource_record_post_action 02:51:19 INFO - Running post-action listener: _start_resource_monitoring 02:51:19 INFO - Starting resource monitoring. 02:51:19 INFO - [mozharness: 2017-09-02 09:51:19.932592Z] Finished create-virtualenv step (success) 02:51:19 INFO - [mozharness: 2017-09-02 09:51:19.933066Z] Running install step. 02:51:19 INFO - Running pre-action listener: _resource_record_pre_action 02:51:19 INFO - Running main action method: install 02:51:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:51:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:51:20 INFO - Reading from file tmpfile_stdout 02:51:20 INFO - Detecting whether we're running mozinstall >=1.0... 02:51:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:51:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:51:20 INFO - Reading from file tmpfile_stdout 02:51:20 INFO - Output received: 02:51:20 INFO - Usage: mozinstall [options] installer 02:51:20 INFO - Options: 02:51:20 INFO - -h, --help show this help message and exit 02:51:20 INFO - -d DEST, --destination=DEST 02:51:20 INFO - Directory to install application into. [default: 02:51:20 INFO - "/builds/slave/test"] 02:51:20 INFO - --app=APP Application being installed. [default: firefox] 02:51:20 INFO - mkdir: /builds/slave/test/build/application 02:51:20 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'] 02:51:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:51:37 INFO - Reading from file tmpfile_stdout 02:51:37 INFO - Output received: 02:51:37 INFO - /builds/slave/test/build/application/firefox/firefox 02:51:37 INFO - Running post-action listener: _resource_record_post_action 02:51:37 INFO - [mozharness: 2017-09-02 09:51:37.131729Z] Finished install step (success) 02:51:37 INFO - [mozharness: 2017-09-02 09:51:37.131975Z] Running setup-mitmproxy step. 02:51:37 INFO - Running pre-action listener: _resource_record_pre_action 02:51:37 INFO - Running main action method: setup_mitmproxy 02:51:37 INFO - Skipping: mitmproxy is not required 02:51:37 INFO - Running post-action listener: _resource_record_post_action 02:51:37 INFO - [mozharness: 2017-09-02 09:51:37.133074Z] Finished setup-mitmproxy step (success) 02:51:37 INFO - [mozharness: 2017-09-02 09:51:37.133295Z] Running run-tests step. 02:51:37 INFO - Running pre-action listener: _resource_record_pre_action 02:51:37 INFO - Running pre-action listener: _set_gcov_prefix 02:51:37 INFO - Running main action method: run_tests 02:51:37 WARNING - Try message not found. 02:51:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:51:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:51:37 INFO - Python 2.7.3 02:51:37 INFO - Return code: 0 02:51:37 INFO - grabbing minidump binary from tooltool 02:51: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')]} 02:51:37 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0xeea090>, '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 0xeead80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xee4f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:51:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 02:51:37 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 02:51:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 02:51:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:51:37 INFO - Return code: 0 02:51:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:51:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:51:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:51:37 INFO - ENV: RUST_BACKTRACE is now full 02:51:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:51:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:51:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 02:51:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 02:51:37 INFO - Using env: {'DISPLAY': ':0', 02:51:37 INFO - 'HOME': '/home/cltbld', 02:51:37 INFO - 'LANG': 'en_US.UTF-8', 02:51:37 INFO - 'LANGUAGE': 'en_US:en', 02:51:37 INFO - 'LOGNAME': 'cltbld', 02:51:37 INFO - 'MAIL': '/var/mail/cltbld', 02:51:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:51:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:51:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:37 INFO - 'MOZ_NO_REMOTE': '1', 02:51:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:51:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:51:37 INFO - 'NO_EM_RESTART': '1', 02:51:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:51:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:37 INFO - 'PWD': '/builds/slave/test', 02:51:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:51:37 INFO - 'RUST_BACKTRACE': 'full', 02:51:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:51:37 INFO - 'SHELL': '/bin/bash', 02:51:37 INFO - 'SHLVL': '1', 02:51:37 INFO - 'STYLO_FORCE_ENABLED': '1', 02:51:37 INFO - 'STYLO_THREADS': '1', 02:51:37 INFO - 'TERM': 'linux', 02:51:37 INFO - 'TMOUT': '86400', 02:51:37 INFO - 'USER': 'cltbld', 02:51:37 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', 02:51:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:37 INFO - '_': '/tools/buildbot/bin/python'} 02:51:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 02:51:37 INFO - mozversion application_buildid: 20170902085613 02:51:37 INFO - mozversion application_changeset: a46a5879b8781ae9ea99f37b5d34a891f0f75047 02:51:37 INFO - mozversion application_display_name: Nightly 02:51:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:51:37 INFO - mozversion application_name: Firefox 02:51:37 INFO - mozversion application_remotingname: firefox 02:51:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:51:37 INFO - mozversion application_vendor: Mozilla 02:51:37 INFO - mozversion application_version: 57.0a1 02:51:37 INFO - mozversion platform_buildid: 20170902085613 02:51:37 INFO - mozversion platform_changeset: a46a5879b8781ae9ea99f37b5d34a891f0f75047 02:51:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:51:37 INFO - mozversion platform_version: 57.0a1 02:51:37 INFO - using testdate: 1504345897 02:51:37 INFO - actual date: 1504345897 02:51:37 INFO - starting webserver on 'localhost:56536' 02:51:37 INFO - SUITE-START | Running 2 tests 02:51:37 INFO - TEST-START | dromaeo_css 02:51:37 INFO - Initialising browser for dromaeo_css test... 02:51:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56536/getInfo.html -profile /tmp/tmpZEpFdC/profile 02:51:37 INFO - TEST-INFO | started process 8763 (/builds/slave/test/build/application/firefox/firefox http://localhost:56536/getInfo.html) 02:51:43 INFO - TEST-INFO | 8763: exit 0 02:51:43 INFO - Browser initialized. 02:51:43 INFO - Running cycle 1/1 for dromaeo_css test... 02:51:43 INFO - Using env: {'DISPLAY': ':0', 02:51:43 INFO - 'HOME': '/home/cltbld', 02:51:43 INFO - 'JSGC_DISABLE_POISONING': '1', 02:51:43 INFO - 'LANG': 'en_US.UTF-8', 02:51:43 INFO - 'LANGUAGE': 'en_US:en', 02:51:43 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:51:43 INFO - 'LOGNAME': 'cltbld', 02:51:43 INFO - 'MAIL': '/var/mail/cltbld', 02:51:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:51:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:51:43 INFO - 'MOZ_CRASHREPORTER': '1', 02:51:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:43 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:51:43 INFO - 'MOZ_NO_REMOTE': '1', 02:51:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:51:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:51:43 INFO - 'NO_EM_RESTART': '1', 02:51:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:51:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:43 INFO - 'PWD': '/builds/slave/test', 02:51:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:51:43 INFO - 'RUST_BACKTRACE': 'full', 02:51:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:51:43 INFO - 'SHELL': '/bin/bash', 02:51:43 INFO - 'SHLVL': '1', 02:51:43 INFO - 'STYLO_FORCE_ENABLED': '1', 02:51:43 INFO - 'STYLO_THREADS': '1', 02:51:43 INFO - 'TERM': 'linux', 02:51:43 INFO - 'TMOUT': '86400', 02:51:43 INFO - 'USER': 'cltbld', 02:51:43 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', 02:51:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:43 INFO - '_': '/tools/buildbot/bin/python'} 02:51:43 INFO - TEST-INFO | started process 8947 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZEpFdC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:51:44 INFO - PID 8947 | 02:51:44 INFO - PID 8947 | (/builds/slave/test/build/application/firefox/firefox:8999): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:51:44 INFO - PID 8947 | 02:51:54 INFO - PID 8947 | RSS: Main: 170323968 02:51:54 INFO - PID 8947 | 02:54:53 INFO - PID 8947 | Cycle 1(1): loaded http://localhost:56536/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56536/tests/dromaeo/cssquery-ext.html) 02:54:54 INFO - PID 8947 | RSS: Main: 173674496 02:54:54 INFO - PID 8947 | 02:57:56 INFO - PID 8947 | Cycle 1(1): loaded http://localhost:56536/tests/dromaeo/cssquery-ext.html (next: http://localhost:56536/tests/dromaeo/cssquery-jquery.html) 02:57:56 INFO - PID 8947 | RSS: Main: 170352640 02:57:56 INFO - PID 8947 | 03:00:58 INFO - PID 8947 | Cycle 1(1): loaded http://localhost:56536/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56536/tests/dromaeo/cssquery-mootools.html) 03:00:59 INFO - PID 8947 | RSS: Main: 172163072 03:00:59 INFO - PID 8947 | 03:04:00 INFO - PID 8947 | Cycle 1(1): loaded http://localhost:56536/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56536/tests/dromaeo/cssquery-prototype.html) 03:04:01 INFO - PID 8947 | RSS: Main: 170803200 03:04:01 INFO - PID 8947 | 03:07:05 INFO - PID 8947 | Cycle 1(1): loaded http://localhost:56536/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56536/tests/dromaeo/cssquery-yui.html) 03:07:05 INFO - PID 8947 | RSS: Main: 174391296 03:07:05 INFO - PID 8947 | 03:10:07 INFO - PID 8947 | Cycle 1(1): loaded http://localhost:56536/tests/dromaeo/cssquery-yui.html (next: http://localhost:56536/tests/dromaeo/cssquery-dojo.html) 03:10:07 INFO - PID 8947 | RSS: Main: 174231552 03:10:07 INFO - PID 8947 | 03:10:07 INFO - PID 8947 | __start_tp_report 03:10:07 INFO - PID 8947 | _x_x_mozilla_page_load 03:10:07 INFO - PID 8947 | _x_x_mozilla_page_load_details 03:10:07 INFO - PID 8947 | |i|pagename|runs| 03:10:07 INFO - PID 8947 | |0;dojo.html;3300.5379246905436;3315.237495376062;3343.465045592706;3364.613069497008;3392.3554524640317;6390;6420.518461115412;6450.677466126698;6487.481001519879;6568.277489476166;6315.747370105202;6389.808305750823;6394.040893865922;6395.776147834827;6421.13314702515;5858.238429004234;5859.560532960024;5871.031279838821;5890.263717035371;5931.911021334683;5916.408359164092;5925;5956.851078723023;5968.612040217394;5990;974.9953226575733;5914.674692891889;5967.791127310545;5986.341502434729;6028;5410.455806465543;5812.680302583356;5829.88340233193;5944.494717948979;5950.702464876739;5821.476067153976;5853.912191317134;5901.144334071536;5930.762769489215;5931.703414829241;6311.305756366842;6393.904091438586;6454.419102280817;6496.252930912911;6504.38208370204;6848.280930502276;6851.212110607295;6854.588724676485;6882.483813713991;6965.686544105527;6914.343137401939;6925.757598484029;7001.789946301619;7010.369066783963;7024.964875175591;6268.686565671698;6335;6381.457576106037;6426.068220108058;6461.870762584816;7133.750318738923;7155.141383034069;7156.391706704884;7161.856762864713;7177.6770045348085;6805.863882722417;6831.897521537181;6847.623380714109;6852.246252912176;6852.725890964405;6853.657317134123;6893.689783959734;6920.134983127109;6979.581225126509;6982.022516847645;6704.329567043256;6795.966020169867;6796.93203067963;6807.65961701913;6917.75787847433;6829.282925292773;6856.485763567653;6857.588544687334;6866.107406037183;6884;5054.241863720471;5065.417476990119;5081.2378143278065;5115.7442127893455;5117.181250999822;4448.777561121931;4456.598906098467;4500.437445319335;4500.617447516997;4524.819007239674;6100.633961962297;6104.847378815476;6115.847103822351;6120.265568131853;6124.6325220486915;6891.620960847202;6892.965535172292;6900.7929762107215;6980.197277313073;6994.265602111806;6009.759609615567;6021.187139736167;6030.7286172121485;6076.392360763888;6100.328963813977;5890.764369425261;5944.524438044946;5945.881082378328;5984.042553191469;5989;6641.369069938348;6699.397054265042;6796.796096117026;6801.727930882709;6812.727490900407;6943.51395402317;6961.303869612998;7020.719171233093;7034.155901291878;7060.576365417988;6579.703913323816;6690.565113267622;6705.362990515893;6742.764003259862;6748.325167483213;6778.661066946534;6818.727250909908;6840.0765896603325;6849.965750171217;6851.040854280305;6834.965825171042;6909.2745261746795;7010;7014.0531028310525;7017.4386049116965;6540.182477190351;6624.867502649824;6644.90032649501;6662.400383965466;6755.527113102035;6715.261321254756;6734.22556406;6745.494087943326;6755.831104222434;6763.086983257394;6497.642629655415;6635.601863888182;6670.166229221348;6692.765753198614;6705.09481220029;6687.164104486939;6705.798826035227;6748.662566871539;6773.983902414677;6817.045613613999;6359.8092057238355;6418.743250269937;6482.222133344029;6513.5114866384265;6532.510061745293 03:10:07 INFO - PID 8947 | |1;ext.html;827.1480375213529;840.1472505407011;879.9472031678096;880.6345366672831;1142.6343570057575;30698.84650576747;31179.220519486997;31386.68613313866;41048.58951410485;41877;42051;42249.7887510562;42320.576794232125;42451.36322955158;42551.78724106383;7302.05073340465;7827.686892524306;7931;7965.44241903067;7971.920280797176;7579.772606821804;7631.12242092161;7644.273793989562;7658.425618078639;7672.65473053714;7622.161562228151;7671;7675.194104619019;7679.7312094076715;7683.270089341531;7613.885791713129;7646.770596882103;7679.193684663113;7681.539107653559;7681.884771728429;7810.375169986388;7859.8035049123655;7860.724874629418;7862.410319226081;7865.842683146362;38844.41733373974;38856.22287554261;39049.21901561953;39137.41293880594;39225;3542.769719968194;3576.499290099388;3594.982025089858;3599.928001439983;3602.765820221677;32161.517577236362;32268.838655806572;32282.192945176324;32315.6768432315;32326.676733232838;5269.473052694738;5276.498732620395;5322.33470816148;5329.333833270841;5343.305370301875;180820;181465;181755;182040;182110;49140.75429622829;49573.25640115401;49689.50310496921;49727.75136124369;49739.25391119064;51489.22766158511;51669.7416512915;51708;51739.48260517346;51877;15100.244987750568;15251.161186134646;15258.618534536725;15289.847101529065;15354.155521446204;11843.23019003762;11989.880101198876;12426.62720118398;13281.468741250243;13523.59429217125;14265.215413152377;14412.855871441361;14496.565103046925;14521.927390363191;14657.267136643126;5737.254156959652;5925.762969481172;5951.047832346891;5954.434328738763;5965.970170149121;1018.5976539266948;1023.125227930124;1027.2860362048305;1030.8402197659225;1036.6112707734599;22430.88784556067;22531;22811.201607943643;22885.31344059681;22917;1114.921955463126;1117.446863802423;1123.2642619084513;1123.9761653281234;1127.9492422840992;12348.629541113782;12413.255204687748;15836.52490425289;15994.520164394855;16524.504264872074;227689;228505;229088;229184;229340;58163;58297.70851145802;58474.41525584775;58486;58614.70692646509;1440.553428437188;1460.3355473259642;1473.462186301992;1483.6142602923533;1491.8358980512135;26466.470670586095;26954.326141845828;27024.864875675496;27049.323766905985;27088.593671094553;36749.81625091964;36925;36927.8153609241;36959;36965.630343697296;208355;208641;209993;210123;210221;36021;37292.440613391365;37909;38199.236015278984;38245.617543824206;46635.53364466312;47083.29375059308;47892.76053619709;47934;47960.5203947956;34252.31495370129;34458.310833782685;34467.482987755706;34544.82727586346;34569.48145777866;33245.83377083099;34068.488972664934;34269.828650857584;34507.65492345044;35158;39031.21937561176;39125.41311880381;39261;39304.213915722095;39537.60462395339;33973.320533589686;34119;34301.828490857384;34345;34933.301333973686;24298;24467.877660611583;24527;24871.50256994886;24933.376665583506 03:10:07 INFO - PID 8947 | |2;jquery.html;572.6678526454657;574.4169667787194;576.9480746732796;591.3938213331335;592.6621825559431;20962.580748385022;21854.89072554635;21857;22033.00851461686;22467.438314042116;24483;25099.498010039788;25198.496030079386;25203.495930081433;25213.495730085386;6612.570182938093;6718.260991290954;6736.124303840518;6740.325967403245;6783.7625683101105;5936.109583562457;6515.543911926167;6537.052127441518;6720.563163394364;6746.089277947488;6453.354664533556;6476.125723027401;6485.902711459332;6503.674816259192;6511.28375878653;6441.066045423434;6460.547761656686;6474.86689829278;6495.382938620824;6509.2839787623325;6010.699465026775;6024.759009639653;6055.455009049207;6067.939320606825;6095.634261944253;28082.15753527377;28422.431551368856;28424.005159819513;28617.71382286171;28850.134495965154;2098.5068508900395;2113.651247544153;2133.1574028258906;2136.7863213678665;2140.176032227588;18886.244550217976;19321.80678193214;19522.511937201543;19632.11655475507;20296.49258768528;2277.7722227777253;2289.9313020609407;2294.265834932822;2318.8608683479047;2332.728662878735;124161.37919310345;127720;128097;132789;136900;14351.641208969859;14507.564773056825;14600.561983140522;14888.255587220596;14917.328720207617;22713;22802.719728027063;22810.31569052931;22815.429614259778;22832.200872969366;9863.753406164904;10131.504630902327;10234.53944572496;10260.58957641686;10312;9164.816703666023;9175.816483670289;9227.861582076273;9333.160015598629;9391.248700103975;7715.537067775951;8909.7924747091;9001;9134.451932884049;9386.906130938603;2609.673790776153;2643.70919198888;2663.2942270298386;2717.4836781011545;2732.4671689020847;946.4368700623119;974.3910056214866;977.6675930183771;979.2117345536911;979.5249304087512;19009.14458849355;19260.518487037654;19461.318853840046;19475.415737527896;19492.610147796964;1039.3607931122306;1045.075108528942;1049.5644307612256;1050.1598721023151;1050.5430137890041;10501.842472362921;10674.252802303765;10716.892831071746;10762.623308184176;10903.400312982703;434431;439352;441257;466160;474663;119058;121715.39142304233;123228.38385808014;125972;126432.36783816022;1213.2659740856832;1223.4487705734653;1241.1374095003885;1259.9559015434415;1263.412513181375;20444.89777551162;20480.48798779983;20507.28224512135;20538;21040.368788936357;56635.43364566302;62170.68914655398;62881;64772.352276478516;66089.33910660831;76844.61577692076;77596.2240377589;78365.60817195878;82118.58940705258;83061.58469207615;21118.260860869796;21182.894085529475;21692.45768855791;21897.343079707633;21899.12403503842;23222.767772322735;24386.26841194767;24467.75532244655;24644.753552464244;24674.259772206864;18415.171317290486;18880.62238755245;19164.329248476235;19261.903690481457;19268.518287042934;16874.240659170122;17236.138193090046;17589.384371546934;17771.20029598697;17855.107244637977;18732.438026859218;19418;19516;19653.80346196577;19716;20511.17955281772;20612.484687882923;20775.480612984193;20776.272830451468;20902.686459703422;17276.13619319054;18011.099445027958;18805.435836924913;19110.904445477685;19160.808391915903 03:10:07 INFO - PID 8947 | |3;mootools.html;1327.296532837596;1336.7861142217241;1387.2578170678685;1392.2899321346117;1396.4763213794827;14912.701745965087;15326.15706136162;15589.376424942988;15595;15599.844001559995;15861.3655453782;16123.516294511153;16138.515844524654;16139.838601613981;16145.677086458292;2736.849473278964;2787.038471727261;2787.1359878437647;2787.456445993032;2792.8743206555755;2743.8353698778137;2849.15949794811;2849.5013372659805;2851.2586727450803;2853.2866783304175;2670.826396284245;2685.6911455182612;2702.216357256393;2702.8918843246347;2703.783697304211;2670.3457652874977;2670.6795184577877;2690.3812123211674;2690.5560582503863;2693.892244310225;2520.735322791099;2527.6208568714655;2536.95984646296;2537.6701028866128;2539.733328000552;14838.480653177086;15195.696086078327;15197.620059498491;15206.543803685907;15225.162616056112;8054.556999365032;8350.33197344211;8357.540335281557;8370.665173393058;8374.162583741638;5256.316678831766;5387.703676297802;5391.353037635509;5480.945190548084;5496.477834605706;1885.1045753267088;1885.8302752752181;1893.1102381880494;1927.4314077347217;1939.1177014458387;26950.46099078027;27315.726842731714;27378.178654640393;27440;27453.17640470789;6511.860424425707;6694.163229596301;6718.563293385914;6900.102986611709;6903.413209877116;17422.47732568025;17426.12869356527;17429.38997135094;17429.738553921703;17448.12759362027;7935.047794264609;8065.6370463329295;8082.434229603871;8095.433319667684;8106.554139522265;7211.963940180371;7304.707811687579;7331.9266807332315;7387.298206670358;7416.517926334771;5158.406783219978;5196.32447781786;5200.635955483155;5249.527542521191;5260.500252476008;1465.6482444213311;1469.133211405276;1486.353436255211;1504.5787179589731;1507.0957425544586;882.2192359761528;897.5422534507449;914.6021480655936;917.1653795046476;925.227435091706;7689.4232932530285;7819.882701759479;7824.334931530883;7892.684292628231;7895.960520197362;707.4375871182423;708.3129364516411;732.150705181997;736.5323019882306;738.5162718419443;4771.403574553181;4808.230683090689;5137.229415587692;5586.301712285965;5652.208690783294;21376.251831185833;22306.665400019014;22476.55046899053;22499.32502024942;22625.547489050456;21253.68119478209;21520.139194432362;22026.33920982373;22064.33806985793;22240.666390004164;10829.29609575375;10913.23607347494;11063.557457701763;11092.279001864854;11094.22340436178;8449.155084491502;8463;8544.786380340542;8598.441101328393;8659.177378149066;17773.911130444267;18276.72584911201;18674.159662814935;18690;18809.529761756065;19076.523086922938;19078.236870525023;19317;19442.222311107962;19489.805101948798;10599.258051936291;11931.105167112328;12314;12374.876251237372;12375;13781.035327526977;13882.236476993863;13888.097273677178;13904.930475347559;13935.860641393456;11177.441127943408;11222.494987725735;11401.942990284995;11418;11535.769284614093;11099.889001109885;11172.664820055412;11228.831567526331;11425.885741142481;11682.591109311134;9701.951490242503;9703.272254580794;9710.902890971;9722.951385243312;9732;7269.104314038185;7300.16048154469;7315.975763393023;7322.487425880137;7327.853442931005;7109.75115870942;7179.712811487482;7237.095363079615;7243.203247642649;7354.521956072838 03:10:07 INFO - PID 8947 | |4;prototype.html;504.4476298453192;510.3467561521252;532.672406470021;534.5483066808546;537.344439783464;6315.747370105196;6325.722739813669;6403.327650596691;6408.967955160218;6447.967760161205;6030.427109424609;6220.315765265817;6224.346443623423;6249.687515624212;6281;5043.495650434964;5095.293076819252;5119.1809310510325;5120.513551212638;5135.127028405162;5031.4465408805;5047.697138171703;5102.36220472441;5133.435322114565;5134.512221338967;4896.804127834882;4919.130730322472;4995.250712393152;4995.950040499585;5027.673201241908;4921.237208232747;4965.702057876503;4974.626902982291;4978.6017118630425;4989.950100498985;4458.621017213541;4485.282354823213;4485.506594274627;4511.954880451186;4519.502854685982;6172;6201.751929922798;6211.161493198406;6216.627002379872;6230.750769969241;5693.487586117228;5716.941436331585;5747.137929310595;5794.565407594405;5799.5650326225705;6499.967500162516;6567;6589.17635295588;6598.637074960923;6635.170603674541;4888.533439967995;4897.926531102036;4921.114199444099;4923.138450771101;4926.3842019747535;6902.551334163263;6941.583504989666;6953.89569156463;7011.789646310619;7046.401055910305;5066.518680725399;5068.214426763858;5072.594192464594;5078.415982162025;5081;5115.309433226466;5117.130087885069;5118.155504341817;5121.692698438105;5135;5689.68706721126;5729.713514324267;5733.77064917407;5745.712714364265;5766.94233057664;6554.9672251639395;6586.242582103024;6660.83347916306;6675.165604299463;6687.899681504781;1668.8581470575136;1689.0878925380157;1757.674830156425;1782.8767876237887;1787.9821201788077;1205.541894080244;1218.9268643881396;1219.152688881219;1232.5994051933158;1242.589945318043;5806.82579522615;5835.766569337179;5875.383084776123;5878.265216847894;5891.204687367236;6626.337366263431;6656.500762442836;6686.5653732507235;6721.26066132725;6730.663466826737;5852.297724273115;5869.413058694182;5906.5570082244;5916.408359164049;5965.522758179335;3557.679808817219;3575.5530558680166;3577.4454959481322;3612.765170263977;3621.3843646580144;6511.544191906521;6517.91426743447;6554.246261679872;6557.6228991911275;6559.5408321418;6853.246142924274;6878.454971660518;6978;6982.1970473395195;7058.576485410892;6477.870442591122;6511.772087976993;6548.214214294315;6603.867922641521;6607.9669601651685;6685.328667833041;6752.966235168793;6771.356721111487;6842.93157068423;6846.520743547904;6775.559588626724;6802.217744959393;6811.557248778813;6834.1457317835275;6856.485763567653;6564.770233041821;6616.239132499824;6647.302033286629;6697.497687673542;6707.631080290631;5485.122380419114;5503.449655034465;5557.610967232416;5568.888622227614;5570.749316280858;6784.830379240559;6823.488238382042;6855.72700978599;6857.55425897335;6901.62041087745;6476.474175071631;6489.221293444817;6509.1863517060365;6554.442872355903;6606.4384527315715;6468.676566171578;6511.47908167336;6516.0551719999385;6530.346965303432;6546.869062618625;6168.784092556739;6392.232932048184;6557.245916719638;6579.572327798869;6596.670166491752;6211.503079753699;6513.609183449008;6520.18497687789;6583.156716119494;6636.170478690164;6263.467605253604;6284.994400895835;6299.527535434769;6358.586691865014;6424.293327734039 03:10:07 INFO - PID 8947 | |5;yui.html;911.3210658059745;911.4850109688026;930.4649826349851;935.1677007463354;941.7363138321268;18712.532186695324;19980.700289495668;20021.699674504856;20025.299114530993;20609.690854637192;20630.48423789406;21540.89229553854;21563.784362156373;21637.675434868488;21725.565488690216;961.2262128986164;999.1058003087232;1000.4947501511726;1001;1002.2032484175062;917.4862077236769;997.670768646345;1001.0190013786494;1016.0753714120152;1016.9084782369586;1061.8778840433372;1065.1265961911236;1065.4033741104968;1065.5098654618846;1065.6643157405392;1052.2897044494973;1052.9157667386592;1053.515382923856;1059.327327147267;1059.8622179116742;776.7436745873905;777.3703300326754;777.3742137579246;777.5140537164273;777.5956502618607;20125.698114528295;20315.796842031537;20451.897740511202;20484.487887802774;21212.469688257763;21004.789952100436;21177.152913883427;21656.89171554148;21677.241296554545;21703.674444883338;17400.129993500275;17474.825251747574;17494.91252543742;17625.38311159116;17672.734908976374;3757.7035922606656;3758.8872333830027;3777.4333849922455;3777.924441511182;3778.3199024175638;121850;121936;121953.39023304827;124142;126129;16475.83524164767;16496.587585310463;17055.829441705424;17119.058451785273;17139.485815425556;29705.81176752949;30108.397832043236;30219.09342719722;30227.848860755556;31016;7542.924570754332;7870.212978702084;8532.976042874894;8648.481091134552;8900.465972041698;4413.16149931512;4506.864794056183;4507.301368287921;4519.728816271035;4531.093781243764;5322.414534401213;5431.565474762009;5441.83674489766;5480.945190548123;5494.230807686847;1091.2906610703108;1099.9450027498754;1124.8031594470935;1131.7170707323169;1135.1202817816093;662.3409707341226;681.4003676764415;687.7352219395568;693.3205458650551;698.7833771530842;11540.826887596693;11685.591004314809;11996.040316774812;12394.504219831106;13641.249731264876;707.8407358344331;709.8402859356603;719.7552832037132;721.3832173491589;735.6211551051213;5426.403095659474;5709.34342550612;5803.158542011384;5874.9118763218585;6043.607165534227;107064.46467767611;107271.4636426813;107445.46277268564;108963;110170;95438;96095.51952240334;98001.50999244959;100468.49765751271;100858;82508;83655;83858.58070709607;88290.55854720685;90947.54526227327;12547.93726031364;12681.619551413472;12813;13169.209847409185;13200.801987969997;37985.81007094947;38896.6110338893;39561;40247;41412.585874140874;69238;70271.64864175646;70769.64615176892;71139;71306;18343.449696508593;18372.448826535227;18732;19218.903905480383;19238.61522769565;21947.451313717283;22091;22201.22295719642;22369.105235790386;22524.324270271918;18837.529061773566;19475.61048778988;19707.40877773669;19755.60488790245;20006.69989950181;18996.810031899506;19038.619227615647;19203.32788352401;19429.61140777148;19697.310594129136;18036.549086272422;18181.54546136357;18204.726929096338;18290.26838926428;18832.152553135416;14044.929775351058;14168.716625667223;14178.574642760734;14307.21310327942;14887.10677359362;14290.425085436495;14441.277936103363;14647.487337943121;14788.482403115839;15187 03:10:07 INFO - PID 8947 | __end_tp_report 03:10:07 INFO - PID 8947 | __start_cc_report 03:10:07 INFO - PID 8947 | _x_x_mozilla_cycle_collect,373 03:10:07 INFO - PID 8947 | __end_cc_report 03:10:07 INFO - PID 8947 | __startTimestamp1504347007656__endTimestamp 03:10:07 INFO - PID 8947 | 03:10:07 INFO - PID 8947 | ------- Summary: start ------- 03:10:07 INFO - PID 8947 | Number of tests: 6 03:10:07 INFO - PID 8947 | 03:10:07 INFO - PID 8947 | [#0] dojo.html Cycles:165 Average:6303.27 Median:6624.87 stddev:886.37 (13.4%) stddev-sans-first:857.22 03:10:07 INFO - PID 8947 | Values: 3300.5 3315.2 3343.5 3364.6 3392.4 6390.0 6420.5 6450.7 6487.5 6568.3 6315.7 6389.8 6394.0 6395.8 6421.1 5858.2 5859.6 5871.0 5890.3 5931.9 5916.4 5925.0 5956.9 5968.6 5990.0 975.0 5914.7 5967.8 5986.3 6028.0 5410.5 5812.7 5829.9 5944.5 5950.7 5821.5 5853.9 5901.1 5930.8 5931.7 6311.3 6393.9 6454.4 6496.3 6504.4 6848.3 6851.2 6854.6 6882.5 6965.7 6914.3 6925.8 7001.8 7010.4 7025.0 6268.7 6335.0 6381.5 6426.1 6461.9 7133.8 7155.1 7156.4 7161.9 7177.7 6805.9 6831.9 6847.6 6852.2 6852.7 6853.7 6893.7 6920.1 6979.6 6982.0 6704.3 6796.0 6796.9 6807.7 6917.8 6829.3 6856.5 6857.6 6866.1 6884.0 5054.2 5065.4 5081.2 5115.7 5117.2 4448.8 4456.6 4500.4 4500.6 4524.8 6100.6 6104.8 6115.8 6120.3 6124.6 6891.6 6893.0 6900.8 6980.2 6994.3 6009.8 6021.2 6030.7 6076.4 6100.3 5890.8 5944.5 5945.9 5984.0 5989.0 6641.4 6699.4 6796.8 6801.7 6812.7 6943.5 6961.3 7020.7 7034.2 7060.6 6579.7 6690.6 6705.4 6742.8 6748.3 6778.7 6818.7 6840.1 6850.0 6851.0 6835.0 6909.3 7010.0 7014.1 7017.4 6540.2 6624.9 6644.9 6662.4 6755.5 6715.3 6734.2 6745.5 6755.8 6763.1 6497.6 6635.6 6670.2 6692.8 6705.1 6687.2 6705.8 6748.7 6774.0 6817.0 6359.8 6418.7 6482.2 6513.5 6532.5 03:10:07 INFO - PID 8947 | 03:10:07 INFO - PID 8947 | [#1] ext.html Cycles:180 Average:38383.10 Median:3585.74 stddev:53764.14 (1499.4%) stddev-sans-first:53841.00 03:10:07 INFO - PID 8947 | Values: 827.1 840.1 879.9 880.6 1142.6 30698.8 31179.2 31386.7 41048.6 41877.0 42051.0 42249.8 42320.6 42451.4 42551.8 7302.1 7827.7 7931.0 7965.4 7971.9 7579.8 7631.1 7644.3 7658.4 7672.7 7622.2 7671.0 7675.2 7679.7 7683.3 7613.9 7646.8 7679.2 7681.5 7681.9 7810.4 7859.8 7860.7 7862.4 7865.8 38844.4 38856.2 39049.2 39137.4 39225.0 3542.8 3576.5 3595.0 3599.9 3602.8 32161.5 32268.8 32282.2 32315.7 32326.7 5269.5 5276.5 5322.3 5329.3 5343.3 180820.0 181465.0 181755.0 182040.0 182110.0 49140.8 49573.3 49689.5 49727.8 49739.3 51489.2 51669.7 51708.0 51739.5 51877.0 15100.2 15251.2 15258.6 15289.8 15354.2 11843.2 11989.9 12426.6 13281.5 13523.6 14265.2 14412.9 14496.6 14521.9 14657.3 5737.3 5925.8 5951.0 5954.4 5966.0 1018.6 1023.1 1027.3 1030.8 1036.6 22430.9 22531.0 22811.2 22885.3 22917.0 1114.9 1117.4 1123.3 1124.0 1127.9 12348.6 12413.3 15836.5 15994.5 16524.5 227689.0 228505.0 229088.0 229184.0 229340.0 58163.0 58297.7 58474.4 58486.0 58614.7 1440.6 1460.3 1473.5 1483.6 1491.8 26466.5 26954.3 27024.9 27049.3 27088.6 36749.8 36925.0 36927.8 36959.0 36965.6 208355.0 208641.0 209993.0 210123.0 210221.0 36021.0 37292.4 37909.0 38199.2 38245.6 46635.5 47083.3 47892.8 47934.0 47960.5 34252.3 34458.3 34467.5 34544.8 34569.5 33245.8 34068.5 34269.8 34507.7 35158.0 39031.2 39125.4 39261.0 39304.2 39537.6 33973.3 34119.0 34301.8 34345.0 34933.3 24298.0 24467.9 24527.0 24871.5 24933.4 03:10:07 INFO - PID 8947 | 03:10:07 INFO - PID 8947 | [#2] jquery.html Cycles:180 Average:34494.16 Median:12540.25 stddev:76709.31 (611.7%) stddev-sans-first:76882.22 03:10:07 INFO - PID 8947 | Values: 572.7 574.4 576.9 591.4 592.7 20962.6 21854.9 21857.0 22033.0 22467.4 24483.0 25099.5 25198.5 25203.5 25213.5 6612.6 6718.3 6736.1 6740.3 6783.8 5936.1 6515.5 6537.1 6720.6 6746.1 6453.4 6476.1 6485.9 6503.7 6511.3 6441.1 6460.5 6474.9 6495.4 6509.3 6010.7 6024.8 6055.5 6067.9 6095.6 28082.2 28422.4 28424.0 28617.7 28850.1 2098.5 2113.7 2133.2 2136.8 2140.2 18886.2 19321.8 19522.5 19632.1 20296.5 2277.8 2289.9 2294.3 2318.9 2332.7 124161.4 127720.0 128097.0 132789.0 136900.0 14351.6 14507.6 14600.6 14888.3 14917.3 22713.0 22802.7 22810.3 22815.4 22832.2 9863.8 10131.5 10234.5 10260.6 10312.0 9164.8 9175.8 9227.9 9333.2 9391.2 7715.5 8909.8 9001.0 9134.5 9386.9 2609.7 2643.7 2663.3 2717.5 2732.5 946.4 974.4 977.7 979.2 979.5 19009.1 19260.5 19461.3 19475.4 19492.6 1039.4 1045.1 1049.6 1050.2 1050.5 10501.8 10674.3 10716.9 10762.6 10903.4 434431.0 439352.0 441257.0 466160.0 474663.0 119058.0 121715.4 123228.4 125972.0 126432.4 1213.3 1223.4 1241.1 1260.0 1263.4 20444.9 20480.5 20507.3 20538.0 21040.4 56635.4 62170.7 62881.0 64772.4 66089.3 76844.6 77596.2 78365.6 82118.6 83061.6 21118.3 21182.9 21692.5 21897.3 21899.1 23222.8 24386.3 24467.8 24644.8 24674.3 18415.2 18880.6 19164.3 19261.9 19268.5 16874.2 17236.1 17589.4 17771.2 17855.1 18732.4 19418.0 19516.0 19653.8 19716.0 20511.2 20612.5 20775.5 20776.3 20902.7 17276.1 18011.1 18805.4 19110.9 19160.8 03:10:07 INFO - PID 8947 | 03:10:07 INFO - PID 8947 | [#3] mootools.html Cycles:180 Average:9427.62 Median:2692.22 stddev:6826.99 (253.6%) stddev-sans-first:6819.02 03:10:07 INFO - PID 8947 | Values: 1327.3 1336.8 1387.3 1392.3 1396.5 14912.7 15326.2 15589.4 15595.0 15599.8 15861.4 16123.5 16138.5 16139.8 16145.7 2736.8 2787.0 2787.1 2787.5 2792.9 2743.8 2849.2 2849.5 2851.3 2853.3 2670.8 2685.7 2702.2 2702.9 2703.8 2670.3 2670.7 2690.4 2690.6 2693.9 2520.7 2527.6 2537.0 2537.7 2539.7 14838.5 15195.7 15197.6 15206.5 15225.2 8054.6 8350.3 8357.5 8370.7 8374.2 5256.3 5387.7 5391.4 5480.9 5496.5 1885.1 1885.8 1893.1 1927.4 1939.1 26950.5 27315.7 27378.2 27440.0 27453.2 6511.9 6694.2 6718.6 6900.1 6903.4 17422.5 17426.1 17429.4 17429.7 17448.1 7935.0 8065.6 8082.4 8095.4 8106.6 7212.0 7304.7 7331.9 7387.3 7416.5 5158.4 5196.3 5200.6 5249.5 5260.5 1465.6 1469.1 1486.4 1504.6 1507.1 882.2 897.5 914.6 917.2 925.2 7689.4 7819.9 7824.3 7892.7 7896.0 707.4 708.3 732.2 736.5 738.5 4771.4 4808.2 5137.2 5586.3 5652.2 21376.3 22306.7 22476.6 22499.3 22625.5 21253.7 21520.1 22026.3 22064.3 22240.7 10829.3 10913.2 11063.6 11092.3 11094.2 8449.2 8463.0 8544.8 8598.4 8659.2 17773.9 18276.7 18674.2 18690.0 18809.5 19076.5 19078.2 19317.0 19442.2 19489.8 10599.3 11931.1 12314.0 12374.9 12375.0 13781.0 13882.2 13888.1 13904.9 13935.9 11177.4 11222.5 11401.9 11418.0 11535.8 11099.9 11172.7 11228.8 11425.9 11682.6 9702.0 9703.3 9710.9 9723.0 9732.0 7269.1 7300.2 7316.0 7322.5 7327.9 7109.8 7179.7 7237.1 7243.2 7354.5 03:10:07 INFO - PID 8947 | 03:10:07 INFO - PID 8947 | [#4] prototype.html Cycles:180 Average:5574.44 Median:6214.07 stddev:1558.79 (25.1%) stddev-sans-first:1516.00 03:10:07 INFO - PID 8947 | Values: 504.4 510.3 532.7 534.5 537.3 6315.7 6325.7 6403.3 6409.0 6448.0 6030.4 6220.3 6224.3 6249.7 6281.0 5043.5 5095.3 5119.2 5120.5 5135.1 5031.4 5047.7 5102.4 5133.4 5134.5 4896.8 4919.1 4995.3 4996.0 5027.7 4921.2 4965.7 4974.6 4978.6 4990.0 4458.6 4485.3 4485.5 4512.0 4519.5 6172.0 6201.8 6211.2 6216.6 6230.8 5693.5 5716.9 5747.1 5794.6 5799.6 6500.0 6567.0 6589.2 6598.6 6635.2 4888.5 4897.9 4921.1 4923.1 4926.4 6902.6 6941.6 6953.9 7011.8 7046.4 5066.5 5068.2 5072.6 5078.4 5081.0 5115.3 5117.1 5118.2 5121.7 5135.0 5689.7 5729.7 5733.8 5745.7 5766.9 6555.0 6586.2 6660.8 6675.2 6687.9 1668.9 1689.1 1757.7 1782.9 1788.0 1205.5 1218.9 1219.2 1232.6 1242.6 5806.8 5835.8 5875.4 5878.3 5891.2 6626.3 6656.5 6686.6 6721.3 6730.7 5852.3 5869.4 5906.6 5916.4 5965.5 3557.7 3575.6 3577.4 3612.8 3621.4 6511.5 6517.9 6554.2 6557.6 6559.5 6853.2 6878.5 6978.0 6982.2 7058.6 6477.9 6511.8 6548.2 6603.9 6608.0 6685.3 6753.0 6771.4 6842.9 6846.5 6775.6 6802.2 6811.6 6834.1 6856.5 6564.8 6616.2 6647.3 6697.5 6707.6 5485.1 5503.4 5557.6 5568.9 5570.7 6784.8 6823.5 6855.7 6857.6 6901.6 6476.5 6489.2 6509.2 6554.4 6606.4 6468.7 6511.5 6516.1 6530.3 6546.9 6168.8 6392.2 6557.2 6579.6 6596.7 6211.5 6513.6 6520.2 6583.2 6636.2 6263.5 6285.0 6299.5 6358.6 6424.3 03:10:07 INFO - PID 8947 | 03:10:07 INFO - PID 8947 | [#5] yui.html Cycles:180 Average:23939.96 Median:20547.09 stddev:31674.17 (154.2%) stddev-sans-first:31715.82 03:10:07 INFO - PID 8947 | Values: 911.3 911.5 930.5 935.2 941.7 18712.5 19980.7 20021.7 20025.3 20609.7 20630.5 21540.9 21563.8 21637.7 21725.6 961.2 999.1 1000.5 1001.0 1002.2 917.5 997.7 1001.0 1016.1 1016.9 1061.9 1065.1 1065.4 1065.5 1065.7 1052.3 1052.9 1053.5 1059.3 1059.9 776.7 777.4 777.4 777.5 777.6 20125.7 20315.8 20451.9 20484.5 21212.5 21004.8 21177.2 21656.9 21677.2 21703.7 17400.1 17474.8 17494.9 17625.4 17672.7 3757.7 3758.9 3777.4 3777.9 3778.3 121850.0 121936.0 121953.4 124142.0 126129.0 16475.8 16496.6 17055.8 17119.1 17139.5 29705.8 30108.4 30219.1 30227.8 31016.0 7542.9 7870.2 8533.0 8648.5 8900.5 4413.2 4506.9 4507.3 4519.7 4531.1 5322.4 5431.6 5441.8 5480.9 5494.2 1091.3 1099.9 1124.8 1131.7 1135.1 662.3 681.4 687.7 693.3 698.8 11540.8 11685.6 11996.0 12394.5 13641.2 707.8 709.8 719.8 721.4 735.6 5426.4 5709.3 5803.2 5874.9 6043.6 107064.5 107271.5 107445.5 108963.0 110170.0 95438.0 96095.5 98001.5 100468.5 100858.0 82508.0 83655.0 83858.6 88290.6 90947.5 12547.9 12681.6 12813.0 13169.2 13200.8 37985.8 38896.6 39561.0 40247.0 41412.6 69238.0 70271.6 70769.6 71139.0 71306.0 18343.4 18372.4 18732.0 19218.9 19238.6 21947.5 22091.0 22201.2 22369.1 22524.3 18837.5 19475.6 19707.4 19755.6 20006.7 18996.8 19038.6 19203.3 19429.6 19697.3 18036.5 18181.5 18204.7 18290.3 18832.2 14044.9 14168.7 14178.6 14307.2 14887.1 14290.4 14441.3 14647.5 14788.5 15187.0 03:10:07 INFO - PID 8947 | -------- Summary: end -------- 03:10:07 INFO - PID 8947 | 03:10:08 INFO - TEST-INFO | 8947: exit 0 03:10:08 INFO - TEST-OK | dromaeo_css | took 1110935ms 03:10:08 INFO - TEST-START | kraken 03:10:08 INFO - Initialising browser for kraken test... 03:10:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56536/getInfo.html -profile /tmp/tmp8GHVba/profile 03:10:08 INFO - TEST-INFO | started process 9326 (/builds/slave/test/build/application/firefox/firefox http://localhost:56536/getInfo.html) 03:10:13 INFO - TEST-INFO | 9326: exit 0 03:10:13 INFO - Browser initialized. 03:10:13 INFO - Running cycle 1/1 for kraken test... 03:10:13 INFO - Using env: {'DISPLAY': ':0', 03:10:13 INFO - 'HOME': '/home/cltbld', 03:10:13 INFO - 'JSGC_DISABLE_POISONING': '1', 03:10:13 INFO - 'LANG': 'en_US.UTF-8', 03:10:13 INFO - 'LANGUAGE': 'en_US:en', 03:10:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:10:13 INFO - 'LOGNAME': 'cltbld', 03:10:13 INFO - 'MAIL': '/var/mail/cltbld', 03:10:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:10:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:10:13 INFO - 'MOZ_CRASHREPORTER': '1', 03:10:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:10:13 INFO - 'MOZ_NO_REMOTE': '1', 03:10:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:10:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:10:13 INFO - 'NO_EM_RESTART': '1', 03:10:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:10:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:13 INFO - 'PWD': '/builds/slave/test', 03:10:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:10:13 INFO - 'RUST_BACKTRACE': 'full', 03:10:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:10:13 INFO - 'SHELL': '/bin/bash', 03:10:13 INFO - 'SHLVL': '1', 03:10:13 INFO - 'STYLO_FORCE_ENABLED': '1', 03:10:13 INFO - 'STYLO_THREADS': '1', 03:10:13 INFO - 'TERM': 'linux', 03:10:13 INFO - 'TMOUT': '86400', 03:10:13 INFO - 'USER': 'cltbld', 03:10:13 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839', 03:10:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:13 INFO - '_': '/tools/buildbot/bin/python'} 03:10:13 INFO - TEST-INFO | started process 9511 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8GHVba/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:10:14 INFO - PID 9511 | 03:10:14 INFO - PID 9511 | (/builds/slave/test/build/application/firefox/firefox:9564): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:10:14 INFO - PID 9511 | 03:10:25 INFO - PID 9511 | RSS: Main: 172728320 03:10:25 INFO - PID 9511 | 03:10:27 INFO - PID 9511 | Cycle 1(1): loaded http://localhost:56536/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56536/tests/kraken/driver.html?testName=audio-beat-detection) 03:10:28 INFO - PID 9511 | RSS: Main: 178429952 03:10:28 INFO - PID 9511 | 03:10:31 INFO - PID 9511 | Cycle 1(1): loaded http://localhost:56536/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56536/tests/kraken/driver.html?testName=audio-dft) 03:10:32 INFO - PID 9511 | RSS: Main: 182480896 03:10:32 INFO - PID 9511 | 03:10:35 INFO - PID 9511 | Cycle 1(1): loaded http://localhost:56536/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56536/tests/kraken/driver.html?testName=audio-fft) 03:10:35 INFO - PID 9511 | RSS: Main: 174985216 03:10:35 INFO - PID 9511 | 03:10:37 INFO - PID 9511 | Cycle 1(1): loaded http://localhost:56536/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56536/tests/kraken/driver.html?testName=audio-oscillator) 03:10:38 INFO - PID 9511 | RSS: Main: 175566848 03:10:38 INFO - PID 9511 | 03:10:40 INFO - PID 9511 | Cycle 1(1): loaded http://localhost:56536/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56536/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:10:41 INFO - PID 9511 | RSS: Main: 175591424 03:10:41 INFO - PID 9511 | 03:10:44 INFO - PID 9511 | Cycle 1(1): loaded http://localhost:56536/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56536/tests/kraken/driver.html?testName=imaging-darkroom) 03:10:45 INFO - PID 9511 | RSS: Main: 176521216 03:10:45 INFO - PID 9511 | 03:10:48 INFO - PID 9511 | Cycle 1(1): loaded http://localhost:56536/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56536/tests/kraken/driver.html?testName=imaging-desaturate) 03:10:49 INFO - PID 9511 | RSS: Main: 176648192 03:10:49 INFO - PID 9511 | 03:10:52 INFO - PID 9511 | Cycle 1(1): loaded http://localhost:56536/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56536/tests/kraken/driver.html?testName=json-parse-financial) 03:10:52 INFO - PID 9511 | RSS: Main: 177168384 03:10:52 INFO - PID 9511 | 03:10:54 INFO - PID 9511 | Cycle 1(1): loaded http://localhost:56536/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56536/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:10:54 INFO - PID 9511 | RSS: Main: 177397760 03:10:54 INFO - PID 9511 | 03:10:56 INFO - PID 9511 | Cycle 1(1): loaded http://localhost:56536/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56536/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:10:57 INFO - PID 9511 | RSS: Main: 177565696 03:10:57 INFO - PID 9511 | 03:10:59 INFO - PID 9511 | Cycle 1(1): loaded http://localhost:56536/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56536/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:10:59 INFO - PID 9511 | RSS: Main: 173465600 03:10:59 INFO - PID 9511 | 03:11:02 INFO - PID 9511 | Cycle 1(1): loaded http://localhost:56536/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56536/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:11:03 INFO - PID 9511 | RSS: Main: 173928448 03:11:03 INFO - PID 9511 | 03:11:06 INFO - PID 9511 | Cycle 1(1): loaded http://localhost:56536/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56536/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:11:06 INFO - PID 9511 | RSS: Main: 174219264 03:11:06 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | Cycle 1(1): loaded http://localhost:56536/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56536/tests/kraken/driver.html?testName=ai-astar) 03:11:08 INFO - PID 9511 | RSS: Main: 175861760 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | __start_tp_report 03:11:08 INFO - PID 9511 | _x_x_mozilla_page_load 03:11:08 INFO - PID 9511 | _x_x_mozilla_page_load_details 03:11:08 INFO - PID 9511 | |i|pagename|runs| 03:11:08 INFO - PID 9511 | |0;ai-astar;103;107;95;108;108;108;111;105;110;111 03:11:08 INFO - PID 9511 | |1;audio-beat-detection;149;117;138;176;118;147;140;116;170;132 03:11:08 INFO - PID 9511 | |2;audio-dft;162;169;164;162;163;162;169;167;162;166 03:11:08 INFO - PID 9511 | |3;audio-fft;75;75;74;170;75;74;74;75;141;75 03:11:08 INFO - PID 9511 | |4;audio-oscillator;83;79;127;79;78;79;135;79;78;138 03:11:08 INFO - PID 9511 | |5;imaging-gaussian-blur;113;114;116;115;114;115;115;115;114;114 03:11:08 INFO - PID 9511 | |6;imaging-darkroom;149;148;148;148;148;148;149;148;147;148 03:11:08 INFO - PID 9511 | |7;imaging-desaturate;95;94;93;95;95;94;94;94;94;94 03:11:08 INFO - PID 9511 | |8;json-parse-financial;55;52;52;53;52;51;53;52;52;53 03:11:08 INFO - PID 9511 | |9;json-stringify-tinderbox;38;39;39;39;39;38;38;39;39;39 03:11:08 INFO - PID 9511 | |10;stanford-crypto-aes;82;74;74;76;74;76;81;77;77;74 03:11:08 INFO - PID 9511 | |11;stanford-crypto-ccm;118;126;119;117;123;123;121;124;118;154 03:11:08 INFO - PID 9511 | |12;stanford-crypto-pbkdf2;170;181;174;171;180;168;171;166;181;167 03:11:08 INFO - PID 9511 | |13;stanford-crypto-sha256-iterative;70;64;66;66;63;65;62;64;64;64 03:11:08 INFO - PID 9511 | __end_tp_report 03:11:08 INFO - PID 9511 | __start_cc_report 03:11:08 INFO - PID 9511 | _x_x_mozilla_cycle_collect,936 03:11:08 INFO - PID 9511 | __end_cc_report 03:11:08 INFO - PID 9511 | __startTimestamp1504347068890__endTimestamp 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | ------- Summary: start ------- 03:11:08 INFO - PID 9511 | Number of tests: 14 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | [#0] ai-astar Cycles:10 Average:106.60 Median:109.00 stddev:4.79 (4.4%) stddev-sans-first:4.90 03:11:08 INFO - PID 9511 | Values: 103.0 107.0 95.0 108.0 108.0 108.0 111.0 105.0 110.0 111.0 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:143.50 stddev:21.03 (14.7%) stddev-sans-first:22.07 03:11:08 INFO - PID 9511 | Values: 149.0 117.0 138.0 176.0 118.0 147.0 140.0 116.0 170.0 132.0 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | [#2] audio-dft Cycles:10 Average:164.60 Median:165.00 stddev:2.91 (1.8%) stddev-sans-first:2.93 03:11:08 INFO - PID 9511 | Values: 162.0 169.0 164.0 162.0 163.0 162.0 169.0 167.0 162.0 166.0 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | [#3] audio-fft Cycles:10 Average:90.80 Median:75.00 stddev:34.78 (46.4%) stddev-sans-first:36.42 03:11:08 INFO - PID 9511 | Values: 75.0 75.0 74.0 170.0 75.0 74.0 74.0 75.0 141.0 75.0 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:79.00 stddev:26.28 (33.3%) stddev-sans-first:27.48 03:11:08 INFO - PID 9511 | Values: 83.0 79.0 127.0 79.0 78.0 79.0 135.0 79.0 78.0 138.0 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:115.00 stddev:0.85 (0.7%) stddev-sans-first:0.71 03:11:08 INFO - PID 9511 | Values: 113.0 114.0 116.0 115.0 114.0 115.0 115.0 115.0 114.0 114.0 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 03:11:08 INFO - PID 9511 | Values: 149.0 148.0 148.0 148.0 148.0 148.0 149.0 148.0 147.0 148.0 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 03:11:08 INFO - PID 9511 | Values: 95.0 94.0 93.0 95.0 95.0 94.0 94.0 94.0 94.0 94.0 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.50 stddev:1.08 (2.1%) stddev-sans-first:0.67 03:11:08 INFO - PID 9511 | Values: 55.0 52.0 52.0 53.0 52.0 51.0 53.0 52.0 52.0 53.0 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | [#9] json-stringify-tinderbox Cycles:10 Average:38.70 Median:39.00 stddev:0.48 (1.2%) stddev-sans-first:0.44 03:11:08 INFO - PID 9511 | Values: 38.0 39.0 39.0 39.0 39.0 38.0 38.0 39.0 39.0 39.0 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | [#10] stanford-crypto-aes Cycles:10 Average:76.50 Median:76.50 stddev:2.92 (3.8%) stddev-sans-first:2.32 03:11:08 INFO - PID 9511 | Values: 82.0 74.0 74.0 76.0 74.0 76.0 81.0 77.0 77.0 74.0 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | [#11] stanford-crypto-ccm Cycles:10 Average:124.30 Median:123.00 stddev:10.85 (8.8%) stddev-sans-first:11.27 03:11:08 INFO - PID 9511 | Values: 118.0 126.0 119.0 117.0 123.0 123.0 121.0 124.0 118.0 154.0 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:172.50 stddev:5.82 (3.4%) stddev-sans-first:6.08 03:11:08 INFO - PID 9511 | Values: 170.0 181.0 174.0 171.0 180.0 168.0 171.0 166.0 181.0 167.0 03:11:08 INFO - PID 9511 | 03:11:08 INFO - PID 9511 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:64.50 stddev:2.20 (3.4%) stddev-sans-first:1.30 03:11:08 INFO - PID 9511 | Values: 70.0 64.0 66.0 66.0 63.0 65.0 62.0 64.0 64.0 64.0 03:11:08 INFO - PID 9511 | -------- Summary: end -------- 03:11:08 INFO - PID 9511 | 03:11:09 INFO - PID 9511 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:11:09 INFO - TEST-INFO | 9511: exit 0 03:11:09 INFO - TEST-OK | kraken | took 61173ms 03:11:09 INFO - SUITE-END | took 1172s 03:11:10 INFO - Completed test suite (00:19:32) 03:11:10 INFO - javascript benchmark 03:11:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8696.593376356483, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3300.5379246905436, 3315.237495376062, 3343.465045592706, 3364.613069497008, 3392.3554524640317, 6390.0, 6420.518461115412, 6450.677466126698, 6487.481001519879, 6568.277489476166, 6315.747370105202, 6389.808305750823, 6394.040893865922, 6395.776147834827, 6421.13314702515, 5858.238429004234, 5859.560532960024, 5871.031279838821, 5890.263717035371, 5931.911021334683, 5916.408359164092, 5925.0, 5956.851078723023, 5968.612040217394, 5990.0, 974.9953226575733, 5914.674692891889, 5967.791127310545, 5986.341502434729, 6028.0, 5410.455806465543, 5812.680302583356, 5829.88340233193, 5944.494717948979, 5950.702464876739, 5821.476067153976, 5853.912191317134, 5901.144334071536, 5930.762769489215, 5931.703414829241, 6311.305756366842, 6393.904091438586, 6454.419102280817, 6496.252930912911, 6504.38208370204, 6848.280930502276, 6851.212110607295, 6854.588724676485, 6882.483813713991, 6965.686544105527, 6914.343137401939, 6925.757598484029, 7001.789946301619, 7010.369066783963, 7024.964875175591, 6268.686565671698, 6335.0, 6381.457576106037, 6426.068220108058, 6461.870762584816, 7133.750318738923, 7155.141383034069, 7156.391706704884, 7161.856762864713, 7177.6770045348085, 6805.863882722417, 6831.897521537181, 6847.623380714109, 6852.246252912176, 6852.725890964405, 6853.657317134123, 6893.689783959734, 6920.134983127109, 6979.581225126509, 6982.022516847645, 6704.329567043256, 6795.966020169867, 6796.93203067963, 6807.65961701913, 6917.75787847433, 6829.282925292773, 6856.485763567653, 6857.588544687334, 6866.107406037183, 6884.0, 5054.241863720471, 5065.417476990119, 5081.2378143278065, 5115.7442127893455, 5117.181250999822, 4448.777561121931, 4456.598906098467, 4500.437445319335, 4500.617447516997, 4524.819007239674, 6100.633961962297, 6104.847378815476, 6115.847103822351, 6120.265568131853, 6124.6325220486915, 6891.620960847202, 6892.965535172292, 6900.7929762107215, 6980.197277313073, 6994.265602111806, 6009.759609615567, 6021.187139736167, 6030.7286172121485, 6076.392360763888, 6100.328963813977, 5890.764369425261, 5944.524438044946, 5945.881082378328, 5984.042553191469, 5989.0, 6641.369069938348, 6699.397054265042, 6796.796096117026, 6801.727930882709, 6812.727490900407, 6943.51395402317, 6961.303869612998, 7020.719171233093, 7034.155901291878, 7060.576365417988, 6579.703913323816, 6690.565113267622, 6705.362990515893, 6742.764003259862, 6748.325167483213, 6778.661066946534, 6818.727250909908, 6840.0765896603325, 6849.965750171217, 6851.040854280305, 6834.965825171042, 6909.2745261746795, 7010.0, 7014.0531028310525, 7017.4386049116965, 6540.182477190351, 6624.867502649824, 6644.90032649501, 6662.400383965466, 6755.527113102035, 6715.261321254756, 6734.22556406, 6745.494087943326, 6755.831104222434, 6763.086983257394, 6497.642629655415, 6635.601863888182, 6670.166229221348, 6692.765753198614, 6705.09481220029, 6687.164104486939, 6705.798826035227, 6748.662566871539, 6773.983902414677, 6817.045613613999, 6359.8092057238355, 6418.743250269937, 6482.222133344029, 6513.5114866384265, 6532.510061745293], "value": 6238.644387260215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.1480375213529, 840.1472505407011, 879.9472031678096, 880.6345366672831, 1142.6343570057575, 30698.84650576747, 31179.220519486997, 31386.68613313866, 41048.58951410485, 41877.0, 42051.0, 42249.7887510562, 42320.576794232125, 42451.36322955158, 42551.78724106383, 7302.05073340465, 7827.686892524306, 7931.0, 7965.44241903067, 7971.920280797176, 7579.772606821804, 7631.12242092161, 7644.273793989562, 7658.425618078639, 7672.65473053714, 7622.161562228151, 7671.0, 7675.194104619019, 7679.7312094076715, 7683.270089341531, 7613.885791713129, 7646.770596882103, 7679.193684663113, 7681.539107653559, 7681.884771728429, 7810.375169986388, 7859.8035049123655, 7860.724874629418, 7862.410319226081, 7865.842683146362, 38844.41733373974, 38856.22287554261, 39049.21901561953, 39137.41293880594, 39225.0, 3542.769719968194, 3576.499290099388, 3594.982025089858, 3599.928001439983, 3602.765820221677, 32161.517577236362, 32268.838655806572, 32282.192945176324, 32315.6768432315, 32326.676733232838, 5269.473052694738, 5276.498732620395, 5322.33470816148, 5329.333833270841, 5343.305370301875, 180820.0, 181465.0, 181755.0, 182040.0, 182110.0, 49140.75429622829, 49573.25640115401, 49689.50310496921, 49727.75136124369, 49739.25391119064, 51489.22766158511, 51669.7416512915, 51708.0, 51739.48260517346, 51877.0, 15100.244987750568, 15251.161186134646, 15258.618534536725, 15289.847101529065, 15354.155521446204, 11843.23019003762, 11989.880101198876, 12426.62720118398, 13281.468741250243, 13523.59429217125, 14265.215413152377, 14412.855871441361, 14496.565103046925, 14521.927390363191, 14657.267136643126, 5737.254156959652, 5925.762969481172, 5951.047832346891, 5954.434328738763, 5965.970170149121, 1018.5976539266948, 1023.125227930124, 1027.2860362048305, 1030.8402197659225, 1036.6112707734599, 22430.88784556067, 22531.0, 22811.201607943643, 22885.31344059681, 22917.0, 1114.921955463126, 1117.446863802423, 1123.2642619084513, 1123.9761653281234, 1127.9492422840992, 12348.629541113782, 12413.255204687748, 15836.52490425289, 15994.520164394855, 16524.504264872074, 227689.0, 228505.0, 229088.0, 229184.0, 229340.0, 58163.0, 58297.70851145802, 58474.41525584775, 58486.0, 58614.70692646509, 1440.553428437188, 1460.3355473259642, 1473.462186301992, 1483.6142602923533, 1491.8358980512135, 26466.470670586095, 26954.326141845828, 27024.864875675496, 27049.323766905985, 27088.593671094553, 36749.81625091964, 36925.0, 36927.8153609241, 36959.0, 36965.630343697296, 208355.0, 208641.0, 209993.0, 210123.0, 210221.0, 36021.0, 37292.440613391365, 37909.0, 38199.236015278984, 38245.617543824206, 46635.53364466312, 47083.29375059308, 47892.76053619709, 47934.0, 47960.5203947956, 34252.31495370129, 34458.310833782685, 34467.482987755706, 34544.82727586346, 34569.48145777866, 33245.83377083099, 34068.488972664934, 34269.828650857584, 34507.65492345044, 35158.0, 39031.21937561176, 39125.41311880381, 39261.0, 39304.213915722095, 39537.60462395339, 33973.320533589686, 34119.0, 34301.828490857384, 34345.0, 34933.301333973686, 24298.0, 24467.877660611583, 24527.0, 24871.50256994886, 24933.376665583506], "value": 17642.842545818396, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.6678526454657, 574.4169667787194, 576.9480746732796, 591.3938213331335, 592.6621825559431, 20962.580748385022, 21854.89072554635, 21857.0, 22033.00851461686, 22467.438314042116, 24483.0, 25099.498010039788, 25198.496030079386, 25203.495930081433, 25213.495730085386, 6612.570182938093, 6718.260991290954, 6736.124303840518, 6740.325967403245, 6783.7625683101105, 5936.109583562457, 6515.543911926167, 6537.052127441518, 6720.563163394364, 6746.089277947488, 6453.354664533556, 6476.125723027401, 6485.902711459332, 6503.674816259192, 6511.28375878653, 6441.066045423434, 6460.547761656686, 6474.86689829278, 6495.382938620824, 6509.2839787623325, 6010.699465026775, 6024.759009639653, 6055.455009049207, 6067.939320606825, 6095.634261944253, 28082.15753527377, 28422.431551368856, 28424.005159819513, 28617.71382286171, 28850.134495965154, 2098.5068508900395, 2113.651247544153, 2133.1574028258906, 2136.7863213678665, 2140.176032227588, 18886.244550217976, 19321.80678193214, 19522.511937201543, 19632.11655475507, 20296.49258768528, 2277.7722227777253, 2289.9313020609407, 2294.265834932822, 2318.8608683479047, 2332.728662878735, 124161.37919310345, 127720.0, 128097.0, 132789.0, 136900.0, 14351.641208969859, 14507.564773056825, 14600.561983140522, 14888.255587220596, 14917.328720207617, 22713.0, 22802.719728027063, 22810.31569052931, 22815.429614259778, 22832.200872969366, 9863.753406164904, 10131.504630902327, 10234.53944572496, 10260.58957641686, 10312.0, 9164.816703666023, 9175.816483670289, 9227.861582076273, 9333.160015598629, 9391.248700103975, 7715.537067775951, 8909.7924747091, 9001.0, 9134.451932884049, 9386.906130938603, 2609.673790776153, 2643.70919198888, 2663.2942270298386, 2717.4836781011545, 2732.4671689020847, 946.4368700623119, 974.3910056214866, 977.6675930183771, 979.2117345536911, 979.5249304087512, 19009.14458849355, 19260.518487037654, 19461.318853840046, 19475.415737527896, 19492.610147796964, 1039.3607931122306, 1045.075108528942, 1049.5644307612256, 1050.1598721023151, 1050.5430137890041, 10501.842472362921, 10674.252802303765, 10716.892831071746, 10762.623308184176, 10903.400312982703, 434431.0, 439352.0, 441257.0, 466160.0, 474663.0, 119058.0, 121715.39142304233, 123228.38385808014, 125972.0, 126432.36783816022, 1213.2659740856832, 1223.4487705734653, 1241.1374095003885, 1259.9559015434415, 1263.412513181375, 20444.89777551162, 20480.48798779983, 20507.28224512135, 20538.0, 21040.368788936357, 56635.43364566302, 62170.68914655398, 62881.0, 64772.352276478516, 66089.33910660831, 76844.61577692076, 77596.2240377589, 78365.60817195878, 82118.58940705258, 83061.58469207615, 21118.260860869796, 21182.894085529475, 21692.45768855791, 21897.343079707633, 21899.12403503842, 23222.767772322735, 24386.26841194767, 24467.75532244655, 24644.753552464244, 24674.259772206864, 18415.171317290486, 18880.62238755245, 19164.329248476235, 19261.903690481457, 19268.518287042934, 16874.240659170122, 17236.138193090046, 17589.384371546934, 17771.20029598697, 17855.107244637977, 18732.438026859218, 19418.0, 19516.0, 19653.80346196577, 19716.0, 20511.17955281772, 20612.484687882923, 20775.480612984193, 20776.272830451468, 20902.686459703422, 17276.13619319054, 18011.099445027958, 18805.435836924913, 19110.904445477685, 19160.808391915903], "value": 12311.809885242297, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.296532837596, 1336.7861142217241, 1387.2578170678685, 1392.2899321346117, 1396.4763213794827, 14912.701745965087, 15326.15706136162, 15589.376424942988, 15595.0, 15599.844001559995, 15861.3655453782, 16123.516294511153, 16138.515844524654, 16139.838601613981, 16145.677086458292, 2736.849473278964, 2787.038471727261, 2787.1359878437647, 2787.456445993032, 2792.8743206555755, 2743.8353698778137, 2849.15949794811, 2849.5013372659805, 2851.2586727450803, 2853.2866783304175, 2670.826396284245, 2685.6911455182612, 2702.216357256393, 2702.8918843246347, 2703.783697304211, 2670.3457652874977, 2670.6795184577877, 2690.3812123211674, 2690.5560582503863, 2693.892244310225, 2520.735322791099, 2527.6208568714655, 2536.95984646296, 2537.6701028866128, 2539.733328000552, 14838.480653177086, 15195.696086078327, 15197.620059498491, 15206.543803685907, 15225.162616056112, 8054.556999365032, 8350.33197344211, 8357.540335281557, 8370.665173393058, 8374.162583741638, 5256.316678831766, 5387.703676297802, 5391.353037635509, 5480.945190548084, 5496.477834605706, 1885.1045753267088, 1885.8302752752181, 1893.1102381880494, 1927.4314077347217, 1939.1177014458387, 26950.46099078027, 27315.726842731714, 27378.178654640393, 27440.0, 27453.17640470789, 6511.860424425707, 6694.163229596301, 6718.563293385914, 6900.102986611709, 6903.413209877116, 17422.47732568025, 17426.12869356527, 17429.38997135094, 17429.738553921703, 17448.12759362027, 7935.047794264609, 8065.6370463329295, 8082.434229603871, 8095.433319667684, 8106.554139522265, 7211.963940180371, 7304.707811687579, 7331.9266807332315, 7387.298206670358, 7416.517926334771, 5158.406783219978, 5196.32447781786, 5200.635955483155, 5249.527542521191, 5260.500252476008, 1465.6482444213311, 1469.133211405276, 1486.353436255211, 1504.5787179589731, 1507.0957425544586, 882.2192359761528, 897.5422534507449, 914.6021480655936, 917.1653795046476, 925.227435091706, 7689.4232932530285, 7819.882701759479, 7824.334931530883, 7892.684292628231, 7895.960520197362, 707.4375871182423, 708.3129364516411, 732.150705181997, 736.5323019882306, 738.5162718419443, 4771.403574553181, 4808.230683090689, 5137.229415587692, 5586.301712285965, 5652.208690783294, 21376.251831185833, 22306.665400019014, 22476.55046899053, 22499.32502024942, 22625.547489050456, 21253.68119478209, 21520.139194432362, 22026.33920982373, 22064.33806985793, 22240.666390004164, 10829.29609575375, 10913.23607347494, 11063.557457701763, 11092.279001864854, 11094.22340436178, 8449.155084491502, 8463.0, 8544.786380340542, 8598.441101328393, 8659.177378149066, 17773.911130444267, 18276.72584911201, 18674.159662814935, 18690.0, 18809.529761756065, 19076.523086922938, 19078.236870525023, 19317.0, 19442.222311107962, 19489.805101948798, 10599.258051936291, 11931.105167112328, 12314.0, 12374.876251237372, 12375.0, 13781.035327526977, 13882.236476993863, 13888.097273677178, 13904.930475347559, 13935.860641393456, 11177.441127943408, 11222.494987725735, 11401.942990284995, 11418.0, 11535.769284614093, 11099.889001109885, 11172.664820055412, 11228.831567526331, 11425.885741142481, 11682.591109311134, 9701.951490242503, 9703.272254580794, 9710.902890971, 9722.951385243312, 9732.0, 7269.104314038185, 7300.16048154469, 7315.975763393023, 7322.487425880137, 7327.853442931005, 7109.75115870942, 7179.712811487482, 7237.095363079615, 7243.203247642649, 7354.521956072838], "value": 6659.086685750899, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.4476298453192, 510.3467561521252, 532.672406470021, 534.5483066808546, 537.344439783464, 6315.747370105196, 6325.722739813669, 6403.327650596691, 6408.967955160218, 6447.967760161205, 6030.427109424609, 6220.315765265817, 6224.346443623423, 6249.687515624212, 6281.0, 5043.495650434964, 5095.293076819252, 5119.1809310510325, 5120.513551212638, 5135.127028405162, 5031.4465408805, 5047.697138171703, 5102.36220472441, 5133.435322114565, 5134.512221338967, 4896.804127834882, 4919.130730322472, 4995.250712393152, 4995.950040499585, 5027.673201241908, 4921.237208232747, 4965.702057876503, 4974.626902982291, 4978.6017118630425, 4989.950100498985, 4458.621017213541, 4485.282354823213, 4485.506594274627, 4511.954880451186, 4519.502854685982, 6172.0, 6201.751929922798, 6211.161493198406, 6216.627002379872, 6230.750769969241, 5693.487586117228, 5716.941436331585, 5747.137929310595, 5794.565407594405, 5799.5650326225705, 6499.967500162516, 6567.0, 6589.17635295588, 6598.637074960923, 6635.170603674541, 4888.533439967995, 4897.926531102036, 4921.114199444099, 4923.138450771101, 4926.3842019747535, 6902.551334163263, 6941.583504989666, 6953.89569156463, 7011.789646310619, 7046.401055910305, 5066.518680725399, 5068.214426763858, 5072.594192464594, 5078.415982162025, 5081.0, 5115.309433226466, 5117.130087885069, 5118.155504341817, 5121.692698438105, 5135.0, 5689.68706721126, 5729.713514324267, 5733.77064917407, 5745.712714364265, 5766.94233057664, 6554.9672251639395, 6586.242582103024, 6660.83347916306, 6675.165604299463, 6687.899681504781, 1668.8581470575136, 1689.0878925380157, 1757.674830156425, 1782.8767876237887, 1787.9821201788077, 1205.541894080244, 1218.9268643881396, 1219.152688881219, 1232.5994051933158, 1242.589945318043, 5806.82579522615, 5835.766569337179, 5875.383084776123, 5878.265216847894, 5891.204687367236, 6626.337366263431, 6656.500762442836, 6686.5653732507235, 6721.26066132725, 6730.663466826737, 5852.297724273115, 5869.413058694182, 5906.5570082244, 5916.408359164049, 5965.522758179335, 3557.679808817219, 3575.5530558680166, 3577.4454959481322, 3612.765170263977, 3621.3843646580144, 6511.544191906521, 6517.91426743447, 6554.246261679872, 6557.6228991911275, 6559.5408321418, 6853.246142924274, 6878.454971660518, 6978.0, 6982.1970473395195, 7058.576485410892, 6477.870442591122, 6511.772087976993, 6548.214214294315, 6603.867922641521, 6607.9669601651685, 6685.328667833041, 6752.966235168793, 6771.356721111487, 6842.93157068423, 6846.520743547904, 6775.559588626724, 6802.217744959393, 6811.557248778813, 6834.1457317835275, 6856.485763567653, 6564.770233041821, 6616.239132499824, 6647.302033286629, 6697.497687673542, 6707.631080290631, 5485.122380419114, 5503.449655034465, 5557.610967232416, 5568.888622227614, 5570.749316280858, 6784.830379240559, 6823.488238382042, 6855.72700978599, 6857.55425897335, 6901.62041087745, 6476.474175071631, 6489.221293444817, 6509.1863517060365, 6554.442872355903, 6606.4384527315715, 6468.676566171578, 6511.47908167336, 6516.0551719999385, 6530.346965303432, 6546.869062618625, 6168.784092556739, 6392.232932048184, 6557.245916719638, 6579.572327798869, 6596.670166491752, 6211.503079753699, 6513.609183449008, 6520.18497687789, 6583.156716119494, 6636.170478690164, 6263.467605253604, 6284.994400895835, 6299.527535434769, 6358.586691865014, 6424.293327734039], "value": 5113.82563134586, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [911.3210658059745, 911.4850109688026, 930.4649826349851, 935.1677007463354, 941.7363138321268, 18712.532186695324, 19980.700289495668, 20021.699674504856, 20025.299114530993, 20609.690854637192, 20630.48423789406, 21540.89229553854, 21563.784362156373, 21637.675434868488, 21725.565488690216, 961.2262128986164, 999.1058003087232, 1000.4947501511726, 1001.0, 1002.2032484175062, 917.4862077236769, 997.670768646345, 1001.0190013786494, 1016.0753714120152, 1016.9084782369586, 1061.8778840433372, 1065.1265961911236, 1065.4033741104968, 1065.5098654618846, 1065.6643157405392, 1052.2897044494973, 1052.9157667386592, 1053.515382923856, 1059.327327147267, 1059.8622179116742, 776.7436745873905, 777.3703300326754, 777.3742137579246, 777.5140537164273, 777.5956502618607, 20125.698114528295, 20315.796842031537, 20451.897740511202, 20484.487887802774, 21212.469688257763, 21004.789952100436, 21177.152913883427, 21656.89171554148, 21677.241296554545, 21703.674444883338, 17400.129993500275, 17474.825251747574, 17494.91252543742, 17625.38311159116, 17672.734908976374, 3757.7035922606656, 3758.8872333830027, 3777.4333849922455, 3777.924441511182, 3778.3199024175638, 121850.0, 121936.0, 121953.39023304827, 124142.0, 126129.0, 16475.83524164767, 16496.587585310463, 17055.829441705424, 17119.058451785273, 17139.485815425556, 29705.81176752949, 30108.397832043236, 30219.09342719722, 30227.848860755556, 31016.0, 7542.924570754332, 7870.212978702084, 8532.976042874894, 8648.481091134552, 8900.465972041698, 4413.16149931512, 4506.864794056183, 4507.301368287921, 4519.728816271035, 4531.093781243764, 5322.414534401213, 5431.565474762009, 5441.83674489766, 5480.945190548123, 5494.230807686847, 1091.2906610703108, 1099.9450027498754, 1124.8031594470935, 1131.7170707323169, 1135.1202817816093, 662.3409707341226, 681.4003676764415, 687.7352219395568, 693.3205458650551, 698.7833771530842, 11540.826887596693, 11685.591004314809, 11996.040316774812, 12394.504219831106, 13641.249731264876, 707.8407358344331, 709.8402859356603, 719.7552832037132, 721.3832173491589, 735.6211551051213, 5426.403095659474, 5709.34342550612, 5803.158542011384, 5874.9118763218585, 6043.607165534227, 107064.46467767611, 107271.4636426813, 107445.46277268564, 108963.0, 110170.0, 95438.0, 96095.51952240334, 98001.50999244959, 100468.49765751271, 100858.0, 82508.0, 83655.0, 83858.58070709607, 88290.55854720685, 90947.54526227327, 12547.93726031364, 12681.619551413472, 12813.0, 13169.209847409185, 13200.801987969997, 37985.81007094947, 38896.6110338893, 39561.0, 40247.0, 41412.585874140874, 69238.0, 70271.64864175646, 70769.64615176892, 71139.0, 71306.0, 18343.449696508593, 18372.448826535227, 18732.0, 19218.903905480383, 19238.61522769565, 21947.451313717283, 22091.0, 22201.22295719642, 22369.105235790386, 22524.324270271918, 18837.529061773566, 19475.61048778988, 19707.40877773669, 19755.60488790245, 20006.69989950181, 18996.810031899506, 19038.619227615647, 19203.32788352401, 19429.61140777148, 19697.310594129136, 18036.549086272422, 18181.54546136357, 18204.726929096338, 18290.26838926428, 18832.152553135416, 14044.929775351058, 14168.716625667223, 14178.574642760734, 14307.21310327942, 14887.10677359362, 14290.425085436495, 14441.277936103363, 14647.487337943121, 14788.482403115839, 15187.0], "value": 9374.052296184786, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1484.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 107.0, 95.0, 108.0, 108.0, 108.0, 111.0, 105.0, 110.0, 111.0], "value": 106.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 138.0, 176.0, 118.0, 147.0, 140.0, 116.0, 170.0, 132.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 169.0, 164.0, 162.0, 163.0, 162.0, 169.0, 167.0, 162.0, 166.0], "value": 164.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 170.0, 75.0, 74.0, 74.0, 75.0, 141.0, 75.0], "value": 90.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 79.0, 127.0, 79.0, 78.0, 79.0, 135.0, 79.0, 78.0, 138.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 116.0, 115.0, 114.0, 115.0, 115.0, 115.0, 114.0, 114.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 148.0, 148.0, 148.0, 149.0, 148.0, 147.0, 148.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 93.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 52.0, 53.0, 52.0, 51.0, 53.0, 52.0, 52.0, 53.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 39.0, 39.0, 39.0, 38.0, 38.0, 39.0, 39.0, 39.0], "value": 38.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 74.0, 74.0, 76.0, 74.0, 76.0, 81.0, 77.0, 77.0, 74.0], "value": 76.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 126.0, 119.0, 117.0, 123.0, 123.0, 121.0, 124.0, 118.0, 154.0], "value": 124.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 181.0, 174.0, 171.0, 180.0, 168.0, 171.0, 166.0, 181.0, 167.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 64.0, 66.0, 66.0, 63.0, 65.0, 62.0, 64.0, 64.0, 64.0], "value": 64.8, "unit": "score"}]}]} 03:11:10 INFO - Return code: 0 03:11:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:11:10 INFO - # TBPL SUCCESS # 03:11:10 INFO - Running post-action listener: _package_coverage_data 03:11:10 INFO - Running post-action listener: _resource_record_post_action 03:11:10 INFO - [mozharness: 2017-09-02 10:11:10.178844Z] Finished run-tests step (success) 03:11:10 INFO - Running post-run listener: _resource_record_post_run 03:11:10 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 12288; Write bytes: 393797632; Read time: 396; Write time: 377344 03:11:10 INFO - TinderboxPrint: CPU usage
13.1% 03:11:10 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 396 03:11:10 INFO - TinderboxPrint: I/O write bytes / time
393,797,632 / 377,344 03:11:10 INFO - TinderboxPrint: CPU idle
8,241.7 (86.9%) 03:11:10 INFO - TinderboxPrint: CPU user
1,193.2 (12.6%) 03:11:10 INFO - TinderboxPrint: Swap in / out
0 / 0 03:11:10 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 104402944; Read time: 140; Write time: 137604 03:11:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:11:10 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 285192192; Read time: 244; Write time: 239212 03:11:10 INFO - Running post-run listener: _upload_blobber_files 03:11:10 INFO - Blob upload gear active. 03:11:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:11:10 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 03:11:10 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:11:10 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:11:11 INFO - (blobuploader) - INFO - Open directory for files ... 03:11:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:11:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:11:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:11:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:11:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:11:12 INFO - (blobuploader) - INFO - Done attempting. 03:11:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:11:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:11:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:11:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:11:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:11:13 INFO - (blobuploader) - INFO - Done attempting. 03:11:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:11:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:11:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:11:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:11:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:11:14 INFO - (blobuploader) - INFO - Done attempting. 03:11:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:11:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:11:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:11:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:11:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:11:21 INFO - (blobuploader) - INFO - Done attempting. 03:11:21 INFO - (blobuploader) - INFO - Iteration through files over. 03:11:21 INFO - Return code: 0 03:11:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:11:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:11:21 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e9806c45d6ecf2927189df81639b466e442dbed00a4bf511ac1ae2b413fee6045dc67836d57521a1294f3a1380681c5339e387d6e0eb3702d856bb42b2033a8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90cc7f92f7148fd0222883787a7fceb0ecc9e42c1cda9eb924a9759a1ea94f6bb584b88fb015f844ac4b72d67c26725932605d2a81012574247a6eafd4f2ebec", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db07445e08b44376f61bd66009fc5676f9d8036a46124da52b9cb98a4e4bc60a921191c740a8b5abb72b6221ff9522cb001809b6b1180c16938da6260bf6ea75", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d32e80f19d720b6c5b9fab0a4bff0ddfffe485021814885f2c91b14b34d26b519b820ac8ef4211c589faff6b8ec2dc792509c7e7de3e6ef9ec2563825211f3ce"} 03:11:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:11:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:11:21 INFO - Contents: 03:11:21 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e9806c45d6ecf2927189df81639b466e442dbed00a4bf511ac1ae2b413fee6045dc67836d57521a1294f3a1380681c5339e387d6e0eb3702d856bb42b2033a8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90cc7f92f7148fd0222883787a7fceb0ecc9e42c1cda9eb924a9759a1ea94f6bb584b88fb015f844ac4b72d67c26725932605d2a81012574247a6eafd4f2ebec", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db07445e08b44376f61bd66009fc5676f9d8036a46124da52b9cb98a4e4bc60a921191c740a8b5abb72b6221ff9522cb001809b6b1180c16938da6260bf6ea75", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d32e80f19d720b6c5b9fab0a4bff0ddfffe485021814885f2c91b14b34d26b519b820ac8ef4211c589faff6b8ec2dc792509c7e7de3e6ef9ec2563825211f3ce"} 03:11:21 INFO - Running post-run listener: copy_logs_to_upload_dir 03:11:21 INFO - Copying logs to upload dir... 03:11:21 INFO - mkdir: /builds/slave/test/build/upload/logs 03:11:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.549009 ========= master_lag: 0.36 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-09-02 03:11:21.950921) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 03:11:21.954167) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e9806c45d6ecf2927189df81639b466e442dbed00a4bf511ac1ae2b413fee6045dc67836d57521a1294f3a1380681c5339e387d6e0eb3702d856bb42b2033a8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90cc7f92f7148fd0222883787a7fceb0ecc9e42c1cda9eb924a9759a1ea94f6bb584b88fb015f844ac4b72d67c26725932605d2a81012574247a6eafd4f2ebec", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db07445e08b44376f61bd66009fc5676f9d8036a46124da52b9cb98a4e4bc60a921191c740a8b5abb72b6221ff9522cb001809b6b1180c16938da6260bf6ea75", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d32e80f19d720b6c5b9fab0a4bff0ddfffe485021814885f2c91b14b34d26b519b820ac8ef4211c589faff6b8ec2dc792509c7e7de3e6ef9ec2563825211f3ce"} build_url:https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011367 build_url: 'https://queue.taskcluster.net/v1/task/elhbt6dOSlyDfLTG7lCKow/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e9806c45d6ecf2927189df81639b466e442dbed00a4bf511ac1ae2b413fee6045dc67836d57521a1294f3a1380681c5339e387d6e0eb3702d856bb42b2033a8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90cc7f92f7148fd0222883787a7fceb0ecc9e42c1cda9eb924a9759a1ea94f6bb584b88fb015f844ac4b72d67c26725932605d2a81012574247a6eafd4f2ebec", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db07445e08b44376f61bd66009fc5676f9d8036a46124da52b9cb98a4e4bc60a921191c740a8b5abb72b6221ff9522cb001809b6b1180c16938da6260bf6ea75", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d32e80f19d720b6c5b9fab0a4bff0ddfffe485021814885f2c91b14b34d26b519b820ac8ef4211c589faff6b8ec2dc792509c7e7de3e6ef9ec2563825211f3ce"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 03:11:21.985155) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 03:11:21.985490) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1504345828.86082-1257486839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004514 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 03:11:22.034636) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 03:11:22.034962) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 03:11:22.035252) ========= ========= Total master_lag: 0.49 =========