builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0035 starttime: 1494560604.77 results: success (0) revision: ad45e59c7683a1354c8b73566f653d515f8d9a42 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 20:43:24.765305) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 20:43:24.765765) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 20:43:24.766054) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 20:43:24.790025) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 20:43:24.790371) ========= 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.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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-05-11 20:43:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2017-05-11 20:43:24 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084910 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 20:43:24.897581) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 20:43:24.897893) ========= 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.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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.019480 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 20:43:24.946692) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 20:43:24.947041) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ad45e59c7683a1354c8b73566f653d515f8d9a42 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev ad45e59c7683a1354c8b73566f653d515f8d9a42 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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-05-11 20:43:25,019 truncating revision to first 12 chars 2017-05-11 20:43:25,019 Setting DEBUG logging. 2017-05-11 20:43:25,019 attempt 1/10 2017-05-11 20:43:25,019 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ad45e59c7683?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 20:43:25,843 unpacking tar archive at: mozilla-central-ad45e59c7683/testing/mozharness/ program finished with exit code 0 elapsedTime=1.079487 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 20:43:26.047742) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 20:43:26.048076) ========= script_repo_revision: ad45e59c7683a1354c8b73566f653d515f8d9a42 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 20:43:26.048439) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 20:43:26.048718) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 20:43:26.067447) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-11 20:43:26.067721) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 20:43:26 INFO - MultiFileLogger online at 20170511 20:43:26 in /builds/slave/test 20:43:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:43:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:43:26 INFO - {'append_to_log': False, 20:43:26 INFO - 'base_work_dir': '/builds/slave/test', 20:43:26 INFO - 'blob_upload_branch': 'Firefox', 20:43:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:43:26 INFO - 'branch': 'Firefox', 20:43:26 INFO - 'buildbot_json_path': 'buildprops.json', 20:43:26 INFO - 'config_files': ('talos/mac_config.py',), 20:43:26 INFO - 'default_actions': ('clobber', 20:43:26 INFO - 'read-buildbot-config', 20:43:26 INFO - 'download-and-extract', 20:43:26 INFO - 'populate-webroot', 20:43:26 INFO - 'create-virtualenv', 20:43:26 INFO - 'install', 20:43:26 INFO - 'run-tests'), 20:43:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:43:26 INFO - 'download_minidump_stackwalk': True, 20:43:26 INFO - 'download_symbols': 'ondemand', 20:43:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:43:26 INFO - 'tooltool.py': '/tools/tooltool.py', 20:43:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:43:26 INFO - '/tools/misc-python/virtualenv.py')}, 20:43:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:43:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:43:26 INFO - 'gecko_profile': False, 20:43:26 INFO - 'gecko_profile_interval': 0, 20:43:26 INFO - 'installer_path': 'installer.exe', 20:43:26 INFO - 'log_level': 'info', 20:43:26 INFO - 'log_name': 'talos', 20:43:26 INFO - 'log_to_console': True, 20:43:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:43:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:43:26 INFO - 'opt_config_files': (), 20:43:26 INFO - 'pip_index': False, 20:43:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:43:26 INFO - 'cmd': ('bash', 20:43:26 INFO - '-c', 20:43:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:43:26 INFO - 'enabled': True, 20:43:26 INFO - 'halt_on_failure': False, 20:43:26 INFO - 'name': 'check_screen_resolution'},), 20:43:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:43:26 INFO - 'cmd': ('bash', 20:43:26 INFO - '-c', 20:43:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:43:26 INFO - 'enabled': True, 20:43:26 INFO - 'halt_on_failure': False, 20:43:26 INFO - 'name': 'check_screen_resolution'},), 20:43:26 INFO - 'run_cmd_checks_enabled': True, 20:43:26 INFO - 'suite': 'dromaeojs-e10s', 20:43:26 INFO - 'system_bits': '32', 20:43:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:43:26 INFO - 'title': 't-yosemite-r7-0035', 20:43:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:43:26 INFO - 'use_talos_json': True, 20:43:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:43:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:43:26 INFO - 'work_dir': 'build'} 20:43:26 INFO - [mozharness: 2017-05-12 03:43:26.279232Z] Running clobber step. 20:43:26 INFO - Running pre-action listener: _resource_record_pre_action 20:43:26 INFO - Running main action method: clobber 20:43:26 INFO - rmtree: /builds/slave/test/build 20:43:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:43:29 INFO - Running post-action listener: _resource_record_post_action 20:43:29 INFO - [mozharness: 2017-05-12 03:43:29.642462Z] Finished clobber step (success) 20:43:29 INFO - [mozharness: 2017-05-12 03:43:29.642702Z] Running read-buildbot-config step. 20:43:29 INFO - Running pre-action listener: _resource_record_pre_action 20:43:29 INFO - Running main action method: read_buildbot_config 20:43:29 INFO - Using buildbot properties: 20:43:29 INFO - { 20:43:29 INFO - "project": "", 20:43:29 INFO - "product": "firefox", 20:43:29 INFO - "who": "ihsiao@mozilla.com", 20:43:29 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 20:43:29 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 20:43:29 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 20:43:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 20:43:29 INFO - "stage_platform": "macosx64", 20:43:29 INFO - "basedir": "/builds/slave/test", 20:43:29 INFO - "buildnumber": 152, 20:43:29 INFO - "platform": "yosemite_r7", 20:43:29 INFO - "parent_task_id": "df1PX6s8QAuZDjIV5osl3w", 20:43:29 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 20:43:29 INFO - "slavebuilddir": "test", 20:43:29 INFO - "taskId": "EgjKRfzERtqh_4EyWHGKHw", 20:43:29 INFO - "branch": "mozilla-central", 20:43:29 INFO - "script_repo_revision": "production", 20:43:29 INFO - "revision": "ad45e59c7683a1354c8b73566f653d515f8d9a42", 20:43:29 INFO - "slavename": "t-yosemite-r7-0035", 20:43:29 INFO - "repo_path": "mozilla-central" 20:43:29 INFO - } 20:43:29 INFO - Finding installer, test and symbols from parent task. 20:43:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EgjKRfzERtqh_4EyWHGKHw'}, attempt #1 20:43:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EgjKRfzERtqh_4EyWHGKHw'}, attempt #1 20:43:30 INFO - Task dependencies: FjCH5Ff4Sd2DkkPOsojvtg 20:43:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w'}, attempt #1 20:43:31 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:43:31 INFO - Set test_url: https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/test_packages.json 20:43:31 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/target.crashreporter-symbols.zip 20:43:31 INFO - Running post-action listener: _resource_record_post_action 20:43:31 INFO - [mozharness: 2017-05-12 03:43:31.370935Z] Finished read-buildbot-config step (success) 20:43:31 INFO - [mozharness: 2017-05-12 03:43:31.371053Z] Running download-and-extract step. 20:43:31 INFO - Running pre-action listener: _resource_record_pre_action 20:43:31 INFO - Running main action method: download_and_extract 20:43:31 INFO - mkdir: /builds/slave/test/build/tests 20:43:31 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')]} 20:43:31 INFO - trying https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 20:43:31 INFO - Downloading https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 20:43:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 20:43:32 INFO - Downloaded 1325 bytes. 20:43:32 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 20:43:32 INFO - Using the following test package requirements: 20:43:32 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:43:32 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 20:43:32 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 20:43:32 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:43:32 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 20:43:32 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:43:32 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 20:43:32 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:43:32 INFO - u'jsshell-mac.zip'], 20:43:32 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:43:32 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 20:43:32 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 20:43:32 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:43:32 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 20:43:32 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:43:32 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 20:43:32 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:43:32 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 20:43:32 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:43:32 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 20:43:32 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:43:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 20:43:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:43:32 INFO - Fetch https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 20:43:33 INFO - Content-Length response header: 52710659 20:43:33 INFO - Bytes received: 52710659 20:43:50 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:43:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 20:43:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:43:50 INFO - Fetch https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 20:43:52 INFO - Content-Length response header: 52710659 20:43:52 INFO - Bytes received: 52710659 20:44:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 20:44:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:44:08 INFO - Fetch https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 20:44:09 INFO - Content-Length response header: 14409411 20:44:09 INFO - Bytes received: 14409411 20:44: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')]} 20:44:09 INFO - trying https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:44:09 INFO - Downloading https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 20:44:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 20:44:11 INFO - Downloaded 75799252 bytes. 20:44:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:44:11 INFO - mkdir: /builds/slave/test/properties 20:44:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:44:11 INFO - Writing to file /builds/slave/test/properties/build_url 20:44:11 INFO - Contents: 20:44:11 INFO - build_url:https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:44:11 INFO - Running post-action listener: _resource_record_post_action 20:44:11 INFO - Running post-action listener: set_extra_try_arguments 20:44:11 INFO - [mozharness: 2017-05-12 03:44:11.507346Z] Finished download-and-extract step (success) 20:44:11 INFO - [mozharness: 2017-05-12 03:44:11.507518Z] Running populate-webroot step. 20:44:11 INFO - Running pre-action listener: _resource_record_pre_action 20:44:11 INFO - Running main action method: populate_webroot 20:44:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:44:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:44:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:44:11 INFO - '--tppagecycles', 20:44:11 INFO - '7'], 20:44:11 INFO - 'tests': ['tsvgm']}}, 20:44:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:44:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:44:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:44:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:44:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:44:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:44:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:44:11 INFO - 'talos_options': ['--disable-e10s'], 20:44:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:44:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:44:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:44:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:44:11 INFO - 'talos_options': ['--disable-e10s'], 20:44:11 INFO - 'tests': ['damp', 'tps']}, 20:44:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:44:11 INFO - 'tests': ['damp', 'tps']}, 20:44:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:44:11 INFO - 'tests': ['dromaeo_dom']}, 20:44:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:44:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:44:11 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 20:44:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 20:44:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:44:11 INFO - 'tests': ['a11yr', 20:44:11 INFO - 'ts_paint', 20:44:11 INFO - 'tpaint', 20:44:11 INFO - 'sessionrestore', 20:44:11 INFO - 'sessionrestore_no_auto_restore', 20:44:11 INFO - 'tabpaint']}, 20:44:11 INFO - 'other-e10s': {'tests': ['a11yr', 20:44:11 INFO - 'ts_paint', 20:44:11 INFO - 'tpaint', 20:44:11 INFO - 'sessionrestore', 20:44:11 INFO - 'sessionrestore_no_auto_restore', 20:44:11 INFO - 'tabpaint']}, 20:44:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:44:11 INFO - 'ts_paint', 20:44:11 INFO - 'tpaint', 20:44:11 INFO - 'sessionrestore', 20:44:11 INFO - 'sessionrestore_no_auto_restore', 20:44:11 INFO - 'tabpaint']}, 20:44:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:44:11 INFO - 'ts_paint', 20:44:11 INFO - 'tpaint', 20:44:11 INFO - 'sessionrestore', 20:44:11 INFO - 'sessionrestore_no_auto_restore', 20:44:11 INFO - 'tabpaint']}, 20:44:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:44:11 INFO - 'tests': ['a11yr', 20:44:11 INFO - 'ts_paint', 20:44:11 INFO - 'tpaint', 20:44:11 INFO - 'sessionrestore', 20:44:11 INFO - 'sessionrestore_no_auto_restore', 20:44:11 INFO - 'tabpaint']}, 20:44:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:44:11 INFO - 'tests': ['a11yr', 20:44:11 INFO - 'ts_paint', 20:44:11 INFO - 'tpaint', 20:44:11 INFO - 'sessionrestore', 20:44:11 INFO - 'sessionrestore_no_auto_restore', 20:44:11 INFO - 'tabpaint']}, 20:44:11 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 20:44:11 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 20:44:11 INFO - 'bloom_basic_ref']}, 20:44:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:44:11 INFO - 'tests': ['tsvgx', 20:44:11 INFO - 'tsvgr_opacity', 20:44:11 INFO - 'tart', 20:44:11 INFO - 'tscrollx', 20:44:11 INFO - 'cart', 20:44:11 INFO - 'tsvg_static']}, 20:44:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:44:11 INFO - 'tsvgr_opacity', 20:44:11 INFO - 'tart', 20:44:11 INFO - 'tscrollx', 20:44:11 INFO - 'cart', 20:44:11 INFO - 'tsvg_static']}, 20:44:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:44:11 INFO - 'talos_options': ['--disable-e10s'], 20:44:11 INFO - 'tests': ['tp5o']}, 20:44:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:44:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:44:11 INFO - 'talos_options': ['--disable-e10s', 20:44:11 INFO - '--xperf_path', 20:44:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:44:11 INFO - 'tests': ['tp5n']}, 20:44:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:44:11 INFO - 'talos_options': ['--xperf_path', 20:44:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:44:11 INFO - 'tests': ['tp5n']}}, 20:44:11 INFO - 'talos.zip': {'path': '', 20:44:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:44:11 INFO - Running post-action listener: _resource_record_post_action 20:44:11 INFO - [mozharness: 2017-05-12 03:44:11.528538Z] Finished populate-webroot step (success) 20:44:11 INFO - [mozharness: 2017-05-12 03:44:11.528687Z] Running create-virtualenv step. 20:44:11 INFO - Running pre-action listener: _resource_record_pre_action 20:44:11 INFO - Running main action method: create_virtualenv 20:44:11 INFO - Creating virtualenv /builds/slave/test/build/venv 20:44:11 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 20:44:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:44:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:44:11 INFO - Using real prefix '/tools/python27' 20:44:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:44:12 INFO - Installing distribute.............................................................................................................................................................................................done. 20:44:15 INFO - Installing pip.................done. 20:44:15 INFO - Return code: 0 20:44:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:44:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:44:15 INFO - Reading from file tmpfile_stdout 20:44:15 INFO - Output received: 20:44:15 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) 20:44:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:44:15 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')]} 20:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:44:15 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')]} 20:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:44:15 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 0x10b0a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5aa4c8430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adbad30>, '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 0x10ad6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adaebe8>, '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.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 20:44:15 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 20:44:15 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 20:44:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k8mjtLSSzq/Render', 20:44:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:44:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:44:15 INFO - 'HOME': '/Users/cltbld', 20:44:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:44:15 INFO - 'LOGNAME': 'cltbld', 20:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:44:15 INFO - 'MOZ_NO_REMOTE': '1', 20:44:15 INFO - 'NO_EM_RESTART': '1', 20:44:15 INFO - 'PAGER': '/bin/cat', 20:44:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:44:15 INFO - 'PWD': '/builds/slave/test', 20:44:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:44:15 INFO - 'SHELL': '/bin/bash', 20:44:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8mXKAz5Fhy/Listeners', 20:44:15 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 20:44:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:44:15 INFO - 'USER': 'cltbld', 20:44:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:44:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:44:15 INFO - 'XPC_FLAGS': '0x0', 20:44:15 INFO - 'XPC_SERVICE_NAME': '0', 20:44:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:44:15 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 20:44:15 INFO - Cleaning up... 20:44:15 INFO - Return code: 0 20:44:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:44:15 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')]} 20:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:44:15 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')]} 20:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:44:15 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 0x10b0a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5aa4c8430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adbad30>, '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 0x10ad6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adaebe8>, '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.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 20:44:15 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 20:44:15 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 20:44:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k8mjtLSSzq/Render', 20:44:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:44:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:44:15 INFO - 'HOME': '/Users/cltbld', 20:44:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:44:15 INFO - 'LOGNAME': 'cltbld', 20:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:44:15 INFO - 'MOZ_NO_REMOTE': '1', 20:44:15 INFO - 'NO_EM_RESTART': '1', 20:44:15 INFO - 'PAGER': '/bin/cat', 20:44:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:44:15 INFO - 'PWD': '/builds/slave/test', 20:44:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:44:15 INFO - 'SHELL': '/bin/bash', 20:44:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8mXKAz5Fhy/Listeners', 20:44:15 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 20:44:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:44:15 INFO - 'USER': 'cltbld', 20:44:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:44:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:44:15 INFO - 'XPC_FLAGS': '0x0', 20:44:15 INFO - 'XPC_SERVICE_NAME': '0', 20:44:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:44:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:44:16 INFO - Downloading/unpacking psutil>=3.1.1 20:44: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 20:44: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 20:44: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 20:44: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 20:44:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:44:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:44:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:44:17 INFO - Installing collected packages: psutil 20:44:17 INFO - Running setup.py install for psutil 20:44:18 INFO - building 'psutil._psutil_osx' extension 20:44:18 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 20:44:18 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 20:44:18 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 20:44:18 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 20:44:18 INFO - building 'psutil._psutil_posix' extension 20:44:18 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 20:44:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:44:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:44:18 INFO - ^ 20:44:18 INFO - 1 warning generated. 20:44:18 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 20:44:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:44:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:44:18 INFO - Successfully installed psutil 20:44:18 INFO - Cleaning up... 20:44:18 INFO - Return code: 0 20:44:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:44: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')]} 20:44:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:44: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')]} 20:44:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:44:18 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 0x10b0a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5aa4c8430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adbad30>, '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 0x10ad6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adaebe8>, '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.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 20:44:18 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 20:44:18 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 20:44:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k8mjtLSSzq/Render', 20:44:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:44:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:44:18 INFO - 'HOME': '/Users/cltbld', 20:44:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:44:18 INFO - 'LOGNAME': 'cltbld', 20:44:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:44:18 INFO - 'MOZ_NO_REMOTE': '1', 20:44:18 INFO - 'NO_EM_RESTART': '1', 20:44:18 INFO - 'PAGER': '/bin/cat', 20:44:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:44:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:44:18 INFO - 'PWD': '/builds/slave/test', 20:44:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:44:18 INFO - 'SHELL': '/bin/bash', 20:44:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8mXKAz5Fhy/Listeners', 20:44:18 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 20:44:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:44:18 INFO - 'USER': 'cltbld', 20:44:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:44:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:44:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:44:18 INFO - 'XPC_FLAGS': '0x0', 20:44:18 INFO - 'XPC_SERVICE_NAME': '0', 20:44:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:44:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:44:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:44: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 20:44: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 20:44: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 20:44: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 20:44:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:44:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:44:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:44:20 INFO - Installing collected packages: mozsystemmonitor 20:44:20 INFO - Running setup.py install for mozsystemmonitor 20:44:20 INFO - Successfully installed mozsystemmonitor 20:44:20 INFO - Cleaning up... 20:44:20 INFO - Return code: 0 20:44:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:44:20 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')]} 20:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:44:20 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')]} 20:44:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:44: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5aa4c8430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adbad30>, '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 0x10ad6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adaebe8>, '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.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 20:44: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==2.5.1'] in /builds/slave/test/build 20:44: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==2.5.1 20:44:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k8mjtLSSzq/Render', 20:44:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:44:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:44:21 INFO - 'HOME': '/Users/cltbld', 20:44:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:44:21 INFO - 'LOGNAME': 'cltbld', 20:44:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:44:21 INFO - 'MOZ_NO_REMOTE': '1', 20:44:21 INFO - 'NO_EM_RESTART': '1', 20:44:21 INFO - 'PAGER': '/bin/cat', 20:44:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:44:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:44:21 INFO - 'PWD': '/builds/slave/test', 20:44:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:44:21 INFO - 'SHELL': '/bin/bash', 20:44:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8mXKAz5Fhy/Listeners', 20:44:21 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 20:44:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:44:21 INFO - 'USER': 'cltbld', 20:44:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:44:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:44:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:44:21 INFO - 'XPC_FLAGS': '0x0', 20:44:21 INFO - 'XPC_SERVICE_NAME': '0', 20:44:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:44:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:44:21 INFO - Downloading/unpacking jsonschema==2.5.1 20:44:21 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 20:44:21 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 20:44:21 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 20:44:21 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 20:44:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:44:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:44:23 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 20:44:23 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 20:44:23 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 20:44:23 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 20:44:23 INFO - Downloading functools32-3.2.3-2.tar.gz 20:44:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:44:23 INFO - warning: no files found matching '*.txt' 20:44:23 INFO - no previously-included directories found matching 'build' 20:44:23 INFO - no previously-included directories found matching 'dist' 20:44:23 INFO - no previously-included directories found matching '.git*' 20:44:23 INFO - Installing collected packages: jsonschema, functools32 20:44:23 INFO - Running setup.py install for functools32 20:44:23 INFO - warning: no files found matching '*.txt' 20:44:23 INFO - no previously-included directories found matching 'build' 20:44:23 INFO - no previously-included directories found matching 'dist' 20:44:23 INFO - no previously-included directories found matching '.git*' 20:44:23 INFO - Successfully installed jsonschema functools32 20:44:23 INFO - Cleaning up... 20:44:23 INFO - Return code: 0 20:44:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:44:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:44:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:44:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:44:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b0a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5aa4c8430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adbad30>, '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 0x10ad6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adaebe8>, '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.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 20:44:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:44:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:44:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k8mjtLSSzq/Render', 20:44:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:44:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:44:24 INFO - 'HOME': '/Users/cltbld', 20:44:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:44:24 INFO - 'LOGNAME': 'cltbld', 20:44:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:44:24 INFO - 'MOZ_NO_REMOTE': '1', 20:44:24 INFO - 'NO_EM_RESTART': '1', 20:44:24 INFO - 'PAGER': '/bin/cat', 20:44:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:44:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:44:24 INFO - 'PWD': '/builds/slave/test', 20:44:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:44:24 INFO - 'SHELL': '/bin/bash', 20:44:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8mXKAz5Fhy/Listeners', 20:44:24 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 20:44:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:44:24 INFO - 'USER': 'cltbld', 20:44:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:44:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:44:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:44:24 INFO - 'XPC_FLAGS': '0x0', 20:44:24 INFO - 'XPC_SERVICE_NAME': '0', 20:44:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:44:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:44:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:44:24 INFO - Cleaning up... 20:44:24 INFO - Return code: 0 20:44:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:44:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:44:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b0a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5aa4c8430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adbad30>, '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 0x10ad6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adaebe8>, '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.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 20:44:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:44:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:44:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k8mjtLSSzq/Render', 20:44:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:44:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:44:24 INFO - 'HOME': '/Users/cltbld', 20:44:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:44:24 INFO - 'LOGNAME': 'cltbld', 20:44:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:44:24 INFO - 'MOZ_NO_REMOTE': '1', 20:44:24 INFO - 'NO_EM_RESTART': '1', 20:44:24 INFO - 'PAGER': '/bin/cat', 20:44:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:44:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:44:24 INFO - 'PWD': '/builds/slave/test', 20:44:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:44:24 INFO - 'SHELL': '/bin/bash', 20:44:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8mXKAz5Fhy/Listeners', 20:44:24 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 20:44:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:44:24 INFO - 'USER': 'cltbld', 20:44:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:44:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:44:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:44:24 INFO - 'XPC_FLAGS': '0x0', 20:44:24 INFO - 'XPC_SERVICE_NAME': '0', 20:44:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:44:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:44:24 INFO - Downloading/unpacking blobuploader==1.2.4 20:44:24 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 20:44:24 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 20:44:24 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 20:44:24 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 20:44:26 INFO - Downloading blobuploader-1.2.4.tar.gz 20:44:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:44:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:44:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:44:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:44:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:44:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:44:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:44:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:44:26 INFO - Downloading docopt-0.6.1.tar.gz 20:44:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:44:26 INFO - Installing collected packages: blobuploader, requests, docopt 20:44:26 INFO - Running setup.py install for blobuploader 20:44:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:44:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:44:27 INFO - Running setup.py install for requests 20:44:27 INFO - Running setup.py install for docopt 20:44:27 INFO - Successfully installed blobuploader requests docopt 20:44:27 INFO - Cleaning up... 20:44:27 INFO - Return code: 0 20:44:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:44:27 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')]} 20:44:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:44:27 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')]} 20:44:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:44:27 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 0x10b0a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5aa4c8430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adbad30>, '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 0x10ad6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adaebe8>, '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.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 20:44:27 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 20:44:27 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 20:44:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k8mjtLSSzq/Render', 20:44:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:44:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:44:27 INFO - 'HOME': '/Users/cltbld', 20:44:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:44:27 INFO - 'LOGNAME': 'cltbld', 20:44:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:44:27 INFO - 'MOZ_NO_REMOTE': '1', 20:44:27 INFO - 'NO_EM_RESTART': '1', 20:44:27 INFO - 'PAGER': '/bin/cat', 20:44:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:44:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:44:27 INFO - 'PWD': '/builds/slave/test', 20:44:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:44:27 INFO - 'SHELL': '/bin/bash', 20:44:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8mXKAz5Fhy/Listeners', 20:44:27 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 20:44:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:44:27 INFO - 'USER': 'cltbld', 20:44:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:44:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:44:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:44:27 INFO - 'XPC_FLAGS': '0x0', 20:44:27 INFO - 'XPC_SERVICE_NAME': '0', 20:44:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:44:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:44:28 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-bTFJlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:44:28 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-1xaq7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:44:28 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-7G4efi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:44:28 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-5A9Xgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:44:28 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-kS59w7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:44:28 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-y2HXr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:44:28 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-snO0mH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:44:28 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-8Y3N0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:44:28 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-PzUbKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:44:29 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-IktsTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:44:29 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-NwCYG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:44:29 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-NcNpoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:44:29 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-VCQxJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:44:29 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-gq0A2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:44:29 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-LbKJB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:44:29 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-z87a9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:44:29 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-VTYYfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:44:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:44:29 INFO - Running setup.py install for manifestparser 20:44:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:44:30 INFO - Running setup.py install for mozcrash 20:44:30 INFO - Running setup.py install for mozdebug 20:44:30 INFO - Running setup.py install for mozdevice 20:44:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:44:30 INFO - Running setup.py install for mozfile 20:44:30 INFO - Running setup.py install for mozhttpd 20:44:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:44:30 INFO - Running setup.py install for mozinfo 20:44:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:44:30 INFO - Running setup.py install for mozInstall 20:44:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:44:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:44:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:44:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:44:30 INFO - Running setup.py install for mozleak 20:44:31 INFO - Running setup.py install for mozlog 20:44:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:44:31 INFO - Running setup.py install for moznetwork 20:44:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:44:31 INFO - Running setup.py install for mozprocess 20:44:31 INFO - Running setup.py install for mozprofile 20:44:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:44:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:44:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:44:31 INFO - Running setup.py install for mozrunner 20:44:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:44:31 INFO - Running setup.py install for mozscreenshot 20:44:32 INFO - Running setup.py install for moztest 20:44:32 INFO - Running setup.py install for mozversion 20:44:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:44:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:44:32 INFO - Cleaning up... 20:44:32 INFO - Return code: 0 20:44:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:44:32 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')]} 20:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:44:32 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')]} 20:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:44:32 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 0x10b0a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5aa4c8430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adbad30>, '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 0x10ad6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adaebe8>, '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.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 20:44:32 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 20:44:32 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 20:44:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k8mjtLSSzq/Render', 20:44:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:44:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:44:32 INFO - 'HOME': '/Users/cltbld', 20:44:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:44:32 INFO - 'LOGNAME': 'cltbld', 20:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:44:32 INFO - 'MOZ_NO_REMOTE': '1', 20:44:32 INFO - 'NO_EM_RESTART': '1', 20:44:32 INFO - 'PAGER': '/bin/cat', 20:44:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:44:32 INFO - 'PWD': '/builds/slave/test', 20:44:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:44:32 INFO - 'SHELL': '/bin/bash', 20:44:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8mXKAz5Fhy/Listeners', 20:44:32 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 20:44:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:44:32 INFO - 'USER': 'cltbld', 20:44:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:44:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:44:32 INFO - 'XPC_FLAGS': '0x0', 20:44:32 INFO - 'XPC_SERVICE_NAME': '0', 20:44:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:44:32 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-1GoWZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:44:32 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)) 20:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:44:32 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-XVjqvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:44:32 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)) 20:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:44:32 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Tz37pj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:44:33 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)) 20:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:44:33 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-LATYOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:44:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 20:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:44:33 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-JXEP4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:44:33 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)) 20:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:44:33 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-5v8UB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:44:33 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)) 20:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:44:33 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-hsWFrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:44:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:44:33 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-sTRKSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:44:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:44:33 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-EiB57N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:44:33 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)) 20:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:44:33 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-3m3uE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:44:33 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)) 20:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:44:33 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-3Z_J2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:44:33 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)) 20:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:44:33 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-w5Lm4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:44:33 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)) 20:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:44:33 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-a_K0Cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:44:34 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)) 20:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:44:34 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-P8ZYyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:44:34 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)) 20:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:44:34 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-QVqefu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:44:34 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)) 20:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:44:34 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-TSXS30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:44:34 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)) 20:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:44:34 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-ZXzVh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:44:34 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)) 20:44:34 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)) 20:44:34 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)) 20:44:34 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)) 20:44:34 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:44:34 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:44:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:44:34 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 20:44:34 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 20:44:34 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 20:44:34 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 20:44:35 INFO - Downloading blessings-1.6.tar.gz 20:44:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:44:36 INFO - Installing collected packages: blessings 20:44:36 INFO - Running setup.py install for blessings 20:44:36 INFO - Successfully installed blessings 20:44:36 INFO - Cleaning up... 20:44:36 INFO - Return code: 0 20:44:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:44:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:44:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:44:36 INFO - Reading from file tmpfile_stdout 20:44:36 INFO - Current package versions: 20:44:36 INFO - blessings == 1.6 20:44:36 INFO - blobuploader == 1.2.4 20:44:36 INFO - docopt == 0.6.1 20:44:36 INFO - functools32 == 3.2.3-2 20:44:36 INFO - jsonschema == 2.5.1 20:44:36 INFO - manifestparser == 1.1 20:44:36 INFO - mozInstall == 1.12 20:44:36 INFO - mozcrash == 1.0 20:44:36 INFO - mozdebug == 0.1 20:44:36 INFO - mozdevice == 0.50 20:44:36 INFO - mozfile == 1.2 20:44:36 INFO - mozhttpd == 0.7 20:44:36 INFO - mozinfo == 0.9 20:44:36 INFO - mozleak == 0.1 20:44:36 INFO - mozlog == 3.4 20:44:36 INFO - moznetwork == 0.27 20:44:36 INFO - mozprocess == 0.25 20:44:36 INFO - mozprofile == 0.28 20:44:36 INFO - mozrunner == 6.13 20:44:36 INFO - mozscreenshot == 0.1 20:44:36 INFO - mozsystemmonitor == 0.3 20:44:36 INFO - moztest == 0.8 20:44:36 INFO - mozversion == 1.4 20:44:36 INFO - psutil == 3.1.1 20:44:36 INFO - requests == 1.2.3 20:44:36 INFO - wsgiref == 0.1.2 20:44:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:44:36 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')]} 20:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:44:36 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')]} 20:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:44:36 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 0x10b0a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5aa4c8430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adbad30>, '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 0x10ad6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adaebe8>, '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.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 20:44:36 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 20:44:36 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 20:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k8mjtLSSzq/Render', 20:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:44:36 INFO - 'HOME': '/Users/cltbld', 20:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:44:36 INFO - 'LOGNAME': 'cltbld', 20:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:44:36 INFO - 'MOZ_NO_REMOTE': '1', 20:44:36 INFO - 'NO_EM_RESTART': '1', 20:44:36 INFO - 'PAGER': '/bin/cat', 20:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:44:36 INFO - 'PWD': '/builds/slave/test', 20:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:44:36 INFO - 'SHELL': '/bin/bash', 20:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8mXKAz5Fhy/Listeners', 20:44:36 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 20:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:44:36 INFO - 'USER': 'cltbld', 20:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:44:36 INFO - 'XPC_FLAGS': '0x0', 20:44:36 INFO - 'XPC_SERVICE_NAME': '0', 20:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:44:36 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)) 20:44:36 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)) 20:44:36 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)) 20:44:36 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)) 20:44:36 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)) 20:44:36 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)) 20:44:36 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)) 20:44:36 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)) 20:44:36 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)) 20:44:36 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)) 20:44:36 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)) 20:44:36 INFO - Cleaning up... 20:44:36 INFO - Return code: 0 20:44:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:44:36 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')]} 20:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:44:36 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')]} 20:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:44:36 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 0x10b0a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5aa4c8430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adbad30>, '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 0x10ad6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adaebe8>, '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.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 20:44:36 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 20:44:36 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 20:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k8mjtLSSzq/Render', 20:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:44:36 INFO - 'HOME': '/Users/cltbld', 20:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:44:36 INFO - 'LOGNAME': 'cltbld', 20:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:44:36 INFO - 'MOZ_NO_REMOTE': '1', 20:44:36 INFO - 'NO_EM_RESTART': '1', 20:44:36 INFO - 'PAGER': '/bin/cat', 20:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:44:36 INFO - 'PWD': '/builds/slave/test', 20:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:44:36 INFO - 'SHELL': '/bin/bash', 20:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8mXKAz5Fhy/Listeners', 20:44:36 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 20:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:44:36 INFO - 'USER': 'cltbld', 20:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:44:36 INFO - 'XPC_FLAGS': '0x0', 20:44:36 INFO - 'XPC_SERVICE_NAME': '0', 20:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:44:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:44:37 INFO - Cleaning up... 20:44:37 INFO - Return code: 0 20:44:37 INFO - Running post-action listener: _resource_record_post_action 20:44:37 INFO - Running post-action listener: _start_resource_monitoring 20:44:37 INFO - Starting resource monitoring. 20:44:37 INFO - [mozharness: 2017-05-12 03:44:37.099949Z] Finished create-virtualenv step (success) 20:44:37 INFO - [mozharness: 2017-05-12 03:44:37.101000Z] Running install step. 20:44:37 INFO - Running pre-action listener: _resource_record_pre_action 20:44:37 INFO - Running main action method: install 20:44:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:44:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:44:37 INFO - Reading from file tmpfile_stdout 20:44:37 INFO - Detecting whether we're running mozinstall >=1.0... 20:44:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:44:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:44:37 INFO - Reading from file tmpfile_stdout 20:44:37 INFO - Output received: 20:44:37 INFO - Usage: mozinstall [options] installer 20:44:37 INFO - Options: 20:44:37 INFO - -h, --help show this help message and exit 20:44:37 INFO - -d DEST, --destination=DEST 20:44:37 INFO - Directory to install application into. [default: 20:44:37 INFO - "/builds/slave/test"] 20:44:37 INFO - --app=APP Application being installed. [default: firefox] 20:44:37 INFO - mkdir: /builds/slave/test/build/application 20:44:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:44:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:44:57 INFO - Reading from file tmpfile_stdout 20:44:57 INFO - Output received: 20:44:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:44:57 INFO - Running post-action listener: _resource_record_post_action 20:44:57 INFO - [mozharness: 2017-05-12 03:44:57.799376Z] Finished install step (success) 20:44:57 INFO - [mozharness: 2017-05-12 03:44:57.799498Z] Running run-tests step. 20:44:57 INFO - Running pre-action listener: _resource_record_pre_action 20:44:57 INFO - Running main action method: run_tests 20:44:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:44:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:44:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:44:57 INFO - 2017-05-11 20:44:57.814 screenresolution[2784:76000] starting screenresolution argv=screenresolution get 20:44:57 INFO - 2017-05-11 20:44:57.817 screenresolution[2784:76000] Display 0: 1600x1200x32@60 20:44:57 INFO - 2017-05-11 20:44:57.823 screenresolution[2785:76002] starting screenresolution argv=screenresolution list 20:44:57 INFO - Available Modes on Display 0 20:44:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:44:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:44:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:44:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:44:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:44:58 INFO - Intel Iris: 20:44:58 INFO - Chipset Model: Intel Iris 20:44:58 INFO - Type: GPU 20:44:58 INFO - Bus: Built-In 20:44:58 INFO - VRAM (Dynamic, Max): 1536 MB 20:44:58 INFO - Vendor: Intel (0x8086) 20:44:58 INFO - Device ID: 0x0a2e 20:44:58 INFO - Revision ID: 0x0009 20:44:58 INFO - Displays: 20:44:58 INFO - Display: 20:44:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:44:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:44:58 INFO - Display Serial Number: 1600x1200 60 20:44:58 INFO - Main Display: Yes 20:44:58 INFO - Mirror: Off 20:44:58 INFO - Online: Yes 20:44:58 INFO - Rotation: Supported 20:44:58 INFO - Return code: 0 20:44:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:44:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:44:58 INFO - Python 2.7.3 20:44:58 INFO - Return code: 0 20:44:58 INFO - grabbing minidump binary from tooltool 20:44:58 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')]} 20:44:58 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 0x10adbad30>, '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 0x10ad6ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adaebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:44:58 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 20:44:58 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 20:44:58 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 20:44:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:44:58 INFO - Return code: 0 20:44:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:44:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:44:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:44:58 INFO - ENV: RUST_BACKTRACE is now 1 20:44:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:44:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:44:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0035', '--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 20:44:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0035 --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 20:44:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k8mjtLSSzq/Render', 20:44:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:44:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:44:58 INFO - 'HOME': '/Users/cltbld', 20:44:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:44:58 INFO - 'LOGNAME': 'cltbld', 20:44:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:44:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:44:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:44:58 INFO - 'MOZ_NO_REMOTE': '1', 20:44:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:44:58 INFO - 'NO_EM_RESTART': '1', 20:44:58 INFO - 'PAGER': '/bin/cat', 20:44:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:44:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:44:58 INFO - 'PWD': '/builds/slave/test', 20:44:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:44:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:44:58 INFO - 'RUST_BACKTRACE': '1', 20:44:58 INFO - 'SHELL': '/bin/bash', 20:44:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8mXKAz5Fhy/Listeners', 20:44:58 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 20:44:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:44:58 INFO - 'USER': 'cltbld', 20:44:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:44:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:44:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:44:58 INFO - 'XPC_FLAGS': '0x0', 20:44:58 INFO - 'XPC_SERVICE_NAME': '0', 20:44:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:44:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0035', '--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 20:44:58 INFO - mozversion application_buildid: 20170511191626 20:44:58 INFO - mozversion application_changeset: ad45e59c7683a1354c8b73566f653d515f8d9a42 20:44:58 INFO - mozversion application_display_name: Nightly 20:44:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:44:58 INFO - mozversion application_name: Firefox 20:44:58 INFO - mozversion application_remotingname: firefox 20:44:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:44:58 INFO - mozversion application_vendor: Mozilla 20:44:58 INFO - mozversion application_version: 55.0a1 20:44:58 INFO - mozversion platform_buildid: 20170511191626 20:44:58 INFO - mozversion platform_changeset: ad45e59c7683a1354c8b73566f653d515f8d9a42 20:44:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:44:58 INFO - mozversion platform_version: 55.0a1 20:44:58 INFO - using testdate: 1494560698 20:44:58 INFO - actual date: 1494560698 20:44:58 INFO - starting webserver on 'localhost:57945' 20:44:58 INFO - SUITE-START | Running 2 tests 20:44:58 INFO - TEST-START | dromaeo_css 20:44:58 INFO - operating with platform_type : mac_ 20:44:58 INFO - Initialising browser for dromaeo_css test... 20:44:58 INFO - TEST-INFO | started process 2795 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpqelnJP/profile http://localhost:57945/getInfo.html) 20:45:00 INFO - PID 2795 | 2017-05-11 20:45:00.961 plugin-container[2797:76176] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 20:45:00 INFO - PID 2795 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:45:00 INFO - PID 2795 | 2017-05-11 20:45:00.964 plugin-container[2797:76176] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c1f, name = 'com.apple.CFPasteboardClient' 20:45:00 INFO - PID 2795 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:45:00 INFO - PID 2795 | 2017-05-11 20:45:00.964 plugin-container[2797:76176] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:45:01 INFO - PID 2795 | __metrics Screen width/height:1600/1200 20:45:01 INFO - PID 2795 | colorDepth:24 20:45:01 INFO - PID 2795 | Browser inner width/height: 1024/691 20:45:01 INFO - PID 2795 | __metrics 20:45:01 INFO - PID 2795 | 2017-05-11 20:45:01.477 plugin-container[2800:76241] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 20:45:01 INFO - PID 2795 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:45:01 INFO - PID 2795 | 2017-05-11 20:45:01.478 plugin-container[2800:76241] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9517, name = 'com.apple.CFPasteboardClient' 20:45:01 INFO - PID 2795 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:45:01 INFO - PID 2795 | 2017-05-11 20:45:01.478 plugin-container[2800:76241] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:45:02 INFO - TEST-INFO | 2795: exit 0 20:45:02 INFO - Browser initialized. 20:45:02 INFO - Running cycle 1/1 for dromaeo_css test... 20:45:02 INFO - TEST-INFO | started process 2801 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpqelnJP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:45:04 INFO - PID 2801 | 2017-05-11 20:45:04.465 plugin-container[2802:76343] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 20:45:04 INFO - PID 2801 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:45:04 INFO - PID 2801 | 2017-05-11 20:45:04.466 plugin-container[2802:76343] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460b, name = 'com.apple.CFPasteboardClient' 20:45:04 INFO - PID 2801 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:45:04 INFO - PID 2801 | 2017-05-11 20:45:04.466 plugin-container[2802:76343] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:45:04 INFO - PID 2801 | 2017-05-11 20:45:04.808 plugin-container[2803:76386] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9143, name = 'com.apple.tsm.portname' 20:45:04 INFO - PID 2801 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:45:04 INFO - PID 2801 | 2017-05-11 20:45:04.808 plugin-container[2803:76386] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4527, name = 'com.apple.CFPasteboardClient' 20:45:04 INFO - PID 2801 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:45:04 INFO - PID 2801 | 2017-05-11 20:45:04.808 plugin-container[2803:76386] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:45:14 INFO - PID 2801 | RSS: Main: 229240832 20:45:14 INFO - PID 2801 | 20:48:18 INFO - PID 2801 | Cycle 1(1): loaded http://localhost:57945/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57945/tests/dromaeo/cssquery-ext.html) 20:48:19 INFO - PID 2801 | RSS: Main: 252297216 20:48:19 INFO - PID 2801 | 20:51:21 INFO - PID 2801 | Cycle 1(1): loaded http://localhost:57945/tests/dromaeo/cssquery-ext.html (next: http://localhost:57945/tests/dromaeo/cssquery-jquery.html) 20:51:22 INFO - PID 2801 | RSS: Main: 258260992 20:51:22 INFO - PID 2801 | 20:54:24 INFO - PID 2801 | Cycle 1(1): loaded http://localhost:57945/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57945/tests/dromaeo/cssquery-mootools.html) 20:54:24 INFO - PID 2801 | RSS: Main: 268161024 20:54:24 INFO - PID 2801 | 20:57:26 INFO - PID 2801 | Cycle 1(1): loaded http://localhost:57945/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57945/tests/dromaeo/cssquery-prototype.html) 20:57:27 INFO - PID 2801 | RSS: Main: 261664768 20:57:27 INFO - PID 2801 | 21:00:30 INFO - PID 2801 | Cycle 1(1): loaded http://localhost:57945/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57945/tests/dromaeo/cssquery-yui.html) 21:00:30 INFO - PID 2801 | RSS: Main: 268697600 21:00:30 INFO - PID 2801 | 21:03:32 INFO - PID 2801 | Cycle 1(1): loaded http://localhost:57945/tests/dromaeo/cssquery-yui.html (next: http://localhost:57945/tests/dromaeo/cssquery-dojo.html) 21:03:32 INFO - PID 2801 | RSS: Main: 267522048 21:03:32 INFO - PID 2801 | 21:03:32 INFO - PID 2801 | __start_tp_report 21:03:32 INFO - PID 2801 | _x_x_mozilla_page_load 21:03:32 INFO - PID 2801 | _x_x_mozilla_page_load_details 21:03:32 INFO - PID 2801 | |i|pagename|runs| 21:03:32 INFO - PID 2801 | |0;dojo.html;3027.6518200406954;3596.7122630189588;3597.9745772454858;3603.387424137897;3608.2963822054694;4838;6152.446279834814;6179.258488981323;6184.7216875240665;6185.876282474359;6099.8410302042585;6156.692165391723;6156.969215153918;6238.1266622672865;6314.936850631492;5442.455754424565;5464.726763661831;5465.9726701366435;5484.149956756709;5490.835274941758;5522.5581953443825;5583.6928968906695;5596.3284405871145;5602.299712535933;5607.355154157283;5373.194020896858;5461.344638643349;5494.2582751328455;5536;5599.076152434844;5503.724813759336;5523.889522209573;5545.168224766277;5555.583331250173;5601.6358936668985;5480.342358916916;5487.42382049883;5501.807436739735;5502.257195278667;5521.282233309644;5305.283786688786;5722.084466485384;6269.090981807658;6274.87450250997;6276.905846412307;6405.231372235315;6505.837354066139;6546.247181574085;6611.140551728245;6612.86774264512;6756.358145976124;6764.966175169093;6772.288909664513;6777.423918966893;6778.4577233821665;6218.160548326008;6288.056791481223;6338.904916426257;6342.809715708536;6343.397377249154;6588.242352129567;6703.430208432241;6809.761658341934;6947.826304342432;6984.266652001568;5350.545203657733;6307.810765676945;6349.555531112777;6482.57863238888;6579.309172536815;6589.901151482732;6637.23671777742;6640.0703901453835;6652.135222421054;6670.433013193933;6488.967555162194;6565.638889861103;6578.2106147261675;6579.046038324512;6583.07836902834;5532.3914369419335;6356.332585078592;6388.7125079371535;6565.24499682533;6565.868682626417;4572.222722137245;5287.41838397776;5325.920111198335;5340.25236466895;5347.973260133675;3523.3481805865486;4474.26174681174;4516.322551617283;4645.488996210414;4713.528647135327;5323.707196104174;6243.313235544087;6277.623342599459;6356.951103067942;6391.680415979183;6501.934980650228;6674.132362792903;6726.7981960541265;6742.224644165984;6769.356911093439;5329.706866122323;6310.810675679737;6314.62112273265;6325.399087086719;6342.460890824332;5903.822885313448;5942.64344139353;5950.494207992283;5955.553333500005;5967.16459695643;5633.718314084279;6667.866642667216;6796.388325050769;6813.386795188457;6915.827104322432;6664.866972614765;6739.191297044386;6750.493712971449;6757;6783.457323414016;5917.023691091094;6659.53383263167;6879.208890977401;6895.862082758417;6908.1019467468905;6369.585976911486;6443.130177426175;6577.736890524326;6622.403983641495;6637.236717777324;6682.198136223684;6744;6765.627890466071;6775;6796.626185559841;5647.491725744702;6312.715927783169;6490;6498.870022599616;6507.804765857032;6263.342349053284;6373.490120790233;6446.452051575481;6477.611343319416;6526.706298216497;5722.141678748221;5903.822885313448;6353.633968696752;6371.74513019474;6376.74493020274;6282.308946016026;6333.556651034568;6367.363263673781;6454.741810327534;6504.447121994684;6253.405926436982;6339.809805705836;6348.111264423077;6348.333424990309;6372.745090196526 21:03:32 INFO - PID 2801 | |1;ext.html;748.2666986353373;802.3460879383302;830.9999999999998;1042.2860340666643;1280.9551665691702;32652;38727;39343.80328098359;39425.40861887074;39430.60569394312;39575.81272561828;39615.40576891342;39892.20215595686;39996.80001599995;40027;7776.533407995525;7942.285194332509;8262.793430164264;8456.78858028548;8474.915250847505;8388.958055209716;8496.150384961516;8496.192861678162;8499.107593702665;8508.149185081504;8409.579521023956;8469.153084691543;8528.688770309987;8528.78678033048;8533.189347012023;8321.95839020807;8438.620262088221;8498.320134389234;8508.02157751854;8518.446300990447;8285.668573257062;8287.254147126787;8348.958255208745;8355.791105222357;8361.95819020907;29579.11262662102;30361.696383036327;33202.667973320196;34254.82872585646;37234.25531489355;2943.381889803144;3171.762117841171;3584.5877724061806;3596.496490491307;3630.9273814523563;30564.388712225853;30577.388452231055;30591;30646.233844153852;30654.846725766452;3115.6572776994512;3158.3683263347193;3165.7467402607735;3182.3953448844877;3183.793053451518;237463;243071;282769;298071;299817;47481.52518474771;47645.76177119092;47721.76139119282;47895.521044789806;48032.5196748035;49423.50576494189;49878.25182622191;49882.50117498779;50056;50283.74858125759;14140.434382624786;14197.148171109766;14259.928700356639;14308.427662893368;14332.57002289933;11625.59310424131;11645.54430696163;11646.41767911601;11649.825252621218;11662.008729258108;13223.73552528944;13403;13416.262105584286;13450.798238026437;13547.525836595672;5975.4024597540765;6024.277086749618;6102.511799056065;6104.084387341934;6109.694515274218;945.7821408796361;965.9903400966041;1015.8577799108131;1023.6570748799087;1054.8206804843196;24241.909114089907;24829;27542.5868611971;31638.841805791286;31901.68098318987;1121.2823792772633;1128.311729844794;1151.2516864038275;1157.2304417562227;1161.285809227319;12377.690557736129;12837.037222208372;12880.677983050498;13003.739925201444;13017.414216360286;300362;301584;302125;302980;303177;55297.44702552923;55432;55516.6120846974;55550;55650.16524752132;1257.3905401086624;1543.5523698127643;1595.7367034439187;1596.0822527046994;1600.5598460423478;24587.508249834544;24626.507469850145;24698.259052228463;24845.254642360756;30059.549106763854;40808.18383632251;51772.482275176764;52473.7376313116;52495;52520.737396312776;222892;270747;271896;272361;272870;35187;36276.09309767277;36934.63065369419;36945.44581831221;37038.814805925795;46254.30618540792;46337;46380;47647.52352476431;48896.77758055935;32886.83556582202;33766.15584610404;34501.827490862386;34566.481502777984;34789.65210347864;33479.83260083684;33676.81100728445;33807.66192338045;34893.8255308732;34910.65089349176;41340.586594133674;41584.792076039426;42352.1529569413;42761.358579621956;42782.35826462668;28167.454112763677;34143;34224.28878556112;35059;35252;21864;23849.64225536653;24735.763211838977;25542;26257.606135907594 21:03:32 INFO - PID 2801 | |2;jquery.html;383.81001404304874;431.3895837390093;435.65833644162626;440.36147586000305;445.6056390094766;18789.530261743465;19526.609467810653;21114.89442552787;21198.57602847942;22085.006174722137;21987.670184947237;22488;23078.192263270746;23715.5256894862;23909.641355379685;5995.370486098962;6156;6200.565960382775;6212.596181248205;6222.533310001745;5824.301083869942;6090.817275481742;6163.753449862;6165.568410211287;6174.135621013039;5918.40815918409;6066.272047354324;6121.571489995702;6136.662483563402;6139.508839292868;5968.67172305523;6108.053251745987;6112.847178820521;6117.602355846878;6133.355997620275;5245.632805703603;5387.757550910218;5456.154296084113;5471.616986810925;5475.23346731454;25034.37414064663;27684.861575692194;28156.718432815615;28287;29662.258443538867;2116.2169997101173;2237.463008877874;2249.1453247765744;2253.5718213539376;2255.165588732163;17854.732179017326;17976.280948762032;18088;18590.90704546482;18815.529611759677;2147.8711277323255;2162.2107930605375;2209.856359336638;2215.7230346206725;2216.4015715756655;160699;161244;161273.387266127;163769;164051;10654.73363165927;12905.806412903814;12966.481340746264;13086.607401777961;13264.668383290495;16922.40771572989;16924.238409271613;16928.661426771396;16928.984260944384;16970.066646334322;7862.646180921873;8838.690645827364;9133.543322833964;9148.130927561871;9294.72115836526;8693.826123477622;8712.172343627346;8731.781705457415;8878.689245876363;8886.511241881759;7251.1661158966335;7384.667689953965;8010.719624813103;8165.591720413956;8183.79540511492;2219.4562332228707;2225.254539729209;2225.788550087739;2227.5322182341565;2331.813454923602;937.803061357109;943.118184497497;959.7024922274118;965.9517024148764;981.1464026297167;16348.918255408647;20251.696224556643;21058.684119738216;21116.683249751266;21413.67879481809;755.6599530211427;842.6924172677051;938.9061093890556;948.8292107420731;962.2206013129388;9437.197838183694;9621.134097931079;10918.399488028235;11027.83458248127;11037.33775973428;524040;527078;531042;540670;543048;97922;105501;122384;135096.27163049442;136689;993.4486360070086;1051.9842202367124;1093.6117678224275;1249.3815561297215;1249.743802520481;21230.681539776608;22273;22777.31668049961;22785.544289113794;22898.198563050213;55815.720921395136;56323;56585;56783;57319;60502.613530678456;77927;80125.79811302718;80252.59873700594;80962.59518702369;8792.120787921413;8872.822543549222;8919.955400222958;8924;8937.46375217489;12063.638090856937;13501.324933753076;13649.317534123056;13669.794953075512;13968.930155349159;11470.139739519402;11630.6510804676;11818.46816893259;11974.760504790029;12120.454579544117;9132.771680708036;9423.340366174301;10894.182936279969;11086.334819910831;11169.88830111721;12047;12079.396030198279;12231.266124032587;12297.446614902172;12307.138500304893;7605.733799316998;8741.038485766434;8848.02671706099;8977.10228977097;9015.819683606422;11512.42437878086;11757.647270581896;11782.469788859351;12019.579314723942;12187.451564679788 21:03:32 INFO - PID 2801 | |3;mootools.html;1382.8686274803893;1420.8081908942297;1441.9855801441995;1444.6027342480816;1454.898157129001;9896.901030989688;11429.771404571913;12095.576654817083;12104.515819367225;12354.444050017748;11921.225120367191;11946.880531194685;12004.81992770107;12106.878931210686;12151.210171338857;2544.7328030556755;2614.7515985981395;2614.80388970827;2614.816962812604;2616.6336712860216;2498.3129639349236;2558.5522533556646;2559.436923876744;2559.5520783862844;2559.7696207341337;2548.3374322676054;2568.833025853323;2584.5606246938064;2602.947941041178;2606.7784238339764;2518.7733104020635;2545.4018305698146;2566.653501777255;2567.8459292442512;2576.1756238003845;2144.485323522359;2215.3353993801957;2525.911593094251;2535.32813804342;2540.4284036091917;11586.594469193622;11684.649460516199;11860.110491713156;11866.169368144234;11885.94057029718;6184.721687524027;6739.460843132538;6784.253732089466;6843.555168914005;6854.520183587151;3838.9040273993096;4151.294279972412;4182.330827067685;4240.173166232586;4242.342436922282;1203.5787474383897;1237.6720169155092;1265.9620211393674;1274.6685861675937;1289.729156877057;16364.34542618294;16507.752383714254;16537.007779533265;16652.66694666112;16654.25055872501;5427.972860135674;5471.753771080311;5608.775648973995;5679.318481782213;5696.544276457874;11791.941040294743;11890.108241881893;11892.583759568373;11902.702432439259;11918.52325906954;6451.483881289486;6508.511861610304;6547.116139321226;6570.868582628416;6574.638394888231;6039.818805435844;6159.630422174684;6209.534284928648;6241.500679945684;6268.49852011838;4301.978490107529;4388.71473354231;4394.868153955386;4424.712393694464;4464.799084041226;1182.261086820737;1274.388293619068;1296.6887946892866;1305.5691621764795;1311.4098655605017;645.6352161029004;811.5982588618554;813.9430239883151;817.8486979908736;823.7117009046785;6085.665288409092;6307.3377295383325;6431.646259455775;6507.804765857032;6511.576747511492;454.6226631895519;587.7472686744655;595.3867516458055;612.063542779548;613.6992873491956;3287.6219234787827;3850.403187505941;3884.592117827644;3932.665723413542;3936.940945885814;14108.506202283075;14802.111873287637;15026;15102.697946041018;15141.924290378478;14602.63493412652;14824.25878706082;15099.622509437351;15139.697206056038;15148.924255378653;8516.148385161558;8656.22094011542;8747.03782583928;8821.779455513664;8837.64649414029;7157.737353600797;7287.0162528060155;7335.963320183365;7345.0084238627105;7387.298206670358;13352.332383380986;13416.32918354098;13582.45670173182;13718.725625487634;13742.519011834538;13899.791503127259;13907.096038757449;14090.22503762303;14125.293735312987;14263.928680356532;9327.673531426368;9461.621535138793;9530.761730956781;9535.284853636143;9588.616455341988;10658.520366583367;10920.945395272973;10949.50727217293;10971.341719496855;11010.449477526252;8840;8969.820603588023;9006.909930900869;9018.458892466211;9266.305027123128;7189.640517974185;7370.742024029133;9051.185393314634;9178.173964343241;9253.67612133599;8396.160383961555;8474.110218427222;8484.533350665773;8492.957535212285;8530.317574594143;5449.782008719607;6080.452759251511;6243.781467648792;6262.185915830913;6296.898042842393;6187.195664563578;6249.406306400884;6372.012338087604;6473.287938326875;6617.66911654425 21:03:32 INFO - PID 2801 | |4;prototype.html;381.952255968004;398.93218152914;402.8147052355916;405.7747949887813;405.95128584569846;5932.436418540243;6134.723937422816;6321.747130114795;6327.841803954898;6361.47324642086;6086.352436690043;6182.505399568035;6186.845328866781;6205.627662340263;6212.409821067003;3725.8695945641907;3863.555691095518;3934.7048971327195;4450.435607478975;4555.105384895916;4326.913461730764;4514.932276015862;4515.532451953112;4540.18276710192;4544.5682660147395;3648.7263455240964;4274.850380236692;4480.865574032784;4481.3726078349055;4525.637948964075;3535.4166562517153;4274.439829462255;4496.820127194908;4499.910001799978;4502.662300327472;3026.727594516482;3638.6361363863666;3771.226898485831;3825.8660946866726;3889.669378102865;5224.294720212761;5550.361708403545;5904.143899134601;5975.966222799593;6030.306514750772;5375.22059301403;5387.19192121181;5389.946100538984;5391.4608539145775;5397.974384866902;6281.466075383598;6471.385218404244;6605.40551350376;6616.834579135512;6650.567713098633;4298.957010429887;4361.996740749613;4377.934330985037;4378.8029538670835;4382.408374869384;5276.894462110737;5825.543614096476;5953.374895635982;6391.424771770563;6906.171259448848;3794.29805485986;4394.428724265825;4402.295632698753;4408.338749187584;4430.822767089345;4461.754603496839;4483.775811209426;4484.753338566345;4503.594676479132;4512.036759564453;5233.502817232356;5318.574514038867;5322.866928326745;5334.759935802898;5362.812301569505;5728.427157284238;6591.505637077238;6597.21875093722;6613.86772264552;6634.701438435282;1529.319452843474;1531.877449804013;1531.9004264722757;1533.53993801859;1536.9231538423035;951.5575257505293;996.6362277768707;996.8056229035413;1010.5149528226492;1022.4683164754287;6271.27880293763;6272.529560282997;6295.716692748837;6314.147590075373;6329.68351582419;6739.142354413084;6850.047736395103;6863.622500762406;6874.862502749917;6928.757493487704;5143;6157.2611286645;6233.158523599256;6267.2165979252595;6275.623462592259;3078.5382192671277;3575.3206890690685;3584.390653588896;3586.5696116466193;3616.1140520572626;6305.716242769086;6510.153680021567;6765.627890465973;6956.826079348057;6995.370416662525;6766.357196066366;6891.310868913169;6953.200381956039;6985.617259288873;7086.326798954091;7043.190033146151;7068.328508791554;7091.361777440055;7110.906755561347;7152.713891444388;6643.202815662152;6872.553284036521;6896.586204827727;6902.378785909293;6921.342472465108;6744.898826517508;6795.694193761194;6805.0472933788315;6816.250212476524;6877.552959057645;5662.8301150965535;7128.394086502498;7132.536385134949;7133.8929916050265;7137.607431591105;4388.824447022211;4433.82264709408;4440.111977604557;4447.887253554536;4450.732956022649;5635.718214089361;5772.595918285848;6364.713587888648;6938.410235129869;6955.756548520778;5458.2904222450825;5489.64317319373;6619.867602648016;6631.204255489372;6658.334166583303;6546.44688571467;6619.3380661933425;6630.569013014331;6630.9336906630315;6638.535302528777;5353.330833645794;6392.881245782062;6541.967290163519;6542.611946639538;6576.408123268929;6622.437092847162;6643.409070462427;6667.666616669244;6670.7665231716655;6679.732810687518;5474.3704473986;5680.630759000652;6363.331850155667;6479.514036447191;6488.188976377953 21:03:32 INFO - PID 2801 | |5;yui.html;840.1724301562963;858.5878778186471;859.1365677494117;862.8403745307119;880.2385936165217;16295;16918.323267069303;16933.661326773472;18294.268229270845;18924.1484133214;17791.11044447779;18037;18268.543286417847;18565.071746412694;18741.34405295815;990.2721499697716;998.4558415663445;1013.6249587652542;1017.4099022566893;1021.6271061062733;1011.3173607814732;1014.457265363031;1015.1067060986321;1019.1795604538381;1025.2566889005486;1066.3601838896677;1068.2842495528014;1070.0369667299415;1070.1813112968584;1071.0307172009343;1043.9634612788554;1047.1151876664283;1051.1958351860833;1062.5165549674957;1063.2025980514613;760.1448370583093;773.8413625206819;776.4487214078011;780.9180036096185;782.475741253356;16763.99416035042;17195.312187512485;17450.214740336716;17664.293428262856;17667.293308267654;16879;16971.915140424342;17382.478525644125;17479.21343539531;17560.121993900255;14087.577372678836;14177.149371037667;14315.355808988515;14395;14497.637559061002;3584.2473080652835;3634.309481198564;3647.434647629622;3652;3652.7077833773233;121250;128168;128172.71827281702;130083.43039385737;131260;13011.544595939096;13381;13455.663608409672;13734.45062197521;13803.654908627364;25376.74623253744;25845.612315815277;26202.95188192489;26427;26775.598366024526;6259.436650701458;7964.442489025712;8056.436049476598;8061.4760040597175;8094.311983481352;3972.4041393791163;3997.5802540733384;4020.155767288902;4037.051292946155;4040.8181631826637;4275.144971005811;4363.258246098171;4413.44831896013;4421.358902959118;4461.687681862239;868.3747701654798;935.9891317377352;953.7520244736419;970.5292932927524;970.8592254123254;581.7963712700521;648.6756621689156;651.5504302031583;660.4155322539588;660.6333484915917;10766.623168189075;11738.882611173949;11758.176927615155;11885.40572971348;11897.702557436134;546.5682111132161;655.4068567946016;669.718718138383;679.6873438218474;680.4318394140848;4256.935766058486;4268;4482.731036137842;5096.439391666913;5364.061289274361;80340.5982970093;92841;93315.53342233246;95264;95383.52308238414;88588.5570572143;89292;90061.54969225243;90472.54763726139;92745.5362723182;79018;79157;79785.60107199427;80061.19938800654;81657.77513337304;9128.269738420777;10501.894981050398;10627.946860265649;10982.890171098186;11169.832452513056;36419;36596.08509787277;36932.26135477329;37153.6284637161;37205.44191837071;46212.7689361551;46608.601741947794;46844;46920.59238222859;47303.526964729914;11950.940245298718;13273.800892986807;14364.635359640817;14532.564023079325;14716.926415367854;12666.430010649727;15723.764143538085;15898.841011590199;15952;16098;13705.177689338672;14106.717865642835;14159.292035397983;14222.715545688821;14262.500812471513;14114;14332.283385830875;14361.425542978164;14464.20446875432;14572.271386430848;13395.665108371979;13706.177629342272;13746.518871839438;13860.099093558887;13883.583492495241;9081;10281.74295642615;10945;10977.83533246986;11034.337939723642;9273.53632318395;10592.841107383549;11029.393383363671;11082.722931926766;11156.888431115585 21:03:32 INFO - PID 2801 | __end_tp_report 21:03:32 INFO - PID 2801 | __start_cc_report 21:03:32 INFO - PID 2801 | _x_x_mozilla_cycle_collect,415 21:03:32 INFO - PID 2801 | __end_cc_report 21:03:32 INFO - PID 2801 | __startTimestamp1494561812493__endTimestamp 21:03:32 INFO - PID 2801 | 21:03:32 INFO - PID 2801 | ------- Summary: start ------- 21:03:32 INFO - PID 2801 | Number of tests: 6 21:03:32 INFO - PID 2801 | 21:03:32 INFO - PID 2801 | [#0] dojo.html Cycles:165 Average:6075.55 Median:6338.90 stddev:748.85 (11.8%) stddev-sans-first:711.95 21:03:32 INFO - PID 2801 | Values: 3027.7 3596.7 3598.0 3603.4 3608.3 4838.0 6152.4 6179.3 6184.7 6185.9 6099.8 6156.7 6157.0 6238.1 6314.9 5442.5 5464.7 5466.0 5484.1 5490.8 5522.6 5583.7 5596.3 5602.3 5607.4 5373.2 5461.3 5494.3 5536.0 5599.1 5503.7 5523.9 5545.2 5555.6 5601.6 5480.3 5487.4 5501.8 5502.3 5521.3 5305.3 5722.1 6269.1 6274.9 6276.9 6405.2 6505.8 6546.2 6611.1 6612.9 6756.4 6765.0 6772.3 6777.4 6778.5 6218.2 6288.1 6338.9 6342.8 6343.4 6588.2 6703.4 6809.8 6947.8 6984.3 5350.5 6307.8 6349.6 6482.6 6579.3 6589.9 6637.2 6640.1 6652.1 6670.4 6489.0 6565.6 6578.2 6579.0 6583.1 5532.4 6356.3 6388.7 6565.2 6565.9 4572.2 5287.4 5325.9 5340.3 5348.0 3523.3 4474.3 4516.3 4645.5 4713.5 5323.7 6243.3 6277.6 6357.0 6391.7 6501.9 6674.1 6726.8 6742.2 6769.4 5329.7 6310.8 6314.6 6325.4 6342.5 5903.8 5942.6 5950.5 5955.6 5967.2 5633.7 6667.9 6796.4 6813.4 6915.8 6664.9 6739.2 6750.5 6757.0 6783.5 5917.0 6659.5 6879.2 6895.9 6908.1 6369.6 6443.1 6577.7 6622.4 6637.2 6682.2 6744.0 6765.6 6775.0 6796.6 5647.5 6312.7 6490.0 6498.9 6507.8 6263.3 6373.5 6446.5 6477.6 6526.7 5722.1 5903.8 6353.6 6371.7 6376.7 6282.3 6333.6 6367.4 6454.7 6504.4 6253.4 6339.8 6348.1 6348.3 6372.7 21:03:32 INFO - PID 2801 | 21:03:32 INFO - PID 2801 | [#1] ext.html Cycles:180 Average:44382.30 Median:34534.15 stddev:73164.48 (211.9%) stddev-sans-first:73296.37 21:03:32 INFO - PID 2801 | Values: 748.3 802.3 831.0 1042.3 1281.0 32652.0 38727.0 39343.8 39425.4 39430.6 39575.8 39615.4 39892.2 39996.8 40027.0 7776.5 7942.3 8262.8 8456.8 8474.9 8389.0 8496.2 8496.2 8499.1 8508.1 8409.6 8469.2 8528.7 8528.8 8533.2 8322.0 8438.6 8498.3 8508.0 8518.4 8285.7 8287.3 8349.0 8355.8 8362.0 29579.1 30361.7 33202.7 34254.8 37234.3 2943.4 3171.8 3584.6 3596.5 3630.9 30564.4 30577.4 30591.0 30646.2 30654.8 3115.7 3158.4 3165.7 3182.4 3183.8 237463.0 243071.0 282769.0 298071.0 299817.0 47481.5 47645.8 47721.8 47895.5 48032.5 49423.5 49878.3 49882.5 50056.0 50283.7 14140.4 14197.1 14259.9 14308.4 14332.6 11625.6 11645.5 11646.4 11649.8 11662.0 13223.7 13403.0 13416.3 13450.8 13547.5 5975.4 6024.3 6102.5 6104.1 6109.7 945.8 966.0 1015.9 1023.7 1054.8 24241.9 24829.0 27542.6 31638.8 31901.7 1121.3 1128.3 1151.3 1157.2 1161.3 12377.7 12837.0 12880.7 13003.7 13017.4 300362.0 301584.0 302125.0 302980.0 303177.0 55297.4 55432.0 55516.6 55550.0 55650.2 1257.4 1543.6 1595.7 1596.1 1600.6 24587.5 24626.5 24698.3 24845.3 30059.5 40808.2 51772.5 52473.7 52495.0 52520.7 222892.0 270747.0 271896.0 272361.0 272870.0 35187.0 36276.1 36934.6 36945.4 37038.8 46254.3 46337.0 46380.0 47647.5 48896.8 32886.8 33766.2 34501.8 34566.5 34789.7 33479.8 33676.8 33807.7 34893.8 34910.7 41340.6 41584.8 42352.2 42761.4 42782.4 28167.5 34143.0 34224.3 35059.0 35252.0 21864.0 23849.6 24735.8 25542.0 26257.6 21:03:32 INFO - PID 2801 | 21:03:32 INFO - PID 2801 | [#2] jquery.html Cycles:180 Average:35049.29 Median:27920.79 stddev:90742.19 (325.0%) stddev-sans-first:90959.42 21:03:32 INFO - PID 2801 | Values: 383.8 431.4 435.7 440.4 445.6 18789.5 19526.6 21114.9 21198.6 22085.0 21987.7 22488.0 23078.2 23715.5 23909.6 5995.4 6156.0 6200.6 6212.6 6222.5 5824.3 6090.8 6163.8 6165.6 6174.1 5918.4 6066.3 6121.6 6136.7 6139.5 5968.7 6108.1 6112.8 6117.6 6133.4 5245.6 5387.8 5456.2 5471.6 5475.2 25034.4 27684.9 28156.7 28287.0 29662.3 2116.2 2237.5 2249.1 2253.6 2255.2 17854.7 17976.3 18088.0 18590.9 18815.5 2147.9 2162.2 2209.9 2215.7 2216.4 160699.0 161244.0 161273.4 163769.0 164051.0 10654.7 12905.8 12966.5 13086.6 13264.7 16922.4 16924.2 16928.7 16929.0 16970.1 7862.6 8838.7 9133.5 9148.1 9294.7 8693.8 8712.2 8731.8 8878.7 8886.5 7251.2 7384.7 8010.7 8165.6 8183.8 2219.5 2225.3 2225.8 2227.5 2331.8 937.8 943.1 959.7 966.0 981.1 16348.9 20251.7 21058.7 21116.7 21413.7 755.7 842.7 938.9 948.8 962.2 9437.2 9621.1 10918.4 11027.8 11037.3 524040.0 527078.0 531042.0 540670.0 543048.0 97922.0 105501.0 122384.0 135096.3 136689.0 993.4 1052.0 1093.6 1249.4 1249.7 21230.7 22273.0 22777.3 22785.5 22898.2 55815.7 56323.0 56585.0 56783.0 57319.0 60502.6 77927.0 80125.8 80252.6 80962.6 8792.1 8872.8 8920.0 8924.0 8937.5 12063.6 13501.3 13649.3 13669.8 13968.9 11470.1 11630.7 11818.5 11974.8 12120.5 9132.8 9423.3 10894.2 11086.3 11169.9 12047.0 12079.4 12231.3 12297.4 12307.1 7605.7 8741.0 8848.0 8977.1 9015.8 11512.4 11757.6 11782.5 12019.6 12187.5 21:03:32 INFO - PID 2801 | 21:03:32 INFO - PID 2801 | [#3] mootools.html Cycles:180 Average:7156.03 Median:3844.65 stddev:4553.59 (118.4%) stddev-sans-first:4545.70 21:03:32 INFO - PID 2801 | Values: 1382.9 1420.8 1442.0 1444.6 1454.9 9896.9 11429.8 12095.6 12104.5 12354.4 11921.2 11946.9 12004.8 12106.9 12151.2 2544.7 2614.8 2614.8 2614.8 2616.6 2498.3 2558.6 2559.4 2559.6 2559.8 2548.3 2568.8 2584.6 2602.9 2606.8 2518.8 2545.4 2566.7 2567.8 2576.2 2144.5 2215.3 2525.9 2535.3 2540.4 11586.6 11684.6 11860.1 11866.2 11885.9 6184.7 6739.5 6784.3 6843.6 6854.5 3838.9 4151.3 4182.3 4240.2 4242.3 1203.6 1237.7 1266.0 1274.7 1289.7 16364.3 16507.8 16537.0 16652.7 16654.3 5428.0 5471.8 5608.8 5679.3 5696.5 11791.9 11890.1 11892.6 11902.7 11918.5 6451.5 6508.5 6547.1 6570.9 6574.6 6039.8 6159.6 6209.5 6241.5 6268.5 4302.0 4388.7 4394.9 4424.7 4464.8 1182.3 1274.4 1296.7 1305.6 1311.4 645.6 811.6 813.9 817.8 823.7 6085.7 6307.3 6431.6 6507.8 6511.6 454.6 587.7 595.4 612.1 613.7 3287.6 3850.4 3884.6 3932.7 3936.9 14108.5 14802.1 15026.0 15102.7 15141.9 14602.6 14824.3 15099.6 15139.7 15148.9 8516.1 8656.2 8747.0 8821.8 8837.6 7157.7 7287.0 7336.0 7345.0 7387.3 13352.3 13416.3 13582.5 13718.7 13742.5 13899.8 13907.1 14090.2 14125.3 14263.9 9327.7 9461.6 9530.8 9535.3 9588.6 10658.5 10920.9 10949.5 10971.3 11010.4 8840.0 8969.8 9006.9 9018.5 9266.3 7189.6 7370.7 9051.2 9178.2 9253.7 8396.2 8474.1 8484.5 8493.0 8530.3 5449.8 6080.5 6243.8 6262.2 6296.9 6187.2 6249.4 6372.0 6473.3 6617.7 21:03:32 INFO - PID 2801 | 21:03:32 INFO - PID 2801 | [#4] prototype.html Cycles:180 Average:5309.96 Median:6058.33 stddev:1705.53 (28.2%) stddev-sans-first:1669.72 21:03:32 INFO - PID 2801 | Values: 382.0 398.9 402.8 405.8 406.0 5932.4 6134.7 6321.7 6327.8 6361.5 6086.4 6182.5 6186.8 6205.6 6212.4 3725.9 3863.6 3934.7 4450.4 4555.1 4326.9 4514.9 4515.5 4540.2 4544.6 3648.7 4274.9 4480.9 4481.4 4525.6 3535.4 4274.4 4496.8 4499.9 4502.7 3026.7 3638.6 3771.2 3825.9 3889.7 5224.3 5550.4 5904.1 5976.0 6030.3 5375.2 5387.2 5389.9 5391.5 5398.0 6281.5 6471.4 6605.4 6616.8 6650.6 4299.0 4362.0 4377.9 4378.8 4382.4 5276.9 5825.5 5953.4 6391.4 6906.2 3794.3 4394.4 4402.3 4408.3 4430.8 4461.8 4483.8 4484.8 4503.6 4512.0 5233.5 5318.6 5322.9 5334.8 5362.8 5728.4 6591.5 6597.2 6613.9 6634.7 1529.3 1531.9 1531.9 1533.5 1536.9 951.6 996.6 996.8 1010.5 1022.5 6271.3 6272.5 6295.7 6314.1 6329.7 6739.1 6850.0 6863.6 6874.9 6928.8 5143.0 6157.3 6233.2 6267.2 6275.6 3078.5 3575.3 3584.4 3586.6 3616.1 6305.7 6510.2 6765.6 6956.8 6995.4 6766.4 6891.3 6953.2 6985.6 7086.3 7043.2 7068.3 7091.4 7110.9 7152.7 6643.2 6872.6 6896.6 6902.4 6921.3 6744.9 6795.7 6805.0 6816.3 6877.6 5662.8 7128.4 7132.5 7133.9 7137.6 4388.8 4433.8 4440.1 4447.9 4450.7 5635.7 5772.6 6364.7 6938.4 6955.8 5458.3 5489.6 6619.9 6631.2 6658.3 6546.4 6619.3 6630.6 6630.9 6638.5 5353.3 6392.9 6542.0 6542.6 6576.4 6622.4 6643.4 6667.7 6670.8 6679.7 5474.4 5680.6 6363.3 6479.5 6488.2 21:03:32 INFO - PID 2801 | 21:03:32 INFO - PID 2801 | [#5] yui.html Cycles:180 Average:20669.42 Median:18281.41 stddev:29600.56 (161.9%) stddev-sans-first:29646.16 21:03:32 INFO - PID 2801 | Values: 840.2 858.6 859.1 862.8 880.2 16295.0 16918.3 16933.7 18294.3 18924.1 17791.1 18037.0 18268.5 18565.1 18741.3 990.3 998.5 1013.6 1017.4 1021.6 1011.3 1014.5 1015.1 1019.2 1025.3 1066.4 1068.3 1070.0 1070.2 1071.0 1044.0 1047.1 1051.2 1062.5 1063.2 760.1 773.8 776.4 780.9 782.5 16764.0 17195.3 17450.2 17664.3 17667.3 16879.0 16971.9 17382.5 17479.2 17560.1 14087.6 14177.1 14315.4 14395.0 14497.6 3584.2 3634.3 3647.4 3652.0 3652.7 121250.0 128168.0 128172.7 130083.4 131260.0 13011.5 13381.0 13455.7 13734.5 13803.7 25376.7 25845.6 26203.0 26427.0 26775.6 6259.4 7964.4 8056.4 8061.5 8094.3 3972.4 3997.6 4020.2 4037.1 4040.8 4275.1 4363.3 4413.4 4421.4 4461.7 868.4 936.0 953.8 970.5 970.9 581.8 648.7 651.6 660.4 660.6 10766.6 11738.9 11758.2 11885.4 11897.7 546.6 655.4 669.7 679.7 680.4 4256.9 4268.0 4482.7 5096.4 5364.1 80340.6 92841.0 93315.5 95264.0 95383.5 88588.6 89292.0 90061.5 90472.5 92745.5 79018.0 79157.0 79785.6 80061.2 81657.8 9128.3 10501.9 10627.9 10982.9 11169.8 36419.0 36596.1 36932.3 37153.6 37205.4 46212.8 46608.6 46844.0 46920.6 47303.5 11950.9 13273.8 14364.6 14532.6 14716.9 12666.4 15723.8 15898.8 15952.0 16098.0 13705.2 14106.7 14159.3 14222.7 14262.5 14114.0 14332.3 14361.4 14464.2 14572.3 13395.7 13706.2 13746.5 13860.1 13883.6 9081.0 10281.7 10945.0 10977.8 11034.3 9273.5 10592.8 11029.4 11082.7 11156.9 21:03:32 INFO - PID 2801 | -------- Summary: end -------- 21:03:32 INFO - PID 2801 | 21:03:33 INFO - TEST-INFO | 2801: exit 0 21:03:33 INFO - TEST-OK | dromaeo_css | took 1114761ms 21:03:33 INFO - TEST-START | kraken 21:03:33 INFO - operating with platform_type : mac_ 21:03:33 INFO - Initialising browser for kraken test... 21:03:33 INFO - TEST-INFO | started process 2918 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpkBPjG5/profile http://localhost:57945/getInfo.html) 21:03:35 INFO - PID 2918 | 2017-05-11 21:03:35.622 plugin-container[2919:78673] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 21:03:35 INFO - PID 2918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:03:35 INFO - PID 2918 | 2017-05-11 21:03:35.625 plugin-container[2919:78673] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 21:03:35 INFO - PID 2918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:03:35 INFO - PID 2918 | 2017-05-11 21:03:35.625 plugin-container[2919:78673] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:03:35 INFO - PID 2918 | __metrics Screen width/height:1600/1200 21:03:35 INFO - PID 2918 | colorDepth:24 21:03:35 INFO - PID 2918 | Browser inner width/height: 1024/691 21:03:35 INFO - PID 2918 | __metrics 21:03:36 INFO - PID 2918 | [Child 2920] WARNING: pipe error: Broken pipe: file /builds/slave/m-cen-m64-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 709 21:03:36 INFO - PID 2918 | 2017-05-11 21:03:36.267 plugin-container[2920:78732] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x944b, name = 'com.apple.tsm.portname' 21:03:36 INFO - PID 2918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:03:36 INFO - PID 2918 | 2017-05-11 21:03:36.267 plugin-container[2920:78732] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x944f, name = 'com.apple.CFPasteboardClient' 21:03:36 INFO - PID 2918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:03:36 INFO - PID 2918 | 2017-05-11 21:03:36.267 plugin-container[2920:78732] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:03:36 INFO - TEST-INFO | 2918: exit 0 21:03:36 INFO - Browser initialized. 21:03:36 INFO - Running cycle 1/1 for kraken test... 21:03:36 INFO - TEST-INFO | started process 2921 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpkBPjG5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:03:39 INFO - PID 2921 | 2017-05-11 21:03:38.993 plugin-container[2922:78833] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9453, name = 'com.apple.tsm.portname' 21:03:39 INFO - PID 2921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:03:39 INFO - PID 2921 | 2017-05-11 21:03:38.994 plugin-container[2922:78833] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 21:03:39 INFO - PID 2921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:03:39 INFO - PID 2921 | 2017-05-11 21:03:38.994 plugin-container[2922:78833] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:03:39 INFO - PID 2921 | 2017-05-11 21:03:39.531 plugin-container[2923:78874] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 21:03:39 INFO - PID 2921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:03:39 INFO - PID 2921 | 2017-05-11 21:03:39.531 plugin-container[2923:78874] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 21:03:39 INFO - PID 2921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:03:39 INFO - PID 2921 | 2017-05-11 21:03:39.531 plugin-container[2923:78874] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:03:48 INFO - PID 2921 | RSS: Main: 229486592 21:03:48 INFO - PID 2921 | 21:03:51 INFO - PID 2921 | Cycle 1(1): loaded http://localhost:57945/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57945/tests/kraken/driver.html?testName=audio-beat-detection) 21:03:52 INFO - PID 2921 | RSS: Main: 243027968 21:03:52 INFO - PID 2921 | 21:03:55 INFO - PID 2921 | Cycle 1(1): loaded http://localhost:57945/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57945/tests/kraken/driver.html?testName=audio-dft) 21:03:55 INFO - PID 2921 | RSS: Main: 251211776 21:03:55 INFO - PID 2921 | 21:03:58 INFO - PID 2921 | Cycle 1(1): loaded http://localhost:57945/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57945/tests/kraken/driver.html?testName=audio-fft) 21:03:59 INFO - PID 2921 | RSS: Main: 253497344 21:03:59 INFO - PID 2921 | 21:04:01 INFO - PID 2921 | Cycle 1(1): loaded http://localhost:57945/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57945/tests/kraken/driver.html?testName=audio-oscillator) 21:04:02 INFO - PID 2921 | RSS: Main: 255844352 21:04:02 INFO - PID 2921 | 21:04:04 INFO - PID 2921 | Cycle 1(1): loaded http://localhost:57945/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57945/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:04:04 INFO - PID 2921 | RSS: Main: 256430080 21:04:04 INFO - PID 2921 | 21:04:07 INFO - PID 2921 | Cycle 1(1): loaded http://localhost:57945/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57945/tests/kraken/driver.html?testName=imaging-darkroom) 21:04:08 INFO - PID 2921 | RSS: Main: 262647808 21:04:08 INFO - PID 2921 | 21:04:11 INFO - PID 2921 | Cycle 1(1): loaded http://localhost:57945/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57945/tests/kraken/driver.html?testName=imaging-desaturate) 21:04:11 INFO - PID 2921 | RSS: Main: 264327168 21:04:11 INFO - PID 2921 | 21:04:14 INFO - PID 2921 | Cycle 1(1): loaded http://localhost:57945/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57945/tests/kraken/driver.html?testName=json-parse-financial) 21:04:15 INFO - PID 2921 | RSS: Main: 263733248 21:04:15 INFO - PID 2921 | 21:04:16 INFO - PID 2921 | Cycle 1(1): loaded http://localhost:57945/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57945/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:04:17 INFO - PID 2921 | RSS: Main: 263155712 21:04:17 INFO - PID 2921 | 21:04:19 INFO - PID 2921 | Cycle 1(1): loaded http://localhost:57945/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57945/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:04:19 INFO - PID 2921 | RSS: Main: 263028736 21:04:19 INFO - PID 2921 | 21:04:21 INFO - PID 2921 | Cycle 1(1): loaded http://localhost:57945/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57945/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:04:22 INFO - PID 2921 | RSS: Main: 262696960 21:04:22 INFO - PID 2921 | 21:04:25 INFO - PID 2921 | Cycle 1(1): loaded http://localhost:57945/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57945/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:04:26 INFO - PID 2921 | RSS: Main: 262864896 21:04:26 INFO - PID 2921 | 21:04:29 INFO - PID 2921 | Cycle 1(1): loaded http://localhost:57945/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57945/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:04:29 INFO - PID 2921 | RSS: Main: 263172096 21:04:29 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | Cycle 1(1): loaded http://localhost:57945/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57945/tests/kraken/driver.html?testName=ai-astar) 21:04:31 INFO - PID 2921 | RSS: Main: 265420800 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | __start_tp_report 21:04:31 INFO - PID 2921 | _x_x_mozilla_page_load 21:04:31 INFO - PID 2921 | _x_x_mozilla_page_load_details 21:04:31 INFO - PID 2921 | |i|pagename|runs| 21:04:31 INFO - PID 2921 | |0;ai-astar;104;113;112;113;114;112;114;114;115;113 21:04:31 INFO - PID 2921 | |1;audio-beat-detection;133;109;123;147;109;110;109;120;153;150 21:04:31 INFO - PID 2921 | |2;audio-dft;196;203;200;211;212;207;224;202;200;192 21:04:31 INFO - PID 2921 | |3;audio-fft;82;61;59;101;87;75;57;61;80;58 21:04:31 INFO - PID 2921 | |4;audio-oscillator;81;70;81;69;71;68;83;70;69;96 21:04:31 INFO - PID 2921 | |5;imaging-gaussian-blur;89;118;90;89;88;89;88;88;90;107 21:04:31 INFO - PID 2921 | |6;imaging-darkroom;96;96;96;120;102;96;97;97;96;96 21:04:31 INFO - PID 2921 | |7;imaging-desaturate;93;97;88;91;91;90;91;90;91;111 21:04:31 INFO - PID 2921 | |8;json-parse-financial;50;48;48;50;48;49;52;50;49;52 21:04:31 INFO - PID 2921 | |9;json-stringify-tinderbox;59;57;65;57;64;59;56;57;59;59 21:04:31 INFO - PID 2921 | |10;stanford-crypto-aes;81;83;83;84;85;93;85;81;83;87 21:04:31 INFO - PID 2921 | |11;stanford-crypto-ccm;140;162;155;165;164;156;139;136;209;162 21:04:31 INFO - PID 2921 | |12;stanford-crypto-pbkdf2;187;193;189;180;186;183;187;183;191;185 21:04:31 INFO - PID 2921 | |13;stanford-crypto-sha256-iterative;67;70;67;99;105;89;98;67;58;98 21:04:31 INFO - PID 2921 | __end_tp_report 21:04:31 INFO - PID 2921 | __start_cc_report 21:04:31 INFO - PID 2921 | _x_x_mozilla_cycle_collect,1000 21:04:31 INFO - PID 2921 | __end_cc_report 21:04:31 INFO - PID 2921 | __startTimestamp1494561871955__endTimestamp 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | ------- Summary: start ------- 21:04:31 INFO - PID 2921 | Number of tests: 14 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.50 stddev:3.10 (2.7%) stddev-sans-first:1.00 21:04:31 INFO - PID 2921 | Values: 104.0 113.0 112.0 113.0 114.0 112.0 114.0 114.0 115.0 113.0 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:128.00 stddev:18.14 (14.2%) stddev-sans-first:19.08 21:04:31 INFO - PID 2921 | Values: 133.0 109.0 123.0 147.0 109.0 110.0 109.0 120.0 153.0 150.0 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | [#2] audio-dft Cycles:10 Average:204.70 Median:205.00 stddev:9.20 (4.5%) stddev-sans-first:9.21 21:04:31 INFO - PID 2921 | Values: 196.0 203.0 200.0 211.0 212.0 207.0 224.0 202.0 200.0 192.0 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | [#3] audio-fft Cycles:10 Average:72.10 Median:68.00 stddev:15.17 (22.3%) stddev-sans-first:15.66 21:04:31 INFO - PID 2921 | Values: 82.0 61.0 59.0 101.0 87.0 75.0 57.0 61.0 80.0 58.0 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:76.00 stddev:9.17 (12.1%) stddev-sans-first:9.54 21:04:31 INFO - PID 2921 | Values: 81.0 70.0 81.0 69.0 71.0 68.0 83.0 70.0 69.0 96.0 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | [#5] imaging-gaussian-blur Cycles:10 Average:93.60 Median:89.00 stddev:10.32 (11.6%) stddev-sans-first:10.81 21:04:31 INFO - PID 2921 | Values: 89.0 118.0 90.0 89.0 88.0 89.0 88.0 88.0 90.0 107.0 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | [#6] imaging-darkroom Cycles:10 Average:99.20 Median:96.00 stddev:7.54 (7.9%) stddev-sans-first:7.91 21:04:31 INFO - PID 2921 | Values: 96.0 96.0 96.0 120.0 102.0 96.0 97.0 97.0 96.0 96.0 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | [#7] imaging-desaturate Cycles:10 Average:93.30 Median:91.00 stddev:6.65 (7.3%) stddev-sans-first:7.05 21:04:31 INFO - PID 2921 | Values: 93.0 97.0 88.0 91.0 91.0 90.0 91.0 90.0 91.0 111.0 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:50.00 stddev:1.51 (3.0%) stddev-sans-first:1.59 21:04:31 INFO - PID 2921 | Values: 50.0 48.0 48.0 50.0 48.0 49.0 52.0 50.0 49.0 52.0 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:3.01 (5.1%) stddev-sans-first:3.19 21:04:31 INFO - PID 2921 | Values: 59.0 57.0 65.0 57.0 64.0 59.0 56.0 57.0 59.0 59.0 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:84.50 stddev:3.50 (4.1%) stddev-sans-first:3.48 21:04:31 INFO - PID 2921 | Values: 81.0 83.0 83.0 84.0 85.0 93.0 85.0 81.0 83.0 87.0 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | [#11] stanford-crypto-ccm Cycles:10 Average:158.80 Median:162.00 stddev:20.80 (12.8%) stddev-sans-first:20.92 21:04:31 INFO - PID 2921 | Values: 140.0 162.0 155.0 165.0 164.0 156.0 139.0 136.0 209.0 162.0 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.40 Median:187.00 stddev:3.92 (2.1%) stddev-sans-first:4.15 21:04:31 INFO - PID 2921 | Values: 187.0 193.0 189.0 180.0 186.0 183.0 187.0 183.0 191.0 185.0 21:04:31 INFO - PID 2921 | 21:04:31 INFO - PID 2921 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.80 Median:79.50 stddev:17.55 (22.1%) stddev-sans-first:17.78 21:04:31 INFO - PID 2921 | Values: 67.0 70.0 67.0 99.0 105.0 89.0 98.0 67.0 58.0 98.0 21:04:31 INFO - PID 2921 | -------- Summary: end -------- 21:04:31 INFO - PID 2921 | 21:04:32 INFO - TEST-INFO | 2921: exit 0 21:04:32 INFO - TEST-OK | kraken | took 59725ms 21:04:32 INFO - SUITE-END | took 1174s 21:04:33 INFO - Completed test suite (00:19:34) 21:04:33 INFO - javascript benchmark 21:04:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7829.190772260529, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3027.6518200406954, 3596.7122630189588, 3597.9745772454858, 3603.387424137897, 3608.2963822054694, 4838.0, 6152.446279834814, 6179.258488981323, 6184.7216875240665, 6185.876282474359, 6099.8410302042585, 6156.692165391723, 6156.969215153918, 6238.1266622672865, 6314.936850631492, 5442.455754424565, 5464.726763661831, 5465.9726701366435, 5484.149956756709, 5490.835274941758, 5522.5581953443825, 5583.6928968906695, 5596.3284405871145, 5602.299712535933, 5607.355154157283, 5373.194020896858, 5461.344638643349, 5494.2582751328455, 5536.0, 5599.076152434844, 5503.724813759336, 5523.889522209573, 5545.168224766277, 5555.583331250173, 5601.6358936668985, 5480.342358916916, 5487.42382049883, 5501.807436739735, 5502.257195278667, 5521.282233309644, 5305.283786688786, 5722.084466485384, 6269.090981807658, 6274.87450250997, 6276.905846412307, 6405.231372235315, 6505.837354066139, 6546.247181574085, 6611.140551728245, 6612.86774264512, 6756.358145976124, 6764.966175169093, 6772.288909664513, 6777.423918966893, 6778.4577233821665, 6218.160548326008, 6288.056791481223, 6338.904916426257, 6342.809715708536, 6343.397377249154, 6588.242352129567, 6703.430208432241, 6809.761658341934, 6947.826304342432, 6984.266652001568, 5350.545203657733, 6307.810765676945, 6349.555531112777, 6482.57863238888, 6579.309172536815, 6589.901151482732, 6637.23671777742, 6640.0703901453835, 6652.135222421054, 6670.433013193933, 6488.967555162194, 6565.638889861103, 6578.2106147261675, 6579.046038324512, 6583.07836902834, 5532.3914369419335, 6356.332585078592, 6388.7125079371535, 6565.24499682533, 6565.868682626417, 4572.222722137245, 5287.41838397776, 5325.920111198335, 5340.25236466895, 5347.973260133675, 3523.3481805865486, 4474.26174681174, 4516.322551617283, 4645.488996210414, 4713.528647135327, 5323.707196104174, 6243.313235544087, 6277.623342599459, 6356.951103067942, 6391.680415979183, 6501.934980650228, 6674.132362792903, 6726.7981960541265, 6742.224644165984, 6769.356911093439, 5329.706866122323, 6310.810675679737, 6314.62112273265, 6325.399087086719, 6342.460890824332, 5903.822885313448, 5942.64344139353, 5950.494207992283, 5955.553333500005, 5967.16459695643, 5633.718314084279, 6667.866642667216, 6796.388325050769, 6813.386795188457, 6915.827104322432, 6664.866972614765, 6739.191297044386, 6750.493712971449, 6757.0, 6783.457323414016, 5917.023691091094, 6659.53383263167, 6879.208890977401, 6895.862082758417, 6908.1019467468905, 6369.585976911486, 6443.130177426175, 6577.736890524326, 6622.403983641495, 6637.236717777324, 6682.198136223684, 6744.0, 6765.627890466071, 6775.0, 6796.626185559841, 5647.491725744702, 6312.715927783169, 6490.0, 6498.870022599616, 6507.804765857032, 6263.342349053284, 6373.490120790233, 6446.452051575481, 6477.611343319416, 6526.706298216497, 5722.141678748221, 5903.822885313448, 6353.633968696752, 6371.74513019474, 6376.74493020274, 6282.308946016026, 6333.556651034568, 6367.363263673781, 6454.741810327534, 6504.447121994684, 6253.405926436982, 6339.809805705836, 6348.111264423077, 6348.333424990309, 6372.745090196526], "value": 6026.42152853223, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.2666986353373, 802.3460879383302, 830.9999999999998, 1042.2860340666643, 1280.9551665691702, 32652.0, 38727.0, 39343.80328098359, 39425.40861887074, 39430.60569394312, 39575.81272561828, 39615.40576891342, 39892.20215595686, 39996.80001599995, 40027.0, 7776.533407995525, 7942.285194332509, 8262.793430164264, 8456.78858028548, 8474.915250847505, 8388.958055209716, 8496.150384961516, 8496.192861678162, 8499.107593702665, 8508.149185081504, 8409.579521023956, 8469.153084691543, 8528.688770309987, 8528.78678033048, 8533.189347012023, 8321.95839020807, 8438.620262088221, 8498.320134389234, 8508.02157751854, 8518.446300990447, 8285.668573257062, 8287.254147126787, 8348.958255208745, 8355.791105222357, 8361.95819020907, 29579.11262662102, 30361.696383036327, 33202.667973320196, 34254.82872585646, 37234.25531489355, 2943.381889803144, 3171.762117841171, 3584.5877724061806, 3596.496490491307, 3630.9273814523563, 30564.388712225853, 30577.388452231055, 30591.0, 30646.233844153852, 30654.846725766452, 3115.6572776994512, 3158.3683263347193, 3165.7467402607735, 3182.3953448844877, 3183.793053451518, 237463.0, 243071.0, 282769.0, 298071.0, 299817.0, 47481.52518474771, 47645.76177119092, 47721.76139119282, 47895.521044789806, 48032.5196748035, 49423.50576494189, 49878.25182622191, 49882.50117498779, 50056.0, 50283.74858125759, 14140.434382624786, 14197.148171109766, 14259.928700356639, 14308.427662893368, 14332.57002289933, 11625.59310424131, 11645.54430696163, 11646.41767911601, 11649.825252621218, 11662.008729258108, 13223.73552528944, 13403.0, 13416.262105584286, 13450.798238026437, 13547.525836595672, 5975.4024597540765, 6024.277086749618, 6102.511799056065, 6104.084387341934, 6109.694515274218, 945.7821408796361, 965.9903400966041, 1015.8577799108131, 1023.6570748799087, 1054.8206804843196, 24241.909114089907, 24829.0, 27542.5868611971, 31638.841805791286, 31901.68098318987, 1121.2823792772633, 1128.311729844794, 1151.2516864038275, 1157.2304417562227, 1161.285809227319, 12377.690557736129, 12837.037222208372, 12880.677983050498, 13003.739925201444, 13017.414216360286, 300362.0, 301584.0, 302125.0, 302980.0, 303177.0, 55297.44702552923, 55432.0, 55516.6120846974, 55550.0, 55650.16524752132, 1257.3905401086624, 1543.5523698127643, 1595.7367034439187, 1596.0822527046994, 1600.5598460423478, 24587.508249834544, 24626.507469850145, 24698.259052228463, 24845.254642360756, 30059.549106763854, 40808.18383632251, 51772.482275176764, 52473.7376313116, 52495.0, 52520.737396312776, 222892.0, 270747.0, 271896.0, 272361.0, 272870.0, 35187.0, 36276.09309767277, 36934.63065369419, 36945.44581831221, 37038.814805925795, 46254.30618540792, 46337.0, 46380.0, 47647.52352476431, 48896.77758055935, 32886.83556582202, 33766.15584610404, 34501.827490862386, 34566.481502777984, 34789.65210347864, 33479.83260083684, 33676.81100728445, 33807.66192338045, 34893.8255308732, 34910.65089349176, 41340.586594133674, 41584.792076039426, 42352.1529569413, 42761.358579621956, 42782.35826462668, 28167.454112763677, 34143.0, 34224.28878556112, 35059.0, 35252.0, 21864.0, 23849.64225536653, 24735.763211838977, 25542.0, 26257.606135907594], "value": 17935.739555891105, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.81001404304874, 431.3895837390093, 435.65833644162626, 440.36147586000305, 445.6056390094766, 18789.530261743465, 19526.609467810653, 21114.89442552787, 21198.57602847942, 22085.006174722137, 21987.670184947237, 22488.0, 23078.192263270746, 23715.5256894862, 23909.641355379685, 5995.370486098962, 6156.0, 6200.565960382775, 6212.596181248205, 6222.533310001745, 5824.301083869942, 6090.817275481742, 6163.753449862, 6165.568410211287, 6174.135621013039, 5918.40815918409, 6066.272047354324, 6121.571489995702, 6136.662483563402, 6139.508839292868, 5968.67172305523, 6108.053251745987, 6112.847178820521, 6117.602355846878, 6133.355997620275, 5245.632805703603, 5387.757550910218, 5456.154296084113, 5471.616986810925, 5475.23346731454, 25034.37414064663, 27684.861575692194, 28156.718432815615, 28287.0, 29662.258443538867, 2116.2169997101173, 2237.463008877874, 2249.1453247765744, 2253.5718213539376, 2255.165588732163, 17854.732179017326, 17976.280948762032, 18088.0, 18590.90704546482, 18815.529611759677, 2147.8711277323255, 2162.2107930605375, 2209.856359336638, 2215.7230346206725, 2216.4015715756655, 160699.0, 161244.0, 161273.387266127, 163769.0, 164051.0, 10654.73363165927, 12905.806412903814, 12966.481340746264, 13086.607401777961, 13264.668383290495, 16922.40771572989, 16924.238409271613, 16928.661426771396, 16928.984260944384, 16970.066646334322, 7862.646180921873, 8838.690645827364, 9133.543322833964, 9148.130927561871, 9294.72115836526, 8693.826123477622, 8712.172343627346, 8731.781705457415, 8878.689245876363, 8886.511241881759, 7251.1661158966335, 7384.667689953965, 8010.719624813103, 8165.591720413956, 8183.79540511492, 2219.4562332228707, 2225.254539729209, 2225.788550087739, 2227.5322182341565, 2331.813454923602, 937.803061357109, 943.118184497497, 959.7024922274118, 965.9517024148764, 981.1464026297167, 16348.918255408647, 20251.696224556643, 21058.684119738216, 21116.683249751266, 21413.67879481809, 755.6599530211427, 842.6924172677051, 938.9061093890556, 948.8292107420731, 962.2206013129388, 9437.197838183694, 9621.134097931079, 10918.399488028235, 11027.83458248127, 11037.33775973428, 524040.0, 527078.0, 531042.0, 540670.0, 543048.0, 97922.0, 105501.0, 122384.0, 135096.27163049442, 136689.0, 993.4486360070086, 1051.9842202367124, 1093.6117678224275, 1249.3815561297215, 1249.743802520481, 21230.681539776608, 22273.0, 22777.31668049961, 22785.544289113794, 22898.198563050213, 55815.720921395136, 56323.0, 56585.0, 56783.0, 57319.0, 60502.613530678456, 77927.0, 80125.79811302718, 80252.59873700594, 80962.59518702369, 8792.120787921413, 8872.822543549222, 8919.955400222958, 8924.0, 8937.46375217489, 12063.638090856937, 13501.324933753076, 13649.317534123056, 13669.794953075512, 13968.930155349159, 11470.139739519402, 11630.6510804676, 11818.46816893259, 11974.760504790029, 12120.454579544117, 9132.771680708036, 9423.340366174301, 10894.182936279969, 11086.334819910831, 11169.88830111721, 12047.0, 12079.396030198279, 12231.266124032587, 12297.446614902172, 12307.138500304893, 7605.733799316998, 8741.038485766434, 8848.02671706099, 8977.10228977097, 9015.819683606422, 11512.42437878086, 11757.647270581896, 11782.469788859351, 12019.579314723942, 12187.451564679788], "value": 10388.030191797898, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1382.8686274803893, 1420.8081908942297, 1441.9855801441995, 1444.6027342480816, 1454.898157129001, 9896.901030989688, 11429.771404571913, 12095.576654817083, 12104.515819367225, 12354.444050017748, 11921.225120367191, 11946.880531194685, 12004.81992770107, 12106.878931210686, 12151.210171338857, 2544.7328030556755, 2614.7515985981395, 2614.80388970827, 2614.816962812604, 2616.6336712860216, 2498.3129639349236, 2558.5522533556646, 2559.436923876744, 2559.5520783862844, 2559.7696207341337, 2548.3374322676054, 2568.833025853323, 2584.5606246938064, 2602.947941041178, 2606.7784238339764, 2518.7733104020635, 2545.4018305698146, 2566.653501777255, 2567.8459292442512, 2576.1756238003845, 2144.485323522359, 2215.3353993801957, 2525.911593094251, 2535.32813804342, 2540.4284036091917, 11586.594469193622, 11684.649460516199, 11860.110491713156, 11866.169368144234, 11885.94057029718, 6184.721687524027, 6739.460843132538, 6784.253732089466, 6843.555168914005, 6854.520183587151, 3838.9040273993096, 4151.294279972412, 4182.330827067685, 4240.173166232586, 4242.342436922282, 1203.5787474383897, 1237.6720169155092, 1265.9620211393674, 1274.6685861675937, 1289.729156877057, 16364.34542618294, 16507.752383714254, 16537.007779533265, 16652.66694666112, 16654.25055872501, 5427.972860135674, 5471.753771080311, 5608.775648973995, 5679.318481782213, 5696.544276457874, 11791.941040294743, 11890.108241881893, 11892.583759568373, 11902.702432439259, 11918.52325906954, 6451.483881289486, 6508.511861610304, 6547.116139321226, 6570.868582628416, 6574.638394888231, 6039.818805435844, 6159.630422174684, 6209.534284928648, 6241.500679945684, 6268.49852011838, 4301.978490107529, 4388.71473354231, 4394.868153955386, 4424.712393694464, 4464.799084041226, 1182.261086820737, 1274.388293619068, 1296.6887946892866, 1305.5691621764795, 1311.4098655605017, 645.6352161029004, 811.5982588618554, 813.9430239883151, 817.8486979908736, 823.7117009046785, 6085.665288409092, 6307.3377295383325, 6431.646259455775, 6507.804765857032, 6511.576747511492, 454.6226631895519, 587.7472686744655, 595.3867516458055, 612.063542779548, 613.6992873491956, 3287.6219234787827, 3850.403187505941, 3884.592117827644, 3932.665723413542, 3936.940945885814, 14108.506202283075, 14802.111873287637, 15026.0, 15102.697946041018, 15141.924290378478, 14602.63493412652, 14824.25878706082, 15099.622509437351, 15139.697206056038, 15148.924255378653, 8516.148385161558, 8656.22094011542, 8747.03782583928, 8821.779455513664, 8837.64649414029, 7157.737353600797, 7287.0162528060155, 7335.963320183365, 7345.0084238627105, 7387.298206670358, 13352.332383380986, 13416.32918354098, 13582.45670173182, 13718.725625487634, 13742.519011834538, 13899.791503127259, 13907.096038757449, 14090.22503762303, 14125.293735312987, 14263.928680356532, 9327.673531426368, 9461.621535138793, 9530.761730956781, 9535.284853636143, 9588.616455341988, 10658.520366583367, 10920.945395272973, 10949.50727217293, 10971.341719496855, 11010.449477526252, 8840.0, 8969.820603588023, 9006.909930900869, 9018.458892466211, 9266.305027123128, 7189.640517974185, 7370.742024029133, 9051.185393314634, 9178.173964343241, 9253.67612133599, 8396.160383961555, 8474.110218427222, 8484.533350665773, 8492.957535212285, 8530.317574594143, 5449.782008719607, 6080.452759251511, 6243.781467648792, 6262.185915830913, 6296.898042842393, 6187.195664563578, 6249.406306400884, 6372.012338087604, 6473.287938326875, 6617.66911654425], "value": 5342.268149370817, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.952255968004, 398.93218152914, 402.8147052355916, 405.7747949887813, 405.95128584569846, 5932.436418540243, 6134.723937422816, 6321.747130114795, 6327.841803954898, 6361.47324642086, 6086.352436690043, 6182.505399568035, 6186.845328866781, 6205.627662340263, 6212.409821067003, 3725.8695945641907, 3863.555691095518, 3934.7048971327195, 4450.435607478975, 4555.105384895916, 4326.913461730764, 4514.932276015862, 4515.532451953112, 4540.18276710192, 4544.5682660147395, 3648.7263455240964, 4274.850380236692, 4480.865574032784, 4481.3726078349055, 4525.637948964075, 3535.4166562517153, 4274.439829462255, 4496.820127194908, 4499.910001799978, 4502.662300327472, 3026.727594516482, 3638.6361363863666, 3771.226898485831, 3825.8660946866726, 3889.669378102865, 5224.294720212761, 5550.361708403545, 5904.143899134601, 5975.966222799593, 6030.306514750772, 5375.22059301403, 5387.19192121181, 5389.946100538984, 5391.4608539145775, 5397.974384866902, 6281.466075383598, 6471.385218404244, 6605.40551350376, 6616.834579135512, 6650.567713098633, 4298.957010429887, 4361.996740749613, 4377.934330985037, 4378.8029538670835, 4382.408374869384, 5276.894462110737, 5825.543614096476, 5953.374895635982, 6391.424771770563, 6906.171259448848, 3794.29805485986, 4394.428724265825, 4402.295632698753, 4408.338749187584, 4430.822767089345, 4461.754603496839, 4483.775811209426, 4484.753338566345, 4503.594676479132, 4512.036759564453, 5233.502817232356, 5318.574514038867, 5322.866928326745, 5334.759935802898, 5362.812301569505, 5728.427157284238, 6591.505637077238, 6597.21875093722, 6613.86772264552, 6634.701438435282, 1529.319452843474, 1531.877449804013, 1531.9004264722757, 1533.53993801859, 1536.9231538423035, 951.5575257505293, 996.6362277768707, 996.8056229035413, 1010.5149528226492, 1022.4683164754287, 6271.27880293763, 6272.529560282997, 6295.716692748837, 6314.147590075373, 6329.68351582419, 6739.142354413084, 6850.047736395103, 6863.622500762406, 6874.862502749917, 6928.757493487704, 5143.0, 6157.2611286645, 6233.158523599256, 6267.2165979252595, 6275.623462592259, 3078.5382192671277, 3575.3206890690685, 3584.390653588896, 3586.5696116466193, 3616.1140520572626, 6305.716242769086, 6510.153680021567, 6765.627890465973, 6956.826079348057, 6995.370416662525, 6766.357196066366, 6891.310868913169, 6953.200381956039, 6985.617259288873, 7086.326798954091, 7043.190033146151, 7068.328508791554, 7091.361777440055, 7110.906755561347, 7152.713891444388, 6643.202815662152, 6872.553284036521, 6896.586204827727, 6902.378785909293, 6921.342472465108, 6744.898826517508, 6795.694193761194, 6805.0472933788315, 6816.250212476524, 6877.552959057645, 5662.8301150965535, 7128.394086502498, 7132.536385134949, 7133.8929916050265, 7137.607431591105, 4388.824447022211, 4433.82264709408, 4440.111977604557, 4447.887253554536, 4450.732956022649, 5635.718214089361, 5772.595918285848, 6364.713587888648, 6938.410235129869, 6955.756548520778, 5458.2904222450825, 5489.64317319373, 6619.867602648016, 6631.204255489372, 6658.334166583303, 6546.44688571467, 6619.3380661933425, 6630.569013014331, 6630.9336906630315, 6638.535302528777, 5353.330833645794, 6392.881245782062, 6541.967290163519, 6542.611946639538, 6576.408123268929, 6622.437092847162, 6643.409070462427, 6667.666616669244, 6670.7665231716655, 6679.732810687518, 5474.3704473986, 5680.630759000652, 6363.331850155667, 6479.514036447191, 6488.188976377953], "value": 4776.043740392726, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [840.1724301562963, 858.5878778186471, 859.1365677494117, 862.8403745307119, 880.2385936165217, 16295.0, 16918.323267069303, 16933.661326773472, 18294.268229270845, 18924.1484133214, 17791.11044447779, 18037.0, 18268.543286417847, 18565.071746412694, 18741.34405295815, 990.2721499697716, 998.4558415663445, 1013.6249587652542, 1017.4099022566893, 1021.6271061062733, 1011.3173607814732, 1014.457265363031, 1015.1067060986321, 1019.1795604538381, 1025.2566889005486, 1066.3601838896677, 1068.2842495528014, 1070.0369667299415, 1070.1813112968584, 1071.0307172009343, 1043.9634612788554, 1047.1151876664283, 1051.1958351860833, 1062.5165549674957, 1063.2025980514613, 760.1448370583093, 773.8413625206819, 776.4487214078011, 780.9180036096185, 782.475741253356, 16763.99416035042, 17195.312187512485, 17450.214740336716, 17664.293428262856, 17667.293308267654, 16879.0, 16971.915140424342, 17382.478525644125, 17479.21343539531, 17560.121993900255, 14087.577372678836, 14177.149371037667, 14315.355808988515, 14395.0, 14497.637559061002, 3584.2473080652835, 3634.309481198564, 3647.434647629622, 3652.0, 3652.7077833773233, 121250.0, 128168.0, 128172.71827281702, 130083.43039385737, 131260.0, 13011.544595939096, 13381.0, 13455.663608409672, 13734.45062197521, 13803.654908627364, 25376.74623253744, 25845.612315815277, 26202.95188192489, 26427.0, 26775.598366024526, 6259.436650701458, 7964.442489025712, 8056.436049476598, 8061.4760040597175, 8094.311983481352, 3972.4041393791163, 3997.5802540733384, 4020.155767288902, 4037.051292946155, 4040.8181631826637, 4275.144971005811, 4363.258246098171, 4413.44831896013, 4421.358902959118, 4461.687681862239, 868.3747701654798, 935.9891317377352, 953.7520244736419, 970.5292932927524, 970.8592254123254, 581.7963712700521, 648.6756621689156, 651.5504302031583, 660.4155322539588, 660.6333484915917, 10766.623168189075, 11738.882611173949, 11758.176927615155, 11885.40572971348, 11897.702557436134, 546.5682111132161, 655.4068567946016, 669.718718138383, 679.6873438218474, 680.4318394140848, 4256.935766058486, 4268.0, 4482.731036137842, 5096.439391666913, 5364.061289274361, 80340.5982970093, 92841.0, 93315.53342233246, 95264.0, 95383.52308238414, 88588.5570572143, 89292.0, 90061.54969225243, 90472.54763726139, 92745.5362723182, 79018.0, 79157.0, 79785.60107199427, 80061.19938800654, 81657.77513337304, 9128.269738420777, 10501.894981050398, 10627.946860265649, 10982.890171098186, 11169.832452513056, 36419.0, 36596.08509787277, 36932.26135477329, 37153.6284637161, 37205.44191837071, 46212.7689361551, 46608.601741947794, 46844.0, 46920.59238222859, 47303.526964729914, 11950.940245298718, 13273.800892986807, 14364.635359640817, 14532.564023079325, 14716.926415367854, 12666.430010649727, 15723.764143538085, 15898.841011590199, 15952.0, 16098.0, 13705.177689338672, 14106.717865642835, 14159.292035397983, 14222.715545688821, 14262.500812471513, 14114.0, 14332.283385830875, 14361.425542978164, 14464.20446875432, 14572.271386430848, 13395.665108371979, 13706.177629342272, 13746.518871839438, 13860.099093558887, 13883.583492495241, 9081.0, 10281.74295642615, 10945.0, 10977.83533246986, 11034.337939723642, 9273.53632318395, 10592.841107383549, 11029.393383363671, 11082.722931926766, 11156.888431115585], "value": 8038.2918702588295, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1497.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 112.0, 113.0, 114.0, 112.0, 114.0, 114.0, 115.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 109.0, 123.0, 147.0, 109.0, 110.0, 109.0, 120.0, 153.0, 150.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 203.0, 200.0, 211.0, 212.0, 207.0, 224.0, 202.0, 200.0, 192.0], "value": 204.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 59.0, 101.0, 87.0, 75.0, 57.0, 61.0, 80.0, 58.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 70.0, 81.0, 69.0, 71.0, 68.0, 83.0, 70.0, 69.0, 96.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 118.0, 90.0, 89.0, 88.0, 89.0, 88.0, 88.0, 90.0, 107.0], "value": 93.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 120.0, 102.0, 96.0, 97.0, 97.0, 96.0, 96.0], "value": 99.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 97.0, 88.0, 91.0, 91.0, 90.0, 91.0, 90.0, 91.0, 111.0], "value": 93.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 48.0, 48.0, 50.0, 48.0, 49.0, 52.0, 50.0, 49.0, 52.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 57.0, 65.0, 57.0, 64.0, 59.0, 56.0, 57.0, 59.0, 59.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 83.0, 84.0, 85.0, 93.0, 85.0, 81.0, 83.0, 87.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 162.0, 155.0, 165.0, 164.0, 156.0, 139.0, 136.0, 209.0, 162.0], "value": 158.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 193.0, 189.0, 180.0, 186.0, 183.0, 187.0, 183.0, 191.0, 185.0], "value": 186.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 67.0, 99.0, 105.0, 89.0, 98.0, 67.0, 58.0, 98.0], "value": 81.8, "unit": "score"}]}]} 21:04:33 INFO - Return code: 0 21:04:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:04:33 INFO - # TBPL SUCCESS # 21:04:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:04:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:04:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:04:33 INFO - 2017-05-11 21:04:33.258 screenresolution[2934:79269] starting screenresolution argv=screenresolution get 21:04:33 INFO - 2017-05-11 21:04:33.261 screenresolution[2934:79269] Display 0: 1600x1200x32@60 21:04:33 INFO - 2017-05-11 21:04:33.267 screenresolution[2935:79272] starting screenresolution argv=screenresolution list 21:04:33 INFO - Available Modes on Display 0 21:04:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:04:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:04:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:04:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:04:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:04:33 INFO - Intel Iris: 21:04:33 INFO - Chipset Model: Intel Iris 21:04:33 INFO - Type: GPU 21:04:33 INFO - Bus: Built-In 21:04:33 INFO - VRAM (Dynamic, Max): 1536 MB 21:04:33 INFO - Vendor: Intel (0x8086) 21:04:33 INFO - Device ID: 0x0a2e 21:04:33 INFO - Revision ID: 0x0009 21:04:33 INFO - Displays: 21:04:33 INFO - Display: 21:04:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:04:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:04:33 INFO - Display Serial Number: 1600x1200 60 21:04:33 INFO - Main Display: Yes 21:04:33 INFO - Mirror: Off 21:04:33 INFO - Online: Yes 21:04:33 INFO - Rotation: Supported 21:04:33 INFO - Return code: 0 21:04:33 INFO - Running post-action listener: _resource_record_post_action 21:04:33 INFO - [mozharness: 2017-05-12 04:04:33.506112Z] Finished run-tests step (success) 21:04:33 INFO - Running post-run listener: _resource_record_post_run 21:04:33 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 947712; Write bytes: 532080128; Read time: 9; Write time: 1578 21:04:33 INFO - TinderboxPrint: CPU usage
54.2% 21:04:33 INFO - TinderboxPrint: I/O read bytes / time
947,712 / 9 21:04:33 INFO - TinderboxPrint: I/O write bytes / time
532,080,128 / 1,578 21:04:33 INFO - TinderboxPrint: CPU idle
2,188.9 (45.8%) 21:04:33 INFO - TinderboxPrint: CPU system
965.1 (20.2%) 21:04:33 INFO - TinderboxPrint: CPU user
1,627.7 (34.0%) 21:04:33 INFO - TinderboxPrint: Swap in / out
214,876,160 / 0 21:04:33 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 207497728; Write bytes: 207742976; Read time: 15869; Write time: 402 21:04:33 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 947712; Write bytes: 314441216; Read time: 9; Write time: 1148 21:04:33 INFO - Running post-run listener: _upload_blobber_files 21:04:33 INFO - Blob upload gear active. 21:04:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:04:33 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:04:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:04:34 INFO - (blobuploader) - INFO - Open directory for files ... 21:04:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:04:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:04:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:04:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:04:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:04:35 INFO - (blobuploader) - INFO - Done attempting. 21:04:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:04:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:04:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:04:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:04:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:04:36 INFO - (blobuploader) - INFO - Done attempting. 21:04:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:04:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:04:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:04:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:04:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:04:36 INFO - (blobuploader) - INFO - Done attempting. 21:04:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:04:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:04:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:04:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:04:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:04:39 INFO - (blobuploader) - INFO - Done attempting. 21:04:39 INFO - (blobuploader) - INFO - Iteration through files over. 21:04:39 INFO - Return code: 0 21:04:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:04:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:04:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a419fc2c0b812d6658450a8546fee5106f1da5f9e946f12e57b664e01f3074896037dd391184635d051c49c1de0f3c816a9eb3fdab555a67ad2c4abf6c2d4112", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cfe097700fd89b2a8c50ab40066dc7f459c2eb17338e86001abdc740f1d1a80d7c4001c450e71e107015a3f2372429dbb06bdbd3eb4bec93aadccb62248353b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/868462902a03354f3e0c1acf9840c7f24213f747e9312f6c26763900766b5c9d1c55c978ebd8ab1e5ed6b99e98b8d77789030791c6158cc971888b676c725b98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b37e8be8ebfbcf3b4142c9d50a3616f50cfcbba9e51601211b25ee237fe0d636ff732e17219c27c5b4ad6723988c8c35c04da48b5d799ed54a3582132692607b"} 21:04:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:04:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:04:39 INFO - Contents: 21:04:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a419fc2c0b812d6658450a8546fee5106f1da5f9e946f12e57b664e01f3074896037dd391184635d051c49c1de0f3c816a9eb3fdab555a67ad2c4abf6c2d4112", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cfe097700fd89b2a8c50ab40066dc7f459c2eb17338e86001abdc740f1d1a80d7c4001c450e71e107015a3f2372429dbb06bdbd3eb4bec93aadccb62248353b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/868462902a03354f3e0c1acf9840c7f24213f747e9312f6c26763900766b5c9d1c55c978ebd8ab1e5ed6b99e98b8d77789030791c6158cc971888b676c725b98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b37e8be8ebfbcf3b4142c9d50a3616f50cfcbba9e51601211b25ee237fe0d636ff732e17219c27c5b4ad6723988c8c35c04da48b5d799ed54a3582132692607b"} 21:04:39 INFO - Running post-run listener: copy_logs_to_upload_dir 21:04:39 INFO - Copying logs to upload dir... 21:04:39 INFO - mkdir: /builds/slave/test/build/upload/logs 21:04:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.202950 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-11 21:04:39.313239) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 21:04:39.316401) ========= 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.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a419fc2c0b812d6658450a8546fee5106f1da5f9e946f12e57b664e01f3074896037dd391184635d051c49c1de0f3c816a9eb3fdab555a67ad2c4abf6c2d4112", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cfe097700fd89b2a8c50ab40066dc7f459c2eb17338e86001abdc740f1d1a80d7c4001c450e71e107015a3f2372429dbb06bdbd3eb4bec93aadccb62248353b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/868462902a03354f3e0c1acf9840c7f24213f747e9312f6c26763900766b5c9d1c55c978ebd8ab1e5ed6b99e98b8d77789030791c6158cc971888b676c725b98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b37e8be8ebfbcf3b4142c9d50a3616f50cfcbba9e51601211b25ee237fe0d636ff732e17219c27c5b4ad6723988c8c35c04da48b5d799ed54a3582132692607b"} build_url:https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011558 build_url: 'https://queue.taskcluster.net/v1/task/df1PX6s8QAuZDjIV5osl3w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a419fc2c0b812d6658450a8546fee5106f1da5f9e946f12e57b664e01f3074896037dd391184635d051c49c1de0f3c816a9eb3fdab555a67ad2c4abf6c2d4112", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cfe097700fd89b2a8c50ab40066dc7f459c2eb17338e86001abdc740f1d1a80d7c4001c450e71e107015a3f2372429dbb06bdbd3eb4bec93aadccb62248353b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/868462902a03354f3e0c1acf9840c7f24213f747e9312f6c26763900766b5c9d1c55c978ebd8ab1e5ed6b99e98b8d77789030791c6158cc971888b676c725b98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b37e8be8ebfbcf3b4142c9d50a3616f50cfcbba9e51601211b25ee237fe0d636ff732e17219c27c5b4ad6723988c8c35c04da48b5d799ed54a3582132692607b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 21:04:39.353917) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 21:04:39.354268) ========= 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.k8mjtLSSzq/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.8mXKAz5Fhy/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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.005676 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 21:04:39.402929) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 21:04:39.403276) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 21:04:39.403597) ========= ========= Total master_lag: 0.18 =========