builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0029 starttime: 1496466705.36 results: success (0) revision: a3432c43dc9eb01376505fe2e992e4230bf20b4a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 22:11:45.362604) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 22:11:45.363050) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 22:11:45.363334) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 22:11:45.536893) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 22:11:45.537197) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w7JPXrf4ml/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-02 22:11:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to 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% 15.1M=0.001s 2017-06-02 22:11:45 (15.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.237675 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 22:11:45.861967) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 22:11:45.862277) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w7JPXrf4ml/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.028521 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 22:11:45.932783) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-02 22:11:45.933136) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev a3432c43dc9eb01376505fe2e992e4230bf20b4a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/cedar --rev a3432c43dc9eb01376505fe2e992e4230bf20b4a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w7JPXrf4ml/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-02 22:11:46,048 truncating revision to first 12 chars 2017-06-02 22:11:46,048 Setting DEBUG logging. 2017-06-02 22:11:46,048 attempt 1/10 2017-06-02 22:11:46,048 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/a3432c43dc9e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-02 22:11:46,881 unpacking tar archive at: cedar-a3432c43dc9e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.119013 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-02 22:11:47.161033) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 22:11:47.161362) ========= script_repo_revision: a3432c43dc9eb01376505fe2e992e4230bf20b4a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 22:11:47.161756) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 22:11:47.162205) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 22:11:47.441182) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-02 22:11:47.441783) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w7JPXrf4ml/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 22:11:47 INFO - MultiFileLogger online at 20170602 22:11:47 in /builds/slave/test 22:11:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 22:11:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:11:47 INFO - {'append_to_log': False, 22:11:47 INFO - 'base_work_dir': '/builds/slave/test', 22:11:47 INFO - 'blob_upload_branch': 'Cedar', 22:11:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:11:47 INFO - 'branch': 'Cedar', 22:11:47 INFO - 'buildbot_json_path': 'buildprops.json', 22:11:47 INFO - 'config_files': ('talos/mac_config.py',), 22:11:47 INFO - 'default_actions': ('clobber', 22:11:47 INFO - 'read-buildbot-config', 22:11:47 INFO - 'download-and-extract', 22:11:47 INFO - 'populate-webroot', 22:11:47 INFO - 'create-virtualenv', 22:11:47 INFO - 'install', 22:11:47 INFO - 'setup-mitmproxy', 22:11:47 INFO - 'run-tests'), 22:11:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:11:47 INFO - 'download_minidump_stackwalk': True, 22:11:47 INFO - 'download_symbols': 'ondemand', 22:11:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:11:47 INFO - 'tooltool.py': '/tools/tooltool.py', 22:11:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:11:47 INFO - '/tools/misc-python/virtualenv.py')}, 22:11:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:11:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:11:47 INFO - 'gecko_profile': False, 22:11:47 INFO - 'gecko_profile_interval': 0, 22:11:47 INFO - 'installer_path': 'installer.exe', 22:11:47 INFO - 'log_level': 'info', 22:11:47 INFO - 'log_name': 'talos', 22:11:47 INFO - 'log_to_console': True, 22:11:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:11:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:11:47 INFO - 'opt_config_files': (), 22:11:47 INFO - 'pip_index': False, 22:11:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:11:47 INFO - 'cmd': ('bash', 22:11:47 INFO - '-c', 22:11:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:11:47 INFO - 'enabled': True, 22:11:47 INFO - 'halt_on_failure': False, 22:11:47 INFO - 'name': 'check_screen_resolution'},), 22:11:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:11:47 INFO - 'cmd': ('bash', 22:11:47 INFO - '-c', 22:11:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:11:47 INFO - 'enabled': True, 22:11:47 INFO - 'halt_on_failure': False, 22:11:47 INFO - 'name': 'check_screen_resolution'},), 22:11:47 INFO - 'run_cmd_checks_enabled': True, 22:11:47 INFO - 'suite': 'dromaeojs-e10s', 22:11:47 INFO - 'system_bits': '32', 22:11:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:11:47 INFO - 'title': 't-yosemite-r7-0029', 22:11:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:11:47 INFO - 'use_talos_json': True, 22:11:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:11:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:11:47 INFO - 'work_dir': 'build'} 22:11:47 INFO - [mozharness: 2017-06-03 05:11:47.661114Z] Running clobber step. 22:11:47 INFO - Running pre-action listener: _resource_record_pre_action 22:11:47 INFO - Running main action method: clobber 22:11:47 INFO - rmtree: /builds/slave/test/build 22:11:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:11:50 INFO - Running post-action listener: _resource_record_post_action 22:11:50 INFO - [mozharness: 2017-06-03 05:11:50.098972Z] Finished clobber step (success) 22:11:50 INFO - [mozharness: 2017-06-03 05:11:50.099154Z] Running read-buildbot-config step. 22:11:50 INFO - Running pre-action listener: _resource_record_pre_action 22:11:50 INFO - Running main action method: read_buildbot_config 22:11:50 INFO - Using buildbot properties: 22:11:50 INFO - { 22:11:50 INFO - "project": "", 22:11:50 INFO - "product": "firefox", 22:11:50 INFO - "who": "mconley@mozilla.com", 22:11:50 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 22:11:50 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 22:11:50 INFO - "repository": "https://hg.mozilla.org/projects/cedar/", 22:11:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 22:11:50 INFO - "stage_platform": "macosx64", 22:11:50 INFO - "basedir": "/builds/slave/test", 22:11:50 INFO - "buildnumber": 3, 22:11:50 INFO - "platform": "yosemite_r7", 22:11:50 INFO - "parent_task_id": "C7HInBe8Sy-Cg6YRDzZ8GA", 22:11:50 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 22:11:50 INFO - "slavebuilddir": "test", 22:11:50 INFO - "taskId": "X5MW1oStRHGm118BaKi2qg", 22:11:50 INFO - "branch": "cedar", 22:11:50 INFO - "script_repo_revision": "default", 22:11:50 INFO - "revision": "a3432c43dc9eb01376505fe2e992e4230bf20b4a", 22:11:50 INFO - "slavename": "t-yosemite-r7-0029", 22:11:50 INFO - "repo_path": "projects/cedar" 22:11:50 INFO - } 22:11:50 INFO - Finding installer, test and symbols from parent task. 22:11:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X5MW1oStRHGm118BaKi2qg'}, attempt #1 22:11:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X5MW1oStRHGm118BaKi2qg'}, attempt #1 22:11:51 INFO - Task dependencies: WzBdXEUjSGepM82ln1iLVA C7HInBe8Sy-Cg6YRDzZ8GA 22:11:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA'}, attempt #1 22:11:51 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:11:51 INFO - Set test_url: https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/test_packages.json 22:11:51 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/target.crashreporter-symbols.zip 22:11:51 INFO - Running post-action listener: _resource_record_post_action 22:11:51 INFO - [mozharness: 2017-06-03 05:11:51.779941Z] Finished read-buildbot-config step (success) 22:11:51 INFO - [mozharness: 2017-06-03 05:11:51.780045Z] Running download-and-extract step. 22:11:51 INFO - Running pre-action listener: _resource_record_pre_action 22:11:51 INFO - Running main action method: download_and_extract 22:11:51 INFO - mkdir: /builds/slave/test/build/tests 22:11:51 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')]} 22:11:51 INFO - trying https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 22:11:51 INFO - Downloading https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:11:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 22:11:52 INFO - Downloaded 1328 bytes. 22:11:52 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:11:52 INFO - Using the following test package requirements: 22:11:52 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:11:52 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 22:11:52 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:11:52 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:11:52 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 22:11:52 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:11:52 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 22:11:52 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:11:52 INFO - u'jsshell-mac.zip'], 22:11:52 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:11:52 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 22:11:52 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:11:52 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:11:52 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 22:11:52 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:11:52 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 22:11:52 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:11:52 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 22:11:52 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:11:52 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 22:11:52 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:11:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:11:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:11:52 INFO - Fetch https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:11:54 INFO - Content-Length response header: 57633823 22:11:54 INFO - Bytes received: 57633823 22:12:12 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:12:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:12:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:12:12 INFO - Fetch https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:12:14 INFO - Content-Length response header: 57633823 22:12:14 INFO - Bytes received: 57633823 22:12:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 22:12:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:12:31 INFO - Fetch https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 22:12:32 INFO - Content-Length response header: 14447425 22:12:32 INFO - Bytes received: 14447425 22:12:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:33 INFO - trying https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:12:33 INFO - Downloading https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 22:12:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 22:12:35 INFO - Downloaded 67777710 bytes. 22:12:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:12:35 INFO - mkdir: /builds/slave/test/properties 22:12:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:12:35 INFO - Writing to file /builds/slave/test/properties/build_url 22:12:35 INFO - Contents: 22:12:35 INFO - build_url:https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:12:35 INFO - Running post-action listener: _resource_record_post_action 22:12:35 INFO - Running post-action listener: set_extra_try_arguments 22:12:35 INFO - [mozharness: 2017-06-03 05:12:35.046147Z] Finished download-and-extract step (success) 22:12:35 INFO - [mozharness: 2017-06-03 05:12:35.046253Z] Running populate-webroot step. 22:12:35 INFO - Running pre-action listener: _resource_record_pre_action 22:12:35 INFO - Running main action method: populate_webroot 22:12:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:12:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:12:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:12:35 INFO - '--tppagecycles', 22:12:35 INFO - '7'], 22:12:35 INFO - 'tests': ['tsvgm']}}, 22:12:35 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:12:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:12:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:12:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:12:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:12:35 INFO - 'talos_options': ['--disable-e10s'], 22:12:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:12:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:12:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:12:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:12:35 INFO - 'talos_options': ['--disable-e10s'], 22:12:35 INFO - 'tests': ['damp', 'tps']}, 22:12:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:12:35 INFO - 'tests': ['damp', 'tps']}, 22:12:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:12:35 INFO - 'tests': ['dromaeo_dom']}, 22:12:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:12:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:12:35 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 22:12:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:12:35 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 22:12:35 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 22:12:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:12:35 INFO - 'tests': ['a11yr', 22:12:35 INFO - 'ts_paint', 22:12:35 INFO - 'tpaint', 22:12:35 INFO - 'sessionrestore', 22:12:35 INFO - 'sessionrestore_no_auto_restore', 22:12:35 INFO - 'tabpaint']}, 22:12:35 INFO - 'other-e10s': {'tests': ['a11yr', 22:12:35 INFO - 'ts_paint', 22:12:35 INFO - 'tpaint', 22:12:35 INFO - 'sessionrestore', 22:12:35 INFO - 'sessionrestore_no_auto_restore', 22:12:35 INFO - 'tabpaint']}, 22:12:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:12:35 INFO - 'ts_paint', 22:12:35 INFO - 'tpaint', 22:12:35 INFO - 'sessionrestore', 22:12:35 INFO - 'sessionrestore_no_auto_restore', 22:12:35 INFO - 'tabpaint']}, 22:12:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:12:35 INFO - 'ts_paint', 22:12:35 INFO - 'tpaint', 22:12:35 INFO - 'sessionrestore', 22:12:35 INFO - 'sessionrestore_no_auto_restore', 22:12:35 INFO - 'tabpaint']}, 22:12:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:12:35 INFO - 'tests': ['a11yr', 22:12:35 INFO - 'ts_paint', 22:12:35 INFO - 'tpaint', 22:12:35 INFO - 'sessionrestore', 22:12:35 INFO - 'sessionrestore_no_auto_restore', 22:12:35 INFO - 'tabpaint']}, 22:12:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:12:35 INFO - 'tests': ['a11yr', 22:12:35 INFO - 'ts_paint', 22:12:35 INFO - 'tpaint', 22:12:35 INFO - 'sessionrestore', 22:12:35 INFO - 'sessionrestore_no_auto_restore', 22:12:35 INFO - 'tabpaint']}, 22:12:35 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 22:12:35 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 22:12:35 INFO - 'bloom_basic_ref']}, 22:12:35 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 22:12:35 INFO - 'talos_options': ['--mitmproxy', 22:12:35 INFO - 'mitmproxy-recording-1.mp', 22:12:35 INFO - '--firstNonBlankPaint'], 22:12:35 INFO - 'tests': ['Quantum_1']}, 22:12:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:12:35 INFO - 'tests': ['tsvgx', 22:12:35 INFO - 'tsvgr_opacity', 22:12:35 INFO - 'tart', 22:12:35 INFO - 'tscrollx', 22:12:35 INFO - 'cart', 22:12:35 INFO - 'tsvg_static']}, 22:12:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:12:35 INFO - 'tsvgr_opacity', 22:12:35 INFO - 'tart', 22:12:35 INFO - 'tscrollx', 22:12:35 INFO - 'cart', 22:12:35 INFO - 'tsvg_static']}, 22:12:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:12:35 INFO - 'talos_options': ['--disable-e10s'], 22:12:35 INFO - 'tests': ['tp5o']}, 22:12:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:12:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:12:35 INFO - 'talos_options': ['--disable-e10s', 22:12:35 INFO - '--xperf_path', 22:12:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:12:35 INFO - 'tests': ['tp5n']}, 22:12:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:12:35 INFO - 'talos_options': ['--xperf_path', 22:12:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:12:35 INFO - 'tests': ['tp5n']}}, 22:12:35 INFO - 'talos.zip': {'path': '', 22:12:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:12:35 INFO - Running post-action listener: _resource_record_post_action 22:12:35 INFO - [mozharness: 2017-06-03 05:12:35.067762Z] Finished populate-webroot step (success) 22:12:35 INFO - [mozharness: 2017-06-03 05:12:35.067889Z] Running create-virtualenv step. 22:12:35 INFO - Running pre-action listener: _resource_record_pre_action 22:12:35 INFO - Running main action method: create_virtualenv 22:12:35 INFO - Creating virtualenv /builds/slave/test/build/venv 22:12:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:12:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:12:35 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 22:12:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:12:35 INFO - Using real prefix '/tools/python27' 22:12:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:12:35 INFO - Installing distribute.............................................................................................................................................................................................done. 22:12:38 INFO - Installing pip.................done. 22:12:38 INFO - Return code: 0 22:12:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:12:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:12:39 INFO - Reading from file tmpfile_stdout 22:12:39 INFO - Output received: 22:12:39 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:12:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:12:39 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')]} 22:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:39 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')]} 22:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:39 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 0x1082d5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb30c5f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10825ec60>, '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 0x107ff3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801cbe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:12:39 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 22:12:39 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 22:12:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 22:12:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:39 INFO - 'HOME': '/Users/cltbld', 22:12:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:39 INFO - 'LOGNAME': 'cltbld', 22:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:39 INFO - 'MOZ_NO_REMOTE': '1', 22:12:39 INFO - 'NO_EM_RESTART': '1', 22:12:39 INFO - 'PAGER': '/bin/cat', 22:12:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:39 INFO - 'PWD': '/builds/slave/test', 22:12:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:39 INFO - 'SHELL': '/bin/bash', 22:12:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 22:12:39 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:12:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:39 INFO - 'USER': 'cltbld', 22:12:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:39 INFO - 'XPC_FLAGS': '0x0', 22:12:39 INFO - 'XPC_SERVICE_NAME': '0', 22:12:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:12:39 INFO - Cleaning up... 22:12:39 INFO - Return code: 0 22:12:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:12:39 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')]} 22:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:39 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')]} 22:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:39 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 0x1082d5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb30c5f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10825ec60>, '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 0x107ff3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801cbe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:12:39 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 22:12:39 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 22:12:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 22:12:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:39 INFO - 'HOME': '/Users/cltbld', 22:12:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:39 INFO - 'LOGNAME': 'cltbld', 22:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:39 INFO - 'MOZ_NO_REMOTE': '1', 22:12:39 INFO - 'NO_EM_RESTART': '1', 22:12:39 INFO - 'PAGER': '/bin/cat', 22:12:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:39 INFO - 'PWD': '/builds/slave/test', 22:12:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:39 INFO - 'SHELL': '/bin/bash', 22:12:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 22:12:39 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:12:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:39 INFO - 'USER': 'cltbld', 22:12:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:39 INFO - 'XPC_FLAGS': '0x0', 22:12:39 INFO - 'XPC_SERVICE_NAME': '0', 22:12:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:39 INFO - Downloading/unpacking psutil>=3.1.1 22:12:39 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 22:12:39 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 22:12:39 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 22:12:39 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 22:12:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:12:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:12:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:12:41 INFO - Installing collected packages: psutil 22:12:41 INFO - Running setup.py install for psutil 22:12:42 INFO - building 'psutil._psutil_osx' extension 22:12:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:12:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:12:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:12:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 22:12:42 INFO - building 'psutil._psutil_posix' extension 22:12:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 22:12:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:12:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:12:42 INFO - ^ 22:12:42 INFO - 1 warning generated. 22:12:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 22:12:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:12:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:12:43 INFO - Successfully installed psutil 22:12:43 INFO - Cleaning up... 22:12:43 INFO - Return code: 0 22:12:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:12:43 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')]} 22:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:43 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')]} 22:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:43 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 0x1082d5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb30c5f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10825ec60>, '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 0x107ff3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801cbe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:12:43 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 22:12:43 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 22:12:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 22:12:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:43 INFO - 'HOME': '/Users/cltbld', 22:12:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:43 INFO - 'LOGNAME': 'cltbld', 22:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:43 INFO - 'MOZ_NO_REMOTE': '1', 22:12:43 INFO - 'NO_EM_RESTART': '1', 22:12:43 INFO - 'PAGER': '/bin/cat', 22:12:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:43 INFO - 'PWD': '/builds/slave/test', 22:12:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:43 INFO - 'SHELL': '/bin/bash', 22:12:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 22:12:43 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:12:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:43 INFO - 'USER': 'cltbld', 22:12:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:43 INFO - 'XPC_FLAGS': '0x0', 22:12:43 INFO - 'XPC_SERVICE_NAME': '0', 22:12:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:12:43 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 22:12:43 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 22:12:43 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 22:12:43 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 22:12:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:12:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:12:44 INFO - Installing collected packages: mozsystemmonitor 22:12:44 INFO - Running setup.py install for mozsystemmonitor 22:12:45 INFO - Successfully installed mozsystemmonitor 22:12:45 INFO - Cleaning up... 22:12:45 INFO - Return code: 0 22:12:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:12:45 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')]} 22:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:45 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')]} 22:12:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:45 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 0x1082d5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb30c5f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10825ec60>, '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 0x107ff3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801cbe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:12:45 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 22:12:45 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 22:12:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 22:12:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:45 INFO - 'HOME': '/Users/cltbld', 22:12:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:45 INFO - 'LOGNAME': 'cltbld', 22:12:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:45 INFO - 'MOZ_NO_REMOTE': '1', 22:12:45 INFO - 'NO_EM_RESTART': '1', 22:12:45 INFO - 'PAGER': '/bin/cat', 22:12:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:45 INFO - 'PWD': '/builds/slave/test', 22:12:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:45 INFO - 'SHELL': '/bin/bash', 22:12:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 22:12:45 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:12:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:45 INFO - 'USER': 'cltbld', 22:12:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:45 INFO - 'XPC_FLAGS': '0x0', 22:12:45 INFO - 'XPC_SERVICE_NAME': '0', 22:12:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:45 INFO - Downloading/unpacking jsonschema==2.5.1 22:12:45 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 22:12:45 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 22:12:45 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 22:12:45 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 22:12:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:12:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:12:46 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 22:12:46 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 22:12:46 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 22:12:46 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 22:12:46 INFO - Downloading functools32-3.2.3-2.tar.gz 22:12:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:12:47 INFO - warning: no files found matching '*.txt' 22:12:47 INFO - no previously-included directories found matching 'build' 22:12:47 INFO - no previously-included directories found matching 'dist' 22:12:47 INFO - no previously-included directories found matching '.git*' 22:12:47 INFO - Installing collected packages: jsonschema, functools32 22:12:47 INFO - Running setup.py install for functools32 22:12:47 INFO - warning: no files found matching '*.txt' 22:12:47 INFO - no previously-included directories found matching 'build' 22:12:47 INFO - no previously-included directories found matching 'dist' 22:12:47 INFO - no previously-included directories found matching '.git*' 22:12:47 INFO - Successfully installed jsonschema functools32 22:12:47 INFO - Cleaning up... 22:12:47 INFO - Return code: 0 22:12:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:12:47 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')]} 22:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:47 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')]} 22:12:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:47 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 0x1082d5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb30c5f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10825ec60>, '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 0x107ff3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801cbe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:12:47 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 22:12:47 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 22:12:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 22:12:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:47 INFO - 'HOME': '/Users/cltbld', 22:12:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:47 INFO - 'LOGNAME': 'cltbld', 22:12:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:47 INFO - 'MOZ_NO_REMOTE': '1', 22:12:47 INFO - 'NO_EM_RESTART': '1', 22:12:47 INFO - 'PAGER': '/bin/cat', 22:12:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:47 INFO - 'PWD': '/builds/slave/test', 22:12:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:47 INFO - 'SHELL': '/bin/bash', 22:12:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 22:12:47 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:12:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:47 INFO - 'USER': 'cltbld', 22:12:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:47 INFO - 'XPC_FLAGS': '0x0', 22:12:47 INFO - 'XPC_SERVICE_NAME': '0', 22:12:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:12:47 INFO - Cleaning up... 22:12:47 INFO - Return code: 0 22:12:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:12:47 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')]} 22:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:47 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')]} 22:12:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:47 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 0x1082d5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb30c5f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10825ec60>, '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 0x107ff3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801cbe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:12:47 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 22:12:47 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 22:12:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 22:12:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:47 INFO - 'HOME': '/Users/cltbld', 22:12:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:47 INFO - 'LOGNAME': 'cltbld', 22:12:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:47 INFO - 'MOZ_NO_REMOTE': '1', 22:12:47 INFO - 'NO_EM_RESTART': '1', 22:12:47 INFO - 'PAGER': '/bin/cat', 22:12:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:47 INFO - 'PWD': '/builds/slave/test', 22:12:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:47 INFO - 'SHELL': '/bin/bash', 22:12:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 22:12:47 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:12:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:47 INFO - 'USER': 'cltbld', 22:12:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:47 INFO - 'XPC_FLAGS': '0x0', 22:12:47 INFO - 'XPC_SERVICE_NAME': '0', 22:12:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:47 INFO - Downloading/unpacking blobuploader==1.2.4 22:12:47 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 22:12:47 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 22:12:47 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 22:12:47 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 22:12:49 INFO - Downloading blobuploader-1.2.4.tar.gz 22:12:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:12:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:12:49 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 22:12:49 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 22:12:49 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 22:12:49 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 22:12:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:12:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:12:49 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 22:12:49 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 22:12:49 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 22:12:49 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 22:12:50 INFO - Downloading docopt-0.6.1.tar.gz 22:12:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:12:50 INFO - Installing collected packages: blobuploader, requests, docopt 22:12:50 INFO - Running setup.py install for blobuploader 22:12:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:12:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:12:50 INFO - Running setup.py install for requests 22:12:50 INFO - Running setup.py install for docopt 22:12:50 INFO - Successfully installed blobuploader requests docopt 22:12:50 INFO - Cleaning up... 22:12:51 INFO - Return code: 0 22:12:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:12:51 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')]} 22:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:51 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')]} 22:12:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:51 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 0x1082d5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb30c5f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10825ec60>, '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 0x107ff3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801cbe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:12:51 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 22:12:51 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 22:12:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 22:12:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:51 INFO - 'HOME': '/Users/cltbld', 22:12:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:51 INFO - 'LOGNAME': 'cltbld', 22:12:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:51 INFO - 'MOZ_NO_REMOTE': '1', 22:12:51 INFO - 'NO_EM_RESTART': '1', 22:12:51 INFO - 'PAGER': '/bin/cat', 22:12:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:51 INFO - 'PWD': '/builds/slave/test', 22:12:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:51 INFO - 'SHELL': '/bin/bash', 22:12:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 22:12:51 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:12:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:51 INFO - 'USER': 'cltbld', 22:12:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:51 INFO - 'XPC_FLAGS': '0x0', 22:12:51 INFO - 'XPC_SERVICE_NAME': '0', 22:12:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:12:51 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-PfNmdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:12:51 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ce5tSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:12:51 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-fzM_Rz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:12:51 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-xhsqzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:12:51 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-A4iFsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:12:51 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-tgQIw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:12:51 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-2K1Xkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:12:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-BJvUIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:12:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Eve25i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:12:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-DIoNaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:12:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-r_mIVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:12:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-4dlDkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:12:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-yYP7Qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:12:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-YAfg3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:12:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-rWHT9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:12:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-b_2R2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:12:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-EsxV_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:12:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:12:53 INFO - Running setup.py install for manifestparser 22:12:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:12:53 INFO - Running setup.py install for mozcrash 22:12:53 INFO - Running setup.py install for mozdebug 22:12:53 INFO - Running setup.py install for mozdevice 22:12:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:12:53 INFO - Running setup.py install for mozfile 22:12:53 INFO - Running setup.py install for mozhttpd 22:12:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:12:53 INFO - Running setup.py install for mozinfo 22:12:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:12:54 INFO - Running setup.py install for mozInstall 22:12:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:12:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:12:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:12:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:12:54 INFO - Running setup.py install for mozleak 22:12:54 INFO - Running setup.py install for mozlog 22:12:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:12:54 INFO - Running setup.py install for moznetwork 22:12:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:12:54 INFO - Running setup.py install for mozprocess 22:12:54 INFO - Running setup.py install for mozprofile 22:12:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:12:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:12:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:12:55 INFO - Running setup.py install for mozrunner 22:12:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:12:55 INFO - Running setup.py install for mozscreenshot 22:12:55 INFO - Running setup.py install for moztest 22:12:55 INFO - Running setup.py install for mozversion 22:12:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:12:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:12:55 INFO - Cleaning up... 22:12:55 INFO - Return code: 0 22:12:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:12: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')]} 22:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12: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')]} 22:12:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:55 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 0x1082d5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb30c5f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10825ec60>, '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 0x107ff3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801cbe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:12:55 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 22:12:55 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 22:12:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 22:12:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:55 INFO - 'HOME': '/Users/cltbld', 22:12:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:55 INFO - 'LOGNAME': 'cltbld', 22:12:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:55 INFO - 'MOZ_NO_REMOTE': '1', 22:12:55 INFO - 'NO_EM_RESTART': '1', 22:12:55 INFO - 'PAGER': '/bin/cat', 22:12:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:55 INFO - 'PWD': '/builds/slave/test', 22:12:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:55 INFO - 'SHELL': '/bin/bash', 22:12:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 22:12:55 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:12:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:55 INFO - 'USER': 'cltbld', 22:12:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:55 INFO - 'XPC_FLAGS': '0x0', 22:12:55 INFO - 'XPC_SERVICE_NAME': '0', 22:12:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:12:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-MG7FmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:12:56 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)) 22:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:12:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ajueRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:12:56 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)) 22:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:12:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-atgNu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:12:56 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)) 22:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:12:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-vbicC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:12:56 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)) 22:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:12:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-qLjiTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:12:56 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)) 22:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:12:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-xw_AHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:12:56 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)) 22:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:12:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-TQ9PBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 22:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:12:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-6uGzu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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)) 22:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:12:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-CdeQZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:12:56 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)) 22:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:12:56 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-VAD0Fr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 22:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:12:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-cWHPLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:12:57 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)) 22:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:12:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-_APCPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:12:57 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)) 22:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:12:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-oj5XTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:12:57 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)) 22:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:12:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-PtO_1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:12:57 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)) 22:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:12:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-I5YUkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:12:57 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)) 22:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:12:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-L5St8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:12:57 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)) 22:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:12:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-cGzLqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:12:57 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)) 22:12:57 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)) 22:12:57 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)) 22:12:57 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)) 22:12:57 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)) 22:12:57 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)) 22:12:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:12:57 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 22:12:57 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 22:12:57 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 22:12:57 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 22:12:59 INFO - Downloading blessings-1.6.tar.gz 22:12:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:12:59 INFO - Installing collected packages: blessings 22:12:59 INFO - Running setup.py install for blessings 22:12:59 INFO - Successfully installed blessings 22:12:59 INFO - Cleaning up... 22:12:59 INFO - Return code: 0 22:12:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:12:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:12:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:12:59 INFO - Reading from file tmpfile_stdout 22:12:59 INFO - Current package versions: 22:12:59 INFO - blessings == 1.6 22:12:59 INFO - blobuploader == 1.2.4 22:12:59 INFO - docopt == 0.6.1 22:12:59 INFO - functools32 == 3.2.3-2 22:12:59 INFO - jsonschema == 2.5.1 22:12:59 INFO - manifestparser == 1.1 22:12:59 INFO - mozInstall == 1.12 22:12:59 INFO - mozcrash == 1.0 22:12:59 INFO - mozdebug == 0.1 22:12:59 INFO - mozdevice == 0.51 22:12:59 INFO - mozfile == 1.2 22:12:59 INFO - mozhttpd == 0.7 22:12:59 INFO - mozinfo == 0.9 22:12:59 INFO - mozleak == 0.1 22:12:59 INFO - mozlog == 3.4 22:12:59 INFO - moznetwork == 0.27 22:12:59 INFO - mozprocess == 0.25 22:12:59 INFO - mozprofile == 0.28 22:12:59 INFO - mozrunner == 6.13 22:12:59 INFO - mozscreenshot == 0.1 22:12:59 INFO - mozsystemmonitor == 0.3 22:12:59 INFO - moztest == 0.8 22:12:59 INFO - mozversion == 1.4 22:12:59 INFO - psutil == 3.1.1 22:12:59 INFO - requests == 1.2.3 22:12:59 INFO - wsgiref == 0.1.2 22:12:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:12:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:59 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 0x1082d5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb30c5f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10825ec60>, '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 0x107ff3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801cbe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:12:59 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 22:12:59 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 22:12:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 22:12:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:59 INFO - 'HOME': '/Users/cltbld', 22:12:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:59 INFO - 'LOGNAME': 'cltbld', 22:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:59 INFO - 'MOZ_NO_REMOTE': '1', 22:12:59 INFO - 'NO_EM_RESTART': '1', 22:12:59 INFO - 'PAGER': '/bin/cat', 22:12:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:59 INFO - 'PWD': '/builds/slave/test', 22:12:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:59 INFO - 'SHELL': '/bin/bash', 22:12:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 22:12:59 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:12:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:59 INFO - 'USER': 'cltbld', 22:12:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:59 INFO - 'XPC_FLAGS': '0x0', 22:12:59 INFO - 'XPC_SERVICE_NAME': '0', 22:12:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 22:13: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 22:13: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 22:13: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 22:13: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 22:13:01 INFO - Downloading marionette_driver-1.4.0.tar.gz 22:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 22:13:01 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)) 22:13:01 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)) 22:13:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 22:13:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 22:13:01 INFO - Installing collected packages: marionette-driver 22:13:01 INFO - Running setup.py install for marionette-driver 22:13:01 INFO - Successfully installed marionette-driver 22:13:01 INFO - Cleaning up... 22:13:01 INFO - Return code: 0 22:13:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:13: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')]} 22:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13: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')]} 22:13:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1082d5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb30c5f300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10825ec60>, '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 0x107ff3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801cbe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:13: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', 'jsonschema'] in /builds/slave/test/build 22:13: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 jsonschema 22:13:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 22:13:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:01 INFO - 'HOME': '/Users/cltbld', 22:13:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:01 INFO - 'LOGNAME': 'cltbld', 22:13:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:01 INFO - 'MOZ_NO_REMOTE': '1', 22:13:01 INFO - 'NO_EM_RESTART': '1', 22:13:01 INFO - 'PAGER': '/bin/cat', 22:13:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:01 INFO - 'PWD': '/builds/slave/test', 22:13:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:01 INFO - 'SHELL': '/bin/bash', 22:13:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 22:13:01 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:13:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:01 INFO - 'USER': 'cltbld', 22:13:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:01 INFO - 'XPC_FLAGS': '0x0', 22:13:01 INFO - 'XPC_SERVICE_NAME': '0', 22:13:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:13:02 INFO - Cleaning up... 22:13:02 INFO - Return code: 0 22:13:02 INFO - Running post-action listener: _resource_record_post_action 22:13:02 INFO - Running post-action listener: _start_resource_monitoring 22:13:02 INFO - Starting resource monitoring. 22:13:02 INFO - [mozharness: 2017-06-03 05:13:02.067883Z] Finished create-virtualenv step (success) 22:13:02 INFO - [mozharness: 2017-06-03 05:13:02.068923Z] Running install step. 22:13:02 INFO - Running pre-action listener: _resource_record_pre_action 22:13:02 INFO - Running main action method: install 22:13:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:13:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:13:02 INFO - Reading from file tmpfile_stdout 22:13:02 INFO - Detecting whether we're running mozinstall >=1.0... 22:13:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:13:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:13:02 INFO - Reading from file tmpfile_stdout 22:13:02 INFO - Output received: 22:13:02 INFO - Usage: mozinstall [options] installer 22:13:02 INFO - Options: 22:13:02 INFO - -h, --help show this help message and exit 22:13:02 INFO - -d DEST, --destination=DEST 22:13:02 INFO - Directory to install application into. [default: 22:13:02 INFO - "/builds/slave/test"] 22:13:02 INFO - --app=APP Application being installed. [default: firefox] 22:13:02 INFO - mkdir: /builds/slave/test/build/application 22:13:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:13:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:13:23 INFO - Reading from file tmpfile_stdout 22:13:23 INFO - Output received: 22:13:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:13:23 INFO - Running post-action listener: _resource_record_post_action 22:13:23 INFO - [mozharness: 2017-06-03 05:13:23.483893Z] Finished install step (success) 22:13:23 INFO - [mozharness: 2017-06-03 05:13:23.484040Z] Running setup-mitmproxy step. 22:13:23 INFO - Running pre-action listener: _resource_record_pre_action 22:13:23 INFO - Running main action method: setup_mitmproxy 22:13:23 INFO - Skipping: mitmproxy is not required 22:13:23 INFO - Running post-action listener: _resource_record_post_action 22:13:23 INFO - [mozharness: 2017-06-03 05:13:23.484712Z] Finished setup-mitmproxy step (success) 22:13:23 INFO - [mozharness: 2017-06-03 05:13:23.484845Z] Running run-tests step. 22:13:23 INFO - Running pre-action listener: _resource_record_pre_action 22:13:23 INFO - Running main action method: run_tests 22:13:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:13:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:13:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:13:23 INFO - 2017-06-02 22:13:23.499 screenresolution[1635:8829] starting screenresolution argv=screenresolution get 22:13:23 INFO - 2017-06-02 22:13:23.502 screenresolution[1635:8829] Display 0: 1600x1200x32@60 22:13:23 INFO - 2017-06-02 22:13:23.507 screenresolution[1636:8831] starting screenresolution argv=screenresolution list 22:13:23 INFO - Available Modes on Display 0 22:13:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:13:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:13:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:13:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:13:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:13:23 INFO - Intel Iris: 22:13:23 INFO - Chipset Model: Intel Iris 22:13:23 INFO - Type: GPU 22:13:23 INFO - Bus: Built-In 22:13:23 INFO - VRAM (Dynamic, Max): 1536 MB 22:13:23 INFO - Vendor: Intel (0x8086) 22:13:23 INFO - Device ID: 0x0a2e 22:13:23 INFO - Revision ID: 0x0009 22:13:23 INFO - Displays: 22:13:23 INFO - Display: 22:13:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:13:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:13:23 INFO - Display Serial Number: 1600x1200 60 22:13:23 INFO - Main Display: Yes 22:13:23 INFO - Mirror: Off 22:13:23 INFO - Online: Yes 22:13:23 INFO - Rotation: Supported 22:13:23 INFO - Return code: 0 22:13:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:13:23 INFO - Python 2.7.3 22:13:23 INFO - Return code: 0 22:13:23 INFO - grabbing minidump binary from tooltool 22:13:23 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')]} 22:13:23 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/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 0x10825ec60>, '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 0x107ff3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10801cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:13:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:13:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:13:23 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:13:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:13:23 INFO - Return code: 0 22:13:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:13:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:13:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:13:23 INFO - ENV: RUST_BACKTRACE is now 1 22:13:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:13:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:13:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 22:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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 22:13:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w7JPXrf4ml/Render', 22:13:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:23 INFO - 'HOME': '/Users/cltbld', 22:13:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:23 INFO - 'LOGNAME': 'cltbld', 22:13:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:13:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:23 INFO - 'MOZ_NO_REMOTE': '1', 22:13:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:13:23 INFO - 'NO_EM_RESTART': '1', 22:13:23 INFO - 'PAGER': '/bin/cat', 22:13:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:23 INFO - 'PWD': '/builds/slave/test', 22:13:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:13:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:23 INFO - 'RUST_BACKTRACE': '1', 22:13:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 22:13:23 INFO - 'SHELL': '/bin/bash', 22:13:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners', 22:13:23 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 22:13:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:23 INFO - 'USER': 'cltbld', 22:13:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:23 INFO - 'XPC_FLAGS': '0x0', 22:13:23 INFO - 'XPC_SERVICE_NAME': '0', 22:13:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 22:13:24 INFO - mozversion application_buildid: 20170602202923 22:13:24 INFO - mozversion application_changeset: a3432c43dc9eb01376505fe2e992e4230bf20b4a 22:13:24 INFO - mozversion application_display_name: Nightly 22:13:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:13:24 INFO - mozversion application_name: Firefox 22:13:24 INFO - mozversion application_remotingname: firefox 22:13:24 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 22:13:24 INFO - mozversion application_vendor: Mozilla 22:13:24 INFO - mozversion application_version: 55.0a1 22:13:24 INFO - mozversion platform_buildid: 20170602202923 22:13:24 INFO - mozversion platform_changeset: a3432c43dc9eb01376505fe2e992e4230bf20b4a 22:13:24 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 22:13:24 INFO - mozversion platform_version: 55.0a1 22:13:24 INFO - using testdate: 1496466804 22:13:24 INFO - actual date: 1496466804 22:13:24 INFO - starting webserver on 'localhost:49234' 22:13:24 INFO - SUITE-START | Running 2 tests 22:13:24 INFO - TEST-START | dromaeo_css 22:13:24 INFO - operating with platform_type : mac_ 22:13:24 INFO - Initialising browser for dromaeo_css test... 22:13:24 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49234/getInfo.html -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpfPBKku/profile 22:13:24 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49234/getInfo.html -foreground) 22:13:57 INFO - TEST-INFO | 1646: killed by SIGTERM 22:13:57 INFO - Browser initialized. 22:13:57 INFO - Running cycle 1/1 for dromaeo_css test... 22:13:57 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpfPBKku/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:14:00 INFO - PID 1654 | 2017-06-02 22:14:00.069 plugin-container[1655:9469] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983b, name = 'com.apple.tsm.portname' 22:14:00 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:14:00 INFO - PID 1654 | 2017-06-02 22:14:00.070 plugin-container[1655:9469] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4813, name = 'com.apple.CFPasteboardClient' 22:14:00 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:14:00 INFO - PID 1654 | 2017-06-02 22:14:00.070 plugin-container[1655:9469] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:14:00 INFO - PID 1654 | 2017-06-02 22:14:00.589 plugin-container[1656:9518] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 22:14:00 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:14:00 INFO - PID 1654 | 2017-06-02 22:14:00.590 plugin-container[1656:9518] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 22:14:00 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:14:00 INFO - PID 1654 | 2017-06-02 22:14:00.590 plugin-container[1656:9518] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:14:09 INFO - PID 1654 | RSS: Main: 268505088 22:14:09 INFO - PID 1654 | 22:17:12 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49234/tests/dromaeo/cssquery-ext.html) 22:17:13 INFO - PID 1654 | RSS: Main: 271409152 22:17:13 INFO - PID 1654 | 22:20:15 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-ext.html (next: http://localhost:49234/tests/dromaeo/cssquery-jquery.html) 22:20:16 INFO - PID 1654 | RSS: Main: 283648000 22:20:16 INFO - PID 1654 | 22:23:18 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49234/tests/dromaeo/cssquery-mootools.html) 22:23:18 INFO - PID 1654 | RSS: Main: 285552640 22:23:18 INFO - PID 1654 | 22:26:20 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49234/tests/dromaeo/cssquery-prototype.html) 22:26:21 INFO - PID 1654 | RSS: Main: 295559168 22:26:21 INFO - PID 1654 | 22:29:23 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49234/tests/dromaeo/cssquery-yui.html) 22:29:24 INFO - PID 1654 | RSS: Main: 295612416 22:29:24 INFO - PID 1654 | 22:32:26 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-yui.html (next: http://localhost:49234/tests/dromaeo/cssquery-dojo.html) 22:32:26 INFO - PID 1654 | RSS: Main: 299212800 22:32:26 INFO - PID 1654 | 22:32:26 INFO - PID 1654 | __start_tp_report 22:32:26 INFO - PID 1654 | _x_x_mozilla_page_load 22:32:26 INFO - PID 1654 | _x_x_mozilla_page_load_details 22:32:26 INFO - PID 1654 | |i|pagename|runs| 22:32:26 INFO - PID 1654 | |0;dojo.html;3631.0377749896284;3645.9635403645966;3669;3687.1519550503385;3688.1148524354153;5510.732531517753;5609.382967873531;6180.660063696495;6306.211723534559;6312.116303717483;6187.102870083835;6215.285242197148;6246.156768836206;6284.842878928029;6319.6524191169465;4671.9955209629925;5299.258103865462;5396.541293990015;5469.644473109255;5477.452254774521;5515.503604675579;5552.7223638817895;5565.888682226353;5596.58025648076;5605.831825045255;5421.322334708161;5471.398146203915;5525.972370138124;5547.72261386929;5569.749361278732;5503.944960550383;5505.201745746845;5519.116941289414;5539.113741801333;5560.443955604448;4250.489941207075;5470.152126420411;5478.780848766045;5512.200730893998;5525.143602741541;6196.349383314732;6299.086632438317;6300.747970081191;6334.651594162319;6335.239771227436;6595;6712.261651218411;6751.122354093985;6788.8302792430095;6802.897956530656;6879.346462086094;6885.896711549331;6891.310868913069;6893.345132212432;6904.965475172592;6352.174217351715;6362.459190968773;6368.522360822911;6379;6387.489000879918;6792.796216113525;6860.588364698134;6937.791866244021;7026.92973070273;7048.048513450619;6404.42360187576;6497.090407342976;6615.900761488581;6667;6706.5640733352175;6736.865262694817;6769.898451523231;6774.864502709918;6863.588184708933;6894.069300644349;6720.563163394364;6725;6736.8989465158065;6737.966310168418;6740.29226931175;6504.089427480157;6751.459883209332;6764.4926630502905;6767.560108592926;6770.255271920085;5330.387005494418;5371.355437347465;5377.7311134443125;5425.186222066643;5457.863553411196;4852.199387101159;4859.173940430135;4892.143874821892;4915.361003069578;4927.827526036572;6288.717007734663;6362.268339141058;6381.489480841522;6384.201974753156;6396.776112836028;6628.767993120217;6741.494387920926;6835.58986460814;6888.896666549906;6911;6427.807165785034;6431.067495213168;6433;6437.967810160919;6438.291787903327;6108.5723999319625;6114.541409394314;6131.448169664752;6135.6932153393045;6155.230596175478;6773.221079575814;6866.244713081657;6975.930240697731;7007.054047703698;7039.753608623673;6793.592384456949;6846.965765171142;6912.896306555506;6942.583444993316;6962.164540255202;6999.720011199699;7042.507024508235;7074.186468556182;7076.752313668997;7086.185088714864;5975.894459525001;6550.410463058348;6641.169853768279;6684.899726504009;6754.358335958076;5674.319081710221;6876.209235937931;6896.827579310357;6901.723931042903;6929.930700692928;6650.401463868275;6685.665716714047;6691.297413771484;6710.396064354231;6711.094002309821;5996.4902983245165;6441.194850643669;6509.674516274073;6574.76988305407;6632.270450250565;5144.511271429208;6499.935000649933;6541.08424820516;6599.934000659932;6600.471962243105;6714.966425167843;6717.630530320879;6750.628715420699;6756.662166891538;6768.289329620319;6346.9682651586445;6407.455366293917;6438.034294855809;6534.477241820738;6545.21457425093 22:32:26 INFO - PID 1654 | |1;ext.html;672.8721542906848;805.5207151744713;857.4940784936888;1247.6007677543184;1315.309462532171;30334.84832575837;36481.72313969012;38925.41611875821;39554.60445395545;39707;39514.40728389077;39619.811405657805;39911;40015;40101;7842.019747531544;8226.670933162666;8406.24343809057;8410.243078122967;8432.494050356967;8387.916120838805;8480.618372173267;8481.915180848206;8485.236328730412;8487.108853570378;8458.111898250656;8464.32285417165;8506.659733610648;8511.361647876403;8524.10496897823;8464.619092140838;8490.40567160299;8493.320534357237;8494.320454363637;8497.277731392838;8233.423660343778;8238.340932725368;8297.917020829775;8301.543415112166;8303.584820758937;37034;37334.25331493355;37356;37359.81320093409;37389.25221495555;3556.5198698175673;3589.964100358989;3597.460380942853;3600.0459878132315;3601.3697602919647;29499;29746;29785.10644680663;29842.955496557515;29893.25266868324;3024.7126522980284;3103.7361824244967;3124.297033167519;3126.7342275906576;3147.5750773645495;244440;245549.7722511376;275543.999999998;298135.50932245416;300227.4988625065;47575.762121189175;47760.28359574609;47790;47926.04147917092;47950;49788.75105624449;49898.50101499011;49920.50079499158;54586;54949.725251373486;14339.498117565836;14385;14417.062890912057;14521.201333926736;14546.781798273034;11596;11659.008984236265;11737.237079589799;11737.88262117385;11761.059115270758;13938.860611393757;13977.440902363995;13989.880809535212;14022.929885350712;14140.43438262458;6153.876922461525;6203.2556093269095;6280.717367718464;6280.842978925472;6281.968590157111;993.7565296502403;1007.2747621712356;1065.9360438373567;1075.2849355178876;1079.6491140379408;22722.545549088925;23689.81550922447;24280;24525.877370613034;24930;1075.3440401355167;1083.550326614462;1134.4157758754247;1149.252985559393;1152.4986630815622;11732.413379331;11977.281363118242;12281.815772763415;12399.442025108892;12714.809277860839;257059.71470142528;310686;317273;317917;318301;45295.09409811785;54085;54836.725816370665;55185.72407137939;55477;1454.3673502026652;1504.9548513544612;1523.5657837516255;1532.1726267815477;1540.1144342003392;22680;22706.65940010868;23611;23970.52058958846;24022.759772402052;36964.44553331648;37176.69881554133;37286.62713372832;37418;37475.43786843145;243149;255605;256490;256574;258003.94713832322;31058.981166224123;31571.684283157792;33287.67671112352;36011.819940900124;36044;42868.78565607152;44275.55724442843;45346.54653453423;46259.537404625524;46439.76780116078;26657.60013599759;28273.57589636116;29830;30006;30709.618067186584;28288.575671364535;30025.249368765955;31211.843940780152;31247.687523125387;31756.6824331763;36468;37418.43872341971;38335.42496862605;38406.23187536289;38408.61591384162;26099.608505872806;29234;29538.523073846653;32168.19579510531;32314.515282271255;21653.78346216518;24102.75897241005;24783.13259035925;24896.253112406655;25040.874795625907 22:32:26 INFO - PID 1654 | |2;jquery.html;411.85173337598457;421.84180932150446;433.8655016944745;436.2061048891019;452.39153338759365;20885.582288354224;21829.890850545744;22675.999999999978;22723.431914202152;24176.274711758633;24916.25251242461;25487.490250194984;25489.87255063727;25497.872510637422;25508.36229094269;5963.344032156459;6856.691448884787;7015.368616824485;7016.684249208773;7030.191527974297;5423.484768946943;6915.55048921821;6970.198427180863;6995.685194166249;7033.613151276678;6905.620190889499;6935.687894044755;6966.721331146748;6976.476764242702;6989.370956613904;5735.369109397963;6918.861622767542;6969.965150174267;6985.126859142607;6992.091028166356;6767.864642707118;6788.049673045777;6826.965865170692;6828.453723702092;6866.313368663144;22559.097636094535;30088.19470831735;30646.84676576603;30841.8457907709;31333;2135.4234356723755;2322.512272422793;2336.906523739048;2340.3797993531557;2347.859128452298;18345.908270458694;18898.81101188998;18920.148593313334;18933;18977.810221897744;2288.656701494789;2324.5350929814103;2390.8445951013127;2392.9521409571885;2394;169609;177187.11406443015;190243;192531.0746892527;199744;24532.754672453048;25262.621060684105;25744.871275643876;26510.867445662647;26534.46931061368;43878;44126;44140;44149.55850441455;44203.778981104886;11133.944330278458;11253.268537545033;11299.49152288149;11384.943075284571;11414.657560273205;9047.909520904706;9070.591823367833;9077.68228112013;9166.49584272858;9171.312151588658;9963.651272205438;12228.510859565695;12404.75190496185;12457.501699931901;12480.563180288646;3433.4334834752485;3966.6231707988272;4017.718759686794;4244.702870799015;4741.051789642016;853.543354305456;939.3612343606413;945.3052006775015;953.5184731710442;1009.4296722351847;21214.575708485743;21220;22126.668099978513;22301.107955681917;22728;952.9523523823781;957.6696039866374;970.4856426094041;998.5756053677159;1014.3457469932;10835.295705779099;10931.726706832234;11225.158113141548;11273.943630281796;11371.260868043551;578578;582417;582981;583799.7025186362;589281;161017.19491402467;163173.18413407856;163500.64993500555;164428;165117;1141.0943723980806;1158.2645020412099;1237.3565745812127;1245.9626211213858;1252.1735654468007;22123;23051.538969220856;23520.7063611503;23626.81865906732;23639.527209456057;70324.64837675779;71452;72810;73065;73541;98532;98970;99021.51467728135;99107.50446247723;99435.5084673716;9451.905480945103;9549.90450095518;10283.12593429567;10293.279470436997;10371.533281002223;17307.826921731124;17911.910440447715;18097.819021809613;18265.634687305912;18771.624567508847;15280.541583752505;15319.923400382926;15432.382704692136;15442.84557154459;15566.9221653891;13316.46734130624;13464.192148471124;13630.591082267549;13956.581302560939;14133.93995450366;14612.123272603678;15689.450869219523;15691.843081569039;15877.52367428979;15892.60268493297;9883.50582470888;10216.489175541343;10428.895711042793;10489.47552622381;10500.26498145153;10668.466576671291;14698.926505367404;14869.033512821632;15134.470293539673;15170.089794612359 22:32:26 INFO - PID 1654 | |3;mootools.html;1282.3780466473759;1457.2058228265594;1462.7001464699738;1465.4724299252268;1471.9116852988818;9708.41749495029;11729.178957472981;12215.20601160924;12268.447919843606;12298.385080745971;9906.801863962717;12143.878561214386;12361.690957726061;12379.56671516497;12381.56664516742;2524.545581795276;2561.2956436979794;2564.807639427041;2565.6536367590325;2566.2942690760005;2384.988075059622;2403.3270684208364;2413.9275821725287;2419.237940048879;2421.2010036687852;2200.460887082659;2353.9176128835493;2477.2568229531157;2493.551160791057;2499.512595043967;2036.328011756117;2138.850280480367;2464.778169964703;2472.2088931542003;2475.665785119004;2434.4279094412977;2448.338948483917;2453.4479742058074;2458.3362492127203;2544.3384719972755;11896.22674526153;11908;11942.641720748392;11966.282023078644;12038.759224815542;6425.646589437579;6639.402453779183;6687.76592819249;6689.364510371507;6691.130153080117;3622.3518299174075;4120.361343991686;4292.570742925714;4322.848700295474;4398.142362239365;1228.6621179175709;1275.375066217556;1278.9586047364148;1303.7718399280088;1304.954326598574;15410.460633877872;15900.761488577566;16659.583510412216;16782.83217167825;16866.494005179866;5554.6944918029085;5619.213310136584;5770.913436298459;5787.450192231731;5800.854978625568;12404.875951240552;12432.875671243171;12465.439055242536;12475.126741128033;12541.435635396429;6353;6512.316206798313;6527.477801775846;6534.640594767334;6553.311902250195;5989.940100598938;6164.321924588292;6175.660338681326;6179.536534759911;6188.164597779242;4415.271480205794;4468.128714900563;4531.750753708578;4559.156556037144;4571.040081582838;1273.2296960339047;1294.5727909789746;1298.805179223124;1308.1758492149884;1310.4430616987743;824.3240542754884;832.5587438657521;864.7924498120406;866.2117473099576;896.7713233125511;6490.448311893542;6708.3291670833505;6825.453963682893;6919.827004324932;6954.860902781916;569.7122952908754;585.4584509328854;608.6591508755112;628.4595248086733;631.1132858334048;3871.090293780959;4274.871753847389;4307.741535507879;4553.134904368159;4964.453910069889;14609.561713148623;15358.308876100602;15370.462033828762;15376.461823836113;15377.846221537866;14281.214533200773;14835.406583736529;14969.775453368207;14991.550253492413;15117.470888518848;8379.748607541784;8581.356398270154;8581.399302048796;8588.8711669325;8598.613062412207;6091.756329746938;6972.477064220305;7336.96331518339;7365.1898291189;7497.250274972459;13235.933820331222;13566.59300220995;13661.38523766413;13679.521216757366;13682.794758078835;12733.61799146027;13108.29592153014;13810.861891380957;14134;14233.572992810232;8998.460092394478;10451.738706532398;10523.263371563917;10567.682969510926;10778.622748203776;10717.624883129052;10823.621173258898;10831.837522437012;10832.620858269922;10855.945720271347;9108.544572771467;9182.816343672956;9244.491552964651;9432.151106400457;9460.479673618018;8903.465792052497;8973.237274831712;9003.909960900308;9078.046805085634;9159.587818548314;8188.83622327562;8252.876206856781;8303.460275082341;8305.792355191168;8367.832643347221;5978.641281522949;6083.27000759894;6471.902921456088;6530.379613936669;6571.34286571339;6183.134361189347;6445.12990746262;6603.636799976048;6605.174353205849;6627.23786764509 22:32:26 INFO - PID 1654 | |4;prototype.html;387.1347538252006;402.40645048553387;403.6447925825274;410.40081481037686;413.7104027180973;6225.750969961207;6383.170187875576;6433.8391540211405;6480.189976252968;6523;5378.757955891984;5631.831045068655;6132.933533233383;6146.262448506185;6250.562370654752;4586.706646676662;4590.495045544987;4594.839180628679;4594.840425000245;4607.331936869144;4121.867380790591;4285.935710964338;4624.14453326134;4626.884327891796;4632.490426053131;4572.199865023625;4576.107659006495;4588.449386073659;4607.447106347243;4614.584687378136;3410.7100896423835;4582.6104781093645;4583.289590113538;4591.747453890035;4673.859784206479;3266.901992940192;3879.941800872989;3931.803409829497;3952.407138929155;3980.3233450313514;6187;6210.223722034746;6221.751129954796;6221.9688901555655;6229.878621848065;4807.471178170434;5467.890642187173;5485.451454854522;5489.560835133219;5521.723913804334;5627.634203776742;6739.584687215642;6782.321767823228;6796.35434633709;6810.897836532456;3949.7037722170667;4504.324351347292;4510.571495707903;4511.751853648048;4523.163214805271;5360.91958620621;6639.86720265592;6973.442124630017;7096.522703875115;7148.892766608505;3755.6244375562223;4483.529229430928;4555.658325625525;4562.771861406916;4567.931481027787;4046.676265898721;4083.612056854594;4628.467726211529;4649.628029757611;4652.813887444464;5294.894102117936;5392.64947778401;5411.161270003156;5436.3476382834315;5437.972810135924;6351.824912391223;6628.23775265841;6767.695453704595;6815.625140617313;6832.350926661959;1585.4133970431014;1591.8856800239878;1603.9839601603835;1605.6788642271592;1614.3211060496951;998.7166491059099;1021.7891798219134;1066.3814987307344;1066.4081434803759;1073.3774410841693;6405.8718825624155;6406.839829004219;6410.903836442457;6428.067930150102;6474.449671777952;7034.718611255493;7040.92959070413;7054.823629409306;7077.124561991077;7078.186008608973;6344.397282258178;6366.236051673829;6383.71273292703;6399.936000639934;6438.291787903327;3206.3426997465463;3598.5501812273465;3707.7589956652732;3712.554493460802;3713.405855063231;7220.4584656151;7233.204347521769;7252.166000909963;7270.63646817657;7295.927040729631;6068.757249709962;7221.783346499614;7260.818479537948;7264.891026634605;7293.70825166998;6102.145699602059;6597.637129957707;7201.963990180016;7253.8549229015125;7297.306755858292;6218.782342617987;6261.592996455216;7037.542559733805;7069.858602827811;7093.269091399745;5503.9174412383045;6816.2842901496615;6835.3253452904355;6954.617496037767;6982.860342793218;6089.330173680803;6479.708413121515;6647.933520664925;7247.601381924045;7291.817704557428;3888.9471764664604;4550.863474095782;4558.111168322145;4599.264117741145;4625.329327247463;5800.622959507618;6620.801375958729;7117.75087871922;7142.678579464041;7159;6416.973284274493;6730.697118629576;6844.178698556205;6850.903855383115;6894.655267236518;5885.911711324419;6031.627070373944;6606.240282367589;6808.897866532105;6843.828904277433;5611.551075913999;6566.179227596551;6652.667366631746;6659.134312539532;6689.09697190873;6600.735970561316;6824.590524568542;6834.51439765733;6869.828254293483;6875.003124546813;6589.374009469093;6645.867082658417;6648.700808463534;6650.933490665031;6663.003845961817 22:32:26 INFO - PID 1654 | |5;yui.html;725.6698202317945;800.6517165033212;808.4502538273979;824.0194168938962;838.6477679374663;15113.395464181433;17151.57121071972;17163;17203.397881074165;17235.39676111335;15364.07815531069;15721;15868.04791712498;16217.432389866357;16231.188440577984;973.5327043019355;988.6440881282732;990.267202270322;990.5493000684708;995.2286977592388;977.7555611097225;988.7231575158967;988.9307748457575;989.8267803134459;992.1071036067561;1053.1995683280686;1058.915286777056;1064.239069065618;1064.8243039898407;1065.8880817514166;1044.9947750261238;1059.2267644619433;1063.3407287481734;1064.7338165458636;1065.323519565074;770.2682451670879;778.5484419036945;781.3827076609472;783.4946459533626;785.8899754034439;17411.129443527774;17780.199891004937;17887.642247154985;18054.548636284067;18062.91622502641;17402.216900239393;17487.737683934753;17618.647627047387;17798;17867.821321786745;12022.51909923602;14854.628634284229;14942.402303907831;14969.101853888802;14976.17631030293;3555.253396786678;3580.1228698968657;3593.802340871251;3599.8200089995657;3604.387254166798;127555;128672;129830.70169298376;130394;130802.34598826944;11024.944875275572;11554;13449.663758406117;13702;13831.377588008563;26150.86924565365;26229.475410491683;26230.21309360722;26420.943162273295;26438.867805660848;7863.921360786433;7900.604969751489;7917.3270272026375;7940.761777146695;7962.402819788539;3915.1190982028816;3946.3685810270217;3956.4065390191126;3997.040710229524;4003.139325045129;4411.183930972717;4421.801018954155;4455.6658250631335;4460.085682435093;4466.843660471933;857.322715055112;895.1227796759213;905.2124651553191;905.4295793649958;906.7506435730227;647.9481641468766;649.7206201333378;652.3737212276174;678.4267294136447;684.9383455643732;11022.50398732059;12338.81491777624;12686.49254029847;13223.73552528944;13368.46526138963;590.1105609441798;636.1983900285674;657.8289644692414;663.847315117516;666.2970566052763;4870.68491507291;4898.951010489921;4952.62855285855;5074.594032477393;5722.713864306768;126560;128548;133452;136869;137063;103375;113578;115619;116682;116967;78420;80552;88489;99215;101348.49325753324;10795.352278863293;11188.545678901315;12006.459709312927;12465.875341246472;12783.872161278268;24335.634965475885;40758.79620601878;42101.578984209766;42721;46374.76812615915;59481;60302.396976029675;60597;61939.6903015482;75778.62110689412;14199.929000354932;17115;17339.306427742747;17437.389691361248;17580.38468653591;15308.617284567976;18849.151788169293;18970.723513178506;19132.426027219204;19186.616267674846;14616.70766584641;17721.91139044348;17862.374816881867;17979;18017.72973405426;14449.927750361601;16856.57858553546;17506.649867002336;17548.73676894822;18003.729944051112;15861.841381586037;16229.295923927817;17643.117844107488;17713.911430442764;17804;11479.540818367172;11485.310881347146;12018.75962480763;12194;12470.68823279389;10912.345259284468;11886.34625095628;11996.560412750527;12557.874421256036;13633.454661813417 22:32:26 INFO - PID 1654 | __end_tp_report 22:32:26 INFO - PID 1654 | __start_cc_report 22:32:26 INFO - PID 1654 | _x_x_mozilla_cycle_collect,453 22:32:26 INFO - PID 1654 | __end_cc_report 22:32:26 INFO - PID 1654 | __startTimestamp1496467946797__endTimestamp 22:32:26 INFO - PID 1654 | 22:32:26 INFO - PID 1654 | ------- Summary: start ------- 22:32:26 INFO - PID 1654 | Number of tests: 6 22:32:26 INFO - PID 1654 | 22:32:26 INFO - PID 1654 | [#0] dojo.html Cycles:165 Average:6233.81 Median:6438.29 stddev:755.70 (11.7%) stddev-sans-first:729.91 22:32:26 INFO - PID 1654 | Values: 3631.0 3646.0 3669.0 3687.2 3688.1 5510.7 5609.4 6180.7 6306.2 6312.1 6187.1 6215.3 6246.2 6284.8 6319.7 4672.0 5299.3 5396.5 5469.6 5477.5 5515.5 5552.7 5565.9 5596.6 5605.8 5421.3 5471.4 5526.0 5547.7 5569.7 5503.9 5505.2 5519.1 5539.1 5560.4 4250.5 5470.2 5478.8 5512.2 5525.1 6196.3 6299.1 6300.7 6334.7 6335.2 6595.0 6712.3 6751.1 6788.8 6802.9 6879.3 6885.9 6891.3 6893.3 6905.0 6352.2 6362.5 6368.5 6379.0 6387.5 6792.8 6860.6 6937.8 7026.9 7048.0 6404.4 6497.1 6615.9 6667.0 6706.6 6736.9 6769.9 6774.9 6863.6 6894.1 6720.6 6725.0 6736.9 6738.0 6740.3 6504.1 6751.5 6764.5 6767.6 6770.3 5330.4 5371.4 5377.7 5425.2 5457.9 4852.2 4859.2 4892.1 4915.4 4927.8 6288.7 6362.3 6381.5 6384.2 6396.8 6628.8 6741.5 6835.6 6888.9 6911.0 6427.8 6431.1 6433.0 6438.0 6438.3 6108.6 6114.5 6131.4 6135.7 6155.2 6773.2 6866.2 6975.9 7007.1 7039.8 6793.6 6847.0 6912.9 6942.6 6962.2 6999.7 7042.5 7074.2 7076.8 7086.2 5975.9 6550.4 6641.2 6684.9 6754.4 5674.3 6876.2 6896.8 6901.7 6929.9 6650.4 6685.7 6691.3 6710.4 6711.1 5996.5 6441.2 6509.7 6574.8 6632.3 5144.5 6499.9 6541.1 6599.9 6600.5 6715.0 6717.6 6750.6 6756.7 6768.3 6347.0 6407.5 6438.0 6534.5 6545.2 22:32:26 INFO - PID 1654 | 22:32:26 INFO - PID 1654 | [#1] ext.html Cycles:180 Average:43170.14 Median:318109.00 stddev:72867.65 (22.9%) stddev-sans-first:73002.20 22:32:26 INFO - PID 1654 | Values: 672.9 805.5 857.5 1247.6 1315.3 30334.8 36481.7 38925.4 39554.6 39707.0 39514.4 39619.8 39911.0 40015.0 40101.0 7842.0 8226.7 8406.2 8410.2 8432.5 8387.9 8480.6 8481.9 8485.2 8487.1 8458.1 8464.3 8506.7 8511.4 8524.1 8464.6 8490.4 8493.3 8494.3 8497.3 8233.4 8238.3 8297.9 8301.5 8303.6 37034.0 37334.3 37356.0 37359.8 37389.3 3556.5 3590.0 3597.5 3600.0 3601.4 29499.0 29746.0 29785.1 29843.0 29893.3 3024.7 3103.7 3124.3 3126.7 3147.6 244440.0 245549.8 275544.0 298135.5 300227.5 47575.8 47760.3 47790.0 47926.0 47950.0 49788.8 49898.5 49920.5 54586.0 54949.7 14339.5 14385.0 14417.1 14521.2 14546.8 11596.0 11659.0 11737.2 11737.9 11761.1 13938.9 13977.4 13989.9 14022.9 14140.4 6153.9 6203.3 6280.7 6280.8 6282.0 993.8 1007.3 1065.9 1075.3 1079.6 22722.5 23689.8 24280.0 24525.9 24930.0 1075.3 1083.6 1134.4 1149.3 1152.5 11732.4 11977.3 12281.8 12399.4 12714.8 257059.7 310686.0 317273.0 317917.0 318301.0 45295.1 54085.0 54836.7 55185.7 55477.0 1454.4 1505.0 1523.6 1532.2 1540.1 22680.0 22706.7 23611.0 23970.5 24022.8 36964.4 37176.7 37286.6 37418.0 37475.4 243149.0 255605.0 256490.0 256574.0 258003.9 31059.0 31571.7 33287.7 36011.8 36044.0 42868.8 44275.6 45346.5 46259.5 46439.8 26657.6 28273.6 29830.0 30006.0 30709.6 28288.6 30025.2 31211.8 31247.7 31756.7 36468.0 37418.4 38335.4 38406.2 38408.6 26099.6 29234.0 29538.5 32168.2 32314.5 21653.8 24102.8 24783.1 24896.3 25040.9 22:32:26 INFO - PID 1654 | 22:32:26 INFO - PID 1654 | [#2] jquery.html Cycles:180 Average:41706.88 Median:2391.90 stddev:100597.82 (4205.8%) stddev-sans-first:100832.24 22:32:26 INFO - PID 1654 | Values: 411.9 421.8 433.9 436.2 452.4 20885.6 21829.9 22676.0 22723.4 24176.3 24916.3 25487.5 25489.9 25497.9 25508.4 5963.3 6856.7 7015.4 7016.7 7030.2 5423.5 6915.6 6970.2 6995.7 7033.6 6905.6 6935.7 6966.7 6976.5 6989.4 5735.4 6918.9 6970.0 6985.1 6992.1 6767.9 6788.0 6827.0 6828.5 6866.3 22559.1 30088.2 30646.8 30841.8 31333.0 2135.4 2322.5 2336.9 2340.4 2347.9 18345.9 18898.8 18920.1 18933.0 18977.8 2288.7 2324.5 2390.8 2393.0 2394.0 169609.0 177187.1 190243.0 192531.1 199744.0 24532.8 25262.6 25744.9 26510.9 26534.5 43878.0 44126.0 44140.0 44149.6 44203.8 11133.9 11253.3 11299.5 11384.9 11414.7 9047.9 9070.6 9077.7 9166.5 9171.3 9963.7 12228.5 12404.8 12457.5 12480.6 3433.4 3966.6 4017.7 4244.7 4741.1 853.5 939.4 945.3 953.5 1009.4 21214.6 21220.0 22126.7 22301.1 22728.0 953.0 957.7 970.5 998.6 1014.3 10835.3 10931.7 11225.2 11273.9 11371.3 578578.0 582417.0 582981.0 583799.7 589281.0 161017.2 163173.2 163500.6 164428.0 165117.0 1141.1 1158.3 1237.4 1246.0 1252.2 22123.0 23051.5 23520.7 23626.8 23639.5 70324.6 71452.0 72810.0 73065.0 73541.0 98532.0 98970.0 99021.5 99107.5 99435.5 9451.9 9549.9 10283.1 10293.3 10371.5 17307.8 17911.9 18097.8 18265.6 18771.6 15280.5 15319.9 15432.4 15442.8 15566.9 13316.5 13464.2 13630.6 13956.6 14133.9 14612.1 15689.5 15691.8 15877.5 15892.6 9883.5 10216.5 10428.9 10489.5 10500.3 10668.5 14698.9 14869.0 15134.5 15170.1 22:32:26 INFO - PID 1654 | 22:32:26 INFO - PID 1654 | [#3] mootools.html Cycles:180 Average:7220.99 Median:2565.97 stddev:4567.01 (178.0%) stddev-sans-first:4558.01 22:32:26 INFO - PID 1654 | Values: 1282.4 1457.2 1462.7 1465.5 1471.9 9708.4 11729.2 12215.2 12268.4 12298.4 9906.8 12143.9 12361.7 12379.6 12381.6 2524.5 2561.3 2564.8 2565.7 2566.3 2385.0 2403.3 2413.9 2419.2 2421.2 2200.5 2353.9 2477.3 2493.6 2499.5 2036.3 2138.9 2464.8 2472.2 2475.7 2434.4 2448.3 2453.4 2458.3 2544.3 11896.2 11908.0 11942.6 11966.3 12038.8 6425.6 6639.4 6687.8 6689.4 6691.1 3622.4 4120.4 4292.6 4322.8 4398.1 1228.7 1275.4 1279.0 1303.8 1305.0 15410.5 15900.8 16659.6 16782.8 16866.5 5554.7 5619.2 5770.9 5787.5 5800.9 12404.9 12432.9 12465.4 12475.1 12541.4 6353.0 6512.3 6527.5 6534.6 6553.3 5989.9 6164.3 6175.7 6179.5 6188.2 4415.3 4468.1 4531.8 4559.2 4571.0 1273.2 1294.6 1298.8 1308.2 1310.4 824.3 832.6 864.8 866.2 896.8 6490.4 6708.3 6825.5 6919.8 6954.9 569.7 585.5 608.7 628.5 631.1 3871.1 4274.9 4307.7 4553.1 4964.5 14609.6 15358.3 15370.5 15376.5 15377.8 14281.2 14835.4 14969.8 14991.6 15117.5 8379.7 8581.4 8581.4 8588.9 8598.6 6091.8 6972.5 7337.0 7365.2 7497.3 13235.9 13566.6 13661.4 13679.5 13682.8 12733.6 13108.3 13810.9 14134.0 14233.6 8998.5 10451.7 10523.3 10567.7 10778.6 10717.6 10823.6 10831.8 10832.6 10855.9 9108.5 9182.8 9244.5 9432.2 9460.5 8903.5 8973.2 9003.9 9078.0 9159.6 8188.8 8252.9 8303.5 8305.8 8367.8 5978.6 6083.3 6471.9 6530.4 6571.3 6183.1 6445.1 6603.6 6605.2 6627.2 22:32:26 INFO - PID 1654 | 22:32:26 INFO - PID 1654 | [#4] prototype.html Cycles:180 Average:5450.51 Median:6220.27 stddev:1741.74 (28.0%) stddev-sans-first:1704.66 22:32:26 INFO - PID 1654 | Values: 387.1 402.4 403.6 410.4 413.7 6225.8 6383.2 6433.8 6480.2 6523.0 5378.8 5631.8 6132.9 6146.3 6250.6 4586.7 4590.5 4594.8 4594.8 4607.3 4121.9 4285.9 4624.1 4626.9 4632.5 4572.2 4576.1 4588.4 4607.4 4614.6 3410.7 4582.6 4583.3 4591.7 4673.9 3266.9 3879.9 3931.8 3952.4 3980.3 6187.0 6210.2 6221.8 6222.0 6229.9 4807.5 5467.9 5485.5 5489.6 5521.7 5627.6 6739.6 6782.3 6796.4 6810.9 3949.7 4504.3 4510.6 4511.8 4523.2 5360.9 6639.9 6973.4 7096.5 7148.9 3755.6 4483.5 4555.7 4562.8 4567.9 4046.7 4083.6 4628.5 4649.6 4652.8 5294.9 5392.6 5411.2 5436.3 5438.0 6351.8 6628.2 6767.7 6815.6 6832.4 1585.4 1591.9 1604.0 1605.7 1614.3 998.7 1021.8 1066.4 1066.4 1073.4 6405.9 6406.8 6410.9 6428.1 6474.4 7034.7 7040.9 7054.8 7077.1 7078.2 6344.4 6366.2 6383.7 6399.9 6438.3 3206.3 3598.6 3707.8 3712.6 3713.4 7220.5 7233.2 7252.2 7270.6 7295.9 6068.8 7221.8 7260.8 7264.9 7293.7 6102.1 6597.6 7202.0 7253.9 7297.3 6218.8 6261.6 7037.5 7069.9 7093.3 5503.9 6816.3 6835.3 6954.6 6982.9 6089.3 6479.7 6647.9 7247.6 7291.8 3888.9 4550.9 4558.1 4599.3 4625.3 5800.6 6620.8 7117.8 7142.7 7159.0 6417.0 6730.7 6844.2 6850.9 6894.7 5885.9 6031.6 6606.2 6808.9 6843.8 5611.6 6566.2 6652.7 6659.1 6689.1 6600.7 6824.6 6834.5 6869.8 6875.0 6589.4 6645.9 6648.7 6650.9 6663.0 22:32:26 INFO - PID 1654 | 22:32:26 INFO - PID 1654 | [#5] yui.html Cycles:180 Average:23798.42 Median:17877.73 stddev:35474.23 (198.4%) stddev-sans-first:35531.45 22:32:26 INFO - PID 1654 | Values: 725.7 800.7 808.5 824.0 838.6 15113.4 17151.6 17163.0 17203.4 17235.4 15364.1 15721.0 15868.0 16217.4 16231.2 973.5 988.6 990.3 990.5 995.2 977.8 988.7 988.9 989.8 992.1 1053.2 1058.9 1064.2 1064.8 1065.9 1045.0 1059.2 1063.3 1064.7 1065.3 770.3 778.5 781.4 783.5 785.9 17411.1 17780.2 17887.6 18054.5 18062.9 17402.2 17487.7 17618.6 17798.0 17867.8 12022.5 14854.6 14942.4 14969.1 14976.2 3555.3 3580.1 3593.8 3599.8 3604.4 127555.0 128672.0 129830.7 130394.0 130802.3 11024.9 11554.0 13449.7 13702.0 13831.4 26150.9 26229.5 26230.2 26420.9 26438.9 7863.9 7900.6 7917.3 7940.8 7962.4 3915.1 3946.4 3956.4 3997.0 4003.1 4411.2 4421.8 4455.7 4460.1 4466.8 857.3 895.1 905.2 905.4 906.8 647.9 649.7 652.4 678.4 684.9 11022.5 12338.8 12686.5 13223.7 13368.5 590.1 636.2 657.8 663.8 666.3 4870.7 4899.0 4952.6 5074.6 5722.7 126560.0 128548.0 133452.0 136869.0 137063.0 103375.0 113578.0 115619.0 116682.0 116967.0 78420.0 80552.0 88489.0 99215.0 101348.5 10795.4 11188.5 12006.5 12465.9 12783.9 24335.6 40758.8 42101.6 42721.0 46374.8 59481.0 60302.4 60597.0 61939.7 75778.6 14199.9 17115.0 17339.3 17437.4 17580.4 15308.6 18849.2 18970.7 19132.4 19186.6 14616.7 17721.9 17862.4 17979.0 18017.7 14449.9 16856.6 17506.6 17548.7 18003.7 15861.8 16229.3 17643.1 17713.9 17804.0 11479.5 11485.3 12018.8 12194.0 12470.7 10912.3 11886.3 11996.6 12557.9 13633.5 22:32:26 INFO - PID 1654 | -------- Summary: end -------- 22:32:26 INFO - PID 1654 | 22:32:27 INFO - TEST-INFO | 1654: exit 0 22:32:27 INFO - TEST-OK | dromaeo_css | took 1143342ms 22:32:27 INFO - TEST-START | kraken 22:32:27 INFO - operating with platform_type : mac_ 22:32:27 INFO - Initialising browser for kraken test... 22:32:27 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49234/getInfo.html -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpmez_V7/profile 22:32:27 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49234/getInfo.html -foreground) 22:33:01 INFO - TEST-INFO | 1664: killed by SIGTERM 22:33:01 INFO - Browser initialized. 22:33:01 INFO - Running cycle 1/1 for kraken test... 22:33:01 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpmez_V7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:33:03 INFO - PID 1668 | 2017-06-02 22:33:03.363 plugin-container[1669:11989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3b, name = 'com.apple.tsm.portname' 22:33:03 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:33:03 INFO - PID 1668 | 2017-06-02 22:33:03.364 plugin-container[1669:11989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491b, name = 'com.apple.CFPasteboardClient' 22:33:03 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:33:03 INFO - PID 1668 | 2017-06-02 22:33:03.364 plugin-container[1669:11989] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:33:04 INFO - PID 1668 | 2017-06-02 22:33:04.124 plugin-container[1670:12033] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 22:33:04 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:33:04 INFO - PID 1668 | 2017-06-02 22:33:04.125 plugin-container[1670:12033] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 22:33:04 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:33:04 INFO - PID 1668 | 2017-06-02 22:33:04.125 plugin-container[1670:12033] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:33:13 INFO - PID 1668 | RSS: Main: 260005888 22:33:13 INFO - PID 1668 | 22:33:16 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection) 22:33:17 INFO - PID 1668 | RSS: Main: 270647296 22:33:17 INFO - PID 1668 | 22:33:19 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-dft) 22:33:20 INFO - PID 1668 | RSS: Main: 279056384 22:33:20 INFO - PID 1668 | 22:33:23 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-fft) 22:33:24 INFO - PID 1668 | RSS: Main: 281124864 22:33:24 INFO - PID 1668 | 22:33:26 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator) 22:33:26 INFO - PID 1668 | RSS: Main: 284209152 22:33:26 INFO - PID 1668 | 22:33:28 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:33:29 INFO - PID 1668 | RSS: Main: 284880896 22:33:29 INFO - PID 1668 | 22:33:32 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom) 22:33:33 INFO - PID 1668 | RSS: Main: 289845248 22:33:33 INFO - PID 1668 | 22:33:35 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate) 22:33:36 INFO - PID 1668 | RSS: Main: 292782080 22:33:36 INFO - PID 1668 | 22:33:39 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial) 22:33:40 INFO - PID 1668 | RSS: Main: 291852288 22:33:40 INFO - PID 1668 | 22:33:41 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:33:42 INFO - PID 1668 | RSS: Main: 291385344 22:33:42 INFO - PID 1668 | 22:33:44 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:33:45 INFO - PID 1668 | RSS: Main: 290963456 22:33:45 INFO - PID 1668 | 22:33:47 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:33:48 INFO - PID 1668 | RSS: Main: 290918400 22:33:48 INFO - PID 1668 | 22:33:50 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:33:51 INFO - PID 1668 | RSS: Main: 290713600 22:33:51 INFO - PID 1668 | 22:33:54 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:33:55 INFO - PID 1668 | RSS: Main: 290717696 22:33:55 INFO - PID 1668 | 22:33:56 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49234/tests/kraken/driver.html?testName=ai-astar) 22:33:57 INFO - PID 1668 | RSS: Main: 292245504 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | __start_tp_report 22:33:57 INFO - PID 1668 | _x_x_mozilla_page_load 22:33:57 INFO - PID 1668 | _x_x_mozilla_page_load_details 22:33:57 INFO - PID 1668 | |i|pagename|runs| 22:33:57 INFO - PID 1668 | |0;ai-astar;100;115;113;112;113;110;114;115;115;114 22:33:57 INFO - PID 1668 | |1;audio-beat-detection;133;105;130;148;111;96;115;141;160;111 22:33:57 INFO - PID 1668 | |2;audio-dft;222;278;205;212;220;277;228;199;192;200 22:33:57 INFO - PID 1668 | |3;audio-fft;78;56;59;86;60;92;83;88;76;56 22:33:57 INFO - PID 1668 | |4;audio-oscillator;78;67;81;71;67;67;90;92;103;104 22:33:57 INFO - PID 1668 | |5;imaging-gaussian-blur;89;89;88;88;89;88;89;92;110;98 22:33:57 INFO - PID 1668 | |6;imaging-darkroom;96;97;95;94;97;103;95;95;95;95 22:33:57 INFO - PID 1668 | |7;imaging-desaturate;122;146;141;94;92;92;90;91;91;91 22:33:57 INFO - PID 1668 | |8;json-parse-financial;51;50;49;51;51;50;51;51;48;50 22:33:57 INFO - PID 1668 | |9;json-stringify-tinderbox;55;70;70;58;62;55;68;82;83;83 22:33:57 INFO - PID 1668 | |10;stanford-crypto-aes;88;88;90;88;84;89;90;95;137;116 22:33:57 INFO - PID 1668 | |11;stanford-crypto-ccm;158;154;126;135;136;144;143;137;148;138 22:33:57 INFO - PID 1668 | |12;stanford-crypto-pbkdf2;186;184;183;185;183;182;184;187;181;191 22:33:57 INFO - PID 1668 | |13;stanford-crypto-sha256-iterative;68;68;70;68;91;89;82;68;68;71 22:33:57 INFO - PID 1668 | __end_tp_report 22:33:57 INFO - PID 1668 | __start_cc_report 22:33:57 INFO - PID 1668 | _x_x_mozilla_cycle_collect,1205 22:33:57 INFO - PID 1668 | __end_cc_report 22:33:57 INFO - PID 1668 | __startTimestamp1496468037210__endTimestamp 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | ------- Summary: start ------- 22:33:57 INFO - PID 1668 | Number of tests: 14 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | [#0] ai-astar Cycles:10 Average:112.10 Median:114.00 stddev:4.53 (4.0%) stddev-sans-first:1.67 22:33:57 INFO - PID 1668 | Values: 100.0 115.0 113.0 112.0 113.0 110.0 114.0 115.0 115.0 114.0 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | [#1] audio-beat-detection Cycles:10 Average:125.00 Median:137.00 stddev:20.63 (15.1%) stddev-sans-first:21.68 22:33:57 INFO - PID 1668 | Values: 133.0 105.0 130.0 148.0 111.0 96.0 115.0 141.0 160.0 111.0 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | [#2] audio-dft Cycles:10 Average:223.30 Median:221.00 stddev:30.71 (13.9%) stddev-sans-first:32.57 22:33:57 INFO - PID 1668 | Values: 222.0 278.0 205.0 212.0 220.0 277.0 228.0 199.0 192.0 200.0 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | [#3] audio-fft Cycles:10 Average:73.40 Median:80.50 stddev:14.26 (17.7%) stddev-sans-first:15.03 22:33:57 INFO - PID 1668 | Values: 78.0 56.0 59.0 86.0 60.0 92.0 83.0 88.0 76.0 56.0 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | [#4] audio-oscillator Cycles:10 Average:82.00 Median:74.50 stddev:14.54 (19.5%) stddev-sans-first:15.35 22:33:57 INFO - PID 1668 | Values: 78.0 67.0 81.0 71.0 67.0 67.0 90.0 92.0 103.0 104.0 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:92.00 Median:89.00 stddev:7.02 (7.9%) stddev-sans-first:7.37 22:33:57 INFO - PID 1668 | Values: 89.0 89.0 88.0 88.0 89.0 88.0 89.0 92.0 110.0 98.0 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | [#6] imaging-darkroom Cycles:10 Average:96.20 Median:95.00 stddev:2.57 (2.7%) stddev-sans-first:2.73 22:33:57 INFO - PID 1668 | Values: 96.0 97.0 95.0 94.0 97.0 103.0 95.0 95.0 95.0 95.0 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | [#7] imaging-desaturate Cycles:10 Average:105.00 Median:91.00 stddev:22.45 (24.7%) stddev-sans-first:22.96 22:33:57 INFO - PID 1668 | Values: 122.0 146.0 141.0 94.0 92.0 92.0 90.0 91.0 91.0 91.0 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:51.00 stddev:1.03 (2.0%) stddev-sans-first:1.05 22:33:57 INFO - PID 1668 | Values: 51.0 50.0 49.0 51.0 51.0 50.0 51.0 51.0 48.0 50.0 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:70.00 stddev:11.18 (16.0%) stddev-sans-first:10.72 22:33:57 INFO - PID 1668 | Values: 55.0 70.0 70.0 58.0 62.0 55.0 68.0 82.0 83.0 83.0 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | [#10] stanford-crypto-aes Cycles:10 Average:96.50 Median:88.50 stddev:16.79 (19.0%) stddev-sans-first:17.52 22:33:57 INFO - PID 1668 | Values: 88.0 88.0 90.0 88.0 84.0 89.0 90.0 95.0 137.0 116.0 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:141.90 Median:143.50 stddev:9.56 (6.7%) stddev-sans-first:8.18 22:33:57 INFO - PID 1668 | Values: 158.0 154.0 126.0 135.0 136.0 144.0 143.0 137.0 148.0 138.0 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.60 Median:184.50 stddev:2.88 (1.6%) stddev-sans-first:3.00 22:33:57 INFO - PID 1668 | Values: 186.0 184.0 183.0 185.0 183.0 182.0 184.0 187.0 181.0 191.0 22:33:57 INFO - PID 1668 | 22:33:57 INFO - PID 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:70.50 stddev:9.32 (13.2%) stddev-sans-first:9.60 22:33:57 INFO - PID 1668 | Values: 68.0 68.0 70.0 68.0 91.0 89.0 82.0 68.0 68.0 71.0 22:33:57 INFO - PID 1668 | -------- Summary: end -------- 22:33:57 INFO - PID 1668 | 22:33:58 INFO - TEST-INFO | 1668: exit 0 22:33:58 INFO - TEST-OK | kraken | took 90633ms 22:33:58 INFO - SUITE-END | took 1233s 22:33:58 INFO - Completed test suite (00:20:34) 22:33:58 INFO - javascript benchmark 22:33:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8221.20884770744, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3631.0377749896284, 3645.9635403645966, 3669.0, 3687.1519550503385, 3688.1148524354153, 5510.732531517753, 5609.382967873531, 6180.660063696495, 6306.211723534559, 6312.116303717483, 6187.102870083835, 6215.285242197148, 6246.156768836206, 6284.842878928029, 6319.6524191169465, 4671.9955209629925, 5299.258103865462, 5396.541293990015, 5469.644473109255, 5477.452254774521, 5515.503604675579, 5552.7223638817895, 5565.888682226353, 5596.58025648076, 5605.831825045255, 5421.322334708161, 5471.398146203915, 5525.972370138124, 5547.72261386929, 5569.749361278732, 5503.944960550383, 5505.201745746845, 5519.116941289414, 5539.113741801333, 5560.443955604448, 4250.489941207075, 5470.152126420411, 5478.780848766045, 5512.200730893998, 5525.143602741541, 6196.349383314732, 6299.086632438317, 6300.747970081191, 6334.651594162319, 6335.239771227436, 6595.0, 6712.261651218411, 6751.122354093985, 6788.8302792430095, 6802.897956530656, 6879.346462086094, 6885.896711549331, 6891.310868913069, 6893.345132212432, 6904.965475172592, 6352.174217351715, 6362.459190968773, 6368.522360822911, 6379.0, 6387.489000879918, 6792.796216113525, 6860.588364698134, 6937.791866244021, 7026.92973070273, 7048.048513450619, 6404.42360187576, 6497.090407342976, 6615.900761488581, 6667.0, 6706.5640733352175, 6736.865262694817, 6769.898451523231, 6774.864502709918, 6863.588184708933, 6894.069300644349, 6720.563163394364, 6725.0, 6736.8989465158065, 6737.966310168418, 6740.29226931175, 6504.089427480157, 6751.459883209332, 6764.4926630502905, 6767.560108592926, 6770.255271920085, 5330.387005494418, 5371.355437347465, 5377.7311134443125, 5425.186222066643, 5457.863553411196, 4852.199387101159, 4859.173940430135, 4892.143874821892, 4915.361003069578, 4927.827526036572, 6288.717007734663, 6362.268339141058, 6381.489480841522, 6384.201974753156, 6396.776112836028, 6628.767993120217, 6741.494387920926, 6835.58986460814, 6888.896666549906, 6911.0, 6427.807165785034, 6431.067495213168, 6433.0, 6437.967810160919, 6438.291787903327, 6108.5723999319625, 6114.541409394314, 6131.448169664752, 6135.6932153393045, 6155.230596175478, 6773.221079575814, 6866.244713081657, 6975.930240697731, 7007.054047703698, 7039.753608623673, 6793.592384456949, 6846.965765171142, 6912.896306555506, 6942.583444993316, 6962.164540255202, 6999.720011199699, 7042.507024508235, 7074.186468556182, 7076.752313668997, 7086.185088714864, 5975.894459525001, 6550.410463058348, 6641.169853768279, 6684.899726504009, 6754.358335958076, 5674.319081710221, 6876.209235937931, 6896.827579310357, 6901.723931042903, 6929.930700692928, 6650.401463868275, 6685.665716714047, 6691.297413771484, 6710.396064354231, 6711.094002309821, 5996.4902983245165, 6441.194850643669, 6509.674516274073, 6574.76988305407, 6632.270450250565, 5144.511271429208, 6499.935000649933, 6541.08424820516, 6599.934000659932, 6600.471962243105, 6714.966425167843, 6717.630530320879, 6750.628715420699, 6756.662166891538, 6768.289329620319, 6346.9682651586445, 6407.455366293917, 6438.034294855809, 6534.477241820738, 6545.21457425093], "value": 6183.9831742323895, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.8721542906848, 805.5207151744713, 857.4940784936888, 1247.6007677543184, 1315.309462532171, 30334.84832575837, 36481.72313969012, 38925.41611875821, 39554.60445395545, 39707.0, 39514.40728389077, 39619.811405657805, 39911.0, 40015.0, 40101.0, 7842.019747531544, 8226.670933162666, 8406.24343809057, 8410.243078122967, 8432.494050356967, 8387.916120838805, 8480.618372173267, 8481.915180848206, 8485.236328730412, 8487.108853570378, 8458.111898250656, 8464.32285417165, 8506.659733610648, 8511.361647876403, 8524.10496897823, 8464.619092140838, 8490.40567160299, 8493.320534357237, 8494.320454363637, 8497.277731392838, 8233.423660343778, 8238.340932725368, 8297.917020829775, 8301.543415112166, 8303.584820758937, 37034.0, 37334.25331493355, 37356.0, 37359.81320093409, 37389.25221495555, 3556.5198698175673, 3589.964100358989, 3597.460380942853, 3600.0459878132315, 3601.3697602919647, 29499.0, 29746.0, 29785.10644680663, 29842.955496557515, 29893.25266868324, 3024.7126522980284, 3103.7361824244967, 3124.297033167519, 3126.7342275906576, 3147.5750773645495, 244440.0, 245549.7722511376, 275543.999999998, 298135.50932245416, 300227.4988625065, 47575.762121189175, 47760.28359574609, 47790.0, 47926.04147917092, 47950.0, 49788.75105624449, 49898.50101499011, 49920.50079499158, 54586.0, 54949.725251373486, 14339.498117565836, 14385.0, 14417.062890912057, 14521.201333926736, 14546.781798273034, 11596.0, 11659.008984236265, 11737.237079589799, 11737.88262117385, 11761.059115270758, 13938.860611393757, 13977.440902363995, 13989.880809535212, 14022.929885350712, 14140.43438262458, 6153.876922461525, 6203.2556093269095, 6280.717367718464, 6280.842978925472, 6281.968590157111, 993.7565296502403, 1007.2747621712356, 1065.9360438373567, 1075.2849355178876, 1079.6491140379408, 22722.545549088925, 23689.81550922447, 24280.0, 24525.877370613034, 24930.0, 1075.3440401355167, 1083.550326614462, 1134.4157758754247, 1149.252985559393, 1152.4986630815622, 11732.413379331, 11977.281363118242, 12281.815772763415, 12399.442025108892, 12714.809277860839, 257059.71470142528, 310686.0, 317273.0, 317917.0, 318301.0, 45295.09409811785, 54085.0, 54836.725816370665, 55185.72407137939, 55477.0, 1454.3673502026652, 1504.9548513544612, 1523.5657837516255, 1532.1726267815477, 1540.1144342003392, 22680.0, 22706.65940010868, 23611.0, 23970.52058958846, 24022.759772402052, 36964.44553331648, 37176.69881554133, 37286.62713372832, 37418.0, 37475.43786843145, 243149.0, 255605.0, 256490.0, 256574.0, 258003.94713832322, 31058.981166224123, 31571.684283157792, 33287.67671112352, 36011.819940900124, 36044.0, 42868.78565607152, 44275.55724442843, 45346.54653453423, 46259.537404625524, 46439.76780116078, 26657.60013599759, 28273.57589636116, 29830.0, 30006.0, 30709.618067186584, 28288.575671364535, 30025.249368765955, 31211.843940780152, 31247.687523125387, 31756.6824331763, 36468.0, 37418.43872341971, 38335.42496862605, 38406.23187536289, 38408.61591384162, 26099.608505872806, 29234.0, 29538.523073846653, 32168.19579510531, 32314.515282271255, 21653.78346216518, 24102.75897241005, 24783.13259035925, 24896.253112406655, 25040.874795625907], "value": 17448.554415430623, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.85173337598457, 421.84180932150446, 433.8655016944745, 436.2061048891019, 452.39153338759365, 20885.582288354224, 21829.890850545744, 22675.999999999978, 22723.431914202152, 24176.274711758633, 24916.25251242461, 25487.490250194984, 25489.87255063727, 25497.872510637422, 25508.36229094269, 5963.344032156459, 6856.691448884787, 7015.368616824485, 7016.684249208773, 7030.191527974297, 5423.484768946943, 6915.55048921821, 6970.198427180863, 6995.685194166249, 7033.613151276678, 6905.620190889499, 6935.687894044755, 6966.721331146748, 6976.476764242702, 6989.370956613904, 5735.369109397963, 6918.861622767542, 6969.965150174267, 6985.126859142607, 6992.091028166356, 6767.864642707118, 6788.049673045777, 6826.965865170692, 6828.453723702092, 6866.313368663144, 22559.097636094535, 30088.19470831735, 30646.84676576603, 30841.8457907709, 31333.0, 2135.4234356723755, 2322.512272422793, 2336.906523739048, 2340.3797993531557, 2347.859128452298, 18345.908270458694, 18898.81101188998, 18920.148593313334, 18933.0, 18977.810221897744, 2288.656701494789, 2324.5350929814103, 2390.8445951013127, 2392.9521409571885, 2394.0, 169609.0, 177187.11406443015, 190243.0, 192531.0746892527, 199744.0, 24532.754672453048, 25262.621060684105, 25744.871275643876, 26510.867445662647, 26534.46931061368, 43878.0, 44126.0, 44140.0, 44149.55850441455, 44203.778981104886, 11133.944330278458, 11253.268537545033, 11299.49152288149, 11384.943075284571, 11414.657560273205, 9047.909520904706, 9070.591823367833, 9077.68228112013, 9166.49584272858, 9171.312151588658, 9963.651272205438, 12228.510859565695, 12404.75190496185, 12457.501699931901, 12480.563180288646, 3433.4334834752485, 3966.6231707988272, 4017.718759686794, 4244.702870799015, 4741.051789642016, 853.543354305456, 939.3612343606413, 945.3052006775015, 953.5184731710442, 1009.4296722351847, 21214.575708485743, 21220.0, 22126.668099978513, 22301.107955681917, 22728.0, 952.9523523823781, 957.6696039866374, 970.4856426094041, 998.5756053677159, 1014.3457469932, 10835.295705779099, 10931.726706832234, 11225.158113141548, 11273.943630281796, 11371.260868043551, 578578.0, 582417.0, 582981.0, 583799.7025186362, 589281.0, 161017.19491402467, 163173.18413407856, 163500.64993500555, 164428.0, 165117.0, 1141.0943723980806, 1158.2645020412099, 1237.3565745812127, 1245.9626211213858, 1252.1735654468007, 22123.0, 23051.538969220856, 23520.7063611503, 23626.81865906732, 23639.527209456057, 70324.64837675779, 71452.0, 72810.0, 73065.0, 73541.0, 98532.0, 98970.0, 99021.51467728135, 99107.50446247723, 99435.5084673716, 9451.905480945103, 9549.90450095518, 10283.12593429567, 10293.279470436997, 10371.533281002223, 17307.826921731124, 17911.910440447715, 18097.819021809613, 18265.634687305912, 18771.624567508847, 15280.541583752505, 15319.923400382926, 15432.382704692136, 15442.84557154459, 15566.9221653891, 13316.46734130624, 13464.192148471124, 13630.591082267549, 13956.581302560939, 14133.93995450366, 14612.123272603678, 15689.450869219523, 15691.843081569039, 15877.52367428979, 15892.60268493297, 9883.50582470888, 10216.489175541343, 10428.895711042793, 10489.47552622381, 10500.26498145153, 10668.466576671291, 14698.926505367404, 14869.033512821632, 15134.470293539673, 15170.089794612359], "value": 12599.80518545903, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1282.3780466473759, 1457.2058228265594, 1462.7001464699738, 1465.4724299252268, 1471.9116852988818, 9708.41749495029, 11729.178957472981, 12215.20601160924, 12268.447919843606, 12298.385080745971, 9906.801863962717, 12143.878561214386, 12361.690957726061, 12379.56671516497, 12381.56664516742, 2524.545581795276, 2561.2956436979794, 2564.807639427041, 2565.6536367590325, 2566.2942690760005, 2384.988075059622, 2403.3270684208364, 2413.9275821725287, 2419.237940048879, 2421.2010036687852, 2200.460887082659, 2353.9176128835493, 2477.2568229531157, 2493.551160791057, 2499.512595043967, 2036.328011756117, 2138.850280480367, 2464.778169964703, 2472.2088931542003, 2475.665785119004, 2434.4279094412977, 2448.338948483917, 2453.4479742058074, 2458.3362492127203, 2544.3384719972755, 11896.22674526153, 11908.0, 11942.641720748392, 11966.282023078644, 12038.759224815542, 6425.646589437579, 6639.402453779183, 6687.76592819249, 6689.364510371507, 6691.130153080117, 3622.3518299174075, 4120.361343991686, 4292.570742925714, 4322.848700295474, 4398.142362239365, 1228.6621179175709, 1275.375066217556, 1278.9586047364148, 1303.7718399280088, 1304.954326598574, 15410.460633877872, 15900.761488577566, 16659.583510412216, 16782.83217167825, 16866.494005179866, 5554.6944918029085, 5619.213310136584, 5770.913436298459, 5787.450192231731, 5800.854978625568, 12404.875951240552, 12432.875671243171, 12465.439055242536, 12475.126741128033, 12541.435635396429, 6353.0, 6512.316206798313, 6527.477801775846, 6534.640594767334, 6553.311902250195, 5989.940100598938, 6164.321924588292, 6175.660338681326, 6179.536534759911, 6188.164597779242, 4415.271480205794, 4468.128714900563, 4531.750753708578, 4559.156556037144, 4571.040081582838, 1273.2296960339047, 1294.5727909789746, 1298.805179223124, 1308.1758492149884, 1310.4430616987743, 824.3240542754884, 832.5587438657521, 864.7924498120406, 866.2117473099576, 896.7713233125511, 6490.448311893542, 6708.3291670833505, 6825.453963682893, 6919.827004324932, 6954.860902781916, 569.7122952908754, 585.4584509328854, 608.6591508755112, 628.4595248086733, 631.1132858334048, 3871.090293780959, 4274.871753847389, 4307.741535507879, 4553.134904368159, 4964.453910069889, 14609.561713148623, 15358.308876100602, 15370.462033828762, 15376.461823836113, 15377.846221537866, 14281.214533200773, 14835.406583736529, 14969.775453368207, 14991.550253492413, 15117.470888518848, 8379.748607541784, 8581.356398270154, 8581.399302048796, 8588.8711669325, 8598.613062412207, 6091.756329746938, 6972.477064220305, 7336.96331518339, 7365.1898291189, 7497.250274972459, 13235.933820331222, 13566.59300220995, 13661.38523766413, 13679.521216757366, 13682.794758078835, 12733.61799146027, 13108.29592153014, 13810.861891380957, 14134.0, 14233.572992810232, 8998.460092394478, 10451.738706532398, 10523.263371563917, 10567.682969510926, 10778.622748203776, 10717.624883129052, 10823.621173258898, 10831.837522437012, 10832.620858269922, 10855.945720271347, 9108.544572771467, 9182.816343672956, 9244.491552964651, 9432.151106400457, 9460.479673618018, 8903.465792052497, 8973.237274831712, 9003.909960900308, 9078.046805085634, 9159.587818548314, 8188.83622327562, 8252.876206856781, 8303.460275082341, 8305.792355191168, 8367.832643347221, 5978.641281522949, 6083.27000759894, 6471.902921456088, 6530.379613936669, 6571.34286571339, 6183.134361189347, 6445.12990746262, 6603.636799976048, 6605.174353205849, 6627.23786764509], "value": 5400.80088164061, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.1347538252006, 402.40645048553387, 403.6447925825274, 410.40081481037686, 413.7104027180973, 6225.750969961207, 6383.170187875576, 6433.8391540211405, 6480.189976252968, 6523.0, 5378.757955891984, 5631.831045068655, 6132.933533233383, 6146.262448506185, 6250.562370654752, 4586.706646676662, 4590.495045544987, 4594.839180628679, 4594.840425000245, 4607.331936869144, 4121.867380790591, 4285.935710964338, 4624.14453326134, 4626.884327891796, 4632.490426053131, 4572.199865023625, 4576.107659006495, 4588.449386073659, 4607.447106347243, 4614.584687378136, 3410.7100896423835, 4582.6104781093645, 4583.289590113538, 4591.747453890035, 4673.859784206479, 3266.901992940192, 3879.941800872989, 3931.803409829497, 3952.407138929155, 3980.3233450313514, 6187.0, 6210.223722034746, 6221.751129954796, 6221.9688901555655, 6229.878621848065, 4807.471178170434, 5467.890642187173, 5485.451454854522, 5489.560835133219, 5521.723913804334, 5627.634203776742, 6739.584687215642, 6782.321767823228, 6796.35434633709, 6810.897836532456, 3949.7037722170667, 4504.324351347292, 4510.571495707903, 4511.751853648048, 4523.163214805271, 5360.91958620621, 6639.86720265592, 6973.442124630017, 7096.522703875115, 7148.892766608505, 3755.6244375562223, 4483.529229430928, 4555.658325625525, 4562.771861406916, 4567.931481027787, 4046.676265898721, 4083.612056854594, 4628.467726211529, 4649.628029757611, 4652.813887444464, 5294.894102117936, 5392.64947778401, 5411.161270003156, 5436.3476382834315, 5437.972810135924, 6351.824912391223, 6628.23775265841, 6767.695453704595, 6815.625140617313, 6832.350926661959, 1585.4133970431014, 1591.8856800239878, 1603.9839601603835, 1605.6788642271592, 1614.3211060496951, 998.7166491059099, 1021.7891798219134, 1066.3814987307344, 1066.4081434803759, 1073.3774410841693, 6405.8718825624155, 6406.839829004219, 6410.903836442457, 6428.067930150102, 6474.449671777952, 7034.718611255493, 7040.92959070413, 7054.823629409306, 7077.124561991077, 7078.186008608973, 6344.397282258178, 6366.236051673829, 6383.71273292703, 6399.936000639934, 6438.291787903327, 3206.3426997465463, 3598.5501812273465, 3707.7589956652732, 3712.554493460802, 3713.405855063231, 7220.4584656151, 7233.204347521769, 7252.166000909963, 7270.63646817657, 7295.927040729631, 6068.757249709962, 7221.783346499614, 7260.818479537948, 7264.891026634605, 7293.70825166998, 6102.145699602059, 6597.637129957707, 7201.963990180016, 7253.8549229015125, 7297.306755858292, 6218.782342617987, 6261.592996455216, 7037.542559733805, 7069.858602827811, 7093.269091399745, 5503.9174412383045, 6816.2842901496615, 6835.3253452904355, 6954.617496037767, 6982.860342793218, 6089.330173680803, 6479.708413121515, 6647.933520664925, 7247.601381924045, 7291.817704557428, 3888.9471764664604, 4550.863474095782, 4558.111168322145, 4599.264117741145, 4625.329327247463, 5800.622959507618, 6620.801375958729, 7117.75087871922, 7142.678579464041, 7159.0, 6416.973284274493, 6730.697118629576, 6844.178698556205, 6850.903855383115, 6894.655267236518, 5885.911711324419, 6031.627070373944, 6606.240282367589, 6808.897866532105, 6843.828904277433, 5611.551075913999, 6566.179227596551, 6652.667366631746, 6659.134312539532, 6689.09697190873, 6600.735970561316, 6824.590524568542, 6834.51439765733, 6869.828254293483, 6875.003124546813, 6589.374009469093, 6645.867082658417, 6648.700808463534, 6650.933490665031, 6663.003845961817], "value": 4907.140385911582, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.6698202317945, 800.6517165033212, 808.4502538273979, 824.0194168938962, 838.6477679374663, 15113.395464181433, 17151.57121071972, 17163.0, 17203.397881074165, 17235.39676111335, 15364.07815531069, 15721.0, 15868.04791712498, 16217.432389866357, 16231.188440577984, 973.5327043019355, 988.6440881282732, 990.267202270322, 990.5493000684708, 995.2286977592388, 977.7555611097225, 988.7231575158967, 988.9307748457575, 989.8267803134459, 992.1071036067561, 1053.1995683280686, 1058.915286777056, 1064.239069065618, 1064.8243039898407, 1065.8880817514166, 1044.9947750261238, 1059.2267644619433, 1063.3407287481734, 1064.7338165458636, 1065.323519565074, 770.2682451670879, 778.5484419036945, 781.3827076609472, 783.4946459533626, 785.8899754034439, 17411.129443527774, 17780.199891004937, 17887.642247154985, 18054.548636284067, 18062.91622502641, 17402.216900239393, 17487.737683934753, 17618.647627047387, 17798.0, 17867.821321786745, 12022.51909923602, 14854.628634284229, 14942.402303907831, 14969.101853888802, 14976.17631030293, 3555.253396786678, 3580.1228698968657, 3593.802340871251, 3599.8200089995657, 3604.387254166798, 127555.0, 128672.0, 129830.70169298376, 130394.0, 130802.34598826944, 11024.944875275572, 11554.0, 13449.663758406117, 13702.0, 13831.377588008563, 26150.86924565365, 26229.475410491683, 26230.21309360722, 26420.943162273295, 26438.867805660848, 7863.921360786433, 7900.604969751489, 7917.3270272026375, 7940.761777146695, 7962.402819788539, 3915.1190982028816, 3946.3685810270217, 3956.4065390191126, 3997.040710229524, 4003.139325045129, 4411.183930972717, 4421.801018954155, 4455.6658250631335, 4460.085682435093, 4466.843660471933, 857.322715055112, 895.1227796759213, 905.2124651553191, 905.4295793649958, 906.7506435730227, 647.9481641468766, 649.7206201333378, 652.3737212276174, 678.4267294136447, 684.9383455643732, 11022.50398732059, 12338.81491777624, 12686.49254029847, 13223.73552528944, 13368.46526138963, 590.1105609441798, 636.1983900285674, 657.8289644692414, 663.847315117516, 666.2970566052763, 4870.68491507291, 4898.951010489921, 4952.62855285855, 5074.594032477393, 5722.713864306768, 126560.0, 128548.0, 133452.0, 136869.0, 137063.0, 103375.0, 113578.0, 115619.0, 116682.0, 116967.0, 78420.0, 80552.0, 88489.0, 99215.0, 101348.49325753324, 10795.352278863293, 11188.545678901315, 12006.459709312927, 12465.875341246472, 12783.872161278268, 24335.634965475885, 40758.79620601878, 42101.578984209766, 42721.0, 46374.76812615915, 59481.0, 60302.396976029675, 60597.0, 61939.6903015482, 75778.62110689412, 14199.929000354932, 17115.0, 17339.306427742747, 17437.389691361248, 17580.38468653591, 15308.617284567976, 18849.151788169293, 18970.723513178506, 19132.426027219204, 19186.616267674846, 14616.70766584641, 17721.91139044348, 17862.374816881867, 17979.0, 18017.72973405426, 14449.927750361601, 16856.57858553546, 17506.649867002336, 17548.73676894822, 18003.729944051112, 15861.841381586037, 16229.295923927817, 17643.117844107488, 17713.911430442764, 17804.0, 11479.540818367172, 11485.310881347146, 12018.75962480763, 12194.0, 12470.68823279389, 10912.345259284468, 11886.34625095628, 11996.560412750527, 12557.874421256036, 13633.454661813417], "value": 8568.476937194635, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1525.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 115.0, 113.0, 112.0, 113.0, 110.0, 114.0, 115.0, 115.0, 114.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 105.0, 130.0, 148.0, 111.0, 96.0, 115.0, 141.0, 160.0, 111.0], "value": 125.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [222.0, 278.0, 205.0, 212.0, 220.0, 277.0, 228.0, 199.0, 192.0, 200.0], "value": 223.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 56.0, 59.0, 86.0, 60.0, 92.0, 83.0, 88.0, 76.0, 56.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 67.0, 81.0, 71.0, 67.0, 67.0, 90.0, 92.0, 103.0, 104.0], "value": 82.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 88.0, 88.0, 89.0, 88.0, 89.0, 92.0, 110.0, 98.0], "value": 92.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 95.0, 94.0, 97.0, 103.0, 95.0, 95.0, 95.0, 95.0], "value": 96.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 146.0, 141.0, 94.0, 92.0, 92.0, 90.0, 91.0, 91.0, 91.0], "value": 105.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 49.0, 51.0, 51.0, 50.0, 51.0, 51.0, 48.0, 50.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 70.0, 70.0, 58.0, 62.0, 55.0, 68.0, 82.0, 83.0, 83.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 90.0, 88.0, 84.0, 89.0, 90.0, 95.0, 137.0, 116.0], "value": 96.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 154.0, 126.0, 135.0, 136.0, 144.0, 143.0, 137.0, 148.0, 138.0], "value": 141.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 183.0, 185.0, 183.0, 182.0, 184.0, 187.0, 181.0, 191.0], "value": 184.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 68.0, 91.0, 89.0, 82.0, 68.0, 68.0, 71.0], "value": 74.3, "unit": "score"}]}]} 22:33:58 INFO - Return code: 0 22:33:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:33:58 INFO - # TBPL SUCCESS # 22:33:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:33:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:33:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:33:58 INFO - 2017-06-02 22:33:58.629 screenresolution[1676:12429] starting screenresolution argv=screenresolution get 22:33:58 INFO - 2017-06-02 22:33:58.632 screenresolution[1676:12429] Display 0: 1600x1200x32@60 22:33:58 INFO - 2017-06-02 22:33:58.638 screenresolution[1677:12432] starting screenresolution argv=screenresolution list 22:33:58 INFO - Available Modes on Display 0 22:33:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:33:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:33:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:33:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:33:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:33:58 INFO - Intel Iris: 22:33:58 INFO - Chipset Model: Intel Iris 22:33:58 INFO - Type: GPU 22:33:58 INFO - Bus: Built-In 22:33:58 INFO - VRAM (Dynamic, Max): 1536 MB 22:33:58 INFO - Vendor: Intel (0x8086) 22:33:58 INFO - Device ID: 0x0a2e 22:33:58 INFO - Revision ID: 0x0009 22:33:58 INFO - Displays: 22:33:58 INFO - Display: 22:33:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:33:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:33:58 INFO - Display Serial Number: 1600x1200 60 22:33:58 INFO - Main Display: Yes 22:33:58 INFO - Mirror: Off 22:33:58 INFO - Online: Yes 22:33:58 INFO - Rotation: Supported 22:33:58 INFO - Return code: 0 22:33:58 INFO - Running post-action listener: _resource_record_post_action 22:33:58 INFO - [mozharness: 2017-06-03 05:33:58.873993Z] Finished run-tests step (success) 22:33:58 INFO - Running post-run listener: _resource_record_post_run 22:33:58 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 36408832; Write bytes: 570354688; Read time: 367; Write time: 1792 22:33:58 INFO - TinderboxPrint: CPU usage
53.2% 22:33:58 INFO - TinderboxPrint: I/O read bytes / time
36,408,832 / 367 22:33:58 INFO - TinderboxPrint: I/O write bytes / time
570,354,688 / 1,792 22:33:58 INFO - TinderboxPrint: CPU idle
2,351.6 (46.8%) 22:33:58 INFO - TinderboxPrint: CPU system
1,013.1 (20.2%) 22:33:58 INFO - TinderboxPrint: CPU user
1,661.1 (33.1%) 22:33:58 INFO - TinderboxPrint: Swap in / out
278,962,176 / 0 22:33:58 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 239438336; Write bytes: 233707008; Read time: 17004; Write time: 446 22:33:58 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 22:33:58 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 34287104; Write bytes: 320382464; Read time: 338; Write time: 1306 22:33:59 INFO - Running post-run listener: _upload_blobber_files 22:33:59 INFO - Blob upload gear active. 22:33:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:33:59 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:33:59 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:33:59 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:33:59 INFO - (blobuploader) - INFO - Open directory for files ... 22:33:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:33:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:33:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:34:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:34:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:34:00 INFO - (blobuploader) - INFO - Done attempting. 22:34:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:34:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:34:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:34:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:34:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:34:01 INFO - (blobuploader) - INFO - Done attempting. 22:34:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:34:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:34:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:34:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:34:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:34:02 INFO - (blobuploader) - INFO - Done attempting. 22:34:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:34:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:34:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:34:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:34:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:34:04 INFO - (blobuploader) - INFO - Done attempting. 22:34:04 INFO - (blobuploader) - INFO - Iteration through files over. 22:34:04 INFO - Return code: 0 22:34:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:34:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:34:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/afd3e6f39d24292b7760a7e8e3d23e490abd18db007350081e15bda419025ce8b278cfed7d8a84f153426d034127b216a5bbc88610c40cedccd734a051332586", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/cee80db778af26ddb249b56586b5fa896ade00c64ef55202ca8954b86bb39933b837d784340d06d51503d4eedde43300c109a574aad6beabbcbaa43b572115e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0f1181bb2ad9d3d05e2a03007781772b858cd133f838724c31419f02e27e93bf7ba8579a2804a3089abb1918abe5d1d3aa32af041d5e2e4a15933ef8cc8a05b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/8d029e1050c6531fd23004c01729954cef202c6162de3250db07d27912dacd11c14716a88637e1a696afd16f6a2d8424be934f112d9b1914f9145ba85a583b18"} 22:34:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:34:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:34:04 INFO - Contents: 22:34:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/afd3e6f39d24292b7760a7e8e3d23e490abd18db007350081e15bda419025ce8b278cfed7d8a84f153426d034127b216a5bbc88610c40cedccd734a051332586", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/cee80db778af26ddb249b56586b5fa896ade00c64ef55202ca8954b86bb39933b837d784340d06d51503d4eedde43300c109a574aad6beabbcbaa43b572115e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0f1181bb2ad9d3d05e2a03007781772b858cd133f838724c31419f02e27e93bf7ba8579a2804a3089abb1918abe5d1d3aa32af041d5e2e4a15933ef8cc8a05b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/8d029e1050c6531fd23004c01729954cef202c6162de3250db07d27912dacd11c14716a88637e1a696afd16f6a2d8424be934f112d9b1914f9145ba85a583b18"} 22:34:04 INFO - Running post-run listener: copy_logs_to_upload_dir 22:34:04 INFO - Copying logs to upload dir... 22:34:04 INFO - mkdir: /builds/slave/test/build/upload/logs 22:34:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.394630 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-02 22:34:04.881620) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 22:34:04.884914) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w7JPXrf4ml/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/afd3e6f39d24292b7760a7e8e3d23e490abd18db007350081e15bda419025ce8b278cfed7d8a84f153426d034127b216a5bbc88610c40cedccd734a051332586", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/cee80db778af26ddb249b56586b5fa896ade00c64ef55202ca8954b86bb39933b837d784340d06d51503d4eedde43300c109a574aad6beabbcbaa43b572115e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0f1181bb2ad9d3d05e2a03007781772b858cd133f838724c31419f02e27e93bf7ba8579a2804a3089abb1918abe5d1d3aa32af041d5e2e4a15933ef8cc8a05b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/8d029e1050c6531fd23004c01729954cef202c6162de3250db07d27912dacd11c14716a88637e1a696afd16f6a2d8424be934f112d9b1914f9145ba85a583b18"} build_url:https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011254 build_url: 'https://queue.taskcluster.net/v1/task/C7HInBe8Sy-Cg6YRDzZ8GA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/afd3e6f39d24292b7760a7e8e3d23e490abd18db007350081e15bda419025ce8b278cfed7d8a84f153426d034127b216a5bbc88610c40cedccd734a051332586", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/cee80db778af26ddb249b56586b5fa896ade00c64ef55202ca8954b86bb39933b837d784340d06d51503d4eedde43300c109a574aad6beabbcbaa43b572115e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0f1181bb2ad9d3d05e2a03007781772b858cd133f838724c31419f02e27e93bf7ba8579a2804a3089abb1918abe5d1d3aa32af041d5e2e4a15933ef8cc8a05b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/8d029e1050c6531fd23004c01729954cef202c6162de3250db07d27912dacd11c14716a88637e1a696afd16f6a2d8424be934f112d9b1914f9145ba85a583b18"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 22:34:04.919260) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 22:34:04.919776) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w7JPXrf4ml/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.VUJdjuA6bq/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.004714 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 22:34:04.968577) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 22:34:04.968885) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 22:34:04.969394) ========= ========= Total master_lag: 0.35 =========