builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0191 starttime: 1497141668.21 results: success (0) revision: 506992e7f7ea38453558a27a11fbf3dc9400eaf7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-10 17:41:08.214024) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-10 17:41:08.214487) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-10 17:41:08.214787) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-10 17:41:08.250091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 17:41:08.250600) ========= 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.V2Ray8zSWD/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.YltVRXN12H/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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-10 17:41:08-- 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% 55.0M=0s 2017-06-10 17:41:08 (55.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127202 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 17:41:08.401950) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 17:41:08.402281) ========= 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.V2Ray8zSWD/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.YltVRXN12H/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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.036002 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 17:41:08.458861) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-10 17:41:08.459202) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 506992e7f7ea38453558a27a11fbf3dc9400eaf7 --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 506992e7f7ea38453558a27a11fbf3dc9400eaf7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.V2Ray8zSWD/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.YltVRXN12H/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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-10 17:41:08,500 truncating revision to first 12 chars 2017-06-10 17:41:08,500 Setting DEBUG logging. 2017-06-10 17:41:08,500 attempt 1/10 2017-06-10 17:41:08,500 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/506992e7f7ea?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-10 17:41:09,323 unpacking tar archive at: cedar-506992e7f7ea/testing/mozharness/ program finished with exit code 0 elapsedTime=1.092545 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-10 17:41:09.576455) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-10 17:41:09.577026) ========= script_repo_revision: 506992e7f7ea38453558a27a11fbf3dc9400eaf7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-10 17:41:09.577704) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-10 17:41:09.578190) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-10 17:41:09.598911) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-10 17:41:09.599560) ========= /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.V2Ray8zSWD/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.YltVRXN12H/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:41:09 INFO - MultiFileLogger online at 20170610 17:41:09 in /builds/slave/test 17:41:09 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 17:41:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:41:09 INFO - {'append_to_log': False, 17:41:09 INFO - 'base_work_dir': '/builds/slave/test', 17:41:09 INFO - 'blob_upload_branch': 'Cedar', 17:41:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:41:09 INFO - 'branch': 'Cedar', 17:41:09 INFO - 'buildbot_json_path': 'buildprops.json', 17:41:09 INFO - 'config_files': ('talos/mac_config.py',), 17:41:09 INFO - 'default_actions': ('clobber', 17:41:09 INFO - 'read-buildbot-config', 17:41:09 INFO - 'download-and-extract', 17:41:09 INFO - 'populate-webroot', 17:41:09 INFO - 'create-virtualenv', 17:41:09 INFO - 'install', 17:41:09 INFO - 'setup-mitmproxy', 17:41:09 INFO - 'run-tests'), 17:41:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:41:09 INFO - 'download_minidump_stackwalk': True, 17:41:09 INFO - 'download_symbols': 'ondemand', 17:41:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:41:09 INFO - 'tooltool.py': '/tools/tooltool.py', 17:41:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:41:09 INFO - '/tools/misc-python/virtualenv.py')}, 17:41:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:41:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:41:09 INFO - 'gecko_profile': False, 17:41:09 INFO - 'gecko_profile_interval': 0, 17:41:09 INFO - 'installer_path': 'installer.exe', 17:41:09 INFO - 'log_level': 'info', 17:41:09 INFO - 'log_name': 'talos', 17:41:09 INFO - 'log_to_console': True, 17:41:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:41:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:41:09 INFO - 'opt_config_files': (), 17:41:09 INFO - 'pip_index': False, 17:41:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:41:09 INFO - 'cmd': ('bash', 17:41:09 INFO - '-c', 17:41:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:41:09 INFO - 'enabled': True, 17:41:09 INFO - 'halt_on_failure': False, 17:41:09 INFO - 'name': 'check_screen_resolution'},), 17:41:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:41:09 INFO - 'cmd': ('bash', 17:41:09 INFO - '-c', 17:41:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:41:09 INFO - 'enabled': True, 17:41:09 INFO - 'halt_on_failure': False, 17:41:09 INFO - 'name': 'check_screen_resolution'},), 17:41:09 INFO - 'run_cmd_checks_enabled': True, 17:41:09 INFO - 'suite': 'dromaeojs-e10s', 17:41:09 INFO - 'system_bits': '32', 17:41:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:41:09 INFO - 'title': 't-yosemite-r7-0191', 17:41:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:41:09 INFO - 'use_talos_json': True, 17:41:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:41:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:41:09 INFO - 'work_dir': 'build'} 17:41:09 INFO - [mozharness: 2017-06-11 00:41:09.757550Z] Running clobber step. 17:41:09 INFO - Running pre-action listener: _resource_record_pre_action 17:41:09 INFO - Running main action method: clobber 17:41:09 INFO - rmtree: /builds/slave/test/build 17:41:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:41:12 INFO - Running post-action listener: _resource_record_post_action 17:41:12 INFO - [mozharness: 2017-06-11 00:41:12.476081Z] Finished clobber step (success) 17:41:12 INFO - [mozharness: 2017-06-11 00:41:12.476287Z] Running read-buildbot-config step. 17:41:12 INFO - Running pre-action listener: _resource_record_pre_action 17:41:12 INFO - Running main action method: read_buildbot_config 17:41:12 INFO - Using buildbot properties: 17:41:12 INFO - { 17:41:12 INFO - "project": "", 17:41:12 INFO - "product": "firefox", 17:41:12 INFO - "who": "mconley@mozilla.com", 17:41:12 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 17:41:12 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 17:41:12 INFO - "repository": "https://hg.mozilla.org/projects/cedar/", 17:41:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 17:41:12 INFO - "stage_platform": "macosx64", 17:41:12 INFO - "basedir": "/builds/slave/test", 17:41:12 INFO - "buildnumber": 1, 17:41:12 INFO - "platform": "yosemite_r7", 17:41:12 INFO - "parent_task_id": "CUrVaMrjTVuA6YwGxUsolw", 17:41:12 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:41:12 INFO - "slavebuilddir": "test", 17:41:12 INFO - "taskId": "MFvyr4q5SkOFLxOiC_9gIg", 17:41:12 INFO - "branch": "cedar", 17:41:12 INFO - "script_repo_revision": "default", 17:41:12 INFO - "revision": "506992e7f7ea38453558a27a11fbf3dc9400eaf7", 17:41:12 INFO - "slavename": "t-yosemite-r7-0191", 17:41:12 INFO - "repo_path": "projects/cedar" 17:41:12 INFO - } 17:41:12 INFO - Finding installer, test and symbols from parent task. 17:41:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MFvyr4q5SkOFLxOiC_9gIg'}, attempt #1 17:41:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MFvyr4q5SkOFLxOiC_9gIg'}, attempt #1 17:41:13 INFO - Task dependencies: eYyIe1rlQ-WCDQ5HF7Tymg CUrVaMrjTVuA6YwGxUsolw 17:41:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw'}, attempt #1 17:41:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:41:13 INFO - Set test_url: https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/test_packages.json 17:41:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/target.crashreporter-symbols.zip 17:41:13 INFO - Running post-action listener: _resource_record_post_action 17:41:13 INFO - [mozharness: 2017-06-11 00:41:13.849867Z] Finished read-buildbot-config step (success) 17:41:13 INFO - [mozharness: 2017-06-11 00:41:13.849950Z] Running download-and-extract step. 17:41:13 INFO - Running pre-action listener: _resource_record_pre_action 17:41:13 INFO - Running main action method: download_and_extract 17:41:13 INFO - mkdir: /builds/slave/test/build/tests 17:41:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:41:13 INFO - trying https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:41:13 INFO - Downloading https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/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 17:41:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/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 17:41:14 INFO - Downloaded 1328 bytes. 17:41:14 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:41:14 INFO - Using the following test package requirements: 17:41:14 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:41:14 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:41:14 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:41:14 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:41:14 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:41:14 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:41:14 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:41:14 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:41:14 INFO - u'jsshell-mac.zip'], 17:41:14 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:41:14 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:41:14 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:41:14 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:41:14 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:41:14 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:41:14 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:41:14 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:41:14 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 17:41:14 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:41:14 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:41:14 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:41:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:41:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:41:14 INFO - Fetch https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:41:16 INFO - Content-Length response header: 59541153 17:41:16 INFO - Bytes received: 59541153 17:41:34 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 17:41:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:41:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:41:34 INFO - Fetch https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:41:36 INFO - Content-Length response header: 59541153 17:41:36 INFO - Bytes received: 59541153 17:41:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:41:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:41:53 INFO - Fetch https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:41:54 INFO - Content-Length response header: 14450010 17:41:54 INFO - Bytes received: 14450010 17:41: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')]} 17:41:55 INFO - trying https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:41:55 INFO - Downloading https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:41:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/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 17:41:57 INFO - Downloaded 69249600 bytes. 17:41:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:41:57 INFO - mkdir: /builds/slave/test/properties 17:41:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:41:57 INFO - Writing to file /builds/slave/test/properties/build_url 17:41:57 INFO - Contents: 17:41:57 INFO - build_url:https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:41:57 INFO - Running post-action listener: _resource_record_post_action 17:41:57 INFO - Running post-action listener: set_extra_try_arguments 17:41:57 INFO - [mozharness: 2017-06-11 00:41:57.237127Z] Finished download-and-extract step (success) 17:41:57 INFO - [mozharness: 2017-06-11 00:41:57.237273Z] Running populate-webroot step. 17:41:57 INFO - Running pre-action listener: _resource_record_pre_action 17:41:57 INFO - Running main action method: populate_webroot 17:41:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:41:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:41:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:41:57 INFO - '--tppagecycles', 17:41:57 INFO - '7'], 17:41:57 INFO - 'tests': ['tsvgm']}}, 17:41:57 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:41:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:41:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:41:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:41:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:41:57 INFO - 'talos_options': ['--disable-e10s'], 17:41:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:41:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:41:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:41:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:41:57 INFO - 'talos_options': ['--disable-e10s'], 17:41:57 INFO - 'tests': ['damp', 'tps']}, 17:41:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:41:57 INFO - 'tests': ['damp', 'tps']}, 17:41:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:41:57 INFO - 'tests': ['dromaeo_dom']}, 17:41:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:41:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:41:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:41:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:41:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:41:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:41:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:41:57 INFO - 'tests': ['a11yr', 17:41:57 INFO - 'ts_paint', 17:41:57 INFO - 'tpaint', 17:41:57 INFO - 'sessionrestore', 17:41:57 INFO - 'sessionrestore_no_auto_restore', 17:41:57 INFO - 'tabpaint']}, 17:41:57 INFO - 'other-e10s': {'tests': ['a11yr', 17:41:57 INFO - 'ts_paint', 17:41:57 INFO - 'tpaint', 17:41:57 INFO - 'sessionrestore', 17:41:57 INFO - 'sessionrestore_no_auto_restore', 17:41:57 INFO - 'tabpaint']}, 17:41:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:41:57 INFO - 'ts_paint', 17:41:57 INFO - 'tpaint', 17:41:57 INFO - 'sessionrestore', 17:41:57 INFO - 'sessionrestore_no_auto_restore', 17:41:57 INFO - 'tabpaint']}, 17:41:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:41:57 INFO - 'ts_paint', 17:41:57 INFO - 'tpaint', 17:41:57 INFO - 'sessionrestore', 17:41:57 INFO - 'sessionrestore_no_auto_restore', 17:41:57 INFO - 'tabpaint']}, 17:41:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:41:57 INFO - 'tests': ['a11yr', 17:41:57 INFO - 'ts_paint', 17:41:57 INFO - 'tpaint', 17:41:57 INFO - 'sessionrestore', 17:41:57 INFO - 'sessionrestore_no_auto_restore', 17:41:57 INFO - 'tabpaint']}, 17:41:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:41:57 INFO - 'tests': ['a11yr', 17:41:57 INFO - 'ts_paint', 17:41:57 INFO - 'tpaint', 17:41:57 INFO - 'sessionrestore', 17:41:57 INFO - 'sessionrestore_no_auto_restore', 17:41:57 INFO - 'tabpaint']}, 17:41:57 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:41:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:41:57 INFO - 'bloom_basic_ref']}, 17:41:57 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:41:57 INFO - 'talos_options': ['--mitmproxy', 17:41:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:41:57 INFO - '--firstNonBlankPaint'], 17:41:57 INFO - 'tests': ['quantum_pageload_google', 17:41:57 INFO - 'quantum_pageload_youtube', 17:41:57 INFO - 'quantum_pageload_amazon', 17:41:57 INFO - 'quantum_pageload_facebook']}, 17:41:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:41:57 INFO - 'tests': ['tsvgx', 17:41:57 INFO - 'tsvgr_opacity', 17:41:57 INFO - 'tart', 17:41:57 INFO - 'tscrollx', 17:41:57 INFO - 'cart', 17:41:57 INFO - 'tsvg_static']}, 17:41:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:41:57 INFO - 'tsvgr_opacity', 17:41:57 INFO - 'tart', 17:41:57 INFO - 'tscrollx', 17:41:57 INFO - 'cart', 17:41:57 INFO - 'tsvg_static']}, 17:41:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:41:57 INFO - 'talos_options': ['--disable-e10s'], 17:41:57 INFO - 'tests': ['tp5o']}, 17:41:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:41:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:41:57 INFO - 'talos_options': ['--disable-e10s', 17:41:57 INFO - '--xperf_path', 17:41:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:41:57 INFO - 'tests': ['tp5n']}, 17:41:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:41:57 INFO - 'talos_options': ['--xperf_path', 17:41:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:41:57 INFO - 'tests': ['tp5n']}}, 17:41:57 INFO - 'talos.zip': {'path': '', 17:41:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:41:57 INFO - Running post-action listener: _resource_record_post_action 17:41:57 INFO - [mozharness: 2017-06-11 00:41:57.259964Z] Finished populate-webroot step (success) 17:41:57 INFO - [mozharness: 2017-06-11 00:41:57.260082Z] Running create-virtualenv step. 17:41:57 INFO - Running pre-action listener: _resource_record_pre_action 17:41:57 INFO - Running main action method: create_virtualenv 17:41:57 INFO - Creating virtualenv /builds/slave/test/build/venv 17:41:57 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 17:41:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:41:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:41:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:41:57 INFO - Using real prefix '/tools/python27' 17:41:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:41:58 INFO - Installing distribute.............................................................................................................................................................................................done. 17:42:00 INFO - Installing pip.................done. 17:42:00 INFO - Return code: 0 17:42:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:42:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:42:01 INFO - Reading from file tmpfile_stdout 17:42:01 INFO - Output received: 17:42:01 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) 17:42:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:42: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')]} 17:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42: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')]} 17:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c56c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7e04b4280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049acd30>, '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 0x104976cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10499dbe8>, '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.V2Ray8zSWD/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.YltVRXN12H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:42:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:42:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:42:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V2Ray8zSWD/Render', 17:42:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:42:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:42:01 INFO - 'HOME': '/Users/cltbld', 17:42:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:42:01 INFO - 'LOGNAME': 'cltbld', 17:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:01 INFO - 'MOZ_NO_REMOTE': '1', 17:42:01 INFO - 'NO_EM_RESTART': '1', 17:42:01 INFO - 'PAGER': '/bin/cat', 17:42:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:01 INFO - 'PWD': '/builds/slave/test', 17:42:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:42:01 INFO - 'SHELL': '/bin/bash', 17:42:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YltVRXN12H/Listeners', 17:42:01 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:42:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:42:01 INFO - 'USER': 'cltbld', 17:42:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:42:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:01 INFO - 'XPC_FLAGS': '0x0', 17:42:01 INFO - 'XPC_SERVICE_NAME': '0', 17:42:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:42:01 INFO - Cleaning up... 17:42:01 INFO - Return code: 0 17:42:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:42: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')]} 17:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42: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')]} 17:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c56c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7e04b4280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049acd30>, '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 0x104976cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10499dbe8>, '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.V2Ray8zSWD/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.YltVRXN12H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:42:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:42:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:42:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V2Ray8zSWD/Render', 17:42:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:42:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:42:01 INFO - 'HOME': '/Users/cltbld', 17:42:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:42:01 INFO - 'LOGNAME': 'cltbld', 17:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:01 INFO - 'MOZ_NO_REMOTE': '1', 17:42:01 INFO - 'NO_EM_RESTART': '1', 17:42:01 INFO - 'PAGER': '/bin/cat', 17:42:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:01 INFO - 'PWD': '/builds/slave/test', 17:42:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:42:01 INFO - 'SHELL': '/bin/bash', 17:42:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YltVRXN12H/Listeners', 17:42:01 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:42:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:42:01 INFO - 'USER': 'cltbld', 17:42:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:42:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:01 INFO - 'XPC_FLAGS': '0x0', 17:42:01 INFO - 'XPC_SERVICE_NAME': '0', 17:42:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:01 INFO - Downloading/unpacking psutil>=3.1.1 17:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:42:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:42:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:42:04 INFO - Installing collected packages: psutil 17:42:04 INFO - Running setup.py install for psutil 17:42:04 INFO - building 'psutil._psutil_osx' extension 17:42:04 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 17:42:04 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 17:42:05 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 17:42:05 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 17:42:05 INFO - building 'psutil._psutil_posix' extension 17:42:05 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 17:42:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:42:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:42:05 INFO - ^ 17:42:05 INFO - 1 warning generated. 17:42:05 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 17:42:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:42:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:42:05 INFO - Successfully installed psutil 17:42:05 INFO - Cleaning up... 17:42:05 INFO - Return code: 0 17:42:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:42:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:42:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c56c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7e04b4280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049acd30>, '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 0x104976cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10499dbe8>, '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.V2Ray8zSWD/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.YltVRXN12H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:42:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:42:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:42:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V2Ray8zSWD/Render', 17:42:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:42:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:42:05 INFO - 'HOME': '/Users/cltbld', 17:42:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:42:05 INFO - 'LOGNAME': 'cltbld', 17:42:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:05 INFO - 'MOZ_NO_REMOTE': '1', 17:42:05 INFO - 'NO_EM_RESTART': '1', 17:42:05 INFO - 'PAGER': '/bin/cat', 17:42:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:42:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:05 INFO - 'PWD': '/builds/slave/test', 17:42:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:42:05 INFO - 'SHELL': '/bin/bash', 17:42:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YltVRXN12H/Listeners', 17:42:05 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:42:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:42:05 INFO - 'USER': 'cltbld', 17:42:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:42:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:42:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:05 INFO - 'XPC_FLAGS': '0x0', 17:42:05 INFO - 'XPC_SERVICE_NAME': '0', 17:42:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:42:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:42:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:42:07 INFO - Installing collected packages: mozsystemmonitor 17:42:07 INFO - Running setup.py install for mozsystemmonitor 17:42:07 INFO - Successfully installed mozsystemmonitor 17:42:07 INFO - Cleaning up... 17:42:07 INFO - Return code: 0 17:42:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:42:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:42:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104c56c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7e04b4280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049acd30>, '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 0x104976cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10499dbe8>, '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.V2Ray8zSWD/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.YltVRXN12H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:42:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:42:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:42:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V2Ray8zSWD/Render', 17:42:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:42:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:42:07 INFO - 'HOME': '/Users/cltbld', 17:42:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:42:07 INFO - 'LOGNAME': 'cltbld', 17:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:07 INFO - 'MOZ_NO_REMOTE': '1', 17:42:07 INFO - 'NO_EM_RESTART': '1', 17:42:07 INFO - 'PAGER': '/bin/cat', 17:42:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:07 INFO - 'PWD': '/builds/slave/test', 17:42:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:42:07 INFO - 'SHELL': '/bin/bash', 17:42:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YltVRXN12H/Listeners', 17:42:07 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:42:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:42:07 INFO - 'USER': 'cltbld', 17:42:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:42:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:07 INFO - 'XPC_FLAGS': '0x0', 17:42:07 INFO - 'XPC_SERVICE_NAME': '0', 17:42:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:42:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:07 INFO - Downloading/unpacking jsonschema==2.5.1 17:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:42:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:09 INFO - Downloading functools32-3.2.3-2.tar.gz 17:42:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:42:09 INFO - warning: no files found matching '*.txt' 17:42:09 INFO - no previously-included directories found matching 'build' 17:42:09 INFO - no previously-included directories found matching 'dist' 17:42:09 INFO - no previously-included directories found matching '.git*' 17:42:09 INFO - Installing collected packages: jsonschema, functools32 17:42:09 INFO - Running setup.py install for functools32 17:42:09 INFO - warning: no files found matching '*.txt' 17:42:09 INFO - no previously-included directories found matching 'build' 17:42:09 INFO - no previously-included directories found matching 'dist' 17:42:09 INFO - no previously-included directories found matching '.git*' 17:42:09 INFO - Successfully installed jsonschema functools32 17:42:09 INFO - Cleaning up... 17:42:09 INFO - Return code: 0 17:42:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:42:09 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')]} 17:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:09 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')]} 17:42:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:09 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 0x104c56c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7e04b4280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049acd30>, '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 0x104976cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10499dbe8>, '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.V2Ray8zSWD/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.YltVRXN12H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:42:09 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 17:42:09 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 17:42:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V2Ray8zSWD/Render', 17:42:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:42:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:42:09 INFO - 'HOME': '/Users/cltbld', 17:42:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:42:09 INFO - 'LOGNAME': 'cltbld', 17:42:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:09 INFO - 'MOZ_NO_REMOTE': '1', 17:42:09 INFO - 'NO_EM_RESTART': '1', 17:42:09 INFO - 'PAGER': '/bin/cat', 17:42:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:42:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:09 INFO - 'PWD': '/builds/slave/test', 17:42:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:42:09 INFO - 'SHELL': '/bin/bash', 17:42:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YltVRXN12H/Listeners', 17:42:09 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:42:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:42:09 INFO - 'USER': 'cltbld', 17:42:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:42:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:42:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:09 INFO - 'XPC_FLAGS': '0x0', 17:42:09 INFO - 'XPC_SERVICE_NAME': '0', 17:42:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:42:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:42:09 INFO - Cleaning up... 17:42:09 INFO - Return code: 0 17:42:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:42:09 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')]} 17:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:09 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')]} 17:42:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:09 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 0x104c56c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7e04b4280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049acd30>, '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 0x104976cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10499dbe8>, '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.V2Ray8zSWD/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.YltVRXN12H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:42:09 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 17:42:09 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 17:42:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V2Ray8zSWD/Render', 17:42:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:42:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:42:09 INFO - 'HOME': '/Users/cltbld', 17:42:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:42:09 INFO - 'LOGNAME': 'cltbld', 17:42:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:09 INFO - 'MOZ_NO_REMOTE': '1', 17:42:09 INFO - 'NO_EM_RESTART': '1', 17:42:09 INFO - 'PAGER': '/bin/cat', 17:42:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:42:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:09 INFO - 'PWD': '/builds/slave/test', 17:42:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:42:09 INFO - 'SHELL': '/bin/bash', 17:42:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YltVRXN12H/Listeners', 17:42:09 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:42:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:42:09 INFO - 'USER': 'cltbld', 17:42:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:42:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:42:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:09 INFO - 'XPC_FLAGS': '0x0', 17:42:09 INFO - 'XPC_SERVICE_NAME': '0', 17:42:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:42:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:10 INFO - Downloading/unpacking blobuploader==1.2.4 17:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:11 INFO - Downloading blobuploader-1.2.4.tar.gz 17:42:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:42:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:42:11 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 17:42:11 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 17:42:11 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 17:42:11 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 17:42:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:42:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:42:12 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 17:42:12 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 17:42:12 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 17:42:12 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 17:42:12 INFO - Downloading docopt-0.6.1.tar.gz 17:42:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:42:12 INFO - Installing collected packages: blobuploader, requests, docopt 17:42:12 INFO - Running setup.py install for blobuploader 17:42:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:42:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:42:12 INFO - Running setup.py install for requests 17:42:13 INFO - Running setup.py install for docopt 17:42:13 INFO - Successfully installed blobuploader requests docopt 17:42:13 INFO - Cleaning up... 17:42:13 INFO - Return code: 0 17:42:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:42:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:42:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:13 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 0x104c56c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7e04b4280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049acd30>, '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 0x104976cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10499dbe8>, '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.V2Ray8zSWD/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.YltVRXN12H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:42:13 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 17:42:13 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 17:42:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V2Ray8zSWD/Render', 17:42:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:42:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:42:13 INFO - 'HOME': '/Users/cltbld', 17:42:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:42:13 INFO - 'LOGNAME': 'cltbld', 17:42:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:13 INFO - 'MOZ_NO_REMOTE': '1', 17:42:13 INFO - 'NO_EM_RESTART': '1', 17:42:13 INFO - 'PAGER': '/bin/cat', 17:42:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:42:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:13 INFO - 'PWD': '/builds/slave/test', 17:42:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:42:13 INFO - 'SHELL': '/bin/bash', 17:42:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YltVRXN12H/Listeners', 17:42:13 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:42:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:42:13 INFO - 'USER': 'cltbld', 17:42:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:42:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:42:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:13 INFO - 'XPC_FLAGS': '0x0', 17:42:13 INFO - 'XPC_SERVICE_NAME': '0', 17:42:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:42:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:42:13 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-G7g4Im-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:42:13 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-tmfdYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:42:13 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-n0JHSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:42:13 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-9PMrnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-YbDXnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-xJDwyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-IIrY5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Oc0aPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-l7bMHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-t3hz27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-3QwXFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Jj4SJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-DZeaxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-4fjSLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:42:15 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-GJ1L6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:42:15 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-CPx8Kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:42:15 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-pSqxCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:42:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:42:15 INFO - Running setup.py install for manifestparser 17:42:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:42:15 INFO - Running setup.py install for mozcrash 17:42:15 INFO - Running setup.py install for mozdebug 17:42:15 INFO - Running setup.py install for mozdevice 17:42:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:42:15 INFO - Running setup.py install for mozfile 17:42:16 INFO - Running setup.py install for mozhttpd 17:42:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:42:16 INFO - Running setup.py install for mozinfo 17:42:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:42:16 INFO - Running setup.py install for mozInstall 17:42:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:42:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:42:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:42:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:42:16 INFO - Running setup.py install for mozleak 17:42:16 INFO - Running setup.py install for mozlog 17:42:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:42:16 INFO - Running setup.py install for moznetwork 17:42:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:42:16 INFO - Running setup.py install for mozprocess 17:42:17 INFO - Running setup.py install for mozprofile 17:42:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:42:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:42:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:42:17 INFO - Running setup.py install for mozrunner 17:42:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:42:17 INFO - Running setup.py install for mozscreenshot 17:42:17 INFO - Running setup.py install for moztest 17:42:17 INFO - Running setup.py install for mozversion 17:42:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:42:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:42:17 INFO - Cleaning up... 17:42:17 INFO - Return code: 0 17:42:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:42:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:42:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:17 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 0x104c56c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7e04b4280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049acd30>, '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 0x104976cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10499dbe8>, '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.V2Ray8zSWD/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.YltVRXN12H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:42:17 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 17:42:17 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 17:42:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V2Ray8zSWD/Render', 17:42:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:42:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:42:17 INFO - 'HOME': '/Users/cltbld', 17:42:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:42:17 INFO - 'LOGNAME': 'cltbld', 17:42:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:17 INFO - 'MOZ_NO_REMOTE': '1', 17:42:17 INFO - 'NO_EM_RESTART': '1', 17:42:17 INFO - 'PAGER': '/bin/cat', 17:42:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:42:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:17 INFO - 'PWD': '/builds/slave/test', 17:42:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:42:17 INFO - 'SHELL': '/bin/bash', 17:42:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YltVRXN12H/Listeners', 17:42:17 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:42:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:42:17 INFO - 'USER': 'cltbld', 17:42:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:42:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:42:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:17 INFO - 'XPC_FLAGS': '0x0', 17:42:17 INFO - 'XPC_SERVICE_NAME': '0', 17:42:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:42:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-z2Iwqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:42:18 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)) 17:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Xc8Yit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:42:18 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)) 17:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-KDTWFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:42:18 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)) 17:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Bwy48z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:42:18 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)) 17:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-2s4cI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:42:18 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)) 17:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-izIHVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:42:18 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)) 17:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-_OmYN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:42:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-3purTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:42:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:42:19 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-USQH6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:42:19 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)) 17:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:42:19 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-1p2KzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:42:19 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)) 17:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:42:19 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-_UZ1ui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:42:19 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)) 17:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:42:19 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-zMiaM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:42:19 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)) 17:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:42:19 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-LgVt4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:42:19 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)) 17:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:42:19 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-n8SwB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:42:19 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)) 17:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:42:19 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-IZVkgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:42:19 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)) 17:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:42:19 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-9mUBk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:42:19 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)) 17:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:42:19 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-clVrBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:42:20 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)) 17:42:20 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)) 17:42:20 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)) 17:42:20 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)) 17:42:20 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)) 17:42:20 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)) 17:42:20 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:42:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:42:20 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 17:42:20 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 17:42:20 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 17:42:20 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 17:42:21 INFO - Downloading blessings-1.6.tar.gz 17:42:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:42:21 INFO - Installing collected packages: blessings 17:42:21 INFO - Running setup.py install for blessings 17:42:21 INFO - Successfully installed blessings 17:42:21 INFO - Cleaning up... 17:42:21 INFO - Return code: 0 17:42:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:42:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:42:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:42:22 INFO - Reading from file tmpfile_stdout 17:42:22 INFO - Current package versions: 17:42:22 INFO - blessings == 1.6 17:42:22 INFO - blobuploader == 1.2.4 17:42:22 INFO - docopt == 0.6.1 17:42:22 INFO - functools32 == 3.2.3-2 17:42:22 INFO - jsonschema == 2.5.1 17:42:22 INFO - manifestparser == 1.1 17:42:22 INFO - mozInstall == 1.13 17:42:22 INFO - mozcrash == 1.0 17:42:22 INFO - mozdebug == 0.1 17:42:22 INFO - mozdevice == 0.51 17:42:22 INFO - mozfile == 1.2 17:42:22 INFO - mozhttpd == 0.7 17:42:22 INFO - mozinfo == 0.10 17:42:22 INFO - mozleak == 0.1 17:42:22 INFO - mozlog == 3.4 17:42:22 INFO - moznetwork == 0.27 17:42:22 INFO - mozprocess == 0.25 17:42:22 INFO - mozprofile == 0.28 17:42:22 INFO - mozrunner == 6.13 17:42:22 INFO - mozscreenshot == 0.1 17:42:22 INFO - mozsystemmonitor == 0.3 17:42:22 INFO - moztest == 0.8 17:42:22 INFO - mozversion == 1.4 17:42:22 INFO - psutil == 3.1.1 17:42:22 INFO - requests == 1.2.3 17:42:22 INFO - wsgiref == 0.1.2 17:42:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:42:22 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')]} 17:42:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:22 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')]} 17:42:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:22 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 0x104c56c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7e04b4280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049acd30>, '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 0x104976cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10499dbe8>, '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.V2Ray8zSWD/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.YltVRXN12H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:42:22 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 17:42:22 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 17:42:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V2Ray8zSWD/Render', 17:42:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:42:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:42:22 INFO - 'HOME': '/Users/cltbld', 17:42:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:42:22 INFO - 'LOGNAME': 'cltbld', 17:42:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:22 INFO - 'MOZ_NO_REMOTE': '1', 17:42:22 INFO - 'NO_EM_RESTART': '1', 17:42:22 INFO - 'PAGER': '/bin/cat', 17:42:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:42:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:22 INFO - 'PWD': '/builds/slave/test', 17:42:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:42:22 INFO - 'SHELL': '/bin/bash', 17:42:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YltVRXN12H/Listeners', 17:42:22 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:42:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:42:22 INFO - 'USER': 'cltbld', 17:42:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:42:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:42:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:22 INFO - 'XPC_FLAGS': '0x0', 17:42:22 INFO - 'XPC_SERVICE_NAME': '0', 17:42:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:42:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:22 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)) 17:42:22 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)) 17:42:22 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)) 17:42:22 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)) 17:42:22 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)) 17:42:22 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)) 17:42:22 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)) 17:42:22 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)) 17:42:22 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)) 17:42:22 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:42:22 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 17:42:22 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 17:42:22 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 17:42:22 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 17:42:23 INFO - Downloading marionette_driver-1.4.0.tar.gz 17:42:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 17:42:23 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)) 17:42:23 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)) 17:42:23 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)) 17:42:23 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)) 17:42:23 INFO - Installing collected packages: marionette-driver 17:42:23 INFO - Running setup.py install for marionette-driver 17:42:24 INFO - Successfully installed marionette-driver 17:42:24 INFO - Cleaning up... 17:42:24 INFO - Return code: 0 17:42:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:42:24 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')]} 17:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:24 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')]} 17:42:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:24 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 0x104c56c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c5a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7e04b4280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049acd30>, '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 0x104976cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10499dbe8>, '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.V2Ray8zSWD/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.YltVRXN12H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:42:24 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 17:42:24 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 17:42:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V2Ray8zSWD/Render', 17:42:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:42:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:42:24 INFO - 'HOME': '/Users/cltbld', 17:42:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:42:24 INFO - 'LOGNAME': 'cltbld', 17:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:24 INFO - 'MOZ_NO_REMOTE': '1', 17:42:24 INFO - 'NO_EM_RESTART': '1', 17:42:24 INFO - 'PAGER': '/bin/cat', 17:42:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:24 INFO - 'PWD': '/builds/slave/test', 17:42:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:42:24 INFO - 'SHELL': '/bin/bash', 17:42:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YltVRXN12H/Listeners', 17:42:24 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:42:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:42:24 INFO - 'USER': 'cltbld', 17:42:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:42:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:24 INFO - 'XPC_FLAGS': '0x0', 17:42:24 INFO - 'XPC_SERVICE_NAME': '0', 17:42:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:42:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:42:24 INFO - Cleaning up... 17:42:24 INFO - Return code: 0 17:42:24 INFO - Running post-action listener: _resource_record_post_action 17:42:24 INFO - Running post-action listener: _start_resource_monitoring 17:42:24 INFO - Starting resource monitoring. 17:42:24 INFO - [mozharness: 2017-06-11 00:42:24.392645Z] Finished create-virtualenv step (success) 17:42:24 INFO - [mozharness: 2017-06-11 00:42:24.393661Z] Running install step. 17:42:24 INFO - Running pre-action listener: _resource_record_pre_action 17:42:24 INFO - Running main action method: install 17:42:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:42:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:42:24 INFO - Reading from file tmpfile_stdout 17:42:24 INFO - Detecting whether we're running mozinstall >=1.0... 17:42:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:42:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:42:24 INFO - Reading from file tmpfile_stdout 17:42:24 INFO - Output received: 17:42:24 INFO - Usage: mozinstall [options] installer 17:42:24 INFO - Options: 17:42:24 INFO - -h, --help show this help message and exit 17:42:24 INFO - -d DEST, --destination=DEST 17:42:24 INFO - Directory to install application into. [default: 17:42:24 INFO - "/builds/slave/test"] 17:42:24 INFO - --app=APP Application being installed. [default: firefox] 17:42:24 INFO - mkdir: /builds/slave/test/build/application 17:42:24 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'] 17:42:24 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 17:42:47 INFO - Reading from file tmpfile_stdout 17:42:47 INFO - Output received: 17:42:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:42:47 INFO - Running post-action listener: _resource_record_post_action 17:42:47 INFO - [mozharness: 2017-06-11 00:42:47.276856Z] Finished install step (success) 17:42:47 INFO - [mozharness: 2017-06-11 00:42:47.277008Z] Running setup-mitmproxy step. 17:42:47 INFO - Running pre-action listener: _resource_record_pre_action 17:42:47 INFO - Running main action method: setup_mitmproxy 17:42:47 INFO - Skipping: mitmproxy is not required 17:42:47 INFO - Running post-action listener: _resource_record_post_action 17:42:47 INFO - [mozharness: 2017-06-11 00:42:47.277636Z] Finished setup-mitmproxy step (success) 17:42:47 INFO - [mozharness: 2017-06-11 00:42:47.277750Z] Running run-tests step. 17:42:47 INFO - Running pre-action listener: _resource_record_pre_action 17:42:47 INFO - Running main action method: run_tests 17:42:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:42:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:42:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:42:47 INFO - 2017-06-10 17:42:47.292 screenresolution[1649:16210] starting screenresolution argv=screenresolution get 17:42:47 INFO - 2017-06-10 17:42:47.295 screenresolution[1649:16210] Display 0: 1600x1200x32@60 17:42:47 INFO - 2017-06-10 17:42:47.301 screenresolution[1650:16213] starting screenresolution argv=screenresolution list 17:42:47 INFO - Available Modes on Display 0 17:42:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:42:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:42:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:42:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:42:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:42:47 INFO - Intel Iris: 17:42:47 INFO - Chipset Model: Intel Iris 17:42:47 INFO - Type: GPU 17:42:47 INFO - Bus: Built-In 17:42:47 INFO - VRAM (Dynamic, Max): 1536 MB 17:42:47 INFO - Vendor: Intel (0x8086) 17:42:47 INFO - Device ID: 0x0a2e 17:42:47 INFO - Revision ID: 0x0009 17:42:47 INFO - Displays: 17:42:47 INFO - Display: 17:42:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:42:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:42:47 INFO - Display Serial Number: 1600x1200 60 17:42:47 INFO - Main Display: Yes 17:42:47 INFO - Mirror: Off 17:42:47 INFO - Online: Yes 17:42:47 INFO - Rotation: Supported 17:42:47 INFO - Return code: 0 17:42:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:42:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:42:47 INFO - Python 2.7.3 17:42:47 INFO - Return code: 0 17:42:47 INFO - grabbing minidump binary from tooltool 17:42: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')]} 17:42:47 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 0x1049acd30>, '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 0x104976cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10499dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:42:47 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 17:42:47 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 17:42:47 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 17:42:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:42:47 INFO - Return code: 0 17:42:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:42:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:42:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:42:47 INFO - ENV: RUST_BACKTRACE is now 1 17:42:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:42:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:42:47 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/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0191', '--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 17:42:47 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/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0191 --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 17:42:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V2Ray8zSWD/Render', 17:42:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:42:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:42:47 INFO - 'HOME': '/Users/cltbld', 17:42:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:42:47 INFO - 'LOGNAME': 'cltbld', 17:42:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:42:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:42:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:47 INFO - 'MOZ_NO_REMOTE': '1', 17:42:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:42:47 INFO - 'NO_EM_RESTART': '1', 17:42:47 INFO - 'PAGER': '/bin/cat', 17:42:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:42:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:47 INFO - 'PWD': '/builds/slave/test', 17:42:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:42:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:42:47 INFO - 'RUST_BACKTRACE': '1', 17:42:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:42:47 INFO - 'SHELL': '/bin/bash', 17:42:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YltVRXN12H/Listeners', 17:42:47 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:42:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:42:47 INFO - 'USER': 'cltbld', 17:42:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:42:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:42:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:47 INFO - 'XPC_FLAGS': '0x0', 17:42:47 INFO - 'XPC_SERVICE_NAME': '0', 17:42:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:42:47 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/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0191', '--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 17:42:47 INFO - mozversion application_buildid: 20170610161132 17:42:47 INFO - mozversion application_changeset: 506992e7f7ea38453558a27a11fbf3dc9400eaf7 17:42:47 INFO - mozversion application_display_name: Nightly 17:42:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:42:47 INFO - mozversion application_name: Firefox 17:42:47 INFO - mozversion application_remotingname: firefox 17:42:47 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 17:42:47 INFO - mozversion application_vendor: Mozilla 17:42:47 INFO - mozversion application_version: 55.0a1 17:42:47 INFO - mozversion platform_buildid: 20170610161132 17:42:47 INFO - mozversion platform_changeset: 506992e7f7ea38453558a27a11fbf3dc9400eaf7 17:42:47 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 17:42:47 INFO - mozversion platform_version: 55.0a1 17:42:47 INFO - using testdate: 1497141767 17:42:47 INFO - actual date: 1497141767 17:42:47 INFO - starting webserver on 'localhost:49232' 17:42:47 INFO - SUITE-START | Running 2 tests 17:42:47 INFO - TEST-START | dromaeo_css 17:42:47 INFO - operating with platform_type : mac_ 17:42:47 INFO - Initialising browser for dromaeo_css test... 17:42:47 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpOOjKmb/profile 17:42:47 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground) 17:43:21 INFO - TEST-INFO | 1660: killed by SIGTERM 17:43:21 INFO - Browser initialized. 17:43:21 INFO - Running cycle 1/1 for dromaeo_css test... 17:43:21 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpOOjKmb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:43:23 INFO - PID 1668 | 2017-06-10 17:43:23.760 plugin-container[1669:16720] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9837, name = 'com.apple.tsm.portname' 17:43:23 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:43:23 INFO - PID 1668 | 2017-06-10 17:43:23.763 plugin-container[1669:16720] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4807, name = 'com.apple.CFPasteboardClient' 17:43:23 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:43:23 INFO - PID 1668 | 2017-06-10 17:43:23.763 plugin-container[1669:16720] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:43:24 INFO - PID 1668 | 2017-06-10 17:43:24.522 plugin-container[1670:16765] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 17:43:24 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:43:24 INFO - PID 1668 | 2017-06-10 17:43:24.522 plugin-container[1670:16765] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 17:43:24 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:43:24 INFO - PID 1668 | 2017-06-10 17:43:24.522 plugin-container[1670:16765] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:43:33 INFO - PID 1668 | RSS: Main: 252264448 17:43:33 INFO - PID 1668 | 17:46:36 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49232/tests/dromaeo/cssquery-ext.html) 17:46:37 INFO - PID 1668 | RSS: Main: 263737344 17:46:37 INFO - PID 1668 | 17:49:39 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-ext.html (next: http://localhost:49232/tests/dromaeo/cssquery-jquery.html) 17:49:40 INFO - PID 1668 | RSS: Main: 275791872 17:49:40 INFO - PID 1668 | 17:52:42 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49232/tests/dromaeo/cssquery-mootools.html) 17:52:42 INFO - PID 1668 | RSS: Main: 275705856 17:52:42 INFO - PID 1668 | 17:55:44 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49232/tests/dromaeo/cssquery-prototype.html) 17:55:45 INFO - PID 1668 | RSS: Main: 279392256 17:55:45 INFO - PID 1668 | 17:58:47 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49232/tests/dromaeo/cssquery-yui.html) 17:58:48 INFO - PID 1668 | RSS: Main: 278364160 17:58:48 INFO - PID 1668 | 18:01:50 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-yui.html (next: http://localhost:49232/tests/dromaeo/cssquery-dojo.html) 18:01:50 INFO - PID 1668 | RSS: Main: 278773760 18:01:50 INFO - PID 1668 | 18:01:50 INFO - PID 1668 | __start_tp_report 18:01:50 INFO - PID 1668 | _x_x_mozilla_page_load 18:01:50 INFO - PID 1668 | _x_x_mozilla_page_load_details 18:01:50 INFO - PID 1668 | |i|pagename|runs| 18:01:50 INFO - PID 1668 | |0;dojo.html;3246.318273162636;3600.2799440111953;3606.675399214072;3624.0758606555332;3625.2386998730267;6121.818815678067;6191.411815877495;6234.594751341159;6285.654289014103;6304.369563043693;6207.317195108546;6224.128621992913;6261.968690156555;6315.115883776263;6321.304656487794;4953.876153096166;5084.0686359130295;5091.930694554148;5492.148716948879;5521.917171242414;5025.643076369378;5591.329040515144;5607.102863541834;5646.40712725162;5656.575756818234;5396.1905714143195;5423.078076726926;5492.972535137339;5508.394076651585;5550.361708403545;5527.972360138214;5529.861753456155;5575.163725441175;5587.357453892813;5604.243427137325;5491.588130890159;5493.642913210629;5510.531604813596;5516.393196748354;5531.9723401382735;6145.539084568675;6193.783217587408;6200.038993955944;6224.564280500367;6322.74709011639;6816.693248803816;6837.076994605714;6864.862702745967;6869.210040845317;6888.448924086061;6921.342472465158;6943.409810166142;6962.094927659423;6974.546654467444;6978.127734033246;6292.559520833498;6360;6452.1289625900845;6454.419102280817;6455.5158363122955;5235.502627250406;6935.098437203132;7022.894656580155;7052.541584796972;7102.644867756591;6541.967290163614;6603.80188594343;6623.768168114093;6629.138212032405;6720.395164435225;6852.5203235773015;6868.450523958071;6883.139607549057;6893.862122757517;6895.55178913379;6635.966820165868;6790.558613690095;6793.796186114425;6809.148856392951;6809.659517024129;6726.630035348005;6732.899006514906;6779;6821.658917054127;6852.6916288767125;5460.453954604587;5468.507834294933;5470.042742520043;5470.398256191816;5479;4768.976155119202;4864.537868902519;4866.124097662384;4869.756512174377;4908.754562271872;6206.937930620727;6299.244090709052;6331.620102793755;6372.936270637327;6382.872342553216;6966.79099627012;6978.057962175143;6992.79021629352;7000.719971201095;7009.983552384876;5878;6118;6372.107904893319;6399.296077431479;6445.16212892321;5990.520758339322;6073.726682299307;6099.451049405575;6115.331611965994;6135.110408990671;7058.505904586629;7062.117235345582;7074.71701131949;7078.752243671447;7107.218205997337;6419.26178489461;6896.793096207121;6956.826079348057;6996.405305548882;7000.579965202104;6867.656617169022;6920.100386949664;6949.826254343682;7010.649467526501;7074.221835598183;5784.855378615568;6790.25307216196;6841.726330946706;6872.209695885037;6894.8621027580175;6912.239653638197;6955.1653801544135;6973.442124629916;6982.860342793218;6984.511084224056;6579.605223686594;6587.4071333580205;6648.434883034996;6677.465802735673;6726.360995705596;6743.831404214934;6751.257361690112;6766.458683305422;6768.120144381199;6772.796816095525;6514.413702766774;6617.536772425884;6627.4698024158915;6634.436072933661;6640.734370625121;5461.644993075437;5609.691466969356;6465.935340646533;6614.272430032596;6745.190577130579;6518.5111116667385;6545.967270163619;6549.443297319781;6563.0811686362995;6569.9014514783275 18:01:50 INFO - PID 1668 | |1;ext.html;713.4221280762581;760.444875241074;830.5971603772169;1156.231106314301;1330.3680751642964;37801.24397512052;39070;39123;39140.41289380661;39200.41199382008;39341.60658393415;39643.60356396435;39649.801750991275;39803.60196398042;39878.60121398785;7494.962525187367;7869.213078692113;7956.960215198945;8223.424360294752;8301.335893128566;8369.121242269566;8414.83170336596;8479.19447652472;8480.406371553994;8481.406301558893;8513.787155321135;8522.786930326729;8523.57382130891;8525.57372131394;8539.829203415959;8452.492850428964;8471.618777155012;8497.61760720769;8510.106438823957;8514.616842242114;7093.467989900726;8296.668133274661;8298.502089874626;8308.833823323499;8313.542755148464;37135.628643713484;37233.25533489315;37339.43990840139;37385.439218411746;37398.813005935066;3647.7597616810413;3674.8346324415465;3691.446283057536;3692.593814680383;3697.9075523112137;30591.08226753201;30916.690833091605;30920.381592368252;30987.38025239505;30993.535096973563;3043.4673932062024;3075.615548056493;3101.581286526313;3127.124405166557;3127.8436078196;245464.77267613728;250097;278824.6058769713;299722;299820;40738.79630601828;40848.97877553025;49342.25986610204;49475;49738;50692.746536267085;50785.238221426705;51477.74261128745;51805;51806.44580662586;12846.743065138646;14426.278686065654;14905.776413353808;14913.403463861325;15033.398664053535;9925.454100024424;11750.177487575787;11790.23363481371;11952.64142075739;11952.940235298942;11786.764264714659;13038;14417.567472975827;14467.131972081708;14571.489997850025;5390.703511306838;6284.842878927972;6337.493000559944;6343.873122537616;6356.9364306357265;999.7300728803328;1026.625281772148;1036.528379587286;1071.721352448369;1075.3440401355167;18519.53701157455;23441.7655823443;23468.53062938732;24164.39589010289;25110.372240693763;987.0672214756986;1107.8615173103362;1161.355447726503;1161.8257261410856;1166.7374840660784;12346.382680865921;12761.489540418279;12871.42078606465;12884.291363975071;12888.871111288954;300253;304645;304772;305094.4745276304;332768;52399.47600523946;53418;58805.70597146987;59377.81244375088;59447;1506.570627371194;1540.399244294726;1543.9459618913284;1545.590418539088;1545.6908618276166;29008.274793129498;29242.268943276587;31013.534796977612;31359;31589.36821263608;39922.600773991886;49428.764280891824;50118.749406254195;50581;50819.74590127026;288826;293471;293625.53187233926;294028;294286;38310.61689383182;38559.228815424096;38572.421413678254;38572.80713596414;38738;46555.53444465512;47074.76462617802;48183;48577.75711121422;49125;30709;31062.68937310598;31497;31613.20966975844;33037.66962330442;31646.050909808786;31670.208244794063;31826.204344891565;33074.66925330716;33242.504087316614;38796;39286.60713392829;39465.60534394619;39488.40767388549;39524.604753953245;25679.358016049002;31453;32670.509942350407;32750.50874236936;33257.66742332546;23152.53694926126;23964.760352396952;24749.752502474745;24755.771761234413;25609.87195064013 18:01:50 INFO - PID 1668 | |2;jquery.html;403.2197697455424;494.92328689053164;499.4680665091676;515.0985774893935;517.829116391591;14745.48390806322;15441.382344706226;21366.03852826623;22834.314970550888;23180.76819231807;23337.533249335003;24287.392815179584;24488;24520.75479245207;24615.507689846236;6264.655443950581;6356.745730170811;6402.647854368008;6424.100625912376;6436;6263.31103578606;6363.522735794811;6363.936360636404;6375.872482550346;6396;5922.58541902067;6168.599041062339;6210.099535567362;6219.378062193789;6334.683265836713;6185.226846644169;6239.376062393724;6241.531885108635;6245.063240513937;6280.466160376373;5631.549476041907;5694.402087780765;5696.6866822324755;5700.629459085146;5700.828975130753;29189.124326270245;29195.832166713517;29783.553246701318;29839.25401864949;30187.396252075054;2219.7447293561286;2272.8409011369213;2272.9090836366527;2282.7374851891914;2283.5889539882814;18108.728369074473;18307.993060381672;18434.446966591022;18592.442226733085;18614.255429782792;2166.967495487569;2185.278857976864;2203.129763743312;2212.1704360864674;2231.7656646052255;202842;206020;209672.90327096687;211959;217118.99999999843;12622.621321360373;12882.355882205853;15005.949583529267;15249.542513724606;15406.152661603495;16514.917425412797;17859.910700446413;18044.18801153925;18781.530461738294;19052.475801935812;8928.464292142493;9471.10024547667;9532.18976387001;9559.617615295449;9694.127528522467;7349.8162545936775;8224.671013159406;9002.099790020946;9016.8647470288;9065.637374504946;8861.24679402258;8973.551322433852;8983.955080224558;11718.824217636742;11990.460429280703;3809.6571308581806;3962.5641179470235;4280.400743895857;4361.629261512807;4450.221211288076;921.5944984206927;934.8644446555212;950.2255661635745;956.4070276428658;992.2338237936484;20811.79188208129;20951.68572471414;21507;21554.245601403876;21875.234366797085;980.6616717232544;990.1237404896574;999.6804218431746;1035.9223058270659;1039.9376037437778;9825.263105267133;10595.735106622396;10625.84061239082;10825.458727063615;11203.047740942111;487887;523783.3810830921;552064;554660;568200;159337;160303;160402;161821;161826;1165.9358735269473;1179.8761130081389;1182.9526818927318;1194.28939780832;1197.5090213012622;15345;18011.369602063864;18336.449906502825;18401.9079904605;18490.81509184891;71511;71974;72571;72601.33554929572;73083;96721.51639241759;97142;97150;97403;97740.51129744305;11955;15588.986715863433;16143.919280403523;16149.273282702541;16176;13829.792553111913;17646.382376616755;17973.910130449265;18038.90980545089;18162.72755908636;12181.147319687538;14620.488282910048;14657.56027319182;14726.63183420423;14730.337134828744;10664.84002739944;11425.571803524559;11476.713082172679;11724.296542207494;13325.200487970753;13199.802002969771;13991.600839916335;14930.552083437515;15100.773488397463;15102.320395582006;15628.2967266471;15646.21768911527;15707.450239242029;15826.366945322057;15853.52439426819;14230.93268004883;14349.784753228501;14460.421583136978;14609.269536523343;14776.77834832457 18:01:50 INFO - PID 1668 | |3;mootools.html;1424.935877885495;1475.468831220761;1488.1666266890536;1495.8279797823254;1514.386673397274;12942.935285323572;13494.932525337372;13506.729865402698;13733.038687291893;14186.43254269828;13580.388882500287;13776.931115344409;13811.723765524708;13822.654433639163;13830.585082447542;2688;2729.822561533494;2751.4497100579865;2753.9586906196423;2758.0760445250867;2258.6837842702034;2676.812623116383;2712.1321177223294;2713.2131681812252;2722.060888993294;2679.504291706031;2680.048582753124;2704.7024827268983;2704.9594256086075;2706.188143556935;2618.5155746187015;2654.21700598323;2654.349684327352;2662.0815818542574;2672.038066296132;2618.9869050654625;2627.671541057368;2634.315078079694;2646.1532309660915;2653.257088015359;13199.406026728724;13377.53178638743;13413.46346146153;13417.66455838612;13453.529126480526;7342.4493163012985;7530.849383012363;7604.011478507814;7626.428017898625;7635.465517413783;4574.794134263966;4753.429588449374;4803.063402636487;4804.351412559329;4866.635002374836;1382.4124746982493;1385.7852032934811;1402.9088109272864;1406.739753145661;1429.656882348229;19620.117094730773;19791.703124453146;19898;19912.900435497875;20191.89904050485;5854.414558544197;6472.741090356333;6518.641474718841;6722.462203023746;6737.393634572814;14190.645233869236;14199.148051116967;14263.215523146326;14270.143791372551;14274.500392486214;7334.889976650355;7410.147832999166;7473.252674732592;7701.961490192513;7712.765957446782;6625;6801.353871382211;6817.965910170417;6872.931270687329;6878.174619045746;5027.949720502748;5078.669886457443;5116.104681680691;5141.357330333708;5185.740712964337;1459.3360021190333;1473.395907677847;1479.7114562660392;1486.2420165715346;1487.970240595182;771.810906327942;822.5928165558087;825.4098319701322;861.7027125641644;869.0397111192168;6662.900056499157;6707.329267073351;7180.3537681608905;7197.208307086216;7315.4147668186415;669.2270427656065;683.9042534045237;692.594832023272;694.9200841903145;713.4185638704542;4485.192665320208;4692.859214223578;4938.9012219755405;5019.29729837823;5071.670341427795;13645.863541364459;17557.297708091788;17873.463796086136;17915.73126403105;17961.550961226072;17751.55621109457;17943.73084403735;18059.638807224044;18126.728099078526;18286.359977400727;7585.810354741066;9373.718788436357;9466.857997130048;9480.573374198178;9491.335606507479;6535.836604084936;7580.507267027626;7855.60721963911;8061.1938806118915;8327.6668933242;14618.879061737478;15420.151891646066;15724.842751572338;15840.841591584398;16107.758383624021;15882.841171588598;15973.201339932724;16079.75880361772;16134;16419.343226270816;10591.629292974709;10705.8929410708;10712.73218169552;10787.67636970892;10898.673039808818;12307.938460307641;12531.248125112523;12565.057620678303;12580.937095314832;12602.36988150607;10017;10110.89889101129;10204.540795664065;10219.23355748307;10400.739981500523;9908.504574771088;9913.256505761952;9935.65225217114;9937.055979681918;9984.400935943866;8851.159139881702;8876.644934202559;8958.68644597436;9045.59294831721;9071.59177837012;6196.287426945959;6302.621842689453;6402.647854368054;6563.212414510098;6643.40209381158;6660;6688.398044176048;6890.069840571659;7002.159740831133;7135.322144396274 18:01:50 INFO - PID 1668 | |4;prototype.html;393.0645064745906;397.7176204894525;436.86893931820464;454.58632644293687;464.9488556258812;6387.936120638792;6454.483641308696;6478.708458119384;6546.574472659274;6583;5425.701586412746;5741.617228850022;6420.1974753155855;6436.452901503366;6448.806535803922;4615.123126605951;4812;4814.927776083353;4830.855074347775;4834.154023045953;4011.759294442328;4222.641075508585;4670.532946705336;4851.757412129379;4864.537868902448;4794.520547945195;4795.304680821278;4797.7121372717575;4799.160146974283;4816.879578010543;4781.665283430161;4782.641301902354;4802.519748025187;4824.396950381202;4825.927611085836;4196.824889031076;4208.200441916057;4210.357960395449;4210.705250632457;4216;5545.556355491551;6308.990561510136;6366;6370.267419246753;6398.328175541547;5729.312482502127;5729.713514324267;5730.05454100073;5734.39788822176;5763.942360576382;6245.313015568284;6718;6956.930430695679;6977.302269773033;7011.754588589426;3689.7048236141045;3851.4222866569958;4468.932966005513;4618.284165954283;4773.782685415232;6058.909116363258;6346.952752795829;6530.249021362509;6695.665216739192;7387.894450081701;4569.657275704336;4843.854684359475;4851.854444366674;4855.271709243641;4878.902421951541;4401.801918913657;4931.753412329369;4958.157113290749;4965.900681986413;4966.354373931365;4832.637552183601;5208.828013696963;5215.139501982207;5660.179274005246;5692.743826527816;6332.33510481402;6853.828654283683;6878.896816547755;6894.724211031503;6899;1682.4279744886796;1698.8301169882914;1700.3708627807544;1705.8208888066822;1707.9752148710675;979.3438396274513;1117.7317443813427;1138.4478527913955;1144.753877916252;1153.2042890405594;5679.744411501492;6424.357564243538;6457.903131453032;6458.806235812933;6462.806115816533;5562.916556251659;6023.066424704178;6876.140482439695;7096.8935465967015;7128.7861364158125;5528.44715528444;6214.036824292151;6291.591046581958;6322.589031712924;6420.839479013062;3300.2574420755136;3700.3524383232825;3728.161163738137;3731.4402839573736;3746.939183449092;7288.8177795554475;7304.479954415718;7320.341169294789;7337.926620733831;7365.226651201654;6774.153230846145;7310.926890731131;7335.376492998048;7347;7348.081489813773;6225.319163825832;6724.697388617524;7307.86728057152;7320.524165929198;7346.11846578414;7098.112735908012;7107.573545587281;7138.535995160298;7139.750978441774;7175.784726458214;6554.868902622016;7002.229754727078;7019.6841142147705;7028.262032486516;7062.71749130029;6874.518783685094;7221.891671625035;7269.236730143259;7285.685013319033;7316.743913962986;4870.00164966174;4891.31521586971;4900.95099049005;4907.55831975124;4907.779149938333;6041.758329666764;7005.614691192033;7201.603911784902;7212.603306818175;7231.746888859075;5833.941660583339;5945.740618564918;6646.7341306349135;6941.271166527442;6946.340097690711;6112.633242005494;6942.58478386859;6954.617496037767;6975.965120174366;7077.929220707933;6597.60414375139;6736.5958042517605;6795.218549866631;6803.353681400259;6810.727570897108;5603.859903502445;6883.724651013903;6916.481263905127;6926.376626103674;6950.409215216763;5552.694601796778;6722.865437755842;6756.223034351113;6772.322767723188;6865.965670171618 18:01:50 INFO - PID 1668 | |5;yui.html;810.469142711524;814.9470284431508;822.1613953767154;844.3582877013467;852.2329903087223;14757.48348807792;15450.768238476432;16985.999999999985;17550.12249387532;17625.82374176258;16369.672606547892;17429.389971351004;18301.176447059883;18425.07874606271;18462.261509539636;953.8998405167462;968.6222373274429;972.3193764364937;975.4976187263529;976.9120779129877;975.3806332978538;975.8097171051647;976.0532283684814;976.5800705696547;996.4967691315911;937.1846493550587;1034.0280136671543;1036.3574583758043;1043.9895601044007;1045.7699306152633;1038.4859494550208;1039.5685790396976;1040.7866387390566;1040.9271351005432;1041.3335465302212;748.2293237964905;754.5925200391782;755.1504557372955;755.4975940999229;755.5089192025173;15253.313600887986;17218.96686198832;17299.65400691979;17357;17413.82586174135;17330.65338693219;17614.823851761448;17832.821671783247;17946.461606151704;18129.637407251783;14582.854171458363;14593.635159121;14638;14703.117812931258;14796.18620975846;3595.676389124991;3613.9277214455565;3614.981925090384;3620.25784714133;3620.619834917322;134944.32527837396;135259;137101;137717.62282377147;138272.61727382697;13810.516631917835;14150.85849141516;14205.857941420454;14251.358688859027;14318.856811431753;27101.322466938484;27128.186154415398;27374;27654.170374888785;27755.44489110247;6654.6672666366485;6829.829254268683;7506.774796756106;8016.7194148204535;8053.597320134087;3854.768713877176;3994.021464741157;3994.3409337458993;3996.020974861178;4001.1797581495725;4598.97700511502;4666.113438446684;4673.556012178838;4683.695559788603;4690.413698287714;1014.7209517382779;1021.2187676427538;1023.5445226874118;1033.9844902326472;1038.1331588124;635.2281977397392;650.9674516274167;657.2901266632026;657.4871600151889;687.3470203306879;10950.34297942126;11349.375784331942;11531.88468115325;12081.395930203455;12123.454444550016;607.76601008612;631.5989346764745;656.2617055812211;661.8345413646589;673.599208470959;5300.1519756838725;5416.241726158341;5423.864403389947;5565.16522521625;5616.129499927518;139213;140531.29734351469;141147.29426352802;144306.278468607;146588;104018.47990759998;108281;128521;133369;133647;94993.98803544827;97255;102403;103741.96258037473;103996.48001759943;11473.655790326304;11487.25332853362;11538.826917596074;11770.941145294219;12380.690482738004;45262.77368613136;45643;45979.080418392114;46152;46213.53786462092;58708.412915872;60380.39619603748;61500.69249653723;61558.69220653868;62323.376766231755;15794.526164215093;15876;16118.435854745028;16203.189840507692;16219.594510137342;15953;17217.31130754756;17868.731969020217;18274.817251827844;18401.631967360845;16264.756028659816;16469.91765041167;16520.83479165241;16568.917155414147;16660.66678666396;15626.45307414235;16197.919010404872;16479.258433370287;16558.337666493204;16594.253258603152;15954.68090638204;16060.19699015068;16152.515424537283;16376.836231637531;16435.09606971628;10969.341839489656;11177.385243811668;11184.496697648789;11311.830322545333;11537.480813363587;10756.139508839431;11352.886471135513;11367.658970230907;12083.275003499819;12482.18865773758 18:01:50 INFO - PID 1668 | __end_tp_report 18:01:50 INFO - PID 1668 | __start_cc_report 18:01:50 INFO - PID 1668 | _x_x_mozilla_cycle_collect,448 18:01:50 INFO - PID 1668 | __end_cc_report 18:01:50 INFO - PID 1668 | __startTimestamp1497142910570__endTimestamp 18:01:50 INFO - PID 1668 | 18:01:50 INFO - PID 1668 | ------- Summary: start ------- 18:01:50 INFO - PID 1668 | Number of tests: 6 18:01:50 INFO - PID 1668 | 18:01:50 INFO - PID 1668 | [#0] dojo.html Cycles:165 Average:6262.50 Median:6518.51 stddev:780.04 (12.0%) stddev-sans-first:745.68 18:01:50 INFO - PID 1668 | Values: 3246.3 3600.3 3606.7 3624.1 3625.2 6121.8 6191.4 6234.6 6285.7 6304.4 6207.3 6224.1 6262.0 6315.1 6321.3 4953.9 5084.1 5091.9 5492.1 5521.9 5025.6 5591.3 5607.1 5646.4 5656.6 5396.2 5423.1 5493.0 5508.4 5550.4 5528.0 5529.9 5575.2 5587.4 5604.2 5491.6 5493.6 5510.5 5516.4 5532.0 6145.5 6193.8 6200.0 6224.6 6322.7 6816.7 6837.1 6864.9 6869.2 6888.4 6921.3 6943.4 6962.1 6974.5 6978.1 6292.6 6360.0 6452.1 6454.4 6455.5 5235.5 6935.1 7022.9 7052.5 7102.6 6542.0 6603.8 6623.8 6629.1 6720.4 6852.5 6868.5 6883.1 6893.9 6895.6 6636.0 6790.6 6793.8 6809.1 6809.7 6726.6 6732.9 6779.0 6821.7 6852.7 5460.5 5468.5 5470.0 5470.4 5479.0 4769.0 4864.5 4866.1 4869.8 4908.8 6206.9 6299.2 6331.6 6372.9 6382.9 6966.8 6978.1 6992.8 7000.7 7010.0 5878.0 6118.0 6372.1 6399.3 6445.2 5990.5 6073.7 6099.5 6115.3 6135.1 7058.5 7062.1 7074.7 7078.8 7107.2 6419.3 6896.8 6956.8 6996.4 7000.6 6867.7 6920.1 6949.8 7010.6 7074.2 5784.9 6790.3 6841.7 6872.2 6894.9 6912.2 6955.2 6973.4 6982.9 6984.5 6579.6 6587.4 6648.4 6677.5 6726.4 6743.8 6751.3 6766.5 6768.1 6772.8 6514.4 6617.5 6627.5 6634.4 6640.7 5461.6 5609.7 6465.9 6614.3 6745.2 6518.5 6546.0 6549.4 6563.1 6569.9 18:01:50 INFO - PID 1668 | 18:01:50 INFO - PID 1668 | [#1] ext.html Cycles:180 Average:45446.10 Median:3683.14 stddev:76710.83 (2082.8%) stddev-sans-first:76852.50 18:01:50 INFO - PID 1668 | Values: 713.4 760.4 830.6 1156.2 1330.4 37801.2 39070.0 39123.0 39140.4 39200.4 39341.6 39643.6 39649.8 39803.6 39878.6 7495.0 7869.2 7957.0 8223.4 8301.3 8369.1 8414.8 8479.2 8480.4 8481.4 8513.8 8522.8 8523.6 8525.6 8539.8 8452.5 8471.6 8497.6 8510.1 8514.6 7093.5 8296.7 8298.5 8308.8 8313.5 37135.6 37233.3 37339.4 37385.4 37398.8 3647.8 3674.8 3691.4 3692.6 3697.9 30591.1 30916.7 30920.4 30987.4 30993.5 3043.5 3075.6 3101.6 3127.1 3127.8 245464.8 250097.0 278824.6 299722.0 299820.0 40738.8 40849.0 49342.3 49475.0 49738.0 50692.7 50785.2 51477.7 51805.0 51806.4 12846.7 14426.3 14905.8 14913.4 15033.4 9925.5 11750.2 11790.2 11952.6 11952.9 11786.8 13038.0 14417.6 14467.1 14571.5 5390.7 6284.8 6337.5 6343.9 6356.9 999.7 1026.6 1036.5 1071.7 1075.3 18519.5 23441.8 23468.5 24164.4 25110.4 987.1 1107.9 1161.4 1161.8 1166.7 12346.4 12761.5 12871.4 12884.3 12888.9 300253.0 304645.0 304772.0 305094.5 332768.0 52399.5 53418.0 58805.7 59377.8 59447.0 1506.6 1540.4 1543.9 1545.6 1545.7 29008.3 29242.3 31013.5 31359.0 31589.4 39922.6 49428.8 50118.7 50581.0 50819.7 288826.0 293471.0 293625.5 294028.0 294286.0 38310.6 38559.2 38572.4 38572.8 38738.0 46555.5 47074.8 48183.0 48577.8 49125.0 30709.0 31062.7 31497.0 31613.2 33037.7 31646.1 31670.2 31826.2 33074.7 33242.5 38796.0 39286.6 39465.6 39488.4 39524.6 25679.4 31453.0 32670.5 32750.5 33257.7 23152.5 23964.8 24749.8 24755.8 25609.9 18:01:50 INFO - PID 1668 | 18:01:50 INFO - PID 1668 | [#2] jquery.html Cycles:180 Average:39583.86 Median:2225.76 stddev:95017.43 (4269.0%) stddev-sans-first:95238.44 18:01:50 INFO - PID 1668 | Values: 403.2 494.9 499.5 515.1 517.8 14745.5 15441.4 21366.0 22834.3 23180.8 23337.5 24287.4 24488.0 24520.8 24615.5 6264.7 6356.7 6402.6 6424.1 6436.0 6263.3 6363.5 6363.9 6375.9 6396.0 5922.6 6168.6 6210.1 6219.4 6334.7 6185.2 6239.4 6241.5 6245.1 6280.5 5631.5 5694.4 5696.7 5700.6 5700.8 29189.1 29195.8 29783.6 29839.3 30187.4 2219.7 2272.8 2272.9 2282.7 2283.6 18108.7 18308.0 18434.4 18592.4 18614.3 2167.0 2185.3 2203.1 2212.2 2231.8 202842.0 206020.0 209672.9 211959.0 217119.0 12622.6 12882.4 15005.9 15249.5 15406.2 16514.9 17859.9 18044.2 18781.5 19052.5 8928.5 9471.1 9532.2 9559.6 9694.1 7349.8 8224.7 9002.1 9016.9 9065.6 8861.2 8973.6 8984.0 11718.8 11990.5 3809.7 3962.6 4280.4 4361.6 4450.2 921.6 934.9 950.2 956.4 992.2 20811.8 20951.7 21507.0 21554.2 21875.2 980.7 990.1 999.7 1035.9 1039.9 9825.3 10595.7 10625.8 10825.5 11203.0 487887.0 523783.4 552064.0 554660.0 568200.0 159337.0 160303.0 160402.0 161821.0 161826.0 1165.9 1179.9 1183.0 1194.3 1197.5 15345.0 18011.4 18336.4 18401.9 18490.8 71511.0 71974.0 72571.0 72601.3 73083.0 96721.5 97142.0 97150.0 97403.0 97740.5 11955.0 15589.0 16143.9 16149.3 16176.0 13829.8 17646.4 17973.9 18038.9 18162.7 12181.1 14620.5 14657.6 14726.6 14730.3 10664.8 11425.6 11476.7 11724.3 13325.2 13199.8 13991.6 14930.6 15100.8 15102.3 15628.3 15646.2 15707.5 15826.4 15853.5 14230.9 14349.8 14460.4 14609.3 14776.8 18:01:50 INFO - PID 1668 | 18:01:50 INFO - PID 1668 | [#3] mootools.html Cycles:180 Average:8143.45 Median:2704.83 stddev:5372.49 (198.6%) stddev-sans-first:5363.84 18:01:50 INFO - PID 1668 | Values: 1424.9 1475.5 1488.2 1495.8 1514.4 12942.9 13494.9 13506.7 13733.0 14186.4 13580.4 13776.9 13811.7 13822.7 13830.6 2688.0 2729.8 2751.4 2754.0 2758.1 2258.7 2676.8 2712.1 2713.2 2722.1 2679.5 2680.0 2704.7 2705.0 2706.2 2618.5 2654.2 2654.3 2662.1 2672.0 2619.0 2627.7 2634.3 2646.2 2653.3 13199.4 13377.5 13413.5 13417.7 13453.5 7342.4 7530.8 7604.0 7626.4 7635.5 4574.8 4753.4 4803.1 4804.4 4866.6 1382.4 1385.8 1402.9 1406.7 1429.7 19620.1 19791.7 19898.0 19912.9 20191.9 5854.4 6472.7 6518.6 6722.5 6737.4 14190.6 14199.1 14263.2 14270.1 14274.5 7334.9 7410.1 7473.3 7702.0 7712.8 6625.0 6801.4 6818.0 6872.9 6878.2 5027.9 5078.7 5116.1 5141.4 5185.7 1459.3 1473.4 1479.7 1486.2 1488.0 771.8 822.6 825.4 861.7 869.0 6662.9 6707.3 7180.4 7197.2 7315.4 669.2 683.9 692.6 694.9 713.4 4485.2 4692.9 4938.9 5019.3 5071.7 13645.9 17557.3 17873.5 17915.7 17961.6 17751.6 17943.7 18059.6 18126.7 18286.4 7585.8 9373.7 9466.9 9480.6 9491.3 6535.8 7580.5 7855.6 8061.2 8327.7 14618.9 15420.2 15724.8 15840.8 16107.8 15882.8 15973.2 16079.8 16134.0 16419.3 10591.6 10705.9 10712.7 10787.7 10898.7 12307.9 12531.2 12565.1 12580.9 12602.4 10017.0 10110.9 10204.5 10219.2 10400.7 9908.5 9913.3 9935.7 9937.1 9984.4 8851.2 8876.6 8958.7 9045.6 9071.6 6196.3 6302.6 6402.6 6563.2 6643.4 6660.0 6688.4 6890.1 7002.2 7135.3 18:01:50 INFO - PID 1668 | 18:01:50 INFO - PID 1668 | [#4] prototype.html Cycles:180 Average:5576.34 Median:6268.45 stddev:1720.93 (27.5%) stddev-sans-first:1681.21 18:01:50 INFO - PID 1668 | Values: 393.1 397.7 436.9 454.6 464.9 6387.9 6454.5 6478.7 6546.6 6583.0 5425.7 5741.6 6420.2 6436.5 6448.8 4615.1 4812.0 4814.9 4830.9 4834.2 4011.8 4222.6 4670.5 4851.8 4864.5 4794.5 4795.3 4797.7 4799.2 4816.9 4781.7 4782.6 4802.5 4824.4 4825.9 4196.8 4208.2 4210.4 4210.7 4216.0 5545.6 6309.0 6366.0 6370.3 6398.3 5729.3 5729.7 5730.1 5734.4 5763.9 6245.3 6718.0 6956.9 6977.3 7011.8 3689.7 3851.4 4468.9 4618.3 4773.8 6058.9 6347.0 6530.2 6695.7 7387.9 4569.7 4843.9 4851.9 4855.3 4878.9 4401.8 4931.8 4958.2 4965.9 4966.4 4832.6 5208.8 5215.1 5660.2 5692.7 6332.3 6853.8 6878.9 6894.7 6899.0 1682.4 1698.8 1700.4 1705.8 1708.0 979.3 1117.7 1138.4 1144.8 1153.2 5679.7 6424.4 6457.9 6458.8 6462.8 5562.9 6023.1 6876.1 7096.9 7128.8 5528.4 6214.0 6291.6 6322.6 6420.8 3300.3 3700.4 3728.2 3731.4 3746.9 7288.8 7304.5 7320.3 7337.9 7365.2 6774.2 7310.9 7335.4 7347.0 7348.1 6225.3 6724.7 7307.9 7320.5 7346.1 7098.1 7107.6 7138.5 7139.8 7175.8 6554.9 7002.2 7019.7 7028.3 7062.7 6874.5 7221.9 7269.2 7285.7 7316.7 4870.0 4891.3 4901.0 4907.6 4907.8 6041.8 7005.6 7201.6 7212.6 7231.7 5833.9 5945.7 6646.7 6941.3 6946.3 6112.6 6942.6 6954.6 6976.0 7077.9 6597.6 6736.6 6795.2 6803.4 6810.7 5603.9 6883.7 6916.5 6926.4 6950.4 5552.7 6722.9 6756.2 6772.3 6866.0 18:01:50 INFO - PID 1668 | 18:01:50 INFO - PID 1668 | [#5] yui.html Cycles:180 Average:24854.55 Median:17315.15 stddev:38103.27 (220.1%) stddev-sans-first:38167.39 18:01:50 INFO - PID 1668 | Values: 810.5 814.9 822.2 844.4 852.2 14757.5 15450.8 16986.0 17550.1 17625.8 16369.7 17429.4 18301.2 18425.1 18462.3 953.9 968.6 972.3 975.5 976.9 975.4 975.8 976.1 976.6 996.5 937.2 1034.0 1036.4 1044.0 1045.8 1038.5 1039.6 1040.8 1040.9 1041.3 748.2 754.6 755.2 755.5 755.5 15253.3 17219.0 17299.7 17357.0 17413.8 17330.7 17614.8 17832.8 17946.5 18129.6 14582.9 14593.6 14638.0 14703.1 14796.2 3595.7 3613.9 3615.0 3620.3 3620.6 134944.3 135259.0 137101.0 137717.6 138272.6 13810.5 14150.9 14205.9 14251.4 14318.9 27101.3 27128.2 27374.0 27654.2 27755.4 6654.7 6829.8 7506.8 8016.7 8053.6 3854.8 3994.0 3994.3 3996.0 4001.2 4599.0 4666.1 4673.6 4683.7 4690.4 1014.7 1021.2 1023.5 1034.0 1038.1 635.2 651.0 657.3 657.5 687.3 10950.3 11349.4 11531.9 12081.4 12123.5 607.8 631.6 656.3 661.8 673.6 5300.2 5416.2 5423.9 5565.2 5616.1 139213.0 140531.3 141147.3 144306.3 146588.0 104018.5 108281.0 128521.0 133369.0 133647.0 94994.0 97255.0 102403.0 103742.0 103996.5 11473.7 11487.3 11538.8 11770.9 12380.7 45262.8 45643.0 45979.1 46152.0 46213.5 58708.4 60380.4 61500.7 61558.7 62323.4 15794.5 15876.0 16118.4 16203.2 16219.6 15953.0 17217.3 17868.7 18274.8 18401.6 16264.8 16469.9 16520.8 16568.9 16660.7 15626.5 16197.9 16479.3 16558.3 16594.3 15954.7 16060.2 16152.5 16376.8 16435.1 10969.3 11177.4 11184.5 11311.8 11537.5 10756.1 11352.9 11367.7 12083.3 12482.2 18:01:50 INFO - PID 1668 | -------- Summary: end -------- 18:01:50 INFO - PID 1668 | 18:01:51 INFO - TEST-INFO | 1668: exit 0 18:01:51 INFO - TEST-OK | dromaeo_css | took 1143266ms 18:01:51 INFO - TEST-START | kraken 18:01:51 INFO - operating with platform_type : mac_ 18:01:51 INFO - Initialising browser for kraken test... 18:01:51 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpPqCbiS/profile 18:01:51 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground) 18:02:25 INFO - TEST-INFO | 1676: killed by SIGTERM 18:02:25 INFO - Browser initialized. 18:02:25 INFO - Running cycle 1/1 for kraken test... 18:02:25 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpPqCbiS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:02:26 INFO - PID 1680 | 2017-06-10 18:02:26.855 plugin-container[1681:19275] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9837, name = 'com.apple.tsm.portname' 18:02:26 INFO - PID 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:26 INFO - PID 1680 | 2017-06-10 18:02:26.856 plugin-container[1681:19275] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4807, name = 'com.apple.CFPasteboardClient' 18:02:26 INFO - PID 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:26 INFO - PID 1680 | 2017-06-10 18:02:26.856 plugin-container[1681:19275] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:02:27 INFO - PID 1680 | 2017-06-10 18:02:27.674 plugin-container[1682:19320] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 18:02:27 INFO - PID 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:27 INFO - PID 1680 | 2017-06-10 18:02:27.674 plugin-container[1682:19320] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 18:02:27 INFO - PID 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:27 INFO - PID 1680 | 2017-06-10 18:02:27.674 plugin-container[1682:19320] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:02:37 INFO - PID 1680 | RSS: Main: 252235776 18:02:37 INFO - PID 1680 | 18:02:39 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-beat-detection) 18:02:40 INFO - PID 1680 | RSS: Main: 265318400 18:02:40 INFO - PID 1680 | 18:02:43 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-dft) 18:02:44 INFO - PID 1680 | RSS: Main: 273719296 18:02:44 INFO - PID 1680 | 18:02:47 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-fft) 18:02:48 INFO - PID 1680 | RSS: Main: 276688896 18:02:48 INFO - PID 1680 | 18:02:50 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-oscillator) 18:02:50 INFO - PID 1680 | RSS: Main: 277712896 18:02:50 INFO - PID 1680 | 18:02:52 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:02:53 INFO - PID 1680 | RSS: Main: 279531520 18:02:53 INFO - PID 1680 | 18:02:56 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-darkroom) 18:02:57 INFO - PID 1680 | RSS: Main: 289415168 18:02:57 INFO - PID 1680 | 18:03:00 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-desaturate) 18:03:01 INFO - PID 1680 | RSS: Main: 288710656 18:03:01 INFO - PID 1680 | 18:03:04 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49232/tests/kraken/driver.html?testName=json-parse-financial) 18:03:04 INFO - PID 1680 | RSS: Main: 286851072 18:03:04 INFO - PID 1680 | 18:03:06 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49232/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:03:07 INFO - PID 1680 | RSS: Main: 287150080 18:03:07 INFO - PID 1680 | 18:03:08 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:03:09 INFO - PID 1680 | RSS: Main: 286830592 18:03:09 INFO - PID 1680 | 18:03:11 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:03:12 INFO - PID 1680 | RSS: Main: 286486528 18:03:12 INFO - PID 1680 | 18:03:15 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:03:15 INFO - PID 1680 | RSS: Main: 286556160 18:03:15 INFO - PID 1680 | 18:03:18 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:03:19 INFO - PID 1680 | RSS: Main: 286531584 18:03:19 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49232/tests/kraken/driver.html?testName=ai-astar) 18:03:21 INFO - PID 1680 | RSS: Main: 288436224 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | __start_tp_report 18:03:21 INFO - PID 1680 | _x_x_mozilla_page_load 18:03:21 INFO - PID 1680 | _x_x_mozilla_page_load_details 18:03:21 INFO - PID 1680 | |i|pagename|runs| 18:03:21 INFO - PID 1680 | |0;ai-astar;114;116;115;164;162;116;115;120;165;165 18:03:21 INFO - PID 1680 | |1;audio-beat-detection;139;112;132;160;111;131;136;125;155;114 18:03:21 INFO - PID 1680 | |2;audio-dft;196;198;187;213;216;215;215;200;199;202 18:03:21 INFO - PID 1680 | |3;audio-fft;76;81;73;148;57;66;85;93;172;76 18:03:21 INFO - PID 1680 | |4;audio-oscillator;69;70;111;71;69;73;109;72;73;116 18:03:21 INFO - PID 1680 | |5;imaging-gaussian-blur;108;129;82;125;137;98;91;91;92;90 18:03:21 INFO - PID 1680 | |6;imaging-darkroom;99;98;99;130;149;149;111;148;135;99 18:03:21 INFO - PID 1680 | |7;imaging-desaturate;92;107;144;100;93;89;95;91;105;91 18:03:21 INFO - PID 1680 | |8;json-parse-financial;50;50;58;75;78;79;78;67;52;54 18:03:21 INFO - PID 1680 | |9;json-stringify-tinderbox;52;60;56;62;60;64;57;55;57;56 18:03:21 INFO - PID 1680 | |10;stanford-crypto-aes;85;85;81;84;84;91;91;88;109;88 18:03:21 INFO - PID 1680 | |11;stanford-crypto-ccm;163;137;119;148;149;157;140;139;139;144 18:03:21 INFO - PID 1680 | |12;stanford-crypto-pbkdf2;203;186;181;182;178;178;223;185;177;176 18:03:21 INFO - PID 1680 | |13;stanford-crypto-sha256-iterative;65;66;63;67;66;64;64;67;65;64 18:03:21 INFO - PID 1680 | __end_tp_report 18:03:21 INFO - PID 1680 | __start_cc_report 18:03:21 INFO - PID 1680 | _x_x_mozilla_cycle_collect,1178 18:03:21 INFO - PID 1680 | __end_cc_report 18:03:21 INFO - PID 1680 | __startTimestamp1497143001407__endTimestamp 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | ------- Summary: start ------- 18:03:21 INFO - PID 1680 | Number of tests: 14 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | [#0] ai-astar Cycles:10 Average:135.20 Median:141.00 stddev:24.85 (17.6%) stddev-sans-first:25.15 18:03:21 INFO - PID 1680 | Values: 114.0 116.0 115.0 164.0 162.0 116.0 115.0 120.0 165.0 165.0 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | [#1] audio-beat-detection Cycles:10 Average:131.50 Median:134.00 stddev:16.97 (12.7%) stddev-sans-first:17.78 18:03:21 INFO - PID 1680 | Values: 139.0 112.0 132.0 160.0 111.0 131.0 136.0 125.0 155.0 114.0 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | [#2] audio-dft Cycles:10 Average:204.10 Median:207.50 stddev:10.00 (4.8%) stddev-sans-first:10.17 18:03:21 INFO - PID 1680 | Values: 196.0 198.0 187.0 213.0 216.0 215.0 215.0 200.0 199.0 202.0 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | [#3] audio-fft Cycles:10 Average:92.70 Median:76.00 stddev:37.23 (49.0%) stddev-sans-first:39.00 18:03:21 INFO - PID 1680 | Values: 76.0 81.0 73.0 148.0 57.0 66.0 85.0 93.0 172.0 76.0 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | [#4] audio-oscillator Cycles:10 Average:83.30 Median:70.50 stddev:19.93 (28.3%) stddev-sans-first:20.45 18:03:21 INFO - PID 1680 | Values: 69.0 70.0 111.0 71.0 69.0 73.0 109.0 72.0 73.0 116.0 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:104.30 Median:90.50 stddev:19.35 (21.4%) stddev-sans-first:20.47 18:03:21 INFO - PID 1680 | Values: 108.0 129.0 82.0 125.0 137.0 98.0 91.0 91.0 92.0 90.0 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | [#6] imaging-darkroom Cycles:10 Average:121.70 Median:123.50 stddev:22.73 (18.4%) stddev-sans-first:22.58 18:03:21 INFO - PID 1680 | Values: 99.0 98.0 99.0 130.0 149.0 149.0 111.0 148.0 135.0 99.0 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | [#7] imaging-desaturate Cycles:10 Average:100.70 Median:91.00 stddev:16.42 (18.0%) stddev-sans-first:17.11 18:03:21 INFO - PID 1680 | Values: 92.0 107.0 144.0 100.0 93.0 89.0 95.0 91.0 105.0 91.0 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | [#8] json-parse-financial Cycles:10 Average:64.10 Median:71.00 stddev:12.56 (17.7%) stddev-sans-first:12.24 18:03:21 INFO - PID 1680 | Values: 50.0 50.0 58.0 75.0 78.0 79.0 78.0 67.0 52.0 54.0 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:57.90 Median:58.50 stddev:3.57 (6.1%) stddev-sans-first:3.09 18:03:21 INFO - PID 1680 | Values: 52.0 60.0 56.0 62.0 60.0 64.0 57.0 55.0 57.0 56.0 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:86.50 stddev:7.85 (9.1%) stddev-sans-first:8.22 18:03:21 INFO - PID 1680 | Values: 85.0 85.0 81.0 84.0 84.0 91.0 91.0 88.0 109.0 88.0 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:143.50 Median:146.00 stddev:12.06 (8.3%) stddev-sans-first:10.52 18:03:21 INFO - PID 1680 | Values: 163.0 137.0 119.0 148.0 149.0 157.0 140.0 139.0 139.0 144.0 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.90 Median:183.50 stddev:14.91 (8.1%) stddev-sans-first:14.63 18:03:21 INFO - PID 1680 | Values: 203.0 186.0 181.0 182.0 178.0 178.0 223.0 185.0 177.0 176.0 18:03:21 INFO - PID 1680 | 18:03:21 INFO - PID 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.50 stddev:1.37 (2.1%) stddev-sans-first:1.45 18:03:21 INFO - PID 1680 | Values: 65.0 66.0 63.0 67.0 66.0 64.0 64.0 67.0 65.0 64.0 18:03:21 INFO - PID 1680 | -------- Summary: end -------- 18:03:21 INFO - PID 1680 | 18:03:22 INFO - TEST-INFO | 1680: exit 0 18:03:22 INFO - TEST-OK | kraken | took 91128ms 18:03:22 INFO - SUITE-END | took 1234s 18:03:22 INFO - Completed test suite (00:20:34) 18:03:22 INFO - javascript benchmark 18:03:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8376.613934936053, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3246.318273162636, 3600.2799440111953, 3606.675399214072, 3624.0758606555332, 3625.2386998730267, 6121.818815678067, 6191.411815877495, 6234.594751341159, 6285.654289014103, 6304.369563043693, 6207.317195108546, 6224.128621992913, 6261.968690156555, 6315.115883776263, 6321.304656487794, 4953.876153096166, 5084.0686359130295, 5091.930694554148, 5492.148716948879, 5521.917171242414, 5025.643076369378, 5591.329040515144, 5607.102863541834, 5646.40712725162, 5656.575756818234, 5396.1905714143195, 5423.078076726926, 5492.972535137339, 5508.394076651585, 5550.361708403545, 5527.972360138214, 5529.861753456155, 5575.163725441175, 5587.357453892813, 5604.243427137325, 5491.588130890159, 5493.642913210629, 5510.531604813596, 5516.393196748354, 5531.9723401382735, 6145.539084568675, 6193.783217587408, 6200.038993955944, 6224.564280500367, 6322.74709011639, 6816.693248803816, 6837.076994605714, 6864.862702745967, 6869.210040845317, 6888.448924086061, 6921.342472465158, 6943.409810166142, 6962.094927659423, 6974.546654467444, 6978.127734033246, 6292.559520833498, 6360.0, 6452.1289625900845, 6454.419102280817, 6455.5158363122955, 5235.502627250406, 6935.098437203132, 7022.894656580155, 7052.541584796972, 7102.644867756591, 6541.967290163614, 6603.80188594343, 6623.768168114093, 6629.138212032405, 6720.395164435225, 6852.5203235773015, 6868.450523958071, 6883.139607549057, 6893.862122757517, 6895.55178913379, 6635.966820165868, 6790.558613690095, 6793.796186114425, 6809.148856392951, 6809.659517024129, 6726.630035348005, 6732.899006514906, 6779.0, 6821.658917054127, 6852.6916288767125, 5460.453954604587, 5468.507834294933, 5470.042742520043, 5470.398256191816, 5479.0, 4768.976155119202, 4864.537868902519, 4866.124097662384, 4869.756512174377, 4908.754562271872, 6206.937930620727, 6299.244090709052, 6331.620102793755, 6372.936270637327, 6382.872342553216, 6966.79099627012, 6978.057962175143, 6992.79021629352, 7000.719971201095, 7009.983552384876, 5878.0, 6118.0, 6372.107904893319, 6399.296077431479, 6445.16212892321, 5990.520758339322, 6073.726682299307, 6099.451049405575, 6115.331611965994, 6135.110408990671, 7058.505904586629, 7062.117235345582, 7074.71701131949, 7078.752243671447, 7107.218205997337, 6419.26178489461, 6896.793096207121, 6956.826079348057, 6996.405305548882, 7000.579965202104, 6867.656617169022, 6920.100386949664, 6949.826254343682, 7010.649467526501, 7074.221835598183, 5784.855378615568, 6790.25307216196, 6841.726330946706, 6872.209695885037, 6894.8621027580175, 6912.239653638197, 6955.1653801544135, 6973.442124629916, 6982.860342793218, 6984.511084224056, 6579.605223686594, 6587.4071333580205, 6648.434883034996, 6677.465802735673, 6726.360995705596, 6743.831404214934, 6751.257361690112, 6766.458683305422, 6768.120144381199, 6772.796816095525, 6514.413702766774, 6617.536772425884, 6627.4698024158915, 6634.436072933661, 6640.734370625121, 5461.644993075437, 5609.691466969356, 6465.935340646533, 6614.272430032596, 6745.190577130579, 6518.5111116667385, 6545.967270163619, 6549.443297319781, 6563.0811686362995, 6569.9014514783275], "value": 6208.672327614961, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.4221280762581, 760.444875241074, 830.5971603772169, 1156.231106314301, 1330.3680751642964, 37801.24397512052, 39070.0, 39123.0, 39140.41289380661, 39200.41199382008, 39341.60658393415, 39643.60356396435, 39649.801750991275, 39803.60196398042, 39878.60121398785, 7494.962525187367, 7869.213078692113, 7956.960215198945, 8223.424360294752, 8301.335893128566, 8369.121242269566, 8414.83170336596, 8479.19447652472, 8480.406371553994, 8481.406301558893, 8513.787155321135, 8522.786930326729, 8523.57382130891, 8525.57372131394, 8539.829203415959, 8452.492850428964, 8471.618777155012, 8497.61760720769, 8510.106438823957, 8514.616842242114, 7093.467989900726, 8296.668133274661, 8298.502089874626, 8308.833823323499, 8313.542755148464, 37135.628643713484, 37233.25533489315, 37339.43990840139, 37385.439218411746, 37398.813005935066, 3647.7597616810413, 3674.8346324415465, 3691.446283057536, 3692.593814680383, 3697.9075523112137, 30591.08226753201, 30916.690833091605, 30920.381592368252, 30987.38025239505, 30993.535096973563, 3043.4673932062024, 3075.615548056493, 3101.581286526313, 3127.124405166557, 3127.8436078196, 245464.77267613728, 250097.0, 278824.6058769713, 299722.0, 299820.0, 40738.79630601828, 40848.97877553025, 49342.25986610204, 49475.0, 49738.0, 50692.746536267085, 50785.238221426705, 51477.74261128745, 51805.0, 51806.44580662586, 12846.743065138646, 14426.278686065654, 14905.776413353808, 14913.403463861325, 15033.398664053535, 9925.454100024424, 11750.177487575787, 11790.23363481371, 11952.64142075739, 11952.940235298942, 11786.764264714659, 13038.0, 14417.567472975827, 14467.131972081708, 14571.489997850025, 5390.703511306838, 6284.842878927972, 6337.493000559944, 6343.873122537616, 6356.9364306357265, 999.7300728803328, 1026.625281772148, 1036.528379587286, 1071.721352448369, 1075.3440401355167, 18519.53701157455, 23441.7655823443, 23468.53062938732, 24164.39589010289, 25110.372240693763, 987.0672214756986, 1107.8615173103362, 1161.355447726503, 1161.8257261410856, 1166.7374840660784, 12346.382680865921, 12761.489540418279, 12871.42078606465, 12884.291363975071, 12888.871111288954, 300253.0, 304645.0, 304772.0, 305094.4745276304, 332768.0, 52399.47600523946, 53418.0, 58805.70597146987, 59377.81244375088, 59447.0, 1506.570627371194, 1540.399244294726, 1543.9459618913284, 1545.590418539088, 1545.6908618276166, 29008.274793129498, 29242.268943276587, 31013.534796977612, 31359.0, 31589.36821263608, 39922.600773991886, 49428.764280891824, 50118.749406254195, 50581.0, 50819.74590127026, 288826.0, 293471.0, 293625.53187233926, 294028.0, 294286.0, 38310.61689383182, 38559.228815424096, 38572.421413678254, 38572.80713596414, 38738.0, 46555.53444465512, 47074.76462617802, 48183.0, 48577.75711121422, 49125.0, 30709.0, 31062.68937310598, 31497.0, 31613.20966975844, 33037.66962330442, 31646.050909808786, 31670.208244794063, 31826.204344891565, 33074.66925330716, 33242.504087316614, 38796.0, 39286.60713392829, 39465.60534394619, 39488.40767388549, 39524.604753953245, 25679.358016049002, 31453.0, 32670.509942350407, 32750.50874236936, 33257.66742332546, 23152.53694926126, 23964.760352396952, 24749.752502474745, 24755.771761234413, 25609.87195064013], "value": 17994.34127727963, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.2197697455424, 494.92328689053164, 499.4680665091676, 515.0985774893935, 517.829116391591, 14745.48390806322, 15441.382344706226, 21366.03852826623, 22834.314970550888, 23180.76819231807, 23337.533249335003, 24287.392815179584, 24488.0, 24520.75479245207, 24615.507689846236, 6264.655443950581, 6356.745730170811, 6402.647854368008, 6424.100625912376, 6436.0, 6263.31103578606, 6363.522735794811, 6363.936360636404, 6375.872482550346, 6396.0, 5922.58541902067, 6168.599041062339, 6210.099535567362, 6219.378062193789, 6334.683265836713, 6185.226846644169, 6239.376062393724, 6241.531885108635, 6245.063240513937, 6280.466160376373, 5631.549476041907, 5694.402087780765, 5696.6866822324755, 5700.629459085146, 5700.828975130753, 29189.124326270245, 29195.832166713517, 29783.553246701318, 29839.25401864949, 30187.396252075054, 2219.7447293561286, 2272.8409011369213, 2272.9090836366527, 2282.7374851891914, 2283.5889539882814, 18108.728369074473, 18307.993060381672, 18434.446966591022, 18592.442226733085, 18614.255429782792, 2166.967495487569, 2185.278857976864, 2203.129763743312, 2212.1704360864674, 2231.7656646052255, 202842.0, 206020.0, 209672.90327096687, 211959.0, 217118.99999999843, 12622.621321360373, 12882.355882205853, 15005.949583529267, 15249.542513724606, 15406.152661603495, 16514.917425412797, 17859.910700446413, 18044.18801153925, 18781.530461738294, 19052.475801935812, 8928.464292142493, 9471.10024547667, 9532.18976387001, 9559.617615295449, 9694.127528522467, 7349.8162545936775, 8224.671013159406, 9002.099790020946, 9016.8647470288, 9065.637374504946, 8861.24679402258, 8973.551322433852, 8983.955080224558, 11718.824217636742, 11990.460429280703, 3809.6571308581806, 3962.5641179470235, 4280.400743895857, 4361.629261512807, 4450.221211288076, 921.5944984206927, 934.8644446555212, 950.2255661635745, 956.4070276428658, 992.2338237936484, 20811.79188208129, 20951.68572471414, 21507.0, 21554.245601403876, 21875.234366797085, 980.6616717232544, 990.1237404896574, 999.6804218431746, 1035.9223058270659, 1039.9376037437778, 9825.263105267133, 10595.735106622396, 10625.84061239082, 10825.458727063615, 11203.047740942111, 487887.0, 523783.3810830921, 552064.0, 554660.0, 568200.0, 159337.0, 160303.0, 160402.0, 161821.0, 161826.0, 1165.9358735269473, 1179.8761130081389, 1182.9526818927318, 1194.28939780832, 1197.5090213012622, 15345.0, 18011.369602063864, 18336.449906502825, 18401.9079904605, 18490.81509184891, 71511.0, 71974.0, 72571.0, 72601.33554929572, 73083.0, 96721.51639241759, 97142.0, 97150.0, 97403.0, 97740.51129744305, 11955.0, 15588.986715863433, 16143.919280403523, 16149.273282702541, 16176.0, 13829.792553111913, 17646.382376616755, 17973.910130449265, 18038.90980545089, 18162.72755908636, 12181.147319687538, 14620.488282910048, 14657.56027319182, 14726.63183420423, 14730.337134828744, 10664.84002739944, 11425.571803524559, 11476.713082172679, 11724.296542207494, 13325.200487970753, 13199.802002969771, 13991.600839916335, 14930.552083437515, 15100.773488397463, 15102.320395582006, 15628.2967266471, 15646.21768911527, 15707.450239242029, 15826.366945322057, 15853.52439426819, 14230.93268004883, 14349.784753228501, 14460.421583136978, 14609.269536523343, 14776.77834832457], "value": 11813.458656185889, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.935877885495, 1475.468831220761, 1488.1666266890536, 1495.8279797823254, 1514.386673397274, 12942.935285323572, 13494.932525337372, 13506.729865402698, 13733.038687291893, 14186.43254269828, 13580.388882500287, 13776.931115344409, 13811.723765524708, 13822.654433639163, 13830.585082447542, 2688.0, 2729.822561533494, 2751.4497100579865, 2753.9586906196423, 2758.0760445250867, 2258.6837842702034, 2676.812623116383, 2712.1321177223294, 2713.2131681812252, 2722.060888993294, 2679.504291706031, 2680.048582753124, 2704.7024827268983, 2704.9594256086075, 2706.188143556935, 2618.5155746187015, 2654.21700598323, 2654.349684327352, 2662.0815818542574, 2672.038066296132, 2618.9869050654625, 2627.671541057368, 2634.315078079694, 2646.1532309660915, 2653.257088015359, 13199.406026728724, 13377.53178638743, 13413.46346146153, 13417.66455838612, 13453.529126480526, 7342.4493163012985, 7530.849383012363, 7604.011478507814, 7626.428017898625, 7635.465517413783, 4574.794134263966, 4753.429588449374, 4803.063402636487, 4804.351412559329, 4866.635002374836, 1382.4124746982493, 1385.7852032934811, 1402.9088109272864, 1406.739753145661, 1429.656882348229, 19620.117094730773, 19791.703124453146, 19898.0, 19912.900435497875, 20191.89904050485, 5854.414558544197, 6472.741090356333, 6518.641474718841, 6722.462203023746, 6737.393634572814, 14190.645233869236, 14199.148051116967, 14263.215523146326, 14270.143791372551, 14274.500392486214, 7334.889976650355, 7410.147832999166, 7473.252674732592, 7701.961490192513, 7712.765957446782, 6625.0, 6801.353871382211, 6817.965910170417, 6872.931270687329, 6878.174619045746, 5027.949720502748, 5078.669886457443, 5116.104681680691, 5141.357330333708, 5185.740712964337, 1459.3360021190333, 1473.395907677847, 1479.7114562660392, 1486.2420165715346, 1487.970240595182, 771.810906327942, 822.5928165558087, 825.4098319701322, 861.7027125641644, 869.0397111192168, 6662.900056499157, 6707.329267073351, 7180.3537681608905, 7197.208307086216, 7315.4147668186415, 669.2270427656065, 683.9042534045237, 692.594832023272, 694.9200841903145, 713.4185638704542, 4485.192665320208, 4692.859214223578, 4938.9012219755405, 5019.29729837823, 5071.670341427795, 13645.863541364459, 17557.297708091788, 17873.463796086136, 17915.73126403105, 17961.550961226072, 17751.55621109457, 17943.73084403735, 18059.638807224044, 18126.728099078526, 18286.359977400727, 7585.810354741066, 9373.718788436357, 9466.857997130048, 9480.573374198178, 9491.335606507479, 6535.836604084936, 7580.507267027626, 7855.60721963911, 8061.1938806118915, 8327.6668933242, 14618.879061737478, 15420.151891646066, 15724.842751572338, 15840.841591584398, 16107.758383624021, 15882.841171588598, 15973.201339932724, 16079.75880361772, 16134.0, 16419.343226270816, 10591.629292974709, 10705.8929410708, 10712.73218169552, 10787.67636970892, 10898.673039808818, 12307.938460307641, 12531.248125112523, 12565.057620678303, 12580.937095314832, 12602.36988150607, 10017.0, 10110.89889101129, 10204.540795664065, 10219.23355748307, 10400.739981500523, 9908.504574771088, 9913.256505761952, 9935.65225217114, 9937.055979681918, 9984.400935943866, 8851.159139881702, 8876.644934202559, 8958.68644597436, 9045.59294831721, 9071.59177837012, 6196.287426945959, 6302.621842689453, 6402.647854368054, 6563.212414510098, 6643.40209381158, 6660.0, 6688.398044176048, 6890.069840571659, 7002.159740831133, 7135.322144396274], "value": 5994.37603374923, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.0645064745906, 397.7176204894525, 436.86893931820464, 454.58632644293687, 464.9488556258812, 6387.936120638792, 6454.483641308696, 6478.708458119384, 6546.574472659274, 6583.0, 5425.701586412746, 5741.617228850022, 6420.1974753155855, 6436.452901503366, 6448.806535803922, 4615.123126605951, 4812.0, 4814.927776083353, 4830.855074347775, 4834.154023045953, 4011.759294442328, 4222.641075508585, 4670.532946705336, 4851.757412129379, 4864.537868902448, 4794.520547945195, 4795.304680821278, 4797.7121372717575, 4799.160146974283, 4816.879578010543, 4781.665283430161, 4782.641301902354, 4802.519748025187, 4824.396950381202, 4825.927611085836, 4196.824889031076, 4208.200441916057, 4210.357960395449, 4210.705250632457, 4216.0, 5545.556355491551, 6308.990561510136, 6366.0, 6370.267419246753, 6398.328175541547, 5729.312482502127, 5729.713514324267, 5730.05454100073, 5734.39788822176, 5763.942360576382, 6245.313015568284, 6718.0, 6956.930430695679, 6977.302269773033, 7011.754588589426, 3689.7048236141045, 3851.4222866569958, 4468.932966005513, 4618.284165954283, 4773.782685415232, 6058.909116363258, 6346.952752795829, 6530.249021362509, 6695.665216739192, 7387.894450081701, 4569.657275704336, 4843.854684359475, 4851.854444366674, 4855.271709243641, 4878.902421951541, 4401.801918913657, 4931.753412329369, 4958.157113290749, 4965.900681986413, 4966.354373931365, 4832.637552183601, 5208.828013696963, 5215.139501982207, 5660.179274005246, 5692.743826527816, 6332.33510481402, 6853.828654283683, 6878.896816547755, 6894.724211031503, 6899.0, 1682.4279744886796, 1698.8301169882914, 1700.3708627807544, 1705.8208888066822, 1707.9752148710675, 979.3438396274513, 1117.7317443813427, 1138.4478527913955, 1144.753877916252, 1153.2042890405594, 5679.744411501492, 6424.357564243538, 6457.903131453032, 6458.806235812933, 6462.806115816533, 5562.916556251659, 6023.066424704178, 6876.140482439695, 7096.8935465967015, 7128.7861364158125, 5528.44715528444, 6214.036824292151, 6291.591046581958, 6322.589031712924, 6420.839479013062, 3300.2574420755136, 3700.3524383232825, 3728.161163738137, 3731.4402839573736, 3746.939183449092, 7288.8177795554475, 7304.479954415718, 7320.341169294789, 7337.926620733831, 7365.226651201654, 6774.153230846145, 7310.926890731131, 7335.376492998048, 7347.0, 7348.081489813773, 6225.319163825832, 6724.697388617524, 7307.86728057152, 7320.524165929198, 7346.11846578414, 7098.112735908012, 7107.573545587281, 7138.535995160298, 7139.750978441774, 7175.784726458214, 6554.868902622016, 7002.229754727078, 7019.6841142147705, 7028.262032486516, 7062.71749130029, 6874.518783685094, 7221.891671625035, 7269.236730143259, 7285.685013319033, 7316.743913962986, 4870.00164966174, 4891.31521586971, 4900.95099049005, 4907.55831975124, 4907.779149938333, 6041.758329666764, 7005.614691192033, 7201.603911784902, 7212.603306818175, 7231.746888859075, 5833.941660583339, 5945.740618564918, 6646.7341306349135, 6941.271166527442, 6946.340097690711, 6112.633242005494, 6942.58478386859, 6954.617496037767, 6975.965120174366, 7077.929220707933, 6597.60414375139, 6736.5958042517605, 6795.218549866631, 6803.353681400259, 6810.727570897108, 5603.859903502445, 6883.724651013903, 6916.481263905127, 6926.376626103674, 6950.409215216763, 5552.694601796778, 6722.865437755842, 6756.223034351113, 6772.322767723188, 6865.965670171618], "value": 5047.436325106192, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.469142711524, 814.9470284431508, 822.1613953767154, 844.3582877013467, 852.2329903087223, 14757.48348807792, 15450.768238476432, 16985.999999999985, 17550.12249387532, 17625.82374176258, 16369.672606547892, 17429.389971351004, 18301.176447059883, 18425.07874606271, 18462.261509539636, 953.8998405167462, 968.6222373274429, 972.3193764364937, 975.4976187263529, 976.9120779129877, 975.3806332978538, 975.8097171051647, 976.0532283684814, 976.5800705696547, 996.4967691315911, 937.1846493550587, 1034.0280136671543, 1036.3574583758043, 1043.9895601044007, 1045.7699306152633, 1038.4859494550208, 1039.5685790396976, 1040.7866387390566, 1040.9271351005432, 1041.3335465302212, 748.2293237964905, 754.5925200391782, 755.1504557372955, 755.4975940999229, 755.5089192025173, 15253.313600887986, 17218.96686198832, 17299.65400691979, 17357.0, 17413.82586174135, 17330.65338693219, 17614.823851761448, 17832.821671783247, 17946.461606151704, 18129.637407251783, 14582.854171458363, 14593.635159121, 14638.0, 14703.117812931258, 14796.18620975846, 3595.676389124991, 3613.9277214455565, 3614.981925090384, 3620.25784714133, 3620.619834917322, 134944.32527837396, 135259.0, 137101.0, 137717.62282377147, 138272.61727382697, 13810.516631917835, 14150.85849141516, 14205.857941420454, 14251.358688859027, 14318.856811431753, 27101.322466938484, 27128.186154415398, 27374.0, 27654.170374888785, 27755.44489110247, 6654.6672666366485, 6829.829254268683, 7506.774796756106, 8016.7194148204535, 8053.597320134087, 3854.768713877176, 3994.021464741157, 3994.3409337458993, 3996.020974861178, 4001.1797581495725, 4598.97700511502, 4666.113438446684, 4673.556012178838, 4683.695559788603, 4690.413698287714, 1014.7209517382779, 1021.2187676427538, 1023.5445226874118, 1033.9844902326472, 1038.1331588124, 635.2281977397392, 650.9674516274167, 657.2901266632026, 657.4871600151889, 687.3470203306879, 10950.34297942126, 11349.375784331942, 11531.88468115325, 12081.395930203455, 12123.454444550016, 607.76601008612, 631.5989346764745, 656.2617055812211, 661.8345413646589, 673.599208470959, 5300.1519756838725, 5416.241726158341, 5423.864403389947, 5565.16522521625, 5616.129499927518, 139213.0, 140531.29734351469, 141147.29426352802, 144306.278468607, 146588.0, 104018.47990759998, 108281.0, 128521.0, 133369.0, 133647.0, 94993.98803544827, 97255.0, 102403.0, 103741.96258037473, 103996.48001759943, 11473.655790326304, 11487.25332853362, 11538.826917596074, 11770.941145294219, 12380.690482738004, 45262.77368613136, 45643.0, 45979.080418392114, 46152.0, 46213.53786462092, 58708.412915872, 60380.39619603748, 61500.69249653723, 61558.69220653868, 62323.376766231755, 15794.526164215093, 15876.0, 16118.435854745028, 16203.189840507692, 16219.594510137342, 15953.0, 17217.31130754756, 17868.731969020217, 18274.817251827844, 18401.631967360845, 16264.756028659816, 16469.91765041167, 16520.83479165241, 16568.917155414147, 16660.66678666396, 15626.45307414235, 16197.919010404872, 16479.258433370287, 16558.337666493204, 16594.253258603152, 15954.68090638204, 16060.19699015068, 16152.515424537283, 16376.836231637531, 16435.09606971628, 10969.341839489656, 11177.385243811668, 11184.496697648789, 11311.830322545333, 11537.480813363587, 10756.139508839431, 11352.886471135513, 11367.658970230907, 12083.275003499819, 12482.18865773758], "value": 8650.793574389349, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1579.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 115.0, 164.0, 162.0, 116.0, 115.0, 120.0, 165.0, 165.0], "value": 135.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 112.0, 132.0, 160.0, 111.0, 131.0, 136.0, 125.0, 155.0, 114.0], "value": 131.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 198.0, 187.0, 213.0, 216.0, 215.0, 215.0, 200.0, 199.0, 202.0], "value": 204.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 81.0, 73.0, 148.0, 57.0, 66.0, 85.0, 93.0, 172.0, 76.0], "value": 92.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 111.0, 71.0, 69.0, 73.0, 109.0, 72.0, 73.0, 116.0], "value": 83.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 129.0, 82.0, 125.0, 137.0, 98.0, 91.0, 91.0, 92.0, 90.0], "value": 104.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 99.0, 130.0, 149.0, 149.0, 111.0, 148.0, 135.0, 99.0], "value": 121.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 107.0, 144.0, 100.0, 93.0, 89.0, 95.0, 91.0, 105.0, 91.0], "value": 100.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 58.0, 75.0, 78.0, 79.0, 78.0, 67.0, 52.0, 54.0], "value": 64.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 60.0, 56.0, 62.0, 60.0, 64.0, 57.0, 55.0, 57.0, 56.0], "value": 57.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 81.0, 84.0, 84.0, 91.0, 91.0, 88.0, 109.0, 88.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 137.0, 119.0, 148.0, 149.0, 157.0, 140.0, 139.0, 139.0, 144.0], "value": 143.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 186.0, 181.0, 182.0, 178.0, 178.0, 223.0, 185.0, 177.0, 176.0], "value": 186.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 63.0, 67.0, 66.0, 64.0, 64.0, 67.0, 65.0, 64.0], "value": 65.1, "unit": "score"}]}]} 18:03:22 INFO - Return code: 0 18:03:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:03:22 INFO - # TBPL SUCCESS # 18:03:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:03:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:03:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:03:22 INFO - 2017-06-10 18:03:22.819 screenresolution[1688:19722] starting screenresolution argv=screenresolution get 18:03:22 INFO - 2017-06-10 18:03:22.822 screenresolution[1688:19722] Display 0: 1600x1200x32@60 18:03:22 INFO - 2017-06-10 18:03:22.828 screenresolution[1689:19725] starting screenresolution argv=screenresolution list 18:03:22 INFO - Available Modes on Display 0 18:03:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:03:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:03:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:03:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:03:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:03:23 INFO - Intel Iris: 18:03:23 INFO - Chipset Model: Intel Iris 18:03:23 INFO - Type: GPU 18:03:23 INFO - Bus: Built-In 18:03:23 INFO - VRAM (Dynamic, Max): 1536 MB 18:03:23 INFO - Vendor: Intel (0x8086) 18:03:23 INFO - Device ID: 0x0a2e 18:03:23 INFO - Revision ID: 0x0009 18:03:23 INFO - Displays: 18:03:23 INFO - Display: 18:03:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:03:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:03:23 INFO - Display Serial Number: 1600x1200 60 18:03:23 INFO - Main Display: Yes 18:03:23 INFO - Mirror: Off 18:03:23 INFO - Online: Yes 18:03:23 INFO - Rotation: Supported 18:03:23 INFO - Return code: 0 18:03:23 INFO - Running post-action listener: _resource_record_post_action 18:03:23 INFO - [mozharness: 2017-06-11 01:03:23.066646Z] Finished run-tests step (success) 18:03:23 INFO - Running post-run listener: _resource_record_post_run 18:03:23 INFO - Total resource usage - Wall time: 1258s; CPU: 53.0%; Read bytes: 7717888; Write bytes: 580075520; Read time: 68; Write time: 2059 18:03:23 INFO - TinderboxPrint: CPU usage
53.1% 18:03:23 INFO - TinderboxPrint: I/O read bytes / time
7,717,888 / 68 18:03:23 INFO - TinderboxPrint: I/O write bytes / time
580,075,520 / 2,059 18:03:23 INFO - TinderboxPrint: CPU idle
2,360.8 (46.9%) 18:03:23 INFO - TinderboxPrint: CPU system
1,017.0 (20.2%) 18:03:23 INFO - TinderboxPrint: CPU user
1,655.9 (32.9%) 18:03:23 INFO - TinderboxPrint: Swap in / out
282,988,544 / 0 18:03:23 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 234404352; Write bytes: 231647232; Read time: 17797; Write time: 522 18:03:23 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:03:23 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 6759424; Write bytes: 322213888; Read time: 54; Write time: 1478 18:03:23 INFO - Running post-run listener: _upload_blobber_files 18:03:23 INFO - Blob upload gear active. 18:03:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:03:23 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 18:03:23 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'] 18:03:23 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 18:03:23 INFO - (blobuploader) - INFO - Open directory for files ... 18:03:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:03:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:03:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:03:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:03:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:03:25 INFO - (blobuploader) - INFO - Done attempting. 18:03:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:03:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:03:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:03:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:03:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:03:26 INFO - (blobuploader) - INFO - Done attempting. 18:03:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:03:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:03:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:03:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:03:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:03:27 INFO - (blobuploader) - INFO - Done attempting. 18:03:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:03:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:03:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:03:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:03:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:03:29 INFO - (blobuploader) - INFO - Done attempting. 18:03:29 INFO - (blobuploader) - INFO - Iteration through files over. 18:03:29 INFO - Return code: 0 18:03:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:03:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:03:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/9653224ce8b689ec8b5a01aba45c404b55c4ef4e2e98b74c8a41bc09c976e8b72fd0ca80b85ff5697a7e8dc9ba227fcba5cd427217f7237f080d77db64cd341f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0de54f32290fd0771c7e7550b398fbe689eb9078aca0ed7e800b633463f777af7942eab04e81c670dd8e06e817c592d72bd1fef5213d43d15da7d9ffe4908d51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/3ea9b55fdb11f30d57c200fc4ef8cb4ba918ad94c94792195b98425e6695587a7e76e6668b12490f01301295f179ebbb3d90f827cd4a782981eed5b002f1bcb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/7639bf5bfe6bcd48932664178775d7fe3c88e958198258169c4f65f75d185ab1cfccb15ece7e9bee22d099ee263a30f5f82b66579d251cefd8eb7f59eb0c4fa9"} 18:03:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:03:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:03:29 INFO - Contents: 18:03:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/9653224ce8b689ec8b5a01aba45c404b55c4ef4e2e98b74c8a41bc09c976e8b72fd0ca80b85ff5697a7e8dc9ba227fcba5cd427217f7237f080d77db64cd341f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0de54f32290fd0771c7e7550b398fbe689eb9078aca0ed7e800b633463f777af7942eab04e81c670dd8e06e817c592d72bd1fef5213d43d15da7d9ffe4908d51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/3ea9b55fdb11f30d57c200fc4ef8cb4ba918ad94c94792195b98425e6695587a7e76e6668b12490f01301295f179ebbb3d90f827cd4a782981eed5b002f1bcb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/7639bf5bfe6bcd48932664178775d7fe3c88e958198258169c4f65f75d185ab1cfccb15ece7e9bee22d099ee263a30f5f82b66579d251cefd8eb7f59eb0c4fa9"} 18:03:29 INFO - Running post-run listener: copy_logs_to_upload_dir 18:03:29 INFO - Copying logs to upload dir... 18:03:29 INFO - mkdir: /builds/slave/test/build/upload/logs 18:03:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.688216 ========= master_lag: 0.10 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-10 18:03:29.390035) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-10 18:03:29.393250) ========= 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.V2Ray8zSWD/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.YltVRXN12H/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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/9653224ce8b689ec8b5a01aba45c404b55c4ef4e2e98b74c8a41bc09c976e8b72fd0ca80b85ff5697a7e8dc9ba227fcba5cd427217f7237f080d77db64cd341f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0de54f32290fd0771c7e7550b398fbe689eb9078aca0ed7e800b633463f777af7942eab04e81c670dd8e06e817c592d72bd1fef5213d43d15da7d9ffe4908d51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/3ea9b55fdb11f30d57c200fc4ef8cb4ba918ad94c94792195b98425e6695587a7e76e6668b12490f01301295f179ebbb3d90f827cd4a782981eed5b002f1bcb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/7639bf5bfe6bcd48932664178775d7fe3c88e958198258169c4f65f75d185ab1cfccb15ece7e9bee22d099ee263a30f5f82b66579d251cefd8eb7f59eb0c4fa9"} build_url:https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011361 build_url: 'https://queue.taskcluster.net/v1/task/CUrVaMrjTVuA6YwGxUsolw/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/9653224ce8b689ec8b5a01aba45c404b55c4ef4e2e98b74c8a41bc09c976e8b72fd0ca80b85ff5697a7e8dc9ba227fcba5cd427217f7237f080d77db64cd341f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0de54f32290fd0771c7e7550b398fbe689eb9078aca0ed7e800b633463f777af7942eab04e81c670dd8e06e817c592d72bd1fef5213d43d15da7d9ffe4908d51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/3ea9b55fdb11f30d57c200fc4ef8cb4ba918ad94c94792195b98425e6695587a7e76e6668b12490f01301295f179ebbb3d90f827cd4a782981eed5b002f1bcb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/7639bf5bfe6bcd48932664178775d7fe3c88e958198258169c4f65f75d185ab1cfccb15ece7e9bee22d099ee263a30f5f82b66579d251cefd8eb7f59eb0c4fa9"}' ========= master_lag: 0.69 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-10 18:03:30.093430) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 18:03:30.093776) ========= 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.V2Ray8zSWD/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.YltVRXN12H/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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.005088 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-10 18:03:30.142853) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-10 18:03:30.143171) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-10 18:03:30.143532) ========= ========= Total master_lag: 0.90 =========