builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0222 starttime: 1496711548.68 results: success (0) revision: f9acc145b45ee96a24859b93bd5389bde4925c5e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 18:12:28.683001) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 18:12:28.683463) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 18:12:28.683746) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 18:12:28.736451) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:12:28.736849) ========= 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.aqKYPhpqd3/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.63dzUnKxli/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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-05 18:12:28-- 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% 64.5M=0s 2017-06-05 18:12:29 (64.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.484270 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:12:29.285160) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:12:29.285535) ========= 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.aqKYPhpqd3/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.63dzUnKxli/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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.026849 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:12:29.352771) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-05 18:12:29.353104) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f9acc145b45ee96a24859b93bd5389bde4925c5e --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 f9acc145b45ee96a24859b93bd5389bde4925c5e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aqKYPhpqd3/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.63dzUnKxli/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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-05 18:12:29,459 truncating revision to first 12 chars 2017-06-05 18:12:29,459 Setting DEBUG logging. 2017-06-05 18:12:29,459 attempt 1/10 2017-06-05 18:12:29,459 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f9acc145b45e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 18:12:31,179 unpacking tar archive at: mozilla-central-f9acc145b45e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.019559 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-05 18:12:31.397865) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 18:12:31.398207) ========= script_repo_revision: f9acc145b45ee96a24859b93bd5389bde4925c5e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 18:12:31.398717) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 18:12:31.399391) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 18:12:31.422993) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-05 18:12:31.423553) ========= /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.aqKYPhpqd3/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.63dzUnKxli/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 18:12:31 INFO - MultiFileLogger online at 20170605 18:12:31 in /builds/slave/test 18:12:31 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 18:12:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:12:31 INFO - {'append_to_log': False, 18:12:31 INFO - 'base_work_dir': '/builds/slave/test', 18:12:31 INFO - 'blob_upload_branch': 'Firefox', 18:12:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:12:31 INFO - 'branch': 'Firefox', 18:12:31 INFO - 'buildbot_json_path': 'buildprops.json', 18:12:31 INFO - 'config_files': ('talos/mac_config.py',), 18:12:31 INFO - 'default_actions': ('clobber', 18:12:31 INFO - 'read-buildbot-config', 18:12:31 INFO - 'download-and-extract', 18:12:31 INFO - 'populate-webroot', 18:12:31 INFO - 'create-virtualenv', 18:12:31 INFO - 'install', 18:12:31 INFO - 'setup-mitmproxy', 18:12:31 INFO - 'run-tests'), 18:12:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:12:31 INFO - 'download_minidump_stackwalk': True, 18:12:31 INFO - 'download_symbols': 'ondemand', 18:12:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:12:31 INFO - 'tooltool.py': '/tools/tooltool.py', 18:12:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:12:31 INFO - '/tools/misc-python/virtualenv.py')}, 18:12:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:12:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:12:31 INFO - 'gecko_profile': False, 18:12:31 INFO - 'gecko_profile_interval': 0, 18:12:31 INFO - 'installer_path': 'installer.exe', 18:12:31 INFO - 'log_level': 'info', 18:12:31 INFO - 'log_name': 'talos', 18:12:31 INFO - 'log_to_console': True, 18:12:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:12:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:12:31 INFO - 'opt_config_files': (), 18:12:31 INFO - 'pip_index': False, 18:12:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:12:31 INFO - 'cmd': ('bash', 18:12:31 INFO - '-c', 18:12:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:12:31 INFO - 'enabled': True, 18:12:31 INFO - 'halt_on_failure': False, 18:12:31 INFO - 'name': 'check_screen_resolution'},), 18:12:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:12:31 INFO - 'cmd': ('bash', 18:12:31 INFO - '-c', 18:12:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:12:31 INFO - 'enabled': True, 18:12:31 INFO - 'halt_on_failure': False, 18:12:31 INFO - 'name': 'check_screen_resolution'},), 18:12:31 INFO - 'run_cmd_checks_enabled': True, 18:12:31 INFO - 'suite': 'dromaeojs-e10s', 18:12:31 INFO - 'system_bits': '32', 18:12:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:12:31 INFO - 'title': 't-yosemite-r7-0222', 18:12:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:12:31 INFO - 'use_talos_json': True, 18:12:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:12:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:12:31 INFO - 'work_dir': 'build'} 18:12:31 INFO - [mozharness: 2017-06-06 01:12:31.637561Z] Running clobber step. 18:12:31 INFO - Running pre-action listener: _resource_record_pre_action 18:12:31 INFO - Running main action method: clobber 18:12:31 INFO - rmtree: /builds/slave/test/build 18:12:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:12:34 INFO - Running post-action listener: _resource_record_post_action 18:12:34 INFO - [mozharness: 2017-06-06 01:12:34.015815Z] Finished clobber step (success) 18:12:34 INFO - [mozharness: 2017-06-06 01:12:34.015951Z] Running read-buildbot-config step. 18:12:34 INFO - Running pre-action listener: _resource_record_pre_action 18:12:34 INFO - Running main action method: read_buildbot_config 18:12:34 INFO - Using buildbot properties: 18:12:34 INFO - { 18:12:34 INFO - "project": "", 18:12:34 INFO - "product": "firefox", 18:12:34 INFO - "who": "kwierso@gmail.com", 18:12:34 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:12:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:12:34 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:12:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:12:34 INFO - "stage_platform": "macosx64", 18:12:34 INFO - "basedir": "/builds/slave/test", 18:12:34 INFO - "buildnumber": 16, 18:12:34 INFO - "platform": "yosemite_r7", 18:12:34 INFO - "parent_task_id": "Y6X1dh45QWiFykMT4crFPw", 18:12:34 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:12:34 INFO - "slavebuilddir": "test", 18:12:34 INFO - "taskId": "aB8fma83RcKTVEyDy20cMw", 18:12:34 INFO - "branch": "mozilla-central", 18:12:34 INFO - "script_repo_revision": "production", 18:12:34 INFO - "revision": "f9acc145b45ee96a24859b93bd5389bde4925c5e", 18:12:34 INFO - "slavename": "t-yosemite-r7-0222", 18:12:34 INFO - "repo_path": "mozilla-central" 18:12:34 INFO - } 18:12:34 INFO - Finding installer, test and symbols from parent task. 18:12:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aB8fma83RcKTVEyDy20cMw'}, attempt #1 18:12:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aB8fma83RcKTVEyDy20cMw'}, attempt #1 18:12:34 INFO - Task dependencies: fle4ZStaQtiLTjrdYLfqgw Y6X1dh45QWiFykMT4crFPw 18:12:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw'}, attempt #1 18:12:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:12:35 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/test_packages.json 18:12:35 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/target.crashreporter-symbols.zip 18:12:35 INFO - Running post-action listener: _resource_record_post_action 18:12:35 INFO - [mozharness: 2017-06-06 01:12:35.426699Z] Finished read-buildbot-config step (success) 18:12:35 INFO - [mozharness: 2017-06-06 01:12:35.426804Z] Running download-and-extract step. 18:12:35 INFO - Running pre-action listener: _resource_record_pre_action 18:12:35 INFO - Running main action method: download_and_extract 18:12:35 INFO - mkdir: /builds/slave/test/build/tests 18:12:35 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')]} 18:12:35 INFO - trying https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:12:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/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 18:12:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/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 18:12:36 INFO - Downloaded 1328 bytes. 18:12:36 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:12:36 INFO - Using the following test package requirements: 18:12:36 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:36 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:12:36 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:12:36 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:36 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:12:36 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:36 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:12:36 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:36 INFO - u'jsshell-mac.zip'], 18:12:36 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:36 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:12:36 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:12:36 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:36 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:12:36 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:36 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:12:36 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:36 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 18:12:36 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:12:36 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:12:36 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:12:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:12:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:12:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:12:38 INFO - Content-Length response header: 57635044 18:12:38 INFO - Bytes received: 57635044 18:12:56 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 18:12:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:12:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:12:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:12:58 INFO - Content-Length response header: 57635044 18:12:58 INFO - Bytes received: 57635044 18:13:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:13:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:13:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:13:15 INFO - Content-Length response header: 14447425 18:13:15 INFO - Bytes received: 14447425 18:13:16 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')]} 18:13:16 INFO - trying https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:13:16 INFO - Downloading https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:13:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/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 18:13:18 INFO - Downloaded 67987497 bytes. 18:13:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:13:18 INFO - mkdir: /builds/slave/test/properties 18:13:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:13:18 INFO - Writing to file /builds/slave/test/properties/build_url 18:13:18 INFO - Contents: 18:13:18 INFO - build_url:https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:13:18 INFO - Running post-action listener: _resource_record_post_action 18:13:18 INFO - Running post-action listener: set_extra_try_arguments 18:13:18 INFO - [mozharness: 2017-06-06 01:13:18.330673Z] Finished download-and-extract step (success) 18:13:18 INFO - [mozharness: 2017-06-06 01:13:18.330861Z] Running populate-webroot step. 18:13:18 INFO - Running pre-action listener: _resource_record_pre_action 18:13:18 INFO - Running main action method: populate_webroot 18:13:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:13:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:13:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:13:18 INFO - '--tppagecycles', 18:13:18 INFO - '7'], 18:13:18 INFO - 'tests': ['tsvgm']}}, 18:13:18 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:13:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:13:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:13:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:13:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:13:18 INFO - 'talos_options': ['--disable-e10s'], 18:13:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:13:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:13:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:13:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:13:18 INFO - 'talos_options': ['--disable-e10s'], 18:13:18 INFO - 'tests': ['damp', 'tps']}, 18:13:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:13:18 INFO - 'tests': ['damp', 'tps']}, 18:13:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:13:18 INFO - 'tests': ['dromaeo_dom']}, 18:13:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:13:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:13:18 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:13:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:13:18 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:13:18 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:13:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:13:18 INFO - 'tests': ['a11yr', 18:13:18 INFO - 'ts_paint', 18:13:18 INFO - 'tpaint', 18:13:18 INFO - 'sessionrestore', 18:13:18 INFO - 'sessionrestore_no_auto_restore', 18:13:18 INFO - 'tabpaint']}, 18:13:18 INFO - 'other-e10s': {'tests': ['a11yr', 18:13:18 INFO - 'ts_paint', 18:13:18 INFO - 'tpaint', 18:13:18 INFO - 'sessionrestore', 18:13:18 INFO - 'sessionrestore_no_auto_restore', 18:13:18 INFO - 'tabpaint']}, 18:13:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:13:18 INFO - 'ts_paint', 18:13:18 INFO - 'tpaint', 18:13:18 INFO - 'sessionrestore', 18:13:18 INFO - 'sessionrestore_no_auto_restore', 18:13:18 INFO - 'tabpaint']}, 18:13:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:13:18 INFO - 'ts_paint', 18:13:18 INFO - 'tpaint', 18:13:18 INFO - 'sessionrestore', 18:13:18 INFO - 'sessionrestore_no_auto_restore', 18:13:18 INFO - 'tabpaint']}, 18:13:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:13:18 INFO - 'tests': ['a11yr', 18:13:18 INFO - 'ts_paint', 18:13:18 INFO - 'tpaint', 18:13:18 INFO - 'sessionrestore', 18:13:18 INFO - 'sessionrestore_no_auto_restore', 18:13:18 INFO - 'tabpaint']}, 18:13:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:13:18 INFO - 'tests': ['a11yr', 18:13:18 INFO - 'ts_paint', 18:13:18 INFO - 'tpaint', 18:13:18 INFO - 'sessionrestore', 18:13:18 INFO - 'sessionrestore_no_auto_restore', 18:13:18 INFO - 'tabpaint']}, 18:13:18 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:13:18 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:13:18 INFO - 'bloom_basic_ref']}, 18:13:18 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 18:13:18 INFO - 'talos_options': ['--mitmproxy', 18:13:18 INFO - 'mitmproxy-recording-1.mp', 18:13:18 INFO - '--firstNonBlankPaint'], 18:13:18 INFO - 'tests': ['Quantum_1']}, 18:13:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:13:18 INFO - 'tests': ['tsvgx', 18:13:18 INFO - 'tsvgr_opacity', 18:13:18 INFO - 'tart', 18:13:18 INFO - 'tscrollx', 18:13:18 INFO - 'cart', 18:13:18 INFO - 'tsvg_static']}, 18:13:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:13:18 INFO - 'tsvgr_opacity', 18:13:18 INFO - 'tart', 18:13:18 INFO - 'tscrollx', 18:13:18 INFO - 'cart', 18:13:18 INFO - 'tsvg_static']}, 18:13:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:13:18 INFO - 'talos_options': ['--disable-e10s'], 18:13:18 INFO - 'tests': ['tp5o']}, 18:13:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:13:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:13:18 INFO - 'talos_options': ['--disable-e10s', 18:13:18 INFO - '--xperf_path', 18:13:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:13:18 INFO - 'tests': ['tp5n']}, 18:13:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:13:18 INFO - 'talos_options': ['--xperf_path', 18:13:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:13:18 INFO - 'tests': ['tp5n']}}, 18:13:18 INFO - 'talos.zip': {'path': '', 18:13:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:13:18 INFO - Running post-action listener: _resource_record_post_action 18:13:18 INFO - [mozharness: 2017-06-06 01:13:18.352719Z] Finished populate-webroot step (success) 18:13:18 INFO - [mozharness: 2017-06-06 01:13:18.352884Z] Running create-virtualenv step. 18:13:18 INFO - Running pre-action listener: _resource_record_pre_action 18:13:18 INFO - Running main action method: create_virtualenv 18:13:18 INFO - Creating virtualenv /builds/slave/test/build/venv 18:13:18 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 18:13:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:13:18 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:13:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:13:18 INFO - Using real prefix '/tools/python27' 18:13:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:13:19 INFO - Installing distribute.............................................................................................................................................................................................done. 18:13:22 INFO - Installing pip.................done. 18:13:22 INFO - Return code: 0 18:13:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:13:22 INFO - Reading from file tmpfile_stdout 18:13:22 INFO - Output received: 18:13:22 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) 18:13:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:22 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 0x109704c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109708768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9870cd3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10968dc60>, '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 0x109422cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10944bbe8>, '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.aqKYPhpqd3/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.63dzUnKxli/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 18:13:22 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 18:13:22 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 18:13:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqKYPhpqd3/Render', 18:13:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:22 INFO - 'HOME': '/Users/cltbld', 18:13:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:22 INFO - 'LOGNAME': 'cltbld', 18:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:22 INFO - 'MOZ_NO_REMOTE': '1', 18:13:22 INFO - 'NO_EM_RESTART': '1', 18:13:22 INFO - 'PAGER': '/bin/cat', 18:13:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:22 INFO - 'PWD': '/builds/slave/test', 18:13:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:22 INFO - 'SHELL': '/bin/bash', 18:13:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.63dzUnKxli/Listeners', 18:13:22 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 18:13:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:22 INFO - 'USER': 'cltbld', 18:13:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:22 INFO - 'XPC_FLAGS': '0x0', 18:13:22 INFO - 'XPC_SERVICE_NAME': '0', 18:13:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:22 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 18:13:22 INFO - Cleaning up... 18:13:22 INFO - Return code: 0 18:13:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:22 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 0x109704c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109708768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9870cd3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10968dc60>, '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 0x109422cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10944bbe8>, '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.aqKYPhpqd3/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.63dzUnKxli/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 18:13:22 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 18:13:22 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 18:13:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqKYPhpqd3/Render', 18:13:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:22 INFO - 'HOME': '/Users/cltbld', 18:13:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:22 INFO - 'LOGNAME': 'cltbld', 18:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:22 INFO - 'MOZ_NO_REMOTE': '1', 18:13:22 INFO - 'NO_EM_RESTART': '1', 18:13:22 INFO - 'PAGER': '/bin/cat', 18:13:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:22 INFO - 'PWD': '/builds/slave/test', 18:13:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:22 INFO - 'SHELL': '/bin/bash', 18:13:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.63dzUnKxli/Listeners', 18:13:22 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 18:13:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:22 INFO - 'USER': 'cltbld', 18:13:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:22 INFO - 'XPC_FLAGS': '0x0', 18:13:22 INFO - 'XPC_SERVICE_NAME': '0', 18:13:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:22 INFO - Downloading/unpacking psutil>=3.1.1 18:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:13:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:13:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:13:25 INFO - Installing collected packages: psutil 18:13:25 INFO - Running setup.py install for psutil 18:13:25 INFO - building 'psutil._psutil_osx' extension 18:13:25 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 18:13:25 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 18:13:25 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 18:13:26 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 18:13:26 INFO - building 'psutil._psutil_posix' extension 18:13:26 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 18:13:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:13:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:13:26 INFO - ^ 18:13:26 INFO - 1 warning generated. 18:13:26 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 18:13:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:13:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:13:26 INFO - Successfully installed psutil 18:13:26 INFO - Cleaning up... 18:13:26 INFO - Return code: 0 18:13:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:26 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 0x109704c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109708768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9870cd3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10968dc60>, '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 0x109422cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10944bbe8>, '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.aqKYPhpqd3/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.63dzUnKxli/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 18:13:26 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 18:13:26 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 18:13:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqKYPhpqd3/Render', 18:13:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:26 INFO - 'HOME': '/Users/cltbld', 18:13:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:26 INFO - 'LOGNAME': 'cltbld', 18:13:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:26 INFO - 'MOZ_NO_REMOTE': '1', 18:13:26 INFO - 'NO_EM_RESTART': '1', 18:13:26 INFO - 'PAGER': '/bin/cat', 18:13:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:26 INFO - 'PWD': '/builds/slave/test', 18:13:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:26 INFO - 'SHELL': '/bin/bash', 18:13:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.63dzUnKxli/Listeners', 18:13:26 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 18:13:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:26 INFO - 'USER': 'cltbld', 18:13:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:26 INFO - 'XPC_FLAGS': '0x0', 18:13:26 INFO - 'XPC_SERVICE_NAME': '0', 18:13:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:13:26 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 18:13:26 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 18:13:26 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 18:13:26 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 18:13:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:13:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:13:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:13:28 INFO - Installing collected packages: mozsystemmonitor 18:13:28 INFO - Running setup.py install for mozsystemmonitor 18:13:28 INFO - Successfully installed mozsystemmonitor 18:13:28 INFO - Cleaning up... 18:13:28 INFO - Return code: 0 18:13:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:13:28 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')]} 18:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:28 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')]} 18:13:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:28 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 0x109704c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109708768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9870cd3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10968dc60>, '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 0x109422cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10944bbe8>, '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.aqKYPhpqd3/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.63dzUnKxli/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 18:13:28 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 18:13:28 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 18:13:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqKYPhpqd3/Render', 18:13:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:28 INFO - 'HOME': '/Users/cltbld', 18:13:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:28 INFO - 'LOGNAME': 'cltbld', 18:13:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:28 INFO - 'MOZ_NO_REMOTE': '1', 18:13:28 INFO - 'NO_EM_RESTART': '1', 18:13:28 INFO - 'PAGER': '/bin/cat', 18:13:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:28 INFO - 'PWD': '/builds/slave/test', 18:13:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:28 INFO - 'SHELL': '/bin/bash', 18:13:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.63dzUnKxli/Listeners', 18:13:28 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 18:13:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:28 INFO - 'USER': 'cltbld', 18:13:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:28 INFO - 'XPC_FLAGS': '0x0', 18:13:28 INFO - 'XPC_SERVICE_NAME': '0', 18:13:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:28 INFO - Downloading/unpacking jsonschema==2.5.1 18:13:28 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 18:13:28 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 18:13:28 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 18:13:28 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 18:13:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:13:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:13:30 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 18:13:30 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 18:13:30 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 18:13:30 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 18:13:30 INFO - Downloading functools32-3.2.3-2.tar.gz 18:13:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:13:30 INFO - warning: no files found matching '*.txt' 18:13:30 INFO - no previously-included directories found matching 'build' 18:13:30 INFO - no previously-included directories found matching 'dist' 18:13:30 INFO - no previously-included directories found matching '.git*' 18:13:30 INFO - Installing collected packages: jsonschema, functools32 18:13:30 INFO - Running setup.py install for functools32 18:13:30 INFO - warning: no files found matching '*.txt' 18:13:30 INFO - no previously-included directories found matching 'build' 18:13:30 INFO - no previously-included directories found matching 'dist' 18:13:30 INFO - no previously-included directories found matching '.git*' 18:13:30 INFO - Successfully installed jsonschema functools32 18:13:30 INFO - Cleaning up... 18:13:30 INFO - Return code: 0 18:13:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:30 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 0x109704c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109708768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9870cd3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10968dc60>, '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 0x109422cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10944bbe8>, '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.aqKYPhpqd3/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.63dzUnKxli/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 18:13:30 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 18:13:30 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 18:13:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqKYPhpqd3/Render', 18:13:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:30 INFO - 'HOME': '/Users/cltbld', 18:13:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:30 INFO - 'LOGNAME': 'cltbld', 18:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:30 INFO - 'MOZ_NO_REMOTE': '1', 18:13:30 INFO - 'NO_EM_RESTART': '1', 18:13:30 INFO - 'PAGER': '/bin/cat', 18:13:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:30 INFO - 'PWD': '/builds/slave/test', 18:13:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:30 INFO - 'SHELL': '/bin/bash', 18:13:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.63dzUnKxli/Listeners', 18:13:30 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 18:13:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:30 INFO - 'USER': 'cltbld', 18:13:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:30 INFO - 'XPC_FLAGS': '0x0', 18:13:30 INFO - 'XPC_SERVICE_NAME': '0', 18:13:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:13:30 INFO - Cleaning up... 18:13:30 INFO - Return code: 0 18:13:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:30 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 0x109704c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109708768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9870cd3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10968dc60>, '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 0x109422cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10944bbe8>, '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.aqKYPhpqd3/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.63dzUnKxli/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 18:13:30 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 18:13:30 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 18:13:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqKYPhpqd3/Render', 18:13:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:30 INFO - 'HOME': '/Users/cltbld', 18:13:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:30 INFO - 'LOGNAME': 'cltbld', 18:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:30 INFO - 'MOZ_NO_REMOTE': '1', 18:13:30 INFO - 'NO_EM_RESTART': '1', 18:13:30 INFO - 'PAGER': '/bin/cat', 18:13:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:30 INFO - 'PWD': '/builds/slave/test', 18:13:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:30 INFO - 'SHELL': '/bin/bash', 18:13:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.63dzUnKxli/Listeners', 18:13:30 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 18:13:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:30 INFO - 'USER': 'cltbld', 18:13:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:30 INFO - 'XPC_FLAGS': '0x0', 18:13:30 INFO - 'XPC_SERVICE_NAME': '0', 18:13:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:31 INFO - Downloading/unpacking blobuploader==1.2.4 18:13:31 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 18:13:31 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 18:13:31 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 18:13:31 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 18:13:32 INFO - Downloading blobuploader-1.2.4.tar.gz 18:13:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:13:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:13:32 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 18:13:32 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 18:13:32 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 18:13:32 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 18:13:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:13:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:13:33 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 18:13:33 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 18:13:33 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 18:13:33 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 18:13:33 INFO - Downloading docopt-0.6.1.tar.gz 18:13:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:13:33 INFO - Installing collected packages: blobuploader, requests, docopt 18:13:33 INFO - Running setup.py install for blobuploader 18:13:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:13:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:13:33 INFO - Running setup.py install for requests 18:13:34 INFO - Running setup.py install for docopt 18:13:34 INFO - Successfully installed blobuploader requests docopt 18:13:34 INFO - Cleaning up... 18:13:34 INFO - Return code: 0 18:13:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:13:34 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')]} 18:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:34 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')]} 18:13:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:34 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 0x109704c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109708768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9870cd3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10968dc60>, '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 0x109422cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10944bbe8>, '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.aqKYPhpqd3/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.63dzUnKxli/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 18:13:34 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 18:13:34 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 18:13:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqKYPhpqd3/Render', 18:13:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:34 INFO - 'HOME': '/Users/cltbld', 18:13:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:34 INFO - 'LOGNAME': 'cltbld', 18:13:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:34 INFO - 'MOZ_NO_REMOTE': '1', 18:13:34 INFO - 'NO_EM_RESTART': '1', 18:13:34 INFO - 'PAGER': '/bin/cat', 18:13:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:34 INFO - 'PWD': '/builds/slave/test', 18:13:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:34 INFO - 'SHELL': '/bin/bash', 18:13:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.63dzUnKxli/Listeners', 18:13:34 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 18:13:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:34 INFO - 'USER': 'cltbld', 18:13:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:34 INFO - 'XPC_FLAGS': '0x0', 18:13:34 INFO - 'XPC_SERVICE_NAME': '0', 18:13:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:13:34 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-lqZrKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:13:34 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-r9A0xE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:13:34 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-_wNsyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:13:34 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-7To8rY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:13:35 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-UD3xwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:13:35 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-kmkE9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:13:35 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-mdmmJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:13:35 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-1Uhgsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:13:35 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-MVFWrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:13:35 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-d2m6XP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:13:35 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-4Usgm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:13:35 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-o4l6Xp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:13:35 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-j1ts1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:13:35 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-jLEa8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:13:36 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-kca7ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:13:36 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-rFF03H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:13:36 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-2Qtv62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:13:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:13:36 INFO - Running setup.py install for manifestparser 18:13:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:13:36 INFO - Running setup.py install for mozcrash 18:13:36 INFO - Running setup.py install for mozdebug 18:13:36 INFO - Running setup.py install for mozdevice 18:13:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:13:36 INFO - Running setup.py install for mozfile 18:13:37 INFO - Running setup.py install for mozhttpd 18:13:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:13:37 INFO - Running setup.py install for mozinfo 18:13:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:13:37 INFO - Running setup.py install for mozInstall 18:13:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:13:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:13:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:13:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:13:37 INFO - Running setup.py install for mozleak 18:13:37 INFO - Running setup.py install for mozlog 18:13:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:13:37 INFO - Running setup.py install for moznetwork 18:13:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:13:38 INFO - Running setup.py install for mozprocess 18:13:38 INFO - Running setup.py install for mozprofile 18:13:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:13:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:13:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:13:38 INFO - Running setup.py install for mozrunner 18:13:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:13:38 INFO - Running setup.py install for mozscreenshot 18:13:38 INFO - Running setup.py install for moztest 18:13:38 INFO - Running setup.py install for mozversion 18:13:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:13:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:13:38 INFO - Cleaning up... 18:13:38 INFO - Return code: 0 18:13:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:13:38 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')]} 18:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:38 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')]} 18:13:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:38 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 0x109704c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109708768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9870cd3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10968dc60>, '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 0x109422cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10944bbe8>, '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.aqKYPhpqd3/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.63dzUnKxli/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 18:13:38 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 18:13:38 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 18:13:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqKYPhpqd3/Render', 18:13:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:38 INFO - 'HOME': '/Users/cltbld', 18:13:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:38 INFO - 'LOGNAME': 'cltbld', 18:13:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:38 INFO - 'MOZ_NO_REMOTE': '1', 18:13:38 INFO - 'NO_EM_RESTART': '1', 18:13:38 INFO - 'PAGER': '/bin/cat', 18:13:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:38 INFO - 'PWD': '/builds/slave/test', 18:13:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:38 INFO - 'SHELL': '/bin/bash', 18:13:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.63dzUnKxli/Listeners', 18:13:38 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 18:13:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:38 INFO - 'USER': 'cltbld', 18:13:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:38 INFO - 'XPC_FLAGS': '0x0', 18:13:38 INFO - 'XPC_SERVICE_NAME': '0', 18:13:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:13:39 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-7weESQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:13:39 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)) 18:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:13:39 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-mudLCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:13:39 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)) 18:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:13:39 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-NYie53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:13:39 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)) 18:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:13:39 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-m5N0M0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:13:39 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)) 18:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:13:39 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-9WcgQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:13:39 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)) 18:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:13:39 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-KrNG6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:13:39 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)) 18:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:13:39 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-2LDVUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:13:39 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)) 18:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:13:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-beSZyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:13:40 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)) 18:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:13:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-MewMFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:13:40 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)) 18:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:13:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-_RKb8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:13:40 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)) 18:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:13:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-S0SXaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:13:40 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)) 18:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:13:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-IibF2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:13:40 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)) 18:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:13:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-PEZ92q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:13:40 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)) 18:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:13:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-EZmCLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:13:40 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)) 18:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:13:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-N5hbwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:13:40 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)) 18:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:13:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-zFE8xm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:13:40 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)) 18:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:13:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-a67ESR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:13:41 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)) 18:13:41 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)) 18:13:41 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)) 18:13:41 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)) 18:13:41 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)) 18:13:41 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)) 18:13:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:13:41 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 18:13:41 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 18:13:41 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 18:13:41 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 18:13:43 INFO - Downloading blessings-1.6.tar.gz 18:13:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:13:43 INFO - Installing collected packages: blessings 18:13:43 INFO - Running setup.py install for blessings 18:13:43 INFO - Successfully installed blessings 18:13:43 INFO - Cleaning up... 18:13:43 INFO - Return code: 0 18:13:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:13:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:13:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:13:44 INFO - Reading from file tmpfile_stdout 18:13:44 INFO - Current package versions: 18:13:44 INFO - blessings == 1.6 18:13:44 INFO - blobuploader == 1.2.4 18:13:44 INFO - docopt == 0.6.1 18:13:44 INFO - functools32 == 3.2.3-2 18:13:44 INFO - jsonschema == 2.5.1 18:13:44 INFO - manifestparser == 1.1 18:13:44 INFO - mozInstall == 1.12 18:13:44 INFO - mozcrash == 1.0 18:13:44 INFO - mozdebug == 0.1 18:13:44 INFO - mozdevice == 0.51 18:13:44 INFO - mozfile == 1.2 18:13:44 INFO - mozhttpd == 0.7 18:13:44 INFO - mozinfo == 0.10 18:13:44 INFO - mozleak == 0.1 18:13:44 INFO - mozlog == 3.4 18:13:44 INFO - moznetwork == 0.27 18:13:44 INFO - mozprocess == 0.25 18:13:44 INFO - mozprofile == 0.28 18:13:44 INFO - mozrunner == 6.13 18:13:44 INFO - mozscreenshot == 0.1 18:13:44 INFO - mozsystemmonitor == 0.3 18:13:44 INFO - moztest == 0.8 18:13:44 INFO - mozversion == 1.4 18:13:44 INFO - psutil == 3.1.1 18:13:44 INFO - requests == 1.2.3 18:13:44 INFO - wsgiref == 0.1.2 18:13:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:13:44 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')]} 18:13:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:44 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')]} 18:13:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:44 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 0x109704c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109708768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9870cd3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10968dc60>, '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 0x109422cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10944bbe8>, '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.aqKYPhpqd3/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.63dzUnKxli/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 18:13:44 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 18:13:44 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 18:13:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqKYPhpqd3/Render', 18:13:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:44 INFO - 'HOME': '/Users/cltbld', 18:13:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:44 INFO - 'LOGNAME': 'cltbld', 18:13:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:44 INFO - 'MOZ_NO_REMOTE': '1', 18:13:44 INFO - 'NO_EM_RESTART': '1', 18:13:44 INFO - 'PAGER': '/bin/cat', 18:13:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:44 INFO - 'PWD': '/builds/slave/test', 18:13:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:44 INFO - 'SHELL': '/bin/bash', 18:13:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.63dzUnKxli/Listeners', 18:13:44 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 18:13:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:44 INFO - 'USER': 'cltbld', 18:13:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:44 INFO - 'XPC_FLAGS': '0x0', 18:13:44 INFO - 'XPC_SERVICE_NAME': '0', 18:13:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:44 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)) 18:13:44 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)) 18:13:44 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)) 18:13:44 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)) 18:13:44 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)) 18:13:44 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)) 18:13:44 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)) 18:13:44 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)) 18:13:44 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)) 18:13:44 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:13:44 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 18:13:44 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 18:13:44 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 18:13:44 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 18:13:45 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:13:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:13:45 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)) 18:13:45 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)) 18:13:45 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)) 18:13:45 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)) 18:13:45 INFO - Installing collected packages: marionette-driver 18:13:45 INFO - Running setup.py install for marionette-driver 18:13:46 INFO - Successfully installed marionette-driver 18:13:46 INFO - Cleaning up... 18:13:46 INFO - Return code: 0 18:13:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:13:46 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')]} 18:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:46 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')]} 18:13:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:46 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 0x109704c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109708768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9870cd3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10968dc60>, '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 0x109422cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10944bbe8>, '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.aqKYPhpqd3/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.63dzUnKxli/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 18:13:46 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 18:13:46 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 18:13:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqKYPhpqd3/Render', 18:13:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:46 INFO - 'HOME': '/Users/cltbld', 18:13:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:46 INFO - 'LOGNAME': 'cltbld', 18:13:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:46 INFO - 'MOZ_NO_REMOTE': '1', 18:13:46 INFO - 'NO_EM_RESTART': '1', 18:13:46 INFO - 'PAGER': '/bin/cat', 18:13:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:46 INFO - 'PWD': '/builds/slave/test', 18:13:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:46 INFO - 'SHELL': '/bin/bash', 18:13:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.63dzUnKxli/Listeners', 18:13:46 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 18:13:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:46 INFO - 'USER': 'cltbld', 18:13:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:46 INFO - 'XPC_FLAGS': '0x0', 18:13:46 INFO - 'XPC_SERVICE_NAME': '0', 18:13:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:13:46 INFO - Cleaning up... 18:13:46 INFO - Return code: 0 18:13:46 INFO - Running post-action listener: _resource_record_post_action 18:13:46 INFO - Running post-action listener: _start_resource_monitoring 18:13:46 INFO - Starting resource monitoring. 18:13:46 INFO - [mozharness: 2017-06-06 01:13:46.423351Z] Finished create-virtualenv step (success) 18:13:46 INFO - [mozharness: 2017-06-06 01:13:46.424449Z] Running install step. 18:13:46 INFO - Running pre-action listener: _resource_record_pre_action 18:13:46 INFO - Running main action method: install 18:13:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:13:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:13:46 INFO - Reading from file tmpfile_stdout 18:13:46 INFO - Detecting whether we're running mozinstall >=1.0... 18:13:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:13:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:13:46 INFO - Reading from file tmpfile_stdout 18:13:46 INFO - Output received: 18:13:46 INFO - Usage: mozinstall [options] installer 18:13:46 INFO - Options: 18:13:46 INFO - -h, --help show this help message and exit 18:13:46 INFO - -d DEST, --destination=DEST 18:13:46 INFO - Directory to install application into. [default: 18:13:46 INFO - "/builds/slave/test"] 18:13:46 INFO - --app=APP Application being installed. [default: firefox] 18:13:46 INFO - mkdir: /builds/slave/test/build/application 18:13:46 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'] 18:13:46 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 18:14:08 INFO - Reading from file tmpfile_stdout 18:14:08 INFO - Output received: 18:14:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:14:08 INFO - Running post-action listener: _resource_record_post_action 18:14:08 INFO - [mozharness: 2017-06-06 01:14:08.372826Z] Finished install step (success) 18:14:08 INFO - [mozharness: 2017-06-06 01:14:08.372981Z] Running setup-mitmproxy step. 18:14:08 INFO - Running pre-action listener: _resource_record_pre_action 18:14:08 INFO - Running main action method: setup_mitmproxy 18:14:08 INFO - Skipping: mitmproxy is not required 18:14:08 INFO - Running post-action listener: _resource_record_post_action 18:14:08 INFO - [mozharness: 2017-06-06 01:14:08.373642Z] Finished setup-mitmproxy step (success) 18:14:08 INFO - [mozharness: 2017-06-06 01:14:08.373784Z] Running run-tests step. 18:14:08 INFO - Running pre-action listener: _resource_record_pre_action 18:14:08 INFO - Running main action method: run_tests 18:14:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:14:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:14:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:14:08 INFO - 2017-06-05 18:14:08.388 screenresolution[1648:8640] starting screenresolution argv=screenresolution get 18:14:08 INFO - 2017-06-05 18:14:08.391 screenresolution[1648:8640] Display 0: 1600x1200x32@60 18:14:08 INFO - 2017-06-05 18:14:08.396 screenresolution[1649:8642] starting screenresolution argv=screenresolution list 18:14:08 INFO - Available Modes on Display 0 18:14:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:14:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:14:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:14:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:14:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:14:08 INFO - Intel Iris: 18:14:08 INFO - Chipset Model: Intel Iris 18:14:08 INFO - Type: GPU 18:14:08 INFO - Bus: Built-In 18:14:08 INFO - VRAM (Dynamic, Max): 1536 MB 18:14:08 INFO - Vendor: Intel (0x8086) 18:14:08 INFO - Device ID: 0x0a2e 18:14:08 INFO - Revision ID: 0x0009 18:14:08 INFO - Displays: 18:14:08 INFO - Display: 18:14:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:14:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:14:08 INFO - Display Serial Number: 1600x1200 60 18:14:08 INFO - Main Display: Yes 18:14:08 INFO - Mirror: Off 18:14:08 INFO - Online: Yes 18:14:08 INFO - Rotation: Supported 18:14:08 INFO - Return code: 0 18:14:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:14:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:14:08 INFO - Python 2.7.3 18:14:08 INFO - Return code: 0 18:14:08 INFO - grabbing minidump binary from tooltool 18:14: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')]} 18:14:08 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 0x10968dc60>, '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 0x109422cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10944bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:14:08 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 18:14:08 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 18:14:08 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 18:14:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:14:08 INFO - Return code: 0 18:14:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:14:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:14:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:14:08 INFO - ENV: RUST_BACKTRACE is now 1 18:14:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:14:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:14:08 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/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0222', '--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 18:14:08 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/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0222 --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 18:14:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aqKYPhpqd3/Render', 18:14:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:08 INFO - 'HOME': '/Users/cltbld', 18:14:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:08 INFO - 'LOGNAME': 'cltbld', 18:14:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:14:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:14:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:08 INFO - 'MOZ_NO_REMOTE': '1', 18:14:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:14:08 INFO - 'NO_EM_RESTART': '1', 18:14:08 INFO - 'PAGER': '/bin/cat', 18:14:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:08 INFO - 'PWD': '/builds/slave/test', 18:14:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:14:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:08 INFO - 'RUST_BACKTRACE': '1', 18:14:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:14:08 INFO - 'SHELL': '/bin/bash', 18:14:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.63dzUnKxli/Listeners', 18:14:08 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 18:14:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:08 INFO - 'USER': 'cltbld', 18:14:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:08 INFO - 'XPC_FLAGS': '0x0', 18:14:08 INFO - 'XPC_SERVICE_NAME': '0', 18:14:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:08 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/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0222', '--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 18:14:09 INFO - mozversion application_buildid: 20170605170724 18:14:09 INFO - mozversion application_changeset: f9acc145b45ee96a24859b93bd5389bde4925c5e 18:14:09 INFO - mozversion application_display_name: Nightly 18:14:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:14:09 INFO - mozversion application_name: Firefox 18:14:09 INFO - mozversion application_remotingname: firefox 18:14:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:14:09 INFO - mozversion application_vendor: Mozilla 18:14:09 INFO - mozversion application_version: 55.0a1 18:14:09 INFO - mozversion platform_buildid: 20170605170724 18:14:09 INFO - mozversion platform_changeset: f9acc145b45ee96a24859b93bd5389bde4925c5e 18:14:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:14:09 INFO - mozversion platform_version: 55.0a1 18:14:09 INFO - using testdate: 1496711649 18:14:09 INFO - actual date: 1496711649 18:14:09 INFO - starting webserver on 'localhost:49229' 18:14:09 INFO - SUITE-START | Running 2 tests 18:14:09 INFO - TEST-START | dromaeo_css 18:14:09 INFO - operating with platform_type : mac_ 18:14:09 INFO - Initialising browser for dromaeo_css test... 18:14:09 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpg4N4Ht/profile 18:14:09 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 18:14:43 INFO - TEST-INFO | 1659: killed by SIGTERM 18:14:43 INFO - Browser initialized. 18:14:43 INFO - Running cycle 1/1 for dromaeo_css test... 18:14:43 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpg4N4Ht/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:14:45 INFO - PID 1667 | 2017-06-05 18:14:45.089 plugin-container[1668:9156] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993b, name = 'com.apple.tsm.portname' 18:14:45 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:14:45 INFO - PID 1667 | 2017-06-05 18:14:45.090 plugin-container[1668:9156] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 18:14:45 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:14:45 INFO - PID 1667 | 2017-06-05 18:14:45.090 plugin-container[1668:9156] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:14:45 INFO - PID 1667 | 2017-06-05 18:14:45.588 plugin-container[1669:9206] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 18:14:45 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:14:45 INFO - PID 1667 | 2017-06-05 18:14:45.588 plugin-container[1669:9206] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 18:14:45 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:14:45 INFO - PID 1667 | 2017-06-05 18:14:45.589 plugin-container[1669:9206] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:14:54 INFO - PID 1667 | RSS: Main: 268374016 18:14:54 INFO - PID 1667 | 18:17:57 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 18:17:58 INFO - PID 1667 | RSS: Main: 267849728 18:17:58 INFO - PID 1667 | 18:21:00 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 18:21:01 INFO - PID 1667 | RSS: Main: 284295168 18:21:01 INFO - PID 1667 | 18:24:03 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 18:24:03 INFO - PID 1667 | RSS: Main: 283062272 18:24:03 INFO - PID 1667 | 18:27:05 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 18:27:06 INFO - PID 1667 | RSS: Main: 286953472 18:27:06 INFO - PID 1667 | 18:30:09 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 18:30:10 INFO - PID 1667 | RSS: Main: 287412224 18:30:10 INFO - PID 1667 | 18:33:12 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 18:33:12 INFO - PID 1667 | RSS: Main: 287395840 18:33:12 INFO - PID 1667 | 18:33:12 INFO - PID 1667 | __start_tp_report 18:33:12 INFO - PID 1667 | _x_x_mozilla_page_load 18:33:12 INFO - PID 1667 | _x_x_mozilla_page_load_details 18:33:12 INFO - PID 1667 | |i|pagename|runs| 18:33:12 INFO - PID 1667 | |0;dojo.html;3539.6106428292887;3563.9643603563954;3601.423772196446;3610.891673249802;3624.275144971005;6102.216595924237;6156.9076463853025;6218;6226.782062627809;6234.532410069246;6132.600268413519;6182.598131121474;6211.534134939875;6262.561620686553;6275.6548389838545;5230.189320655304;5402.810901618434;5406.675599464044;5411.377691565481;5493.011257973563;5473.945260547383;5551.500364967153;5553.194786755938;5561.944380556203;5581.944180558183;5366;5392;5468.9726551367385;5485.122380419134;5492.340919089695;5499.065158922993;5500.22996780451;5506.339239291271;5516.310461192351;5543.501084902337;5422.674639521601;5448.019356515825;5455.427180146067;5466;5488.451154884519;6079;6133.73253493014;6170.074488826667;6208.906866397007;6269.686515674198;6701.262861085277;6739.393454589062;6744.865102697968;6755.392014718699;6755.493337999621;6831.351021652935;6831.931680683229;6832.111825462708;6860.862782744317;6866.725330986755;6209.285932117864;6241.594296370721;6293.087502312231;6336.049592561107;6354.968225158845;6910.827229319308;6951.860962780716;6956.095707557985;6977.581345119208;6988.405985491188;6394.5843520171975;6476.935230647728;6484.708188131545;6530.869382612416;6597.703103360264;6786.491013174032;6791.762288319885;6807.761728339485;6814.591124532544;6832.214295355998;5442.755076021588;6431.6462594556815;6504.5121615879;6602.60384376939;6647.966760166265;6548.8362790930605;6645.800625981228;6659.334066593302;6691.26396096429;6703.396694297439;5329.626926115135;5334.51989320963;5355.491228333302;5362.678239305654;5390.110631745718;4704.553067458586;4740.265258884879;4757.643176761757;4768.523147685273;4782.139214941344;4840.394950631171;6154.5076393888385;6202.658853762996;6284.308726040132;6288.339724328971;6819.147606549182;6835;6847.863042739117;6866.725330986705;6870.175578930561;6295.087212354183;6312.621242725451;6318.147050148263;6338.239411270677;6364.459020983269;5800.6809625471005;5866.354700982888;5964.642121472725;6004.639721616717;6073.240844894388;6831;6947.687354069079;6950.826229344307;6964.338387853146;7001.579905205704;6761.188657361148;6793.116894803644;6799.660016999229;6806.319368063154;6826;6686.364795344434;6785.355391237825;6866.34769696878;6903.240643529308;6948;5769.221155144001;5774.913376299275;6513.804585862432;6648.235452922976;6714.261431242657;6607.174103237096;6827.6244806536115;6833.829154271183;6858.862822743417;6886.8622627548175;6377.266614339411;6529.118568993315;6588.176477940257;6593.3736295051895;6624.56940298879;5525.723713814213;6522.967385163044;6581.638009909501;6597.868042639217;6615.933840661724;6423.903641445476;6469.514786390945;6481.027845823164;6489.513286503437;6510.934890651033;6374.07575901501;6440.452561532322;6483.481321494363;6541.901871471837;6564.179477565304;6414.711337989895;6421.8394540135005;6424;6428.967855160694;6437.517186210959 18:33:12 INFO - PID 1667 | |1;ext.html;664.1664710787963;683.8529716111036;752.1726101288582;1258.786006378916;1327.3761332173874;37049.44425833608;39259.41110883339;39934.40098398523;39971.800140999294;40148.19703605926;40819.79590102053;41397.793011034904;41433.58566414335;41435.37846932298;41471.79264103683;7889.368850491961;8072.999999999971;8377.83244335113;8560.400771945966;8569.185927336925;8464.78838029048;8498.660053597849;8589;8600.311975041981;8602;8540.975082990019;8558.657653693845;8588.184122508383;8610.827783444327;8635.006974197984;8557.358198135134;8586.527740974245;8590.87113693295;8591.44155629882;8595.44129631572;8355.206255405727;8382.580870956428;8405.579721013924;8441.409101362908;8451.45065570736;37065.444018339746;37137.257254854754;37178.25643487142;37273.440898386274;37280.25439491195;3590.622984586607;3616.4936908832783;3617.963820361789;3621.149029977953;3621.7283703722064;30814;30827.691723082706;30903.845480772678;31004.53493197604;31138.84430577855;3082.7533797296387;3121.1884909923356;3131.968680313213;3132.216945763559;3137.5293705944064;251027;252580.7370963152;270299.6485017582;313167;314214;48262;48655.5134448651;48727.512724873006;48770.75614621904;48783;45355;45733;50746;51244;51280.48719512832;14262.215578143301;14274.714505709828;14564.126152430888;14581.635459113395;14595.78106328406;11439.59961401347;11546.19176657626;11557;11640.185187036826;11653.941730291464;14065.296735163201;14094.083884547472;14111;14116.50592229267;14236.501722439665;5110.770015349244;6262.123302737529;6267.122602835607;6268.529860260407;6273.215848018997;987.3779518903045;1009.5911155981839;1014.3812274512542;1032.6179313653845;1052.984205236922;23127.53744925092;30406.087817365515;31314;31673;31753.364932701217;997.9820583005294;1028.5782829040206;1052.153016821466;1115.5872327238974;1118.1948996722347;11629.069674426026;11700.590479333183;11767.588134415255;12209.595896471841;12574.685632859251;338027;339645;342307;343026;343027;54689;55071.72464137734;55158.035776780285;55207.44792552103;55386.16920746192;1448.507507447473;1454.3237394611344;1500.317355603198;1512.4933147395525;1521.1405555860904;22363.776362236822;22456.66315005309;23431.765682342957;23875.607578613093;23934.80325983729;30222.54666179965;38480.03799905025;38508.65219717297;38513.80743096267;38562.80718596389;294690;298685;299538.004619951;299948;300645;35338.82330588433;35561.64438355583;35588;35600.821995889855;35635.93092207238;43437.782811087;43635.34546981861;43765;45465.318020230385;45503.54496454993;27622.861885690443;29557.26106847346;29958.70041299559;30415.391692166475;31197.532037019915;23573.882130589238;29225.97709080172;29356.412871742017;30155.547666784576;31178;37613.623863761015;37638;37688.24623507569;37697.2460550793;37793.677221298225;27932.44135117327;29088;29732;31557.89547365831;31637.89267375723;24068;24102;24717;24786.876065619555;24997.250082497554 18:33:12 INFO - PID 1667 | |2;jquery.html;363.55464555919;397.8607487379418;425.7126439653233;439.20942303853064;453.3879262994955;22265.775382353964;23092.88453557734;23142.652860207112;23479.647805282933;24694;25339.986400543956;25776.742232577668;26003;26019.219423417282;26031;7087.503874728758;7206.315400037014;7258.927410725904;7267.709291628328;7275.527090739111;6609.636469994149;6986.196587392438;7042.6478676066245;7073.504854660176;7143.428525717956;7016.754413595525;7017.43860491162;7018.122734658168;7027.332403421692;7028.402585780214;5109.41241757199;6917.8239699251;6999;7018.719251229944;7027.89458158128;6800.591964482147;6813.318668133196;6843.520953533254;6852.383285504328;6880.65596720167;30094.398112037856;30940;31480.685193148005;31490.527642085388;31635.683643163735;2291.4958709083976;2346.7066616672914;2359.044586942291;2361.5749165150264;2362;20045.1981920723;20399;20434.795652043438;20445.693314600292;20455.89772051145;2152.042341158185;2156.353094071788;2270.2167752125492;2285.6914316567218;2342.8125749939963;158299;164876;177620.11189944096;214219;219109;19547.383734009254;20504.794952050586;23915.80420978944;24356.903939322772;26358.945642174098;43002.56997430048;43519.56480435219;43524.56475435205;43532;43626.34560481595;10965.177611679155;11122;11219.1585631078;11261.324320540794;11322.94338528302;7732.884006739903;7901;8349.580571302893;8403.663853445916;8815.911840881638;10726.463676816127;11334.603288885015;11985.460654270579;12010.159288849696;12269.325187114617;4454.37638730578;4758.524147585283;4843.491433399513;4859.2954021666665;4878.512148785093;776.4254451705697;963.5086106085946;983.7983213441228;1007.345225603349;1016.2479764973866;20801;20993;21015.658020666884;21234.681479777817;22234.666480002812;890.4790697441939;936.601944173737;966.777641142527;984.3552473130111;1019.0498506822854;10563.419011954416;11021.559137634566;11156.107511399068;11295;11311.264767790068;520665;522585.3870730698;532216;532325;532657;102253;148493;148686;149309;149484;969.7914948286152;1124.1905827803969;1169.5204965963915;1193.3496244546743;1203.5667159822458;22257.99839007216;23020.539589207787;24333;24588.6311705321;24885.875570622033;65154.348456514825;66756.33243667502;67025.32974670191;67130;68825.65587172032;76831;86442.13557864341;96202;96285;96756;16306.67386652284;16678.082705451317;16987.490375288762;17208.31166753316;17236.310547578458;17700.291988320838;18187.636247275244;18571.978541179826;18591.53521161982;18674.906625466785;14696.77954830657;15140.621484462536;15284.77072843886;15351;15404.46084387041;11265.380404077527;13325.66685832862;13470.461181552628;13472.259025753285;13832.37754301079;13529.18824870511;15664;15781.842181578037;15806.261311255523;15871.127088010271;13654;16519.256633451285;16852.662946741242;16903.239354228845;16940.49178524597;14959;15143.924280378527;15152.545423637308;15177.772333415229;15218 18:33:12 INFO - PID 1667 | |3;mootools.html;1300.323831607564;1321.8083377910202;1443.1052747296678;1453.3895763779212;1454.9490767823124;11792.174547781648;12008.63974080779;12391.69020774481;12426.813597796017;12428.502859885604;10814.405207713573;11302.773944521125;12139.271643701384;12233.327167005811;12269.079819013587;2407.350015495815;2427.3446169534213;2435.1233555919853;2438.061346381647;2445.6820613320333;2140.1332460353506;2230.185982116524;2511.1211076123395;2542.9745702543014;2616.8953241870304;2065.1429656692453;2373.801230378665;2400.747921468238;2411.071737381112;2420.878956052199;2466.5683505386487;2475.405902583385;2498.9875050624723;2499.2252401755513;2499.6750422445148;2504.4865802510626;2513.962290565643;2516;2521.407469244726;2524.9747502525106;11891.762164756656;12065.517379304818;12174.878251217464;12193.39033048353;12212.450439730233;6589.308122647101;6732.394084532367;6771.93228067718;6804.489663275274;6819.24988251292;4063.780865740266;4261.552536983603;4346.717463364871;4359.716618419792;4365.476142862845;1240.7828629989806;1244.8319476870597;1245.5266998540587;1274.3819247664876;1275.3177050278057;16939;17036.403725869535;17066.890652107573;17073.060981646006;17121.143942802933;5532.5020748132865;5636.478150899235;5734.16854556087;5742.741576628978;5881.852953676193;12403.25580465175;12622.053345998904;12636.55772047974;12668.873311266769;12675.556355527513;6508.9023664645065;6632.701528431232;6634.933650663528;6648.900266496006;6652.467802575782;6150.815475535741;6163.137160797492;6169.105479705418;6181.289151747516;6247.812565623039;3682.8895133146048;4352.608265256142;4472;4537.727736335831;4560.338750881104;1164.0105909976453;1193.6359410379916;1244.6203907808024;1285.3894400160036;1288;679.2494293805274;818.4966245758934;818.705266104202;842.700841201377;853.8676505141713;6852.794416167523;7035.613041282779;7481.476296659291;7567.356774674105;7607.9239207608325;918.6509126531876;962.1100482054212;971.7862070344513;981.6858605246396;985.4776968206862;4436.489803672554;4637.60580350674;4716.575508204278;4814.9518504815205;4905.190643543847;15125.848741512664;15288;15476.148811815458;15557.29992150356;15645.295961681752;15061.924690376478;15103.942724009214;15337.386504539916;15460.458883939009;15609.453669121527;8418.242358187792;8599.398042137116;8619.138086191457;8621.69824056155;8632.136786321318;5157.381114266312;6875.3812156906115;7281.963590182015;7458.47790654649;7499.287567681062;10420.426876521864;11213.71965700864;11393.94908916403;14066.929665351608;14074.648133796327;14274.714505710035;14418.990670652953;14422.567322980327;14441.277936103363;14513.709725805636;8808.20726134651;8988;8997.145271199226;9050.683226087056;9068.546572671208;10778.29941053829;10790.406527641055;10848.945755271174;10909;11037.613683521038;8752.256058235122;9126.99603043678;9311.44131352121;9312.34813563044;9420.905790942003;9128.406653567497;9145.508467419395;9171.220446262143;9240.214581760625;9353.064693530592;8308.667653294042;8476.745697629081;8486.957565212135;8497.957510212656;8511.659533618586;6088.6042407243385;6094.933386657229;6207.317195108444;6492.837679057872;6551.541392102507;6253;6261.56169068179;6298.874022519615;6610.504212184202;6746.9325306746305 18:33:12 INFO - PID 1667 | |4;prototype.html;355.68344173685443;375.08292225508626;389.55006966953164;392.21361170852435;407.1246819338423;4806.572680796193;5080.923786143211;6133.478654314383;6456.6771661416915;6524.9673751631235;5236.528712415882;5396.460353964601;6243.906341404882;6261.841559311531;6287.874242515147;3992.7005474589378;4635.721856688592;4643.907121857561;4659.184642687533;4666.953330466686;4172.061286210609;4343.847965321214;4664.6239359389065;4668.929966050512;4690.718556886581;4617.330487079388;4618.907621847561;4621.464579596612;4625.468071171807;4628.282616194479;4002.7598344099447;4603.171429142753;4634.142683603544;4648.674592778507;4660.603848672867;3964.048628329209;3972.423998520227;3976.880693579197;3994.720369574131;4006.1787333596544;5063.493650634944;6109.136713302441;6285.9057114143325;6305.117283580303;6311.431971122575;5519.724013799294;5531.917021244684;5541.168824676291;5548.500634942875;5554.528050066736;6809.659517024129;6829.93170068298;6836.726530938757;6853.485988550879;6878.69045892931;3700.0749812546865;4298.753361525154;4553.317002449659;4576.633869290449;4576.748278844662;6642.93357066428;6805.591664500146;7045.6181033103885;7166.856662866766;7286.489945703803;3875.748076375027;4389.758563278986;4542.409486766765;4599.264117741145;4603.976980115078;4633.212353899845;4666.110225358735;4696.459907110726;4696.906061878812;4703.294505824154;4875.219964805614;5423.620346575702;5443.2107344434835;5494.752736126884;5637.041702910515;6313.715882785285;6364.581459045533;6753.8649227015185;6799.796006119825;6890.138732658418;1757.9208935597928;1759.3314540474537;1772.459399883048;1776.0853160622032;1790;977.4233202410612;1101.2160300313383;1198.8801119887942;1241.069198101424;1250.8624051354343;5364.275822763877;6358.904616430757;6371.23545174582;6427.807165785034;6454.32229615893;5823.941760582425;5945.910811337833;6949.061876646689;7030.964845175844;7060.6116663584;5315.787368505294;6370.108184854124;6376.617402955838;6379.776707815204;6391.872162556816;2990.596269503633;3232.272738633789;3387.8983630491125;3695.5195824543175;3708.3881159608363;6008.699565021731;6211.378862113753;7250.3112204340505;7253.927460725431;7287.198408175096;6044.607100538451;6293;6703.497237707192;7217.639118044077;7277.272272772786;6219.720112594944;7073.115860517435;7249.918784516665;7264.34620884123;7273.036280204421;5921.7631294747725;6521.380468855451;7003.3346832050875;7039.366457018893;7111.324424179695;5912.793052243151;6111.54163437735;6962.547434416747;6979.511434199558;6993.685284162327;6044.304904935812;6913.41235994926;7170.462215333767;7256.818579535554;7260.709571617076;3673.7979411132646;3897.941530877096;4615.723056616614;4624.953750462587;4625.768711564341;5325.600579956441;5979.910301345396;7061.611611361424;7068.621618784287;7105.360517553445;5969.462748352669;6100.054491553817;6823.249442561215;6845.315468453115;6847.794566163023;6833.453323734188;6844.5208835381045;6845.897311540431;6849.760258390932;6850.760223392157;5749.770009199585;5774.740136693775;5981.671008094597;6331.113644089924;6717.865642687021;5253.448387919213;5723.340231332867;6377.617342959437;6848.383645471931;6872.759453419107;6641.269460359454;6647.235567909753;6659.967705005732;6709.396154346132;6739.865202695821 18:33:12 INFO - PID 1667 | |5;yui.html;821.8520666280075;824.2787560884225;829.6639860856352;859.5959898847543;864.5374724522381;14785.038972466782;15390.999585026966;16629.41797037104;16691.248893799777;17086.2311195996;16267.51197464078;17237.82762172378;18109;18170.273189072454;18278.543036424064;909.5452273863068;915.0894859614692;931.4644079654192;933.4352716606458;935.1910597333326;995.2485873165786;1002.4536627538009;1004.2267454060369;1005.9949700251489;1006.5973610555764;915.8809354783868;1032.1484775060567;1032.3908893752684;1035.896410358962;1038.174651152332;1028.9948550257275;1038.7455073507;1041.037040737315;1041.3647674918332;1041.9114375278111;769.1462476650913;769.7883082152397;770.9190534993801;771.1286246541372;771.1941021632408;17057;17104.144792760435;17275.222614982355;17439.825601743945;17444.476665699924;17431.65136697259;17464.47606571805;17600.119994000248;17649.470515884543;18037.7294340585;14850.777238341434;15058.849411505855;15153;15226.238688065552;15284.006539575003;3282.261491164493;3344.2141096842224;3570.9107272318142;3577.6601222884046;3639.909002274938;145325;148166;149058;149118;151734;10610.94694526538;13626.863731362757;13641.658958526115;13663.453461861613;13951.023428359917;26578.468430631277;26973;27226.047088351814;27238.591421128698;27278.181654550393;6259.561830671901;6476.4495017924;7305.4155667546465;7531.284527969834;7626.54240745557;3734.7012239020814;3792.355299599075;3814.7138964577766;3822.560405553397;3822.7897465639658;3728.925421491609;4347.065380943112;4440.222960981815;4456.977715111469;4688.695234809727;956.1251454918811;960.4429430930184;979.9363041402286;980.2354163752142;982.921366290695;634.6921742954663;639.558704493907;646.3988490703691;655.9212894452601;682.2563405887606;11068.612598559012;11447.864321607987;11871.70320741971;11943.283402995674;12814.679633009064;614.5943677173044;652.0610321137547;665.04565947864;673.474689742002;677.7085853083123;5302.681839089667;5476.91784623231;5879.764809407576;5908.729623131047;6594.109795177686;128335.46881777389;132902;134623.3268833669;134861;138287.30856345655;100370.49814751025;110706.44646776715;112926;113165;114604;62807;99508.50245748725;101416.08678567692;101636.49181754045;102888.97111028944;10201.591936322464;12316.81524777147;12647.304398258184;12783.04127190483;12817.428028014901;42187.7890610545;44683.77658111689;44867.10265794731;45043.873903151376;45458.31812522749;56394.7180264096;57185.71407142938;57489.137662934256;57862.132068018174;57931.71034144802;16471.25879335457;16863.409780657617;16915.407960721317;17062.914685426495;17186.65626687484;18603.534911627317;18693.626127477102;18729.90635046816;18732.6253474927;18870.962097084244;16659.416920407726;16884.409045683344;17238.05190714523;17369.305227790748;17387.56531086733;16852.1573921301;17250.913745431193;17477.126143693018;17485.65028699444;17492.650146996733;16766.497005089866;16981.915090424467;17157.485275441755;17353.21910514055;17362.826371736122;11307.43462826839;11796.174267801172;11861.644150675493;11879.82180267314;12014.159008869296;12348.506059757508;13284.202947823162;13300.667483312995;13383.330833458484;13548.187108773505 18:33:12 INFO - PID 1667 | __end_tp_report 18:33:12 INFO - PID 1667 | __start_cc_report 18:33:12 INFO - PID 1667 | _x_x_mozilla_cycle_collect,460 18:33:12 INFO - PID 1667 | __end_cc_report 18:33:12 INFO - PID 1667 | __startTimestamp1496712792324__endTimestamp 18:33:12 INFO - PID 1667 | 18:33:12 INFO - PID 1667 | ------- Summary: start ------- 18:33:12 INFO - PID 1667 | Number of tests: 6 18:33:12 INFO - PID 1667 | 18:33:12 INFO - PID 1667 | [#0] dojo.html Cycles:165 Average:6173.93 Median:6414.71 stddev:736.96 (11.5%) stddev-sans-first:709.65 18:33:12 INFO - PID 1667 | Values: 3539.6 3564.0 3601.4 3610.9 3624.3 6102.2 6156.9 6218.0 6226.8 6234.5 6132.6 6182.6 6211.5 6262.6 6275.7 5230.2 5402.8 5406.7 5411.4 5493.0 5473.9 5551.5 5553.2 5561.9 5581.9 5366.0 5392.0 5469.0 5485.1 5492.3 5499.1 5500.2 5506.3 5516.3 5543.5 5422.7 5448.0 5455.4 5466.0 5488.5 6079.0 6133.7 6170.1 6208.9 6269.7 6701.3 6739.4 6744.9 6755.4 6755.5 6831.4 6831.9 6832.1 6860.9 6866.7 6209.3 6241.6 6293.1 6336.0 6355.0 6910.8 6951.9 6956.1 6977.6 6988.4 6394.6 6476.9 6484.7 6530.9 6597.7 6786.5 6791.8 6807.8 6814.6 6832.2 5442.8 6431.6 6504.5 6602.6 6648.0 6548.8 6645.8 6659.3 6691.3 6703.4 5329.6 5334.5 5355.5 5362.7 5390.1 4704.6 4740.3 4757.6 4768.5 4782.1 4840.4 6154.5 6202.7 6284.3 6288.3 6819.1 6835.0 6847.9 6866.7 6870.2 6295.1 6312.6 6318.1 6338.2 6364.5 5800.7 5866.4 5964.6 6004.6 6073.2 6831.0 6947.7 6950.8 6964.3 7001.6 6761.2 6793.1 6799.7 6806.3 6826.0 6686.4 6785.4 6866.3 6903.2 6948.0 5769.2 5774.9 6513.8 6648.2 6714.3 6607.2 6827.6 6833.8 6858.9 6886.9 6377.3 6529.1 6588.2 6593.4 6624.6 5525.7 6523.0 6581.6 6597.9 6615.9 6423.9 6469.5 6481.0 6489.5 6510.9 6374.1 6440.5 6483.5 6541.9 6564.2 6414.7 6421.8 6424.0 6429.0 6437.5 18:33:12 INFO - PID 1667 | 18:33:12 INFO - PID 1667 | [#1] ext.html Cycles:180 Average:45933.41 Median:189182.91 stddev:80882.71 (42.8%) stddev-sans-first:81038.19 18:33:12 INFO - PID 1667 | Values: 664.2 683.9 752.2 1258.8 1327.4 37049.4 39259.4 39934.4 39971.8 40148.2 40819.8 41397.8 41433.6 41435.4 41471.8 7889.4 8073.0 8377.8 8560.4 8569.2 8464.8 8498.7 8589.0 8600.3 8602.0 8541.0 8558.7 8588.2 8610.8 8635.0 8557.4 8586.5 8590.9 8591.4 8595.4 8355.2 8382.6 8405.6 8441.4 8451.5 37065.4 37137.3 37178.3 37273.4 37280.3 3590.6 3616.5 3618.0 3621.1 3621.7 30814.0 30827.7 30903.8 31004.5 31138.8 3082.8 3121.2 3132.0 3132.2 3137.5 251027.0 252580.7 270299.6 313167.0 314214.0 48262.0 48655.5 48727.5 48770.8 48783.0 45355.0 45733.0 50746.0 51244.0 51280.5 14262.2 14274.7 14564.1 14581.6 14595.8 11439.6 11546.2 11557.0 11640.2 11653.9 14065.3 14094.1 14111.0 14116.5 14236.5 5110.8 6262.1 6267.1 6268.5 6273.2 987.4 1009.6 1014.4 1032.6 1053.0 23127.5 30406.1 31314.0 31673.0 31753.4 998.0 1028.6 1052.2 1115.6 1118.2 11629.1 11700.6 11767.6 12209.6 12574.7 338027.0 339645.0 342307.0 343026.0 343027.0 54689.0 55071.7 55158.0 55207.4 55386.2 1448.5 1454.3 1500.3 1512.5 1521.1 22363.8 22456.7 23431.8 23875.6 23934.8 30222.5 38480.0 38508.7 38513.8 38562.8 294690.0 298685.0 299538.0 299948.0 300645.0 35338.8 35561.6 35588.0 35600.8 35635.9 43437.8 43635.3 43765.0 45465.3 45503.5 27622.9 29557.3 29958.7 30415.4 31197.5 23573.9 29226.0 29356.4 30155.5 31178.0 37613.6 37638.0 37688.2 37697.2 37793.7 27932.4 29088.0 29732.0 31557.9 31637.9 24068.0 24102.0 24717.0 24786.9 24997.3 18:33:12 INFO - PID 1667 | 18:33:12 INFO - PID 1667 | [#2] jquery.html Cycles:180 Average:39552.12 Median:12913.18 stddev:91425.08 (708.0%) stddev-sans-first:91634.21 18:33:12 INFO - PID 1667 | Values: 363.6 397.9 425.7 439.2 453.4 22265.8 23092.9 23142.7 23479.6 24694.0 25340.0 25776.7 26003.0 26019.2 26031.0 7087.5 7206.3 7258.9 7267.7 7275.5 6609.6 6986.2 7042.6 7073.5 7143.4 7016.8 7017.4 7018.1 7027.3 7028.4 5109.4 6917.8 6999.0 7018.7 7027.9 6800.6 6813.3 6843.5 6852.4 6880.7 30094.4 30940.0 31480.7 31490.5 31635.7 2291.5 2346.7 2359.0 2361.6 2362.0 20045.2 20399.0 20434.8 20445.7 20455.9 2152.0 2156.4 2270.2 2285.7 2342.8 158299.0 164876.0 177620.1 214219.0 219109.0 19547.4 20504.8 23915.8 24356.9 26358.9 43002.6 43519.6 43524.6 43532.0 43626.3 10965.2 11122.0 11219.2 11261.3 11322.9 7732.9 7901.0 8349.6 8403.7 8815.9 10726.5 11334.6 11985.5 12010.2 12269.3 4454.4 4758.5 4843.5 4859.3 4878.5 776.4 963.5 983.8 1007.3 1016.2 20801.0 20993.0 21015.7 21234.7 22234.7 890.5 936.6 966.8 984.4 1019.0 10563.4 11021.6 11156.1 11295.0 11311.3 520665.0 522585.4 532216.0 532325.0 532657.0 102253.0 148493.0 148686.0 149309.0 149484.0 969.8 1124.2 1169.5 1193.3 1203.6 22258.0 23020.5 24333.0 24588.6 24885.9 65154.3 66756.3 67025.3 67130.0 68825.7 76831.0 86442.1 96202.0 96285.0 96756.0 16306.7 16678.1 16987.5 17208.3 17236.3 17700.3 18187.6 18572.0 18591.5 18674.9 14696.8 15140.6 15284.8 15351.0 15404.5 11265.4 13325.7 13470.5 13472.3 13832.4 13529.2 15664.0 15781.8 15806.3 15871.1 13654.0 16519.3 16852.7 16903.2 16940.5 14959.0 15143.9 15152.5 15177.8 15218.0 18:33:12 INFO - PID 1667 | 18:33:12 INFO - PID 1667 | [#3] mootools.html Cycles:180 Average:7267.94 Median:3873.34 stddev:4623.53 (119.4%) stddev-sans-first:4614.76 18:33:12 INFO - PID 1667 | Values: 1300.3 1321.8 1443.1 1453.4 1454.9 11792.2 12008.6 12391.7 12426.8 12428.5 10814.4 11302.8 12139.3 12233.3 12269.1 2407.4 2427.3 2435.1 2438.1 2445.7 2140.1 2230.2 2511.1 2543.0 2616.9 2065.1 2373.8 2400.7 2411.1 2420.9 2466.6 2475.4 2499.0 2499.2 2499.7 2504.5 2514.0 2516.0 2521.4 2525.0 11891.8 12065.5 12174.9 12193.4 12212.5 6589.3 6732.4 6771.9 6804.5 6819.2 4063.8 4261.6 4346.7 4359.7 4365.5 1240.8 1244.8 1245.5 1274.4 1275.3 16939.0 17036.4 17066.9 17073.1 17121.1 5532.5 5636.5 5734.2 5742.7 5881.9 12403.3 12622.1 12636.6 12668.9 12675.6 6508.9 6632.7 6634.9 6648.9 6652.5 6150.8 6163.1 6169.1 6181.3 6247.8 3682.9 4352.6 4472.0 4537.7 4560.3 1164.0 1193.6 1244.6 1285.4 1288.0 679.2 818.5 818.7 842.7 853.9 6852.8 7035.6 7481.5 7567.4 7607.9 918.7 962.1 971.8 981.7 985.5 4436.5 4637.6 4716.6 4815.0 4905.2 15125.8 15288.0 15476.1 15557.3 15645.3 15061.9 15103.9 15337.4 15460.5 15609.5 8418.2 8599.4 8619.1 8621.7 8632.1 5157.4 6875.4 7282.0 7458.5 7499.3 10420.4 11213.7 11393.9 14066.9 14074.6 14274.7 14419.0 14422.6 14441.3 14513.7 8808.2 8988.0 8997.1 9050.7 9068.5 10778.3 10790.4 10848.9 10909.0 11037.6 8752.3 9127.0 9311.4 9312.3 9420.9 9128.4 9145.5 9171.2 9240.2 9353.1 8308.7 8476.7 8487.0 8498.0 8511.7 6088.6 6094.9 6207.3 6492.8 6551.5 6253.0 6261.6 6298.9 6610.5 6746.9 18:33:12 INFO - PID 1667 | 18:33:12 INFO - PID 1667 | [#4] prototype.html Cycles:180 Average:5394.49 Median:5974.69 stddev:1695.05 (28.4%) stddev-sans-first:1657.07 18:33:12 INFO - PID 1667 | Values: 355.7 375.1 389.6 392.2 407.1 4806.6 5080.9 6133.5 6456.7 6525.0 5236.5 5396.5 6243.9 6261.8 6287.9 3992.7 4635.7 4643.9 4659.2 4667.0 4172.1 4343.8 4664.6 4668.9 4690.7 4617.3 4618.9 4621.5 4625.5 4628.3 4002.8 4603.2 4634.1 4648.7 4660.6 3964.0 3972.4 3976.9 3994.7 4006.2 5063.5 6109.1 6285.9 6305.1 6311.4 5519.7 5531.9 5541.2 5548.5 5554.5 6809.7 6829.9 6836.7 6853.5 6878.7 3700.1 4298.8 4553.3 4576.6 4576.7 6642.9 6805.6 7045.6 7166.9 7286.5 3875.7 4389.8 4542.4 4599.3 4604.0 4633.2 4666.1 4696.5 4696.9 4703.3 4875.2 5423.6 5443.2 5494.8 5637.0 6313.7 6364.6 6753.9 6799.8 6890.1 1757.9 1759.3 1772.5 1776.1 1790.0 977.4 1101.2 1198.9 1241.1 1250.9 5364.3 6358.9 6371.2 6427.8 6454.3 5823.9 5945.9 6949.1 7031.0 7060.6 5315.8 6370.1 6376.6 6379.8 6391.9 2990.6 3232.3 3387.9 3695.5 3708.4 6008.7 6211.4 7250.3 7253.9 7287.2 6044.6 6293.0 6703.5 7217.6 7277.3 6219.7 7073.1 7249.9 7264.3 7273.0 5921.8 6521.4 7003.3 7039.4 7111.3 5912.8 6111.5 6962.5 6979.5 6993.7 6044.3 6913.4 7170.5 7256.8 7260.7 3673.8 3897.9 4615.7 4625.0 4625.8 5325.6 5979.9 7061.6 7068.6 7105.4 5969.5 6100.1 6823.2 6845.3 6847.8 6833.5 6844.5 6845.9 6849.8 6850.8 5749.8 5774.7 5981.7 6331.1 6717.9 5253.4 5723.3 6377.6 6848.4 6872.8 6641.3 6647.2 6660.0 6709.4 6739.9 18:33:12 INFO - PID 1667 | 18:33:12 INFO - PID 1667 | [#5] yui.html Cycles:180 Average:24462.81 Median:17454.48 stddev:37198.52 (213.1%) stddev-sans-first:37260.52 18:33:12 INFO - PID 1667 | Values: 821.9 824.3 829.7 859.6 864.5 14785.0 15391.0 16629.4 16691.2 17086.2 16267.5 17237.8 18109.0 18170.3 18278.5 909.5 915.1 931.5 933.4 935.2 995.2 1002.5 1004.2 1006.0 1006.6 915.9 1032.1 1032.4 1035.9 1038.2 1029.0 1038.7 1041.0 1041.4 1041.9 769.1 769.8 770.9 771.1 771.2 17057.0 17104.1 17275.2 17439.8 17444.5 17431.7 17464.5 17600.1 17649.5 18037.7 14850.8 15058.8 15153.0 15226.2 15284.0 3282.3 3344.2 3570.9 3577.7 3639.9 145325.0 148166.0 149058.0 149118.0 151734.0 10610.9 13626.9 13641.7 13663.5 13951.0 26578.5 26973.0 27226.0 27238.6 27278.2 6259.6 6476.4 7305.4 7531.3 7626.5 3734.7 3792.4 3814.7 3822.6 3822.8 3728.9 4347.1 4440.2 4457.0 4688.7 956.1 960.4 979.9 980.2 982.9 634.7 639.6 646.4 655.9 682.3 11068.6 11447.9 11871.7 11943.3 12814.7 614.6 652.1 665.0 673.5 677.7 5302.7 5476.9 5879.8 5908.7 6594.1 128335.5 132902.0 134623.3 134861.0 138287.3 100370.5 110706.4 112926.0 113165.0 114604.0 62807.0 99508.5 101416.1 101636.5 102889.0 10201.6 12316.8 12647.3 12783.0 12817.4 42187.8 44683.8 44867.1 45043.9 45458.3 56394.7 57185.7 57489.1 57862.1 57931.7 16471.3 16863.4 16915.4 17062.9 17186.7 18603.5 18693.6 18729.9 18732.6 18871.0 16659.4 16884.4 17238.1 17369.3 17387.6 16852.2 17250.9 17477.1 17485.7 17492.7 16766.5 16981.9 17157.5 17353.2 17362.8 11307.4 11796.2 11861.6 11879.8 12014.2 12348.5 13284.2 13300.7 13383.3 13548.2 18:33:12 INFO - PID 1667 | -------- Summary: end -------- 18:33:12 INFO - PID 1667 | 18:33:12 INFO - TEST-INFO | 1667: exit 0 18:33:12 INFO - TEST-OK | dromaeo_css | took 1143907ms 18:33:12 INFO - TEST-START | kraken 18:33:12 INFO - operating with platform_type : mac_ 18:33:12 INFO - Initialising browser for kraken test... 18:33:12 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpV8ljSB/profile 18:33:12 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 18:33:46 INFO - TEST-INFO | 1674: killed by SIGTERM 18:33:46 INFO - Browser initialized. 18:33:46 INFO - Running cycle 1/1 for kraken test... 18:33:46 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpV8ljSB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:33:49 INFO - PID 1678 | 2017-06-05 18:33:49.042 plugin-container[1679:11616] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 18:33:49 INFO - PID 1678 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:33:49 INFO - PID 1678 | 2017-06-05 18:33:49.043 plugin-container[1679:11616] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e1f, name = 'com.apple.CFPasteboardClient' 18:33:49 INFO - PID 1678 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:33:49 INFO - PID 1678 | 2017-06-05 18:33:49.043 plugin-container[1679:11616] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:33:49 INFO - PID 1678 | 2017-06-05 18:33:49.477 plugin-container[1680:11662] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 18:33:49 INFO - PID 1678 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:33:49 INFO - PID 1678 | 2017-06-05 18:33:49.478 plugin-container[1680:11662] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 18:33:49 INFO - PID 1678 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:33:49 INFO - PID 1678 | 2017-06-05 18:33:49.478 plugin-container[1680:11662] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:33:58 INFO - PID 1678 | RSS: Main: 268443648 18:33:58 INFO - PID 1678 | 18:34:01 INFO - PID 1678 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 18:34:02 INFO - PID 1678 | RSS: Main: 276287488 18:34:02 INFO - PID 1678 | 18:34:04 INFO - PID 1678 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 18:34:05 INFO - PID 1678 | RSS: Main: 284418048 18:34:05 INFO - PID 1678 | 18:34:08 INFO - PID 1678 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 18:34:09 INFO - PID 1678 | RSS: Main: 283467776 18:34:09 INFO - PID 1678 | 18:34:11 INFO - PID 1678 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 18:34:12 INFO - PID 1678 | RSS: Main: 286089216 18:34:12 INFO - PID 1678 | 18:34:14 INFO - PID 1678 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:34:14 INFO - PID 1678 | RSS: Main: 286281728 18:34:14 INFO - PID 1678 | 18:34:17 INFO - PID 1678 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 18:34:18 INFO - PID 1678 | RSS: Main: 290516992 18:34:18 INFO - PID 1678 | 18:34:21 INFO - PID 1678 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 18:34:21 INFO - PID 1678 | RSS: Main: 293953536 18:34:21 INFO - PID 1678 | 18:34:25 INFO - PID 1678 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 18:34:25 INFO - PID 1678 | RSS: Main: 292671488 18:34:25 INFO - PID 1678 | 18:34:27 INFO - PID 1678 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:34:27 INFO - PID 1678 | RSS: Main: 292487168 18:34:27 INFO - PID 1678 | 18:34:29 INFO - PID 1678 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:34:30 INFO - PID 1678 | RSS: Main: 292454400 18:34:30 INFO - PID 1678 | 18:34:32 INFO - PID 1678 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:34:33 INFO - PID 1678 | RSS: Main: 292716544 18:34:33 INFO - PID 1678 | 18:34:35 INFO - PID 1678 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:34:36 INFO - PID 1678 | RSS: Main: 292061184 18:34:36 INFO - PID 1678 | 18:34:39 INFO - PID 1678 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:34:40 INFO - PID 1678 | RSS: Main: 292130816 18:34:40 INFO - PID 1678 | 18:34:41 INFO - PID 1678 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 18:34:42 INFO - PID 1678 | RSS: Main: 294072320 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | __start_tp_report 18:34:42 INFO - PID 1678 | _x_x_mozilla_page_load 18:34:42 INFO - PID 1678 | _x_x_mozilla_page_load_details 18:34:42 INFO - PID 1678 | |i|pagename|runs| 18:34:42 INFO - PID 1678 | |0;ai-astar;104;118;115;113;115;114;116;115;116;111 18:34:42 INFO - PID 1678 | |1;audio-beat-detection;146;111;112;154;115;110;128;90;134;113 18:34:42 INFO - PID 1678 | |2;audio-dft;199;197;207;198;208;208;207;237;217;193 18:34:42 INFO - PID 1678 | |3;audio-fft;66;78;86;111;84;72;58;58;84;84 18:34:42 INFO - PID 1678 | |4;audio-oscillator;77;70;84;69;67;67;87;78;99;95 18:34:42 INFO - PID 1678 | |5;imaging-gaussian-blur;90;91;90;90;92;89;90;95;90;88 18:34:42 INFO - PID 1678 | |6;imaging-darkroom;96;98;97;97;98;97;97;97;98;97 18:34:42 INFO - PID 1678 | |7;imaging-desaturate;93;103;142;84;143;145;89;90;94;89 18:34:42 INFO - PID 1678 | |8;json-parse-financial;49;51;51;52;51;50;53;51;54;52 18:34:42 INFO - PID 1678 | |9;json-stringify-tinderbox;58;60;56;74;62;63;58;57;59;58 18:34:42 INFO - PID 1678 | |10;stanford-crypto-aes;88;85;86;87;89;85;91;90;110;91 18:34:42 INFO - PID 1678 | |11;stanford-crypto-ccm;138;138;133;137;133;137;139;137;152;147 18:34:42 INFO - PID 1678 | |12;stanford-crypto-pbkdf2;184;188;182;184;187;180;242;184;185;183 18:34:42 INFO - PID 1678 | |13;stanford-crypto-sha256-iterative;65;64;68;68;68;68;69;63;65;69 18:34:42 INFO - PID 1678 | __end_tp_report 18:34:42 INFO - PID 1678 | __start_cc_report 18:34:42 INFO - PID 1678 | _x_x_mozilla_cycle_collect,1200 18:34:42 INFO - PID 1678 | __end_cc_report 18:34:42 INFO - PID 1678 | __startTimestamp1496712882158__endTimestamp 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | ------- Summary: start ------- 18:34:42 INFO - PID 1678 | Number of tests: 14 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | [#0] ai-astar Cycles:10 Average:113.70 Median:115.00 stddev:3.89 (3.4%) stddev-sans-first:1.99 18:34:42 INFO - PID 1678 | Values: 104.0 118.0 115.0 113.0 115.0 114.0 116.0 115.0 116.0 111.0 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:131.00 stddev:19.13 (14.6%) stddev-sans-first:18.08 18:34:42 INFO - PID 1678 | Values: 146.0 111.0 112.0 154.0 115.0 110.0 128.0 90.0 134.0 113.0 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | [#2] audio-dft Cycles:10 Average:207.10 Median:207.50 stddev:12.66 (6.1%) stddev-sans-first:13.09 18:34:42 INFO - PID 1678 | Values: 199.0 197.0 207.0 198.0 208.0 208.0 207.0 237.0 217.0 193.0 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | [#3] audio-fft Cycles:10 Average:78.10 Median:81.00 stddev:15.78 (19.5%) stddev-sans-first:16.12 18:34:42 INFO - PID 1678 | Values: 66.0 78.0 86.0 111.0 84.0 72.0 58.0 58.0 84.0 84.0 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | [#4] audio-oscillator Cycles:10 Average:79.30 Median:81.00 stddev:11.63 (14.4%) stddev-sans-first:12.31 18:34:42 INFO - PID 1678 | Values: 77.0 70.0 84.0 69.0 67.0 67.0 87.0 78.0 99.0 95.0 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:90.00 stddev:1.90 (2.1%) stddev-sans-first:2.01 18:34:42 INFO - PID 1678 | Values: 90.0 91.0 90.0 90.0 92.0 89.0 90.0 95.0 90.0 88.0 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:97.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 18:34:42 INFO - PID 1678 | Values: 96.0 98.0 97.0 97.0 98.0 97.0 97.0 97.0 98.0 97.0 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | [#7] imaging-desaturate Cycles:10 Average:107.20 Median:89.00 stddev:25.41 (28.6%) stddev-sans-first:26.43 18:34:42 INFO - PID 1678 | Values: 93.0 103.0 142.0 84.0 143.0 145.0 89.0 90.0 94.0 89.0 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:51.50 stddev:1.43 (2.8%) stddev-sans-first:1.22 18:34:42 INFO - PID 1678 | Values: 49.0 51.0 51.0 52.0 51.0 50.0 53.0 51.0 54.0 52.0 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:59.50 stddev:5.21 (8.8%) stddev-sans-first:5.45 18:34:42 INFO - PID 1678 | Values: 58.0 60.0 56.0 74.0 62.0 63.0 58.0 57.0 59.0 58.0 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:88.50 stddev:7.32 (8.3%) stddev-sans-first:7.72 18:34:42 INFO - PID 1678 | Values: 88.0 85.0 86.0 87.0 89.0 85.0 91.0 90.0 110.0 91.0 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:139.10 Median:138.00 stddev:5.95 (4.3%) stddev-sans-first:6.30 18:34:42 INFO - PID 1678 | Values: 138.0 138.0 133.0 137.0 133.0 137.0 139.0 137.0 152.0 147.0 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.90 Median:184.50 stddev:18.45 (10.0%) stddev-sans-first:19.44 18:34:42 INFO - PID 1678 | Values: 184.0 188.0 182.0 184.0 187.0 180.0 242.0 184.0 185.0 183.0 18:34:42 INFO - PID 1678 | 18:34:42 INFO - PID 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:68.00 stddev:2.21 (3.3%) stddev-sans-first:2.26 18:34:42 INFO - PID 1678 | Values: 65.0 64.0 68.0 68.0 68.0 68.0 69.0 63.0 65.0 69.0 18:34:42 INFO - PID 1678 | -------- Summary: end -------- 18:34:42 INFO - PID 1678 | 18:34:42 INFO - TEST-INFO | 1678: exit 0 18:34:43 INFO - TEST-OK | kraken | took 90102ms 18:34:43 INFO - SUITE-END | took 1234s 18:34:43 INFO - Completed test suite (00:20:34) 18:34:43 INFO - javascript benchmark 18:34:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8274.699807839186, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3539.6106428292887, 3563.9643603563954, 3601.423772196446, 3610.891673249802, 3624.275144971005, 6102.216595924237, 6156.9076463853025, 6218.0, 6226.782062627809, 6234.532410069246, 6132.600268413519, 6182.598131121474, 6211.534134939875, 6262.561620686553, 6275.6548389838545, 5230.189320655304, 5402.810901618434, 5406.675599464044, 5411.377691565481, 5493.011257973563, 5473.945260547383, 5551.500364967153, 5553.194786755938, 5561.944380556203, 5581.944180558183, 5366.0, 5392.0, 5468.9726551367385, 5485.122380419134, 5492.340919089695, 5499.065158922993, 5500.22996780451, 5506.339239291271, 5516.310461192351, 5543.501084902337, 5422.674639521601, 5448.019356515825, 5455.427180146067, 5466.0, 5488.451154884519, 6079.0, 6133.73253493014, 6170.074488826667, 6208.906866397007, 6269.686515674198, 6701.262861085277, 6739.393454589062, 6744.865102697968, 6755.392014718699, 6755.493337999621, 6831.351021652935, 6831.931680683229, 6832.111825462708, 6860.862782744317, 6866.725330986755, 6209.285932117864, 6241.594296370721, 6293.087502312231, 6336.049592561107, 6354.968225158845, 6910.827229319308, 6951.860962780716, 6956.095707557985, 6977.581345119208, 6988.405985491188, 6394.5843520171975, 6476.935230647728, 6484.708188131545, 6530.869382612416, 6597.703103360264, 6786.491013174032, 6791.762288319885, 6807.761728339485, 6814.591124532544, 6832.214295355998, 5442.755076021588, 6431.6462594556815, 6504.5121615879, 6602.60384376939, 6647.966760166265, 6548.8362790930605, 6645.800625981228, 6659.334066593302, 6691.26396096429, 6703.396694297439, 5329.626926115135, 5334.51989320963, 5355.491228333302, 5362.678239305654, 5390.110631745718, 4704.553067458586, 4740.265258884879, 4757.643176761757, 4768.523147685273, 4782.139214941344, 4840.394950631171, 6154.5076393888385, 6202.658853762996, 6284.308726040132, 6288.339724328971, 6819.147606549182, 6835.0, 6847.863042739117, 6866.725330986705, 6870.175578930561, 6295.087212354183, 6312.621242725451, 6318.147050148263, 6338.239411270677, 6364.459020983269, 5800.6809625471005, 5866.354700982888, 5964.642121472725, 6004.639721616717, 6073.240844894388, 6831.0, 6947.687354069079, 6950.826229344307, 6964.338387853146, 7001.579905205704, 6761.188657361148, 6793.116894803644, 6799.660016999229, 6806.319368063154, 6826.0, 6686.364795344434, 6785.355391237825, 6866.34769696878, 6903.240643529308, 6948.0, 5769.221155144001, 5774.913376299275, 6513.804585862432, 6648.235452922976, 6714.261431242657, 6607.174103237096, 6827.6244806536115, 6833.829154271183, 6858.862822743417, 6886.8622627548175, 6377.266614339411, 6529.118568993315, 6588.176477940257, 6593.3736295051895, 6624.56940298879, 5525.723713814213, 6522.967385163044, 6581.638009909501, 6597.868042639217, 6615.933840661724, 6423.903641445476, 6469.514786390945, 6481.027845823164, 6489.513286503437, 6510.934890651033, 6374.07575901501, 6440.452561532322, 6483.481321494363, 6541.901871471837, 6564.179477565304, 6414.711337989895, 6421.8394540135005, 6424.0, 6428.967855160694, 6437.517186210959], "value": 6125.116771716441, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.1664710787963, 683.8529716111036, 752.1726101288582, 1258.786006378916, 1327.3761332173874, 37049.44425833608, 39259.41110883339, 39934.40098398523, 39971.800140999294, 40148.19703605926, 40819.79590102053, 41397.793011034904, 41433.58566414335, 41435.37846932298, 41471.79264103683, 7889.368850491961, 8072.999999999971, 8377.83244335113, 8560.400771945966, 8569.185927336925, 8464.78838029048, 8498.660053597849, 8589.0, 8600.311975041981, 8602.0, 8540.975082990019, 8558.657653693845, 8588.184122508383, 8610.827783444327, 8635.006974197984, 8557.358198135134, 8586.527740974245, 8590.87113693295, 8591.44155629882, 8595.44129631572, 8355.206255405727, 8382.580870956428, 8405.579721013924, 8441.409101362908, 8451.45065570736, 37065.444018339746, 37137.257254854754, 37178.25643487142, 37273.440898386274, 37280.25439491195, 3590.622984586607, 3616.4936908832783, 3617.963820361789, 3621.149029977953, 3621.7283703722064, 30814.0, 30827.691723082706, 30903.845480772678, 31004.53493197604, 31138.84430577855, 3082.7533797296387, 3121.1884909923356, 3131.968680313213, 3132.216945763559, 3137.5293705944064, 251027.0, 252580.7370963152, 270299.6485017582, 313167.0, 314214.0, 48262.0, 48655.5134448651, 48727.512724873006, 48770.75614621904, 48783.0, 45355.0, 45733.0, 50746.0, 51244.0, 51280.48719512832, 14262.215578143301, 14274.714505709828, 14564.126152430888, 14581.635459113395, 14595.78106328406, 11439.59961401347, 11546.19176657626, 11557.0, 11640.185187036826, 11653.941730291464, 14065.296735163201, 14094.083884547472, 14111.0, 14116.50592229267, 14236.501722439665, 5110.770015349244, 6262.123302737529, 6267.122602835607, 6268.529860260407, 6273.215848018997, 987.3779518903045, 1009.5911155981839, 1014.3812274512542, 1032.6179313653845, 1052.984205236922, 23127.53744925092, 30406.087817365515, 31314.0, 31673.0, 31753.364932701217, 997.9820583005294, 1028.5782829040206, 1052.153016821466, 1115.5872327238974, 1118.1948996722347, 11629.069674426026, 11700.590479333183, 11767.588134415255, 12209.595896471841, 12574.685632859251, 338027.0, 339645.0, 342307.0, 343026.0, 343027.0, 54689.0, 55071.72464137734, 55158.035776780285, 55207.44792552103, 55386.16920746192, 1448.507507447473, 1454.3237394611344, 1500.317355603198, 1512.4933147395525, 1521.1405555860904, 22363.776362236822, 22456.66315005309, 23431.765682342957, 23875.607578613093, 23934.80325983729, 30222.54666179965, 38480.03799905025, 38508.65219717297, 38513.80743096267, 38562.80718596389, 294690.0, 298685.0, 299538.004619951, 299948.0, 300645.0, 35338.82330588433, 35561.64438355583, 35588.0, 35600.821995889855, 35635.93092207238, 43437.782811087, 43635.34546981861, 43765.0, 45465.318020230385, 45503.54496454993, 27622.861885690443, 29557.26106847346, 29958.70041299559, 30415.391692166475, 31197.532037019915, 23573.882130589238, 29225.97709080172, 29356.412871742017, 30155.547666784576, 31178.0, 37613.623863761015, 37638.0, 37688.24623507569, 37697.2460550793, 37793.677221298225, 27932.44135117327, 29088.0, 29732.0, 31557.89547365831, 31637.89267375723, 24068.0, 24102.0, 24717.0, 24786.876065619555, 24997.250082497554], "value": 17701.804665649066, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.55464555919, 397.8607487379418, 425.7126439653233, 439.20942303853064, 453.3879262994955, 22265.775382353964, 23092.88453557734, 23142.652860207112, 23479.647805282933, 24694.0, 25339.986400543956, 25776.742232577668, 26003.0, 26019.219423417282, 26031.0, 7087.503874728758, 7206.315400037014, 7258.927410725904, 7267.709291628328, 7275.527090739111, 6609.636469994149, 6986.196587392438, 7042.6478676066245, 7073.504854660176, 7143.428525717956, 7016.754413595525, 7017.43860491162, 7018.122734658168, 7027.332403421692, 7028.402585780214, 5109.41241757199, 6917.8239699251, 6999.0, 7018.719251229944, 7027.89458158128, 6800.591964482147, 6813.318668133196, 6843.520953533254, 6852.383285504328, 6880.65596720167, 30094.398112037856, 30940.0, 31480.685193148005, 31490.527642085388, 31635.683643163735, 2291.4958709083976, 2346.7066616672914, 2359.044586942291, 2361.5749165150264, 2362.0, 20045.1981920723, 20399.0, 20434.795652043438, 20445.693314600292, 20455.89772051145, 2152.042341158185, 2156.353094071788, 2270.2167752125492, 2285.6914316567218, 2342.8125749939963, 158299.0, 164876.0, 177620.11189944096, 214219.0, 219109.0, 19547.383734009254, 20504.794952050586, 23915.80420978944, 24356.903939322772, 26358.945642174098, 43002.56997430048, 43519.56480435219, 43524.56475435205, 43532.0, 43626.34560481595, 10965.177611679155, 11122.0, 11219.1585631078, 11261.324320540794, 11322.94338528302, 7732.884006739903, 7901.0, 8349.580571302893, 8403.663853445916, 8815.911840881638, 10726.463676816127, 11334.603288885015, 11985.460654270579, 12010.159288849696, 12269.325187114617, 4454.37638730578, 4758.524147585283, 4843.491433399513, 4859.2954021666665, 4878.512148785093, 776.4254451705697, 963.5086106085946, 983.7983213441228, 1007.345225603349, 1016.2479764973866, 20801.0, 20993.0, 21015.658020666884, 21234.681479777817, 22234.666480002812, 890.4790697441939, 936.601944173737, 966.777641142527, 984.3552473130111, 1019.0498506822854, 10563.419011954416, 11021.559137634566, 11156.107511399068, 11295.0, 11311.264767790068, 520665.0, 522585.3870730698, 532216.0, 532325.0, 532657.0, 102253.0, 148493.0, 148686.0, 149309.0, 149484.0, 969.7914948286152, 1124.1905827803969, 1169.5204965963915, 1193.3496244546743, 1203.5667159822458, 22257.99839007216, 23020.539589207787, 24333.0, 24588.6311705321, 24885.875570622033, 65154.348456514825, 66756.33243667502, 67025.32974670191, 67130.0, 68825.65587172032, 76831.0, 86442.13557864341, 96202.0, 96285.0, 96756.0, 16306.67386652284, 16678.082705451317, 16987.490375288762, 17208.31166753316, 17236.310547578458, 17700.291988320838, 18187.636247275244, 18571.978541179826, 18591.53521161982, 18674.906625466785, 14696.77954830657, 15140.621484462536, 15284.77072843886, 15351.0, 15404.46084387041, 11265.380404077527, 13325.66685832862, 13470.461181552628, 13472.259025753285, 13832.37754301079, 13529.18824870511, 15664.0, 15781.842181578037, 15806.261311255523, 15871.127088010271, 13654.0, 16519.256633451285, 16852.662946741242, 16903.239354228845, 16940.49178524597, 14959.0, 15143.924280378527, 15152.545423637308, 15177.772333415229, 15218.0], "value": 12817.730643182316, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.323831607564, 1321.8083377910202, 1443.1052747296678, 1453.3895763779212, 1454.9490767823124, 11792.174547781648, 12008.63974080779, 12391.69020774481, 12426.813597796017, 12428.502859885604, 10814.405207713573, 11302.773944521125, 12139.271643701384, 12233.327167005811, 12269.079819013587, 2407.350015495815, 2427.3446169534213, 2435.1233555919853, 2438.061346381647, 2445.6820613320333, 2140.1332460353506, 2230.185982116524, 2511.1211076123395, 2542.9745702543014, 2616.8953241870304, 2065.1429656692453, 2373.801230378665, 2400.747921468238, 2411.071737381112, 2420.878956052199, 2466.5683505386487, 2475.405902583385, 2498.9875050624723, 2499.2252401755513, 2499.6750422445148, 2504.4865802510626, 2513.962290565643, 2516.0, 2521.407469244726, 2524.9747502525106, 11891.762164756656, 12065.517379304818, 12174.878251217464, 12193.39033048353, 12212.450439730233, 6589.308122647101, 6732.394084532367, 6771.93228067718, 6804.489663275274, 6819.24988251292, 4063.780865740266, 4261.552536983603, 4346.717463364871, 4359.716618419792, 4365.476142862845, 1240.7828629989806, 1244.8319476870597, 1245.5266998540587, 1274.3819247664876, 1275.3177050278057, 16939.0, 17036.403725869535, 17066.890652107573, 17073.060981646006, 17121.143942802933, 5532.5020748132865, 5636.478150899235, 5734.16854556087, 5742.741576628978, 5881.852953676193, 12403.25580465175, 12622.053345998904, 12636.55772047974, 12668.873311266769, 12675.556355527513, 6508.9023664645065, 6632.701528431232, 6634.933650663528, 6648.900266496006, 6652.467802575782, 6150.815475535741, 6163.137160797492, 6169.105479705418, 6181.289151747516, 6247.812565623039, 3682.8895133146048, 4352.608265256142, 4472.0, 4537.727736335831, 4560.338750881104, 1164.0105909976453, 1193.6359410379916, 1244.6203907808024, 1285.3894400160036, 1288.0, 679.2494293805274, 818.4966245758934, 818.705266104202, 842.700841201377, 853.8676505141713, 6852.794416167523, 7035.613041282779, 7481.476296659291, 7567.356774674105, 7607.9239207608325, 918.6509126531876, 962.1100482054212, 971.7862070344513, 981.6858605246396, 985.4776968206862, 4436.489803672554, 4637.60580350674, 4716.575508204278, 4814.9518504815205, 4905.190643543847, 15125.848741512664, 15288.0, 15476.148811815458, 15557.29992150356, 15645.295961681752, 15061.924690376478, 15103.942724009214, 15337.386504539916, 15460.458883939009, 15609.453669121527, 8418.242358187792, 8599.398042137116, 8619.138086191457, 8621.69824056155, 8632.136786321318, 5157.381114266312, 6875.3812156906115, 7281.963590182015, 7458.47790654649, 7499.287567681062, 10420.426876521864, 11213.71965700864, 11393.94908916403, 14066.929665351608, 14074.648133796327, 14274.714505710035, 14418.990670652953, 14422.567322980327, 14441.277936103363, 14513.709725805636, 8808.20726134651, 8988.0, 8997.145271199226, 9050.683226087056, 9068.546572671208, 10778.29941053829, 10790.406527641055, 10848.945755271174, 10909.0, 11037.613683521038, 8752.256058235122, 9126.99603043678, 9311.44131352121, 9312.34813563044, 9420.905790942003, 9128.406653567497, 9145.508467419395, 9171.220446262143, 9240.214581760625, 9353.064693530592, 8308.667653294042, 8476.745697629081, 8486.957565212135, 8497.957510212656, 8511.659533618586, 6088.6042407243385, 6094.933386657229, 6207.317195108444, 6492.837679057872, 6551.541392102507, 6253.0, 6261.56169068179, 6298.874022519615, 6610.504212184202, 6746.9325306746305], "value": 5470.636882975971, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.68344173685443, 375.08292225508626, 389.55006966953164, 392.21361170852435, 407.1246819338423, 4806.572680796193, 5080.923786143211, 6133.478654314383, 6456.6771661416915, 6524.9673751631235, 5236.528712415882, 5396.460353964601, 6243.906341404882, 6261.841559311531, 6287.874242515147, 3992.7005474589378, 4635.721856688592, 4643.907121857561, 4659.184642687533, 4666.953330466686, 4172.061286210609, 4343.847965321214, 4664.6239359389065, 4668.929966050512, 4690.718556886581, 4617.330487079388, 4618.907621847561, 4621.464579596612, 4625.468071171807, 4628.282616194479, 4002.7598344099447, 4603.171429142753, 4634.142683603544, 4648.674592778507, 4660.603848672867, 3964.048628329209, 3972.423998520227, 3976.880693579197, 3994.720369574131, 4006.1787333596544, 5063.493650634944, 6109.136713302441, 6285.9057114143325, 6305.117283580303, 6311.431971122575, 5519.724013799294, 5531.917021244684, 5541.168824676291, 5548.500634942875, 5554.528050066736, 6809.659517024129, 6829.93170068298, 6836.726530938757, 6853.485988550879, 6878.69045892931, 3700.0749812546865, 4298.753361525154, 4553.317002449659, 4576.633869290449, 4576.748278844662, 6642.93357066428, 6805.591664500146, 7045.6181033103885, 7166.856662866766, 7286.489945703803, 3875.748076375027, 4389.758563278986, 4542.409486766765, 4599.264117741145, 4603.976980115078, 4633.212353899845, 4666.110225358735, 4696.459907110726, 4696.906061878812, 4703.294505824154, 4875.219964805614, 5423.620346575702, 5443.2107344434835, 5494.752736126884, 5637.041702910515, 6313.715882785285, 6364.581459045533, 6753.8649227015185, 6799.796006119825, 6890.138732658418, 1757.9208935597928, 1759.3314540474537, 1772.459399883048, 1776.0853160622032, 1790.0, 977.4233202410612, 1101.2160300313383, 1198.8801119887942, 1241.069198101424, 1250.8624051354343, 5364.275822763877, 6358.904616430757, 6371.23545174582, 6427.807165785034, 6454.32229615893, 5823.941760582425, 5945.910811337833, 6949.061876646689, 7030.964845175844, 7060.6116663584, 5315.787368505294, 6370.108184854124, 6376.617402955838, 6379.776707815204, 6391.872162556816, 2990.596269503633, 3232.272738633789, 3387.8983630491125, 3695.5195824543175, 3708.3881159608363, 6008.699565021731, 6211.378862113753, 7250.3112204340505, 7253.927460725431, 7287.198408175096, 6044.607100538451, 6293.0, 6703.497237707192, 7217.639118044077, 7277.272272772786, 6219.720112594944, 7073.115860517435, 7249.918784516665, 7264.34620884123, 7273.036280204421, 5921.7631294747725, 6521.380468855451, 7003.3346832050875, 7039.366457018893, 7111.324424179695, 5912.793052243151, 6111.54163437735, 6962.547434416747, 6979.511434199558, 6993.685284162327, 6044.304904935812, 6913.41235994926, 7170.462215333767, 7256.818579535554, 7260.709571617076, 3673.7979411132646, 3897.941530877096, 4615.723056616614, 4624.953750462587, 4625.768711564341, 5325.600579956441, 5979.910301345396, 7061.611611361424, 7068.621618784287, 7105.360517553445, 5969.462748352669, 6100.054491553817, 6823.249442561215, 6845.315468453115, 6847.794566163023, 6833.453323734188, 6844.5208835381045, 6845.897311540431, 6849.760258390932, 6850.760223392157, 5749.770009199585, 5774.740136693775, 5981.671008094597, 6331.113644089924, 6717.865642687021, 5253.448387919213, 5723.340231332867, 6377.617342959437, 6848.383645471931, 6872.759453419107, 6641.269460359454, 6647.235567909753, 6659.967705005732, 6709.396154346132, 6739.865202695821], "value": 4880.6976689496005, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.8520666280075, 824.2787560884225, 829.6639860856352, 859.5959898847543, 864.5374724522381, 14785.038972466782, 15390.999585026966, 16629.41797037104, 16691.248893799777, 17086.2311195996, 16267.51197464078, 17237.82762172378, 18109.0, 18170.273189072454, 18278.543036424064, 909.5452273863068, 915.0894859614692, 931.4644079654192, 933.4352716606458, 935.1910597333326, 995.2485873165786, 1002.4536627538009, 1004.2267454060369, 1005.9949700251489, 1006.5973610555764, 915.8809354783868, 1032.1484775060567, 1032.3908893752684, 1035.896410358962, 1038.174651152332, 1028.9948550257275, 1038.7455073507, 1041.037040737315, 1041.3647674918332, 1041.9114375278111, 769.1462476650913, 769.7883082152397, 770.9190534993801, 771.1286246541372, 771.1941021632408, 17057.0, 17104.144792760435, 17275.222614982355, 17439.825601743945, 17444.476665699924, 17431.65136697259, 17464.47606571805, 17600.119994000248, 17649.470515884543, 18037.7294340585, 14850.777238341434, 15058.849411505855, 15153.0, 15226.238688065552, 15284.006539575003, 3282.261491164493, 3344.2141096842224, 3570.9107272318142, 3577.6601222884046, 3639.909002274938, 145325.0, 148166.0, 149058.0, 149118.0, 151734.0, 10610.94694526538, 13626.863731362757, 13641.658958526115, 13663.453461861613, 13951.023428359917, 26578.468430631277, 26973.0, 27226.047088351814, 27238.591421128698, 27278.181654550393, 6259.561830671901, 6476.4495017924, 7305.4155667546465, 7531.284527969834, 7626.54240745557, 3734.7012239020814, 3792.355299599075, 3814.7138964577766, 3822.560405553397, 3822.7897465639658, 3728.925421491609, 4347.065380943112, 4440.222960981815, 4456.977715111469, 4688.695234809727, 956.1251454918811, 960.4429430930184, 979.9363041402286, 980.2354163752142, 982.921366290695, 634.6921742954663, 639.558704493907, 646.3988490703691, 655.9212894452601, 682.2563405887606, 11068.612598559012, 11447.864321607987, 11871.70320741971, 11943.283402995674, 12814.679633009064, 614.5943677173044, 652.0610321137547, 665.04565947864, 673.474689742002, 677.7085853083123, 5302.681839089667, 5476.91784623231, 5879.764809407576, 5908.729623131047, 6594.109795177686, 128335.46881777389, 132902.0, 134623.3268833669, 134861.0, 138287.30856345655, 100370.49814751025, 110706.44646776715, 112926.0, 113165.0, 114604.0, 62807.0, 99508.50245748725, 101416.08678567692, 101636.49181754045, 102888.97111028944, 10201.591936322464, 12316.81524777147, 12647.304398258184, 12783.04127190483, 12817.428028014901, 42187.7890610545, 44683.77658111689, 44867.10265794731, 45043.873903151376, 45458.31812522749, 56394.7180264096, 57185.71407142938, 57489.137662934256, 57862.132068018174, 57931.71034144802, 16471.25879335457, 16863.409780657617, 16915.407960721317, 17062.914685426495, 17186.65626687484, 18603.534911627317, 18693.626127477102, 18729.90635046816, 18732.6253474927, 18870.962097084244, 16659.416920407726, 16884.409045683344, 17238.05190714523, 17369.305227790748, 17387.56531086733, 16852.1573921301, 17250.913745431193, 17477.126143693018, 17485.65028699444, 17492.650146996733, 16766.497005089866, 16981.915090424467, 17157.485275441755, 17353.21910514055, 17362.826371736122, 11307.43462826839, 11796.174267801172, 11861.644150675493, 11879.82180267314, 12014.159008869296, 12348.506059757508, 13284.202947823162, 13300.667483312995, 13383.330833458484, 13548.187108773505], "value": 8650.030725849216, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1492.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 118.0, 115.0, 113.0, 115.0, 114.0, 116.0, 115.0, 116.0, 111.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 111.0, 112.0, 154.0, 115.0, 110.0, 128.0, 90.0, 134.0, 113.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 197.0, 207.0, 198.0, 208.0, 208.0, 207.0, 237.0, 217.0, 193.0], "value": 207.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 78.0, 86.0, 111.0, 84.0, 72.0, 58.0, 58.0, 84.0, 84.0], "value": 78.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 70.0, 84.0, 69.0, 67.0, 67.0, 87.0, 78.0, 99.0, 95.0], "value": 79.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 90.0, 90.0, 92.0, 89.0, 90.0, 95.0, 90.0, 88.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 97.0, 97.0, 98.0, 97.0, 97.0, 97.0, 98.0, 97.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 103.0, 142.0, 84.0, 143.0, 145.0, 89.0, 90.0, 94.0, 89.0], "value": 107.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 51.0, 51.0, 52.0, 51.0, 50.0, 53.0, 51.0, 54.0, 52.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 56.0, 74.0, 62.0, 63.0, 58.0, 57.0, 59.0, 58.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 86.0, 87.0, 89.0, 85.0, 91.0, 90.0, 110.0, 91.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 133.0, 137.0, 133.0, 137.0, 139.0, 137.0, 152.0, 147.0], "value": 139.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 182.0, 184.0, 187.0, 180.0, 242.0, 184.0, 185.0, 183.0], "value": 189.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 68.0, 68.0, 68.0, 68.0, 69.0, 63.0, 65.0, 69.0], "value": 66.7, "unit": "score"}]}]} 18:34:43 INFO - Return code: 0 18:34:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:34:43 INFO - # TBPL SUCCESS # 18:34:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:34:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:34:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:34:43 INFO - 2017-06-05 18:34:43.562 screenresolution[1687:12075] starting screenresolution argv=screenresolution get 18:34:43 INFO - 2017-06-05 18:34:43.564 screenresolution[1687:12075] Display 0: 1600x1200x32@60 18:34:43 INFO - 2017-06-05 18:34:43.570 screenresolution[1688:12078] starting screenresolution argv=screenresolution list 18:34:43 INFO - Available Modes on Display 0 18:34:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:34:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:34:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:34:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:34:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:34:43 INFO - Intel Iris: 18:34:43 INFO - Chipset Model: Intel Iris 18:34:43 INFO - Type: GPU 18:34:43 INFO - Bus: Built-In 18:34:43 INFO - VRAM (Dynamic, Max): 1536 MB 18:34:43 INFO - Vendor: Intel (0x8086) 18:34:43 INFO - Device ID: 0x0a2e 18:34:43 INFO - Revision ID: 0x0009 18:34:43 INFO - Displays: 18:34:43 INFO - Display: 18:34:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:34:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:34:43 INFO - Display Serial Number: 1600x1200 60 18:34:43 INFO - Main Display: Yes 18:34:43 INFO - Mirror: Off 18:34:43 INFO - Online: Yes 18:34:43 INFO - Rotation: Supported 18:34:43 INFO - Return code: 0 18:34:43 INFO - Running post-action listener: _resource_record_post_action 18:34:43 INFO - [mozharness: 2017-06-06 01:34:43.806591Z] Finished run-tests step (success) 18:34:43 INFO - Running post-run listener: _resource_record_post_run 18:34:43 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 32682496; Write bytes: 570890240; Read time: 214; Write time: 1774 18:34:43 INFO - TinderboxPrint: CPU usage
53.2% 18:34:43 INFO - TinderboxPrint: I/O read bytes / time
32,682,496 / 214 18:34:43 INFO - TinderboxPrint: I/O write bytes / time
570,890,240 / 1,774 18:34:43 INFO - TinderboxPrint: CPU idle
2,353.8 (46.8%) 18:34:43 INFO - TinderboxPrint: CPU system
1,016.2 (20.2%) 18:34:43 INFO - TinderboxPrint: CPU user
1,655.6 (32.9%) 18:34:43 INFO - TinderboxPrint: Swap in / out
274,890,752 / 0 18:34:43 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 227095040; Write bytes: 219414528; Read time: 16818; Write time: 448 18:34:43 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:34:43 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 31703552; Write bytes: 323172352; Read time: 201; Write time: 1252 18:34:44 INFO - Running post-run listener: _upload_blobber_files 18:34:44 INFO - Blob upload gear active. 18:34:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:34:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:34:44 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'] 18:34:44 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 18:34:44 INFO - (blobuploader) - INFO - Open directory for files ... 18:34:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:34:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:34:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:45 INFO - (blobuploader) - INFO - Done attempting. 18:34:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:34:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:34:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:46 INFO - (blobuploader) - INFO - Done attempting. 18:34:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:34:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:34:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:47 INFO - (blobuploader) - INFO - Done attempting. 18:34:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:34:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:34:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:49 INFO - (blobuploader) - INFO - Done attempting. 18:34:49 INFO - (blobuploader) - INFO - Iteration through files over. 18:34:49 INFO - Return code: 0 18:34:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:34:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:34:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91185676259a61e68416c68fee8ad934217bb8edf5afb1e6e2a2fd5fcbfc910b396729fabcae43b9de3496fc77c35138330bf0cbdd1966e9a25f0b34d2dcc372", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/083948b748ab46c96ad82259eeec2c22f9f48c9f0155a3a445d7317431d5fd64eac8f11e67686496096565290a13f213af77ec9b7eda52b009fea2183c03eb92", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec0a89fec6ef5637ee35d24cc628e36f2636f94726f1acdd5aca63e79ba96125972bbb05dd0ccddc07a2ccef8046eb1c0ce4d761ad50e3d521499bb1aef2dc3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4bdbff1c3ab2cf704a00efd8f39dbd2484c0405237466115be1f03b170445641c46faace9d796aa7de8078585a885a21baaadb69ae3f2d98afd0a279c517f4a"} 18:34:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:34:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:34:49 INFO - Contents: 18:34:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91185676259a61e68416c68fee8ad934217bb8edf5afb1e6e2a2fd5fcbfc910b396729fabcae43b9de3496fc77c35138330bf0cbdd1966e9a25f0b34d2dcc372", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/083948b748ab46c96ad82259eeec2c22f9f48c9f0155a3a445d7317431d5fd64eac8f11e67686496096565290a13f213af77ec9b7eda52b009fea2183c03eb92", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec0a89fec6ef5637ee35d24cc628e36f2636f94726f1acdd5aca63e79ba96125972bbb05dd0ccddc07a2ccef8046eb1c0ce4d761ad50e3d521499bb1aef2dc3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4bdbff1c3ab2cf704a00efd8f39dbd2484c0405237466115be1f03b170445641c46faace9d796aa7de8078585a885a21baaadb69ae3f2d98afd0a279c517f4a"} 18:34:49 INFO - Running post-run listener: copy_logs_to_upload_dir 18:34:49 INFO - Copying logs to upload dir... 18:34:49 INFO - mkdir: /builds/slave/test/build/upload/logs 18:34:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.380014 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-05 18:34:49.839512) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 18:34:49.842820) ========= 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.aqKYPhpqd3/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.63dzUnKxli/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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/91185676259a61e68416c68fee8ad934217bb8edf5afb1e6e2a2fd5fcbfc910b396729fabcae43b9de3496fc77c35138330bf0cbdd1966e9a25f0b34d2dcc372", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/083948b748ab46c96ad82259eeec2c22f9f48c9f0155a3a445d7317431d5fd64eac8f11e67686496096565290a13f213af77ec9b7eda52b009fea2183c03eb92", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec0a89fec6ef5637ee35d24cc628e36f2636f94726f1acdd5aca63e79ba96125972bbb05dd0ccddc07a2ccef8046eb1c0ce4d761ad50e3d521499bb1aef2dc3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4bdbff1c3ab2cf704a00efd8f39dbd2484c0405237466115be1f03b170445641c46faace9d796aa7de8078585a885a21baaadb69ae3f2d98afd0a279c517f4a"} build_url:https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011315 build_url: 'https://queue.taskcluster.net/v1/task/Y6X1dh45QWiFykMT4crFPw/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/91185676259a61e68416c68fee8ad934217bb8edf5afb1e6e2a2fd5fcbfc910b396729fabcae43b9de3496fc77c35138330bf0cbdd1966e9a25f0b34d2dcc372", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/083948b748ab46c96ad82259eeec2c22f9f48c9f0155a3a445d7317431d5fd64eac8f11e67686496096565290a13f213af77ec9b7eda52b009fea2183c03eb92", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec0a89fec6ef5637ee35d24cc628e36f2636f94726f1acdd5aca63e79ba96125972bbb05dd0ccddc07a2ccef8046eb1c0ce4d761ad50e3d521499bb1aef2dc3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4bdbff1c3ab2cf704a00efd8f39dbd2484c0405237466115be1f03b170445641c46faace9d796aa7de8078585a885a21baaadb69ae3f2d98afd0a279c517f4a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 18:34:49.876609) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:34:49.876999) ========= 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.aqKYPhpqd3/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.63dzUnKxli/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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.005082 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:34:49.926571) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 18:34:49.926884) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 18:34:49.927240) ========= ========= Total master_lag: 0.23 =========