builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0239 starttime: 1497321212.36 results: success (0) revision: 496fd4521a947593a9e46daca2bc42bd73ea49af ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 19:33:32.364867) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 19:33:32.365312) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 19:33:32.365588) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 19:33:32.405210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 19:33:32.405582) ========= 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.ffPYaiHkrD/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.LQyfUThZNo/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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-12 19:33:32-- 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% 28.6M=0s 2017-06-12 19:33:32 (28.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.236989 ========= master_lag: 0.41 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 19:33:33.052330) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 19:33:33.052652) ========= 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.ffPYaiHkrD/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.LQyfUThZNo/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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.039667 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 19:33:33.123948) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 19:33:33.124300) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 496fd4521a947593a9e46daca2bc42bd73ea49af --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 496fd4521a947593a9e46daca2bc42bd73ea49af --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ffPYaiHkrD/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.LQyfUThZNo/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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-12 19:33:33,261 truncating revision to first 12 chars 2017-06-12 19:33:33,261 Setting DEBUG logging. 2017-06-12 19:33:33,261 attempt 1/10 2017-06-12 19:33:33,261 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/496fd4521a94?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-12 19:33:34,676 unpacking tar archive at: mozilla-beta-496fd4521a94/testing/mozharness/ program finished with exit code 0 elapsedTime=1.829136 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 19:33:34.988302) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 19:33:34.988632) ========= script_repo_revision: 496fd4521a947593a9e46daca2bc42bd73ea49af ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 19:33:34.989026) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 19:33:34.989307) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 19:33:35.019417) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 23 mins, 29 secs) (at 2017-06-12 19:33:35.019794) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ffPYaiHkrD/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.LQyfUThZNo/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 19:33:35 INFO - MultiFileLogger online at 20170612 19:33:35 in /builds/slave/test 19:33:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 19:33:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:33:35 INFO - {'append_to_log': False, 19:33:35 INFO - 'base_work_dir': '/builds/slave/test', 19:33:35 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:33:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:33:35 INFO - 'branch': 'Mozilla-Beta', 19:33:35 INFO - 'buildbot_json_path': 'buildprops.json', 19:33:35 INFO - 'config_files': ('talos/mac_config.py',), 19:33:35 INFO - 'default_actions': ('clobber', 19:33:35 INFO - 'read-buildbot-config', 19:33:35 INFO - 'download-and-extract', 19:33:35 INFO - 'populate-webroot', 19:33:35 INFO - 'create-virtualenv', 19:33:35 INFO - 'install', 19:33:35 INFO - 'setup-mitmproxy', 19:33:35 INFO - 'run-tests'), 19:33:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:33:35 INFO - 'download_minidump_stackwalk': True, 19:33:35 INFO - 'download_symbols': 'ondemand', 19:33:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:33:35 INFO - 'tooltool.py': '/tools/tooltool.py', 19:33:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:33:35 INFO - '/tools/misc-python/virtualenv.py')}, 19:33:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:33:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:33:35 INFO - 'gecko_profile': False, 19:33:35 INFO - 'gecko_profile_interval': 0, 19:33:35 INFO - 'installer_path': 'installer.exe', 19:33:35 INFO - 'log_level': 'info', 19:33:35 INFO - 'log_name': 'talos', 19:33:35 INFO - 'log_to_console': True, 19:33:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:33:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:33:35 INFO - 'opt_config_files': (), 19:33:35 INFO - 'pip_index': False, 19:33:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:33:35 INFO - 'cmd': ('bash', 19:33:35 INFO - '-c', 19:33:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:33:35 INFO - 'enabled': True, 19:33:35 INFO - 'halt_on_failure': False, 19:33:35 INFO - 'name': 'check_screen_resolution'},), 19:33:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:33:35 INFO - 'cmd': ('bash', 19:33:35 INFO - '-c', 19:33:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:33:35 INFO - 'enabled': True, 19:33:35 INFO - 'halt_on_failure': False, 19:33:35 INFO - 'name': 'check_screen_resolution'},), 19:33:35 INFO - 'run_cmd_checks_enabled': True, 19:33:35 INFO - 'suite': 'dromaeojs-e10s', 19:33:35 INFO - 'system_bits': '32', 19:33:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:33:35 INFO - 'title': 't-yosemite-r7-0239', 19:33:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:33:35 INFO - 'use_talos_json': True, 19:33:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:33:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:33:35 INFO - 'work_dir': 'build'} 19:33:35 INFO - [mozharness: 2017-06-13 02:33:35.362642Z] Running clobber step. 19:33:35 INFO - Running pre-action listener: _resource_record_pre_action 19:33:35 INFO - Running main action method: clobber 19:33:35 INFO - rmtree: /builds/slave/test/build 19:33:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:33:42 INFO - Running post-action listener: _resource_record_post_action 19:33:42 INFO - [mozharness: 2017-06-13 02:33:42.891618Z] Finished clobber step (success) 19:33:42 INFO - [mozharness: 2017-06-13 02:33:42.891835Z] Running read-buildbot-config step. 19:33:42 INFO - Running pre-action listener: _resource_record_pre_action 19:33:42 INFO - Running main action method: read_buildbot_config 19:33:42 INFO - Using buildbot properties: 19:33:42 INFO - { 19:33:42 INFO - "project": "", 19:33:42 INFO - "product": "firefox", 19:33:42 INFO - "who": "asasaki@mozilla.com", 19:33:42 INFO - "installer_path": "public/build/firefox-55.0.en-US.mac.dmg", 19:33:42 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 19:33:42 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 19:33:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 19:33:42 INFO - "stage_platform": "macosx64", 19:33:42 INFO - "basedir": "/builds/slave/test", 19:33:42 INFO - "buildnumber": 0, 19:33:42 INFO - "platform": "yosemite_r7", 19:33:42 INFO - "parent_task_id": "dn1W25meT52Sg1Mlb0NHcA", 19:33:42 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 19:33:42 INFO - "slavebuilddir": "test", 19:33:42 INFO - "taskId": "Z1aBvRxqT2OCgA03-jGT0w", 19:33:42 INFO - "branch": "mozilla-beta", 19:33:42 INFO - "script_repo_revision": "production", 19:33:42 INFO - "revision": "496fd4521a947593a9e46daca2bc42bd73ea49af", 19:33:42 INFO - "slavename": "t-yosemite-r7-0239", 19:33:42 INFO - "repo_path": "releases/mozilla-beta" 19:33:42 INFO - } 19:33:42 INFO - Finding installer, test and symbols from parent task. 19:33:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z1aBvRxqT2OCgA03-jGT0w'}, attempt #1 19:33:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z1aBvRxqT2OCgA03-jGT0w'}, attempt #1 19:33:44 INFO - Task dependencies: b6BOtJ3kRV27R9dHya2vxg dn1W25meT52Sg1Mlb0NHcA 19:33:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA'}, attempt #1 19:33:44 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 19:33:44 INFO - Set test_url: https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/test_packages.json 19:33:44 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/target.crashreporter-symbols.zip 19:33:44 INFO - Running post-action listener: _resource_record_post_action 19:33:44 INFO - [mozharness: 2017-06-13 02:33:44.670066Z] Finished read-buildbot-config step (success) 19:33:44 INFO - [mozharness: 2017-06-13 02:33:44.670237Z] Running download-and-extract step. 19:33:44 INFO - Running pre-action listener: _resource_record_pre_action 19:33:44 INFO - Running main action method: download_and_extract 19:33:44 INFO - mkdir: /builds/slave/test/build/tests 19:33: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')]} 19:33:44 INFO - trying https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 19:33:44 INFO - Downloading https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 19:33:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 19:33:45 INFO - Downloaded 1290 bytes. 19:33:45 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 19:33:45 INFO - Using the following test package requirements: 19:33:45 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:33:45 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 19:33:45 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 19:33:45 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:33:45 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 19:33:45 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:33:45 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 19:33:45 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 19:33:45 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:33:45 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 19:33:45 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 19:33:45 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:33:45 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 19:33:45 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:33:45 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 19:33:45 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:33:45 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 19:33:45 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 19:33:45 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 19:33:45 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 19:33:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 19:33:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 19:33:45 INFO - Fetch https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 19:33:48 INFO - Content-Length response header: 58981227 19:33:48 INFO - Bytes received: 58981227 19:34:19 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 19:34:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 19:34:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 19:34:19 INFO - Fetch https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 19:34:22 INFO - Content-Length response header: 58981227 19:34:22 INFO - Bytes received: 58981227 19:34:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 19:34:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 19:34:48 INFO - Fetch https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 19:34:50 INFO - Content-Length response header: 14450018 19:34:50 INFO - Bytes received: 14450018 19:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:34:50 INFO - trying https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 19:34:50 INFO - Downloading https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 19:34:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 19:34:52 INFO - Downloaded 51079685 bytes. 19:34:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 19:34:52 INFO - mkdir: /builds/slave/test/properties 19:34:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:34:52 INFO - Writing to file /builds/slave/test/properties/build_url 19:34:52 INFO - Contents: 19:34:52 INFO - build_url:https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 19:34:52 INFO - Running post-action listener: _resource_record_post_action 19:34:52 INFO - Running post-action listener: set_extra_try_arguments 19:34:52 INFO - [mozharness: 2017-06-13 02:34:52.377497Z] Finished download-and-extract step (success) 19:34:52 INFO - [mozharness: 2017-06-13 02:34:52.377685Z] Running populate-webroot step. 19:34:52 INFO - Running pre-action listener: _resource_record_pre_action 19:34:52 INFO - Running main action method: populate_webroot 19:34:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:34:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:34:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:34:52 INFO - '--tppagecycles', 19:34:52 INFO - '7'], 19:34:52 INFO - 'tests': ['tsvgm']}}, 19:34:52 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:34:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:34:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:34:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:34:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:34:52 INFO - 'talos_options': ['--disable-e10s'], 19:34:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:34:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:34:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:34:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:34:52 INFO - 'talos_options': ['--disable-e10s'], 19:34:52 INFO - 'tests': ['damp', 'tps']}, 19:34:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:34:52 INFO - 'tests': ['damp', 'tps']}, 19:34:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:34:52 INFO - 'tests': ['dromaeo_dom']}, 19:34:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:34:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:34:52 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 19:34:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:34:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:34:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:34:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:34:52 INFO - 'tests': ['a11yr', 19:34:52 INFO - 'ts_paint', 19:34:52 INFO - 'tpaint', 19:34:52 INFO - 'sessionrestore', 19:34:52 INFO - 'sessionrestore_no_auto_restore', 19:34:52 INFO - 'tabpaint']}, 19:34:52 INFO - 'other-e10s': {'tests': ['a11yr', 19:34:52 INFO - 'ts_paint', 19:34:52 INFO - 'tpaint', 19:34:52 INFO - 'sessionrestore', 19:34:52 INFO - 'sessionrestore_no_auto_restore', 19:34:52 INFO - 'tabpaint']}, 19:34:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:34:52 INFO - 'ts_paint', 19:34:52 INFO - 'tpaint', 19:34:52 INFO - 'sessionrestore', 19:34:52 INFO - 'sessionrestore_no_auto_restore', 19:34:52 INFO - 'tabpaint']}, 19:34:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:34:52 INFO - 'ts_paint', 19:34:52 INFO - 'tpaint', 19:34:52 INFO - 'sessionrestore', 19:34:52 INFO - 'sessionrestore_no_auto_restore', 19:34:52 INFO - 'tabpaint']}, 19:34:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:34:52 INFO - 'tests': ['a11yr', 19:34:52 INFO - 'ts_paint', 19:34:52 INFO - 'tpaint', 19:34:52 INFO - 'sessionrestore', 19:34:52 INFO - 'sessionrestore_no_auto_restore', 19:34:52 INFO - 'tabpaint']}, 19:34:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:34:52 INFO - 'tests': ['a11yr', 19:34:52 INFO - 'ts_paint', 19:34:52 INFO - 'tpaint', 19:34:52 INFO - 'sessionrestore', 19:34:52 INFO - 'sessionrestore_no_auto_restore', 19:34:52 INFO - 'tabpaint']}, 19:34:52 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 19:34:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 19:34:52 INFO - 'bloom_basic_ref']}, 19:34:52 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:34:52 INFO - 'talos_options': ['--mitmproxy', 19:34:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:34:52 INFO - '--firstNonBlankPaint'], 19:34:52 INFO - 'tests': ['quantum_pageload_google', 19:34:52 INFO - 'quantum_pageload_youtube', 19:34:52 INFO - 'quantum_pageload_amazon', 19:34:52 INFO - 'quantum_pageload_facebook']}, 19:34:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:34:52 INFO - 'tests': ['tsvgx', 19:34:52 INFO - 'tsvgr_opacity', 19:34:52 INFO - 'tart', 19:34:52 INFO - 'tscrollx', 19:34:52 INFO - 'cart', 19:34:52 INFO - 'tsvg_static']}, 19:34:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:34:52 INFO - 'tsvgr_opacity', 19:34:52 INFO - 'tart', 19:34:52 INFO - 'tscrollx', 19:34:52 INFO - 'cart', 19:34:52 INFO - 'tsvg_static']}, 19:34:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:34:52 INFO - 'talos_options': ['--disable-e10s'], 19:34:52 INFO - 'tests': ['tp5o']}, 19:34:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:34:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:34:52 INFO - 'talos_options': ['--disable-e10s', 19:34:52 INFO - '--xperf_path', 19:34:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:34:52 INFO - 'tests': ['tp5n']}, 19:34:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:34:52 INFO - 'talos_options': ['--xperf_path', 19:34:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:34:52 INFO - 'tests': ['tp5n']}}, 19:34:52 INFO - 'talos.zip': {'path': '', 19:34:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:34:52 INFO - Running post-action listener: _resource_record_post_action 19:34:52 INFO - [mozharness: 2017-06-13 02:34:52.402641Z] Finished populate-webroot step (success) 19:34:52 INFO - [mozharness: 2017-06-13 02:34:52.402819Z] Running create-virtualenv step. 19:34:52 INFO - Running pre-action listener: _resource_record_pre_action 19:34:52 INFO - Running main action method: create_virtualenv 19:34:52 INFO - Creating virtualenv /builds/slave/test/build/venv 19:34:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:34:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:34:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:34:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:34:52 INFO - Using real prefix '/tools/python27' 19:34:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:34:53 INFO - Installing distribute.............................................................................................................................................................................................done. 19:34:56 INFO - Installing pip.................done. 19:34:56 INFO - Return code: 0 19:34:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:34:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:34:56 INFO - Reading from file tmpfile_stdout 19:34:56 INFO - Output received: 19:34:56 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 19:34:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:34:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b38c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf92482ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488ed30>, '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 0x104858cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10487fbe8>, '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.ffPYaiHkrD/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.LQyfUThZNo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 19:34:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:34:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:34:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffPYaiHkrD/Render', 19:34:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:34:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:34:56 INFO - 'HOME': '/Users/cltbld', 19:34:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:34:56 INFO - 'LOGNAME': 'cltbld', 19:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:56 INFO - 'MOZ_NO_REMOTE': '1', 19:34:56 INFO - 'NO_EM_RESTART': '1', 19:34:56 INFO - 'PAGER': '/bin/cat', 19:34:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:56 INFO - 'PWD': '/builds/slave/test', 19:34:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:34:56 INFO - 'SHELL': '/bin/bash', 19:34:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LQyfUThZNo/Listeners', 19:34:56 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 19:34:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:34:56 INFO - 'USER': 'cltbld', 19:34:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:34:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:56 INFO - 'XPC_FLAGS': '0x0', 19:34:56 INFO - 'XPC_SERVICE_NAME': '0', 19:34:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:34:57 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 19:34:57 INFO - Cleaning up... 19:34:57 INFO - Return code: 0 19:34:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:34:57 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')]} 19:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:34:57 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')]} 19:34:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:34:57 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 0x104b38c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf92482ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488ed30>, '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 0x104858cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10487fbe8>, '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.ffPYaiHkrD/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.LQyfUThZNo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 19:34:57 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 19:34:57 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 19:34:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffPYaiHkrD/Render', 19:34:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:34:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:34:57 INFO - 'HOME': '/Users/cltbld', 19:34:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:34:57 INFO - 'LOGNAME': 'cltbld', 19:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:57 INFO - 'MOZ_NO_REMOTE': '1', 19:34:57 INFO - 'NO_EM_RESTART': '1', 19:34:57 INFO - 'PAGER': '/bin/cat', 19:34:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:57 INFO - 'PWD': '/builds/slave/test', 19:34:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:34:57 INFO - 'SHELL': '/bin/bash', 19:34:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LQyfUThZNo/Listeners', 19:34:57 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 19:34:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:34:57 INFO - 'USER': 'cltbld', 19:34:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:34:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:57 INFO - 'XPC_FLAGS': '0x0', 19:34:57 INFO - 'XPC_SERVICE_NAME': '0', 19:34:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:34:57 INFO - Downloading/unpacking psutil>=3.1.1 19:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:34:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:34:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:34:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:34:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:34:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:34:59 INFO - Installing collected packages: psutil 19:34:59 INFO - Running setup.py install for psutil 19:34:59 INFO - building 'psutil._psutil_osx' extension 19:34:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:35:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:35:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:35:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:35:00 INFO - building 'psutil._psutil_posix' extension 19:35:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 19:35:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:35:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:35:00 INFO - ^ 19:35:00 INFO - 1 warning generated. 19:35:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 19:35:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:35:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:35:00 INFO - Successfully installed psutil 19:35:00 INFO - Cleaning up... 19:35:01 INFO - Return code: 0 19:35:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:35:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b38c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf92482ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488ed30>, '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 0x104858cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10487fbe8>, '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.ffPYaiHkrD/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.LQyfUThZNo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 19:35:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:35:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:35:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffPYaiHkrD/Render', 19:35:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:01 INFO - 'HOME': '/Users/cltbld', 19:35:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:01 INFO - 'LOGNAME': 'cltbld', 19:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:01 INFO - 'MOZ_NO_REMOTE': '1', 19:35:01 INFO - 'NO_EM_RESTART': '1', 19:35:01 INFO - 'PAGER': '/bin/cat', 19:35:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:01 INFO - 'PWD': '/builds/slave/test', 19:35:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:01 INFO - 'SHELL': '/bin/bash', 19:35:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LQyfUThZNo/Listeners', 19:35:01 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 19:35:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:01 INFO - 'USER': 'cltbld', 19:35:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:01 INFO - 'XPC_FLAGS': '0x0', 19:35:01 INFO - 'XPC_SERVICE_NAME': '0', 19:35:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:35:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:35:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:35:02 INFO - Installing collected packages: mozsystemmonitor 19:35:02 INFO - Running setup.py install for mozsystemmonitor 19:35:03 INFO - Successfully installed mozsystemmonitor 19:35:03 INFO - Cleaning up... 19:35:03 INFO - Return code: 0 19:35:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:35:03 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')]} 19:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:03 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')]} 19:35:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:03 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 0x104b38c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf92482ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488ed30>, '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 0x104858cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10487fbe8>, '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.ffPYaiHkrD/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.LQyfUThZNo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 19:35:03 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 19:35:03 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 19:35:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffPYaiHkrD/Render', 19:35:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:03 INFO - 'HOME': '/Users/cltbld', 19:35:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:03 INFO - 'LOGNAME': 'cltbld', 19:35:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:03 INFO - 'MOZ_NO_REMOTE': '1', 19:35:03 INFO - 'NO_EM_RESTART': '1', 19:35:03 INFO - 'PAGER': '/bin/cat', 19:35:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:03 INFO - 'PWD': '/builds/slave/test', 19:35:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:03 INFO - 'SHELL': '/bin/bash', 19:35:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LQyfUThZNo/Listeners', 19:35:03 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 19:35:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:03 INFO - 'USER': 'cltbld', 19:35:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:03 INFO - 'XPC_FLAGS': '0x0', 19:35:03 INFO - 'XPC_SERVICE_NAME': '0', 19:35:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:03 INFO - Downloading/unpacking jsonschema==2.5.1 19:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:35:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:05 INFO - Downloading functools32-3.2.3-2.tar.gz 19:35:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:35:05 INFO - warning: no files found matching '*.txt' 19:35:05 INFO - no previously-included directories found matching 'build' 19:35:05 INFO - no previously-included directories found matching 'dist' 19:35:05 INFO - no previously-included directories found matching '.git*' 19:35:05 INFO - Installing collected packages: jsonschema, functools32 19:35:05 INFO - Running setup.py install for functools32 19:35:05 INFO - warning: no files found matching '*.txt' 19:35:05 INFO - no previously-included directories found matching 'build' 19:35:05 INFO - no previously-included directories found matching 'dist' 19:35:05 INFO - no previously-included directories found matching '.git*' 19:35:05 INFO - Successfully installed jsonschema functools32 19:35:05 INFO - Cleaning up... 19:35:05 INFO - Return code: 0 19:35:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104b38c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf92482ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488ed30>, '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 0x104858cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10487fbe8>, '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.ffPYaiHkrD/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.LQyfUThZNo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 19:35:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:35:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:35:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffPYaiHkrD/Render', 19:35:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:05 INFO - 'HOME': '/Users/cltbld', 19:35:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:05 INFO - 'LOGNAME': 'cltbld', 19:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:05 INFO - 'MOZ_NO_REMOTE': '1', 19:35:05 INFO - 'NO_EM_RESTART': '1', 19:35:05 INFO - 'PAGER': '/bin/cat', 19:35:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:05 INFO - 'PWD': '/builds/slave/test', 19:35:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:05 INFO - 'SHELL': '/bin/bash', 19:35:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LQyfUThZNo/Listeners', 19:35:05 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 19:35:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:05 INFO - 'USER': 'cltbld', 19:35:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:05 INFO - 'XPC_FLAGS': '0x0', 19:35:05 INFO - 'XPC_SERVICE_NAME': '0', 19:35:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:35:05 INFO - Cleaning up... 19:35:05 INFO - Return code: 0 19:35:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b38c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf92482ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488ed30>, '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 0x104858cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10487fbe8>, '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.ffPYaiHkrD/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.LQyfUThZNo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 19:35:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:35:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:35:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffPYaiHkrD/Render', 19:35:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:05 INFO - 'HOME': '/Users/cltbld', 19:35:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:05 INFO - 'LOGNAME': 'cltbld', 19:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:05 INFO - 'MOZ_NO_REMOTE': '1', 19:35:05 INFO - 'NO_EM_RESTART': '1', 19:35:05 INFO - 'PAGER': '/bin/cat', 19:35:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:05 INFO - 'PWD': '/builds/slave/test', 19:35:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:05 INFO - 'SHELL': '/bin/bash', 19:35:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LQyfUThZNo/Listeners', 19:35:05 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 19:35:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:05 INFO - 'USER': 'cltbld', 19:35:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:05 INFO - 'XPC_FLAGS': '0x0', 19:35:05 INFO - 'XPC_SERVICE_NAME': '0', 19:35:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:06 INFO - Downloading/unpacking blobuploader==1.2.4 19:35:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:07 INFO - Downloading blobuploader-1.2.4.tar.gz 19:35:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:35:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:35:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:35:08 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 19:35:08 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 19:35:08 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 19:35:08 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 19:35:08 INFO - Downloading docopt-0.6.1.tar.gz 19:35:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:35:08 INFO - Installing collected packages: blobuploader, requests, docopt 19:35:08 INFO - Running setup.py install for blobuploader 19:35:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:35:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:35:08 INFO - Running setup.py install for requests 19:35:09 INFO - Running setup.py install for docopt 19:35:09 INFO - Successfully installed blobuploader requests docopt 19:35:09 INFO - Cleaning up... 19:35:09 INFO - Return code: 0 19:35:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:35:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:09 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 0x104b38c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf92482ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488ed30>, '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 0x104858cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10487fbe8>, '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.ffPYaiHkrD/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.LQyfUThZNo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 19:35:09 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 19:35:09 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 19:35:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffPYaiHkrD/Render', 19:35:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:09 INFO - 'HOME': '/Users/cltbld', 19:35:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:09 INFO - 'LOGNAME': 'cltbld', 19:35:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:09 INFO - 'MOZ_NO_REMOTE': '1', 19:35:09 INFO - 'NO_EM_RESTART': '1', 19:35:09 INFO - 'PAGER': '/bin/cat', 19:35:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:09 INFO - 'PWD': '/builds/slave/test', 19:35:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:09 INFO - 'SHELL': '/bin/bash', 19:35:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LQyfUThZNo/Listeners', 19:35:09 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 19:35:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:09 INFO - 'USER': 'cltbld', 19:35:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:09 INFO - 'XPC_FLAGS': '0x0', 19:35:09 INFO - 'XPC_SERVICE_NAME': '0', 19:35:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:35:09 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-j4epcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:35:09 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-LcIFbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:35:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-3iiV4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:35:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-v4DrZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:35:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-Qmu5Av-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:35:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-xJCHTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:35:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-sX0F8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:35:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-OwXEbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:35:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-FEcuyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:35:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-l1UXaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:35:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-1zQ1It-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:35:11 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-8CAWDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:35:11 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-jRdw5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:35:11 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-72fX_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:11 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-xRofnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:35:11 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-dxW8so-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:35:11 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-VriLNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:35:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:35:11 INFO - Running setup.py install for manifestparser 19:35:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:35:11 INFO - Running setup.py install for mozcrash 19:35:12 INFO - Running setup.py install for mozdebug 19:35:12 INFO - Running setup.py install for mozdevice 19:35:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:35:12 INFO - Running setup.py install for mozfile 19:35:12 INFO - Running setup.py install for mozhttpd 19:35:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:35:12 INFO - Running setup.py install for mozinfo 19:35:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:35:12 INFO - Running setup.py install for mozInstall 19:35:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:35:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:35:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:35:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:35:12 INFO - Running setup.py install for mozleak 19:35:13 INFO - Running setup.py install for mozlog 19:35:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:35:13 INFO - Running setup.py install for moznetwork 19:35:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:35:13 INFO - Running setup.py install for mozprocess 19:35:13 INFO - Running setup.py install for mozprofile 19:35:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:35:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:35:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:35:13 INFO - Running setup.py install for mozrunner 19:35:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:35:13 INFO - Running setup.py install for mozscreenshot 19:35:14 INFO - Running setup.py install for moztest 19:35:14 INFO - Running setup.py install for mozversion 19:35:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:35:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:35:14 INFO - Cleaning up... 19:35:14 INFO - Return code: 0 19:35:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:35:14 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')]} 19:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:14 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')]} 19:35:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:14 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 0x104b38c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf92482ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488ed30>, '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 0x104858cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10487fbe8>, '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.ffPYaiHkrD/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.LQyfUThZNo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 19:35:14 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 19:35:14 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 19:35:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffPYaiHkrD/Render', 19:35:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:14 INFO - 'HOME': '/Users/cltbld', 19:35:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:14 INFO - 'LOGNAME': 'cltbld', 19:35:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:14 INFO - 'MOZ_NO_REMOTE': '1', 19:35:14 INFO - 'NO_EM_RESTART': '1', 19:35:14 INFO - 'PAGER': '/bin/cat', 19:35:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:14 INFO - 'PWD': '/builds/slave/test', 19:35:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:14 INFO - 'SHELL': '/bin/bash', 19:35:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LQyfUThZNo/Listeners', 19:35:14 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 19:35:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:14 INFO - 'USER': 'cltbld', 19:35:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:14 INFO - 'XPC_FLAGS': '0x0', 19:35:14 INFO - 'XPC_SERVICE_NAME': '0', 19:35:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:35:14 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-7Gj3IT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:35:14 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)) 19:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:35:14 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-Sy21ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:35:14 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)) 19:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:35:14 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-KTyCRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:35:15 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)) 19:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:35:15 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-9pXaIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:35:15 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)) 19:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:35:15 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-GCL1RA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:35:15 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)) 19:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:35:15 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-ywnElL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:35:15 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)) 19:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:35:15 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-27VuOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:35:15 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)) 19:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:35:15 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-afq73a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:35:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:35:15 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-_Ok9Kk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:35:15 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)) 19:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:35:15 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-0BkTIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:35:16 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)) 19:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:35:16 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-p600pX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:35:16 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)) 19:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:35:16 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-olreT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:35:16 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)) 19:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:35:16 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-_GGSa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:35:16 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)) 19:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:35:16 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-t1zo3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:35:16 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)) 19:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:16 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-6u81oS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:16 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)) 19:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:35:16 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-Utl1Xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:35:16 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)) 19:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:35:16 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-5PSLkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:35:16 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)) 19:35:16 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)) 19:35:16 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)) 19:35:16 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)) 19:35:16 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)) 19:35:16 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)) 19:35:16 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:35:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:35:16 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 19:35:16 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 19:35:16 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 19:35:16 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 19:35:18 INFO - Downloading blessings-1.6.tar.gz 19:35:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:35:18 INFO - Installing collected packages: blessings 19:35:18 INFO - Running setup.py install for blessings 19:35:18 INFO - Successfully installed blessings 19:35:18 INFO - Cleaning up... 19:35:18 INFO - Return code: 0 19:35:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:35:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:35:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:35:18 INFO - Reading from file tmpfile_stdout 19:35:18 INFO - Current package versions: 19:35:18 INFO - blessings == 1.6 19:35:18 INFO - blobuploader == 1.2.4 19:35:18 INFO - docopt == 0.6.1 19:35:18 INFO - functools32 == 3.2.3-2 19:35:18 INFO - jsonschema == 2.5.1 19:35:18 INFO - manifestparser == 1.1 19:35:18 INFO - mozInstall == 1.13 19:35:18 INFO - mozcrash == 1.0 19:35:18 INFO - mozdebug == 0.1 19:35:18 INFO - mozdevice == 0.51 19:35:18 INFO - mozfile == 1.2 19:35:18 INFO - mozhttpd == 0.7 19:35:18 INFO - mozinfo == 0.10 19:35:18 INFO - mozleak == 0.1 19:35:18 INFO - mozlog == 3.4 19:35:18 INFO - moznetwork == 0.27 19:35:18 INFO - mozprocess == 0.25 19:35:18 INFO - mozprofile == 0.28 19:35:18 INFO - mozrunner == 6.13 19:35:18 INFO - mozscreenshot == 0.1 19:35:18 INFO - mozsystemmonitor == 0.3 19:35:18 INFO - moztest == 0.8 19:35:18 INFO - mozversion == 1.4 19:35:18 INFO - psutil == 3.1.1 19:35:18 INFO - requests == 1.2.3 19:35:18 INFO - wsgiref == 0.1.2 19:35:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:35:18 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')]} 19:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:18 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')]} 19:35:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:18 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 0x104b38c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf92482ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488ed30>, '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 0x104858cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10487fbe8>, '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.ffPYaiHkrD/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.LQyfUThZNo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 19:35:18 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 19:35:18 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 19:35:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffPYaiHkrD/Render', 19:35:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:18 INFO - 'HOME': '/Users/cltbld', 19:35:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:18 INFO - 'LOGNAME': 'cltbld', 19:35:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:18 INFO - 'MOZ_NO_REMOTE': '1', 19:35:18 INFO - 'NO_EM_RESTART': '1', 19:35:18 INFO - 'PAGER': '/bin/cat', 19:35:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:18 INFO - 'PWD': '/builds/slave/test', 19:35:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:19 INFO - 'SHELL': '/bin/bash', 19:35:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LQyfUThZNo/Listeners', 19:35:19 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 19:35:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:19 INFO - 'USER': 'cltbld', 19:35:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:19 INFO - 'XPC_FLAGS': '0x0', 19:35:19 INFO - 'XPC_SERVICE_NAME': '0', 19:35:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:19 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)) 19:35:19 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)) 19:35:19 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)) 19:35:19 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)) 19:35:19 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)) 19:35:19 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)) 19:35:19 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)) 19:35:19 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)) 19:35:19 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)) 19:35:19 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:35:19 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 19:35:19 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 19:35:19 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 19:35:19 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 19:35:20 INFO - Downloading marionette_driver-1.4.0.tar.gz 19:35:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 19:35:20 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)) 19:35:20 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)) 19:35:20 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)) 19:35:20 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)) 19:35:20 INFO - Installing collected packages: marionette-driver 19:35:20 INFO - Running setup.py install for marionette-driver 19:35:21 INFO - Successfully installed marionette-driver 19:35:21 INFO - Cleaning up... 19:35:21 INFO - Return code: 0 19:35:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:35:21 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')]} 19:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:21 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')]} 19:35:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:21 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 0x104b38c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf92482ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488ed30>, '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 0x104858cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10487fbe8>, '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.ffPYaiHkrD/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.LQyfUThZNo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 19:35:21 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 19:35:21 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 19:35:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffPYaiHkrD/Render', 19:35:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:21 INFO - 'HOME': '/Users/cltbld', 19:35:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:21 INFO - 'LOGNAME': 'cltbld', 19:35:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:21 INFO - 'MOZ_NO_REMOTE': '1', 19:35:21 INFO - 'NO_EM_RESTART': '1', 19:35:21 INFO - 'PAGER': '/bin/cat', 19:35:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:21 INFO - 'PWD': '/builds/slave/test', 19:35:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:21 INFO - 'SHELL': '/bin/bash', 19:35:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LQyfUThZNo/Listeners', 19:35:21 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 19:35:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:21 INFO - 'USER': 'cltbld', 19:35:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:21 INFO - 'XPC_FLAGS': '0x0', 19:35:21 INFO - 'XPC_SERVICE_NAME': '0', 19:35:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:35:21 INFO - Cleaning up... 19:35:21 INFO - Return code: 0 19:35:21 INFO - Running post-action listener: _resource_record_post_action 19:35:21 INFO - Running post-action listener: _start_resource_monitoring 19:35:21 INFO - Starting resource monitoring. 19:35:21 INFO - [mozharness: 2017-06-13 02:35:21.421505Z] Finished create-virtualenv step (success) 19:35:21 INFO - [mozharness: 2017-06-13 02:35:21.422587Z] Running install step. 19:35:21 INFO - Running pre-action listener: _resource_record_pre_action 19:35:21 INFO - Running main action method: install 19:35:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:35:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:35:21 INFO - Reading from file tmpfile_stdout 19:35:21 INFO - Detecting whether we're running mozinstall >=1.0... 19:35:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:35:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:35:21 INFO - Reading from file tmpfile_stdout 19:35:21 INFO - Output received: 19:35:21 INFO - Usage: mozinstall [options] installer 19:35:21 INFO - Options: 19:35:21 INFO - -h, --help show this help message and exit 19:35:21 INFO - -d DEST, --destination=DEST 19:35:21 INFO - Directory to install application into. [default: 19:35:21 INFO - "/builds/slave/test"] 19:35:21 INFO - --app=APP Application being installed. [default: firefox] 19:35:21 INFO - mkdir: /builds/slave/test/build/application 19:35:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:35:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 19:35:39 INFO - Reading from file tmpfile_stdout 19:35:39 INFO - Output received: 19:35:39 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 19:35:39 INFO - Running post-action listener: _resource_record_post_action 19:35:39 INFO - [mozharness: 2017-06-13 02:35:39.308671Z] Finished install step (success) 19:35:39 INFO - [mozharness: 2017-06-13 02:35:39.308879Z] Running setup-mitmproxy step. 19:35:39 INFO - Running pre-action listener: _resource_record_pre_action 19:35:39 INFO - Running main action method: setup_mitmproxy 19:35:39 INFO - Skipping: mitmproxy is not required 19:35:39 INFO - Running post-action listener: _resource_record_post_action 19:35:39 INFO - [mozharness: 2017-06-13 02:35:39.309593Z] Finished setup-mitmproxy step (success) 19:35:39 INFO - [mozharness: 2017-06-13 02:35:39.309774Z] Running run-tests step. 19:35:39 INFO - Running pre-action listener: _resource_record_pre_action 19:35:39 INFO - Running main action method: run_tests 19:35:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:35:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:35:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:35:39 INFO - 2017-06-12 19:35:39.325 screenresolution[1657:7589] starting screenresolution argv=screenresolution get 19:35:39 INFO - 2017-06-12 19:35:39.328 screenresolution[1657:7589] Display 0: 1600x1200x32@60 19:35:39 INFO - 2017-06-12 19:35:39.334 screenresolution[1658:7596] starting screenresolution argv=screenresolution list 19:35:39 INFO - Available Modes on Display 0 19:35:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:35:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:35:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:35:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:35:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:35:39 INFO - Intel Iris: 19:35:39 INFO - Chipset Model: Intel Iris 19:35:39 INFO - Type: GPU 19:35:39 INFO - Bus: Built-In 19:35:39 INFO - VRAM (Dynamic, Max): 1536 MB 19:35:39 INFO - Vendor: Intel (0x8086) 19:35:39 INFO - Device ID: 0x0a2e 19:35:39 INFO - Revision ID: 0x0009 19:35:39 INFO - Displays: 19:35:39 INFO - Display: 19:35:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:35:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:35:39 INFO - Display Serial Number: 1600x1200 60 19:35:39 INFO - Main Display: Yes 19:35:39 INFO - Mirror: Off 19:35:39 INFO - Online: Yes 19:35:39 INFO - Rotation: Supported 19:35:39 INFO - Return code: 0 19:35:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:35:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:35:39 INFO - Python 2.7.3 19:35:39 INFO - Return code: 0 19:35:39 INFO - grabbing minidump binary from tooltool 19:35:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:39 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 0x10488ed30>, '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 0x104858cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10487fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:35:39 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 19:35:39 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 19:35:39 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 19:35:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:35:39 INFO - Return code: 0 19:35:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:35:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:35:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:35:39 INFO - ENV: RUST_BACKTRACE is now 1 19:35:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:35:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:35:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0239', '--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 19:35:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0239 --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 19:35:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffPYaiHkrD/Render', 19:35:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:39 INFO - 'HOME': '/Users/cltbld', 19:35:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:39 INFO - 'LOGNAME': 'cltbld', 19:35:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:35:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:35:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:39 INFO - 'MOZ_NO_REMOTE': '1', 19:35:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:35:39 INFO - 'NO_EM_RESTART': '1', 19:35:39 INFO - 'PAGER': '/bin/cat', 19:35:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:39 INFO - 'PWD': '/builds/slave/test', 19:35:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:35:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:39 INFO - 'RUST_BACKTRACE': '1', 19:35:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:35:39 INFO - 'SHELL': '/bin/bash', 19:35:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LQyfUThZNo/Listeners', 19:35:39 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 19:35:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:39 INFO - 'USER': 'cltbld', 19:35:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:39 INFO - 'XPC_FLAGS': '0x0', 19:35:39 INFO - 'XPC_SERVICE_NAME': '0', 19:35:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0239', '--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 19:35:40 INFO - mozversion application_buildid: 20170612173856 19:35:40 INFO - mozversion application_changeset: 496fd4521a947593a9e46daca2bc42bd73ea49af 19:35:40 INFO - mozversion application_display_name: Firefox 19:35:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:35:40 INFO - mozversion application_name: Firefox 19:35:40 INFO - mozversion application_remotingname: firefox 19:35:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:35:40 INFO - mozversion application_vendor: Mozilla 19:35:40 INFO - mozversion application_version: 55.0 19:35:40 INFO - mozversion platform_buildid: 20170612173856 19:35:40 INFO - mozversion platform_changeset: 496fd4521a947593a9e46daca2bc42bd73ea49af 19:35:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:35:40 INFO - mozversion platform_version: 55.0 19:35:40 INFO - using testdate: 1497321340 19:35:40 INFO - actual date: 1497321340 19:35:40 INFO - starting webserver on 'localhost:49233' 19:35:40 INFO - SUITE-START | Running 2 tests 19:35:40 INFO - TEST-START | dromaeo_css 19:35:40 INFO - operating with platform_type : mac_ 19:35:40 INFO - Initialising browser for dromaeo_css test... 19:35:40 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpCjN2PL/profile 19:35:40 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground) 19:36:13 INFO - TEST-INFO | 1668: killed by SIGTERM 19:36:13 INFO - Browser initialized. 19:36:13 INFO - Running cycle 1/1 for dromaeo_css test... 19:36:13 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpCjN2PL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:36:15 INFO - PID 1679 | 2017-06-12 19:36:15.692 plugin-container[1680:8046] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x484b, name = 'com.apple.tsm.portname' 19:36:15 INFO - PID 1679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:36:15 INFO - PID 1679 | 2017-06-12 19:36:15.693 plugin-container[1680:8046] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x991f, name = 'com.apple.CFPasteboardClient' 19:36:15 INFO - PID 1679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:36:15 INFO - PID 1679 | 2017-06-12 19:36:15.693 plugin-container[1680:8046] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:36:25 INFO - PID 1679 | RSS: Main: 185884672 19:36:25 INFO - PID 1679 | 19:39:27 INFO - PID 1679 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49233/tests/dromaeo/cssquery-ext.html) 19:39:29 INFO - PID 1679 | RSS: Main: 199671808 19:39:29 INFO - PID 1679 | 19:42:31 INFO - PID 1679 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-ext.html (next: http://localhost:49233/tests/dromaeo/cssquery-jquery.html) 19:42:32 INFO - PID 1679 | RSS: Main: 214081536 19:42:32 INFO - PID 1679 | 19:45:34 INFO - PID 1679 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49233/tests/dromaeo/cssquery-mootools.html) 19:45:35 INFO - PID 1679 | RSS: Main: 215863296 19:45:35 INFO - PID 1679 | 19:48:37 INFO - PID 1679 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49233/tests/dromaeo/cssquery-prototype.html) 19:48:38 INFO - PID 1679 | RSS: Main: 217464832 19:48:38 INFO - PID 1679 | 19:51:41 INFO - PID 1679 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49233/tests/dromaeo/cssquery-yui.html) 19:51:41 INFO - PID 1679 | RSS: Main: 217997312 19:51:41 INFO - PID 1679 | 19:54:43 INFO - PID 1679 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-yui.html (next: http://localhost:49233/tests/dromaeo/cssquery-dojo.html) 19:54:44 INFO - PID 1679 | RSS: Main: 216592384 19:54:44 INFO - PID 1679 | 19:54:44 INFO - PID 1679 | __start_tp_report 19:54:44 INFO - PID 1679 | _x_x_mozilla_page_load 19:54:44 INFO - PID 1679 | _x_x_mozilla_page_load_details 19:54:44 INFO - PID 1679 | |i|pagename|runs| 19:54:44 INFO - PID 1679 | |0;dojo.html;2425.0542288507486;2532.835365701228;2673.2648521656556;2810.3676672748634;2837.992512658007;4479.619232365253;4535.206338890697;4546.727196368216;4555.066211426658;4727.409073865767;4460.286354183331;4493.640508759299;4548.8635340939745;4605.562471565205;4630.143423466661;3906.921861562767;3939.2712348215528;3956.6439020488224;3980.303446896796;3982.0841206522373;3675.320065787838;3867.5939026402243;4004.7797371144575;4029.899252518696;4039.2527382434346;3844.615538446133;3861.594532574067;3905.7265991380614;3964.623360780721;3973.642372186517;3797;3860.2279544091293;3864.7294689371756;3911.4523966644692;3940.7241493095494;3784;3792.90517737056;3833.0034191110235;3846.0961674006585;3902.2780785554764;4379.8467053653285;4440.777961101964;4455.04216593434;4520.570545798144;4524.3213517972235;4759.8814278644495;4816.229403295491;4820.25286080658;4948.950510494885;4978.153713868651;3844.7116466265143;3942.467766851467;4745.762711864393;4807.927881081787;4945.579625731817;4189.224993376235;4270.0605866709275;4338.522762496123;4377.518472967971;4553.317002449626;3462.6191118976894;3620.9637903621156;4234.237837189274;4974.731443481889;5153.948460515422;4459.0858873930765;4642.071585682809;4817.253325734517;4838.153323168502;4856.611471082305;3596.964030359663;3901.882943511699;4598.218302888517;4690.718556886598;4766.45185803637;3710.777353358807;4534.682572219913;4670.509596492388;4685.2737825636395;4736.360591320127;3885.592012838611;4574.4739354973935;4594.89844355059;4627.398438203079;4668.976655116703;3063.433264846011;3522.7710198836994;3655.94516082259;3694.981525092411;3702.777833329955;2856.0146749371434;3135.52967054939;3267.1668724475107;3331.950020749691;3355.647656996029;4189.120284740177;4288.742675439484;4315.7194782339675;4407.647388208936;4412.940894185373;4527.592516673515;4693.178693728652;4741.099191153669;4761.714297142183;4826.420829500483;3680.687141592941;4344.892052526586;4346.413234213404;4378.452693413324;4525.981654127795;3750.3249415105543;3943.2507823514015;4004.5394779600138;4043.8180281887385;4164.104717485755;4721.055788842245;4740.360051393018;4896.559309662148;4897.804087836518;4952.356193694796;3941.566427692894;4425.601695847389;4587.38070360497;4718.693284936468;4719.457262414866;4546.158960592301;4655.604273636779;4679.836205732783;4692.765361731968;4696.342512048248;4356.869293921188;4406.625436837905;4408.58118478744;4483.798229079634;4522.819087236474;4251.06476575153;4278.5079715831835;4420.933685994648;4479.395281636937;4549.9317510236715;3857.0935830079347;3980.482537270136;4022.798860057044;4186.35111557709;4186.727862688916;3699.093722038118;3738.1962877981364;3765.2281282336508;3828.5214348206473;3993.8617494014065;3428.9314213716084;3433.210361616792;3443.7933723976644;3477.756557040983;3558.5195998540903;3035.8178509289514;3417.5215469833747;3471.166919939247;3493.982530087333;3564.787972089502;3495.2485215677734;3568.982839890619;3602.9731526514815;3625.836837342273;3632.945505817362 19:54:44 INFO - PID 1679 | |1;ext.html;423.3205704843726;427.78610694652673;440.52643408336036;451.5416851895327;459.94213309388397;19977.30079447218;21608.459788505257;21683.349499515;22011.779882201172;22235.666465003018;20296.39110826674;21349;21510.89244553779;21893.67159492609;21910.780892191073;4234.343676730112;4235.74585524868;4279.529251782302;4392.582704643054;4437.5784300491405;4085.3871919212206;4196.349565817303;4269.380939763732;4273.978630106845;4307.1601037797645;4179.644730197937;4179.79101044948;4300.440942677447;4823.32473453715;4828.082664293773;4643.094596553639;4709.882252943704;4723.692959957609;4728.794157489818;4801.1357955568155;4542.841000564964;4600.332951722015;4611.723296602215;4623.0984957933215;4655.650826188015;20729.58540829175;21364.679529807065;21541.892290538446;21588.24441144568;21598.460038499004;2004.8095430934038;2008.1866843928233;2023.2918478532542;2048.95902081959;2065.380385884244;17186;17361.913190433967;17541.736873946775;18188.27246910122;18679.906600467046;1762.753214549964;1800.558863078541;1817.5637846916777;1821.1258595874056;1846.4737549798376;139326;176890;179303.31045034336;180403;182464;19212.80787192138;22840.629562225986;23381.298561043197;23629.7637023631;24234.87882560576;28829.855850721033;29073.12780616585;29781.404371912875;30315;31116.377672446877;7016.7544135954995;8769.473831569998;8851.380403371832;8897.79879716243;9078.54607269634;6835.940429233477;7003.789886303419;7018.613976231356;7129.60787156701;7459.254074592497;6363.809085727436;7614.543127412373;7667.619828430825;7676.731314403969;7786.221377862168;3361.915952101168;3441.1913200397885;3462.7576069675383;3472.045187573387;3486.4770284457536;519.870032491877;560.6608002158716;565.933215888054;585.1661382529878;604.7490291528966;11884.692683804775;12470.81293780594;13152.21086734799;14634.195119268543;14771.704565908622;536.9543588794918;584.3017593975244;603.8882806680778;623.8801351573936;633.8859005379076;5798.43350383628;6391.648459334781;8464;8827.646894124291;9246.676366327147;192681;198802.00598996913;200017;201922.96154076836;205200;24722;29114.98097566575;30309.242268943453;30532.847335763177;30571.694283056884;821.6795449774712;845.2139510255398;859.3726579596929;861.8965724113147;865.6970060478782;13355.465781368637;14310.785338219726;15513.681337086475;17579.121043948006;17984.730229046836;21673;21840.67238991448;21903.452413689785;21930.780692192875;22124.66812997774;172012.1399392995;176257;177301;177600.11199944434;180954;16546.255418505956;16643.50069497917;19216.846989180693;19432.708509372653;19695.507612309808;22437.887810560842;23532.411689707893;23850.761492384852;23865.52268954646;24235.272941811774;11592.594259200887;16189;16531.173441327646;17643.823561764217;18034.909825450788;15998.520044398687;16505.752413714043;16792.748108778134;16927.746083808506;17300.567485812957;18922.905385472983;19614.921179335273;19708.71893326929;20225.79774202239;21026.84352360635;16221.918890405472;16333;16907.408240711517;17555.736663950305;17857.73213401826;12024;12840.357982100672;13288.667783305495;13995.93002035024;14277.071990320595 19:54:44 INFO - PID 1679 | |2;jquery.html;265.1356577557165;298.7609912070344;299.27276717576297;320.74661017795944;335.91266270769603;12728.363581820906;13774.862251377483;13807.585772426819;14060.859391406082;14407.423703051878;9897.604095836175;10381;10679.733006674836;12036.939815300933;12209.633710988663;3445.1559367954887;3495.1436897959907;3677.485152078711;3706.22169344438;3769.3403654360513;3571.499990001402;3602.883106237075;3608.693261072799;3660.5058317127114;3668.7248456365746;3488.7209023278206;3611.043073585502;3658.213484100918;3756.323861704892;3810.9047273818237;2197.769234230415;2350.2361732437025;3668.468072129553;3711.740178187528;3837.443570682249;2194.462356722597;2202.438378213545;2784.429192015632;3190.8512935343256;3361.96638033619;16984.726145539134;17393.304267829397;17840.46478605644;17894.463166104906;18951;1289.38109707341;1298.8116723075102;1322.4842311498428;1355.1733442600007;1374.4570894496717;10112.634794302749;10617.362958222455;10644.57421703131;11289.0968722502;11335.319880807096;1277.7444511097817;1289.9548515801903;1307.3789949773877;1315.2634524666219;1339.9196048237136;112636;115492;117164;118962;120166;8828.77928051791;8883.978342490567;9027.729168124966;9081;9570.856437153448;9193.172614464731;11598.420078996016;11675.649730508097;11900.404979750978;11929.46317415701;5776.277965254343;5800.970995144998;5918.644881307136;6065.848353791191;6222.968885155546;4597.3793537872625;4842.467328593852;5275.947240527622;5507.614466987353;5587.44125587438;4344.218040752695;4545.863534116471;4969.378827646544;5036.6726162799305;5074.492550744896;1540.11997564555;1584.8098228212457;1615.0390517641983;1626.3088187520257;1634.87738419619;546.368943869831;567.5544697412512;568.7867049856304;575.4763165519357;586.8180863932195;11240.718982025515;11268.605598804003;11754.807576072628;11944.701382465335;13502.907049407357;402.22370824309365;465.12556393979776;586.718375179916;592.4697395830793;604.247711599062;5277.709725965032;5834.474897259267;6137.202163718688;6287.811365659038;6458.418742313214;335713;346743;355299;358848;361740;90734;97051.5737106578;97837;99619;100268;591.520868096843;651.9119918810994;713.5219403000002;724.8294005181593;737.6864832446189;10460.58157673715;12216.633500994983;13307.135036223002;13615.727685446038;13807.447702092206;42786.786066070716;44793.88015299643;45784.7710761444;45909.77045114887;47157.528424716686;54563.65546114745;57549.712251438476;57666.42333576778;58754.706226468596;58872.11691824712;6505.7072431741635;6515;6568.047633093271;6713.295104014008;6780.677767835422;10149.79700405973;10372.377657340583;10450.790984180427;10466.790664186827;10722.463876806285;6639.601623902388;8210.522106020973;8446.535440550828;8670.653173872974;8676.522791246289;7512.849743005218;7770.45606807518;7784.455088143775;7998.960005199937;8039.758807235792;6083.817485475266;7052.717891284291;7291.8541629168185;7569.5079819811535;8709.782255443411;6640.103586016019;6712.664366781739;6825.317468253135;6909;6991.230964593992;8269.966254218223;8458.492490450593;8531.786705332417;8567.657293708182;8684.522351270742 19:54:44 INFO - PID 1679 | |3;mootools.html;681.4990981628503;821.3593397150225;826.768504818651;827.6647957577184;866.2247288676639;7408.296211859878;7924.366050715943;8277.089520152778;8537.14628537146;8573.442726222791;6653;6732.192136943574;6826.590404575729;7267.38227250683;7794.37644988401;1499.452699764589;1519.2707500399815;1575.0234854390294;1651.7357222844348;1653.5700717813336;1571.3793051744553;1577.1483398964604;1632.8285530019355;1649.1836540912204;1666.9081751452818;1540.6533529955727;1645.654412573361;1661.1279078483772;1662.3350659736143;1686.2496189195813;1288.4330894406526;1343.039726595489;1350.2776014832116;1586.9682606347808;1591.8697724615538;1649.7855278813677;1671.8578920791747;1673.2219517924102;1678.7565802958502;1699.7365408361725;7898.842023159562;8090.474119182234;8113.5131892086665;8149.266566009029;8329.791755206108;4331.848385306499;4471.485779135409;4530.79611417487;4531.501534831166;4581.770911454414;2819.28108332376;2834.3480999370054;2933.296008957856;2937.8824847006094;3002.03934740883;791.5765065689886;826.4710585225461;831.1107115386538;838.4969018588858;860.6342304520616;11943.163978521507;12017.63947081589;12086.637400877988;12101;12234.510619575207;3992.54085780139;4068.3287257602165;4068.9389659155136;4189.790510474464;4242.5121111072;9306.488143152057;9578.90421095794;9627.374220675634;10142.290039697298;10422.166226701846;4510.962478629934;4566.931496027562;4601.194790911577;4616.792244349012;4721.173794585934;4080.836766529365;4126.381042843597;4127.174565086994;4139.130782535702;4194.748315101104;2924.532074868011;3110.73558747509;3209.646938836726;3263.7225835803747;3268.542404063433;998.5057396588735;1018.511114664965;1036.7926414716935;1052.0584077250971;1056.7252514346326;460.5026571302985;494.6438564233746;495.68523987268344;501.1730644436636;502.89187824617153;3595.856165753393;4323.805428755713;4452.332150177434;4674.275487299474;4695.483496815347;502.27922930593985;531.4393315052579;583.7635757518212;588.5762251179144;595.2707932782394;2478.256523043116;2523.9242822715346;2722.264988453146;2978.0321395546534;3096.2723759916403;10130.594776209016;10525.052745252964;10666.89333106659;10772.892271077346;11371.658850234506;7766.922330776733;10037.548310326052;10767.946160269148;11033.393163376091;11308.49111789955;5576.163575463713;5627.409122042209;5681.829545113571;5869.148973398918;5899.46904778572;3737.850485980639;4246.977148668732;4531;4668.019715859572;4702.717836929795;6708.362705542965;8853.468791872509;9569.043095690375;9630.47032413224;10093.444860532742;10113.949430252802;10169.288149829441;10238.53926573291;10240.795184096127;10561.841572376574;5930.754541546324;6286.842828929313;6317.241930968313;6385.489160867212;6423.0686550450855;6825.761098361533;7080.362767350757;7155.427565794828;7211.819704507429;7450.776476705708;5198.298229739088;5344.385395679391;5860.853478662897;5891.646501209942;6010.639361638316;5409.756560954688;5755.597108202385;5789.363170051207;5844.970775146097;6003.879922401615;5161.974190129025;5282.445343238905;5284.682919024871;5328.706921119376;5361.115415956324;3272.7545434093013;3980.343243364812;4015.0966032642423;4096.8566100187545;4139.79301034941;3712.3874560698264;3891.5524714658177;3937.8818635440985;3994.141259629194;4220.620144187038 19:54:44 INFO - PID 1679 | |4;prototype.html;245.93851537115722;272.40615458300897;274.8529536697866;280.35378452666595;288.3483327679444;3030.6060212172442;3262.9069261797267;3845.8077096145184;3962.930008897596;4024.1951609678035;2687.9651334236314;3214.2607200343937;3434.79391236526;3716.3682174030414;3884.6309600587897;2319.826013049028;2697.8920843166297;2738.2332946774836;2788;2821.4216085702483;2304.827137964651;2513.3339664988794;2536.3405514566252;2676.8393896366183;2805.607214989893;2211.5024119573127;2468.5682304263573;2787.818791778528;2810.775137988956;2902.8258304501796;2593.482812554658;2767.7093905139873;2778.19432364614;2815.634417307604;2849.5725641153786;2354.2584086012853;2433.4889673168655;2462.3351695042415;2469.9506009879883;2494.775470207672;2847.515922293215;3136.341368312657;3621;3835.3671644178676;3869.245497128033;2997.7152170543764;3069.785115041948;3353.279045005311;3362.9159271018175;3414.402479566066;3490.965090349089;3554.773603106924;3971.384435412516;3997.42166302736;4122;2593.4683389905026;2614.516314481827;2708.7426694463993;2719.3473566344132;2836.6454193225845;3521.841517131735;4015.5984401559904;4192.392103145027;4323.832565207376;4346.826126954918;2719.428919926798;2730.6859711133075;2956.7782416318864;3103.8292893891053;3157.8894738684035;2714.1450443110357;2725.591161325777;3079.6603477487365;3178.4254753649616;3187.119599436344;2971.4502816978497;3233.9812958917864;3288.4245257079915;3619.1675914539805;3701.11173318407;3848.826802793881;4043.807076912318;4057.5130984282073;4136.462259906193;4170.332746760504;1041;1117.463617463622;1140.3841925360211;1147.9196456248148;1159.750653609478;770.6532060572765;807.6688557691318;813.8535063688474;820.1142765813026;826.396730386821;3715.981420092882;3794.3359912015285;3803.5625903021505;3904.4143378492886;3920.7059470539825;3689.3543629864666;3797.3354662933875;3937.940930886039;4031.576684448091;4162.41726158338;3764.5670747864347;3769.8115094245727;3784.4397501011217;3788.4696142540065;3807.5240594925635;1849.657813304543;1997.2809788476136;2104.705341252226;2123.443232585413;2191.1125993972146;3371.190914180579;3463.740219483549;3733.9253214935547;4263.019505513687;4320.2655548556195;3680.32545191937;3810.314143454205;4271.722338048017;4295.72077814941;4308.913821723548;4053.918921621551;4431.202383570923;4510.1656193604285;4586.958803332485;4619.838305659286;3964.4449777030663;4131.583023931034;4412.426384569985;4469.754163521037;4781.95218047829;3709.3508635988055;4446.955530444783;4693.6949098308505;5038.521340472649;5050.570701490415;5496.790706044664;5586.86032849182;5632.6338787980285;6181.474574661204;6972.163340398982;4152.937705934474;4580.587747102777;4767.69010014348;5001.599872010158;5027.949720502748;5313.442088580643;6079.878402431838;6230.909590821498;6349.174607301206;6374.362563743589;4234.696936389621;5569.66582005081;5599.80400685974;5935.109733540004;5961.135635332939;5460.754266058117;5711.029125048669;5762.366139724711;5858.037557418313;5929.377415371324;5154.819581314636;5524.254225679482;5530.032244357302;5621.437856214346;5634.1548767683535;5271.841844744664;5301.257823904733;5497.422770609029;5737.196792449144;5760.6669298934;4835.58897493717;4881.3403442828185;5227.4249832517635;5334.413214546319;5618.578606604439 19:54:44 INFO - PID 1679 | |5;yui.html;528.8968651113032;581.2327727399833;617.1082785375133;630.5932673425638;649.6746636860804;12554.9328307094;12561.995040396769;12728.045396595246;12766.68083297917;13011.349432528372;12733.872661273384;13233.272170030645;13461.125026873246;13464.798028029563;13681.863181368184;714.5069901767789;722.68201991124;724.5326764237064;727.8899677991079;754.5925200391782;704.2323866984985;708.6138054760142;721.7690339091492;728.5628622826315;734.7465124532027;663.1346093348179;726.498715886037;735.5696917303384;738.9298016688433;765.2003656179306;599.865030368168;684.3567046975852;703.3318347569829;706.7349743846083;727.5016613619661;516.1689679615815;518.9014087323434;523.0819911056072;529.0450736420759;532.2096686420663;12253.816192757116;12296.262224266573;12376.81434778479;12605.30670813105;12650.81023784644;11569.24799888013;11585.76828463426;11931.284122952651;11985;12314.876851231553;8876.822463550758;9920.958299378533;10016.148627366683;10175.745606359826;10203.92858749828;2376.346504711201;2449.4856080223176;2452.901883924641;2461.5323088613286;2515.295717199187;95028.52485737596;98174.99999999929;98656.50671746595;99203;116422;9405.858912116324;9417.293702972305;9436.764080898032;9458.006909274565;9544.809103817885;13696.452141914411;16952;17714.645707085787;17855.107244637977;18014.189361478766;4649.349091127245;4766.880827979259;4834.323194752737;4910.698664853817;5336.1195402758885;2702.8243164194264;2734.8786997331395;2819.8590070496393;2829.5472724364004;2915.97084029157;3058.3118798270657;3134.73354764843;3142.434361814895;3160.9525857111703;3214.8714051437682;675.0717763075771;682.8634273145391;684.9178098628197;696.2967402923084;704.9953815812447;348.27732455155655;425.68073944541595;444.68205233258374;444.81456917314995;464.7652935267736;6531.967340163269;6740;7529.8117547060665;8296.174841534852;8300.501969881827;412.40201707523613;428.47511798047765;441.70847240820905;447.11694403552724;452.3305507848403;2708.6072519484565;3529.5588051493564;3580.6598373154507;3699.9815000924823;3870.07118291608;72023;85800;89559;96144;100759.49620251852;68484.3151568488;69006.90398275951;71990.64004679942;73332;73652;53806;54410.63973400697;56337.718311409;58079.2576222714;60928.39071609227;6267.965785645318;6436.13112229843;6544.509161812788;6663.533552651268;6705.2959439258175;23258.60448373103;24527.160462965705;26391.60412593774;26454.338641533344;27055.86472067627;31826.681733182373;33108.66891331056;39184.21631567409;39926;48204.758976204896;8871.778205544655;9450.858237126586;9589.952050239983;9631.277654176105;9859.654912078044;10262.58949642006;10281.228907832092;10556.472176391304;10606.681799546026;10726.249162558546;7712.421568382506;9634.662786802428;9674.903250967682;9712;9851.704448866545;9507.66723164686;9517.476538790155;9622.278329125484;9925.205983521446;10113.544890480092;7812.726554570563;9010;9183;9580.233581313618;9657.661981830603;5711.600187986801;6291.905621415591;6361.268454127649;6737.326267373223;6773.966130169318;5709.429057094423;6582.473402127723;6999.09011828459;7003.859922801617;7345.889811652936 19:54:44 INFO - PID 1679 | __end_tp_report 19:54:44 INFO - PID 1679 | __start_cc_report 19:54:44 INFO - PID 1679 | _x_x_mozilla_cycle_collect,556 19:54:44 INFO - PID 1679 | __end_cc_report 19:54:44 INFO - PID 1679 | __startTimestamp1497322484180__endTimestamp 19:54:44 INFO - PID 1679 | 19:54:44 INFO - PID 1679 | ------- Summary: start ------- 19:54:44 INFO - PID 1679 | Number of tests: 6 19:54:44 INFO - PID 1679 | 19:54:44 INFO - PID 1679 | [#0] dojo.html Cycles:165 Average:4144.01 Median:4234.24 stddev:552.49 (13.0%) stddev-sans-first:537.48 19:54:44 INFO - PID 1679 | Values: 2425.1 2532.8 2673.3 2810.4 2838.0 4479.6 4535.2 4546.7 4555.1 4727.4 4460.3 4493.6 4548.9 4605.6 4630.1 3906.9 3939.3 3956.6 3980.3 3982.1 3675.3 3867.6 4004.8 4029.9 4039.3 3844.6 3861.6 3905.7 3964.6 3973.6 3797.0 3860.2 3864.7 3911.5 3940.7 3784.0 3792.9 3833.0 3846.1 3902.3 4379.8 4440.8 4455.0 4520.6 4524.3 4759.9 4816.2 4820.3 4949.0 4978.2 3844.7 3942.5 4745.8 4807.9 4945.6 4189.2 4270.1 4338.5 4377.5 4553.3 3462.6 3621.0 4234.2 4974.7 5153.9 4459.1 4642.1 4817.3 4838.2 4856.6 3597.0 3901.9 4598.2 4690.7 4766.5 3710.8 4534.7 4670.5 4685.3 4736.4 3885.6 4574.5 4594.9 4627.4 4669.0 3063.4 3522.8 3655.9 3695.0 3702.8 2856.0 3135.5 3267.2 3332.0 3355.6 4189.1 4288.7 4315.7 4407.6 4412.9 4527.6 4693.2 4741.1 4761.7 4826.4 3680.7 4344.9 4346.4 4378.5 4526.0 3750.3 3943.3 4004.5 4043.8 4164.1 4721.1 4740.4 4896.6 4897.8 4952.4 3941.6 4425.6 4587.4 4718.7 4719.5 4546.2 4655.6 4679.8 4692.8 4696.3 4356.9 4406.6 4408.6 4483.8 4522.8 4251.1 4278.5 4420.9 4479.4 4549.9 3857.1 3980.5 4022.8 4186.4 4186.7 3699.1 3738.2 3765.2 3828.5 3993.9 3428.9 3433.2 3443.8 3477.8 3558.5 3035.8 3417.5 3471.2 3494.0 3564.8 3495.2 3569.0 3603.0 3625.8 3632.9 19:54:44 INFO - PID 1679 | 19:54:44 INFO - PID 1679 | [#1] ext.html Cycles:180 Average:26401.24 Median:22336.78 stddev:48250.56 (216.0%) stddev-sans-first:48346.49 19:54:44 INFO - PID 1679 | Values: 423.3 427.8 440.5 451.5 459.9 19977.3 21608.5 21683.3 22011.8 22235.7 20296.4 21349.0 21510.9 21893.7 21910.8 4234.3 4235.7 4279.5 4392.6 4437.6 4085.4 4196.3 4269.4 4274.0 4307.2 4179.6 4179.8 4300.4 4823.3 4828.1 4643.1 4709.9 4723.7 4728.8 4801.1 4542.8 4600.3 4611.7 4623.1 4655.7 20729.6 21364.7 21541.9 21588.2 21598.5 2004.8 2008.2 2023.3 2049.0 2065.4 17186.0 17361.9 17541.7 18188.3 18679.9 1762.8 1800.6 1817.6 1821.1 1846.5 139326.0 176890.0 179303.3 180403.0 182464.0 19212.8 22840.6 23381.3 23629.8 24234.9 28829.9 29073.1 29781.4 30315.0 31116.4 7016.8 8769.5 8851.4 8897.8 9078.5 6835.9 7003.8 7018.6 7129.6 7459.3 6363.8 7614.5 7667.6 7676.7 7786.2 3361.9 3441.2 3462.8 3472.0 3486.5 519.9 560.7 565.9 585.2 604.7 11884.7 12470.8 13152.2 14634.2 14771.7 537.0 584.3 603.9 623.9 633.9 5798.4 6391.6 8464.0 8827.6 9246.7 192681.0 198802.0 200017.0 201923.0 205200.0 24722.0 29115.0 30309.2 30532.8 30571.7 821.7 845.2 859.4 861.9 865.7 13355.5 14310.8 15513.7 17579.1 17984.7 21673.0 21840.7 21903.5 21930.8 22124.7 172012.1 176257.0 177301.0 177600.1 180954.0 16546.3 16643.5 19216.8 19432.7 19695.5 22437.9 23532.4 23850.8 23865.5 24235.3 11592.6 16189.0 16531.2 17643.8 18034.9 15998.5 16505.8 16792.7 16927.7 17300.6 18922.9 19614.9 19708.7 20225.8 21026.8 16221.9 16333.0 16907.4 17555.7 17857.7 12024.0 12840.4 13288.7 13995.9 14277.1 19:54:44 INFO - PID 1679 | 19:54:44 INFO - PID 1679 | [#2] jquery.html Cycles:180 Average:24097.00 Median:3762.83 stddev:61003.71 (1621.2%) stddev-sans-first:61148.60 19:54:44 INFO - PID 1679 | Values: 265.1 298.8 299.3 320.7 335.9 12728.4 13774.9 13807.6 14060.9 14407.4 9897.6 10381.0 10679.7 12036.9 12209.6 3445.2 3495.1 3677.5 3706.2 3769.3 3571.5 3602.9 3608.7 3660.5 3668.7 3488.7 3611.0 3658.2 3756.3 3810.9 2197.8 2350.2 3668.5 3711.7 3837.4 2194.5 2202.4 2784.4 3190.9 3362.0 16984.7 17393.3 17840.5 17894.5 18951.0 1289.4 1298.8 1322.5 1355.2 1374.5 10112.6 10617.4 10644.6 11289.1 11335.3 1277.7 1290.0 1307.4 1315.3 1339.9 112636.0 115492.0 117164.0 118962.0 120166.0 8828.8 8884.0 9027.7 9081.0 9570.9 9193.2 11598.4 11675.6 11900.4 11929.5 5776.3 5801.0 5918.6 6065.8 6223.0 4597.4 4842.5 5275.9 5507.6 5587.4 4344.2 4545.9 4969.4 5036.7 5074.5 1540.1 1584.8 1615.0 1626.3 1634.9 546.4 567.6 568.8 575.5 586.8 11240.7 11268.6 11754.8 11944.7 13502.9 402.2 465.1 586.7 592.5 604.2 5277.7 5834.5 6137.2 6287.8 6458.4 335713.0 346743.0 355299.0 358848.0 361740.0 90734.0 97051.6 97837.0 99619.0 100268.0 591.5 651.9 713.5 724.8 737.7 10460.6 12216.6 13307.1 13615.7 13807.4 42786.8 44793.9 45784.8 45909.8 47157.5 54563.7 57549.7 57666.4 58754.7 58872.1 6505.7 6515.0 6568.0 6713.3 6780.7 10149.8 10372.4 10450.8 10466.8 10722.5 6639.6 8210.5 8446.5 8670.7 8676.5 7512.8 7770.5 7784.5 7999.0 8039.8 6083.8 7052.7 7291.9 7569.5 8709.8 6640.1 6712.7 6825.3 6909.0 6991.2 8270.0 8458.5 8531.8 8567.7 8684.5 19:54:44 INFO - PID 1679 | 19:54:44 INFO - PID 1679 | [#3] mootools.html Cycles:180 Average:4876.95 Median:4392.09 stddev:3252.12 (74.0%) stddev-sans-first:3245.96 19:54:44 INFO - PID 1679 | Values: 681.5 821.4 826.8 827.7 866.2 7408.3 7924.4 8277.1 8537.1 8573.4 6653.0 6732.2 6826.6 7267.4 7794.4 1499.5 1519.3 1575.0 1651.7 1653.6 1571.4 1577.1 1632.8 1649.2 1666.9 1540.7 1645.7 1661.1 1662.3 1686.2 1288.4 1343.0 1350.3 1587.0 1591.9 1649.8 1671.9 1673.2 1678.8 1699.7 7898.8 8090.5 8113.5 8149.3 8329.8 4331.8 4471.5 4530.8 4531.5 4581.8 2819.3 2834.3 2933.3 2937.9 3002.0 791.6 826.5 831.1 838.5 860.6 11943.2 12017.6 12086.6 12101.0 12234.5 3992.5 4068.3 4068.9 4189.8 4242.5 9306.5 9578.9 9627.4 10142.3 10422.2 4511.0 4566.9 4601.2 4616.8 4721.2 4080.8 4126.4 4127.2 4139.1 4194.7 2924.5 3110.7 3209.6 3263.7 3268.5 998.5 1018.5 1036.8 1052.1 1056.7 460.5 494.6 495.7 501.2 502.9 3595.9 4323.8 4452.3 4674.3 4695.5 502.3 531.4 583.8 588.6 595.3 2478.3 2523.9 2722.3 2978.0 3096.3 10130.6 10525.1 10666.9 10772.9 11371.7 7766.9 10037.5 10767.9 11033.4 11308.5 5576.2 5627.4 5681.8 5869.1 5899.5 3737.9 4247.0 4531.0 4668.0 4702.7 6708.4 8853.5 9569.0 9630.5 10093.4 10113.9 10169.3 10238.5 10240.8 10561.8 5930.8 6286.8 6317.2 6385.5 6423.1 6825.8 7080.4 7155.4 7211.8 7450.8 5198.3 5344.4 5860.9 5891.6 6010.6 5409.8 5755.6 5789.4 5845.0 6003.9 5162.0 5282.4 5284.7 5328.7 5361.1 3272.8 3980.3 4015.1 4096.9 4139.8 3712.4 3891.6 3937.9 3994.1 4220.6 19:54:44 INFO - PID 1679 | 19:54:44 INFO - PID 1679 | [#4] prototype.html Cycles:180 Average:3664.87 Median:3805.54 stddev:1406.31 (37.0%) stddev-sans-first:1386.64 19:54:44 INFO - PID 1679 | Values: 245.9 272.4 274.9 280.4 288.3 3030.6 3262.9 3845.8 3962.9 4024.2 2688.0 3214.3 3434.8 3716.4 3884.6 2319.8 2697.9 2738.2 2788.0 2821.4 2304.8 2513.3 2536.3 2676.8 2805.6 2211.5 2468.6 2787.8 2810.8 2902.8 2593.5 2767.7 2778.2 2815.6 2849.6 2354.3 2433.5 2462.3 2470.0 2494.8 2847.5 3136.3 3621.0 3835.4 3869.2 2997.7 3069.8 3353.3 3362.9 3414.4 3491.0 3554.8 3971.4 3997.4 4122.0 2593.5 2614.5 2708.7 2719.3 2836.6 3521.8 4015.6 4192.4 4323.8 4346.8 2719.4 2730.7 2956.8 3103.8 3157.9 2714.1 2725.6 3079.7 3178.4 3187.1 2971.5 3234.0 3288.4 3619.2 3701.1 3848.8 4043.8 4057.5 4136.5 4170.3 1041.0 1117.5 1140.4 1147.9 1159.8 770.7 807.7 813.9 820.1 826.4 3716.0 3794.3 3803.6 3904.4 3920.7 3689.4 3797.3 3937.9 4031.6 4162.4 3764.6 3769.8 3784.4 3788.5 3807.5 1849.7 1997.3 2104.7 2123.4 2191.1 3371.2 3463.7 3733.9 4263.0 4320.3 3680.3 3810.3 4271.7 4295.7 4308.9 4053.9 4431.2 4510.2 4587.0 4619.8 3964.4 4131.6 4412.4 4469.8 4782.0 3709.4 4447.0 4693.7 5038.5 5050.6 5496.8 5586.9 5632.6 6181.5 6972.2 4152.9 4580.6 4767.7 5001.6 5027.9 5313.4 6079.9 6230.9 6349.2 6374.4 4234.7 5569.7 5599.8 5935.1 5961.1 5460.8 5711.0 5762.4 5858.0 5929.4 5154.8 5524.3 5530.0 5621.4 5634.2 5271.8 5301.3 5497.4 5737.2 5760.7 4835.6 4881.3 5227.4 5334.4 5618.6 19:54:44 INFO - PID 1679 | 19:54:44 INFO - PID 1679 | [#5] yui.html Cycles:180 Average:15731.15 Median:5710.51 stddev:24644.08 (431.6%) stddev-sans-first:24686.78 19:54:44 INFO - PID 1679 | Values: 528.9 581.2 617.1 630.6 649.7 12554.9 12562.0 12728.0 12766.7 13011.3 12733.9 13233.3 13461.1 13464.8 13681.9 714.5 722.7 724.5 727.9 754.6 704.2 708.6 721.8 728.6 734.7 663.1 726.5 735.6 738.9 765.2 599.9 684.4 703.3 706.7 727.5 516.2 518.9 523.1 529.0 532.2 12253.8 12296.3 12376.8 12605.3 12650.8 11569.2 11585.8 11931.3 11985.0 12314.9 8876.8 9921.0 10016.1 10175.7 10203.9 2376.3 2449.5 2452.9 2461.5 2515.3 95028.5 98175.0 98656.5 99203.0 116422.0 9405.9 9417.3 9436.8 9458.0 9544.8 13696.5 16952.0 17714.6 17855.1 18014.2 4649.3 4766.9 4834.3 4910.7 5336.1 2702.8 2734.9 2819.9 2829.5 2916.0 3058.3 3134.7 3142.4 3161.0 3214.9 675.1 682.9 684.9 696.3 705.0 348.3 425.7 444.7 444.8 464.8 6532.0 6740.0 7529.8 8296.2 8300.5 412.4 428.5 441.7 447.1 452.3 2708.6 3529.6 3580.7 3700.0 3870.1 72023.0 85800.0 89559.0 96144.0 100759.5 68484.3 69006.9 71990.6 73332.0 73652.0 53806.0 54410.6 56337.7 58079.3 60928.4 6268.0 6436.1 6544.5 6663.5 6705.3 23258.6 24527.2 26391.6 26454.3 27055.9 31826.7 33108.7 39184.2 39926.0 48204.8 8871.8 9450.9 9590.0 9631.3 9859.7 10262.6 10281.2 10556.5 10606.7 10726.2 7712.4 9634.7 9674.9 9712.0 9851.7 9507.7 9517.5 9622.3 9925.2 10113.5 7812.7 9010.0 9183.0 9580.2 9657.7 5711.6 6291.9 6361.3 6737.3 6774.0 5709.4 6582.5 6999.1 7003.9 7345.9 19:54:44 INFO - PID 1679 | -------- Summary: end -------- 19:54:44 INFO - PID 1679 | 19:54:44 INFO - TEST-INFO | 1679: exit 0 19:54:44 INFO - TEST-OK | dromaeo_css | took 1144994ms 19:54:44 INFO - TEST-START | kraken 19:54:44 INFO - operating with platform_type : mac_ 19:54:44 INFO - Initialising browser for kraken test... 19:54:45 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpLUgkfk/profile 19:54:45 INFO - TEST-INFO | started process 1801 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground) 19:55:19 INFO - TEST-INFO | 1801: killed by SIGTERM 19:55:19 INFO - Browser initialized. 19:55:19 INFO - Running cycle 1/1 for kraken test... 19:55:19 INFO - TEST-INFO | started process 1807 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpLUgkfk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:55:22 INFO - PID 1807 | 2017-06-12 19:55:22.561 plugin-container[1808:10946] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 19:55:22 INFO - PID 1807 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:55:22 INFO - PID 1807 | 2017-06-12 19:55:22.562 plugin-container[1808:10946] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 19:55:22 INFO - PID 1807 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:55:22 INFO - PID 1807 | 2017-06-12 19:55:22.562 plugin-container[1808:10946] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:55:32 INFO - PID 1807 | RSS: Main: 184721408 19:55:32 INFO - PID 1807 | 19:55:36 INFO - PID 1807 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-beat-detection) 19:55:38 INFO - PID 1807 | RSS: Main: 198012928 19:55:38 INFO - PID 1807 | 19:55:41 INFO - PID 1807 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-dft) 19:55:42 INFO - PID 1807 | RSS: Main: 207802368 19:55:42 INFO - PID 1807 | 19:55:47 INFO - PID 1807 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-fft) 19:55:48 INFO - PID 1807 | RSS: Main: 205815808 19:55:48 INFO - PID 1807 | 19:55:51 INFO - PID 1807 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-oscillator) 19:55:52 INFO - PID 1807 | RSS: Main: 211378176 19:55:52 INFO - PID 1807 | 19:55:55 INFO - PID 1807 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:55:55 INFO - PID 1807 | RSS: Main: 212402176 19:55:55 INFO - PID 1807 | 19:56:01 INFO - PID 1807 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-darkroom) 19:56:02 INFO - PID 1807 | RSS: Main: 211615744 19:56:02 INFO - PID 1807 | 19:56:06 INFO - PID 1807 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-desaturate) 19:56:07 INFO - PID 1807 | RSS: Main: 211697664 19:56:07 INFO - PID 1807 | 19:56:12 INFO - PID 1807 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49233/tests/kraken/driver.html?testName=json-parse-financial) 19:56:13 INFO - PID 1807 | RSS: Main: 210878464 19:56:13 INFO - PID 1807 | 19:56:15 INFO - PID 1807 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49233/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:56:15 INFO - PID 1807 | RSS: Main: 215646208 19:56:15 INFO - PID 1807 | 19:56:18 INFO - PID 1807 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:56:19 INFO - PID 1807 | RSS: Main: 218521600 19:56:19 INFO - PID 1807 | 19:56:22 INFO - PID 1807 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:56:23 INFO - PID 1807 | RSS: Main: 220991488 19:56:23 INFO - PID 1807 | 19:56:27 INFO - PID 1807 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:56:28 INFO - PID 1807 | RSS: Main: 216977408 19:56:28 INFO - PID 1807 | 19:56:33 INFO - PID 1807 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:56:34 INFO - PID 1807 | RSS: Main: 217264128 19:56:34 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49233/tests/kraken/driver.html?testName=ai-astar) 19:56:37 INFO - PID 1807 | RSS: Main: 223080448 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | __start_tp_report 19:56:37 INFO - PID 1807 | _x_x_mozilla_page_load 19:56:37 INFO - PID 1807 | _x_x_mozilla_page_load_details 19:56:37 INFO - PID 1807 | |i|pagename|runs| 19:56:37 INFO - PID 1807 | |0;ai-astar;231;228;179;215;219;209;225;200;240;207 19:56:37 INFO - PID 1807 | |1;audio-beat-detection;236;184;212;243;180;217;188;340;304;215 19:56:37 INFO - PID 1807 | |2;audio-dft;315;395;342;315;340;278;393;398;403;318 19:56:37 INFO - PID 1807 | |3;audio-fft;125;130;117;208;106;105;101;105;153;150 19:56:37 INFO - PID 1807 | |4;audio-oscillator;128;130;185;121;124;154;196;182;131;202 19:56:37 INFO - PID 1807 | |5;imaging-gaussian-blur;225;248;220;242;224;270;201;235;194;165 19:56:37 INFO - PID 1807 | |6;imaging-darkroom;199;188;171;179;188;215;182;182;197;176 19:56:37 INFO - PID 1807 | |7;imaging-desaturate;197;222;142;153;158;204;178;184;161;168 19:56:37 INFO - PID 1807 | |8;json-parse-financial;81;98;90;90;112;96;95;100;90;97 19:56:37 INFO - PID 1807 | |9;json-stringify-tinderbox;95;92;103;98;103;107;109;92;104;111 19:56:37 INFO - PID 1807 | |10;stanford-crypto-aes;149;183;196;137;186;166;139;166;141;174 19:56:37 INFO - PID 1807 | |11;stanford-crypto-ccm;242;257;235;281;274;237;236;263;267;355 19:56:37 INFO - PID 1807 | |12;stanford-crypto-pbkdf2;343;335;361;412;321;319;390;322;314;353 19:56:37 INFO - PID 1807 | |13;stanford-crypto-sha256-iterative;103;127;134;147;103;174;150;156;121;149 19:56:37 INFO - PID 1807 | __end_tp_report 19:56:37 INFO - PID 1807 | __start_cc_report 19:56:37 INFO - PID 1807 | _x_x_mozilla_cycle_collect,1444 19:56:37 INFO - PID 1807 | __end_cc_report 19:56:37 INFO - PID 1807 | __startTimestamp1497322597339__endTimestamp 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | ------- Summary: start ------- 19:56:37 INFO - PID 1807 | Number of tests: 14 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | [#0] ai-astar Cycles:10 Average:215.30 Median:222.00 stddev:17.59 (7.9%) stddev-sans-first:17.72 19:56:37 INFO - PID 1807 | Values: 231.0 228.0 179.0 215.0 219.0 209.0 225.0 200.0 240.0 207.0 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | [#1] audio-beat-detection Cycles:10 Average:231.90 Median:226.50 stddev:52.54 (23.2%) stddev-sans-first:55.70 19:56:37 INFO - PID 1807 | Values: 236.0 184.0 212.0 243.0 180.0 217.0 188.0 340.0 304.0 215.0 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | [#2] audio-dft Cycles:10 Average:349.70 Median:367.50 stddev:44.48 (12.1%) stddev-sans-first:45.37 19:56:37 INFO - PID 1807 | Values: 315.0 395.0 342.0 315.0 340.0 278.0 393.0 398.0 403.0 318.0 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | [#3] audio-fft Cycles:10 Average:130.00 Median:127.50 stddev:33.12 (26.0%) stddev-sans-first:35.08 19:56:37 INFO - PID 1807 | Values: 125.0 130.0 117.0 208.0 106.0 105.0 101.0 105.0 153.0 150.0 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | [#4] audio-oscillator Cycles:10 Average:155.30 Median:168.00 stddev:32.60 (19.4%) stddev-sans-first:33.05 19:56:37 INFO - PID 1807 | Values: 128.0 130.0 185.0 121.0 124.0 154.0 196.0 182.0 131.0 202.0 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | [#5] imaging-gaussian-blur Cycles:10 Average:222.40 Median:230.00 stddev:29.89 (13.0%) stddev-sans-first:31.68 19:56:37 INFO - PID 1807 | Values: 225.0 248.0 220.0 242.0 224.0 270.0 201.0 235.0 194.0 165.0 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | [#6] imaging-darkroom Cycles:10 Average:187.70 Median:188.00 stddev:12.98 (6.9%) stddev-sans-first:13.11 19:56:37 INFO - PID 1807 | Values: 199.0 188.0 171.0 179.0 188.0 215.0 182.0 182.0 197.0 176.0 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | [#7] imaging-desaturate Cycles:10 Average:176.70 Median:181.00 stddev:25.17 (13.9%) stddev-sans-first:25.60 19:56:37 INFO - PID 1807 | Values: 197.0 222.0 142.0 153.0 158.0 204.0 178.0 184.0 161.0 168.0 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | [#8] json-parse-financial Cycles:10 Average:94.90 Median:92.50 stddev:8.16 (8.8%) stddev-sans-first:6.93 19:56:37 INFO - PID 1807 | Values: 81.0 98.0 90.0 90.0 112.0 96.0 95.0 100.0 90.0 97.0 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | [#9] json-stringify-tinderbox Cycles:10 Average:101.40 Median:101.50 stddev:6.85 (6.7%) stddev-sans-first:6.86 19:56:37 INFO - PID 1807 | Values: 95.0 92.0 103.0 98.0 103.0 107.0 109.0 92.0 104.0 111.0 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | [#10] stanford-crypto-aes Cycles:10 Average:163.70 Median:170.00 stddev:21.30 (12.5%) stddev-sans-first:21.92 19:56:37 INFO - PID 1807 | Values: 149.0 183.0 196.0 137.0 186.0 166.0 139.0 166.0 141.0 174.0 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | [#11] stanford-crypto-ccm Cycles:10 Average:264.70 Median:265.00 stddev:35.84 (13.5%) stddev-sans-first:37.06 19:56:37 INFO - PID 1807 | Values: 242.0 257.0 235.0 281.0 274.0 237.0 236.0 263.0 267.0 355.0 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:347.00 Median:348.00 stddev:32.73 (9.4%) stddev-sans-first:34.68 19:56:37 INFO - PID 1807 | Values: 343.0 335.0 361.0 412.0 321.0 319.0 390.0 322.0 314.0 353.0 19:56:37 INFO - PID 1807 | 19:56:37 INFO - PID 1807 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:136.40 Median:148.00 stddev:23.13 (15.6%) stddev-sans-first:21.15 19:56:37 INFO - PID 1807 | Values: 103.0 127.0 134.0 147.0 103.0 174.0 150.0 156.0 121.0 149.0 19:56:37 INFO - PID 1807 | -------- Summary: end -------- 19:56:37 INFO - PID 1807 | 19:56:38 INFO - TEST-INFO | 1807: exit 0 19:56:38 INFO - TEST-OK | kraken | took 113620ms 19:56:38 INFO - SUITE-END | took 1258s 19:56:39 INFO - Completed test suite (00:20:59) 19:56:39 INFO - javascript benchmark 19:56:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 5112.2217172859655, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2425.0542288507486, 2532.835365701228, 2673.2648521656556, 2810.3676672748634, 2837.992512658007, 4479.619232365253, 4535.206338890697, 4546.727196368216, 4555.066211426658, 4727.409073865767, 4460.286354183331, 4493.640508759299, 4548.8635340939745, 4605.562471565205, 4630.143423466661, 3906.921861562767, 3939.2712348215528, 3956.6439020488224, 3980.303446896796, 3982.0841206522373, 3675.320065787838, 3867.5939026402243, 4004.7797371144575, 4029.899252518696, 4039.2527382434346, 3844.615538446133, 3861.594532574067, 3905.7265991380614, 3964.623360780721, 3973.642372186517, 3797.0, 3860.2279544091293, 3864.7294689371756, 3911.4523966644692, 3940.7241493095494, 3784.0, 3792.90517737056, 3833.0034191110235, 3846.0961674006585, 3902.2780785554764, 4379.8467053653285, 4440.777961101964, 4455.04216593434, 4520.570545798144, 4524.3213517972235, 4759.8814278644495, 4816.229403295491, 4820.25286080658, 4948.950510494885, 4978.153713868651, 3844.7116466265143, 3942.467766851467, 4745.762711864393, 4807.927881081787, 4945.579625731817, 4189.224993376235, 4270.0605866709275, 4338.522762496123, 4377.518472967971, 4553.317002449626, 3462.6191118976894, 3620.9637903621156, 4234.237837189274, 4974.731443481889, 5153.948460515422, 4459.0858873930765, 4642.071585682809, 4817.253325734517, 4838.153323168502, 4856.611471082305, 3596.964030359663, 3901.882943511699, 4598.218302888517, 4690.718556886598, 4766.45185803637, 3710.777353358807, 4534.682572219913, 4670.509596492388, 4685.2737825636395, 4736.360591320127, 3885.592012838611, 4574.4739354973935, 4594.89844355059, 4627.398438203079, 4668.976655116703, 3063.433264846011, 3522.7710198836994, 3655.94516082259, 3694.981525092411, 3702.777833329955, 2856.0146749371434, 3135.52967054939, 3267.1668724475107, 3331.950020749691, 3355.647656996029, 4189.120284740177, 4288.742675439484, 4315.7194782339675, 4407.647388208936, 4412.940894185373, 4527.592516673515, 4693.178693728652, 4741.099191153669, 4761.714297142183, 4826.420829500483, 3680.687141592941, 4344.892052526586, 4346.413234213404, 4378.452693413324, 4525.981654127795, 3750.3249415105543, 3943.2507823514015, 4004.5394779600138, 4043.8180281887385, 4164.104717485755, 4721.055788842245, 4740.360051393018, 4896.559309662148, 4897.804087836518, 4952.356193694796, 3941.566427692894, 4425.601695847389, 4587.38070360497, 4718.693284936468, 4719.457262414866, 4546.158960592301, 4655.604273636779, 4679.836205732783, 4692.765361731968, 4696.342512048248, 4356.869293921188, 4406.625436837905, 4408.58118478744, 4483.798229079634, 4522.819087236474, 4251.06476575153, 4278.5079715831835, 4420.933685994648, 4479.395281636937, 4549.9317510236715, 3857.0935830079347, 3980.482537270136, 4022.798860057044, 4186.35111557709, 4186.727862688916, 3699.093722038118, 3738.1962877981364, 3765.2281282336508, 3828.5214348206473, 3993.8617494014065, 3428.9314213716084, 3433.210361616792, 3443.7933723976644, 3477.756557040983, 3558.5195998540903, 3035.8178509289514, 3417.5215469833747, 3471.166919939247, 3493.982530087333, 3564.787972089502, 3495.2485215677734, 3568.982839890619, 3602.9731526514815, 3625.836837342273, 3632.945505817362], "value": 4110.29135745242, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.3205704843726, 427.78610694652673, 440.52643408336036, 451.5416851895327, 459.94213309388397, 19977.30079447218, 21608.459788505257, 21683.349499515, 22011.779882201172, 22235.666465003018, 20296.39110826674, 21349.0, 21510.89244553779, 21893.67159492609, 21910.780892191073, 4234.343676730112, 4235.74585524868, 4279.529251782302, 4392.582704643054, 4437.5784300491405, 4085.3871919212206, 4196.349565817303, 4269.380939763732, 4273.978630106845, 4307.1601037797645, 4179.644730197937, 4179.79101044948, 4300.440942677447, 4823.32473453715, 4828.082664293773, 4643.094596553639, 4709.882252943704, 4723.692959957609, 4728.794157489818, 4801.1357955568155, 4542.841000564964, 4600.332951722015, 4611.723296602215, 4623.0984957933215, 4655.650826188015, 20729.58540829175, 21364.679529807065, 21541.892290538446, 21588.24441144568, 21598.460038499004, 2004.8095430934038, 2008.1866843928233, 2023.2918478532542, 2048.95902081959, 2065.380385884244, 17186.0, 17361.913190433967, 17541.736873946775, 18188.27246910122, 18679.906600467046, 1762.753214549964, 1800.558863078541, 1817.5637846916777, 1821.1258595874056, 1846.4737549798376, 139326.0, 176890.0, 179303.31045034336, 180403.0, 182464.0, 19212.80787192138, 22840.629562225986, 23381.298561043197, 23629.7637023631, 24234.87882560576, 28829.855850721033, 29073.12780616585, 29781.404371912875, 30315.0, 31116.377672446877, 7016.7544135954995, 8769.473831569998, 8851.380403371832, 8897.79879716243, 9078.54607269634, 6835.940429233477, 7003.789886303419, 7018.613976231356, 7129.60787156701, 7459.254074592497, 6363.809085727436, 7614.543127412373, 7667.619828430825, 7676.731314403969, 7786.221377862168, 3361.915952101168, 3441.1913200397885, 3462.7576069675383, 3472.045187573387, 3486.4770284457536, 519.870032491877, 560.6608002158716, 565.933215888054, 585.1661382529878, 604.7490291528966, 11884.692683804775, 12470.81293780594, 13152.21086734799, 14634.195119268543, 14771.704565908622, 536.9543588794918, 584.3017593975244, 603.8882806680778, 623.8801351573936, 633.8859005379076, 5798.43350383628, 6391.648459334781, 8464.0, 8827.646894124291, 9246.676366327147, 192681.0, 198802.00598996913, 200017.0, 201922.96154076836, 205200.0, 24722.0, 29114.98097566575, 30309.242268943453, 30532.847335763177, 30571.694283056884, 821.6795449774712, 845.2139510255398, 859.3726579596929, 861.8965724113147, 865.6970060478782, 13355.465781368637, 14310.785338219726, 15513.681337086475, 17579.121043948006, 17984.730229046836, 21673.0, 21840.67238991448, 21903.452413689785, 21930.780692192875, 22124.66812997774, 172012.1399392995, 176257.0, 177301.0, 177600.11199944434, 180954.0, 16546.255418505956, 16643.50069497917, 19216.846989180693, 19432.708509372653, 19695.507612309808, 22437.887810560842, 23532.411689707893, 23850.761492384852, 23865.52268954646, 24235.272941811774, 11592.594259200887, 16189.0, 16531.173441327646, 17643.823561764217, 18034.909825450788, 15998.520044398687, 16505.752413714043, 16792.748108778134, 16927.746083808506, 17300.567485812957, 18922.905385472983, 19614.921179335273, 19708.71893326929, 20225.79774202239, 21026.84352360635, 16221.918890405472, 16333.0, 16907.408240711517, 17555.736663950305, 17857.73213401826, 12024.0, 12840.357982100672, 13288.667783305495, 13995.93002035024, 14277.071990320595], "value": 9806.302849061816, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.1356577557165, 298.7609912070344, 299.27276717576297, 320.74661017795944, 335.91266270769603, 12728.363581820906, 13774.862251377483, 13807.585772426819, 14060.859391406082, 14407.423703051878, 9897.604095836175, 10381.0, 10679.733006674836, 12036.939815300933, 12209.633710988663, 3445.1559367954887, 3495.1436897959907, 3677.485152078711, 3706.22169344438, 3769.3403654360513, 3571.499990001402, 3602.883106237075, 3608.693261072799, 3660.5058317127114, 3668.7248456365746, 3488.7209023278206, 3611.043073585502, 3658.213484100918, 3756.323861704892, 3810.9047273818237, 2197.769234230415, 2350.2361732437025, 3668.468072129553, 3711.740178187528, 3837.443570682249, 2194.462356722597, 2202.438378213545, 2784.429192015632, 3190.8512935343256, 3361.96638033619, 16984.726145539134, 17393.304267829397, 17840.46478605644, 17894.463166104906, 18951.0, 1289.38109707341, 1298.8116723075102, 1322.4842311498428, 1355.1733442600007, 1374.4570894496717, 10112.634794302749, 10617.362958222455, 10644.57421703131, 11289.0968722502, 11335.319880807096, 1277.7444511097817, 1289.9548515801903, 1307.3789949773877, 1315.2634524666219, 1339.9196048237136, 112636.0, 115492.0, 117164.0, 118962.0, 120166.0, 8828.77928051791, 8883.978342490567, 9027.729168124966, 9081.0, 9570.856437153448, 9193.172614464731, 11598.420078996016, 11675.649730508097, 11900.404979750978, 11929.46317415701, 5776.277965254343, 5800.970995144998, 5918.644881307136, 6065.848353791191, 6222.968885155546, 4597.3793537872625, 4842.467328593852, 5275.947240527622, 5507.614466987353, 5587.44125587438, 4344.218040752695, 4545.863534116471, 4969.378827646544, 5036.6726162799305, 5074.492550744896, 1540.11997564555, 1584.8098228212457, 1615.0390517641983, 1626.3088187520257, 1634.87738419619, 546.368943869831, 567.5544697412512, 568.7867049856304, 575.4763165519357, 586.8180863932195, 11240.718982025515, 11268.605598804003, 11754.807576072628, 11944.701382465335, 13502.907049407357, 402.22370824309365, 465.12556393979776, 586.718375179916, 592.4697395830793, 604.247711599062, 5277.709725965032, 5834.474897259267, 6137.202163718688, 6287.811365659038, 6458.418742313214, 335713.0, 346743.0, 355299.0, 358848.0, 361740.0, 90734.0, 97051.5737106578, 97837.0, 99619.0, 100268.0, 591.520868096843, 651.9119918810994, 713.5219403000002, 724.8294005181593, 737.6864832446189, 10460.58157673715, 12216.633500994983, 13307.135036223002, 13615.727685446038, 13807.447702092206, 42786.786066070716, 44793.88015299643, 45784.7710761444, 45909.77045114887, 47157.528424716686, 54563.65546114745, 57549.712251438476, 57666.42333576778, 58754.706226468596, 58872.11691824712, 6505.7072431741635, 6515.0, 6568.047633093271, 6713.295104014008, 6780.677767835422, 10149.79700405973, 10372.377657340583, 10450.790984180427, 10466.790664186827, 10722.463876806285, 6639.601623902388, 8210.522106020973, 8446.535440550828, 8670.653173872974, 8676.522791246289, 7512.849743005218, 7770.45606807518, 7784.455088143775, 7998.960005199937, 8039.758807235792, 6083.817485475266, 7052.717891284291, 7291.8541629168185, 7569.5079819811535, 8709.782255443411, 6640.103586016019, 6712.664366781739, 6825.317468253135, 6909.0, 6991.230964593992, 8269.966254218223, 8458.492490450593, 8531.786705332417, 8567.657293708182, 8684.522351270742], "value": 6706.4258176848525, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.4990981628503, 821.3593397150225, 826.768504818651, 827.6647957577184, 866.2247288676639, 7408.296211859878, 7924.366050715943, 8277.089520152778, 8537.14628537146, 8573.442726222791, 6653.0, 6732.192136943574, 6826.590404575729, 7267.38227250683, 7794.37644988401, 1499.452699764589, 1519.2707500399815, 1575.0234854390294, 1651.7357222844348, 1653.5700717813336, 1571.3793051744553, 1577.1483398964604, 1632.8285530019355, 1649.1836540912204, 1666.9081751452818, 1540.6533529955727, 1645.654412573361, 1661.1279078483772, 1662.3350659736143, 1686.2496189195813, 1288.4330894406526, 1343.039726595489, 1350.2776014832116, 1586.9682606347808, 1591.8697724615538, 1649.7855278813677, 1671.8578920791747, 1673.2219517924102, 1678.7565802958502, 1699.7365408361725, 7898.842023159562, 8090.474119182234, 8113.5131892086665, 8149.266566009029, 8329.791755206108, 4331.848385306499, 4471.485779135409, 4530.79611417487, 4531.501534831166, 4581.770911454414, 2819.28108332376, 2834.3480999370054, 2933.296008957856, 2937.8824847006094, 3002.03934740883, 791.5765065689886, 826.4710585225461, 831.1107115386538, 838.4969018588858, 860.6342304520616, 11943.163978521507, 12017.63947081589, 12086.637400877988, 12101.0, 12234.510619575207, 3992.54085780139, 4068.3287257602165, 4068.9389659155136, 4189.790510474464, 4242.5121111072, 9306.488143152057, 9578.90421095794, 9627.374220675634, 10142.290039697298, 10422.166226701846, 4510.962478629934, 4566.931496027562, 4601.194790911577, 4616.792244349012, 4721.173794585934, 4080.836766529365, 4126.381042843597, 4127.174565086994, 4139.130782535702, 4194.748315101104, 2924.532074868011, 3110.73558747509, 3209.646938836726, 3263.7225835803747, 3268.542404063433, 998.5057396588735, 1018.511114664965, 1036.7926414716935, 1052.0584077250971, 1056.7252514346326, 460.5026571302985, 494.6438564233746, 495.68523987268344, 501.1730644436636, 502.89187824617153, 3595.856165753393, 4323.805428755713, 4452.332150177434, 4674.275487299474, 4695.483496815347, 502.27922930593985, 531.4393315052579, 583.7635757518212, 588.5762251179144, 595.2707932782394, 2478.256523043116, 2523.9242822715346, 2722.264988453146, 2978.0321395546534, 3096.2723759916403, 10130.594776209016, 10525.052745252964, 10666.89333106659, 10772.892271077346, 11371.658850234506, 7766.922330776733, 10037.548310326052, 10767.946160269148, 11033.393163376091, 11308.49111789955, 5576.163575463713, 5627.409122042209, 5681.829545113571, 5869.148973398918, 5899.46904778572, 3737.850485980639, 4246.977148668732, 4531.0, 4668.019715859572, 4702.717836929795, 6708.362705542965, 8853.468791872509, 9569.043095690375, 9630.47032413224, 10093.444860532742, 10113.949430252802, 10169.288149829441, 10238.53926573291, 10240.795184096127, 10561.841572376574, 5930.754541546324, 6286.842828929313, 6317.241930968313, 6385.489160867212, 6423.0686550450855, 6825.761098361533, 7080.362767350757, 7155.427565794828, 7211.819704507429, 7450.776476705708, 5198.298229739088, 5344.385395679391, 5860.853478662897, 5891.646501209942, 6010.639361638316, 5409.756560954688, 5755.597108202385, 5789.363170051207, 5844.970775146097, 6003.879922401615, 5161.974190129025, 5282.445343238905, 5284.682919024871, 5328.706921119376, 5361.115415956324, 3272.7545434093013, 3980.343243364812, 4015.0966032642423, 4096.8566100187545, 4139.79301034941, 3712.3874560698264, 3891.5524714658177, 3937.8818635440985, 3994.141259629194, 4220.620144187038], "value": 3614.200594636619, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [245.93851537115722, 272.40615458300897, 274.8529536697866, 280.35378452666595, 288.3483327679444, 3030.6060212172442, 3262.9069261797267, 3845.8077096145184, 3962.930008897596, 4024.1951609678035, 2687.9651334236314, 3214.2607200343937, 3434.79391236526, 3716.3682174030414, 3884.6309600587897, 2319.826013049028, 2697.8920843166297, 2738.2332946774836, 2788.0, 2821.4216085702483, 2304.827137964651, 2513.3339664988794, 2536.3405514566252, 2676.8393896366183, 2805.607214989893, 2211.5024119573127, 2468.5682304263573, 2787.818791778528, 2810.775137988956, 2902.8258304501796, 2593.482812554658, 2767.7093905139873, 2778.19432364614, 2815.634417307604, 2849.5725641153786, 2354.2584086012853, 2433.4889673168655, 2462.3351695042415, 2469.9506009879883, 2494.775470207672, 2847.515922293215, 3136.341368312657, 3621.0, 3835.3671644178676, 3869.245497128033, 2997.7152170543764, 3069.785115041948, 3353.279045005311, 3362.9159271018175, 3414.402479566066, 3490.965090349089, 3554.773603106924, 3971.384435412516, 3997.42166302736, 4122.0, 2593.4683389905026, 2614.516314481827, 2708.7426694463993, 2719.3473566344132, 2836.6454193225845, 3521.841517131735, 4015.5984401559904, 4192.392103145027, 4323.832565207376, 4346.826126954918, 2719.428919926798, 2730.6859711133075, 2956.7782416318864, 3103.8292893891053, 3157.8894738684035, 2714.1450443110357, 2725.591161325777, 3079.6603477487365, 3178.4254753649616, 3187.119599436344, 2971.4502816978497, 3233.9812958917864, 3288.4245257079915, 3619.1675914539805, 3701.11173318407, 3848.826802793881, 4043.807076912318, 4057.5130984282073, 4136.462259906193, 4170.332746760504, 1041.0, 1117.463617463622, 1140.3841925360211, 1147.9196456248148, 1159.750653609478, 770.6532060572765, 807.6688557691318, 813.8535063688474, 820.1142765813026, 826.396730386821, 3715.981420092882, 3794.3359912015285, 3803.5625903021505, 3904.4143378492886, 3920.7059470539825, 3689.3543629864666, 3797.3354662933875, 3937.940930886039, 4031.576684448091, 4162.41726158338, 3764.5670747864347, 3769.8115094245727, 3784.4397501011217, 3788.4696142540065, 3807.5240594925635, 1849.657813304543, 1997.2809788476136, 2104.705341252226, 2123.443232585413, 2191.1125993972146, 3371.190914180579, 3463.740219483549, 3733.9253214935547, 4263.019505513687, 4320.2655548556195, 3680.32545191937, 3810.314143454205, 4271.722338048017, 4295.72077814941, 4308.913821723548, 4053.918921621551, 4431.202383570923, 4510.1656193604285, 4586.958803332485, 4619.838305659286, 3964.4449777030663, 4131.583023931034, 4412.426384569985, 4469.754163521037, 4781.95218047829, 3709.3508635988055, 4446.955530444783, 4693.6949098308505, 5038.521340472649, 5050.570701490415, 5496.790706044664, 5586.86032849182, 5632.6338787980285, 6181.474574661204, 6972.163340398982, 4152.937705934474, 4580.587747102777, 4767.69010014348, 5001.599872010158, 5027.949720502748, 5313.442088580643, 6079.878402431838, 6230.909590821498, 6349.174607301206, 6374.362563743589, 4234.696936389621, 5569.66582005081, 5599.80400685974, 5935.109733540004, 5961.135635332939, 5460.754266058117, 5711.029125048669, 5762.366139724711, 5858.037557418313, 5929.377415371324, 5154.819581314636, 5524.254225679482, 5530.032244357302, 5621.437856214346, 5634.1548767683535, 5271.841844744664, 5301.257823904733, 5497.422770609029, 5737.196792449144, 5760.6669298934, 4835.58897493717, 4881.3403442828185, 5227.4249832517635, 5334.413214546319, 5618.578606604439], "value": 3254.783054041879, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [528.8968651113032, 581.2327727399833, 617.1082785375133, 630.5932673425638, 649.6746636860804, 12554.9328307094, 12561.995040396769, 12728.045396595246, 12766.68083297917, 13011.349432528372, 12733.872661273384, 13233.272170030645, 13461.125026873246, 13464.798028029563, 13681.863181368184, 714.5069901767789, 722.68201991124, 724.5326764237064, 727.8899677991079, 754.5925200391782, 704.2323866984985, 708.6138054760142, 721.7690339091492, 728.5628622826315, 734.7465124532027, 663.1346093348179, 726.498715886037, 735.5696917303384, 738.9298016688433, 765.2003656179306, 599.865030368168, 684.3567046975852, 703.3318347569829, 706.7349743846083, 727.5016613619661, 516.1689679615815, 518.9014087323434, 523.0819911056072, 529.0450736420759, 532.2096686420663, 12253.816192757116, 12296.262224266573, 12376.81434778479, 12605.30670813105, 12650.81023784644, 11569.24799888013, 11585.76828463426, 11931.284122952651, 11985.0, 12314.876851231553, 8876.822463550758, 9920.958299378533, 10016.148627366683, 10175.745606359826, 10203.92858749828, 2376.346504711201, 2449.4856080223176, 2452.901883924641, 2461.5323088613286, 2515.295717199187, 95028.52485737596, 98174.99999999929, 98656.50671746595, 99203.0, 116422.0, 9405.858912116324, 9417.293702972305, 9436.764080898032, 9458.006909274565, 9544.809103817885, 13696.452141914411, 16952.0, 17714.645707085787, 17855.107244637977, 18014.189361478766, 4649.349091127245, 4766.880827979259, 4834.323194752737, 4910.698664853817, 5336.1195402758885, 2702.8243164194264, 2734.8786997331395, 2819.8590070496393, 2829.5472724364004, 2915.97084029157, 3058.3118798270657, 3134.73354764843, 3142.434361814895, 3160.9525857111703, 3214.8714051437682, 675.0717763075771, 682.8634273145391, 684.9178098628197, 696.2967402923084, 704.9953815812447, 348.27732455155655, 425.68073944541595, 444.68205233258374, 444.81456917314995, 464.7652935267736, 6531.967340163269, 6740.0, 7529.8117547060665, 8296.174841534852, 8300.501969881827, 412.40201707523613, 428.47511798047765, 441.70847240820905, 447.11694403552724, 452.3305507848403, 2708.6072519484565, 3529.5588051493564, 3580.6598373154507, 3699.9815000924823, 3870.07118291608, 72023.0, 85800.0, 89559.0, 96144.0, 100759.49620251852, 68484.3151568488, 69006.90398275951, 71990.64004679942, 73332.0, 73652.0, 53806.0, 54410.63973400697, 56337.718311409, 58079.2576222714, 60928.39071609227, 6267.965785645318, 6436.13112229843, 6544.509161812788, 6663.533552651268, 6705.2959439258175, 23258.60448373103, 24527.160462965705, 26391.60412593774, 26454.338641533344, 27055.86472067627, 31826.681733182373, 33108.66891331056, 39184.21631567409, 39926.0, 48204.758976204896, 8871.778205544655, 9450.858237126586, 9589.952050239983, 9631.277654176105, 9859.654912078044, 10262.58949642006, 10281.228907832092, 10556.472176391304, 10606.681799546026, 10726.249162558546, 7712.421568382506, 9634.662786802428, 9674.903250967682, 9712.0, 9851.704448866545, 9507.66723164686, 9517.476538790155, 9622.278329125484, 9925.205983521446, 10113.544890480092, 7812.726554570563, 9010.0, 9183.0, 9580.233581313618, 9657.661981830603, 5711.600187986801, 6291.905621415591, 6361.268454127649, 6737.326267373223, 6773.966130169318, 5709.429057094423, 6582.473402127723, 6999.09011828459, 7003.859922801617, 7345.889811652936], "value": 5613.307179542489, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 2777.1000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [231.0, 228.0, 179.0, 215.0, 219.0, 209.0, 225.0, 200.0, 240.0, 207.0], "value": 215.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [236.0, 184.0, 212.0, 243.0, 180.0, 217.0, 188.0, 340.0, 304.0, 215.0], "value": 231.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [315.0, 395.0, 342.0, 315.0, 340.0, 278.0, 393.0, 398.0, 403.0, 318.0], "value": 349.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 130.0, 117.0, 208.0, 106.0, 105.0, 101.0, 105.0, 153.0, 150.0], "value": 130.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 130.0, 185.0, 121.0, 124.0, 154.0, 196.0, 182.0, 131.0, 202.0], "value": 155.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [225.0, 248.0, 220.0, 242.0, 224.0, 270.0, 201.0, 235.0, 194.0, 165.0], "value": 222.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 188.0, 171.0, 179.0, 188.0, 215.0, 182.0, 182.0, 197.0, 176.0], "value": 187.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 222.0, 142.0, 153.0, 158.0, 204.0, 178.0, 184.0, 161.0, 168.0], "value": 176.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 98.0, 90.0, 90.0, 112.0, 96.0, 95.0, 100.0, 90.0, 97.0], "value": 94.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 92.0, 103.0, 98.0, 103.0, 107.0, 109.0, 92.0, 104.0, 111.0], "value": 101.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 183.0, 196.0, 137.0, 186.0, 166.0, 139.0, 166.0, 141.0, 174.0], "value": 163.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [242.0, 257.0, 235.0, 281.0, 274.0, 237.0, 236.0, 263.0, 267.0, 355.0], "value": 264.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [343.0, 335.0, 361.0, 412.0, 321.0, 319.0, 390.0, 322.0, 314.0, 353.0], "value": 347.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 127.0, 134.0, 147.0, 103.0, 174.0, 150.0, 156.0, 121.0, 149.0], "value": 136.4, "unit": "score"}]}]} 19:56:39 INFO - Return code: 0 19:56:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:56:39 INFO - # TBPL SUCCESS # 19:56:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:56:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:56:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:56:39 INFO - 2017-06-12 19:56:39.292 screenresolution[1822:11376] starting screenresolution argv=screenresolution get 19:56:39 INFO - 2017-06-12 19:56:39.296 screenresolution[1822:11376] Display 0: 1600x1200x32@60 19:56:39 INFO - 2017-06-12 19:56:39.306 screenresolution[1823:11379] starting screenresolution argv=screenresolution list 19:56:39 INFO - Available Modes on Display 0 19:56:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:56:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:56:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:56:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:56:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:56:39 INFO - Intel Iris: 19:56:39 INFO - Chipset Model: Intel Iris 19:56:39 INFO - Type: GPU 19:56:39 INFO - Bus: Built-In 19:56:39 INFO - VRAM (Dynamic, Max): 1536 MB 19:56:39 INFO - Vendor: Intel (0x8086) 19:56:39 INFO - Device ID: 0x0a2e 19:56:39 INFO - Revision ID: 0x0009 19:56:39 INFO - Displays: 19:56:39 INFO - Display: 19:56:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:56:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:56:39 INFO - Display Serial Number: 1600x1200 60 19:56:39 INFO - Main Display: Yes 19:56:39 INFO - Mirror: Off 19:56:39 INFO - Online: Yes 19:56:39 INFO - Rotation: Supported 19:56:39 INFO - Return code: 0 19:56:39 INFO - Running post-action listener: _resource_record_post_action 19:56:39 INFO - [mozharness: 2017-06-13 02:56:39.672505Z] Finished run-tests step (success) 19:56:39 INFO - Running post-run listener: _resource_record_post_run 19:56:39 INFO - Total resource usage - Wall time: 1278s; CPU: 54.0%; Read bytes: 53487104; Write bytes: 484454400; Read time: 459; Write time: 1786 19:56:39 INFO - TinderboxPrint: CPU usage
54.0% 19:56:39 INFO - TinderboxPrint: I/O read bytes / time
53,487,104 / 459 19:56:39 INFO - TinderboxPrint: I/O write bytes / time
484,454,400 / 1,786 19:56:39 INFO - TinderboxPrint: CPU idle
2,353.0 (46.0%) 19:56:39 INFO - TinderboxPrint: CPU system
1,058.2 (20.7%) 19:56:39 INFO - TinderboxPrint: CPU user
1,699.1 (33.2%) 19:56:39 INFO - TinderboxPrint: Swap in / out
189,370,368 / 0 19:56:39 INFO - install - Wall time: 18s; CPU: 54.0%; Read bytes: 137413120; Write bytes: 137549824; Read time: 12747; Write time: 302 19:56:39 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:56:39 INFO - run-tests - Wall time: 1260s; CPU: 54.0%; Read bytes: 52454912; Write bytes: 328460288; Read time: 446; Write time: 1435 19:56:40 INFO - Running post-run listener: _upload_blobber_files 19:56:40 INFO - Blob upload gear active. 19:56:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:56:40 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 19:56:40 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:56:40 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:56:41 INFO - (blobuploader) - INFO - Open directory for files ... 19:56:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:56:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:56:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:56:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:56:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:56:42 INFO - (blobuploader) - INFO - Done attempting. 19:56:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:56:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:56:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:57:01 INFO - (blobuploader) - CRITICAL - Unexpected error in client: Traceback (most recent call last): 19:57:01 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 145, in upload_file 19:57:01 INFO - check_status(response) 19:57:01 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 181, in check_status 19:57:01 INFO - ret = requests.head(blob_url) 19:57:01 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/api.py", line 77, in head 19:57:01 INFO - return request('head', url, **kwargs) 19:57:01 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/api.py", line 44, in request 19:57:01 INFO - return session.request(method=method, url=url, **kwargs) 19:57:01 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/sessions.py", line 335, in request 19:57:01 INFO - resp = self.send(prep, **send_kwargs) 19:57:01 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/sessions.py", line 438, in send 19:57:01 INFO - r = adapter.send(request, **kwargs) 19:57:01 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/adapters.py", line 327, in send 19:57:01 INFO - raise ConnectionError(e) 19:57:01 INFO - ConnectionError: HTTPConnectionPool(host='mozilla-releng-blobs.s3.amazonaws.com', port=80): Max retries exceeded with url: /blobs/Mozilla-Beta/sha512/90ecd595ef1a06eabb61f4321f2b9e1d18ae85e5ee2657572dc7f001877fcbdcac37c222594913562bacaa13ee074c3dd8420372bed7eb966f5788c8c65282f5 (Caused by : [Errno 54] Connection reset by peer) 19:57:01 INFO - (blobuploader) - INFO - Done attempting. 19:57:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:57:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:57:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:57:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:57:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:57:02 INFO - (blobuploader) - INFO - Done attempting. 19:57:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:57:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:57:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:57:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:57:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:57:04 INFO - (blobuploader) - INFO - Done attempting. 19:57:04 INFO - (blobuploader) - INFO - Iteration through files over. 19:57:04 INFO - Return code: 0 19:57:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:57:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:57:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/14f3508eb82eea56e55c22a6daa8d1f6ebf6775ecf3ced82c1f9cdaf0cc4c406264d8d819c841e221d42dbfd523be80565b2469c663915b19afd6ded1ba1ffbe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7a5c3c865057eb27788cbe707207747436049ec7a65bb8e7a93a6bfe72cc033250b3612baa30b0dc9f8a3d68780e32eeebed6e92e8b68f95a993e4823e09b6c1", "dromaeojs-e10s_raw.log": null, "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/854cb4ed1513aed73559f6b3dd08ff478f4332039eb99ddff075cda471f09e49010106a741ee6974c0b469b297ad86aa5fcb238cfac8ea0a0f847582efca2b5c"} 19:57:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:57:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:57:04 INFO - Contents: 19:57:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/14f3508eb82eea56e55c22a6daa8d1f6ebf6775ecf3ced82c1f9cdaf0cc4c406264d8d819c841e221d42dbfd523be80565b2469c663915b19afd6ded1ba1ffbe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7a5c3c865057eb27788cbe707207747436049ec7a65bb8e7a93a6bfe72cc033250b3612baa30b0dc9f8a3d68780e32eeebed6e92e8b68f95a993e4823e09b6c1", "dromaeojs-e10s_raw.log": null, "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/854cb4ed1513aed73559f6b3dd08ff478f4332039eb99ddff075cda471f09e49010106a741ee6974c0b469b297ad86aa5fcb238cfac8ea0a0f847582efca2b5c"} 19:57:04 INFO - Running post-run listener: copy_logs_to_upload_dir 19:57:04 INFO - Copying logs to upload dir... 19:57:04 INFO - mkdir: /builds/slave/test/build/upload/logs 19:57:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1409.945415 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 23 mins, 29 secs) (at 2017-06-12 19:57:05.010759) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 19:57:05.014720) ========= 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.ffPYaiHkrD/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.LQyfUThZNo/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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/Mozilla-Beta/sha512/14f3508eb82eea56e55c22a6daa8d1f6ebf6775ecf3ced82c1f9cdaf0cc4c406264d8d819c841e221d42dbfd523be80565b2469c663915b19afd6ded1ba1ffbe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7a5c3c865057eb27788cbe707207747436049ec7a65bb8e7a93a6bfe72cc033250b3612baa30b0dc9f8a3d68780e32eeebed6e92e8b68f95a993e4823e09b6c1", "dromaeojs-e10s_raw.log": null, "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/854cb4ed1513aed73559f6b3dd08ff478f4332039eb99ddff075cda471f09e49010106a741ee6974c0b469b297ad86aa5fcb238cfac8ea0a0f847582efca2b5c"} build_url:https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.014797 build_url: 'https://queue.taskcluster.net/v1/task/dn1W25meT52Sg1Mlb0NHcA/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/14f3508eb82eea56e55c22a6daa8d1f6ebf6775ecf3ced82c1f9cdaf0cc4c406264d8d819c841e221d42dbfd523be80565b2469c663915b19afd6ded1ba1ffbe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7a5c3c865057eb27788cbe707207747436049ec7a65bb8e7a93a6bfe72cc033250b3612baa30b0dc9f8a3d68780e32eeebed6e92e8b68f95a993e4823e09b6c1", "dromaeojs-e10s_raw.log": null, "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/854cb4ed1513aed73559f6b3dd08ff478f4332039eb99ddff075cda471f09e49010106a741ee6974c0b469b297ad86aa5fcb238cfac8ea0a0f847582efca2b5c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 19:57:05.059937) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 19:57:05.060269) ========= 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.ffPYaiHkrD/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.LQyfUThZNo/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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.008455 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 19:57:05.113222) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 19:57:05.113642) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 19:57:05.113972) ========= ========= Total master_lag: 0.60 =========