builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-085 starttime: 1504686611.8 results: success (0) revision: b235fb79d6e017b9f47309cb06eb701c06b7e8d2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:11.799894) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:11.800398) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:11.800718) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:11.868664) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:11.869001) ========= 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=5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-06 01:30:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 36.6M=0s 2017-09-06 01:30:12 (36.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.159377 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:12.058826) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:12.059210) ========= 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=5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014423 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:12.109080) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-06 01:30:12.111501) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b235fb79d6e017b9f47309cb06eb701c06b7e8d2 --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 b235fb79d6e017b9f47309cb06eb701c06b7e8d2 --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=5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-06 01:30:12,312 truncating revision to first 12 chars 2017-09-06 01:30:12,312 Setting DEBUG logging. 2017-09-06 01:30:12,312 attempt 1/10 2017-09-06 01:30:12,312 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b235fb79d6e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-06 01:30:12,869 attempt 1/10 2017-09-06 01:30:13,506 current task status: no status available at this point. state: STARTED 2017-09-06 01:30:13,506 sleeping for 9.00s (attempt 1/10) 2017-09-06 01:30:22,516 attempt 2/10 2017-09-06 01:30:22,865 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-06 01:30:23,096 unpacking tar archive at: mozilla-central-b235fb79d6e0/testing/mozharness/ program finished with exit code 0 elapsedTime=11.165522 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-06 01:30:23.300268) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:23.300602) ========= script_repo_revision: b235fb79d6e017b9f47309cb06eb701c06b7e8d2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:23.300990) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:23.301267) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-06 01:30:23.314754) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-06 01:30:23.315098) ========= /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=5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:30:23 INFO - MultiFileLogger online at 20170906 01:30:23 in /builds/slave/test 01:30:23 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 01:30:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:30:23 INFO - {'append_to_log': False, 01:30:23 INFO - 'base_work_dir': '/builds/slave/test', 01:30:23 INFO - 'blob_upload_branch': 'Firefox', 01:30:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:30:23 INFO - 'branch': 'Firefox', 01:30:23 INFO - 'buildbot_json_path': 'buildprops.json', 01:30:23 INFO - 'code_coverage': False, 01:30:23 INFO - 'config_files': ('talos/linux_config.py',), 01:30:23 INFO - 'default_actions': ('clobber', 01:30:23 INFO - 'read-buildbot-config', 01:30:23 INFO - 'download-and-extract', 01:30:23 INFO - 'populate-webroot', 01:30:23 INFO - 'create-virtualenv', 01:30:23 INFO - 'install', 01:30:23 INFO - 'setup-mitmproxy', 01:30:23 INFO - 'run-tests'), 01:30:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:30:23 INFO - 'disable_ccov_upload': False, 01:30:23 INFO - 'disable_stylo': False, 01:30:23 INFO - 'download_minidump_stackwalk': True, 01:30:23 INFO - 'download_symbols': 'ondemand', 01:30:23 INFO - 'enable_stylo': False, 01:30:23 INFO - 'enable_webrender': False, 01:30:23 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 01:30:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:30:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:30:23 INFO - 'gecko_profile': False, 01:30:23 INFO - 'gecko_profile_interval': 0, 01:30:23 INFO - 'installer_path': 'installer.exe', 01:30:23 INFO - 'log_level': 'info', 01:30:23 INFO - 'log_name': 'talos', 01:30:23 INFO - 'log_to_console': True, 01:30:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:30:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:30:23 INFO - 'opt_config_files': (), 01:30:23 INFO - 'pip_index': False, 01:30:23 INFO - 'suite': 'dromaeojs-e10s', 01:30:23 INFO - 'system_bits': '32', 01:30:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:30:23 INFO - 'title': 'talos-linux64-ix-085', 01:30:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:30:23 INFO - 'use_talos_json': True, 01:30:23 INFO - 'verify': 'False', 01:30:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:30:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:30:23 INFO - 'work_dir': 'build'} 01:30:23 INFO - [mozharness: 2017-09-06 08:30:23.656354Z] Running clobber step. 01:30:23 INFO - Running pre-action listener: _resource_record_pre_action 01:30:23 INFO - Running main action method: clobber 01:30:23 INFO - rmtree: /builds/slave/test/build 01:30:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:30:24 INFO - Running post-action listener: _resource_record_post_action 01:30:24 INFO - [mozharness: 2017-09-06 08:30:24.662585Z] Finished clobber step (success) 01:30:24 INFO - [mozharness: 2017-09-06 08:30:24.662684Z] Running read-buildbot-config step. 01:30:24 INFO - Running pre-action listener: _resource_record_pre_action 01:30:24 INFO - Running main action method: read_buildbot_config 01:30:24 INFO - Using buildbot properties: 01:30:24 INFO - { 01:30:24 INFO - "project": "", 01:30:24 INFO - "product": "firefox", 01:30:24 INFO - "who": "archaeopteryx@coole-files.de", 01:30:24 INFO - "installer_path": "public/build/target.tar.bz2", 01:30:24 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 01:30:24 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 01:30:24 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 01:30:24 INFO - "stage_platform": "linux64-stylo", 01:30:24 INFO - "basedir": "/builds/slave/test", 01:30:24 INFO - "buildnumber": 121, 01:30:24 INFO - "platform": "ubuntu64_hw_stylo", 01:30:24 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:30:24 INFO - "slavebuilddir": "test", 01:30:24 INFO - "taskId": "TFHLtlWdQaqNjAe_6E8aAA", 01:30:24 INFO - "branch": "mozilla-central", 01:30:24 INFO - "script_repo_revision": "production", 01:30:24 INFO - "revision": "b235fb79d6e017b9f47309cb06eb701c06b7e8d2", 01:30:24 INFO - "slavename": "talos-linux64-ix-085", 01:30:24 INFO - "repo_path": "mozilla-central" 01:30:24 INFO - } 01:30:24 INFO - Finding installer, test and symbols from parent task. 01:30:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TFHLtlWdQaqNjAe_6E8aAA'}, attempt #1 01:30:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TFHLtlWdQaqNjAe_6E8aAA'}, attempt #1 01:30:25 INFO - Task dependencies: K7fP2uF7RUix-OKaOebu0g 01:30:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g'}, attempt #1 01:30:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2 01:30:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.test_packages.json 01:30:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.crashreporter-symbols.zip 01:30:26 INFO - Running post-action listener: _resource_record_post_action 01:30:26 INFO - [mozharness: 2017-09-06 08:30:26.107893Z] Finished read-buildbot-config step (success) 01:30:26 INFO - [mozharness: 2017-09-06 08:30:26.108110Z] Running download-and-extract step. 01:30:26 INFO - Running pre-action listener: _resource_record_pre_action 01:30:26 INFO - Running main action method: download_and_extract 01:30:26 INFO - mkdir: /builds/slave/test/build/tests 01:30:26 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')]} 01:30:26 INFO - trying https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.test_packages.json 01:30:26 INFO - Downloading https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 01:30:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 01:30:26 INFO - Downloaded 989 bytes. 01:30:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 01:30:26 INFO - Using the following test package requirements: 01:30:26 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 01:30:26 INFO - u'common': [u'target.common.tests.zip'], 01:30:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 01:30:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 01:30:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 01:30:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 01:30:26 INFO - u'mozbase': [u'target.common.tests.zip'], 01:30:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 01:30:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 01:30:26 INFO - u'web-platform': [u'target.common.tests.zip', 01:30:26 INFO - u'target.web-platform.tests.tar.gz'], 01:30:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 01:30:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 01:30:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.common.tests.zip 01:30:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:30:26 INFO - Fetch https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.common.tests.zip into memory 01:30:27 INFO - Content-Length response header: 55019854 01:30:27 INFO - Bytes received: 55019854 01:30:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 01:30:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.common.tests.zip 01:30:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:30:39 INFO - Fetch https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.common.tests.zip into memory 01:30:40 INFO - Content-Length response header: 55019854 01:30:40 INFO - Bytes received: 55019854 01:30:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.talos.tests.zip 01:30:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 01:30:52 INFO - Fetch https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.talos.tests.zip into memory 01:30:53 INFO - Content-Length response header: 14496654 01:30:53 INFO - Bytes received: 14496654 01:30:53 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')]} 01:30:53 INFO - trying https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2 01:30:53 INFO - Downloading https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 01:30:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 01:30:54 INFO - Downloaded 62756371 bytes. 01:30:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2 01:30:54 INFO - mkdir: /builds/slave/test/properties 01:30:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:30:54 INFO - Writing to file /builds/slave/test/properties/build_url 01:30:54 INFO - Contents: 01:30:54 INFO - build_url:https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2 01:30:54 INFO - Running post-action listener: _resource_record_post_action 01:30:54 INFO - Running post-action listener: find_tests_for_verification 01:30:54 INFO - Running post-action listener: set_extra_try_arguments 01:30:54 INFO - [mozharness: 2017-09-06 08:30:54.373771Z] Finished download-and-extract step (success) 01:30:54 INFO - [mozharness: 2017-09-06 08:30:54.373868Z] Running populate-webroot step. 01:30:54 INFO - Running pre-action listener: _resource_record_pre_action 01:30:54 INFO - Running main action method: populate_webroot 01:30:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:30:54 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:30:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:30:54 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:30:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:30:54 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:30:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'tests': ['damp', 'tps']}, 01:30:54 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['damp', 'tps']}, 01:30:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:30:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 01:30:54 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 01:30:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 01:30:54 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 01:30:54 INFO - 'other-e10s': {'tests': ['a11yr', 01:30:54 INFO - 'ts_paint', 01:30:54 INFO - 'tpaint', 01:30:54 INFO - 'sessionrestore', 01:30:54 INFO - 'sessionrestore_many_windows', 01:30:54 INFO - 'sessionrestore_no_auto_restore', 01:30:54 INFO - 'tabpaint']}, 01:30:54 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['a11yr', 01:30:54 INFO - 'ts_paint', 01:30:54 INFO - 'tpaint', 01:30:54 INFO - 'sessionrestore', 01:30:54 INFO - 'sessionrestore_many_windows', 01:30:54 INFO - 'sessionrestore_no_auto_restore', 01:30:54 INFO - 'tabpaint']}, 01:30:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 01:30:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 01:30:54 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['bloom_basic_singleton']}, 01:30:54 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['bloom_basic']}, 01:30:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:30:54 INFO - 'tsvgr_opacity', 01:30:54 INFO - 'tart', 01:30:54 INFO - 'tscrollx', 01:30:54 INFO - 'tsvg_static']}, 01:30:54 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['tsvgx', 01:30:54 INFO - 'tsvgr_opacity', 01:30:54 INFO - 'tart', 01:30:54 INFO - 'tscrollx', 01:30:54 INFO - 'tsvg_static']}, 01:30:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:30:54 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'talos_options': ['--enable-stylo'], 01:30:54 INFO - 'tests': ['tp5o']}, 01:30:54 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 01:30:54 INFO - 'talos_options': ['--mitmproxy', 01:30:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 01:30:54 INFO - '--firstNonBlankPaint'], 01:30:54 INFO - 'tests': ['tp6_google', 01:30:54 INFO - 'tp6_youtube', 01:30:54 INFO - 'tp6_amazon', 01:30:54 INFO - 'tp6_facebook']}, 01:30:54 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 01:30:54 INFO - 'talos_options': ['--enable-stylo', 01:30:54 INFO - '--mitmproxy', 01:30:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 01:30:54 INFO - '--firstNonBlankPaint'], 01:30:54 INFO - 'tests': ['tp6_google', 01:30:54 INFO - 'tp6_youtube', 01:30:54 INFO - 'tp6_amazon', 01:30:54 INFO - 'tp6_facebook']}, 01:30:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 01:30:54 INFO - 'talos_options': ['--enable-stylo', 01:30:54 INFO - '--stylo-threads=1', 01:30:54 INFO - '--mitmproxy', 01:30:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 01:30:54 INFO - '--firstNonBlankPaint'], 01:30:54 INFO - 'tests': ['tp6_google', 01:30:54 INFO - 'tp6_youtube', 01:30:54 INFO - 'tp6_amazon', 01:30:54 INFO - 'tp6_facebook']}, 01:30:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'talos_options': ['--xperf_path', 01:30:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:30:54 INFO - 'tests': ['tp5n']}, 01:30:54 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:30:54 INFO - 'talos_options': ['--enable-stylo', 01:30:54 INFO - '--xperf_path', 01:30:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:30:54 INFO - 'tests': ['tp5n']}}} 01:30:54 INFO - Running post-action listener: _resource_record_post_action 01:30:54 INFO - [mozharness: 2017-09-06 08:30:54.388180Z] Finished populate-webroot step (success) 01:30:54 INFO - [mozharness: 2017-09-06 08:30:54.388269Z] Running create-virtualenv step. 01:30:54 INFO - Running pre-action listener: _resource_record_pre_action 01:30:54 INFO - Running main action method: create_virtualenv 01:30:54 INFO - Creating virtualenv /builds/slave/test/build/venv 01:30:54 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:30:54 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 01:30:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 01:30:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:30:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:30:55 INFO - Installing distribute.............................................................................................................................................................................................done. 01:30:55 INFO - Installing pip...............done. 01:30:55 INFO - Return code: 0 01:30:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:30:55 INFO - Reading from file tmpfile_stdout 01:30:55 INFO - Output received: 01:30:55 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 01:30:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:30:55 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')]} 01:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:55 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')]} 01:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:55 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 0x1a4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbb090>, '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 0x1dbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db5f90>, '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': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', '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 01:30:55 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 01:30:55 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 01:30:55 INFO - Using env: {'DISPLAY': ':0', 01:30:55 INFO - 'HOME': '/home/cltbld', 01:30:55 INFO - 'LANG': 'en_US.UTF-8', 01:30:55 INFO - 'LANGUAGE': 'en_US:en', 01:30:55 INFO - 'LOGNAME': 'cltbld', 01:30:55 INFO - 'MAIL': '/var/mail/cltbld', 01:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:55 INFO - 'MOZ_NO_REMOTE': '1', 01:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:30:55 INFO - 'NO_EM_RESTART': '1', 01:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:55 INFO - 'PWD': '/builds/slave/test', 01:30:55 INFO - 'SHELL': '/bin/bash', 01:30:55 INFO - 'SHLVL': '1', 01:30:55 INFO - 'TERM': 'linux', 01:30:55 INFO - 'TMOUT': '86400', 01:30:55 INFO - 'USER': 'cltbld', 01:30:55 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', 01:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:55 INFO - '_': '/tools/buildbot/bin/python'} 01:30:56 INFO - Ignoring indexes: http://pypi.python.org/simple/ 01:30:56 INFO - Downloading/unpacking pip>=1.5 01:30:56 INFO - Running setup.py egg_info for package pip 01:30:56 INFO - warning: no files found matching 'pip/cacert.pem' 01:30:56 INFO - warning: no files found matching '*.html' under directory 'docs' 01:30:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 01:30:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 01:30:56 INFO - Installing collected packages: pip 01:30:56 INFO - Found existing installation: pip 1.1 01:30:56 INFO - Uninstalling pip: 01:30:56 INFO - Successfully uninstalled pip 01:30:56 INFO - Running setup.py install for pip 01:30:56 INFO - warning: no files found matching 'pip/cacert.pem' 01:30:56 INFO - warning: no files found matching '*.html' under directory 'docs' 01:30:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 01:30:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 01:30:56 INFO - Installing pip script to /builds/slave/test/build/venv/bin 01:30:56 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 01:30:56 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 01:30:56 INFO - Successfully installed pip 01:30:56 INFO - Cleaning up... 01:30:56 INFO - Return code: 0 01:30:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:30:56 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')]} 01:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:56 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')]} 01:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:56 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 0x1a4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbb090>, '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 0x1dbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db5f90>, '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': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', '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 01:30:56 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 01:30:56 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 01:30:56 INFO - Using env: {'DISPLAY': ':0', 01:30:56 INFO - 'HOME': '/home/cltbld', 01:30:56 INFO - 'LANG': 'en_US.UTF-8', 01:30:56 INFO - 'LANGUAGE': 'en_US:en', 01:30:56 INFO - 'LOGNAME': 'cltbld', 01:30:56 INFO - 'MAIL': '/var/mail/cltbld', 01:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:56 INFO - 'MOZ_NO_REMOTE': '1', 01:30:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:30:56 INFO - 'NO_EM_RESTART': '1', 01:30:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:56 INFO - 'PWD': '/builds/slave/test', 01:30:56 INFO - 'SHELL': '/bin/bash', 01:30:56 INFO - 'SHLVL': '1', 01:30:56 INFO - 'TERM': 'linux', 01:30:56 INFO - 'TMOUT': '86400', 01:30:56 INFO - 'USER': 'cltbld', 01:30:56 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', 01:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:56 INFO - '_': '/tools/buildbot/bin/python'} 01:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:56 INFO - Downloading/unpacking psutil>=3.1.1 01:30:56 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 01:30:56 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 01:30:56 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 01:30:56 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 01:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:30:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:30:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:30:58 INFO - Installing collected packages: psutil 01:30:58 INFO - Running setup.py install for psutil 01:30:58 INFO - building 'psutil._psutil_linux' extension 01:30:58 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 01:30:58 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 01:30:58 INFO - building 'psutil._psutil_posix' extension 01:30:58 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 01:30:58 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 01:30:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:30:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:30:58 INFO - Successfully installed psutil 01:30:58 INFO - Cleaning up... 01:30:58 INFO - Return code: 0 01:30:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:30:58 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')]} 01:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:58 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')]} 01:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:58 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 0x1a4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbb090>, '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 0x1dbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db5f90>, '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': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', '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 01:30:58 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 01:30:58 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 01:30:58 INFO - Using env: {'DISPLAY': ':0', 01:30:58 INFO - 'HOME': '/home/cltbld', 01:30:58 INFO - 'LANG': 'en_US.UTF-8', 01:30:58 INFO - 'LANGUAGE': 'en_US:en', 01:30:58 INFO - 'LOGNAME': 'cltbld', 01:30:58 INFO - 'MAIL': '/var/mail/cltbld', 01:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:58 INFO - 'MOZ_NO_REMOTE': '1', 01:30:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:30:58 INFO - 'NO_EM_RESTART': '1', 01:30:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:58 INFO - 'PWD': '/builds/slave/test', 01:30:58 INFO - 'SHELL': '/bin/bash', 01:30:58 INFO - 'SHLVL': '1', 01:30:58 INFO - 'TERM': 'linux', 01:30:58 INFO - 'TMOUT': '86400', 01:30:58 INFO - 'USER': 'cltbld', 01:30:58 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', 01:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:58 INFO - '_': '/tools/buildbot/bin/python'} 01:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:30:58 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 01:30:58 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 01:30:58 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 01:30:58 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 01:30:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:30:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:30:59 INFO - Installing collected packages: mozsystemmonitor 01:30:59 INFO - Running setup.py install for mozsystemmonitor 01:31:00 INFO - Successfully installed mozsystemmonitor 01:31:00 INFO - Cleaning up... 01:31:00 INFO - Return code: 0 01:31:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:31:00 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')]} 01:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:00 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')]} 01:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:00 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 0x1a4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbb090>, '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 0x1dbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db5f90>, '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': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', '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 01:31:00 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 01:31:00 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 01:31:00 INFO - Using env: {'DISPLAY': ':0', 01:31:00 INFO - 'HOME': '/home/cltbld', 01:31:00 INFO - 'LANG': 'en_US.UTF-8', 01:31:00 INFO - 'LANGUAGE': 'en_US:en', 01:31:00 INFO - 'LOGNAME': 'cltbld', 01:31:00 INFO - 'MAIL': '/var/mail/cltbld', 01:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:00 INFO - 'MOZ_NO_REMOTE': '1', 01:31:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:00 INFO - 'NO_EM_RESTART': '1', 01:31:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:00 INFO - 'PWD': '/builds/slave/test', 01:31:00 INFO - 'SHELL': '/bin/bash', 01:31:00 INFO - 'SHLVL': '1', 01:31:00 INFO - 'TERM': 'linux', 01:31:00 INFO - 'TMOUT': '86400', 01:31:00 INFO - 'USER': 'cltbld', 01:31:00 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', 01:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:00 INFO - '_': '/tools/buildbot/bin/python'} 01:31:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:00 INFO - Downloading/unpacking jsonschema==2.5.1 01:31:00 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 01:31:00 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 01:31:00 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 01:31:00 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 01:31:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:31:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:01 INFO - Downloading functools32-3.2.3-2.tar.gz 01:31:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:31:01 INFO - warning: no files found matching '*.txt' 01:31:01 INFO - no previously-included directories found matching 'build' 01:31:01 INFO - no previously-included directories found matching 'dist' 01:31:01 INFO - no previously-included directories found matching '.git*' 01:31:01 INFO - Installing collected packages: jsonschema, functools32 01:31:01 INFO - Running setup.py install for functools32 01:31:01 INFO - warning: no files found matching '*.txt' 01:31:01 INFO - no previously-included directories found matching 'build' 01:31:01 INFO - no previously-included directories found matching 'dist' 01:31:01 INFO - no previously-included directories found matching '.git*' 01:31:01 INFO - Successfully installed jsonschema functools32 01:31:01 INFO - Cleaning up... 01:31:01 INFO - Return code: 0 01:31:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:31: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')]} 01:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31: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')]} 01:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31: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', '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 0x1a4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbb090>, '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 0x1dbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db5f90>, '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': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', '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 01:31: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:31: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 functools32==3.2.3-2 01:31:01 INFO - Using env: {'DISPLAY': ':0', 01:31:01 INFO - 'HOME': '/home/cltbld', 01:31:01 INFO - 'LANG': 'en_US.UTF-8', 01:31:01 INFO - 'LANGUAGE': 'en_US:en', 01:31:01 INFO - 'LOGNAME': 'cltbld', 01:31:01 INFO - 'MAIL': '/var/mail/cltbld', 01:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:01 INFO - 'MOZ_NO_REMOTE': '1', 01:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:01 INFO - 'NO_EM_RESTART': '1', 01:31:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:01 INFO - 'PWD': '/builds/slave/test', 01:31:01 INFO - 'SHELL': '/bin/bash', 01:31:01 INFO - 'SHLVL': '1', 01:31:01 INFO - 'TERM': 'linux', 01:31:01 INFO - 'TMOUT': '86400', 01:31:01 INFO - 'USER': 'cltbld', 01:31:01 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', 01:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:01 INFO - '_': '/tools/buildbot/bin/python'} 01:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:31:01 INFO - Cleaning up... 01:31:01 INFO - Return code: 0 01:31:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:31: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')]} 01:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31: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')]} 01:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31: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', '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 0x1a4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbb090>, '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 0x1dbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db5f90>, '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': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', '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 01:31: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:31: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 blobuploader==1.2.4 01:31:01 INFO - Using env: {'DISPLAY': ':0', 01:31:01 INFO - 'HOME': '/home/cltbld', 01:31:01 INFO - 'LANG': 'en_US.UTF-8', 01:31:01 INFO - 'LANGUAGE': 'en_US:en', 01:31:01 INFO - 'LOGNAME': 'cltbld', 01:31:01 INFO - 'MAIL': '/var/mail/cltbld', 01:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:01 INFO - 'MOZ_NO_REMOTE': '1', 01:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:01 INFO - 'NO_EM_RESTART': '1', 01:31:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:01 INFO - 'PWD': '/builds/slave/test', 01:31:01 INFO - 'SHELL': '/bin/bash', 01:31:01 INFO - 'SHLVL': '1', 01:31:01 INFO - 'TERM': 'linux', 01:31:01 INFO - 'TMOUT': '86400', 01:31:01 INFO - 'USER': 'cltbld', 01:31:01 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', 01:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:01 INFO - '_': '/tools/buildbot/bin/python'} 01:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:02 INFO - Downloading/unpacking blobuploader==1.2.4 01:31:02 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 01:31:02 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 01:31:02 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 01:31:02 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 01:31:03 INFO - Downloading blobuploader-1.2.4.tar.gz 01:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:31:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:31: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 01:31: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 01:31: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 01:31: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 01:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:31:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:31: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 01:31: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 01:31: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 01:31: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 01:31:03 INFO - Downloading docopt-0.6.1.tar.gz 01:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:31:03 INFO - Installing collected packages: blobuploader, requests, docopt 01:31:03 INFO - Running setup.py install for blobuploader 01:31:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:31:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:31:03 INFO - Running setup.py install for requests 01:31:04 INFO - Running setup.py install for docopt 01:31:04 INFO - Successfully installed blobuploader requests docopt 01:31:04 INFO - Cleaning up... 01:31:04 INFO - Return code: 0 01:31:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:04 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 0x1a4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbb090>, '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 0x1dbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db5f90>, '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': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', '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 01:31:04 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 01:31:04 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 01:31:04 INFO - Using env: {'DISPLAY': ':0', 01:31:04 INFO - 'HOME': '/home/cltbld', 01:31:04 INFO - 'LANG': 'en_US.UTF-8', 01:31:04 INFO - 'LANGUAGE': 'en_US:en', 01:31:04 INFO - 'LOGNAME': 'cltbld', 01:31:04 INFO - 'MAIL': '/var/mail/cltbld', 01:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:04 INFO - 'MOZ_NO_REMOTE': '1', 01:31:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:04 INFO - 'NO_EM_RESTART': '1', 01:31:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:04 INFO - 'PWD': '/builds/slave/test', 01:31:04 INFO - 'SHELL': '/bin/bash', 01:31:04 INFO - 'SHLVL': '1', 01:31:04 INFO - 'TERM': 'linux', 01:31:04 INFO - 'TMOUT': '86400', 01:31:04 INFO - 'USER': 'cltbld', 01:31:04 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', 01:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:04 INFO - '_': '/tools/buildbot/bin/python'} 01:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:31:04 INFO - Running setup.py (path:/tmp/pip-y202C0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:31:04 INFO - Running setup.py (path:/tmp/pip-Fv8VdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:31:04 INFO - Running setup.py (path:/tmp/pip-ProunS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:31:04 INFO - Running setup.py (path:/tmp/pip-BaWkPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:31:04 INFO - Running setup.py (path:/tmp/pip-aKXSJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:31:05 INFO - Running setup.py (path:/tmp/pip-UsxBes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:31:05 INFO - Running setup.py (path:/tmp/pip-VTGtaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:31:05 INFO - Running setup.py (path:/tmp/pip-gkZcu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:31:05 INFO - Running setup.py (path:/tmp/pip-ecOfOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:31:05 INFO - Running setup.py (path:/tmp/pip-ylkcTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:31:05 INFO - Running setup.py (path:/tmp/pip-GTGm14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:31:05 INFO - Running setup.py (path:/tmp/pip-RZbYUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:31:05 INFO - Running setup.py (path:/tmp/pip-VDsWJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:31:05 INFO - Running setup.py (path:/tmp/pip-H6f2ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:31:05 INFO - Running setup.py (path:/tmp/pip-E1RZzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:31:05 INFO - Running setup.py (path:/tmp/pip-tPb06C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:31:05 INFO - Running setup.py (path:/tmp/pip-vcS1E3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:31:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:31:05 INFO - Running setup.py install for manifestparser 01:31:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:31:05 INFO - Running setup.py install for mozcrash 01:31:06 INFO - Running setup.py install for mozdebug 01:31:06 INFO - Running setup.py install for mozdevice 01:31:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:31:06 INFO - Running setup.py install for mozfile 01:31:06 INFO - Running setup.py install for mozhttpd 01:31:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:31:06 INFO - Running setup.py install for mozinfo 01:31:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:31:06 INFO - Running setup.py install for mozInstall 01:31:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:31:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:31:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:31:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:31:06 INFO - Running setup.py install for mozleak 01:31:06 INFO - Running setup.py install for mozlog 01:31:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:31:06 INFO - Running setup.py install for moznetwork 01:31:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:31:06 INFO - Running setup.py install for mozprocess 01:31:07 INFO - Running setup.py install for mozprofile 01:31:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Running setup.py install for mozrunner 01:31:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Running setup.py install for mozscreenshot 01:31:07 INFO - Running setup.py install for moztest 01:31:07 INFO - Running setup.py install for mozversion 01:31:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:31:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:31:07 INFO - Cleaning up... 01:31:07 INFO - Return code: 0 01:31:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:07 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 0x1a4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbb090>, '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 0x1dbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db5f90>, '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': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', '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 01:31:07 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 01:31:07 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 01:31:07 INFO - Using env: {'DISPLAY': ':0', 01:31:07 INFO - 'HOME': '/home/cltbld', 01:31:07 INFO - 'LANG': 'en_US.UTF-8', 01:31:07 INFO - 'LANGUAGE': 'en_US:en', 01:31:07 INFO - 'LOGNAME': 'cltbld', 01:31:07 INFO - 'MAIL': '/var/mail/cltbld', 01:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:07 INFO - 'MOZ_NO_REMOTE': '1', 01:31:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:07 INFO - 'NO_EM_RESTART': '1', 01:31:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:07 INFO - 'PWD': '/builds/slave/test', 01:31:07 INFO - 'SHELL': '/bin/bash', 01:31:07 INFO - 'SHLVL': '1', 01:31:07 INFO - 'TERM': 'linux', 01:31:07 INFO - 'TMOUT': '86400', 01:31:07 INFO - 'USER': 'cltbld', 01:31:07 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', 01:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:07 INFO - '_': '/tools/buildbot/bin/python'} 01:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:31:07 INFO - Running setup.py (path:/tmp/pip-Il5Fxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:31:07 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)) 01:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:31:07 INFO - Running setup.py (path:/tmp/pip-Epn7ym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:31:07 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)) 01:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:31:07 INFO - Running setup.py (path:/tmp/pip-jhJv6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:31:08 INFO - Running setup.py (path:/tmp/pip-EHHxyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:31:08 INFO - Running setup.py (path:/tmp/pip-wT9ibh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:31:08 INFO - Running setup.py (path:/tmp/pip-FjMFzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:31:08 INFO - Running setup.py (path:/tmp/pip-CUCCzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:31:08 INFO - Running setup.py (path:/tmp/pip-pMzKuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:31:08 INFO - Running setup.py (path:/tmp/pip-PIbqmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:31:08 INFO - Running setup.py (path:/tmp/pip-yRHZIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:31:08 INFO - Running setup.py (path:/tmp/pip-An5DRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:31:08 INFO - Running setup.py (path:/tmp/pip-goHMgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:31:08 INFO - Running setup.py (path:/tmp/pip-o8dZwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:31:08 INFO - Running setup.py (path:/tmp/pip-qvWWoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:31:08 INFO - Running setup.py (path:/tmp/pip-gR0WQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:31:08 INFO - Running setup.py (path:/tmp/pip-T7KiYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:31:08 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)) 01:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:31:08 INFO - Running setup.py (path:/tmp/pip-JxX_Zn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:31:09 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)) 01:31:09 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)) 01:31:09 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)) 01:31:09 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)) 01:31:09 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)) 01:31:09 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)) 01:31:09 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)) 01:31:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:31: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 01:31: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 01:31: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 01:31: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 01:31:10 INFO - Downloading blessings-1.6.tar.gz 01:31:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:31:10 INFO - Installing collected packages: blessings 01:31:10 INFO - Running setup.py install for blessings 01:31:10 INFO - Successfully installed blessings 01:31:10 INFO - Cleaning up... 01:31:10 INFO - Return code: 0 01:31:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:31:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:31:10 INFO - Reading from file tmpfile_stdout 01:31:10 INFO - Current package versions: 01:31:10 INFO - argparse == 1.2.1 01:31:10 INFO - blessings == 1.6 01:31:10 INFO - blobuploader == 1.2.4 01:31:10 INFO - docopt == 0.6.1 01:31:10 INFO - functools32 == 3.2.3-2 01:31:10 INFO - jsonschema == 2.5.1 01:31:10 INFO - manifestparser == 1.1 01:31:10 INFO - mozInstall == 1.13 01:31:10 INFO - mozcrash == 1.0 01:31:10 INFO - mozdebug == 0.1 01:31:10 INFO - mozdevice == 0.51 01:31:10 INFO - mozfile == 1.2 01:31:10 INFO - mozhttpd == 0.7 01:31:10 INFO - mozinfo == 0.10 01:31:10 INFO - mozleak == 0.1 01:31:10 INFO - mozlog == 3.5 01:31:10 INFO - moznetwork == 0.27 01:31:10 INFO - mozprocess == 0.25 01:31:10 INFO - mozprofile == 0.28 01:31:10 INFO - mozrunner == 6.13 01:31:10 INFO - mozscreenshot == 0.1 01:31:10 INFO - mozsystemmonitor == 0.3 01:31:10 INFO - moztest == 0.8 01:31:10 INFO - mozversion == 1.4 01:31:10 INFO - psutil == 3.1.1 01:31:10 INFO - requests == 1.2.3 01:31:10 INFO - wsgiref == 0.1.2 01:31:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:10 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 0x1a4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbb090>, '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 0x1dbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db5f90>, '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': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', '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 01:31:10 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 01:31:10 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 01:31:10 INFO - Using env: {'DISPLAY': ':0', 01:31:10 INFO - 'HOME': '/home/cltbld', 01:31:10 INFO - 'LANG': 'en_US.UTF-8', 01:31:10 INFO - 'LANGUAGE': 'en_US:en', 01:31:10 INFO - 'LOGNAME': 'cltbld', 01:31:10 INFO - 'MAIL': '/var/mail/cltbld', 01:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:10 INFO - 'MOZ_NO_REMOTE': '1', 01:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:10 INFO - 'NO_EM_RESTART': '1', 01:31:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:10 INFO - 'PWD': '/builds/slave/test', 01:31:10 INFO - 'SHELL': '/bin/bash', 01:31:10 INFO - 'SHLVL': '1', 01:31:10 INFO - 'TERM': 'linux', 01:31:10 INFO - 'TMOUT': '86400', 01:31:10 INFO - 'USER': 'cltbld', 01:31:10 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', 01:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:10 INFO - '_': '/tools/buildbot/bin/python'} 01:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:10 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)) 01:31:10 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)) 01:31:10 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)) 01:31:10 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)) 01:31:10 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)) 01:31:10 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)) 01:31:10 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)) 01:31:10 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)) 01:31:10 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)) 01:31:10 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 01:31: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 01:31: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 01:31: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 01:31: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 01:31:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 01:31:12 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)) 01:31:12 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)) 01:31:12 INFO - Installing collected packages: simplejson 01:31:12 INFO - Running setup.py install for simplejson 01:31:12 INFO - building 'simplejson._speedups' extension 01:31:12 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 01:31:12 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 01:31:13 INFO - Successfully installed simplejson 01:31:13 INFO - Cleaning up... 01:31:13 INFO - Return code: 0 01:31:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:13 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 0x1a4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbb090>, '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 0x1dbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db5f90>, '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': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', '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 01:31:13 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 01:31:13 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 01:31:13 INFO - Using env: {'DISPLAY': ':0', 01:31:13 INFO - 'HOME': '/home/cltbld', 01:31:13 INFO - 'LANG': 'en_US.UTF-8', 01:31:13 INFO - 'LANGUAGE': 'en_US:en', 01:31:13 INFO - 'LOGNAME': 'cltbld', 01:31:13 INFO - 'MAIL': '/var/mail/cltbld', 01:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:13 INFO - 'MOZ_NO_REMOTE': '1', 01:31:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:13 INFO - 'NO_EM_RESTART': '1', 01:31:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:13 INFO - 'PWD': '/builds/slave/test', 01:31:13 INFO - 'SHELL': '/bin/bash', 01:31:13 INFO - 'SHLVL': '1', 01:31:13 INFO - 'TERM': 'linux', 01:31:13 INFO - 'TMOUT': '86400', 01:31:13 INFO - 'USER': 'cltbld', 01:31:13 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', 01:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:13 INFO - '_': '/tools/buildbot/bin/python'} 01:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:31:13 INFO - Cleaning up... 01:31:13 INFO - Return code: 0 01:31:13 INFO - Running post-action listener: _resource_record_post_action 01:31:13 INFO - Running post-action listener: _start_resource_monitoring 01:31:13 INFO - Starting resource monitoring. 01:31:13 INFO - [mozharness: 2017-09-06 08:31:13.305104Z] Finished create-virtualenv step (success) 01:31:13 INFO - [mozharness: 2017-09-06 08:31:13.305572Z] Running install step. 01:31:13 INFO - Running pre-action listener: _resource_record_pre_action 01:31:13 INFO - Running main action method: install 01:31:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:31:13 INFO - Reading from file tmpfile_stdout 01:31:13 INFO - Detecting whether we're running mozinstall >=1.0... 01:31:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:31:13 INFO - Reading from file tmpfile_stdout 01:31:13 INFO - Output received: 01:31:13 INFO - Usage: mozinstall [options] installer 01:31:13 INFO - Options: 01:31:13 INFO - -h, --help show this help message and exit 01:31:13 INFO - -d DEST, --destination=DEST 01:31:13 INFO - Directory to install application into. [default: 01:31:13 INFO - "/builds/slave/test"] 01:31:13 INFO - --app=APP Application being installed. [default: firefox] 01:31:13 INFO - mkdir: /builds/slave/test/build/application 01:31:13 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'] 01:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 01:31:30 INFO - Reading from file tmpfile_stdout 01:31:30 INFO - Output received: 01:31:30 INFO - /builds/slave/test/build/application/firefox/firefox 01:31:30 INFO - Running post-action listener: _resource_record_post_action 01:31:30 INFO - [mozharness: 2017-09-06 08:31:30.180250Z] Finished install step (success) 01:31:30 INFO - [mozharness: 2017-09-06 08:31:30.180499Z] Running setup-mitmproxy step. 01:31:30 INFO - Running pre-action listener: _resource_record_pre_action 01:31:30 INFO - Running main action method: setup_mitmproxy 01:31:30 INFO - Skipping: mitmproxy is not required 01:31:30 INFO - Running post-action listener: _resource_record_post_action 01:31:30 INFO - [mozharness: 2017-09-06 08:31:30.181615Z] Finished setup-mitmproxy step (success) 01:31:30 INFO - [mozharness: 2017-09-06 08:31:30.181833Z] Running run-tests step. 01:31:30 INFO - Running pre-action listener: _resource_record_pre_action 01:31:30 INFO - Running pre-action listener: _set_gcov_prefix 01:31:30 INFO - Running main action method: run_tests 01:31:30 WARNING - Try message not found. 01:31:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:31:30 INFO - Python 2.7.3 01:31:30 INFO - Return code: 0 01:31:30 INFO - grabbing minidump binary from tooltool 01:31:30 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')]} 01:31:30 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 0x1dbb090>, '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 0x1dbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db5f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:31:30 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 01:31:30 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 01:31:30 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 01:31:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:31:30 INFO - Return code: 0 01:31:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:31:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:31:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:31:30 INFO - ENV: RUST_BACKTRACE is now full 01:31:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:31:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:31:30 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/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-085', '--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 01:31:30 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/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-085 --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 01:31:30 INFO - Using env: {'DISPLAY': ':0', 01:31:30 INFO - 'HOME': '/home/cltbld', 01:31:30 INFO - 'LANG': 'en_US.UTF-8', 01:31:30 INFO - 'LANGUAGE': 'en_US:en', 01:31:30 INFO - 'LOGNAME': 'cltbld', 01:31:30 INFO - 'MAIL': '/var/mail/cltbld', 01:31:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:31:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:30 INFO - 'MOZ_NO_REMOTE': '1', 01:31:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:31:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:30 INFO - 'NO_EM_RESTART': '1', 01:31:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:30 INFO - 'PWD': '/builds/slave/test', 01:31:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:31:30 INFO - 'RUST_BACKTRACE': 'full', 01:31:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 01:31:30 INFO - 'SHELL': '/bin/bash', 01:31:30 INFO - 'SHLVL': '1', 01:31:30 INFO - 'STYLO_FORCE_ENABLED': '1', 01:31:30 INFO - 'TERM': 'linux', 01:31:30 INFO - 'TMOUT': '86400', 01:31:30 INFO - 'USER': 'cltbld', 01:31:30 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', 01:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:30 INFO - '_': '/tools/buildbot/bin/python'} 01:31:30 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/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-085', '--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 01:31:30 INFO - mozversion application_buildid: 20170906073832 01:31:30 INFO - mozversion application_changeset: b235fb79d6e017b9f47309cb06eb701c06b7e8d2 01:31:30 INFO - mozversion application_display_name: Nightly 01:31:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:31:30 INFO - mozversion application_name: Firefox 01:31:30 INFO - mozversion application_remotingname: firefox 01:31:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 01:31:30 INFO - mozversion application_vendor: Mozilla 01:31:30 INFO - mozversion application_version: 57.0a1 01:31:30 INFO - mozversion platform_buildid: 20170906073832 01:31:30 INFO - mozversion platform_changeset: b235fb79d6e017b9f47309cb06eb701c06b7e8d2 01:31:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 01:31:30 INFO - mozversion platform_version: 57.0a1 01:31:30 INFO - using testdate: 1504686690 01:31:30 INFO - actual date: 1504686690 01:31:30 INFO - starting webserver on 'localhost:34700' 01:31:30 INFO - SUITE-START | Running 2 tests 01:31:30 INFO - TEST-START | dromaeo_css 01:31:30 INFO - Initialising browser for dromaeo_css test... 01:31:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34700/getInfo.html -profile /tmp/tmp1l5C56/profile 01:31:30 INFO - TEST-INFO | started process 14793 (/builds/slave/test/build/application/firefox/firefox http://localhost:34700/getInfo.html) 01:31:35 INFO - TEST-INFO | 14793: exit 0 01:31:35 INFO - Browser initialized. 01:31:35 INFO - Running cycle 1/1 for dromaeo_css test... 01:31:35 INFO - Using env: {'DISPLAY': ':0', 01:31:35 INFO - 'HOME': '/home/cltbld', 01:31:35 INFO - 'JSGC_DISABLE_POISONING': '1', 01:31:35 INFO - 'LANG': 'en_US.UTF-8', 01:31:35 INFO - 'LANGUAGE': 'en_US:en', 01:31:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 01:31:35 INFO - 'LOGNAME': 'cltbld', 01:31:35 INFO - 'MAIL': '/var/mail/cltbld', 01:31:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:31:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:31:35 INFO - 'MOZ_CRASHREPORTER': '1', 01:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 01:31:35 INFO - 'MOZ_NO_REMOTE': '1', 01:31:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:31:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:31:35 INFO - 'NO_EM_RESTART': '1', 01:31:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:35 INFO - 'PWD': '/builds/slave/test', 01:31:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:31:35 INFO - 'RUST_BACKTRACE': 'full', 01:31:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 01:31:35 INFO - 'SHELL': '/bin/bash', 01:31:35 INFO - 'SHLVL': '1', 01:31:35 INFO - 'STYLO_FORCE_ENABLED': '1', 01:31:35 INFO - 'TERM': 'linux', 01:31:35 INFO - 'TMOUT': '86400', 01:31:35 INFO - 'USER': 'cltbld', 01:31:35 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', 01:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:35 INFO - '_': '/tools/buildbot/bin/python'} 01:31:35 INFO - TEST-INFO | started process 14987 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1l5C56/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 01:31:36 INFO - PID 14987 | 01:31:36 INFO - PID 14987 | (/builds/slave/test/build/application/firefox/firefox:15040): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 01:31:36 INFO - PID 14987 | 01:31:47 INFO - PID 14987 | RSS: Main: 172347392 01:31:47 INFO - PID 14987 | 01:34:44 INFO - PID 14987 | Cycle 1(1): loaded http://localhost:34700/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34700/tests/dromaeo/cssquery-ext.html) 01:34:45 INFO - PID 14987 | RSS: Main: 175288320 01:34:45 INFO - PID 14987 | 01:37:47 INFO - PID 14987 | Cycle 1(1): loaded http://localhost:34700/tests/dromaeo/cssquery-ext.html (next: http://localhost:34700/tests/dromaeo/cssquery-jquery.html) 01:37:47 INFO - PID 14987 | RSS: Main: 171737088 01:37:47 INFO - PID 14987 | 01:40:49 INFO - PID 14987 | Cycle 1(1): loaded http://localhost:34700/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34700/tests/dromaeo/cssquery-mootools.html) 01:40:49 INFO - PID 14987 | RSS: Main: 172670976 01:40:49 INFO - PID 14987 | 01:43:51 INFO - PID 14987 | Cycle 1(1): loaded http://localhost:34700/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34700/tests/dromaeo/cssquery-prototype.html) 01:43:52 INFO - PID 14987 | RSS: Main: 173015040 01:43:52 INFO - PID 14987 | 01:46:55 INFO - PID 14987 | Cycle 1(1): loaded http://localhost:34700/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34700/tests/dromaeo/cssquery-yui.html) 01:46:56 INFO - PID 14987 | RSS: Main: 174157824 01:46:56 INFO - PID 14987 | 01:49:58 INFO - PID 14987 | Cycle 1(1): loaded http://localhost:34700/tests/dromaeo/cssquery-yui.html (next: http://localhost:34700/tests/dromaeo/cssquery-dojo.html) 01:49:58 INFO - PID 14987 | RSS: Main: 176640000 01:49:58 INFO - PID 14987 | 01:49:58 INFO - PID 14987 | __start_tp_report 01:49:58 INFO - PID 14987 | _x_x_mozilla_page_load 01:49:58 INFO - PID 14987 | _x_x_mozilla_page_load_details 01:49:58 INFO - PID 14987 | |i|pagename|runs| 01:49:58 INFO - PID 14987 | |0;dojo.html;3232.983835080824;3288.8684452621887;3327.1183136468826;3327.2347360107174;3342.4317865962785;6367.6497792621385;6432.903506447407;6435.259945106308;6437.35626437356;6464.547481676279;6240.656763877985;6298.275698294698;6327.905081423783;6340.080688300205;6392.3288054754275;5815.796447124351;5832.708364581765;5857.794977175799;5861.8827623447505;5873.324567674708;5893.027650437694;5899.823005309826;5992.850178745544;6002.909956350636;6030.788922387717;5878.853028674296;5926.792562260322;5948.1375200595885;5973.283206015284;5991.730372133265;5717.914231286534;5771.913421298684;5818.883622327529;5844.883102337972;5854.824355269348;5699.344575373802;5831.650100993954;5845.210896528956;5897.3218079920925;5956.434138756811;6358.618482891041;6417.973124300136;6447.709853056577;6468.8706225875685;6540.117084193621;6666.766663166766;6736.696848641774;6755.256921738604;6831.863362732717;6929.203141638725;6829.282925292822;6847.931520684829;6851.314868513158;6853.623050732208;6858.554193977376;6378.87242255157;6393.904091438632;6470.4823614110755;6495.3179916109075;6508.967455162741;7003;7092.397146242524;7154.391876690435;7181.748638797617;7204.56772593646;6610.768623098168;6739.427148692416;6801.863962720817;6888.035675005503;6890.379865812101;6727.091842601284;6787.592744435349;6872.965635171892;6886.208086070066;6895.448364130857;6780.864382712417;6893.276205998398;6914.688839002257;6919.75780847678;6942.097527321416;6855.383015528626;6877.415419689282;6892.414144797648;6963.303669632996;6987.371136597731;4911.926321105187;4959.578435832995;4983.9252411213865;5094.083065048253;5097;4462.9107417851465;4487.214737420938;4520.2315606347;4520.909581808345;4532.88667783308;5993.041113421918;5996.850078747979;6080.75676972925;6081.878362432726;6090.847728806815;6889.932060530626;6909.965450172817;6914.0666010089;6991.19601245853;7004.614746188907;5888.558358123158;5923.881522369528;5979.5814292999075;6007.789727359522;6046.969765151146;5944.464998150101;5945.554083443759;6010.879782404328;6019.729112189962;6027.397260273938;6699.832504187434;6711.798646040529;6812.080369150201;6819.454443644497;6826.078479405316;6659.101021362248;6822;6874.415674667709;6963.268856770169;6976.093107895941;6609.3060228675295;6681.766138185335;6718.93281067183;6725.39471447572;6725.9663701681175;6672.065910772592;6684.0642310075655;6777.390034896883;6777.762778302736;6778.966105169443;6750.932490675031;6832.385085342343;6834.384905358542;6910.7581234656545;6943.618101004493;6674.299198584079;6701.698423571049;6733.966330168318;6743.763968261087;6754.763583274562;6650.634215118215;6657.167854018247;6665.900011499928;6754.3921047106;6761.763338283136;6406.711697973509;6457.257415397102;6460.25707043696;6535.411812936859;6639.136912201383;6578.342165783383;6583.80248592543;6594.967025164843;6603.141591593062;6657.933420665731;6373.649449280334;6411.166548348685;6421.678916054272;6430.839229019125;6460.903086453613 01:49:58 INFO - PID 14987 | |1;ext.html;821.9013718353798;866.6230189867408;895.6372669069025;1156.50270383735;1175.8588969323682;30748.692513074864;41070.79464602676;41590.79204603973;41672.99999999996;41742.37386439206;41625.37561936573;41675.79162104193;41695.58304416955;41728.79135604318;41768.37347439791;7219.711211551532;7693.999999999986;7839.490433121858;7854.803629909269;7886.921130788704;7728.613569321511;7788.766337009899;7823.647935842901;7829.64766585505;7847.176046515119;7795.454318197729;7818.921810781905;7839.294463498284;7847.450678452511;7850.293473587376;7803.76588702337;7808.141104478503;7811.492253003565;7813.609319534001;7823.100343460518;7759.262870027338;7799.49303295284;7820.100688420791;7824.139344672139;7824.804379890491;38182.809085954395;38492.61507384946;38500.42249366262;38508;38519.229615407814;3482.355764183634;3522.154682876117;3528.1179705073732;3530.770499917497;3538.274653695986;31655.20861978446;31679.52480712768;31706.841465792524;31734.523982140287;31833.840830795696;5178.456262092502;5183.403908550527;5230.686158830483;5253.9474605253845;5271.446498117681;186891;189008;189068;189798;190696;47968.520314796406;48020.27969580459;48069.9999999993;48101.51898480971;48130.518694813305;51028.74485627622;51036.48963510391;51184.23223651648;51333.743331283105;51393;12559.686007849694;14219.85780142206;14467.421303147967;14494.710105797825;14516.637084072983;10051.396916185053;11363.829542556869;11648.359340236368;11651.825222621668;11657.067434605211;14066.71866562663;14149.646258843406;14210.07634503754;14272.785908211385;14305.57083287503;5573.804916827891;5716.142578613159;5785.88428231433;5890.263717035371;5961.731722072517;1004.8593196952432;1013.9797204055877;1026.3995562595965;1030.9587616495405;1042.5412818359898;23279.719615421316;23436.359454838;23514.7648523516;23564.64653030206;23702.05191792309;1123.8201887698092;1128.6162704680448;1138.322697994692;1140.9771804563861;1149.4597539156582;12459.252444853339;12494.250344979331;12499.750004999849;12565;12585.307808070462;229433;229520;230758;231644;232204;52929.47070529322;53296.20055699167;53304;53326.200106998425;53414.465855340946;1443.6463066548763;1461.7416092137673;1472.5876754508754;1476.0258229568433;1486.5020218226798;22562.887185563966;22619.773802261767;22780.20269290567;22781.886090569442;23072.307830765105;36342;36699.44950825793;36736;36737.26525469529;36778.26443471169;201372;201400;201627;201836;202259;29633.407331853672;30030.849845750632;32885.342293153524;33414.83292583522;33467.66532334743;43007.13985720331;43090.353644695984;43235.783821080695;43279;43801.78099109484;31926.361472770877;31979.520307195875;32057.198570035;32057.83971080223;32626.83686581552;31179.220519486287;32250.35499289954;33788;34118.48822267717;34186.14534636654;36821;37358.62641373552;37469.250614988094;37554.812225938695;37803.243935121696;32455;32607.510887337183;32764.344713106082;32878.67121328756;33188.50217246695;24021.159259425836;24198.274051778473;24247.15134970338;24392.89471191457;24817.62773558434 01:49:58 INFO - PID 14987 | |2;jquery.html;560.6467925207119;564.8813749112686;569.996805622105;572.3904042195064;587.4947545111204;21878;22464.438389040282;22532.324030279076;22581.209657661944;22613.095476180933;24507.264782056565;25164.49671006579;25202;25202.74797252027;25219.369515762115;6602.801915942518;6720.227173875018;6720.361565651279;6723.731050757964;6728.192616885956;6413.8396540086405;6687.699053542602;6693.431058360045;6704.061431399608;6704.698288577026;6654.567453115532;6663.966680166593;6675.699593518303;6678.699458524378;6679.532432729711;6671.03270025846;6684.83287917801;6691.531592788482;6691.866162676719;6695.163104611924;6184.907226391608;6196.349383314732;6198.907016394758;6211.19254496917;6211.720472578745;26460.73539264602;28265.010724624746;28927.56608650872;29276.56085158703;29354.412911741645;2011.6781314989532;2047.252752745252;2047.252752745252;2047.3550831488033;2048.416201382599;19737;19974.80025199744;20060.79939200604;20068.49828754278;20083.397498075083;2327.8254130940245;2335.9182428615086;2385.8449200801892;2404.326788499223;2426.963595546068;132098;138837.22325553533;138840;139136.30431847775;139165.3041734795;13919.721605567833;14411.711765764627;14472.493462728755;14747.483838065617;14749.926250368679;22259.777402226093;23134.53730925372;23171.189008384627;23188.76811231866;23569.41076473102;8976.192142707188;9001.594928228245;9271.90728092724;9305.209057230077;9381.155695987394;8152.2662960333855;8172.468789528662;8265.628046737911;8270.338372930151;8310.875336869953;8829.955850220837;9208.664743612138;9216.907830921606;9420.43477391345;9469.431834089977;2564.2948189248104;2578.174984005138;2627.8423294602385;2648.6291919130954;2739.6849662035547;962.3407965543626;965.1120968708805;970.0784254957678;982.7150126463246;1002.0480543483719;19436.416907492516;19744.703829442282;19957.802531848134;20071;20173;1036.5542816588788;1041.9270651054505;1052.1004541117388;1056.1709058389129;1059.7456610413599;10822.404767737693;11006.339619622848;11046.226764126595;11049.558017679203;11100.833487497694;441655;447592;451976;454032;456278;107728;107854;108413;110103;110637;1161.349644199251;1171.742216712322;1183.089021453476;1192.6481687901917;1195.4321697193764;17708.557286067437;17859.553511162325;17863.642727145645;17882.374116905845;18021.369252076114;52509;53233.46766532285;55177.72411137919;56368;57597.99999999833;70806.29193707996;71092.28907710857;71647;71824;72003.27996719966;14415.207163605683;14458.421663133357;15122.092674439129;15126.168060756763;15181.468648597245;21545.138194472045;21694.674579880997;21889.56220875542;22305;24236.87881560581;18777.90611046936;19033.809661903204;19044.90477547659;19178.616427671648;19185.136668850213;16517.33930642761;17179.31282748676;17208.741868871726;17494.212760426068;17587.912060439616;19428.90285548563;19720.30978915731;19979.800201998376;19987.400377988684;20216.494587635425;14803.111813291236;14876.702465950404;14896.925515372353;14909.254537272876;14920.328585213909;18664.626707465504;18718.71921921145;19064.142113605198;19083.713744293567;19368.322108726126 01:49:58 INFO - PID 14987 | |3;mootools.html;1318.466021261389;1369.8356994736002;1386.7503849307125;1391.3738817532108;1401.481451862811;14817;14859.479918202866;15457.227138643051;15472.149031803261;15480.458183963563;14836.035657682243;15043.323050462728;15048.84951150488;15051.924740376282;15061.698766024672;2732.330579008146;2786.8745906434156;2797.021042635072;2801;2803.649543807024;2671.1051797647715;2770.6952235254107;2842.0621195005597;2842.9147125586264;2844.4737723521216;2777.722227777216;2820.605115283862;2821.4357128574266;2821.8024738268327;2821.985890070557;2661.5076210901047;2721.27886110179;2725.9591106133425;2733.2346942856034;2736.3022429280604;2629.2769488390654;2676.050002249193;2678.3839716865036;2680.276325392133;2681.034827462112;14637.41450341985;14978.026428282235;15080.321385537678;15088.471903483434;15107.244637768177;8088.757337279891;8338.874916876252;8356.2897153742;8357.080721120672;8358.916410835935;5316.122839731478;5572.442755724435;5703.657780533182;5790.826275211708;5795.043817770063;1856.0997916010724;1878.3519685708409;1912.4453908366556;1954.452753229098;1959.5101224693826;26475.470490590273;26806.731932680814;27003.72996270032;27007.594886076724;27008;6809.421199198025;6817.863642727118;6867.862642747217;7020;7027.648617569101;17110.315587376615;17123;17124.74312885308;17151.65696686059;17153.313867445413;7813.960930195312;8048.436609437284;8066.23370779775;8068.596570171468;8080.555569443737;7392.704291828267;7421.851562968711;7448.180700122982;7450.5529668219;7472.476926615193;4955.306257124005;5090.694558326512;5460.481254280837;5504.587155963319;5551.639143455744;1524.7636616324487;1574.7874037005088;1575.558843523815;1586.7143914095343;1589.9205039748197;845.5222799118478;849.7153453592992;870.6647940542937;881.4667126388571;888.2228050455851;8388.580570971428;8763.298936085097;8773.166549177819;8853.778655533535;9161.129692679186;976.1312431935582;990.5146478225618;1006.2000709436016;1008.6469735592484;1041.6666666666595;5380.3274590676165;5494.725263736797;5852.122181672698;5941.638753539973;6101;21654.566908661738;21728.02223899897;22251.109955601918;22323.776762232494;22492.550148997256;21324.466888327606;21716.45708857291;21973.450663733536;22009.779902201095;22022.33932982013;11598.94200528992;11666.94166529179;11793.88206117945;11874.346910919983;11945;8587.613557389808;8694.826103477768;8705.782355441164;8855.512946787989;9113.954430227806;18159.636807264043;18721.15754791065;19408.767991680572;19507.21971121196;19761.802381975995;18814.435566933014;19158.71261931044;19321.613567728848;19366.515837104183;19370.2251909922;12161.087918405978;12341.753164936472;12353.938230308791;12433.813492797795;12486.562970295996;13796.103253288504;13955.651108721957;14016;14092.36584353686;14103.224322662352;10963.56145754161;10997.725056873642;11109.833352499558;11188.384638844616;11361.204715669825;10684.923316093144;10884.238103333008;11102.389368584805;11243.775124497628;11280.210385272952;10015.14871235953;10214.438205898747;10289.485525723834;10305.74235644115;10316.226283028953;7269.273072692688;7280.563166210044;7289.307515786204;7317.414606831547;7335.303146201102;7393.445491588045;7397.186309506058;7459.664315105942;7470.252974702486;7595.696172153052 01:49:58 INFO - PID 14987 | |4;prototype.html;490.17658288259634;501.46844344994287;527.8865044015536;531.7128750474744;534.1533669134421;6170.969145154279;6217.626942383455;6233.472799164206;6285.182926219585;6371.585846919947;6043.516518678506;6139.140520327147;6179.938200617992;6183.381661833814;6222.813315600528;4999.975005123959;5009.674371165881;5019.498050194988;5058.898822023557;5068.670536415121;4845.5396737310075;4961.553460188582;4999.675021123615;5018.924716129261;5042.82350117746;4959.851204463854;4971.552560269575;4993.201087825949;5006.148954677696;5018.963527066127;4916.901661966776;4967.652264341497;4985.127602669555;5013.022460620162;5025.497450254982;4419.712718673308;4442.822287108512;4464.441944756905;4471.642268618502;4478.708883922534;6092.695365231765;6136.662483563402;6151.569390142692;6170.475509581691;6178.876422471571;5678.627456858196;5714.171445140431;5720.141978703228;5729.828105156852;5741.483266506035;6469.417752402259;6477.157969463987;6506.251781045193;6507.577007494498;6522.934770652328;4805.783739731721;4837.443693975203;4876.878317986848;4891.584215341701;4914.238293064581;6772.2211945625895;6822.761203357859;6914.446844252448;6924.826879328007;7006.124234470692;4945.604351651859;4953.975230123826;4968.950310496921;5003.924941125886;5011.649184557046;5039;5059.139946209153;5074.771635276347;5085.491450854959;5100.209467532539;5564.387917329091;5584.190292407578;5627.521660658808;5705.087186050212;5717.799877004285;6452.967735161388;6476.514261430411;6503.089567460559;6596.80209593713;6636.5354425190735;1620.8379162083697;1683.9242234099495;1697.940572079996;1715.6397156597004;1733.8556552675425;1193.6240084373394;1196.8743281955444;1197.3953153657346;1217.6955761059735;1219.591436868659;5667.518260947783;5766.740496677659;5786.450287222707;5841.211436456024;5845.707714614252;6380.170577824946;6599.604023758494;6664.633445160466;6718.596884186964;6808.489363297773;5832.154337621021;5849.415058494116;5865.853353666193;5878.794242201502;5923.763049477972;3532.275883443888;3539.0975301298004;3551.1477245460906;3567.928641427157;3571.4821350904494;6513.674316284167;6542.3130571290285;6554.2134943806095;6569.408753212138;6644;6927.134108236471;6937.479689023344;6973.581585104808;6981.441484681213;7050.647467626701;6504.707288172043;6522.315156908551;6549.803505894831;6589.9900530337745;6627.966860165669;6679.532432729662;6692.364225398579;6712.328767123248;6771.559848609824;6784.932150678627;6697.3637504437;6697.899531506934;6717.816709569312;6719.731210751515;6794.524383293122;6500.349965003462;6581.177352830896;6585.703643335965;6588.769393071411;6598.802035938929;4960.23116416956;4965.82619608312;5000;5011.699298042129;5018.44797072329;6608.735650574115;6659.234188068434;6742.359475849787;6744.617353442434;6890.517663763489;6412.775552855627;6422.903656445251;6476.643784591893;6518.837029074311;6546.607203567801;6197.938020619736;6455.386738259858;6460.709268082854;6468.676566171578;6609.239937407259;6099.695015249131;6274.247090349188;6288.654124023223;6304.275008374096;6322.36776322364;6487.935120648922;6516.087747715229;6521.413072823469;6556;6651.235107962646;6246.562740608296;6270.435660790551;6333.36666333363;6403.199600049994;6464.870702586016 01:49:58 INFO - PID 14987 | |5;yui.html;916.7845556294274;921.4149015375243;922.4741897118641;934.4066517761224;943.4716558727112;17494.125293735327;18536.81463185368;19398.515037124078;19409.708854367178;19435.22259109634;16527.421540246094;18380.81619183808;20344.59310813783;20481.59036819263;20518.179272829108;958.5399008475936;965.657191696948;993.3692105512979;999.0608827701973;999.1058003087232;993.5479356892632;999.5052449037737;1004.036125319694;1004.5780772075746;1004.8492726091072;926.9768255793625;1016.903394177552;1030.7526193713493;1051.6529545250087;1059.311447559089;1008.7074748322977;1024.359775140537;1029.5624359647156;1029.6344797596896;1030.1141018723893;746.5707218349417;751;755.5844285642875;755.7241606813528;756.735142700056;19937;20197.899010504854;20603;20618.278360257318;20783.376498705064;20987;21022.2642207522;21412.46468838276;21459.356219313446;21560.245391411227;16412.17939103052;16720.498385048468;16783.748243776354;16886;16896.577585560335;3766.8493260269556;3803.4865293185344;3805.6194380562;3805.676517496016;3806.1816709407613;126367;127031;130108;131495;133085;16544.6691066178;16610.169491525376;16649.83350166507;16658.250378732988;16788.748168777478;29533;29884;30114.54828177579;30115.247118821768;30134.84932575367;8729.738107856774;8847;8852.468851868909;8852.513111778786;8866.73399798007;3789.6020917803785;3954.6440820326306;4268.189044081677;4442.311441726538;4493.0115374617535;5049.091163590516;5147.253648221062;5171.974140129275;5184.5593124584075;5184.714840683799;942.1002942190192;956.086936975187;1064.2390690656218;1089.8147314956477;1102.7794441111648;695.391532409142;709.3580309819622;709.5352544083634;720.902678138455;734.0384096833166;12647.367631618567;13031.804522932163;13043.804342934864;13059.34703264852;13275.66810829737;706.445440329339;712.0636363182399;713.1192976673804;714.5926821711679;717.121526130486;5455.481729235717;5561.527270181999;6279.811605651837;6383.467967687721;6488.7728929488185;108800;109640;112363;112576.43711781388;115122;96340.51829740807;96628.51685741526;98469.50765246271;100162;100547;88635.55682221676;88667;90981.54509227411;92851.5357423222;93088.53455732814;11984;12690.619281421572;12759.80860287115;13091.541796037092;13213.207207567577;41861.58138418577;42946.92632684083;43046.78476607597;43239.135217296105;43404.78297608618;55241.44758552363;56763.71618141883;56849.715751420976;57000.42999569951;58011.709941451714;18267.177976990803;18785;18806.435806925812;18831.435056948314;18977.90511047436;17479.38822141219;19303;19452.416427507196;19538.90230548838;19617.70573441371;19123.713144303125;19236.23055077781;19387.12757925925;19459.610807783483;19582.412527624194;16039.518814435585;16068.758968615246;16177.27202275924;16194.352225910832;16208.270627821541;18124.365647202285;18272.817271827113;18302.993335366682;18353.816461835744;18409.631807364047;13451.125676830974;13773.517926872511;13853.16880987144;13874.514391996232;14024.158550487004;14271.928640356731;14703.779443308145;14868.405263789327;15083.39666413375;15466 01:49:58 INFO - PID 14987 | __end_tp_report 01:49:58 INFO - PID 14987 | __start_cc_report 01:49:58 INFO - PID 14987 | _x_x_mozilla_cycle_collect,378 01:49:58 INFO - PID 14987 | __end_cc_report 01:49:58 INFO - PID 14987 | __startTimestamp1504687798299__endTimestamp 01:49:58 INFO - PID 14987 | 01:49:58 INFO - PID 14987 | ------- Summary: start ------- 01:49:58 INFO - PID 14987 | Number of tests: 6 01:49:58 INFO - PID 14987 | 01:49:58 INFO - PID 14987 | [#0] dojo.html Cycles:165 Average:6298.77 Median:6535.41 stddev:776.14 (11.9%) stddev-sans-first:740.32 01:49:58 INFO - PID 14987 | Values: 3233.0 3288.9 3327.1 3327.2 3342.4 6367.6 6432.9 6435.3 6437.4 6464.5 6240.7 6298.3 6327.9 6340.1 6392.3 5815.8 5832.7 5857.8 5861.9 5873.3 5893.0 5899.8 5992.9 6002.9 6030.8 5878.9 5926.8 5948.1 5973.3 5991.7 5717.9 5771.9 5818.9 5844.9 5854.8 5699.3 5831.7 5845.2 5897.3 5956.4 6358.6 6418.0 6447.7 6468.9 6540.1 6666.8 6736.7 6755.3 6831.9 6929.2 6829.3 6847.9 6851.3 6853.6 6858.6 6378.9 6393.9 6470.5 6495.3 6509.0 7003.0 7092.4 7154.4 7181.7 7204.6 6610.8 6739.4 6801.9 6888.0 6890.4 6727.1 6787.6 6873.0 6886.2 6895.4 6780.9 6893.3 6914.7 6919.8 6942.1 6855.4 6877.4 6892.4 6963.3 6987.4 4911.9 4959.6 4983.9 5094.1 5097.0 4462.9 4487.2 4520.2 4520.9 4532.9 5993.0 5996.9 6080.8 6081.9 6090.8 6889.9 6910.0 6914.1 6991.2 7004.6 5888.6 5923.9 5979.6 6007.8 6047.0 5944.5 5945.6 6010.9 6019.7 6027.4 6699.8 6711.8 6812.1 6819.5 6826.1 6659.1 6822.0 6874.4 6963.3 6976.1 6609.3 6681.8 6718.9 6725.4 6726.0 6672.1 6684.1 6777.4 6777.8 6779.0 6750.9 6832.4 6834.4 6910.8 6943.6 6674.3 6701.7 6734.0 6743.8 6754.8 6650.6 6657.2 6665.9 6754.4 6761.8 6406.7 6457.3 6460.3 6535.4 6639.1 6578.3 6583.8 6595.0 6603.1 6657.9 6373.6 6411.2 6421.7 6430.8 6460.9 01:49:58 INFO - PID 14987 | 01:49:58 INFO - PID 14987 | [#1] ext.html Cycles:180 Average:37589.34 Median:33953.24 stddev:53879.97 (158.7%) stddev-sans-first:53960.38 01:49:58 INFO - PID 14987 | Values: 821.9 866.6 895.6 1156.5 1175.9 30748.7 41070.8 41590.8 41673.0 41742.4 41625.4 41675.8 41695.6 41728.8 41768.4 7219.7 7694.0 7839.5 7854.8 7886.9 7728.6 7788.8 7823.6 7829.6 7847.2 7795.5 7818.9 7839.3 7847.5 7850.3 7803.8 7808.1 7811.5 7813.6 7823.1 7759.3 7799.5 7820.1 7824.1 7824.8 38182.8 38492.6 38500.4 38508.0 38519.2 3482.4 3522.2 3528.1 3530.8 3538.3 31655.2 31679.5 31706.8 31734.5 31833.8 5178.5 5183.4 5230.7 5253.9 5271.4 186891.0 189008.0 189068.0 189798.0 190696.0 47968.5 48020.3 48070.0 48101.5 48130.5 51028.7 51036.5 51184.2 51333.7 51393.0 12559.7 14219.9 14467.4 14494.7 14516.6 10051.4 11363.8 11648.4 11651.8 11657.1 14066.7 14149.6 14210.1 14272.8 14305.6 5573.8 5716.1 5785.9 5890.3 5961.7 1004.9 1014.0 1026.4 1031.0 1042.5 23279.7 23436.4 23514.8 23564.6 23702.1 1123.8 1128.6 1138.3 1141.0 1149.5 12459.3 12494.3 12499.8 12565.0 12585.3 229433.0 229520.0 230758.0 231644.0 232204.0 52929.5 53296.2 53304.0 53326.2 53414.5 1443.6 1461.7 1472.6 1476.0 1486.5 22562.9 22619.8 22780.2 22781.9 23072.3 36342.0 36699.4 36736.0 36737.3 36778.3 201372.0 201400.0 201627.0 201836.0 202259.0 29633.4 30030.8 32885.3 33414.8 33467.7 43007.1 43090.4 43235.8 43279.0 43801.8 31926.4 31979.5 32057.2 32057.8 32626.8 31179.2 32250.4 33788.0 34118.5 34186.1 36821.0 37358.6 37469.3 37554.8 37803.2 32455.0 32607.5 32764.3 32878.7 33188.5 24021.2 24198.3 24247.2 24392.9 24817.6 01:49:58 INFO - PID 14987 | 01:49:58 INFO - PID 14987 | [#2] jquery.html Cycles:180 Average:33418.00 Median:22873.82 stddev:76278.51 (333.5%) stddev-sans-first:76452.59 01:49:58 INFO - PID 14987 | Values: 560.6 564.9 570.0 572.4 587.5 21878.0 22464.4 22532.3 22581.2 22613.1 24507.3 25164.5 25202.0 25202.7 25219.4 6602.8 6720.2 6720.4 6723.7 6728.2 6413.8 6687.7 6693.4 6704.1 6704.7 6654.6 6664.0 6675.7 6678.7 6679.5 6671.0 6684.8 6691.5 6691.9 6695.2 6184.9 6196.3 6198.9 6211.2 6211.7 26460.7 28265.0 28927.6 29276.6 29354.4 2011.7 2047.3 2047.3 2047.4 2048.4 19737.0 19974.8 20060.8 20068.5 20083.4 2327.8 2335.9 2385.8 2404.3 2427.0 132098.0 138837.2 138840.0 139136.3 139165.3 13919.7 14411.7 14472.5 14747.5 14749.9 22259.8 23134.5 23171.2 23188.8 23569.4 8976.2 9001.6 9271.9 9305.2 9381.2 8152.3 8172.5 8265.6 8270.3 8310.9 8830.0 9208.7 9216.9 9420.4 9469.4 2564.3 2578.2 2627.8 2648.6 2739.7 962.3 965.1 970.1 982.7 1002.0 19436.4 19744.7 19957.8 20071.0 20173.0 1036.6 1041.9 1052.1 1056.2 1059.7 10822.4 11006.3 11046.2 11049.6 11100.8 441655.0 447592.0 451976.0 454032.0 456278.0 107728.0 107854.0 108413.0 110103.0 110637.0 1161.3 1171.7 1183.1 1192.6 1195.4 17708.6 17859.6 17863.6 17882.4 18021.4 52509.0 53233.5 55177.7 56368.0 57598.0 70806.3 71092.3 71647.0 71824.0 72003.3 14415.2 14458.4 15122.1 15126.2 15181.5 21545.1 21694.7 21889.6 22305.0 24236.9 18777.9 19033.8 19044.9 19178.6 19185.1 16517.3 17179.3 17208.7 17494.2 17587.9 19428.9 19720.3 19979.8 19987.4 20216.5 14803.1 14876.7 14896.9 14909.3 14920.3 18664.6 18718.7 19064.1 19083.7 19368.3 01:49:58 INFO - PID 14987 | 01:49:58 INFO - PID 14987 | [#3] mootools.html Cycles:180 Average:9503.83 Median:2666.31 stddev:6729.67 (252.4%) stddev-sans-first:6720.45 01:49:58 INFO - PID 14987 | Values: 1318.5 1369.8 1386.8 1391.4 1401.5 14817.0 14859.5 15457.2 15472.1 15480.5 14836.0 15043.3 15048.8 15051.9 15061.7 2732.3 2786.9 2797.0 2801.0 2803.6 2671.1 2770.7 2842.1 2842.9 2844.5 2777.7 2820.6 2821.4 2821.8 2822.0 2661.5 2721.3 2726.0 2733.2 2736.3 2629.3 2676.1 2678.4 2680.3 2681.0 14637.4 14978.0 15080.3 15088.5 15107.2 8088.8 8338.9 8356.3 8357.1 8358.9 5316.1 5572.4 5703.7 5790.8 5795.0 1856.1 1878.4 1912.4 1954.5 1959.5 26475.5 26806.7 27003.7 27007.6 27008.0 6809.4 6817.9 6867.9 7020.0 7027.6 17110.3 17123.0 17124.7 17151.7 17153.3 7814.0 8048.4 8066.2 8068.6 8080.6 7392.7 7421.9 7448.2 7450.6 7472.5 4955.3 5090.7 5460.5 5504.6 5551.6 1524.8 1574.8 1575.6 1586.7 1589.9 845.5 849.7 870.7 881.5 888.2 8388.6 8763.3 8773.2 8853.8 9161.1 976.1 990.5 1006.2 1008.6 1041.7 5380.3 5494.7 5852.1 5941.6 6101.0 21654.6 21728.0 22251.1 22323.8 22492.6 21324.5 21716.5 21973.5 22009.8 22022.3 11598.9 11666.9 11793.9 11874.3 11945.0 8587.6 8694.8 8705.8 8855.5 9114.0 18159.6 18721.2 19408.8 19507.2 19761.8 18814.4 19158.7 19321.6 19366.5 19370.2 12161.1 12341.8 12353.9 12433.8 12486.6 13796.1 13955.7 14016.0 14092.4 14103.2 10963.6 10997.7 11109.8 11188.4 11361.2 10684.9 10884.2 11102.4 11243.8 11280.2 10015.1 10214.4 10289.5 10305.7 10316.2 7269.3 7280.6 7289.3 7317.4 7335.3 7393.4 7397.2 7459.7 7470.3 7595.7 01:49:58 INFO - PID 14987 | 01:49:58 INFO - PID 14987 | [#4] prototype.html Cycles:180 Average:5509.21 Median:6161.02 stddev:1551.88 (25.2%) stddev-sans-first:1509.82 01:49:58 INFO - PID 14987 | Values: 490.2 501.5 527.9 531.7 534.2 6171.0 6217.6 6233.5 6285.2 6371.6 6043.5 6139.1 6179.9 6183.4 6222.8 5000.0 5009.7 5019.5 5058.9 5068.7 4845.5 4961.6 4999.7 5018.9 5042.8 4959.9 4971.6 4993.2 5006.1 5019.0 4916.9 4967.7 4985.1 5013.0 5025.5 4419.7 4442.8 4464.4 4471.6 4478.7 6092.7 6136.7 6151.6 6170.5 6178.9 5678.6 5714.2 5720.1 5729.8 5741.5 6469.4 6477.2 6506.3 6507.6 6522.9 4805.8 4837.4 4876.9 4891.6 4914.2 6772.2 6822.8 6914.4 6924.8 7006.1 4945.6 4954.0 4969.0 5003.9 5011.6 5039.0 5059.1 5074.8 5085.5 5100.2 5564.4 5584.2 5627.5 5705.1 5717.8 6453.0 6476.5 6503.1 6596.8 6636.5 1620.8 1683.9 1697.9 1715.6 1733.9 1193.6 1196.9 1197.4 1217.7 1219.6 5667.5 5766.7 5786.5 5841.2 5845.7 6380.2 6599.6 6664.6 6718.6 6808.5 5832.2 5849.4 5865.9 5878.8 5923.8 3532.3 3539.1 3551.1 3567.9 3571.5 6513.7 6542.3 6554.2 6569.4 6644.0 6927.1 6937.5 6973.6 6981.4 7050.6 6504.7 6522.3 6549.8 6590.0 6628.0 6679.5 6692.4 6712.3 6771.6 6784.9 6697.4 6697.9 6717.8 6719.7 6794.5 6500.3 6581.2 6585.7 6588.8 6598.8 4960.2 4965.8 5000.0 5011.7 5018.4 6608.7 6659.2 6742.4 6744.6 6890.5 6412.8 6422.9 6476.6 6518.8 6546.6 6197.9 6455.4 6460.7 6468.7 6609.2 6099.7 6274.2 6288.7 6304.3 6322.4 6487.9 6516.1 6521.4 6556.0 6651.2 6246.6 6270.4 6333.4 6403.2 6464.9 01:49:58 INFO - PID 14987 | 01:49:58 INFO - PID 14987 | [#5] yui.html Cycles:180 Average:23767.48 Median:20271.25 stddev:32421.17 (159.9%) stddev-sans-first:32466.72 01:49:58 INFO - PID 14987 | Values: 916.8 921.4 922.5 934.4 943.5 17494.1 18536.8 19398.5 19409.7 19435.2 16527.4 18380.8 20344.6 20481.6 20518.2 958.5 965.7 993.4 999.1 999.1 993.5 999.5 1004.0 1004.6 1004.8 927.0 1016.9 1030.8 1051.7 1059.3 1008.7 1024.4 1029.6 1029.6 1030.1 746.6 751.0 755.6 755.7 756.7 19937.0 20197.9 20603.0 20618.3 20783.4 20987.0 21022.3 21412.5 21459.4 21560.2 16412.2 16720.5 16783.7 16886.0 16896.6 3766.8 3803.5 3805.6 3805.7 3806.2 126367.0 127031.0 130108.0 131495.0 133085.0 16544.7 16610.2 16649.8 16658.3 16788.7 29533.0 29884.0 30114.5 30115.2 30134.8 8729.7 8847.0 8852.5 8852.5 8866.7 3789.6 3954.6 4268.2 4442.3 4493.0 5049.1 5147.3 5172.0 5184.6 5184.7 942.1 956.1 1064.2 1089.8 1102.8 695.4 709.4 709.5 720.9 734.0 12647.4 13031.8 13043.8 13059.3 13275.7 706.4 712.1 713.1 714.6 717.1 5455.5 5561.5 6279.8 6383.5 6488.8 108800.0 109640.0 112363.0 112576.4 115122.0 96340.5 96628.5 98469.5 100162.0 100547.0 88635.6 88667.0 90981.5 92851.5 93088.5 11984.0 12690.6 12759.8 13091.5 13213.2 41861.6 42946.9 43046.8 43239.1 43404.8 55241.4 56763.7 56849.7 57000.4 58011.7 18267.2 18785.0 18806.4 18831.4 18977.9 17479.4 19303.0 19452.4 19538.9 19617.7 19123.7 19236.2 19387.1 19459.6 19582.4 16039.5 16068.8 16177.3 16194.4 16208.3 18124.4 18272.8 18303.0 18353.8 18409.6 13451.1 13773.5 13853.2 13874.5 14024.2 14271.9 14703.8 14868.4 15083.4 15466.0 01:49:58 INFO - PID 14987 | -------- Summary: end -------- 01:49:58 INFO - PID 14987 | 01:49:59 INFO - TEST-INFO | 14987: exit 0 01:49:59 INFO - TEST-OK | dromaeo_css | took 1108514ms 01:49:59 INFO - TEST-START | kraken 01:49:59 INFO - Initialising browser for kraken test... 01:49:59 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34700/getInfo.html -profile /tmp/tmpAdrW2V/profile 01:49:59 INFO - TEST-INFO | started process 15371 (/builds/slave/test/build/application/firefox/firefox http://localhost:34700/getInfo.html) 01:50:04 INFO - TEST-INFO | 15371: exit 0 01:50:04 INFO - Browser initialized. 01:50:04 INFO - Running cycle 1/1 for kraken test... 01:50:04 INFO - Using env: {'DISPLAY': ':0', 01:50:04 INFO - 'HOME': '/home/cltbld', 01:50:04 INFO - 'JSGC_DISABLE_POISONING': '1', 01:50:04 INFO - 'LANG': 'en_US.UTF-8', 01:50:04 INFO - 'LANGUAGE': 'en_US:en', 01:50:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 01:50:04 INFO - 'LOGNAME': 'cltbld', 01:50:04 INFO - 'MAIL': '/var/mail/cltbld', 01:50:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:50:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:50:04 INFO - 'MOZ_CRASHREPORTER': '1', 01:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 01:50:04 INFO - 'MOZ_NO_REMOTE': '1', 01:50:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:50:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:04 INFO - 'NO_EM_RESTART': '1', 01:50:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:50:04 INFO - 'PWD': '/builds/slave/test', 01:50:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:50:04 INFO - 'RUST_BACKTRACE': 'full', 01:50:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 01:50:04 INFO - 'SHELL': '/bin/bash', 01:50:04 INFO - 'SHLVL': '1', 01:50:04 INFO - 'STYLO_FORCE_ENABLED': '1', 01:50:04 INFO - 'TERM': 'linux', 01:50:04 INFO - 'TMOUT': '86400', 01:50:04 INFO - 'USER': 'cltbld', 01:50:04 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818', 01:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:04 INFO - '_': '/tools/buildbot/bin/python'} 01:50:04 INFO - TEST-INFO | started process 15566 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAdrW2V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 01:50:05 INFO - PID 15566 | 01:50:05 INFO - PID 15566 | (/builds/slave/test/build/application/firefox/firefox:15618): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 01:50:05 INFO - PID 15566 | 01:50:15 INFO - PID 15566 | RSS: Main: 172666880 01:50:15 INFO - PID 15566 | 01:50:18 INFO - PID 15566 | Cycle 1(1): loaded http://localhost:34700/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34700/tests/kraken/driver.html?testName=audio-beat-detection) 01:50:19 INFO - PID 15566 | RSS: Main: 178114560 01:50:19 INFO - PID 15566 | 01:50:21 INFO - PID 15566 | Cycle 1(1): loaded http://localhost:34700/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34700/tests/kraken/driver.html?testName=audio-dft) 01:50:22 INFO - PID 15566 | RSS: Main: 178954240 01:50:22 INFO - PID 15566 | 01:50:25 INFO - PID 15566 | Cycle 1(1): loaded http://localhost:34700/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34700/tests/kraken/driver.html?testName=audio-fft) 01:50:26 INFO - PID 15566 | RSS: Main: 172982272 01:50:26 INFO - PID 15566 | 01:50:28 INFO - PID 15566 | Cycle 1(1): loaded http://localhost:34700/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34700/tests/kraken/driver.html?testName=audio-oscillator) 01:50:28 INFO - PID 15566 | RSS: Main: 174022656 01:50:28 INFO - PID 15566 | 01:50:30 INFO - PID 15566 | Cycle 1(1): loaded http://localhost:34700/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34700/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:50:31 INFO - PID 15566 | RSS: Main: 174227456 01:50:31 INFO - PID 15566 | 01:50:34 INFO - PID 15566 | Cycle 1(1): loaded http://localhost:34700/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34700/tests/kraken/driver.html?testName=imaging-darkroom) 01:50:35 INFO - PID 15566 | RSS: Main: 176418816 01:50:35 INFO - PID 15566 | 01:50:38 INFO - PID 15566 | Cycle 1(1): loaded http://localhost:34700/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34700/tests/kraken/driver.html?testName=imaging-desaturate) 01:50:39 INFO - PID 15566 | RSS: Main: 176504832 01:50:39 INFO - PID 15566 | 01:50:42 INFO - PID 15566 | Cycle 1(1): loaded http://localhost:34700/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34700/tests/kraken/driver.html?testName=json-parse-financial) 01:50:42 INFO - PID 15566 | RSS: Main: 176680960 01:50:42 INFO - PID 15566 | 01:50:44 INFO - PID 15566 | Cycle 1(1): loaded http://localhost:34700/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34700/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:50:45 INFO - PID 15566 | RSS: Main: 177156096 01:50:45 INFO - PID 15566 | 01:50:46 INFO - PID 15566 | Cycle 1(1): loaded http://localhost:34700/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34700/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:50:47 INFO - PID 15566 | RSS: Main: 177303552 01:50:47 INFO - PID 15566 | 01:50:49 INFO - PID 15566 | Cycle 1(1): loaded http://localhost:34700/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34700/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:50:50 INFO - PID 15566 | RSS: Main: 174448640 01:50:50 INFO - PID 15566 | 01:50:52 INFO - PID 15566 | Cycle 1(1): loaded http://localhost:34700/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34700/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:50:53 INFO - PID 15566 | RSS: Main: 174583808 01:50:53 INFO - PID 15566 | 01:50:56 INFO - PID 15566 | Cycle 1(1): loaded http://localhost:34700/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34700/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:50:57 INFO - PID 15566 | RSS: Main: 174551040 01:50:57 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | Cycle 1(1): loaded http://localhost:34700/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34700/tests/kraken/driver.html?testName=ai-astar) 01:50:59 INFO - PID 15566 | RSS: Main: 176406528 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | __start_tp_report 01:50:59 INFO - PID 15566 | _x_x_mozilla_page_load 01:50:59 INFO - PID 15566 | _x_x_mozilla_page_load_details 01:50:59 INFO - PID 15566 | |i|pagename|runs| 01:50:59 INFO - PID 15566 | |0;ai-astar;98;108;100;107;107;113;108;97;107;111 01:50:59 INFO - PID 15566 | |1;audio-beat-detection;140;117;152;173;115;150;147;116;168;117 01:50:59 INFO - PID 15566 | |2;audio-dft;163;162;163;161;161;162;160;165;164;165 01:50:59 INFO - PID 15566 | |3;audio-fft;76;73;73;173;78;75;73;74;157;74 01:50:59 INFO - PID 15566 | |4;audio-oscillator;79;79;127;80;79;79;127;80;80;126 01:50:59 INFO - PID 15566 | |5;imaging-gaussian-blur;114;117;114;115;114;114;115;114;114;113 01:50:59 INFO - PID 15566 | |6;imaging-darkroom;149;147;149;148;148;147;148;148;151;148 01:50:59 INFO - PID 15566 | |7;imaging-desaturate;94;94;94;94;94;94;95;95;93;94 01:50:59 INFO - PID 15566 | |8;json-parse-financial;52;53;53;51;51;52;52;52;51;55 01:50:59 INFO - PID 15566 | |9;json-stringify-tinderbox;37;38;37;38;38;38;38;38;38;38 01:50:59 INFO - PID 15566 | |10;stanford-crypto-aes;74;77;74;74;82;74;74;74;73;76 01:50:59 INFO - PID 15566 | |11;stanford-crypto-ccm;128;119;122;126;128;125;120;130;129;166 01:50:59 INFO - PID 15566 | |12;stanford-crypto-pbkdf2;172;176;173;168;172;170;185;171;168;178 01:50:59 INFO - PID 15566 | |13;stanford-crypto-sha256-iterative;68;66;72;69;66;63;63;71;64;64 01:50:59 INFO - PID 15566 | __end_tp_report 01:50:59 INFO - PID 15566 | __start_cc_report 01:50:59 INFO - PID 15566 | _x_x_mozilla_cycle_collect,964 01:50:59 INFO - PID 15566 | __end_cc_report 01:50:59 INFO - PID 15566 | __startTimestamp1504687859253__endTimestamp 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | ------- Summary: start ------- 01:50:59 INFO - PID 15566 | Number of tests: 14 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | [#0] ai-astar Cycles:10 Average:105.60 Median:109.50 stddev:5.42 (4.9%) stddev-sans-first:5.00 01:50:59 INFO - PID 15566 | Values: 98.0 108.0 100.0 107.0 107.0 113.0 108.0 97.0 107.0 111.0 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | [#1] audio-beat-detection Cycles:10 Average:139.50 Median:148.50 stddev:22.17 (14.9%) stddev-sans-first:23.51 01:50:59 INFO - PID 15566 | Values: 140.0 117.0 152.0 173.0 115.0 150.0 147.0 116.0 168.0 117.0 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | [#2] audio-dft Cycles:10 Average:162.60 Median:163.00 stddev:1.71 (1.1%) stddev-sans-first:1.81 01:50:59 INFO - PID 15566 | Values: 163.0 162.0 163.0 161.0 161.0 162.0 160.0 165.0 164.0 165.0 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | [#3] audio-fft Cycles:10 Average:92.60 Median:74.00 stddev:38.38 (51.9%) stddev-sans-first:40.23 01:50:59 INFO - PID 15566 | Values: 76.0 73.0 73.0 173.0 78.0 75.0 73.0 74.0 157.0 74.0 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | [#4] audio-oscillator Cycles:10 Average:93.60 Median:79.00 stddev:22.82 (28.9%) stddev-sans-first:23.59 01:50:59 INFO - PID 15566 | Values: 79.0 79.0 127.0 80.0 79.0 79.0 127.0 80.0 80.0 126.0 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:1.07 (0.9%) stddev-sans-first:1.13 01:50:59 INFO - PID 15566 | Values: 114.0 117.0 114.0 115.0 114.0 114.0 115.0 114.0 114.0 113.0 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.00 stddev:1.16 (0.8%) stddev-sans-first:1.20 01:50:59 INFO - PID 15566 | Values: 149.0 147.0 149.0 148.0 148.0 147.0 148.0 148.0 151.0 148.0 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 01:50:59 INFO - PID 15566 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 95.0 95.0 93.0 94.0 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.00 stddev:1.23 (2.4%) stddev-sans-first:1.30 01:50:59 INFO - PID 15566 | Values: 52.0 53.0 53.0 51.0 51.0 52.0 52.0 52.0 51.0 55.0 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | [#9] json-stringify-tinderbox Cycles:10 Average:37.80 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.33 01:50:59 INFO - PID 15566 | Values: 37.0 38.0 37.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | [#10] stanford-crypto-aes Cycles:10 Average:75.20 Median:74.00 stddev:2.66 (3.6%) stddev-sans-first:2.78 01:50:59 INFO - PID 15566 | Values: 74.0 77.0 74.0 74.0 82.0 74.0 74.0 74.0 73.0 76.0 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | [#11] stanford-crypto-ccm Cycles:10 Average:129.30 Median:128.00 stddev:13.44 (10.5%) stddev-sans-first:14.25 01:50:59 INFO - PID 15566 | Values: 128.0 119.0 122.0 126.0 128.0 125.0 120.0 130.0 129.0 166.0 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.30 Median:172.50 stddev:5.19 (3.0%) stddev-sans-first:5.48 01:50:59 INFO - PID 15566 | Values: 172.0 176.0 173.0 168.0 172.0 170.0 185.0 171.0 168.0 178.0 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:3.27 (4.9%) stddev-sans-first:3.43 01:50:59 INFO - PID 15566 | Values: 68.0 66.0 72.0 69.0 66.0 63.0 63.0 71.0 64.0 64.0 01:50:59 INFO - PID 15566 | -------- Summary: end -------- 01:50:59 INFO - PID 15566 | 01:50:59 INFO - PID 15566 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 01:51:00 INFO - TEST-INFO | 15566: exit 0 01:51:00 INFO - TEST-OK | kraken | took 61018ms 01:51:00 INFO - SUITE-END | took 1169s 01:51:00 INFO - Completed test suite (00:19:29) 01:51:00 INFO - javascript benchmark 01:51:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8594.745214133449, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3232.983835080824, 3288.8684452621887, 3327.1183136468826, 3327.2347360107174, 3342.4317865962785, 6367.6497792621385, 6432.903506447407, 6435.259945106308, 6437.35626437356, 6464.547481676279, 6240.656763877985, 6298.275698294698, 6327.905081423783, 6340.080688300205, 6392.3288054754275, 5815.796447124351, 5832.708364581765, 5857.794977175799, 5861.8827623447505, 5873.324567674708, 5893.027650437694, 5899.823005309826, 5992.850178745544, 6002.909956350636, 6030.788922387717, 5878.853028674296, 5926.792562260322, 5948.1375200595885, 5973.283206015284, 5991.730372133265, 5717.914231286534, 5771.913421298684, 5818.883622327529, 5844.883102337972, 5854.824355269348, 5699.344575373802, 5831.650100993954, 5845.210896528956, 5897.3218079920925, 5956.434138756811, 6358.618482891041, 6417.973124300136, 6447.709853056577, 6468.8706225875685, 6540.117084193621, 6666.766663166766, 6736.696848641774, 6755.256921738604, 6831.863362732717, 6929.203141638725, 6829.282925292822, 6847.931520684829, 6851.314868513158, 6853.623050732208, 6858.554193977376, 6378.87242255157, 6393.904091438632, 6470.4823614110755, 6495.3179916109075, 6508.967455162741, 7003.0, 7092.397146242524, 7154.391876690435, 7181.748638797617, 7204.56772593646, 6610.768623098168, 6739.427148692416, 6801.863962720817, 6888.035675005503, 6890.379865812101, 6727.091842601284, 6787.592744435349, 6872.965635171892, 6886.208086070066, 6895.448364130857, 6780.864382712417, 6893.276205998398, 6914.688839002257, 6919.75780847678, 6942.097527321416, 6855.383015528626, 6877.415419689282, 6892.414144797648, 6963.303669632996, 6987.371136597731, 4911.926321105187, 4959.578435832995, 4983.9252411213865, 5094.083065048253, 5097.0, 4462.9107417851465, 4487.214737420938, 4520.2315606347, 4520.909581808345, 4532.88667783308, 5993.041113421918, 5996.850078747979, 6080.75676972925, 6081.878362432726, 6090.847728806815, 6889.932060530626, 6909.965450172817, 6914.0666010089, 6991.19601245853, 7004.614746188907, 5888.558358123158, 5923.881522369528, 5979.5814292999075, 6007.789727359522, 6046.969765151146, 5944.464998150101, 5945.554083443759, 6010.879782404328, 6019.729112189962, 6027.397260273938, 6699.832504187434, 6711.798646040529, 6812.080369150201, 6819.454443644497, 6826.078479405316, 6659.101021362248, 6822.0, 6874.415674667709, 6963.268856770169, 6976.093107895941, 6609.3060228675295, 6681.766138185335, 6718.93281067183, 6725.39471447572, 6725.9663701681175, 6672.065910772592, 6684.0642310075655, 6777.390034896883, 6777.762778302736, 6778.966105169443, 6750.932490675031, 6832.385085342343, 6834.384905358542, 6910.7581234656545, 6943.618101004493, 6674.299198584079, 6701.698423571049, 6733.966330168318, 6743.763968261087, 6754.763583274562, 6650.634215118215, 6657.167854018247, 6665.900011499928, 6754.3921047106, 6761.763338283136, 6406.711697973509, 6457.257415397102, 6460.25707043696, 6535.411812936859, 6639.136912201383, 6578.342165783383, 6583.80248592543, 6594.967025164843, 6603.141591593062, 6657.933420665731, 6373.649449280334, 6411.166548348685, 6421.678916054272, 6430.839229019125, 6460.903086453613], "value": 6238.950942269244, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.9013718353798, 866.6230189867408, 895.6372669069025, 1156.50270383735, 1175.8588969323682, 30748.692513074864, 41070.79464602676, 41590.79204603973, 41672.99999999996, 41742.37386439206, 41625.37561936573, 41675.79162104193, 41695.58304416955, 41728.79135604318, 41768.37347439791, 7219.711211551532, 7693.999999999986, 7839.490433121858, 7854.803629909269, 7886.921130788704, 7728.613569321511, 7788.766337009899, 7823.647935842901, 7829.64766585505, 7847.176046515119, 7795.454318197729, 7818.921810781905, 7839.294463498284, 7847.450678452511, 7850.293473587376, 7803.76588702337, 7808.141104478503, 7811.492253003565, 7813.609319534001, 7823.100343460518, 7759.262870027338, 7799.49303295284, 7820.100688420791, 7824.139344672139, 7824.804379890491, 38182.809085954395, 38492.61507384946, 38500.42249366262, 38508.0, 38519.229615407814, 3482.355764183634, 3522.154682876117, 3528.1179705073732, 3530.770499917497, 3538.274653695986, 31655.20861978446, 31679.52480712768, 31706.841465792524, 31734.523982140287, 31833.840830795696, 5178.456262092502, 5183.403908550527, 5230.686158830483, 5253.9474605253845, 5271.446498117681, 186891.0, 189008.0, 189068.0, 189798.0, 190696.0, 47968.520314796406, 48020.27969580459, 48069.9999999993, 48101.51898480971, 48130.518694813305, 51028.74485627622, 51036.48963510391, 51184.23223651648, 51333.743331283105, 51393.0, 12559.686007849694, 14219.85780142206, 14467.421303147967, 14494.710105797825, 14516.637084072983, 10051.396916185053, 11363.829542556869, 11648.359340236368, 11651.825222621668, 11657.067434605211, 14066.71866562663, 14149.646258843406, 14210.07634503754, 14272.785908211385, 14305.57083287503, 5573.804916827891, 5716.142578613159, 5785.88428231433, 5890.263717035371, 5961.731722072517, 1004.8593196952432, 1013.9797204055877, 1026.3995562595965, 1030.9587616495405, 1042.5412818359898, 23279.719615421316, 23436.359454838, 23514.7648523516, 23564.64653030206, 23702.05191792309, 1123.8201887698092, 1128.6162704680448, 1138.322697994692, 1140.9771804563861, 1149.4597539156582, 12459.252444853339, 12494.250344979331, 12499.750004999849, 12565.0, 12585.307808070462, 229433.0, 229520.0, 230758.0, 231644.0, 232204.0, 52929.47070529322, 53296.20055699167, 53304.0, 53326.200106998425, 53414.465855340946, 1443.6463066548763, 1461.7416092137673, 1472.5876754508754, 1476.0258229568433, 1486.5020218226798, 22562.887185563966, 22619.773802261767, 22780.20269290567, 22781.886090569442, 23072.307830765105, 36342.0, 36699.44950825793, 36736.0, 36737.26525469529, 36778.26443471169, 201372.0, 201400.0, 201627.0, 201836.0, 202259.0, 29633.407331853672, 30030.849845750632, 32885.342293153524, 33414.83292583522, 33467.66532334743, 43007.13985720331, 43090.353644695984, 43235.783821080695, 43279.0, 43801.78099109484, 31926.361472770877, 31979.520307195875, 32057.198570035, 32057.83971080223, 32626.83686581552, 31179.220519486287, 32250.35499289954, 33788.0, 34118.48822267717, 34186.14534636654, 36821.0, 37358.62641373552, 37469.250614988094, 37554.812225938695, 37803.243935121696, 32455.0, 32607.510887337183, 32764.344713106082, 32878.67121328756, 33188.50217246695, 24021.159259425836, 24198.274051778473, 24247.15134970338, 24392.89471191457, 24817.62773558434], "value": 17176.97819868732, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.6467925207119, 564.8813749112686, 569.996805622105, 572.3904042195064, 587.4947545111204, 21878.0, 22464.438389040282, 22532.324030279076, 22581.209657661944, 22613.095476180933, 24507.264782056565, 25164.49671006579, 25202.0, 25202.74797252027, 25219.369515762115, 6602.801915942518, 6720.227173875018, 6720.361565651279, 6723.731050757964, 6728.192616885956, 6413.8396540086405, 6687.699053542602, 6693.431058360045, 6704.061431399608, 6704.698288577026, 6654.567453115532, 6663.966680166593, 6675.699593518303, 6678.699458524378, 6679.532432729711, 6671.03270025846, 6684.83287917801, 6691.531592788482, 6691.866162676719, 6695.163104611924, 6184.907226391608, 6196.349383314732, 6198.907016394758, 6211.19254496917, 6211.720472578745, 26460.73539264602, 28265.010724624746, 28927.56608650872, 29276.56085158703, 29354.412911741645, 2011.6781314989532, 2047.252752745252, 2047.252752745252, 2047.3550831488033, 2048.416201382599, 19737.0, 19974.80025199744, 20060.79939200604, 20068.49828754278, 20083.397498075083, 2327.8254130940245, 2335.9182428615086, 2385.8449200801892, 2404.326788499223, 2426.963595546068, 132098.0, 138837.22325553533, 138840.0, 139136.30431847775, 139165.3041734795, 13919.721605567833, 14411.711765764627, 14472.493462728755, 14747.483838065617, 14749.926250368679, 22259.777402226093, 23134.53730925372, 23171.189008384627, 23188.76811231866, 23569.41076473102, 8976.192142707188, 9001.594928228245, 9271.90728092724, 9305.209057230077, 9381.155695987394, 8152.2662960333855, 8172.468789528662, 8265.628046737911, 8270.338372930151, 8310.875336869953, 8829.955850220837, 9208.664743612138, 9216.907830921606, 9420.43477391345, 9469.431834089977, 2564.2948189248104, 2578.174984005138, 2627.8423294602385, 2648.6291919130954, 2739.6849662035547, 962.3407965543626, 965.1120968708805, 970.0784254957678, 982.7150126463246, 1002.0480543483719, 19436.416907492516, 19744.703829442282, 19957.802531848134, 20071.0, 20173.0, 1036.5542816588788, 1041.9270651054505, 1052.1004541117388, 1056.1709058389129, 1059.7456610413599, 10822.404767737693, 11006.339619622848, 11046.226764126595, 11049.558017679203, 11100.833487497694, 441655.0, 447592.0, 451976.0, 454032.0, 456278.0, 107728.0, 107854.0, 108413.0, 110103.0, 110637.0, 1161.349644199251, 1171.742216712322, 1183.089021453476, 1192.6481687901917, 1195.4321697193764, 17708.557286067437, 17859.553511162325, 17863.642727145645, 17882.374116905845, 18021.369252076114, 52509.0, 53233.46766532285, 55177.72411137919, 56368.0, 57597.99999999833, 70806.29193707996, 71092.28907710857, 71647.0, 71824.0, 72003.27996719966, 14415.207163605683, 14458.421663133357, 15122.092674439129, 15126.168060756763, 15181.468648597245, 21545.138194472045, 21694.674579880997, 21889.56220875542, 22305.0, 24236.87881560581, 18777.90611046936, 19033.809661903204, 19044.90477547659, 19178.616427671648, 19185.136668850213, 16517.33930642761, 17179.31282748676, 17208.741868871726, 17494.212760426068, 17587.912060439616, 19428.90285548563, 19720.30978915731, 19979.800201998376, 19987.400377988684, 20216.494587635425, 14803.111813291236, 14876.702465950404, 14896.925515372353, 14909.254537272876, 14920.328585213909, 18664.626707465504, 18718.71921921145, 19064.142113605198, 19083.713744293567, 19368.322108726126], "value": 11892.557328861229, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.466021261389, 1369.8356994736002, 1386.7503849307125, 1391.3738817532108, 1401.481451862811, 14817.0, 14859.479918202866, 15457.227138643051, 15472.149031803261, 15480.458183963563, 14836.035657682243, 15043.323050462728, 15048.84951150488, 15051.924740376282, 15061.698766024672, 2732.330579008146, 2786.8745906434156, 2797.021042635072, 2801.0, 2803.649543807024, 2671.1051797647715, 2770.6952235254107, 2842.0621195005597, 2842.9147125586264, 2844.4737723521216, 2777.722227777216, 2820.605115283862, 2821.4357128574266, 2821.8024738268327, 2821.985890070557, 2661.5076210901047, 2721.27886110179, 2725.9591106133425, 2733.2346942856034, 2736.3022429280604, 2629.2769488390654, 2676.050002249193, 2678.3839716865036, 2680.276325392133, 2681.034827462112, 14637.41450341985, 14978.026428282235, 15080.321385537678, 15088.471903483434, 15107.244637768177, 8088.757337279891, 8338.874916876252, 8356.2897153742, 8357.080721120672, 8358.916410835935, 5316.122839731478, 5572.442755724435, 5703.657780533182, 5790.826275211708, 5795.043817770063, 1856.0997916010724, 1878.3519685708409, 1912.4453908366556, 1954.452753229098, 1959.5101224693826, 26475.470490590273, 26806.731932680814, 27003.72996270032, 27007.594886076724, 27008.0, 6809.421199198025, 6817.863642727118, 6867.862642747217, 7020.0, 7027.648617569101, 17110.315587376615, 17123.0, 17124.74312885308, 17151.65696686059, 17153.313867445413, 7813.960930195312, 8048.436609437284, 8066.23370779775, 8068.596570171468, 8080.555569443737, 7392.704291828267, 7421.851562968711, 7448.180700122982, 7450.5529668219, 7472.476926615193, 4955.306257124005, 5090.694558326512, 5460.481254280837, 5504.587155963319, 5551.639143455744, 1524.7636616324487, 1574.7874037005088, 1575.558843523815, 1586.7143914095343, 1589.9205039748197, 845.5222799118478, 849.7153453592992, 870.6647940542937, 881.4667126388571, 888.2228050455851, 8388.580570971428, 8763.298936085097, 8773.166549177819, 8853.778655533535, 9161.129692679186, 976.1312431935582, 990.5146478225618, 1006.2000709436016, 1008.6469735592484, 1041.6666666666595, 5380.3274590676165, 5494.725263736797, 5852.122181672698, 5941.638753539973, 6101.0, 21654.566908661738, 21728.02223899897, 22251.109955601918, 22323.776762232494, 22492.550148997256, 21324.466888327606, 21716.45708857291, 21973.450663733536, 22009.779902201095, 22022.33932982013, 11598.94200528992, 11666.94166529179, 11793.88206117945, 11874.346910919983, 11945.0, 8587.613557389808, 8694.826103477768, 8705.782355441164, 8855.512946787989, 9113.954430227806, 18159.636807264043, 18721.15754791065, 19408.767991680572, 19507.21971121196, 19761.802381975995, 18814.435566933014, 19158.71261931044, 19321.613567728848, 19366.515837104183, 19370.2251909922, 12161.087918405978, 12341.753164936472, 12353.938230308791, 12433.813492797795, 12486.562970295996, 13796.103253288504, 13955.651108721957, 14016.0, 14092.36584353686, 14103.224322662352, 10963.56145754161, 10997.725056873642, 11109.833352499558, 11188.384638844616, 11361.204715669825, 10684.923316093144, 10884.238103333008, 11102.389368584805, 11243.775124497628, 11280.210385272952, 10015.14871235953, 10214.438205898747, 10289.485525723834, 10305.74235644115, 10316.226283028953, 7269.273072692688, 7280.563166210044, 7289.307515786204, 7317.414606831547, 7335.303146201102, 7393.445491588045, 7397.186309506058, 7459.664315105942, 7470.252974702486, 7595.696172153052], "value": 6814.51470504773, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.17658288259634, 501.46844344994287, 527.8865044015536, 531.7128750474744, 534.1533669134421, 6170.969145154279, 6217.626942383455, 6233.472799164206, 6285.182926219585, 6371.585846919947, 6043.516518678506, 6139.140520327147, 6179.938200617992, 6183.381661833814, 6222.813315600528, 4999.975005123959, 5009.674371165881, 5019.498050194988, 5058.898822023557, 5068.670536415121, 4845.5396737310075, 4961.553460188582, 4999.675021123615, 5018.924716129261, 5042.82350117746, 4959.851204463854, 4971.552560269575, 4993.201087825949, 5006.148954677696, 5018.963527066127, 4916.901661966776, 4967.652264341497, 4985.127602669555, 5013.022460620162, 5025.497450254982, 4419.712718673308, 4442.822287108512, 4464.441944756905, 4471.642268618502, 4478.708883922534, 6092.695365231765, 6136.662483563402, 6151.569390142692, 6170.475509581691, 6178.876422471571, 5678.627456858196, 5714.171445140431, 5720.141978703228, 5729.828105156852, 5741.483266506035, 6469.417752402259, 6477.157969463987, 6506.251781045193, 6507.577007494498, 6522.934770652328, 4805.783739731721, 4837.443693975203, 4876.878317986848, 4891.584215341701, 4914.238293064581, 6772.2211945625895, 6822.761203357859, 6914.446844252448, 6924.826879328007, 7006.124234470692, 4945.604351651859, 4953.975230123826, 4968.950310496921, 5003.924941125886, 5011.649184557046, 5039.0, 5059.139946209153, 5074.771635276347, 5085.491450854959, 5100.209467532539, 5564.387917329091, 5584.190292407578, 5627.521660658808, 5705.087186050212, 5717.799877004285, 6452.967735161388, 6476.514261430411, 6503.089567460559, 6596.80209593713, 6636.5354425190735, 1620.8379162083697, 1683.9242234099495, 1697.940572079996, 1715.6397156597004, 1733.8556552675425, 1193.6240084373394, 1196.8743281955444, 1197.3953153657346, 1217.6955761059735, 1219.591436868659, 5667.518260947783, 5766.740496677659, 5786.450287222707, 5841.211436456024, 5845.707714614252, 6380.170577824946, 6599.604023758494, 6664.633445160466, 6718.596884186964, 6808.489363297773, 5832.154337621021, 5849.415058494116, 5865.853353666193, 5878.794242201502, 5923.763049477972, 3532.275883443888, 3539.0975301298004, 3551.1477245460906, 3567.928641427157, 3571.4821350904494, 6513.674316284167, 6542.3130571290285, 6554.2134943806095, 6569.408753212138, 6644.0, 6927.134108236471, 6937.479689023344, 6973.581585104808, 6981.441484681213, 7050.647467626701, 6504.707288172043, 6522.315156908551, 6549.803505894831, 6589.9900530337745, 6627.966860165669, 6679.532432729662, 6692.364225398579, 6712.328767123248, 6771.559848609824, 6784.932150678627, 6697.3637504437, 6697.899531506934, 6717.816709569312, 6719.731210751515, 6794.524383293122, 6500.349965003462, 6581.177352830896, 6585.703643335965, 6588.769393071411, 6598.802035938929, 4960.23116416956, 4965.82619608312, 5000.0, 5011.699298042129, 5018.44797072329, 6608.735650574115, 6659.234188068434, 6742.359475849787, 6744.617353442434, 6890.517663763489, 6412.775552855627, 6422.903656445251, 6476.643784591893, 6518.837029074311, 6546.607203567801, 6197.938020619736, 6455.386738259858, 6460.709268082854, 6468.676566171578, 6609.239937407259, 6099.695015249131, 6274.247090349188, 6288.654124023223, 6304.275008374096, 6322.36776322364, 6487.935120648922, 6516.087747715229, 6521.413072823469, 6556.0, 6651.235107962646, 6246.562740608296, 6270.435660790551, 6333.36666333363, 6403.199600049994, 6464.870702586016], "value": 5049.990722274122, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [916.7845556294274, 921.4149015375243, 922.4741897118641, 934.4066517761224, 943.4716558727112, 17494.125293735327, 18536.81463185368, 19398.515037124078, 19409.708854367178, 19435.22259109634, 16527.421540246094, 18380.81619183808, 20344.59310813783, 20481.59036819263, 20518.179272829108, 958.5399008475936, 965.657191696948, 993.3692105512979, 999.0608827701973, 999.1058003087232, 993.5479356892632, 999.5052449037737, 1004.036125319694, 1004.5780772075746, 1004.8492726091072, 926.9768255793625, 1016.903394177552, 1030.7526193713493, 1051.6529545250087, 1059.311447559089, 1008.7074748322977, 1024.359775140537, 1029.5624359647156, 1029.6344797596896, 1030.1141018723893, 746.5707218349417, 751.0, 755.5844285642875, 755.7241606813528, 756.735142700056, 19937.0, 20197.899010504854, 20603.0, 20618.278360257318, 20783.376498705064, 20987.0, 21022.2642207522, 21412.46468838276, 21459.356219313446, 21560.245391411227, 16412.17939103052, 16720.498385048468, 16783.748243776354, 16886.0, 16896.577585560335, 3766.8493260269556, 3803.4865293185344, 3805.6194380562, 3805.676517496016, 3806.1816709407613, 126367.0, 127031.0, 130108.0, 131495.0, 133085.0, 16544.6691066178, 16610.169491525376, 16649.83350166507, 16658.250378732988, 16788.748168777478, 29533.0, 29884.0, 30114.54828177579, 30115.247118821768, 30134.84932575367, 8729.738107856774, 8847.0, 8852.468851868909, 8852.513111778786, 8866.73399798007, 3789.6020917803785, 3954.6440820326306, 4268.189044081677, 4442.311441726538, 4493.0115374617535, 5049.091163590516, 5147.253648221062, 5171.974140129275, 5184.5593124584075, 5184.714840683799, 942.1002942190192, 956.086936975187, 1064.2390690656218, 1089.8147314956477, 1102.7794441111648, 695.391532409142, 709.3580309819622, 709.5352544083634, 720.902678138455, 734.0384096833166, 12647.367631618567, 13031.804522932163, 13043.804342934864, 13059.34703264852, 13275.66810829737, 706.445440329339, 712.0636363182399, 713.1192976673804, 714.5926821711679, 717.121526130486, 5455.481729235717, 5561.527270181999, 6279.811605651837, 6383.467967687721, 6488.7728929488185, 108800.0, 109640.0, 112363.0, 112576.43711781388, 115122.0, 96340.51829740807, 96628.51685741526, 98469.50765246271, 100162.0, 100547.0, 88635.55682221676, 88667.0, 90981.54509227411, 92851.5357423222, 93088.53455732814, 11984.0, 12690.619281421572, 12759.80860287115, 13091.541796037092, 13213.207207567577, 41861.58138418577, 42946.92632684083, 43046.78476607597, 43239.135217296105, 43404.78297608618, 55241.44758552363, 56763.71618141883, 56849.715751420976, 57000.42999569951, 58011.709941451714, 18267.177976990803, 18785.0, 18806.435806925812, 18831.435056948314, 18977.90511047436, 17479.38822141219, 19303.0, 19452.416427507196, 19538.90230548838, 19617.70573441371, 19123.713144303125, 19236.23055077781, 19387.12757925925, 19459.610807783483, 19582.412527624194, 16039.518814435585, 16068.758968615246, 16177.27202275924, 16194.352225910832, 16208.270627821541, 18124.365647202285, 18272.817271827113, 18302.993335366682, 18353.816461835744, 18409.631807364047, 13451.125676830974, 13773.517926872511, 13853.16880987144, 13874.514391996232, 14024.158550487004, 14271.928640356731, 14703.779443308145, 14868.405263789327, 15083.39666413375, 15466.0], "value": 9189.96224138075, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1485.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 100.0, 107.0, 107.0, 113.0, 108.0, 97.0, 107.0, 111.0], "value": 105.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 117.0, 152.0, 173.0, 115.0, 150.0, 147.0, 116.0, 168.0, 117.0], "value": 139.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 161.0, 161.0, 162.0, 160.0, 165.0, 164.0, 165.0], "value": 162.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 73.0, 173.0, 78.0, 75.0, 73.0, 74.0, 157.0, 74.0], "value": 92.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 127.0, 80.0, 79.0, 79.0, 127.0, 80.0, 80.0, 126.0], "value": 93.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 117.0, 114.0, 115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 113.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 149.0, 148.0, 148.0, 147.0, 148.0, 148.0, 151.0, 148.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 93.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 53.0, 51.0, 51.0, 52.0, 52.0, 52.0, 51.0, 55.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 37.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 37.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 74.0, 74.0, 82.0, 74.0, 74.0, 74.0, 73.0, 76.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 119.0, 122.0, 126.0, 128.0, 125.0, 120.0, 130.0, 129.0, 166.0], "value": 129.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 176.0, 173.0, 168.0, 172.0, 170.0, 185.0, 171.0, 168.0, 178.0], "value": 173.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 72.0, 69.0, 66.0, 63.0, 63.0, 71.0, 64.0, 64.0], "value": 66.6, "unit": "score"}]}]} 01:51:00 INFO - Return code: 0 01:51:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:51:00 INFO - # TBPL SUCCESS # 01:51:00 INFO - Running post-action listener: _package_coverage_data 01:51:00 INFO - Running post-action listener: _resource_record_post_action 01:51:00 INFO - [mozharness: 2017-09-06 08:51:00.562101Z] Finished run-tests step (success) 01:51:00 INFO - Running post-run listener: _resource_record_post_run 01:51:00 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 16384; Write bytes: 396496896; Read time: 352; Write time: 371372 01:51:00 INFO - TinderboxPrint: CPU usage
13.1% 01:51:00 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 352 01:51:00 INFO - TinderboxPrint: I/O write bytes / time
396,496,896 / 371,372 01:51:00 INFO - TinderboxPrint: CPU idle
8,222.2 (86.9%) 01:51:00 INFO - TinderboxPrint: CPU user
1,190.1 (12.6%) 01:51:00 INFO - TinderboxPrint: Swap in / out
0 / 0 01:51:00 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 112091136; Read time: 104; Write time: 167020 01:51:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 01:51:00 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 12288; Write bytes: 284405760; Read time: 248; Write time: 204352 01:51:01 INFO - Running post-run listener: _upload_blobber_files 01:51:01 INFO - Blob upload gear active. 01:51:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:51:01 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 01:51:01 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'] 01:51:01 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 01:51:01 INFO - (blobuploader) - INFO - Open directory for files ... 01:51:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:51:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:51:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:51:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:51:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:51:02 INFO - (blobuploader) - INFO - Done attempting. 01:51:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:51:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:51:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:51:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:51:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:51:09 INFO - (blobuploader) - INFO - Done attempting. 01:51:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:51:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:51:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:51:10 INFO - (blobuploader) - INFO - Done attempting. 01:51:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:51:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:51:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:51:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:51:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:51:11 INFO - (blobuploader) - INFO - Done attempting. 01:51:11 INFO - (blobuploader) - INFO - Iteration through files over. 01:51:11 INFO - Return code: 0 01:51:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:51:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:51:11 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f15a8dd3346e8ad408db32a3e4a4591a1291c398a20d308d8243d051c7a5f474f94db74a0a44b5b711d0591d70831c4fd46646806b3e4b5c3b1e900c10f99c6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5c11c976994fa50c3af83cc0c76b5f8132e7eb924235d2b010f18f2dd6abc015c3ef9530b8976eda4d55bc009deaaa7a05bc5784285a22d549f2e43030bb90e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8219c8db07ef043df16b4591a0374406fc28cc5488fb963faa0cc3c0677b558a72891d7127cb37ffb9ef23b75492f91bcece394f99290ed442322b3d11ff1cc1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79cf39876a34265affeebfa72a4e12232fb3da250c9afb6010fca0345d3c0cb4ef5adf3af2eebc1415971f4c3bf7c3214d0bbc1437934d0b7d10d1b06fd6e314"} 01:51:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:51:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:51:11 INFO - Contents: 01:51:11 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f15a8dd3346e8ad408db32a3e4a4591a1291c398a20d308d8243d051c7a5f474f94db74a0a44b5b711d0591d70831c4fd46646806b3e4b5c3b1e900c10f99c6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5c11c976994fa50c3af83cc0c76b5f8132e7eb924235d2b010f18f2dd6abc015c3ef9530b8976eda4d55bc009deaaa7a05bc5784285a22d549f2e43030bb90e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8219c8db07ef043df16b4591a0374406fc28cc5488fb963faa0cc3c0677b558a72891d7127cb37ffb9ef23b75492f91bcece394f99290ed442322b3d11ff1cc1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79cf39876a34265affeebfa72a4e12232fb3da250c9afb6010fca0345d3c0cb4ef5adf3af2eebc1415971f4c3bf7c3214d0bbc1437934d0b7d10d1b06fd6e314"} 01:51:11 INFO - Running post-run listener: copy_logs_to_upload_dir 01:51:11 INFO - Copying logs to upload dir... 01:51:11 INFO - mkdir: /builds/slave/test/build/upload/logs 01:51:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.898853 ========= master_lag: -0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-06 01:51:11.121750) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-06 01:51:11.129695) ========= 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=5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818 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/1f15a8dd3346e8ad408db32a3e4a4591a1291c398a20d308d8243d051c7a5f474f94db74a0a44b5b711d0591d70831c4fd46646806b3e4b5c3b1e900c10f99c6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5c11c976994fa50c3af83cc0c76b5f8132e7eb924235d2b010f18f2dd6abc015c3ef9530b8976eda4d55bc009deaaa7a05bc5784285a22d549f2e43030bb90e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8219c8db07ef043df16b4591a0374406fc28cc5488fb963faa0cc3c0677b558a72891d7127cb37ffb9ef23b75492f91bcece394f99290ed442322b3d11ff1cc1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79cf39876a34265affeebfa72a4e12232fb3da250c9afb6010fca0345d3c0cb4ef5adf3af2eebc1415971f4c3bf7c3214d0bbc1437934d0b7d10d1b06fd6e314"} build_url:https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011346 build_url: 'https://queue.taskcluster.net/v1/task/K7fP2uF7RUix-OKaOebu0g/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f15a8dd3346e8ad408db32a3e4a4591a1291c398a20d308d8243d051c7a5f474f94db74a0a44b5b711d0591d70831c4fd46646806b3e4b5c3b1e900c10f99c6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5c11c976994fa50c3af83cc0c76b5f8132e7eb924235d2b010f18f2dd6abc015c3ef9530b8976eda4d55bc009deaaa7a05bc5784285a22d549f2e43030bb90e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8219c8db07ef043df16b4591a0374406fc28cc5488fb963faa0cc3c0677b558a72891d7127cb37ffb9ef23b75492f91bcece394f99290ed442322b3d11ff1cc1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79cf39876a34265affeebfa72a4e12232fb3da250c9afb6010fca0345d3c0cb4ef5adf3af2eebc1415971f4c3bf7c3214d0bbc1437934d0b7d10d1b06fd6e314"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-06 01:51:11.160365) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:51:11.160683) ========= 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=5c37b704b588aaa989324530000001d0-1504686068.508141-2146712818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004562 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:51:11.241303) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-06 01:51:11.241610) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-06 01:51:11.241902) ========= ========= Total master_lag: 0.09 =========