builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0030 starttime: 1496894761.32 results: success (0) revision: 7efda263a842e60cd0cc00b3c4a7058c65590702 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-07 21:06:01.316269) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-07 21:06:01.316701) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-07 21:06:01.316988) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-07 21:06:01.345253) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 21:06:01.345584) ========= 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.eRkDl5lmQ1/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.HCETlepvW5/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-07 21:06:01-- 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% 149M=0s 2017-06-07 21:06:01 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.165348 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 21:06:01.533336) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 21:06:01.533639) ========= 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.eRkDl5lmQ1/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.HCETlepvW5/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.033315 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 21:06:01.594129) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-07 21:06:01.594446) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7efda263a842e60cd0cc00b3c4a7058c65590702 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 7efda263a842e60cd0cc00b3c4a7058c65590702 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eRkDl5lmQ1/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.HCETlepvW5/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-07 21:06:01,691 truncating revision to first 12 chars 2017-06-07 21:06:01,691 Setting DEBUG logging. 2017-06-07 21:06:01,691 attempt 1/10 2017-06-07 21:06:01,691 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7efda263a842?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-07 21:06:02,750 unpacking tar archive at: mozilla-central-7efda263a842/testing/mozharness/ program finished with exit code 0 elapsedTime=1.342562 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-07 21:06:02.963047) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-07 21:06:02.963316) ========= script_repo_revision: 7efda263a842e60cd0cc00b3c4a7058c65590702 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-07 21:06:02.963645) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-07 21:06:02.963930) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-07 21:06:02.988600) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-06-07 21:06:02.988899) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eRkDl5lmQ1/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.HCETlepvW5/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:06:03 INFO - MultiFileLogger online at 20170607 21:06:03 in /builds/slave/test 21:06:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:06:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:06:03 INFO - {'append_to_log': False, 21:06:03 INFO - 'base_work_dir': '/builds/slave/test', 21:06:03 INFO - 'blob_upload_branch': 'Firefox', 21:06:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:06:03 INFO - 'branch': 'Firefox', 21:06:03 INFO - 'buildbot_json_path': 'buildprops.json', 21:06:03 INFO - 'config_files': ('talos/mac_config.py',), 21:06:03 INFO - 'default_actions': ('clobber', 21:06:03 INFO - 'read-buildbot-config', 21:06:03 INFO - 'download-and-extract', 21:06:03 INFO - 'populate-webroot', 21:06:03 INFO - 'create-virtualenv', 21:06:03 INFO - 'install', 21:06:03 INFO - 'setup-mitmproxy', 21:06:03 INFO - 'run-tests'), 21:06:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:06:03 INFO - 'download_minidump_stackwalk': True, 21:06:03 INFO - 'download_symbols': 'ondemand', 21:06:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:06:03 INFO - 'tooltool.py': '/tools/tooltool.py', 21:06:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:06:03 INFO - '/tools/misc-python/virtualenv.py')}, 21:06:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:06:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:06:03 INFO - 'gecko_profile': False, 21:06:03 INFO - 'gecko_profile_interval': 0, 21:06:03 INFO - 'installer_path': 'installer.exe', 21:06:03 INFO - 'log_level': 'info', 21:06:03 INFO - 'log_name': 'talos', 21:06:03 INFO - 'log_to_console': True, 21:06:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:06:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:06:03 INFO - 'opt_config_files': (), 21:06:03 INFO - 'pip_index': False, 21:06:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:06:03 INFO - 'cmd': ('bash', 21:06:03 INFO - '-c', 21:06:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:06:03 INFO - 'enabled': True, 21:06:03 INFO - 'halt_on_failure': False, 21:06:03 INFO - 'name': 'check_screen_resolution'},), 21:06:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:06:03 INFO - 'cmd': ('bash', 21:06:03 INFO - '-c', 21:06:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:06:03 INFO - 'enabled': True, 21:06:03 INFO - 'halt_on_failure': False, 21:06:03 INFO - 'name': 'check_screen_resolution'},), 21:06:03 INFO - 'run_cmd_checks_enabled': True, 21:06:03 INFO - 'suite': 'dromaeojs-e10s', 21:06:03 INFO - 'system_bits': '32', 21:06:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:06:03 INFO - 'title': 't-yosemite-r7-0030', 21:06:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:06:03 INFO - 'use_talos_json': True, 21:06:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:06:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:06:03 INFO - 'work_dir': 'build'} 21:06:03 INFO - [mozharness: 2017-06-08 04:06:03.202247Z] Running clobber step. 21:06:03 INFO - Running pre-action listener: _resource_record_pre_action 21:06:03 INFO - Running main action method: clobber 21:06:03 INFO - rmtree: /builds/slave/test/build 21:06:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:06:05 INFO - Running post-action listener: _resource_record_post_action 21:06:05 INFO - [mozharness: 2017-06-08 04:06:05.793264Z] Finished clobber step (success) 21:06:05 INFO - [mozharness: 2017-06-08 04:06:05.793426Z] Running read-buildbot-config step. 21:06:05 INFO - Running pre-action listener: _resource_record_pre_action 21:06:05 INFO - Running main action method: read_buildbot_config 21:06:05 INFO - Using buildbot properties: 21:06:05 INFO - { 21:06:05 INFO - "project": "", 21:06:05 INFO - "product": "firefox", 21:06:05 INFO - "who": "ryanvm@gmail.com", 21:06:05 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 21:06:05 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 21:06:05 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 21:06:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 21:06:05 INFO - "stage_platform": "macosx64", 21:06:05 INFO - "basedir": "/builds/slave/test", 21:06:05 INFO - "buildnumber": 233, 21:06:05 INFO - "platform": "yosemite_r7", 21:06:05 INFO - "parent_task_id": "ak9IUoiDQP6NNCSZ8Fj0Ng", 21:06:05 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 21:06:05 INFO - "slavebuilddir": "test", 21:06:05 INFO - "taskId": "RnQHYo0lSp6E-RzliVp4jw", 21:06:05 INFO - "branch": "mozilla-central", 21:06:05 INFO - "script_repo_revision": "production", 21:06:05 INFO - "revision": "7efda263a842e60cd0cc00b3c4a7058c65590702", 21:06:05 INFO - "slavename": "t-yosemite-r7-0030", 21:06:05 INFO - "repo_path": "mozilla-central" 21:06:05 INFO - } 21:06:05 INFO - Finding installer, test and symbols from parent task. 21:06:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RnQHYo0lSp6E-RzliVp4jw'}, attempt #1 21:06:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RnQHYo0lSp6E-RzliVp4jw'}, attempt #1 21:06:07 INFO - Task dependencies: PzZ7EurRQrORkFO3ME1-Qw ak9IUoiDQP6NNCSZ8Fj0Ng 21:06:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng'}, attempt #1 21:06:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:06:07 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/test_packages.json 21:06:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/target.crashreporter-symbols.zip 21:06:07 INFO - Running post-action listener: _resource_record_post_action 21:06:07 INFO - [mozharness: 2017-06-08 04:06:07.537904Z] Finished read-buildbot-config step (success) 21:06:07 INFO - [mozharness: 2017-06-08 04:06:07.538049Z] Running download-and-extract step. 21:06:07 INFO - Running pre-action listener: _resource_record_pre_action 21:06:07 INFO - Running main action method: download_and_extract 21:06:07 INFO - mkdir: /builds/slave/test/build/tests 21:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:06:07 INFO - trying https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 21:06:07 INFO - Downloading https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/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 21:06:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/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 21:06:08 INFO - Downloaded 1328 bytes. 21:06:08 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 21:06:08 INFO - Using the following test package requirements: 21:06:08 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:06:08 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 21:06:08 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 21:06:08 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:06:08 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 21:06:08 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:06:08 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 21:06:08 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:06:08 INFO - u'jsshell-mac.zip'], 21:06:08 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:06:08 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 21:06:08 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 21:06:08 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:06:08 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 21:06:08 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:06:08 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 21:06:08 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:06:08 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 21:06:08 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:06:08 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 21:06:08 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:06:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 21:06:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:06:08 INFO - Fetch https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 21:06:11 INFO - Content-Length response header: 57645195 21:06:11 INFO - Bytes received: 57645195 21:06:29 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 21:06:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 21:06:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:06:29 INFO - Fetch https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 21:06:32 INFO - Content-Length response header: 57645195 21:06:32 INFO - Bytes received: 57645195 21:06:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 21:06:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:06:49 INFO - Fetch https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 21:06:50 INFO - Content-Length response header: 14447563 21:06:50 INFO - Bytes received: 14447563 21:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:06:50 INFO - trying https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:06:50 INFO - Downloading https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 21:06:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/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 21:06:52 INFO - Downloaded 68072506 bytes. 21:06:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:06:52 INFO - mkdir: /builds/slave/test/properties 21:06:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:06:52 INFO - Writing to file /builds/slave/test/properties/build_url 21:06:52 INFO - Contents: 21:06:52 INFO - build_url:https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:06:52 INFO - Running post-action listener: _resource_record_post_action 21:06:52 INFO - Running post-action listener: set_extra_try_arguments 21:06:52 INFO - [mozharness: 2017-06-08 04:06:52.484337Z] Finished download-and-extract step (success) 21:06:52 INFO - [mozharness: 2017-06-08 04:06:52.484454Z] Running populate-webroot step. 21:06:52 INFO - Running pre-action listener: _resource_record_pre_action 21:06:52 INFO - Running main action method: populate_webroot 21:06:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:06:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:06:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:06:52 INFO - '--tppagecycles', 21:06:52 INFO - '7'], 21:06:52 INFO - 'tests': ['tsvgm']}}, 21:06:52 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:06:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:06:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:06:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:06:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:06:52 INFO - 'talos_options': ['--disable-e10s'], 21:06:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:06:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:06:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:06:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:06:52 INFO - 'talos_options': ['--disable-e10s'], 21:06:52 INFO - 'tests': ['damp', 'tps']}, 21:06:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:06:52 INFO - 'tests': ['damp', 'tps']}, 21:06:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:06:52 INFO - 'tests': ['dromaeo_dom']}, 21:06:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:06:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:06:52 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 21:06:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:06:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 21:06:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 21:06:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:06:52 INFO - 'tests': ['a11yr', 21:06:52 INFO - 'ts_paint', 21:06:52 INFO - 'tpaint', 21:06:52 INFO - 'sessionrestore', 21:06:52 INFO - 'sessionrestore_no_auto_restore', 21:06:52 INFO - 'tabpaint']}, 21:06:52 INFO - 'other-e10s': {'tests': ['a11yr', 21:06:52 INFO - 'ts_paint', 21:06:52 INFO - 'tpaint', 21:06:52 INFO - 'sessionrestore', 21:06:52 INFO - 'sessionrestore_no_auto_restore', 21:06:52 INFO - 'tabpaint']}, 21:06:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:06:52 INFO - 'ts_paint', 21:06:52 INFO - 'tpaint', 21:06:52 INFO - 'sessionrestore', 21:06:52 INFO - 'sessionrestore_no_auto_restore', 21:06:52 INFO - 'tabpaint']}, 21:06:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:06:52 INFO - 'ts_paint', 21:06:52 INFO - 'tpaint', 21:06:52 INFO - 'sessionrestore', 21:06:52 INFO - 'sessionrestore_no_auto_restore', 21:06:52 INFO - 'tabpaint']}, 21:06:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:06:52 INFO - 'tests': ['a11yr', 21:06:52 INFO - 'ts_paint', 21:06:52 INFO - 'tpaint', 21:06:52 INFO - 'sessionrestore', 21:06:52 INFO - 'sessionrestore_no_auto_restore', 21:06:52 INFO - 'tabpaint']}, 21:06:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:06:52 INFO - 'tests': ['a11yr', 21:06:52 INFO - 'ts_paint', 21:06:52 INFO - 'tpaint', 21:06:52 INFO - 'sessionrestore', 21:06:52 INFO - 'sessionrestore_no_auto_restore', 21:06:52 INFO - 'tabpaint']}, 21:06:52 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 21:06:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 21:06:52 INFO - 'bloom_basic_ref']}, 21:06:52 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 21:06:52 INFO - 'talos_options': ['--mitmproxy', 21:06:52 INFO - 'mitmproxy-recording-1.mp', 21:06:52 INFO - '--firstNonBlankPaint'], 21:06:52 INFO - 'tests': ['Quantum_1']}, 21:06:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:06:52 INFO - 'tests': ['tsvgx', 21:06:52 INFO - 'tsvgr_opacity', 21:06:52 INFO - 'tart', 21:06:52 INFO - 'tscrollx', 21:06:52 INFO - 'cart', 21:06:52 INFO - 'tsvg_static']}, 21:06:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:06:52 INFO - 'tsvgr_opacity', 21:06:52 INFO - 'tart', 21:06:52 INFO - 'tscrollx', 21:06:52 INFO - 'cart', 21:06:52 INFO - 'tsvg_static']}, 21:06:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:06:52 INFO - 'talos_options': ['--disable-e10s'], 21:06:52 INFO - 'tests': ['tp5o']}, 21:06:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:06:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:06:52 INFO - 'talos_options': ['--disable-e10s', 21:06:52 INFO - '--xperf_path', 21:06:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:06:52 INFO - 'tests': ['tp5n']}, 21:06:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:06:52 INFO - 'talos_options': ['--xperf_path', 21:06:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:06:52 INFO - 'tests': ['tp5n']}}, 21:06:52 INFO - 'talos.zip': {'path': '', 21:06:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:06:52 INFO - Running post-action listener: _resource_record_post_action 21:06:52 INFO - [mozharness: 2017-06-08 04:06:52.506424Z] Finished populate-webroot step (success) 21:06:52 INFO - [mozharness: 2017-06-08 04:06:52.506565Z] Running create-virtualenv step. 21:06:52 INFO - Running pre-action listener: _resource_record_pre_action 21:06:52 INFO - Running main action method: create_virtualenv 21:06:52 INFO - Creating virtualenv /builds/slave/test/build/venv 21:06:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:06:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:06:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 21:06:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:06:52 INFO - Using real prefix '/tools/python27' 21:06:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:06:53 INFO - Installing distribute.............................................................................................................................................................................................done. 21:06:56 INFO - Installing pip.................done. 21:06:56 INFO - Return code: 0 21:06:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:06:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:06:56 INFO - Reading from file tmpfile_stdout 21:06:56 INFO - Output received: 21:06:56 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) 21:06:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:06:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:06:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98d84db070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c508d30>, '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 0x10c4d2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f9be8>, '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.eRkDl5lmQ1/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.HCETlepvW5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:06:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:06:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:06:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eRkDl5lmQ1/Render', 21:06:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:06:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:06:56 INFO - 'HOME': '/Users/cltbld', 21:06:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:06:56 INFO - 'LOGNAME': 'cltbld', 21:06:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:06:56 INFO - 'MOZ_NO_REMOTE': '1', 21:06:56 INFO - 'NO_EM_RESTART': '1', 21:06:56 INFO - 'PAGER': '/bin/cat', 21:06:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:06:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:06:56 INFO - 'PWD': '/builds/slave/test', 21:06:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:06:56 INFO - 'SHELL': '/bin/bash', 21:06:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HCETlepvW5/Listeners', 21:06:56 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:06:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:06:56 INFO - 'USER': 'cltbld', 21:06:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:06:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:06:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:06:56 INFO - 'XPC_FLAGS': '0x0', 21:06:56 INFO - 'XPC_SERVICE_NAME': '0', 21:06:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:06:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:06:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:06:56 INFO - Cleaning up... 21:06:56 INFO - Return code: 0 21:06:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:06:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:06:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98d84db070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c508d30>, '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 0x10c4d2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f9be8>, '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.eRkDl5lmQ1/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.HCETlepvW5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:06:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:06:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:06:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eRkDl5lmQ1/Render', 21:06:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:06:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:06:56 INFO - 'HOME': '/Users/cltbld', 21:06:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:06:56 INFO - 'LOGNAME': 'cltbld', 21:06:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:06:56 INFO - 'MOZ_NO_REMOTE': '1', 21:06:56 INFO - 'NO_EM_RESTART': '1', 21:06:56 INFO - 'PAGER': '/bin/cat', 21:06:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:06:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:06:56 INFO - 'PWD': '/builds/slave/test', 21:06:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:06:56 INFO - 'SHELL': '/bin/bash', 21:06:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HCETlepvW5/Listeners', 21:06:56 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:06:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:06:56 INFO - 'USER': 'cltbld', 21:06:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:06:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:06:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:06:56 INFO - 'XPC_FLAGS': '0x0', 21:06:56 INFO - 'XPC_SERVICE_NAME': '0', 21:06:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:06:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:06:57 INFO - Downloading/unpacking psutil>=3.1.1 21:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:06:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:06:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:06:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:06:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:06:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:06:59 INFO - Installing collected packages: psutil 21:06:59 INFO - Running setup.py install for psutil 21:06:59 INFO - building 'psutil._psutil_osx' extension 21:06:59 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 21:07:00 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 21:07:00 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 21:07:00 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 21:07:00 INFO - building 'psutil._psutil_posix' extension 21:07:00 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 21:07:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:07:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:07:00 INFO - ^ 21:07:00 INFO - 1 warning generated. 21:07:00 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 21:07:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:07:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:07:00 INFO - Successfully installed psutil 21:07:00 INFO - Cleaning up... 21:07:00 INFO - Return code: 0 21:07:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:07:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98d84db070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c508d30>, '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 0x10c4d2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f9be8>, '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.eRkDl5lmQ1/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.HCETlepvW5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:07:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:07:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:07:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eRkDl5lmQ1/Render', 21:07:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:00 INFO - 'HOME': '/Users/cltbld', 21:07:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:00 INFO - 'LOGNAME': 'cltbld', 21:07:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:00 INFO - 'MOZ_NO_REMOTE': '1', 21:07:00 INFO - 'NO_EM_RESTART': '1', 21:07:00 INFO - 'PAGER': '/bin/cat', 21:07:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:00 INFO - 'PWD': '/builds/slave/test', 21:07:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:00 INFO - 'SHELL': '/bin/bash', 21:07:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HCETlepvW5/Listeners', 21:07:00 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:07:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:00 INFO - 'USER': 'cltbld', 21:07:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:00 INFO - 'XPC_FLAGS': '0x0', 21:07:00 INFO - 'XPC_SERVICE_NAME': '0', 21:07:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:07:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:07:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:07:02 INFO - Installing collected packages: mozsystemmonitor 21:07:02 INFO - Running setup.py install for mozsystemmonitor 21:07:02 INFO - Successfully installed mozsystemmonitor 21:07:02 INFO - Cleaning up... 21:07:02 INFO - Return code: 0 21:07:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:02 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 0x10c7b2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98d84db070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c508d30>, '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 0x10c4d2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f9be8>, '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.eRkDl5lmQ1/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.HCETlepvW5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:07:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:07:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:07:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eRkDl5lmQ1/Render', 21:07:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:02 INFO - 'HOME': '/Users/cltbld', 21:07:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:02 INFO - 'LOGNAME': 'cltbld', 21:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:02 INFO - 'MOZ_NO_REMOTE': '1', 21:07:02 INFO - 'NO_EM_RESTART': '1', 21:07:02 INFO - 'PAGER': '/bin/cat', 21:07:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:02 INFO - 'PWD': '/builds/slave/test', 21:07:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:02 INFO - 'SHELL': '/bin/bash', 21:07:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HCETlepvW5/Listeners', 21:07:02 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:07:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:02 INFO - 'USER': 'cltbld', 21:07:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:02 INFO - 'XPC_FLAGS': '0x0', 21:07:02 INFO - 'XPC_SERVICE_NAME': '0', 21:07:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:02 INFO - Downloading/unpacking jsonschema==2.5.1 21:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:07:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:04 INFO - Downloading functools32-3.2.3-2.tar.gz 21:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:07:04 INFO - warning: no files found matching '*.txt' 21:07:04 INFO - no previously-included directories found matching 'build' 21:07:04 INFO - no previously-included directories found matching 'dist' 21:07:04 INFO - no previously-included directories found matching '.git*' 21:07:04 INFO - Installing collected packages: jsonschema, functools32 21:07:04 INFO - Running setup.py install for functools32 21:07:04 INFO - warning: no files found matching '*.txt' 21:07:04 INFO - no previously-included directories found matching 'build' 21:07:04 INFO - no previously-included directories found matching 'dist' 21:07:04 INFO - no previously-included directories found matching '.git*' 21:07:04 INFO - Successfully installed jsonschema functools32 21:07:04 INFO - Cleaning up... 21:07:04 INFO - Return code: 0 21:07:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:07:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c7b2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98d84db070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c508d30>, '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 0x10c4d2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f9be8>, '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.eRkDl5lmQ1/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.HCETlepvW5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:07:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:07:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:07:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eRkDl5lmQ1/Render', 21:07:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:04 INFO - 'HOME': '/Users/cltbld', 21:07:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:04 INFO - 'LOGNAME': 'cltbld', 21:07:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:04 INFO - 'MOZ_NO_REMOTE': '1', 21:07:04 INFO - 'NO_EM_RESTART': '1', 21:07:04 INFO - 'PAGER': '/bin/cat', 21:07:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:04 INFO - 'PWD': '/builds/slave/test', 21:07:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:04 INFO - 'SHELL': '/bin/bash', 21:07:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HCETlepvW5/Listeners', 21:07:04 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:07:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:04 INFO - 'USER': 'cltbld', 21:07:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:04 INFO - 'XPC_FLAGS': '0x0', 21:07:04 INFO - 'XPC_SERVICE_NAME': '0', 21:07:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:07:05 INFO - Cleaning up... 21:07:05 INFO - Return code: 0 21:07:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07: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', '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 0x10c7b2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98d84db070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c508d30>, '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 0x10c4d2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f9be8>, '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.eRkDl5lmQ1/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.HCETlepvW5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:07: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:07: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 blobuploader==1.2.4 21:07:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eRkDl5lmQ1/Render', 21:07:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:05 INFO - 'HOME': '/Users/cltbld', 21:07:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:05 INFO - 'LOGNAME': 'cltbld', 21:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:05 INFO - 'MOZ_NO_REMOTE': '1', 21:07:05 INFO - 'NO_EM_RESTART': '1', 21:07:05 INFO - 'PAGER': '/bin/cat', 21:07:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:05 INFO - 'PWD': '/builds/slave/test', 21:07:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:05 INFO - 'SHELL': '/bin/bash', 21:07:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HCETlepvW5/Listeners', 21:07:05 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:07:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:05 INFO - 'USER': 'cltbld', 21:07:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:05 INFO - 'XPC_FLAGS': '0x0', 21:07:05 INFO - 'XPC_SERVICE_NAME': '0', 21:07:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:05 INFO - Downloading/unpacking blobuploader==1.2.4 21:07: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 21:07: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 21:07: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 21:07: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 21:07:06 INFO - Downloading blobuploader-1.2.4.tar.gz 21:07:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:07:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:07:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:07:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:07 INFO - Downloading docopt-0.6.1.tar.gz 21:07:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:07:07 INFO - Installing collected packages: blobuploader, requests, docopt 21:07:07 INFO - Running setup.py install for blobuploader 21:07:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:07:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:07:07 INFO - Running setup.py install for requests 21:07:08 INFO - Running setup.py install for docopt 21:07:08 INFO - Successfully installed blobuploader requests docopt 21:07:08 INFO - Cleaning up... 21:07:08 INFO - Return code: 0 21:07:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:08 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 0x10c7b2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98d84db070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c508d30>, '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 0x10c4d2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f9be8>, '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.eRkDl5lmQ1/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.HCETlepvW5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:07:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:07:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:07:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eRkDl5lmQ1/Render', 21:07:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:08 INFO - 'HOME': '/Users/cltbld', 21:07:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:08 INFO - 'LOGNAME': 'cltbld', 21:07:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:08 INFO - 'MOZ_NO_REMOTE': '1', 21:07:08 INFO - 'NO_EM_RESTART': '1', 21:07:08 INFO - 'PAGER': '/bin/cat', 21:07:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:08 INFO - 'PWD': '/builds/slave/test', 21:07:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:08 INFO - 'SHELL': '/bin/bash', 21:07:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HCETlepvW5/Listeners', 21:07:08 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:07:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:08 INFO - 'USER': 'cltbld', 21:07:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:08 INFO - 'XPC_FLAGS': '0x0', 21:07:08 INFO - 'XPC_SERVICE_NAME': '0', 21:07:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:07:08 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-qJRLSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:07:08 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-QRWFGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:07:08 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-su7qqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:07:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-P3NB3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:07:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-zpStvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:07:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Z4Vm0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:07:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-iRkUYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:07:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-QRy1mG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:07:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-liA2Jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:07:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-HC6etn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:07:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-6OQSCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:07:09 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-WVgSXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:07:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Jje9XB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:07:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-gzw6wQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:07:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Ic1sPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:07:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Hd8fZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:07:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-JNyD8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:07:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:07:10 INFO - Running setup.py install for manifestparser 21:07:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:07:10 INFO - Running setup.py install for mozcrash 21:07:10 INFO - Running setup.py install for mozdebug 21:07:10 INFO - Running setup.py install for mozdevice 21:07:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:07:11 INFO - Running setup.py install for mozfile 21:07:11 INFO - Running setup.py install for mozhttpd 21:07:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:07:11 INFO - Running setup.py install for mozinfo 21:07:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:07:11 INFO - Running setup.py install for mozInstall 21:07:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:07:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:07:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:07:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:07:11 INFO - Running setup.py install for mozleak 21:07:11 INFO - Running setup.py install for mozlog 21:07:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:07:12 INFO - Running setup.py install for moznetwork 21:07:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:07:12 INFO - Running setup.py install for mozprocess 21:07:12 INFO - Running setup.py install for mozprofile 21:07:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:07:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:07:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:07:12 INFO - Running setup.py install for mozrunner 21:07:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:07:12 INFO - Running setup.py install for mozscreenshot 21:07:12 INFO - Running setup.py install for moztest 21:07:12 INFO - Running setup.py install for mozversion 21:07:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:07:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:07:13 INFO - Cleaning up... 21:07:13 INFO - Return code: 0 21:07:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98d84db070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c508d30>, '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 0x10c4d2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f9be8>, '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.eRkDl5lmQ1/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.HCETlepvW5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:07:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:07:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:07:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eRkDl5lmQ1/Render', 21:07:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:13 INFO - 'HOME': '/Users/cltbld', 21:07:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:13 INFO - 'LOGNAME': 'cltbld', 21:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:13 INFO - 'MOZ_NO_REMOTE': '1', 21:07:13 INFO - 'NO_EM_RESTART': '1', 21:07:13 INFO - 'PAGER': '/bin/cat', 21:07:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:13 INFO - 'PWD': '/builds/slave/test', 21:07:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:13 INFO - 'SHELL': '/bin/bash', 21:07:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HCETlepvW5/Listeners', 21:07:13 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:07:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:13 INFO - 'USER': 'cltbld', 21:07:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:13 INFO - 'XPC_FLAGS': '0x0', 21:07:13 INFO - 'XPC_SERVICE_NAME': '0', 21:07:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:07:13 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-oSAt6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:07:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:07:13 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-2u89ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:07:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:07:13 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-s2XMAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:07:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:07:13 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-GfqxbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:07:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:07:13 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-8GftzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:07:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:07:13 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-vT4rRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:07:14 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-kFP8Vg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:07:14 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-xbv4E4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:07:14 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-X2Pf3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:07:14 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-n4jHeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:07:14 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)) 21:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:07:14 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-mi1DRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 21:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:07:14 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-39yzUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:07:14 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-BB_R9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:07:14 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-S3JnE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:07:14 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-mppfFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:07:14 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-tciiyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:07:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:07:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:07:15 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-TzdwDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:07:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:07:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:07:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:07:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:07:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:07:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:07:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:16 INFO - Downloading blessings-1.6.tar.gz 21:07:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:07:16 INFO - Installing collected packages: blessings 21:07:16 INFO - Running setup.py install for blessings 21:07:16 INFO - Successfully installed blessings 21:07:16 INFO - Cleaning up... 21:07:16 INFO - Return code: 0 21:07:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:07:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:07:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:07:17 INFO - Reading from file tmpfile_stdout 21:07:17 INFO - Current package versions: 21:07:17 INFO - blessings == 1.6 21:07:17 INFO - blobuploader == 1.2.4 21:07:17 INFO - docopt == 0.6.1 21:07:17 INFO - functools32 == 3.2.3-2 21:07:17 INFO - jsonschema == 2.5.1 21:07:17 INFO - manifestparser == 1.1 21:07:17 INFO - mozInstall == 1.12 21:07:17 INFO - mozcrash == 1.0 21:07:17 INFO - mozdebug == 0.1 21:07:17 INFO - mozdevice == 0.51 21:07:17 INFO - mozfile == 1.2 21:07:17 INFO - mozhttpd == 0.7 21:07:17 INFO - mozinfo == 0.10 21:07:17 INFO - mozleak == 0.1 21:07:17 INFO - mozlog == 3.4 21:07:17 INFO - moznetwork == 0.27 21:07:17 INFO - mozprocess == 0.25 21:07:17 INFO - mozprofile == 0.28 21:07:17 INFO - mozrunner == 6.13 21:07:17 INFO - mozscreenshot == 0.1 21:07:17 INFO - mozsystemmonitor == 0.3 21:07:17 INFO - moztest == 0.8 21:07:17 INFO - mozversion == 1.4 21:07:17 INFO - psutil == 3.1.1 21:07:17 INFO - requests == 1.2.3 21:07:17 INFO - wsgiref == 0.1.2 21:07:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:07: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')]} 21:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07: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')]} 21:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98d84db070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c508d30>, '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 0x10c4d2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f9be8>, '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.eRkDl5lmQ1/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.HCETlepvW5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:07:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:07:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eRkDl5lmQ1/Render', 21:07:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:17 INFO - 'HOME': '/Users/cltbld', 21:07:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:17 INFO - 'LOGNAME': 'cltbld', 21:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:17 INFO - 'MOZ_NO_REMOTE': '1', 21:07:17 INFO - 'NO_EM_RESTART': '1', 21:07:17 INFO - 'PAGER': '/bin/cat', 21:07:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:17 INFO - 'PWD': '/builds/slave/test', 21:07:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:17 INFO - 'SHELL': '/bin/bash', 21:07:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HCETlepvW5/Listeners', 21:07:17 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:07:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:17 INFO - 'USER': 'cltbld', 21:07:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:17 INFO - 'XPC_FLAGS': '0x0', 21:07:17 INFO - 'XPC_SERVICE_NAME': '0', 21:07:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:07:17 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 21:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:18 INFO - Downloading marionette_driver-1.4.0.tar.gz 21:07:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 21:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:07:18 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)) 21:07:18 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)) 21:07:18 INFO - Installing collected packages: marionette-driver 21:07:18 INFO - Running setup.py install for marionette-driver 21:07:19 INFO - Successfully installed marionette-driver 21:07:19 INFO - Cleaning up... 21:07:19 INFO - Return code: 0 21:07:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:07:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98d84db070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c508d30>, '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 0x10c4d2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f9be8>, '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.eRkDl5lmQ1/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.HCETlepvW5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 21:07:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:07:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:07:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eRkDl5lmQ1/Render', 21:07:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:19 INFO - 'HOME': '/Users/cltbld', 21:07:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:19 INFO - 'LOGNAME': 'cltbld', 21:07:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:19 INFO - 'MOZ_NO_REMOTE': '1', 21:07:19 INFO - 'NO_EM_RESTART': '1', 21:07:19 INFO - 'PAGER': '/bin/cat', 21:07:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:19 INFO - 'PWD': '/builds/slave/test', 21:07:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:19 INFO - 'SHELL': '/bin/bash', 21:07:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HCETlepvW5/Listeners', 21:07:19 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:07:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:19 INFO - 'USER': 'cltbld', 21:07:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:19 INFO - 'XPC_FLAGS': '0x0', 21:07:19 INFO - 'XPC_SERVICE_NAME': '0', 21:07:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:07:19 INFO - Cleaning up... 21:07:19 INFO - Return code: 0 21:07:19 INFO - Running post-action listener: _resource_record_post_action 21:07:19 INFO - Running post-action listener: _start_resource_monitoring 21:07:19 INFO - Starting resource monitoring. 21:07:19 INFO - [mozharness: 2017-06-08 04:07:19.393825Z] Finished create-virtualenv step (success) 21:07:19 INFO - [mozharness: 2017-06-08 04:07:19.394811Z] Running install step. 21:07:19 INFO - Running pre-action listener: _resource_record_pre_action 21:07:19 INFO - Running main action method: install 21:07:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:07:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:07:19 INFO - Reading from file tmpfile_stdout 21:07:19 INFO - Detecting whether we're running mozinstall >=1.0... 21:07:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:07:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:07:19 INFO - Reading from file tmpfile_stdout 21:07:19 INFO - Output received: 21:07:19 INFO - Usage: mozinstall [options] installer 21:07:19 INFO - Options: 21:07:19 INFO - -h, --help show this help message and exit 21:07:19 INFO - -d DEST, --destination=DEST 21:07:19 INFO - Directory to install application into. [default: 21:07:19 INFO - "/builds/slave/test"] 21:07:19 INFO - --app=APP Application being installed. [default: firefox] 21:07:19 INFO - mkdir: /builds/slave/test/build/application 21:07:19 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'] 21:07:19 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 21:07:41 INFO - Reading from file tmpfile_stdout 21:07:41 INFO - Output received: 21:07:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:07:41 INFO - Running post-action listener: _resource_record_post_action 21:07:41 INFO - [mozharness: 2017-06-08 04:07:41.329561Z] Finished install step (success) 21:07:41 INFO - [mozharness: 2017-06-08 04:07:41.329691Z] Running setup-mitmproxy step. 21:07:41 INFO - Running pre-action listener: _resource_record_pre_action 21:07:41 INFO - Running main action method: setup_mitmproxy 21:07:41 INFO - Skipping: mitmproxy is not required 21:07:41 INFO - Running post-action listener: _resource_record_post_action 21:07:41 INFO - [mozharness: 2017-06-08 04:07:41.330332Z] Finished setup-mitmproxy step (success) 21:07:41 INFO - [mozharness: 2017-06-08 04:07:41.330475Z] Running run-tests step. 21:07:41 INFO - Running pre-action listener: _resource_record_pre_action 21:07:41 INFO - Running main action method: run_tests 21:07:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:07:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:07:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:07:41 INFO - 2017-06-07 21:07:41.345 screenresolution[1642:10899] starting screenresolution argv=screenresolution get 21:07:41 INFO - 2017-06-07 21:07:41.347 screenresolution[1642:10899] Display 0: 1600x1200x32@60 21:07:41 INFO - 2017-06-07 21:07:41.353 screenresolution[1643:10901] starting screenresolution argv=screenresolution list 21:07:41 INFO - Available Modes on Display 0 21:07:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:07:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:07:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:07:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:07:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:07:41 INFO - Intel Iris: 21:07:41 INFO - Chipset Model: Intel Iris 21:07:41 INFO - Type: GPU 21:07:41 INFO - Bus: Built-In 21:07:41 INFO - VRAM (Dynamic, Max): 1536 MB 21:07:41 INFO - Vendor: Intel (0x8086) 21:07:41 INFO - Device ID: 0x0a2e 21:07:41 INFO - Revision ID: 0x0009 21:07:41 INFO - Displays: 21:07:41 INFO - Display: 21:07:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:07:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:07:41 INFO - Display Serial Number: 1600x1200 60 21:07:41 INFO - Main Display: Yes 21:07:41 INFO - Mirror: Off 21:07:41 INFO - Online: Yes 21:07:41 INFO - Rotation: Supported 21:07:41 INFO - Return code: 0 21:07:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:07:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:07:41 INFO - Python 2.7.3 21:07:41 INFO - Return code: 0 21:07:41 INFO - grabbing minidump binary from tooltool 21:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:41 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 0x10c508d30>, '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 0x10c4d2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4f9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:07:41 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 21:07:41 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 21:07:41 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 21:07:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:07:41 INFO - Return code: 0 21:07:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:07:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:07:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:07:41 INFO - ENV: RUST_BACKTRACE is now 1 21:07:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:07:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:07:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:07:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0030 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:07:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eRkDl5lmQ1/Render', 21:07:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:41 INFO - 'HOME': '/Users/cltbld', 21:07:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:41 INFO - 'LOGNAME': 'cltbld', 21:07:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:07:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:07:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:41 INFO - 'MOZ_NO_REMOTE': '1', 21:07:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:07:41 INFO - 'NO_EM_RESTART': '1', 21:07:41 INFO - 'PAGER': '/bin/cat', 21:07:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:41 INFO - 'PWD': '/builds/slave/test', 21:07:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:07:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:41 INFO - 'RUST_BACKTRACE': '1', 21:07:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:07:41 INFO - 'SHELL': '/bin/bash', 21:07:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HCETlepvW5/Listeners', 21:07:41 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 21:07:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:41 INFO - 'USER': 'cltbld', 21:07:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:41 INFO - 'XPC_FLAGS': '0x0', 21:07:41 INFO - 'XPC_SERVICE_NAME': '0', 21:07:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:07:41 INFO - mozversion application_buildid: 20170607195825 21:07:41 INFO - mozversion application_changeset: 7efda263a842e60cd0cc00b3c4a7058c65590702 21:07:41 INFO - mozversion application_display_name: Nightly 21:07:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:07:41 INFO - mozversion application_name: Firefox 21:07:41 INFO - mozversion application_remotingname: firefox 21:07:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:07:41 INFO - mozversion application_vendor: Mozilla 21:07:41 INFO - mozversion application_version: 55.0a1 21:07:41 INFO - mozversion platform_buildid: 20170607195825 21:07:41 INFO - mozversion platform_changeset: 7efda263a842e60cd0cc00b3c4a7058c65590702 21:07:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:07:41 INFO - mozversion platform_version: 55.0a1 21:07:41 INFO - using testdate: 1496894861 21:07:41 INFO - actual date: 1496894861 21:07:41 INFO - starting webserver on 'localhost:49227' 21:07:41 INFO - SUITE-START | Running 2 tests 21:07:41 INFO - TEST-START | dromaeo_css 21:07:41 INFO - operating with platform_type : mac_ 21:07:41 INFO - Initialising browser for dromaeo_css test... 21:07:42 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpfNmzfC/profile 21:07:42 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 21:08:15 INFO - TEST-INFO | 1653: killed by SIGTERM 21:08:15 INFO - Browser initialized. 21:08:15 INFO - Running cycle 1/1 for dromaeo_css test... 21:08:15 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpfNmzfC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:08:18 INFO - PID 1661 | 2017-06-07 21:08:18.172 plugin-container[1662:11397] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b47, name = 'com.apple.tsm.portname' 21:08:18 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:08:18 INFO - PID 1661 | 2017-06-07 21:08:18.172 plugin-container[1662:11397] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00f, name = 'com.apple.CFPasteboardClient' 21:08:18 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:08:18 INFO - PID 1661 | 2017-06-07 21:08:18.172 plugin-container[1662:11397] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:08:18 INFO - PID 1661 | 2017-06-07 21:08:18.683 plugin-container[1663:11446] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 21:08:18 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:08:18 INFO - PID 1661 | 2017-06-07 21:08:18.683 plugin-container[1663:11446] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 21:08:18 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:08:18 INFO - PID 1661 | 2017-06-07 21:08:18.683 plugin-container[1663:11446] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:08:28 INFO - PID 1661 | RSS: Main: 253747200 21:08:28 INFO - PID 1661 | 21:11:31 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 21:11:32 INFO - PID 1661 | RSS: Main: 264200192 21:11:32 INFO - PID 1661 | 21:14:34 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 21:14:35 INFO - PID 1661 | RSS: Main: 274866176 21:14:35 INFO - PID 1661 | 21:17:37 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 21:17:37 INFO - PID 1661 | RSS: Main: 276955136 21:17:37 INFO - PID 1661 | 21:20:39 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 21:20:40 INFO - PID 1661 | RSS: Main: 279666688 21:20:40 INFO - PID 1661 | 21:23:43 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 21:23:43 INFO - PID 1661 | RSS: Main: 278720512 21:23:43 INFO - PID 1661 | 21:26:45 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 21:26:45 INFO - PID 1661 | RSS: Main: 277712896 21:26:45 INFO - PID 1661 | 21:26:45 INFO - PID 1661 | __start_tp_report 21:26:45 INFO - PID 1661 | _x_x_mozilla_page_load 21:26:45 INFO - PID 1661 | _x_x_mozilla_page_load_details 21:26:45 INFO - PID 1661 | |i|pagename|runs| 21:26:45 INFO - PID 1661 | |0;dojo.html;3574.6425357464254;3586.91032724182;3591.4612808078787;3623.384024715798;3629.5825980012296;5373.140297552392;5701.914471282934;6124.938750612492;6129.203203583539;6243.937560624392;5413.621046526745;6193.287771906232;6196.132541444202;6218.37816218378;6239.3448687887685;4424.827420733508;5353.571714262869;5425.348958125031;5452.073147564904;5456.263404440409;5515.951969125853;5564.666120032791;5565.916511252334;5605.691686957216;5612.41069687683;5383.73081345935;5440.537554307849;5518.66887986718;5568.86077848053;5572;4774.594159496447;5235.450277720861;5443.346798384219;5526.4197259288;5552;5387.299651045379;5462.535684466825;5478.233047373371;5488.615796894219;5522.834314970557;6142;6232.688365581703;6244.781432649881;6254;6266.686665666698;6399.488040956712;6521.934780652227;6568.901466478007;6666.9333306666795;6685.899711504331;6733.787918174727;6754.8311292217595;6763.323667633246;6774.93225067748;6783.457323414164;6275.748970041239;6277.18396608438;6285.811425657192;6286.779962701284;6296.212973378328;6406.647634380154;6483.578567393104;6772.72909083641;6858.862822743617;6889.483288753263;6284.057391391237;6334.113224148623;6455.644939528371;6515.7393704252245;6597.241317248482;6728.966355168291;6745.932540674628;6752.662366881735;6757.932420675828;6803.387695107365;6110.572259941761;6403.839904002344;6628.5028622852515;6645.966770166118;6657.900131498031;5480.862978425571;6397.7440902364315;6451.612903225821;6520.15238019064;6559.967200163968;5306.893862122736;5315.813946511854;5322.121849894801;5323.520883120537;5351.518363347317;4723.385959825269;4779.641526885497;4780.306855505932;4785.186518291899;4786.832460863922;6155.876882462326;6211.9689401553605;6215.098810672427;6227.408396202353;6391.137196478416;5542.75057622408;6763.256041835394;6764.797056088325;6779.084823548757;6795.52431329812;5275.314209152785;5327.493888080626;6279.7488100476385;6342.904856427157;6349.396807303298;5748.19525266463;5781.653100813965;5848.619839710405;5948.430303060592;5994.850128746816;6792.694328755218;6903.585784852925;6908.3091690830515;6923.5499692519825;6940.6529673515115;6735.057092007025;6756.966215169089;6758.594484330956;6766.763163289262;6784.457243420613;6688.3646053624825;6760.594364338155;6865.107536020286;6893.732834206529;6895.34494223048;5109.846704598868;6549.312322206099;6578.934210658023;6588.07766912623;6590.670466476753;6674.332566743286;6686.933130668631;6704.631245281361;6705.966470167618;6709.630970296681;6366.745330186926;6384.201974753156;6446.8388290293115;6455.225372955088;6494.805155845333;6514.315996820265;6524;6548.148740663683;6550.705218265094;6554.442872355903;6286.685665716787;6380.7447702091395;6416.037594360883;6424.068510066108;6439.227292724903;6291.496680265476;6532.738690452328;6571.112899758471;6583.177102862142;6589.439897608757;6301.527385446018;6310.1796766420075;6364.936350636434;6413.871722565429;6442.291347951816 21:26:45 INFO - PID 1661 | |1;ext.html;786.229495094807;822.333909533278;845.0999685335117;1065.8987396197358;1177.528988404638;39301;39807.99999999996;39876.80061599688;40136.39795403071;40432;39780.00549986252;40246.7987660062;40289.7985510072;40509.39235911464;40641.59358406415;7790.688372465109;7944.245296696805;8336.70821521247;8571.057183709787;8581.52801595912;8433.789155271106;8467.788305292386;8536.317094632415;8554.230119289263;8559.529225892573;8471.237588617023;8504.31965442763;8519.148085191462;8577.871331930026;8579.84172136763;8468.153184681543;8519.446235994672;8536.018357888861;8559.657613695445;8573.442726222807;8429.662813487454;8482.363822713321;8493.36299777513;8495.150484951455;8514.403991720583;38127.237455251015;38340.616593833984;38393.808030959946;38404.80797595994;38444;3596.496490491333;3600.225951420457;3638.5815631202486;3640.1809592841664;3643.3806252937065;30915;31170;31205.90779322713;31212;31215;3026.9394612107635;3081.907542773698;3092.9690703092906;3121.4849549824253;3134.5768321276564;220653.6901946472;240612;287097.5645121782;314341.4282928571;315483;49636.50363496391;49914.50085499171;49950;50064.24903626448;50107;46214.537854621696;49269;51635.48364516382;51818.48181518137;51957.70105747311;14414.42342306296;14542.127472351693;14552.272386380639;14642.633934151732;14762.70474590502;11656;11661.591844285409;11715.76568468626;11807.055435565306;11816.940915295369;14293.356798943865;14360.281985900663;14448.783268250985;14481;14554.490592829201;6184.134221209034;6206.224221972254;6225.190725205694;6227.532935029801;6251.249850018027;1011.8330475471465;1039.7868436970402;1041.3075304922293;1085.7285678580356;1086.706589220906;20083.297084601967;23329;24297.02811887505;24810.627840582405;25189.99240030415;1077.946102694862;1114.793763153819;1172.7009612548745;1173.7007063199;1177.2289150606366;12047.156699031158;12941.223526588246;12984.831365176991;13010.479580816851;13047.934760326138;328600;328935;330414.34792826365;331269;332959;55920.720396398574;55931;56748.432515675144;57085.42914570884;57190.42809571851;1478.1722235548127;1534.1485475560949;1534.9309281082378;1536.9077855328717;1554.463710020064;23102.537949240585;23440.413989650395;23672.763272367058;24476.53140811557;24840.875795620905;38429.6157038426;38655.80672096622;38839.41740873946;55507.16739248995;57055;292398;295272;295285;295372;295547;32039;37057.81471092735;38612.227755444175;39002.80498597602;39487.012824679616;48875.02249955052;49036.2644560339;50820.74589627028;51937.480625194774;52352.73823630858;32186.83906580452;32492.83753581217;32508;33247.50128748119;34433.82783086169;32203;32646.673533264362;33237.833810830794;33848.830755846066;35017.474737878445;35799.28401432009;37731.24537509179;40657;40770.79614601908;40997.795011024755;33406.83296583599;34282.48576271408;34385.656143438246;34777.47833782445;34939;22412.215572455538;24680.629790552797;26166;26525;26853 21:26:45 INFO - PID 1661 | |2;jquery.html;427.22458737391634;497.77102532834897;502.8835984115265;511.5421697580665;521.121909582354;21738.130474780988;22117.557648847032;22759.86200689965;22990.310290691286;24875.38310009848;25053.62419563708;25690.87154564229;25694.871525642393;25709;25756.484870302582;6423.100765892767;6588.275289718127;6589.20929488462;6590.308017658149;6593.307702691221;6047.969760151193;6333.8416539586415;6529.34706529348;6550.11573437587;6584.868302633969;6335.968320158416;6341.8097457076365;6344.873102537947;6359.586626869262;6369.235691716977;6323.715432805512;6329.556931014831;6349.714262858182;6357.141785858919;6369.14016607759;5779.1620215069;5797.826065218051;5832.416758324176;5841.853953651193;5846.181534585162;27411.31471713223;29357.559636605467;30003.549946750816;30064;30069.398612027857;2237.44063984004;2262.6832243485924;2267.331137314496;2267.8639281643154;2268.965965510519;19369.612607747767;19714.70427943582;19905.601887962304;19909.50226244341;19962.201511939504;1944.824965753089;2149.580831737812;2215.1250256148733;2240.484688521633;2246.5506898620342;185260;190414.0958590424;193454;195544.08911821683;205094;13021.869781302255;15449.918505704492;15520.767188492182;15560.688786224213;15728;20508.179672813218;20816.895915520327;20849.37451876446;20861;20899.582008359746;9781.364211326241;9955.25335599833;9966.15287700539;9992.450415227087;10059.446730429896;9058.864117038249;9075.334768471688;9094.499802510793;9173.990861005284;9185.035571265054;9271.423857944166;9352.018038105902;9390.365042204652;9427.952860235655;9477.573509192103;2299.36767388966;2605.3486628342916;2641.854697991629;2650.655414796064;2804.9859750701526;951.7573018880141;961.956711947964;967.1361967567308;1001.5993602558889;1010.838265877456;18323.633527329646;21700;22037.669434958487;22778.65832012521;22841.428964276027;985.0691096913348;1005.7586179317058;1020.2552136939927;1036.652721338345;1040.427764729396;10971.615993440191;11304.265222760494;11406.48670809799;11688.357140357362;11841.467133978991;551999;562756;567105.1644741832;568590;570418;157308;161441;162021;163207;163549;1120.0367683791933;1143.3018077337476;1242.0870660064845;1252.2048499203072;1252.7118762684634;22691.659625105305;23806.928688208725;24667.383315417257;24741.87629061843;24801.26391152602;72759;73469.6326518364;74639.25360746324;75134;76847;93764;93909.06090939003;94119;94376.52811735898;94403.52798235965;10116.898831011596;12062.396880155782;12169.939150304546;12173.513059477522;12233.449494772578;18567.721484177477;18786.15462304227;18975.33586324472;18981.525461863563;19007;15351.232438377812;15489.612759681098;15557;15619.375224990874;15670.529884103495;12464.688382790502;13439.260840653858;13624.83849344751;13730.018204942688;13861.79207311871;14848.777268340767;15527;15884.96747711395;16063.277152528399;16099.195040248174;12016.63950081499;12049.939750301193;12156.696082598006;12191.08566857507;12203.96266317373;15032.699346013238;15542.145182014645;15598.688026239639;15645.608859778597;15715.292811823267 21:26:45 INFO - PID 1661 | |3;mootools.html;1413.1662319231655;1480.659448326884;1490.9339822027255;1494.3350209156922;1501.0768377447869;11586.362750048744;11672.124590655703;11959.043276537877;13835.861641383595;13846.930765346171;13077.607671769862;13368.197908125496;13474.93262533686;13489.527866524673;13494.932525337359;2661.4563553139183;2718.972810271902;2733.9726602733917;2735.193118030176;2745.1627253687616;2282.1213832674457;2379.2981070584133;2667.5198464276423;2690.865456727156;2744.986275068622;2729.5533367315356;2765.875535600903;2766.2392841968517;2769.612254284402;2774.611554382388;2685.5568831142837;2704.9053283135195;2705.066751970567;2714.606382074598;2718.972810271902;2664.307280107186;2689.3276680829795;2690.273626120956;2701.0681314946314;2708.282305189107;13004.479820807157;13261.801072983913;13307.933460332733;13311.600651980456;13334;7288.672009759521;7507.286807753255;7558.281963213541;7568.962155189244;7572.280633339824;4518.932216016762;4742.573168414825;4903.41159060915;4935.950640493621;4941.530554597308;1431.105559025609;1436.3967133803828;1443.3216388297483;1462.700146469971;1462.96342591435;19740.50648733779;19843.908585027817;19914.701279480818;19997.700034499496;20027.499312517302;6727.990801379734;6783.118194634666;6847.828804279933;6921.792346229621;6983.406410455168;14490.710185796224;15737.134457604941;16413.42553010639;16447.342106315853;16458.75311870323;7644.464887457824;7647;7648.885266721004;7656.042994625672;7659.846803063908;6276.623402595767;6756.324367563205;6787.762428314985;6861.794146175623;6885.139357580302;4311.655067594585;4378.343248512749;4912.72979986097;5037.647364684437;5102.668326558762;1238.721287710258;1322.2397121654894;1475.2328789029646;1481.207553958627;1493.16382825618;815.889854869585;831.085805613832;859.7162936231028;861.6553378648591;895.892492900856;7132.857342853217;7342.082239720035;7713.112992005879;7853.528788272722;8184.345252379795;942.528735632184;943.3349488610513;993.3692105513052;1000.2448151645607;1022.3150489172273;4274.615284624399;4349.978250108729;4562.9771851141195;4939.728314942641;4953.984433191187;15560.377584896703;17480.47558573245;17813;18348.26606935734;18366.724499132524;17561.736573951403;18164.273429062952;18336.541586460444;18340.816591834177;18367;9268.351215414856;9282.90717092834;9299.814003719888;9306.348555601044;9325.906740932503;6984.860302794017;7448.366888814295;7542.358899493604;7787.29914307715;7961.044674639081;15890.761638575661;16108.838911611203;16283.02301861892;16415;16600.08699521538;16441.67116657636;16720;16804.159792010105;16934.237959291615;17159.7426038607;9560.330776845554;9745.80508389814;9746.805063898824;9754.31719779637;9760.511974401394;12348.753024939271;12363.443645035815;12488.687782805502;12500.499980000699;12524.373781310716;8471.61877715492;10117;10217.13154381886;10255.230857685554;10259.94870025675;9911;9930.950345248228;9986.500674966368;9999.800004000024;10045.49772511357;9066.320025998208;9213.953930230575;9224.677136300197;9254.259659227111;9283.953580232055;6474.773382931575;6475.579087359307;6828.760993365208;6890.173179218526;6993.650317484207;6648.849813922006;6964.233934267125;7028.437724982092;7164.426845852424;7244.348008679033 21:26:45 INFO - PID 1661 | |4;prototype.html;416.91870085333363;455.71517801374154;458.32397214109193;462.46123266394653;465.59958435745256;6419.422251997319;6471.70877310521;6599.109120268762;6639.867202655944;6897.964895446;5843.912341314884;6132.662703551303;6397.488200943925;6402.263739669939;6487.124238227838;4396.736195828261;4554.134714404253;4636.953630463694;4722.229164063476;4769;4761.40482439695;4774.236122220445;4789.090072886141;4791.257355109963;4793.640476964224;4707.223308154167;4726.621870250389;4755.619550435974;4765.928511072337;4772.093302272575;4764.737939413331;4779.832705855296;4782.760861956923;4784.64115191362;4808.591269742076;3474.3919814032697;4152.647025002879;4169.728967617095;4187.508432482668;4196.538380778143;4692.389989301403;5418.756155972991;6173.660448675321;6330.715117819709;6340.936590634081;5659.4340565943485;5671.206031155601;5676.347220069662;5690.2887139107615;5705.686187259699;5653.364241322118;5690.231818704463;5764;6258.499320054385;6589.571677840925;4108.650764685005;4607.378003969488;4718.952810471886;4729.574338309569;4749.833755818529;6927.653617319164;7137.714491420337;7326.4505162113055;7363.6318184091115;7379.077615298088;4151.91696166075;4781.450133234653;4855.150348688965;4857.0528746894015;4879.194932836113;4461.442319710036;4656.137544981966;4940.962397896482;4942.975285123552;4964;5593.552515798727;5618.494335509824;5637.842808027518;5692.37383887772;5835.679037652885;5992.880142397127;6476.093346931433;6797.762078327235;6831.863362732817;7013.719451221894;1881.8144568921766;1882.8493720502327;1884.5853912139307;1890.6124244529838;1910.980890191108;1321.5044358365612;1362.8023936529148;1364.8089267502558;1366.9589912302647;1374.5120482228665;6477.1579694639395;6543.9345606544275;6545.21457425112;6546.0835483032415;6549.508786841006;5879.823605291848;6572.967135164389;6825.976103584502;7123.501354905107;7154.284571542804;5514.172874068921;6502.219733631994;6509.837254068591;6510.283868774431;6517.4134327910715;3307.991062725892;3523.3481805865995;3591.982040089835;3637.872674456434;3714.2200137971327;5611.691356975324;7320.15818180916;7382.493315675011;7411.962940185264;7560.281773231534;6147.907781383283;6537.855875221817;7091.787246382513;7301.707931682673;7374.741884034033;6123.938760612336;6412.743490260337;7377.668004939791;7380.704771809067;7381.188069312371;6948.33990770876;7131.393831524379;7132.714691412493;7134.678939447841;7148.785536433916;6637.402633762985;6997.810372236834;7018.087648605853;7025.489519753278;7033.859322813618;6120.632762034292;7306.063893068235;7339.339459448675;7350.852982940204;7360.33756961876;4494.4606647202545;4840.588549973151;4872.147374209429;4877.439094504178;4881.585065269492;7011.964940175267;7121.679524421517;7134.785956421316;7135.464840137114;7193.820154496179;6887.931120688729;6891.6561270551765;6893.242223233044;6913.654317284217;6924.896126558207;5712.314522257356;6013.3685962975005;6889.002189365028;6897.517173797786;6913.5506192097355;6739.764108256383;6764.2221144569;6775.695093720893;6784.59292442455;6789.864202716017;6863.759768408082;6866.34769696878;6895.897131039167;6905.93094069073;6916.585004899722;6661.633610151488;6689.76585819494;6690.899636505359;6725.159355080615;6725.428338591275 21:26:45 INFO - PID 1661 | |5;yui.html;742.777166849945;832.3923535818848;841.9494830310182;864.56339548528;868.6004437958538;17207.913960430196;17557.824421755795;17641.55989582032;17654.646907061884;17689.82310176898;15621.375144994186;16163.919180404082;16493.092879891603;16613.169341532936;16707.749383759252;860.3934226370394;983.2576404814373;1008.7074748322977;1012.0082319327028;1013.7313611892854;907.7412937312868;908.2007833106857;909.7498187998287;911.6444586611227;912.6395073945787;1011.8836333821595;1055.7255113670462;1056.4136904018267;1059.3379138038727;1063.526730604881;1035.79801938622;1049.0768124050862;1051.1590727418072;1058.3544038136731;1061.389700921969;758.4842307231081;773.2113244490588;774.1020416317101;776.9223077692242;777.8755399136111;15364.539063828102;15417.768733469007;15443.073415594989;15450.381984720598;15793.921030394775;16921.83078169227;17435;18057;18075.186616602154;18103.909480452512;14285.57143285703;14682.632934176625;14794.778078328725;14795.112293262331;14809.777853332209;3688.7417880748358;3702.740808143431;3706.0549559862334;3714.1271801126713;3725.8509659613583;130728;135122;135996.32001839927;137253;138710;13280.867191328156;13660.795088073686;13678.521251756141;13792.379342929593;13808.309584520734;22893.313200604007;25528;25635;27402;30841.537376939363;7498.137714162832;8135.674573017013;8136.593170341577;8145.837083258301;8166.591670416456;3906.902327441837;4050.696197785178;4053.1691003344245;4053.3919912012843;4074.898127546776;4658.440987081572;4675.877789330604;4728.71627702339;4731.172044892199;4741.383620129361;992.6376872441507;1019.8827134879435;1030.2015937648232;1031.0256807317014;1037.0510982451124;639.517164540778;655.0894256982798;677.7729460630645;679.6092246958019;680.7038938061959;11127.721806954729;12051.879481205076;12726.490940362468;12777.616671499869;12869.163504372185;655.8393193667582;661.2032500836576;665.8634979829123;682.0962225051826;682.501773705188;5865.20819689345;5893.263342082239;6586.538942274091;6657.300983396771;6793.184817821893;135890;137585;142843;145235;150269;103295.48352258191;103771.48114259381;104997;110385;112706.87293127128;81218;88919;94529.58205626921;95242;98533;11955.581554645545;12603.054770892402;12698.746025079632;12722;12817.487300507875;43365;43689.344659830764;44729;44922.10155796931;45023.324650129616;59158;61045.08432373429;61099;61651.691741541006;62011.37988620237;15092.54722358331;16396.2621682027;17760.733588995918;17790.555236119202;17802.46592602224;18238.635227295646;18420.723689144404;19008.52478688044;19013.42959711211;19106.904465477583;17282.395116171378;17301.82698173002;17610.207540660445;17851.910740446216;17932.55168620795;15396;17274.740878887078;17516.91241543784;17606.91196544009;17710.557236069202;14225.217613031382;16806.83193168053;16865.241064151898;17055.658886821944;17269.827301726822;11284.717882053013;11390.373529455628;11442.599509017144;11982.58060967862;12041;13031.283279419724;14282.57152285433;14361.928190358982;14405.92797036008;14489.565313040626 21:26:45 INFO - PID 1661 | __end_tp_report 21:26:45 INFO - PID 1661 | __start_cc_report 21:26:45 INFO - PID 1661 | _x_x_mozilla_cycle_collect,456 21:26:45 INFO - PID 1661 | __end_cc_report 21:26:45 INFO - PID 1661 | __startTimestamp1496896005934__endTimestamp 21:26:45 INFO - PID 1661 | 21:26:45 INFO - PID 1661 | ------- Summary: start ------- 21:26:45 INFO - PID 1661 | Number of tests: 6 21:26:45 INFO - PID 1661 | 21:26:45 INFO - PID 1661 | [#0] dojo.html Cycles:165 Average:6104.90 Median:6342.90 stddev:728.07 (11.5%) stddev-sans-first:702.72 21:26:45 INFO - PID 1661 | Values: 3574.6 3586.9 3591.5 3623.4 3629.6 5373.1 5701.9 6124.9 6129.2 6243.9 5413.6 6193.3 6196.1 6218.4 6239.3 4424.8 5353.6 5425.3 5452.1 5456.3 5516.0 5564.7 5565.9 5605.7 5612.4 5383.7 5440.5 5518.7 5568.9 5572.0 4774.6 5235.5 5443.3 5526.4 5552.0 5387.3 5462.5 5478.2 5488.6 5522.8 6142.0 6232.7 6244.8 6254.0 6266.7 6399.5 6521.9 6568.9 6666.9 6685.9 6733.8 6754.8 6763.3 6774.9 6783.5 6275.7 6277.2 6285.8 6286.8 6296.2 6406.6 6483.6 6772.7 6858.9 6889.5 6284.1 6334.1 6455.6 6515.7 6597.2 6729.0 6745.9 6752.7 6757.9 6803.4 6110.6 6403.8 6628.5 6646.0 6657.9 5480.9 6397.7 6451.6 6520.2 6560.0 5306.9 5315.8 5322.1 5323.5 5351.5 4723.4 4779.6 4780.3 4785.2 4786.8 6155.9 6212.0 6215.1 6227.4 6391.1 5542.8 6763.3 6764.8 6779.1 6795.5 5275.3 5327.5 6279.7 6342.9 6349.4 5748.2 5781.7 5848.6 5948.4 5994.9 6792.7 6903.6 6908.3 6923.5 6940.7 6735.1 6757.0 6758.6 6766.8 6784.5 6688.4 6760.6 6865.1 6893.7 6895.3 5109.8 6549.3 6578.9 6588.1 6590.7 6674.3 6686.9 6704.6 6706.0 6709.6 6366.7 6384.2 6446.8 6455.2 6494.8 6514.3 6524.0 6548.1 6550.7 6554.4 6286.7 6380.7 6416.0 6424.1 6439.2 6291.5 6532.7 6571.1 6583.2 6589.4 6301.5 6310.2 6364.9 6413.9 6442.3 21:26:45 INFO - PID 1661 | 21:26:45 INFO - PID 1661 | [#1] ext.html Cycles:180 Average:46302.89 Median:34858.24 stddev:79138.40 (227.0%) stddev-sans-first:79286.62 21:26:45 INFO - PID 1661 | Values: 786.2 822.3 845.1 1065.9 1177.5 39301.0 39808.0 39876.8 40136.4 40432.0 39780.0 40246.8 40289.8 40509.4 40641.6 7790.7 7944.2 8336.7 8571.1 8581.5 8433.8 8467.8 8536.3 8554.2 8559.5 8471.2 8504.3 8519.1 8577.9 8579.8 8468.2 8519.4 8536.0 8559.7 8573.4 8429.7 8482.4 8493.4 8495.2 8514.4 38127.2 38340.6 38393.8 38404.8 38444.0 3596.5 3600.2 3638.6 3640.2 3643.4 30915.0 31170.0 31205.9 31212.0 31215.0 3026.9 3081.9 3093.0 3121.5 3134.6 220653.7 240612.0 287097.6 314341.4 315483.0 49636.5 49914.5 49950.0 50064.2 50107.0 46214.5 49269.0 51635.5 51818.5 51957.7 14414.4 14542.1 14552.3 14642.6 14762.7 11656.0 11661.6 11715.8 11807.1 11816.9 14293.4 14360.3 14448.8 14481.0 14554.5 6184.1 6206.2 6225.2 6227.5 6251.2 1011.8 1039.8 1041.3 1085.7 1086.7 20083.3 23329.0 24297.0 24810.6 25190.0 1077.9 1114.8 1172.7 1173.7 1177.2 12047.2 12941.2 12984.8 13010.5 13047.9 328600.0 328935.0 330414.3 331269.0 332959.0 55920.7 55931.0 56748.4 57085.4 57190.4 1478.2 1534.1 1534.9 1536.9 1554.5 23102.5 23440.4 23672.8 24476.5 24840.9 38429.6 38655.8 38839.4 55507.2 57055.0 292398.0 295272.0 295285.0 295372.0 295547.0 32039.0 37057.8 38612.2 39002.8 39487.0 48875.0 49036.3 50820.7 51937.5 52352.7 32186.8 32492.8 32508.0 33247.5 34433.8 32203.0 32646.7 33237.8 33848.8 35017.5 35799.3 37731.2 40657.0 40770.8 40997.8 33406.8 34282.5 34385.7 34777.5 34939.0 22412.2 24680.6 26166.0 26525.0 26853.0 21:26:45 INFO - PID 1661 | 21:26:45 INFO - PID 1661 | [#2] jquery.html Cycles:180 Average:40345.77 Median:2268.41 stddev:98056.63 (4322.7%) stddev-sans-first:98285.90 21:26:45 INFO - PID 1661 | Values: 427.2 497.8 502.9 511.5 521.1 21738.1 22117.6 22759.9 22990.3 24875.4 25053.6 25690.9 25694.9 25709.0 25756.5 6423.1 6588.3 6589.2 6590.3 6593.3 6048.0 6333.8 6529.3 6550.1 6584.9 6336.0 6341.8 6344.9 6359.6 6369.2 6323.7 6329.6 6349.7 6357.1 6369.1 5779.2 5797.8 5832.4 5841.9 5846.2 27411.3 29357.6 30003.5 30064.0 30069.4 2237.4 2262.7 2267.3 2267.9 2269.0 19369.6 19714.7 19905.6 19909.5 19962.2 1944.8 2149.6 2215.1 2240.5 2246.6 185260.0 190414.1 193454.0 195544.1 205094.0 13021.9 15449.9 15520.8 15560.7 15728.0 20508.2 20816.9 20849.4 20861.0 20899.6 9781.4 9955.3 9966.2 9992.5 10059.4 9058.9 9075.3 9094.5 9174.0 9185.0 9271.4 9352.0 9390.4 9428.0 9477.6 2299.4 2605.3 2641.9 2650.7 2805.0 951.8 962.0 967.1 1001.6 1010.8 18323.6 21700.0 22037.7 22778.7 22841.4 985.1 1005.8 1020.3 1036.7 1040.4 10971.6 11304.3 11406.5 11688.4 11841.5 551999.0 562756.0 567105.2 568590.0 570418.0 157308.0 161441.0 162021.0 163207.0 163549.0 1120.0 1143.3 1242.1 1252.2 1252.7 22691.7 23806.9 24667.4 24741.9 24801.3 72759.0 73469.6 74639.3 75134.0 76847.0 93764.0 93909.1 94119.0 94376.5 94403.5 10116.9 12062.4 12169.9 12173.5 12233.4 18567.7 18786.2 18975.3 18981.5 19007.0 15351.2 15489.6 15557.0 15619.4 15670.5 12464.7 13439.3 13624.8 13730.0 13861.8 14848.8 15527.0 15885.0 16063.3 16099.2 12016.6 12049.9 12156.7 12191.1 12204.0 15032.7 15542.1 15598.7 15645.6 15715.3 21:26:45 INFO - PID 1661 | 21:26:45 INFO - PID 1661 | [#3] mootools.html Cycles:180 Average:8213.13 Median:2740.09 stddev:5456.87 (199.1%) stddev-sans-first:5448.25 21:26:45 INFO - PID 1661 | Values: 1413.2 1480.7 1490.9 1494.3 1501.1 11586.4 11672.1 11959.0 13835.9 13846.9 13077.6 13368.2 13474.9 13489.5 13494.9 2661.5 2719.0 2734.0 2735.2 2745.2 2282.1 2379.3 2667.5 2690.9 2745.0 2729.6 2765.9 2766.2 2769.6 2774.6 2685.6 2704.9 2705.1 2714.6 2719.0 2664.3 2689.3 2690.3 2701.1 2708.3 13004.5 13261.8 13307.9 13311.6 13334.0 7288.7 7507.3 7558.3 7569.0 7572.3 4518.9 4742.6 4903.4 4936.0 4941.5 1431.1 1436.4 1443.3 1462.7 1463.0 19740.5 19843.9 19914.7 19997.7 20027.5 6728.0 6783.1 6847.8 6921.8 6983.4 14490.7 15737.1 16413.4 16447.3 16458.8 7644.5 7647.0 7648.9 7656.0 7659.8 6276.6 6756.3 6787.8 6861.8 6885.1 4311.7 4378.3 4912.7 5037.6 5102.7 1238.7 1322.2 1475.2 1481.2 1493.2 815.9 831.1 859.7 861.7 895.9 7132.9 7342.1 7713.1 7853.5 8184.3 942.5 943.3 993.4 1000.2 1022.3 4274.6 4350.0 4563.0 4939.7 4954.0 15560.4 17480.5 17813.0 18348.3 18366.7 17561.7 18164.3 18336.5 18340.8 18367.0 9268.4 9282.9 9299.8 9306.3 9325.9 6984.9 7448.4 7542.4 7787.3 7961.0 15890.8 16108.8 16283.0 16415.0 16600.1 16441.7 16720.0 16804.2 16934.2 17159.7 9560.3 9745.8 9746.8 9754.3 9760.5 12348.8 12363.4 12488.7 12500.5 12524.4 8471.6 10117.0 10217.1 10255.2 10259.9 9911.0 9931.0 9986.5 9999.8 10045.5 9066.3 9214.0 9224.7 9254.3 9284.0 6474.8 6475.6 6828.8 6890.2 6993.7 6648.8 6964.2 7028.4 7164.4 7244.3 21:26:45 INFO - PID 1661 | 21:26:45 INFO - PID 1661 | [#4] prototype.html Cycles:180 Average:5593.89 Median:6160.78 stddev:1696.06 (27.5%) stddev-sans-first:1655.71 21:26:45 INFO - PID 1661 | Values: 416.9 455.7 458.3 462.5 465.6 6419.4 6471.7 6599.1 6639.9 6898.0 5843.9 6132.7 6397.5 6402.3 6487.1 4396.7 4554.1 4637.0 4722.2 4769.0 4761.4 4774.2 4789.1 4791.3 4793.6 4707.2 4726.6 4755.6 4765.9 4772.1 4764.7 4779.8 4782.8 4784.6 4808.6 3474.4 4152.6 4169.7 4187.5 4196.5 4692.4 5418.8 6173.7 6330.7 6340.9 5659.4 5671.2 5676.3 5690.3 5705.7 5653.4 5690.2 5764.0 6258.5 6589.6 4108.7 4607.4 4719.0 4729.6 4749.8 6927.7 7137.7 7326.5 7363.6 7379.1 4151.9 4781.5 4855.2 4857.1 4879.2 4461.4 4656.1 4941.0 4943.0 4964.0 5593.6 5618.5 5637.8 5692.4 5835.7 5992.9 6476.1 6797.8 6831.9 7013.7 1881.8 1882.8 1884.6 1890.6 1911.0 1321.5 1362.8 1364.8 1367.0 1374.5 6477.2 6543.9 6545.2 6546.1 6549.5 5879.8 6573.0 6826.0 7123.5 7154.3 5514.2 6502.2 6509.8 6510.3 6517.4 3308.0 3523.3 3592.0 3637.9 3714.2 5611.7 7320.2 7382.5 7412.0 7560.3 6147.9 6537.9 7091.8 7301.7 7374.7 6123.9 6412.7 7377.7 7380.7 7381.2 6948.3 7131.4 7132.7 7134.7 7148.8 6637.4 6997.8 7018.1 7025.5 7033.9 6120.6 7306.1 7339.3 7350.9 7360.3 4494.5 4840.6 4872.1 4877.4 4881.6 7012.0 7121.7 7134.8 7135.5 7193.8 6887.9 6891.7 6893.2 6913.7 6924.9 5712.3 6013.4 6889.0 6897.5 6913.6 6739.8 6764.2 6775.7 6784.6 6789.9 6863.8 6866.3 6895.9 6905.9 6916.6 6661.6 6689.8 6690.9 6725.2 6725.4 21:26:45 INFO - PID 1661 | 21:26:45 INFO - PID 1661 | [#5] yui.html Cycles:180 Average:24335.82 Median:17648.10 stddev:36434.14 (206.4%) stddev-sans-first:36493.28 21:26:45 INFO - PID 1661 | Values: 742.8 832.4 841.9 864.6 868.6 17207.9 17557.8 17641.6 17654.6 17689.8 15621.4 16163.9 16493.1 16613.2 16707.7 860.4 983.3 1008.7 1012.0 1013.7 907.7 908.2 909.7 911.6 912.6 1011.9 1055.7 1056.4 1059.3 1063.5 1035.8 1049.1 1051.2 1058.4 1061.4 758.5 773.2 774.1 776.9 777.9 15364.5 15417.8 15443.1 15450.4 15793.9 16921.8 17435.0 18057.0 18075.2 18103.9 14285.6 14682.6 14794.8 14795.1 14809.8 3688.7 3702.7 3706.1 3714.1 3725.9 130728.0 135122.0 135996.3 137253.0 138710.0 13280.9 13660.8 13678.5 13792.4 13808.3 22893.3 25528.0 25635.0 27402.0 30841.5 7498.1 8135.7 8136.6 8145.8 8166.6 3906.9 4050.7 4053.2 4053.4 4074.9 4658.4 4675.9 4728.7 4731.2 4741.4 992.6 1019.9 1030.2 1031.0 1037.1 639.5 655.1 677.8 679.6 680.7 11127.7 12051.9 12726.5 12777.6 12869.2 655.8 661.2 665.9 682.1 682.5 5865.2 5893.3 6586.5 6657.3 6793.2 135890.0 137585.0 142843.0 145235.0 150269.0 103295.5 103771.5 104997.0 110385.0 112706.9 81218.0 88919.0 94529.6 95242.0 98533.0 11955.6 12603.1 12698.7 12722.0 12817.5 43365.0 43689.3 44729.0 44922.1 45023.3 59158.0 61045.1 61099.0 61651.7 62011.4 15092.5 16396.3 17760.7 17790.6 17802.5 18238.6 18420.7 19008.5 19013.4 19106.9 17282.4 17301.8 17610.2 17851.9 17932.6 15396.0 17274.7 17516.9 17606.9 17710.6 14225.2 16806.8 16865.2 17055.7 17269.8 11284.7 11390.4 11442.6 11982.6 12041.0 13031.3 14282.6 14361.9 14405.9 14489.6 21:26:45 INFO - PID 1661 | -------- Summary: end -------- 21:26:45 INFO - PID 1661 | 21:26:46 INFO - TEST-INFO | 1661: exit 0 21:26:46 INFO - TEST-OK | dromaeo_css | took 1144635ms 21:26:46 INFO - TEST-START | kraken 21:26:46 INFO - operating with platform_type : mac_ 21:26:46 INFO - Initialising browser for kraken test... 21:26:46 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpkcRRbt/profile 21:26:46 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 21:27:20 INFO - TEST-INFO | 1669: killed by SIGTERM 21:27:20 INFO - Browser initialized. 21:27:20 INFO - Running cycle 1/1 for kraken test... 21:27:20 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpkcRRbt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:27:22 INFO - PID 1673 | 2017-06-07 21:27:22.440 plugin-container[1674:13930] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993b, name = 'com.apple.tsm.portname' 21:27:22 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:27:22 INFO - PID 1673 | 2017-06-07 21:27:22.441 plugin-container[1674:13930] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4823, name = 'com.apple.CFPasteboardClient' 21:27:22 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:27:22 INFO - PID 1673 | 2017-06-07 21:27:22.441 plugin-container[1674:13930] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:27:22 INFO - PID 1673 | 2017-06-07 21:27:22.860 plugin-container[1675:13976] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a4f, name = 'com.apple.tsm.portname' 21:27:22 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:27:22 INFO - PID 1673 | 2017-06-07 21:27:22.861 plugin-container[1675:13976] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450b, name = 'com.apple.CFPasteboardClient' 21:27:22 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:27:22 INFO - PID 1673 | 2017-06-07 21:27:22.861 plugin-container[1675:13976] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:27:32 INFO - PID 1673 | RSS: Main: 252624896 21:27:32 INFO - PID 1673 | 21:27:34 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 21:27:35 INFO - PID 1673 | RSS: Main: 265781248 21:27:35 INFO - PID 1673 | 21:27:38 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 21:27:39 INFO - PID 1673 | RSS: Main: 275222528 21:27:39 INFO - PID 1673 | 21:27:42 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 21:27:43 INFO - PID 1673 | RSS: Main: 277860352 21:27:43 INFO - PID 1673 | 21:27:45 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 21:27:45 INFO - PID 1673 | RSS: Main: 280178688 21:27:45 INFO - PID 1673 | 21:27:47 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:27:48 INFO - PID 1673 | RSS: Main: 280879104 21:27:48 INFO - PID 1673 | 21:27:51 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 21:27:51 INFO - PID 1673 | RSS: Main: 284725248 21:27:51 INFO - PID 1673 | 21:27:54 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 21:27:55 INFO - PID 1673 | RSS: Main: 286810112 21:27:55 INFO - PID 1673 | 21:27:58 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 21:27:58 INFO - PID 1673 | RSS: Main: 285835264 21:27:58 INFO - PID 1673 | 21:28:00 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:28:01 INFO - PID 1673 | RSS: Main: 286412800 21:28:01 INFO - PID 1673 | 21:28:02 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:28:03 INFO - PID 1673 | RSS: Main: 286060544 21:28:03 INFO - PID 1673 | 21:28:05 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:28:06 INFO - PID 1673 | RSS: Main: 285450240 21:28:06 INFO - PID 1673 | 21:28:09 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:28:09 INFO - PID 1673 | RSS: Main: 285134848 21:28:09 INFO - PID 1673 | 21:28:13 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:28:13 INFO - PID 1673 | RSS: Main: 285708288 21:28:13 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 21:28:15 INFO - PID 1673 | RSS: Main: 287133696 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | __start_tp_report 21:28:15 INFO - PID 1673 | _x_x_mozilla_page_load 21:28:15 INFO - PID 1673 | _x_x_mozilla_page_load_details 21:28:15 INFO - PID 1673 | |i|pagename|runs| 21:28:15 INFO - PID 1673 | |0;ai-astar;113;117;100;115;117;115;115;114;116;114 21:28:15 INFO - PID 1673 | |1;audio-beat-detection;133;140;167;187;136;128;127;113;167;107 21:28:15 INFO - PID 1673 | |2;audio-dft;191;199;196;209;210;216;232;200;198;199 21:28:15 INFO - PID 1673 | |3;audio-fft;66;84;73;145;63;62;61;64;126;63 21:28:15 INFO - PID 1673 | |4;audio-oscillator;69;70;108;71;73;75;110;69;71;145 21:28:15 INFO - PID 1673 | |5;imaging-gaussian-blur;89;91;92;90;89;90;89;89;89;89 21:28:15 INFO - PID 1673 | |6;imaging-darkroom;96;97;96;96;98;97;96;97;98;98 21:28:15 INFO - PID 1673 | |7;imaging-desaturate;89;95;90;92;108;90;92;91;91;89 21:28:15 INFO - PID 1673 | |8;json-parse-financial;48;49;51;51;52;51;53;55;53;52 21:28:15 INFO - PID 1673 | |9;json-stringify-tinderbox;55;56;57;60;61;59;57;56;53;57 21:28:15 INFO - PID 1673 | |10;stanford-crypto-aes;87;82;99;102;107;86;91;89;102;109 21:28:15 INFO - PID 1673 | |11;stanford-crypto-ccm;150;130;149;150;145;136;138;135;128;146 21:28:15 INFO - PID 1673 | |12;stanford-crypto-pbkdf2;220;221;177;179;203;219;223;211;217;161 21:28:15 INFO - PID 1673 | |13;stanford-crypto-sha256-iterative;66;68;67;64;66;65;64;66;68;65 21:28:15 INFO - PID 1673 | __end_tp_report 21:28:15 INFO - PID 1673 | __start_cc_report 21:28:15 INFO - PID 1673 | _x_x_mozilla_cycle_collect,1204 21:28:15 INFO - PID 1673 | __end_cc_report 21:28:15 INFO - PID 1673 | __startTimestamp1496896095702__endTimestamp 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | ------- Summary: start ------- 21:28:15 INFO - PID 1673 | Number of tests: 14 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | [#0] ai-astar Cycles:10 Average:113.60 Median:115.00 stddev:4.95 (4.3%) stddev-sans-first:5.24 21:28:15 INFO - PID 1673 | Values: 113.0 117.0 100.0 115.0 117.0 115.0 115.0 114.0 116.0 114.0 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:138.00 stddev:25.52 (18.5%) stddev-sans-first:26.92 21:28:15 INFO - PID 1673 | Values: 133.0 140.0 167.0 187.0 136.0 128.0 127.0 113.0 167.0 107.0 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | [#2] audio-dft Cycles:10 Average:205.00 Median:204.50 stddev:12.08 (5.9%) stddev-sans-first:11.71 21:28:15 INFO - PID 1673 | Values: 191.0 199.0 196.0 209.0 210.0 216.0 232.0 200.0 198.0 199.0 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | [#3] audio-fft Cycles:10 Average:80.70 Median:63.50 stddev:30.03 (47.3%) stddev-sans-first:31.38 21:28:15 INFO - PID 1673 | Values: 66.0 84.0 73.0 145.0 63.0 62.0 61.0 64.0 126.0 63.0 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | [#4] audio-oscillator Cycles:10 Average:86.10 Median:70.50 stddev:26.07 (37.0%) stddev-sans-first:26.90 21:28:15 INFO - PID 1673 | Values: 69.0 70.0 108.0 71.0 73.0 75.0 110.0 69.0 71.0 145.0 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:89.50 stddev:1.06 (1.2%) stddev-sans-first:1.09 21:28:15 INFO - PID 1673 | Values: 89.0 91.0 92.0 90.0 89.0 90.0 89.0 89.0 89.0 89.0 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | [#6] imaging-darkroom Cycles:10 Average:96.90 Median:97.00 stddev:0.88 (0.9%) stddev-sans-first:0.87 21:28:15 INFO - PID 1673 | Values: 96.0 97.0 96.0 96.0 98.0 97.0 96.0 97.0 98.0 98.0 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | [#7] imaging-desaturate Cycles:10 Average:92.70 Median:91.00 stddev:5.66 (6.2%) stddev-sans-first:5.84 21:28:15 INFO - PID 1673 | Values: 89.0 95.0 90.0 92.0 108.0 90.0 92.0 91.0 91.0 89.0 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:52.00 stddev:2.01 (3.9%) stddev-sans-first:1.69 21:28:15 INFO - PID 1673 | Values: 48.0 49.0 51.0 51.0 52.0 51.0 53.0 55.0 53.0 52.0 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:57.10 Median:57.00 stddev:2.38 (4.2%) stddev-sans-first:2.40 21:28:15 INFO - PID 1673 | Values: 55.0 56.0 57.0 60.0 61.0 59.0 57.0 56.0 53.0 57.0 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | [#10] stanford-crypto-aes Cycles:10 Average:95.40 Median:86.50 stddev:9.54 (11.0%) stddev-sans-first:9.62 21:28:15 INFO - PID 1673 | Values: 87.0 82.0 99.0 102.0 107.0 86.0 91.0 89.0 102.0 109.0 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:140.70 Median:145.50 stddev:8.34 (5.7%) stddev-sans-first:8.14 21:28:15 INFO - PID 1673 | Values: 150.0 130.0 149.0 150.0 145.0 136.0 138.0 135.0 128.0 146.0 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:203.10 Median:218.00 stddev:22.47 (10.3%) stddev-sans-first:22.99 21:28:15 INFO - PID 1673 | Values: 220.0 221.0 177.0 179.0 203.0 219.0 223.0 211.0 217.0 161.0 21:28:15 INFO - PID 1673 | 21:28:15 INFO - PID 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:1.45 (2.2%) stddev-sans-first:1.54 21:28:15 INFO - PID 1673 | Values: 66.0 68.0 67.0 64.0 66.0 65.0 64.0 66.0 68.0 65.0 21:28:15 INFO - PID 1673 | -------- Summary: end -------- 21:28:15 INFO - PID 1673 | 21:28:16 INFO - TEST-INFO | 1673: exit 0 21:28:16 INFO - TEST-OK | kraken | took 89968ms 21:28:16 INFO - SUITE-END | took 1234s 21:28:16 INFO - Completed test suite (00:20:34) 21:28:16 INFO - javascript benchmark 21:28:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8429.990470434415, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3574.6425357464254, 3586.91032724182, 3591.4612808078787, 3623.384024715798, 3629.5825980012296, 5373.140297552392, 5701.914471282934, 6124.938750612492, 6129.203203583539, 6243.937560624392, 5413.621046526745, 6193.287771906232, 6196.132541444202, 6218.37816218378, 6239.3448687887685, 4424.827420733508, 5353.571714262869, 5425.348958125031, 5452.073147564904, 5456.263404440409, 5515.951969125853, 5564.666120032791, 5565.916511252334, 5605.691686957216, 5612.41069687683, 5383.73081345935, 5440.537554307849, 5518.66887986718, 5568.86077848053, 5572.0, 4774.594159496447, 5235.450277720861, 5443.346798384219, 5526.4197259288, 5552.0, 5387.299651045379, 5462.535684466825, 5478.233047373371, 5488.615796894219, 5522.834314970557, 6142.0, 6232.688365581703, 6244.781432649881, 6254.0, 6266.686665666698, 6399.488040956712, 6521.934780652227, 6568.901466478007, 6666.9333306666795, 6685.899711504331, 6733.787918174727, 6754.8311292217595, 6763.323667633246, 6774.93225067748, 6783.457323414164, 6275.748970041239, 6277.18396608438, 6285.811425657192, 6286.779962701284, 6296.212973378328, 6406.647634380154, 6483.578567393104, 6772.72909083641, 6858.862822743617, 6889.483288753263, 6284.057391391237, 6334.113224148623, 6455.644939528371, 6515.7393704252245, 6597.241317248482, 6728.966355168291, 6745.932540674628, 6752.662366881735, 6757.932420675828, 6803.387695107365, 6110.572259941761, 6403.839904002344, 6628.5028622852515, 6645.966770166118, 6657.900131498031, 5480.862978425571, 6397.7440902364315, 6451.612903225821, 6520.15238019064, 6559.967200163968, 5306.893862122736, 5315.813946511854, 5322.121849894801, 5323.520883120537, 5351.518363347317, 4723.385959825269, 4779.641526885497, 4780.306855505932, 4785.186518291899, 4786.832460863922, 6155.876882462326, 6211.9689401553605, 6215.098810672427, 6227.408396202353, 6391.137196478416, 5542.75057622408, 6763.256041835394, 6764.797056088325, 6779.084823548757, 6795.52431329812, 5275.314209152785, 5327.493888080626, 6279.7488100476385, 6342.904856427157, 6349.396807303298, 5748.19525266463, 5781.653100813965, 5848.619839710405, 5948.430303060592, 5994.850128746816, 6792.694328755218, 6903.585784852925, 6908.3091690830515, 6923.5499692519825, 6940.6529673515115, 6735.057092007025, 6756.966215169089, 6758.594484330956, 6766.763163289262, 6784.457243420613, 6688.3646053624825, 6760.594364338155, 6865.107536020286, 6893.732834206529, 6895.34494223048, 5109.846704598868, 6549.312322206099, 6578.934210658023, 6588.07766912623, 6590.670466476753, 6674.332566743286, 6686.933130668631, 6704.631245281361, 6705.966470167618, 6709.630970296681, 6366.745330186926, 6384.201974753156, 6446.8388290293115, 6455.225372955088, 6494.805155845333, 6514.315996820265, 6524.0, 6548.148740663683, 6550.705218265094, 6554.442872355903, 6286.685665716787, 6380.7447702091395, 6416.037594360883, 6424.068510066108, 6439.227292724903, 6291.496680265476, 6532.738690452328, 6571.112899758471, 6583.177102862142, 6589.439897608757, 6301.527385446018, 6310.1796766420075, 6364.936350636434, 6413.871722565429, 6442.291347951816], "value": 6058.935862593092, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.229495094807, 822.333909533278, 845.0999685335117, 1065.8987396197358, 1177.528988404638, 39301.0, 39807.99999999996, 39876.80061599688, 40136.39795403071, 40432.0, 39780.00549986252, 40246.7987660062, 40289.7985510072, 40509.39235911464, 40641.59358406415, 7790.688372465109, 7944.245296696805, 8336.70821521247, 8571.057183709787, 8581.52801595912, 8433.789155271106, 8467.788305292386, 8536.317094632415, 8554.230119289263, 8559.529225892573, 8471.237588617023, 8504.31965442763, 8519.148085191462, 8577.871331930026, 8579.84172136763, 8468.153184681543, 8519.446235994672, 8536.018357888861, 8559.657613695445, 8573.442726222807, 8429.662813487454, 8482.363822713321, 8493.36299777513, 8495.150484951455, 8514.403991720583, 38127.237455251015, 38340.616593833984, 38393.808030959946, 38404.80797595994, 38444.0, 3596.496490491333, 3600.225951420457, 3638.5815631202486, 3640.1809592841664, 3643.3806252937065, 30915.0, 31170.0, 31205.90779322713, 31212.0, 31215.0, 3026.9394612107635, 3081.907542773698, 3092.9690703092906, 3121.4849549824253, 3134.5768321276564, 220653.6901946472, 240612.0, 287097.5645121782, 314341.4282928571, 315483.0, 49636.50363496391, 49914.50085499171, 49950.0, 50064.24903626448, 50107.0, 46214.537854621696, 49269.0, 51635.48364516382, 51818.48181518137, 51957.70105747311, 14414.42342306296, 14542.127472351693, 14552.272386380639, 14642.633934151732, 14762.70474590502, 11656.0, 11661.591844285409, 11715.76568468626, 11807.055435565306, 11816.940915295369, 14293.356798943865, 14360.281985900663, 14448.783268250985, 14481.0, 14554.490592829201, 6184.134221209034, 6206.224221972254, 6225.190725205694, 6227.532935029801, 6251.249850018027, 1011.8330475471465, 1039.7868436970402, 1041.3075304922293, 1085.7285678580356, 1086.706589220906, 20083.297084601967, 23329.0, 24297.02811887505, 24810.627840582405, 25189.99240030415, 1077.946102694862, 1114.793763153819, 1172.7009612548745, 1173.7007063199, 1177.2289150606366, 12047.156699031158, 12941.223526588246, 12984.831365176991, 13010.479580816851, 13047.934760326138, 328600.0, 328935.0, 330414.34792826365, 331269.0, 332959.0, 55920.720396398574, 55931.0, 56748.432515675144, 57085.42914570884, 57190.42809571851, 1478.1722235548127, 1534.1485475560949, 1534.9309281082378, 1536.9077855328717, 1554.463710020064, 23102.537949240585, 23440.413989650395, 23672.763272367058, 24476.53140811557, 24840.875795620905, 38429.6157038426, 38655.80672096622, 38839.41740873946, 55507.16739248995, 57055.0, 292398.0, 295272.0, 295285.0, 295372.0, 295547.0, 32039.0, 37057.81471092735, 38612.227755444175, 39002.80498597602, 39487.012824679616, 48875.02249955052, 49036.2644560339, 50820.74589627028, 51937.480625194774, 52352.73823630858, 32186.83906580452, 32492.83753581217, 32508.0, 33247.50128748119, 34433.82783086169, 32203.0, 32646.673533264362, 33237.833810830794, 33848.830755846066, 35017.474737878445, 35799.28401432009, 37731.24537509179, 40657.0, 40770.79614601908, 40997.795011024755, 33406.83296583599, 34282.48576271408, 34385.656143438246, 34777.47833782445, 34939.0, 22412.215572455538, 24680.629790552797, 26166.0, 26525.0, 26853.0], "value": 18176.699011857734, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.22458737391634, 497.77102532834897, 502.8835984115265, 511.5421697580665, 521.121909582354, 21738.130474780988, 22117.557648847032, 22759.86200689965, 22990.310290691286, 24875.38310009848, 25053.62419563708, 25690.87154564229, 25694.871525642393, 25709.0, 25756.484870302582, 6423.100765892767, 6588.275289718127, 6589.20929488462, 6590.308017658149, 6593.307702691221, 6047.969760151193, 6333.8416539586415, 6529.34706529348, 6550.11573437587, 6584.868302633969, 6335.968320158416, 6341.8097457076365, 6344.873102537947, 6359.586626869262, 6369.235691716977, 6323.715432805512, 6329.556931014831, 6349.714262858182, 6357.141785858919, 6369.14016607759, 5779.1620215069, 5797.826065218051, 5832.416758324176, 5841.853953651193, 5846.181534585162, 27411.31471713223, 29357.559636605467, 30003.549946750816, 30064.0, 30069.398612027857, 2237.44063984004, 2262.6832243485924, 2267.331137314496, 2267.8639281643154, 2268.965965510519, 19369.612607747767, 19714.70427943582, 19905.601887962304, 19909.50226244341, 19962.201511939504, 1944.824965753089, 2149.580831737812, 2215.1250256148733, 2240.484688521633, 2246.5506898620342, 185260.0, 190414.0958590424, 193454.0, 195544.08911821683, 205094.0, 13021.869781302255, 15449.918505704492, 15520.767188492182, 15560.688786224213, 15728.0, 20508.179672813218, 20816.895915520327, 20849.37451876446, 20861.0, 20899.582008359746, 9781.364211326241, 9955.25335599833, 9966.15287700539, 9992.450415227087, 10059.446730429896, 9058.864117038249, 9075.334768471688, 9094.499802510793, 9173.990861005284, 9185.035571265054, 9271.423857944166, 9352.018038105902, 9390.365042204652, 9427.952860235655, 9477.573509192103, 2299.36767388966, 2605.3486628342916, 2641.854697991629, 2650.655414796064, 2804.9859750701526, 951.7573018880141, 961.956711947964, 967.1361967567308, 1001.5993602558889, 1010.838265877456, 18323.633527329646, 21700.0, 22037.669434958487, 22778.65832012521, 22841.428964276027, 985.0691096913348, 1005.7586179317058, 1020.2552136939927, 1036.652721338345, 1040.427764729396, 10971.615993440191, 11304.265222760494, 11406.48670809799, 11688.357140357362, 11841.467133978991, 551999.0, 562756.0, 567105.1644741832, 568590.0, 570418.0, 157308.0, 161441.0, 162021.0, 163207.0, 163549.0, 1120.0367683791933, 1143.3018077337476, 1242.0870660064845, 1252.2048499203072, 1252.7118762684634, 22691.659625105305, 23806.928688208725, 24667.383315417257, 24741.87629061843, 24801.26391152602, 72759.0, 73469.6326518364, 74639.25360746324, 75134.0, 76847.0, 93764.0, 93909.06090939003, 94119.0, 94376.52811735898, 94403.52798235965, 10116.898831011596, 12062.396880155782, 12169.939150304546, 12173.513059477522, 12233.449494772578, 18567.721484177477, 18786.15462304227, 18975.33586324472, 18981.525461863563, 19007.0, 15351.232438377812, 15489.612759681098, 15557.0, 15619.375224990874, 15670.529884103495, 12464.688382790502, 13439.260840653858, 13624.83849344751, 13730.018204942688, 13861.79207311871, 14848.777268340767, 15527.0, 15884.96747711395, 16063.277152528399, 16099.195040248174, 12016.63950081499, 12049.939750301193, 12156.696082598006, 12191.08566857507, 12203.96266317373, 15032.699346013238, 15542.145182014645, 15598.688026239639, 15645.608859778597, 15715.292811823267], "value": 11984.117839367276, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.1662319231655, 1480.659448326884, 1490.9339822027255, 1494.3350209156922, 1501.0768377447869, 11586.362750048744, 11672.124590655703, 11959.043276537877, 13835.861641383595, 13846.930765346171, 13077.607671769862, 13368.197908125496, 13474.93262533686, 13489.527866524673, 13494.932525337359, 2661.4563553139183, 2718.972810271902, 2733.9726602733917, 2735.193118030176, 2745.1627253687616, 2282.1213832674457, 2379.2981070584133, 2667.5198464276423, 2690.865456727156, 2744.986275068622, 2729.5533367315356, 2765.875535600903, 2766.2392841968517, 2769.612254284402, 2774.611554382388, 2685.5568831142837, 2704.9053283135195, 2705.066751970567, 2714.606382074598, 2718.972810271902, 2664.307280107186, 2689.3276680829795, 2690.273626120956, 2701.0681314946314, 2708.282305189107, 13004.479820807157, 13261.801072983913, 13307.933460332733, 13311.600651980456, 13334.0, 7288.672009759521, 7507.286807753255, 7558.281963213541, 7568.962155189244, 7572.280633339824, 4518.932216016762, 4742.573168414825, 4903.41159060915, 4935.950640493621, 4941.530554597308, 1431.105559025609, 1436.3967133803828, 1443.3216388297483, 1462.700146469971, 1462.96342591435, 19740.50648733779, 19843.908585027817, 19914.701279480818, 19997.700034499496, 20027.499312517302, 6727.990801379734, 6783.118194634666, 6847.828804279933, 6921.792346229621, 6983.406410455168, 14490.710185796224, 15737.134457604941, 16413.42553010639, 16447.342106315853, 16458.75311870323, 7644.464887457824, 7647.0, 7648.885266721004, 7656.042994625672, 7659.846803063908, 6276.623402595767, 6756.324367563205, 6787.762428314985, 6861.794146175623, 6885.139357580302, 4311.655067594585, 4378.343248512749, 4912.72979986097, 5037.647364684437, 5102.668326558762, 1238.721287710258, 1322.2397121654894, 1475.2328789029646, 1481.207553958627, 1493.16382825618, 815.889854869585, 831.085805613832, 859.7162936231028, 861.6553378648591, 895.892492900856, 7132.857342853217, 7342.082239720035, 7713.112992005879, 7853.528788272722, 8184.345252379795, 942.528735632184, 943.3349488610513, 993.3692105513052, 1000.2448151645607, 1022.3150489172273, 4274.615284624399, 4349.978250108729, 4562.9771851141195, 4939.728314942641, 4953.984433191187, 15560.377584896703, 17480.47558573245, 17813.0, 18348.26606935734, 18366.724499132524, 17561.736573951403, 18164.273429062952, 18336.541586460444, 18340.816591834177, 18367.0, 9268.351215414856, 9282.90717092834, 9299.814003719888, 9306.348555601044, 9325.906740932503, 6984.860302794017, 7448.366888814295, 7542.358899493604, 7787.29914307715, 7961.044674639081, 15890.761638575661, 16108.838911611203, 16283.02301861892, 16415.0, 16600.08699521538, 16441.67116657636, 16720.0, 16804.159792010105, 16934.237959291615, 17159.7426038607, 9560.330776845554, 9745.80508389814, 9746.805063898824, 9754.31719779637, 9760.511974401394, 12348.753024939271, 12363.443645035815, 12488.687782805502, 12500.499980000699, 12524.373781310716, 8471.61877715492, 10117.0, 10217.13154381886, 10255.230857685554, 10259.94870025675, 9911.0, 9930.950345248228, 9986.500674966368, 9999.800004000024, 10045.49772511357, 9066.320025998208, 9213.953930230575, 9224.677136300197, 9254.259659227111, 9283.953580232055, 6474.773382931575, 6475.579087359307, 6828.760993365208, 6890.173179218526, 6993.650317484207, 6648.849813922006, 6964.233934267125, 7028.437724982092, 7164.426845852424, 7244.348008679033], "value": 6079.743144781372, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.91870085333363, 455.71517801374154, 458.32397214109193, 462.46123266394653, 465.59958435745256, 6419.422251997319, 6471.70877310521, 6599.109120268762, 6639.867202655944, 6897.964895446, 5843.912341314884, 6132.662703551303, 6397.488200943925, 6402.263739669939, 6487.124238227838, 4396.736195828261, 4554.134714404253, 4636.953630463694, 4722.229164063476, 4769.0, 4761.40482439695, 4774.236122220445, 4789.090072886141, 4791.257355109963, 4793.640476964224, 4707.223308154167, 4726.621870250389, 4755.619550435974, 4765.928511072337, 4772.093302272575, 4764.737939413331, 4779.832705855296, 4782.760861956923, 4784.64115191362, 4808.591269742076, 3474.3919814032697, 4152.647025002879, 4169.728967617095, 4187.508432482668, 4196.538380778143, 4692.389989301403, 5418.756155972991, 6173.660448675321, 6330.715117819709, 6340.936590634081, 5659.4340565943485, 5671.206031155601, 5676.347220069662, 5690.2887139107615, 5705.686187259699, 5653.364241322118, 5690.231818704463, 5764.0, 6258.499320054385, 6589.571677840925, 4108.650764685005, 4607.378003969488, 4718.952810471886, 4729.574338309569, 4749.833755818529, 6927.653617319164, 7137.714491420337, 7326.4505162113055, 7363.6318184091115, 7379.077615298088, 4151.91696166075, 4781.450133234653, 4855.150348688965, 4857.0528746894015, 4879.194932836113, 4461.442319710036, 4656.137544981966, 4940.962397896482, 4942.975285123552, 4964.0, 5593.552515798727, 5618.494335509824, 5637.842808027518, 5692.37383887772, 5835.679037652885, 5992.880142397127, 6476.093346931433, 6797.762078327235, 6831.863362732817, 7013.719451221894, 1881.8144568921766, 1882.8493720502327, 1884.5853912139307, 1890.6124244529838, 1910.980890191108, 1321.5044358365612, 1362.8023936529148, 1364.8089267502558, 1366.9589912302647, 1374.5120482228665, 6477.1579694639395, 6543.9345606544275, 6545.21457425112, 6546.0835483032415, 6549.508786841006, 5879.823605291848, 6572.967135164389, 6825.976103584502, 7123.501354905107, 7154.284571542804, 5514.172874068921, 6502.219733631994, 6509.837254068591, 6510.283868774431, 6517.4134327910715, 3307.991062725892, 3523.3481805865995, 3591.982040089835, 3637.872674456434, 3714.2200137971327, 5611.691356975324, 7320.15818180916, 7382.493315675011, 7411.962940185264, 7560.281773231534, 6147.907781383283, 6537.855875221817, 7091.787246382513, 7301.707931682673, 7374.741884034033, 6123.938760612336, 6412.743490260337, 7377.668004939791, 7380.704771809067, 7381.188069312371, 6948.33990770876, 7131.393831524379, 7132.714691412493, 7134.678939447841, 7148.785536433916, 6637.402633762985, 6997.810372236834, 7018.087648605853, 7025.489519753278, 7033.859322813618, 6120.632762034292, 7306.063893068235, 7339.339459448675, 7350.852982940204, 7360.33756961876, 4494.4606647202545, 4840.588549973151, 4872.147374209429, 4877.439094504178, 4881.585065269492, 7011.964940175267, 7121.679524421517, 7134.785956421316, 7135.464840137114, 7193.820154496179, 6887.931120688729, 6891.6561270551765, 6893.242223233044, 6913.654317284217, 6924.896126558207, 5712.314522257356, 6013.3685962975005, 6889.002189365028, 6897.517173797786, 6913.5506192097355, 6739.764108256383, 6764.2221144569, 6775.695093720893, 6784.59292442455, 6789.864202716017, 6863.759768408082, 6866.34769696878, 6895.897131039167, 6905.93094069073, 6916.585004899722, 6661.633610151488, 6689.76585819494, 6690.899636505359, 6725.159355080615, 6725.428338591275], "value": 5100.309640976505, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.777166849945, 832.3923535818848, 841.9494830310182, 864.56339548528, 868.6004437958538, 17207.913960430196, 17557.824421755795, 17641.55989582032, 17654.646907061884, 17689.82310176898, 15621.375144994186, 16163.919180404082, 16493.092879891603, 16613.169341532936, 16707.749383759252, 860.3934226370394, 983.2576404814373, 1008.7074748322977, 1012.0082319327028, 1013.7313611892854, 907.7412937312868, 908.2007833106857, 909.7498187998287, 911.6444586611227, 912.6395073945787, 1011.8836333821595, 1055.7255113670462, 1056.4136904018267, 1059.3379138038727, 1063.526730604881, 1035.79801938622, 1049.0768124050862, 1051.1590727418072, 1058.3544038136731, 1061.389700921969, 758.4842307231081, 773.2113244490588, 774.1020416317101, 776.9223077692242, 777.8755399136111, 15364.539063828102, 15417.768733469007, 15443.073415594989, 15450.381984720598, 15793.921030394775, 16921.83078169227, 17435.0, 18057.0, 18075.186616602154, 18103.909480452512, 14285.57143285703, 14682.632934176625, 14794.778078328725, 14795.112293262331, 14809.777853332209, 3688.7417880748358, 3702.740808143431, 3706.0549559862334, 3714.1271801126713, 3725.8509659613583, 130728.0, 135122.0, 135996.32001839927, 137253.0, 138710.0, 13280.867191328156, 13660.795088073686, 13678.521251756141, 13792.379342929593, 13808.309584520734, 22893.313200604007, 25528.0, 25635.0, 27402.0, 30841.537376939363, 7498.137714162832, 8135.674573017013, 8136.593170341577, 8145.837083258301, 8166.591670416456, 3906.902327441837, 4050.696197785178, 4053.1691003344245, 4053.3919912012843, 4074.898127546776, 4658.440987081572, 4675.877789330604, 4728.71627702339, 4731.172044892199, 4741.383620129361, 992.6376872441507, 1019.8827134879435, 1030.2015937648232, 1031.0256807317014, 1037.0510982451124, 639.517164540778, 655.0894256982798, 677.7729460630645, 679.6092246958019, 680.7038938061959, 11127.721806954729, 12051.879481205076, 12726.490940362468, 12777.616671499869, 12869.163504372185, 655.8393193667582, 661.2032500836576, 665.8634979829123, 682.0962225051826, 682.501773705188, 5865.20819689345, 5893.263342082239, 6586.538942274091, 6657.300983396771, 6793.184817821893, 135890.0, 137585.0, 142843.0, 145235.0, 150269.0, 103295.48352258191, 103771.48114259381, 104997.0, 110385.0, 112706.87293127128, 81218.0, 88919.0, 94529.58205626921, 95242.0, 98533.0, 11955.581554645545, 12603.054770892402, 12698.746025079632, 12722.0, 12817.487300507875, 43365.0, 43689.344659830764, 44729.0, 44922.10155796931, 45023.324650129616, 59158.0, 61045.08432373429, 61099.0, 61651.691741541006, 62011.37988620237, 15092.54722358331, 16396.2621682027, 17760.733588995918, 17790.555236119202, 17802.46592602224, 18238.635227295646, 18420.723689144404, 19008.52478688044, 19013.42959711211, 19106.904465477583, 17282.395116171378, 17301.82698173002, 17610.207540660445, 17851.910740446216, 17932.55168620795, 15396.0, 17274.740878887078, 17516.91241543784, 17606.91196544009, 17710.557236069202, 14225.217613031382, 16806.83193168053, 16865.241064151898, 17055.658886821944, 17269.827301726822, 11284.717882053013, 11390.373529455628, 11442.599509017144, 11982.58060967862, 12041.0, 13031.283279419724, 14282.57152285433, 14361.928190358982, 14405.92797036008, 14489.565313040626], "value": 8768.68516233516, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1518.9000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 100.0, 115.0, 117.0, 115.0, 115.0, 114.0, 116.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 140.0, 167.0, 187.0, 136.0, 128.0, 127.0, 113.0, 167.0, 107.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 199.0, 196.0, 209.0, 210.0, 216.0, 232.0, 200.0, 198.0, 199.0], "value": 205.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 84.0, 73.0, 145.0, 63.0, 62.0, 61.0, 64.0, 126.0, 63.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 108.0, 71.0, 73.0, 75.0, 110.0, 69.0, 71.0, 145.0], "value": 86.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 92.0, 90.0, 89.0, 90.0, 89.0, 89.0, 89.0, 89.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 98.0, 97.0, 96.0, 97.0, 98.0, 98.0], "value": 96.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 90.0, 92.0, 108.0, 90.0, 92.0, 91.0, 91.0, 89.0], "value": 92.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 49.0, 51.0, 51.0, 52.0, 51.0, 53.0, 55.0, 53.0, 52.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 57.0, 60.0, 61.0, 59.0, 57.0, 56.0, 53.0, 57.0], "value": 57.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 82.0, 99.0, 102.0, 107.0, 86.0, 91.0, 89.0, 102.0, 109.0], "value": 95.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 130.0, 149.0, 150.0, 145.0, 136.0, 138.0, 135.0, 128.0, 146.0], "value": 140.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 221.0, 177.0, 179.0, 203.0, 219.0, 223.0, 211.0, 217.0, 161.0], "value": 203.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 64.0, 66.0, 65.0, 64.0, 66.0, 68.0, 65.0], "value": 65.9, "unit": "score"}]}]} 21:28:17 INFO - Return code: 0 21:28:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:28:17 INFO - # TBPL SUCCESS # 21:28:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:28:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:28:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:28:17 INFO - 2017-06-07 21:28:17.118 screenresolution[1681:14361] starting screenresolution argv=screenresolution get 21:28:17 INFO - 2017-06-07 21:28:17.121 screenresolution[1681:14361] Display 0: 1600x1200x32@60 21:28:17 INFO - 2017-06-07 21:28:17.126 screenresolution[1682:14364] starting screenresolution argv=screenresolution list 21:28:17 INFO - Available Modes on Display 0 21:28:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:28:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:28:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:28:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:28:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:28:17 INFO - Intel Iris: 21:28:17 INFO - Chipset Model: Intel Iris 21:28:17 INFO - Type: GPU 21:28:17 INFO - Bus: Built-In 21:28:17 INFO - VRAM (Dynamic, Max): 1536 MB 21:28:17 INFO - Vendor: Intel (0x8086) 21:28:17 INFO - Device ID: 0x0a2e 21:28:17 INFO - Revision ID: 0x0009 21:28:17 INFO - Displays: 21:28:17 INFO - Display: 21:28:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:28:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:28:17 INFO - Display Serial Number: 1600x1200 60 21:28:17 INFO - Main Display: Yes 21:28:17 INFO - Mirror: Off 21:28:17 INFO - Online: Yes 21:28:17 INFO - Rotation: Supported 21:28:17 INFO - Return code: 0 21:28:17 INFO - Running post-action listener: _resource_record_post_action 21:28:17 INFO - [mozharness: 2017-06-08 04:28:17.361389Z] Finished run-tests step (success) 21:28:17 INFO - Running post-run listener: _resource_record_post_run 21:28:17 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 36099072; Write bytes: 572266496; Read time: 361; Write time: 1853 21:28:17 INFO - TinderboxPrint: CPU usage
53.2% 21:28:17 INFO - TinderboxPrint: I/O read bytes / time
36,099,072 / 361 21:28:17 INFO - TinderboxPrint: I/O write bytes / time
572,266,496 / 1,853 21:28:17 INFO - TinderboxPrint: CPU idle
2,351.7 (46.8%) 21:28:17 INFO - TinderboxPrint: CPU system
1,018.2 (20.2%) 21:28:17 INFO - TinderboxPrint: CPU user
1,659.8 (33.0%) 21:28:17 INFO - TinderboxPrint: Swap in / out
278,945,792 / 0 21:28:17 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 227256832; Write bytes: 219631616; Read time: 16628; Write time: 386 21:28:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:28:17 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 35107840; Write bytes: 324323328; Read time: 346; Write time: 1400 21:28:17 INFO - Running post-run listener: _upload_blobber_files 21:28:17 INFO - Blob upload gear active. 21:28:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:28:17 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 21:28:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:28:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:28:18 INFO - (blobuploader) - INFO - Open directory for files ... 21:28:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:28:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:28:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:20 INFO - (blobuploader) - INFO - Done attempting. 21:28:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:28:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:28:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:21 INFO - (blobuploader) - INFO - Done attempting. 21:28:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:28:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:28:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:22 INFO - (blobuploader) - INFO - Done attempting. 21:28:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:28:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:28:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:24 INFO - (blobuploader) - INFO - Done attempting. 21:28:24 INFO - (blobuploader) - INFO - Iteration through files over. 21:28:24 INFO - Return code: 0 21:28:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:28:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:28:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05dbe2db68562ae4fc31d6bb278ef2bd1e8e440e5186d6436965d2353d426ab319874325557a696a7c6d802d3673b72abc5e29ffed14b0cd858109bec9bda269", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53232df44a87539c5d6ed2119f27d0d537be2a6ac473929f01648f3fd4a251d3c108df7d0f98ae43a92c870124b7b9ad6db39a61f93fc34e487bf778b31431f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2ab6528f4457a29b29ed59a9d5aa3ee89be9d1e5264e861e4e03e496a802ccbf0a0727b96dfa3cce3c28617c503cba5759bd93556a300c48c93024f78902424", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/993c33e218e6dacc31fd67c22a596989c37be57d38d5f83dbb2c663dca6d6ae0169d81b116bb3fd534e7f574a4653ed36e380e3dc482830d11eef1fb2f892244"} 21:28:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:28:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:28:24 INFO - Contents: 21:28:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05dbe2db68562ae4fc31d6bb278ef2bd1e8e440e5186d6436965d2353d426ab319874325557a696a7c6d802d3673b72abc5e29ffed14b0cd858109bec9bda269", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53232df44a87539c5d6ed2119f27d0d537be2a6ac473929f01648f3fd4a251d3c108df7d0f98ae43a92c870124b7b9ad6db39a61f93fc34e487bf778b31431f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2ab6528f4457a29b29ed59a9d5aa3ee89be9d1e5264e861e4e03e496a802ccbf0a0727b96dfa3cce3c28617c503cba5759bd93556a300c48c93024f78902424", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/993c33e218e6dacc31fd67c22a596989c37be57d38d5f83dbb2c663dca6d6ae0169d81b116bb3fd534e7f574a4653ed36e380e3dc482830d11eef1fb2f892244"} 21:28:24 INFO - Running post-run listener: copy_logs_to_upload_dir 21:28:24 INFO - Copying logs to upload dir... 21:28:24 INFO - mkdir: /builds/slave/test/build/upload/logs 21:28:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1341.780944 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-06-07 21:28:24.812978) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-07 21:28:24.816443) ========= 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.eRkDl5lmQ1/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.HCETlepvW5/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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/Firefox/sha512/05dbe2db68562ae4fc31d6bb278ef2bd1e8e440e5186d6436965d2353d426ab319874325557a696a7c6d802d3673b72abc5e29ffed14b0cd858109bec9bda269", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53232df44a87539c5d6ed2119f27d0d537be2a6ac473929f01648f3fd4a251d3c108df7d0f98ae43a92c870124b7b9ad6db39a61f93fc34e487bf778b31431f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2ab6528f4457a29b29ed59a9d5aa3ee89be9d1e5264e861e4e03e496a802ccbf0a0727b96dfa3cce3c28617c503cba5759bd93556a300c48c93024f78902424", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/993c33e218e6dacc31fd67c22a596989c37be57d38d5f83dbb2c663dca6d6ae0169d81b116bb3fd534e7f574a4653ed36e380e3dc482830d11eef1fb2f892244"} build_url:https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010981 build_url: 'https://queue.taskcluster.net/v1/task/ak9IUoiDQP6NNCSZ8Fj0Ng/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05dbe2db68562ae4fc31d6bb278ef2bd1e8e440e5186d6436965d2353d426ab319874325557a696a7c6d802d3673b72abc5e29ffed14b0cd858109bec9bda269", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53232df44a87539c5d6ed2119f27d0d537be2a6ac473929f01648f3fd4a251d3c108df7d0f98ae43a92c870124b7b9ad6db39a61f93fc34e487bf778b31431f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2ab6528f4457a29b29ed59a9d5aa3ee89be9d1e5264e861e4e03e496a802ccbf0a0727b96dfa3cce3c28617c503cba5759bd93556a300c48c93024f78902424", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/993c33e218e6dacc31fd67c22a596989c37be57d38d5f83dbb2c663dca6d6ae0169d81b116bb3fd534e7f574a4653ed36e380e3dc482830d11eef1fb2f892244"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-07 21:28:24.850392) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 21:28:24.850746) ========= 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.eRkDl5lmQ1/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.HCETlepvW5/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.004955 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 21:28:24.899765) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-07 21:28:24.900106) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-07 21:28:24.900701) ========= ========= Total master_lag: 0.19 =========