builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0336 starttime: 1494936155.88 results: success (0) revision: 49365d675cbb2a8368cda3e4858a2a0d0634c249 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 05:02:35.883976) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 05:02:35.884428) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 05:02:35.884707) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 05:02:35.909798) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 05:02:35.910074) ========= 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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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-16 05:02:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.6M=0.001s 2017-05-16 05:02:36 (14.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122353 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 05:02:36.055406) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 05:02:36.055726) ========= 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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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.039518 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 05:02:36.117484) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-16 05:02:36.117819) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 49365d675cbb2a8368cda3e4858a2a0d0634c249 --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 49365d675cbb2a8368cda3e4858a2a0d0634c249 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rDKAYbI2RU/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.Ebb2os4jb7/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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-16 05:02:36,213 truncating revision to first 12 chars 2017-05-16 05:02:36,213 Setting DEBUG logging. 2017-05-16 05:02:36,213 attempt 1/10 2017-05-16 05:02:36,213 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/49365d675cbb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-16 05:02:36,975 unpacking tar archive at: mozilla-central-49365d675cbb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.045756 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-16 05:02:37.187094) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 05:02:37.187437) ========= script_repo_revision: 49365d675cbb2a8368cda3e4858a2a0d0634c249 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 05:02:37.187813) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 05:02:37.188084) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 05:02:37.207989) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-16 05:02:37.208520) ========= /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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 05:02:37 INFO - MultiFileLogger online at 20170516 05:02:37 in /builds/slave/test 05:02:37 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 05:02:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:02:37 INFO - {'append_to_log': False, 05:02:37 INFO - 'base_work_dir': '/builds/slave/test', 05:02:37 INFO - 'blob_upload_branch': 'Firefox', 05:02:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:02:37 INFO - 'branch': 'Firefox', 05:02:37 INFO - 'buildbot_json_path': 'buildprops.json', 05:02:37 INFO - 'config_files': ('talos/mac_config.py',), 05:02:37 INFO - 'default_actions': ('clobber', 05:02:37 INFO - 'read-buildbot-config', 05:02:37 INFO - 'download-and-extract', 05:02:37 INFO - 'populate-webroot', 05:02:37 INFO - 'create-virtualenv', 05:02:37 INFO - 'install', 05:02:37 INFO - 'run-tests'), 05:02:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:02:37 INFO - 'download_minidump_stackwalk': True, 05:02:37 INFO - 'download_symbols': 'ondemand', 05:02:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:02:37 INFO - 'tooltool.py': '/tools/tooltool.py', 05:02:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:02:37 INFO - '/tools/misc-python/virtualenv.py')}, 05:02:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:02:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:02:37 INFO - 'gecko_profile': False, 05:02:37 INFO - 'gecko_profile_interval': 0, 05:02:37 INFO - 'installer_path': 'installer.exe', 05:02:37 INFO - 'log_level': 'info', 05:02:37 INFO - 'log_name': 'talos', 05:02:37 INFO - 'log_to_console': True, 05:02:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:02:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:02:37 INFO - 'opt_config_files': (), 05:02:37 INFO - 'pip_index': False, 05:02:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:02:37 INFO - 'cmd': ('bash', 05:02:37 INFO - '-c', 05:02:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:02:37 INFO - 'enabled': True, 05:02:37 INFO - 'halt_on_failure': False, 05:02:37 INFO - 'name': 'check_screen_resolution'},), 05:02:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:02:37 INFO - 'cmd': ('bash', 05:02:37 INFO - '-c', 05:02:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:02:37 INFO - 'enabled': True, 05:02:37 INFO - 'halt_on_failure': False, 05:02:37 INFO - 'name': 'check_screen_resolution'},), 05:02:37 INFO - 'run_cmd_checks_enabled': True, 05:02:37 INFO - 'suite': 'dromaeojs-e10s', 05:02:37 INFO - 'system_bits': '32', 05:02:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:02:37 INFO - 'title': 't-yosemite-r7-0336', 05:02:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:02:37 INFO - 'use_talos_json': True, 05:02:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:02:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:02:37 INFO - 'work_dir': 'build'} 05:02:37 INFO - [mozharness: 2017-05-16 12:02:37.413022Z] Running clobber step. 05:02:37 INFO - Running pre-action listener: _resource_record_pre_action 05:02:37 INFO - Running main action method: clobber 05:02:37 INFO - rmtree: /builds/slave/test/build 05:02:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:02:39 INFO - Running post-action listener: _resource_record_post_action 05:02:39 INFO - [mozharness: 2017-05-16 12:02:39.865299Z] Finished clobber step (success) 05:02:39 INFO - [mozharness: 2017-05-16 12:02:39.865421Z] Running read-buildbot-config step. 05:02:39 INFO - Running pre-action listener: _resource_record_pre_action 05:02:39 INFO - Running main action method: read_buildbot_config 05:02:39 INFO - Using buildbot properties: 05:02:39 INFO - { 05:02:39 INFO - "project": "", 05:02:39 INFO - "product": "firefox", 05:02:39 INFO - "who": "cbook@mozilla.com", 05:02:39 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 05:02:39 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:02:39 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:02:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:02:39 INFO - "stage_platform": "macosx64", 05:02:39 INFO - "basedir": "/builds/slave/test", 05:02:39 INFO - "buildnumber": 217, 05:02:39 INFO - "platform": "yosemite_r7", 05:02:39 INFO - "parent_task_id": "A7pZ2d4ETx-gYBsagkAOtw", 05:02:39 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:02:39 INFO - "slavebuilddir": "test", 05:02:39 INFO - "taskId": "K8EK87UCQUKGoLMuLANU5Q", 05:02:39 INFO - "branch": "mozilla-central", 05:02:39 INFO - "script_repo_revision": "production", 05:02:39 INFO - "revision": "49365d675cbb2a8368cda3e4858a2a0d0634c249", 05:02:39 INFO - "slavename": "t-yosemite-r7-0336", 05:02:39 INFO - "repo_path": "mozilla-central" 05:02:39 INFO - } 05:02:39 INFO - Finding installer, test and symbols from parent task. 05:02:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K8EK87UCQUKGoLMuLANU5Q'}, attempt #1 05:02:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K8EK87UCQUKGoLMuLANU5Q'}, attempt #1 05:02:40 INFO - Task dependencies: UXtnSEJCR0aE9rogdqKBJQ 05:02:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw'}, attempt #1 05:02:41 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:02:41 INFO - Set test_url: https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/test_packages.json 05:02:41 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/target.crashreporter-symbols.zip 05:02:41 INFO - Running post-action listener: _resource_record_post_action 05:02:41 INFO - [mozharness: 2017-05-16 12:02:41.285376Z] Finished read-buildbot-config step (success) 05:02:41 INFO - [mozharness: 2017-05-16 12:02:41.285477Z] Running download-and-extract step. 05:02:41 INFO - Running pre-action listener: _resource_record_pre_action 05:02:41 INFO - Running main action method: download_and_extract 05:02:41 INFO - mkdir: /builds/slave/test/build/tests 05:02:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:41 INFO - trying https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:02:41 INFO - Downloading https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/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 05:02:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/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 05:02:42 INFO - Downloaded 1325 bytes. 05:02:42 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:02:42 INFO - Using the following test package requirements: 05:02:42 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:42 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:02:42 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:02:42 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:42 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:02:42 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:42 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:02:42 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:42 INFO - u'jsshell-mac.zip'], 05:02:42 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:42 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:02:42 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:02:42 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:42 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:02:42 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:42 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:02:42 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:42 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:02:42 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:42 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:02:42 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:02:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:02:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:02:42 INFO - Fetch https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:02:44 INFO - Content-Length response header: 56566001 05:02:44 INFO - Bytes received: 56566001 05:03:02 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 05:03:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:03:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:03:02 INFO - Fetch https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:03:04 INFO - Content-Length response header: 56566001 05:03:04 INFO - Bytes received: 56566001 05:03:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:03:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:03:20 INFO - Fetch https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:03:22 INFO - Content-Length response header: 14410018 05:03:22 INFO - Bytes received: 14410018 05:03:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:22 INFO - trying https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:03:22 INFO - Downloading https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:03:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/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 05:03:24 INFO - Downloaded 75968228 bytes. 05:03:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:03:24 INFO - mkdir: /builds/slave/test/properties 05:03:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:03:24 INFO - Writing to file /builds/slave/test/properties/build_url 05:03:24 INFO - Contents: 05:03:24 INFO - build_url:https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:03:24 INFO - Running post-action listener: _resource_record_post_action 05:03:24 INFO - Running post-action listener: set_extra_try_arguments 05:03:24 INFO - [mozharness: 2017-05-16 12:03:24.610288Z] Finished download-and-extract step (success) 05:03:24 INFO - [mozharness: 2017-05-16 12:03:24.610400Z] Running populate-webroot step. 05:03:24 INFO - Running pre-action listener: _resource_record_pre_action 05:03:24 INFO - Running main action method: populate_webroot 05:03:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:03:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:03:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:03:24 INFO - '--tppagecycles', 05:03:24 INFO - '7'], 05:03:24 INFO - 'tests': ['tsvgm']}}, 05:03:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:03:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:03:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:03:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:03:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:03:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:03:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:03:24 INFO - 'talos_options': ['--disable-e10s'], 05:03:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:03:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:03:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:03:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:03:24 INFO - 'talos_options': ['--disable-e10s'], 05:03:24 INFO - 'tests': ['damp', 'tps']}, 05:03:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:03:24 INFO - 'tests': ['damp', 'tps']}, 05:03:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:03:24 INFO - 'tests': ['dromaeo_dom']}, 05:03:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:03:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:03:24 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:03:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:03:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:03:24 INFO - 'tests': ['a11yr', 05:03:24 INFO - 'ts_paint', 05:03:24 INFO - 'tpaint', 05:03:24 INFO - 'sessionrestore', 05:03:24 INFO - 'sessionrestore_no_auto_restore', 05:03:24 INFO - 'tabpaint']}, 05:03:24 INFO - 'other-e10s': {'tests': ['a11yr', 05:03:24 INFO - 'ts_paint', 05:03:24 INFO - 'tpaint', 05:03:24 INFO - 'sessionrestore', 05:03:24 INFO - 'sessionrestore_no_auto_restore', 05:03:24 INFO - 'tabpaint']}, 05:03:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:03:24 INFO - 'ts_paint', 05:03:24 INFO - 'tpaint', 05:03:24 INFO - 'sessionrestore', 05:03:24 INFO - 'sessionrestore_no_auto_restore', 05:03:24 INFO - 'tabpaint']}, 05:03:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:03:24 INFO - 'ts_paint', 05:03:24 INFO - 'tpaint', 05:03:24 INFO - 'sessionrestore', 05:03:24 INFO - 'sessionrestore_no_auto_restore', 05:03:24 INFO - 'tabpaint']}, 05:03:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:03:24 INFO - 'tests': ['a11yr', 05:03:24 INFO - 'ts_paint', 05:03:24 INFO - 'tpaint', 05:03:24 INFO - 'sessionrestore', 05:03:24 INFO - 'sessionrestore_no_auto_restore', 05:03:24 INFO - 'tabpaint']}, 05:03:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:03:24 INFO - 'tests': ['a11yr', 05:03:24 INFO - 'ts_paint', 05:03:24 INFO - 'tpaint', 05:03:24 INFO - 'sessionrestore', 05:03:24 INFO - 'sessionrestore_no_auto_restore', 05:03:24 INFO - 'tabpaint']}, 05:03:24 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 05:03:24 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 05:03:24 INFO - 'bloom_basic_ref']}, 05:03:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:03:24 INFO - 'tests': ['tsvgx', 05:03:24 INFO - 'tsvgr_opacity', 05:03:24 INFO - 'tart', 05:03:24 INFO - 'tscrollx', 05:03:24 INFO - 'cart', 05:03:24 INFO - 'tsvg_static']}, 05:03:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:03:24 INFO - 'tsvgr_opacity', 05:03:24 INFO - 'tart', 05:03:24 INFO - 'tscrollx', 05:03:24 INFO - 'cart', 05:03:24 INFO - 'tsvg_static']}, 05:03:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:03:24 INFO - 'talos_options': ['--disable-e10s'], 05:03:24 INFO - 'tests': ['tp5o']}, 05:03:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:03:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:03:24 INFO - 'talos_options': ['--disable-e10s', 05:03:24 INFO - '--xperf_path', 05:03:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:03:24 INFO - 'tests': ['tp5n']}, 05:03:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:03:24 INFO - 'talos_options': ['--xperf_path', 05:03:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:03:24 INFO - 'tests': ['tp5n']}}, 05:03:24 INFO - 'talos.zip': {'path': '', 05:03:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:03:24 INFO - Running post-action listener: _resource_record_post_action 05:03:24 INFO - [mozharness: 2017-05-16 12:03:24.631358Z] Finished populate-webroot step (success) 05:03:24 INFO - [mozharness: 2017-05-16 12:03:24.631457Z] Running create-virtualenv step. 05:03:24 INFO - Running pre-action listener: _resource_record_pre_action 05:03:24 INFO - Running main action method: create_virtualenv 05:03:24 INFO - Creating virtualenv /builds/slave/test/build/venv 05:03:24 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 05:03:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:03:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:03:24 INFO - Using real prefix '/tools/python27' 05:03:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:03:25 INFO - Installing distribute.............................................................................................................................................................................................done. 05:03:28 INFO - Installing pip.................done. 05:03:28 INFO - Return code: 0 05:03:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:03:28 INFO - Reading from file tmpfile_stdout 05:03:28 INFO - Output received: 05:03:28 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) 05:03:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d896768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf2b47cb40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a2d30>, '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 0x10d556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d597be8>, '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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 05:03:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rDKAYbI2RU/Render', 05:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:28 INFO - 'HOME': '/Users/cltbld', 05:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:28 INFO - 'LOGNAME': 'cltbld', 05:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:28 INFO - 'MOZ_NO_REMOTE': '1', 05:03:28 INFO - 'NO_EM_RESTART': '1', 05:03:28 INFO - 'PAGER': '/bin/cat', 05:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:28 INFO - 'PWD': '/builds/slave/test', 05:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:28 INFO - 'SHELL': '/bin/bash', 05:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ebb2os4jb7/Listeners', 05:03:28 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 05:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:28 INFO - 'USER': 'cltbld', 05:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:28 INFO - 'XPC_FLAGS': '0x0', 05:03:28 INFO - 'XPC_SERVICE_NAME': '0', 05:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:28 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 05:03:28 INFO - Cleaning up... 05:03:28 INFO - Return code: 0 05:03:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d896768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf2b47cb40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a2d30>, '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 0x10d556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d597be8>, '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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 05:03:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rDKAYbI2RU/Render', 05:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:28 INFO - 'HOME': '/Users/cltbld', 05:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:28 INFO - 'LOGNAME': 'cltbld', 05:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:28 INFO - 'MOZ_NO_REMOTE': '1', 05:03:28 INFO - 'NO_EM_RESTART': '1', 05:03:28 INFO - 'PAGER': '/bin/cat', 05:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:28 INFO - 'PWD': '/builds/slave/test', 05:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:28 INFO - 'SHELL': '/bin/bash', 05:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ebb2os4jb7/Listeners', 05:03:28 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 05:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:28 INFO - 'USER': 'cltbld', 05:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:28 INFO - 'XPC_FLAGS': '0x0', 05:03:28 INFO - 'XPC_SERVICE_NAME': '0', 05:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:29 INFO - Downloading/unpacking psutil>=3.1.1 05:03:29 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 05:03:29 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 05:03:29 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 05:03:29 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 05:03:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:03:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:03:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:03:31 INFO - Installing collected packages: psutil 05:03:31 INFO - Running setup.py install for psutil 05:03:31 INFO - building 'psutil._psutil_osx' extension 05:03:31 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 05:03:31 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 05:03:31 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 05:03:31 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 05:03:31 INFO - building 'psutil._psutil_posix' extension 05:03:31 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 05:03:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:03:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:03:32 INFO - ^ 05:03:32 INFO - 1 warning generated. 05:03:32 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 05:03:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:03:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:03:32 INFO - Successfully installed psutil 05:03:32 INFO - Cleaning up... 05:03:32 INFO - Return code: 0 05:03:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:03: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')]} 05:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:33 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')]} 05:03:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:33 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 0x10d892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d896768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf2b47cb40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a2d30>, '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 0x10d556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d597be8>, '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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 05:03:33 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 05:03:33 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 05:03:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rDKAYbI2RU/Render', 05:03:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:33 INFO - 'HOME': '/Users/cltbld', 05:03:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:33 INFO - 'LOGNAME': 'cltbld', 05:03:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:33 INFO - 'MOZ_NO_REMOTE': '1', 05:03:33 INFO - 'NO_EM_RESTART': '1', 05:03:33 INFO - 'PAGER': '/bin/cat', 05:03:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:33 INFO - 'PWD': '/builds/slave/test', 05:03:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:33 INFO - 'SHELL': '/bin/bash', 05:03:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ebb2os4jb7/Listeners', 05:03:33 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 05:03:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:33 INFO - 'USER': 'cltbld', 05:03:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:33 INFO - 'XPC_FLAGS': '0x0', 05:03:33 INFO - 'XPC_SERVICE_NAME': '0', 05:03:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:03:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:03:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:03:35 INFO - Installing collected packages: mozsystemmonitor 05:03:35 INFO - Running setup.py install for mozsystemmonitor 05:03:35 INFO - Successfully installed mozsystemmonitor 05:03:35 INFO - Cleaning up... 05:03:35 INFO - Return code: 0 05:03:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:35 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 0x10d892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d896768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf2b47cb40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a2d30>, '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 0x10d556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d597be8>, '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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 05:03:35 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 05:03:35 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 05:03:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rDKAYbI2RU/Render', 05:03:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:35 INFO - 'HOME': '/Users/cltbld', 05:03:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:35 INFO - 'LOGNAME': 'cltbld', 05:03:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:35 INFO - 'MOZ_NO_REMOTE': '1', 05:03:35 INFO - 'NO_EM_RESTART': '1', 05:03:35 INFO - 'PAGER': '/bin/cat', 05:03:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:35 INFO - 'PWD': '/builds/slave/test', 05:03:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:35 INFO - 'SHELL': '/bin/bash', 05:03:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ebb2os4jb7/Listeners', 05:03:35 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 05:03:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:35 INFO - 'USER': 'cltbld', 05:03:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:35 INFO - 'XPC_FLAGS': '0x0', 05:03:35 INFO - 'XPC_SERVICE_NAME': '0', 05:03:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:36 INFO - Downloading/unpacking jsonschema==2.5.1 05:03:36 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 05:03:36 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 05:03:36 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 05:03:36 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 05:03:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:03:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:03:37 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 05:03:37 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 05:03:37 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 05:03:37 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 05:03:37 INFO - Downloading functools32-3.2.3-2.tar.gz 05:03:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:03:37 INFO - warning: no files found matching '*.txt' 05:03:37 INFO - no previously-included directories found matching 'build' 05:03:37 INFO - no previously-included directories found matching 'dist' 05:03:37 INFO - no previously-included directories found matching '.git*' 05:03:37 INFO - Installing collected packages: jsonschema, functools32 05:03:37 INFO - Running setup.py install for functools32 05:03:37 INFO - warning: no files found matching '*.txt' 05:03:37 INFO - no previously-included directories found matching 'build' 05:03:37 INFO - no previously-included directories found matching 'dist' 05:03:37 INFO - no previously-included directories found matching '.git*' 05:03:38 INFO - Successfully installed jsonschema functools32 05:03:38 INFO - Cleaning up... 05:03:38 INFO - Return code: 0 05:03:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:03:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:38 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 0x10d892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d896768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf2b47cb40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a2d30>, '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 0x10d556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d597be8>, '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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 05:03:38 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 05:03:38 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 05:03:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rDKAYbI2RU/Render', 05:03:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:38 INFO - 'HOME': '/Users/cltbld', 05:03:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:38 INFO - 'LOGNAME': 'cltbld', 05:03:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:38 INFO - 'MOZ_NO_REMOTE': '1', 05:03:38 INFO - 'NO_EM_RESTART': '1', 05:03:38 INFO - 'PAGER': '/bin/cat', 05:03:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:38 INFO - 'PWD': '/builds/slave/test', 05:03:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:38 INFO - 'SHELL': '/bin/bash', 05:03:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ebb2os4jb7/Listeners', 05:03:38 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 05:03:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:38 INFO - 'USER': 'cltbld', 05:03:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:38 INFO - 'XPC_FLAGS': '0x0', 05:03:38 INFO - 'XPC_SERVICE_NAME': '0', 05:03:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:03:38 INFO - Cleaning up... 05:03:38 INFO - Return code: 0 05:03:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:03:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:38 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 0x10d892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d896768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf2b47cb40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a2d30>, '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 0x10d556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d597be8>, '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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 05:03:38 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 05:03:38 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 05:03:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rDKAYbI2RU/Render', 05:03:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:38 INFO - 'HOME': '/Users/cltbld', 05:03:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:38 INFO - 'LOGNAME': 'cltbld', 05:03:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:38 INFO - 'MOZ_NO_REMOTE': '1', 05:03:38 INFO - 'NO_EM_RESTART': '1', 05:03:38 INFO - 'PAGER': '/bin/cat', 05:03:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:38 INFO - 'PWD': '/builds/slave/test', 05:03:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:38 INFO - 'SHELL': '/bin/bash', 05:03:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ebb2os4jb7/Listeners', 05:03:38 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 05:03:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:38 INFO - 'USER': 'cltbld', 05:03:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:38 INFO - 'XPC_FLAGS': '0x0', 05:03:38 INFO - 'XPC_SERVICE_NAME': '0', 05:03:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:38 INFO - Downloading/unpacking blobuploader==1.2.4 05:03:38 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 05:03:38 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 05:03:38 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 05:03:38 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 05:03:39 INFO - Downloading blobuploader-1.2.4.tar.gz 05:03:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:03:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:03:40 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 05:03:40 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 05:03:40 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 05:03:40 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 05:03:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:03:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:03:40 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 05:03:40 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 05:03:40 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 05:03:40 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 05:03:40 INFO - Downloading docopt-0.6.1.tar.gz 05:03:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:03:40 INFO - Installing collected packages: blobuploader, requests, docopt 05:03:40 INFO - Running setup.py install for blobuploader 05:03:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:03:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:03:41 INFO - Running setup.py install for requests 05:03:41 INFO - Running setup.py install for docopt 05:03:41 INFO - Successfully installed blobuploader requests docopt 05:03:41 INFO - Cleaning up... 05:03:41 INFO - Return code: 0 05:03:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:03:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:41 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 0x10d892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d896768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf2b47cb40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a2d30>, '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 0x10d556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d597be8>, '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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 05:03:41 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 05:03:41 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 05:03:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rDKAYbI2RU/Render', 05:03:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:41 INFO - 'HOME': '/Users/cltbld', 05:03:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:41 INFO - 'LOGNAME': 'cltbld', 05:03:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:41 INFO - 'MOZ_NO_REMOTE': '1', 05:03:41 INFO - 'NO_EM_RESTART': '1', 05:03:41 INFO - 'PAGER': '/bin/cat', 05:03:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:41 INFO - 'PWD': '/builds/slave/test', 05:03:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:41 INFO - 'SHELL': '/bin/bash', 05:03:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ebb2os4jb7/Listeners', 05:03:41 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 05:03:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:41 INFO - 'USER': 'cltbld', 05:03:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:41 INFO - 'XPC_FLAGS': '0x0', 05:03:41 INFO - 'XPC_SERVICE_NAME': '0', 05:03:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:03:42 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-wBKdeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:03:42 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-22DVub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:03:42 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-mtEYAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:03:42 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-QylX4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:03:42 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-nvE3JG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:03:42 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-uyWI6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:03:42 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-e4WLul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:03:42 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-LkGJZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:03:42 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-xLQ0QK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:03:43 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-CUXKjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:03:43 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-uWTbUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:03:43 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-fF3bfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:03:43 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-FKx958-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:03:43 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-EJl7TT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:03:43 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-gYhArN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:03:43 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-Fl1gul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:03:43 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-3uh1l4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:03:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:03:43 INFO - Running setup.py install for manifestparser 05:03:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:03:43 INFO - Running setup.py install for mozcrash 05:03:44 INFO - Running setup.py install for mozdebug 05:03:44 INFO - Running setup.py install for mozdevice 05:03:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:03:44 INFO - Running setup.py install for mozfile 05:03:44 INFO - Running setup.py install for mozhttpd 05:03:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:03:44 INFO - Running setup.py install for mozinfo 05:03:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:03:44 INFO - Running setup.py install for mozInstall 05:03:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:03:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:03:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:03:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:03:44 INFO - Running setup.py install for mozleak 05:03:45 INFO - Running setup.py install for mozlog 05:03:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:03:45 INFO - Running setup.py install for moznetwork 05:03:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:03:45 INFO - Running setup.py install for mozprocess 05:03:45 INFO - Running setup.py install for mozprofile 05:03:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:03:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:03:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:03:45 INFO - Running setup.py install for mozrunner 05:03:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:03:45 INFO - Running setup.py install for mozscreenshot 05:03:46 INFO - Running setup.py install for moztest 05:03:46 INFO - Running setup.py install for mozversion 05:03:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:03:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:03:46 INFO - Cleaning up... 05:03:46 INFO - Return code: 0 05:03:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:46 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 0x10d892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d896768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf2b47cb40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a2d30>, '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 0x10d556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d597be8>, '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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 05:03:46 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 05:03:46 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 05:03:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rDKAYbI2RU/Render', 05:03:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:46 INFO - 'HOME': '/Users/cltbld', 05:03:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:46 INFO - 'LOGNAME': 'cltbld', 05:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:46 INFO - 'MOZ_NO_REMOTE': '1', 05:03:46 INFO - 'NO_EM_RESTART': '1', 05:03:46 INFO - 'PAGER': '/bin/cat', 05:03:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:46 INFO - 'PWD': '/builds/slave/test', 05:03:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:46 INFO - 'SHELL': '/bin/bash', 05:03:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ebb2os4jb7/Listeners', 05:03:46 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 05:03:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:46 INFO - 'USER': 'cltbld', 05:03:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:46 INFO - 'XPC_FLAGS': '0x0', 05:03:46 INFO - 'XPC_SERVICE_NAME': '0', 05:03:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:03:46 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-_d6ldm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:03:46 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)) 05:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:03:46 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-znWA8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:03:46 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)) 05:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:03:46 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-GtOrb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:03:46 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)) 05:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:03:46 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-aJU1X1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:03:47 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)) 05:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:03:47 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-SEFyZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:03:47 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)) 05:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:03:47 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-FqzaBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:03:47 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)) 05:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:03:47 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-BvCffY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:03:47 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)) 05:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:03:47 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-sC0oRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:03:47 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)) 05:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:03:47 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-gy6rEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:03:47 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)) 05:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:03:47 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-2xoS3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:03:47 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)) 05:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:03:47 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-1RPZGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:03:47 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)) 05:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:03:47 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-9ceu7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:03:47 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)) 05:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:03:47 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-tQEDNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:03:48 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)) 05:03:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:03:48 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-2tsdKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:03:48 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)) 05:03:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:03:48 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-ZPOQJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:03:48 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)) 05:03:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:03:48 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-e5fUJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:03:48 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)) 05:03:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:03:48 INFO - Running setup.py (path:/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/pip-IfrHpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:03:48 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)) 05:03:48 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)) 05:03:48 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)) 05:03:48 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)) 05:03:48 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)) 05:03:48 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)) 05:03:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:03:48 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 05:03:48 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 05:03:48 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 05:03:48 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 05:03:49 INFO - Downloading blessings-1.6.tar.gz 05:03:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:03:49 INFO - Installing collected packages: blessings 05:03:49 INFO - Running setup.py install for blessings 05:03:50 INFO - Successfully installed blessings 05:03:50 INFO - Cleaning up... 05:03:50 INFO - Return code: 0 05:03:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:03:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:03:50 INFO - Reading from file tmpfile_stdout 05:03:50 INFO - Current package versions: 05:03:50 INFO - blessings == 1.6 05:03:50 INFO - blobuploader == 1.2.4 05:03:50 INFO - docopt == 0.6.1 05:03:50 INFO - functools32 == 3.2.3-2 05:03:50 INFO - jsonschema == 2.5.1 05:03:50 INFO - manifestparser == 1.1 05:03:50 INFO - mozInstall == 1.12 05:03:50 INFO - mozcrash == 1.0 05:03:50 INFO - mozdebug == 0.1 05:03:50 INFO - mozdevice == 0.50 05:03:50 INFO - mozfile == 1.2 05:03:50 INFO - mozhttpd == 0.7 05:03:50 INFO - mozinfo == 0.9 05:03:50 INFO - mozleak == 0.1 05:03:50 INFO - mozlog == 3.4 05:03:50 INFO - moznetwork == 0.27 05:03:50 INFO - mozprocess == 0.25 05:03:50 INFO - mozprofile == 0.28 05:03:50 INFO - mozrunner == 6.13 05:03:50 INFO - mozscreenshot == 0.1 05:03:50 INFO - mozsystemmonitor == 0.3 05:03:50 INFO - moztest == 0.8 05:03:50 INFO - mozversion == 1.4 05:03:50 INFO - psutil == 3.1.1 05:03:50 INFO - requests == 1.2.3 05:03:50 INFO - wsgiref == 0.1.2 05:03:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:50 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 0x10d892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d896768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf2b47cb40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a2d30>, '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 0x10d556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d597be8>, '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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 05:03:50 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 05:03:50 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 05:03:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rDKAYbI2RU/Render', 05:03:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:50 INFO - 'HOME': '/Users/cltbld', 05:03:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:50 INFO - 'LOGNAME': 'cltbld', 05:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:50 INFO - 'MOZ_NO_REMOTE': '1', 05:03:50 INFO - 'NO_EM_RESTART': '1', 05:03:50 INFO - 'PAGER': '/bin/cat', 05:03:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:50 INFO - 'PWD': '/builds/slave/test', 05:03:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:50 INFO - 'SHELL': '/bin/bash', 05:03:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ebb2os4jb7/Listeners', 05:03:50 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 05:03:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:50 INFO - 'USER': 'cltbld', 05:03:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:50 INFO - 'XPC_FLAGS': '0x0', 05:03:50 INFO - 'XPC_SERVICE_NAME': '0', 05:03:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:50 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)) 05:03:50 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)) 05:03:50 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)) 05:03:50 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)) 05:03:50 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)) 05:03:50 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)) 05:03:50 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)) 05:03:50 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)) 05:03:50 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)) 05:03:50 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)) 05:03:50 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)) 05:03:50 INFO - Cleaning up... 05:03:50 INFO - Return code: 0 05:03:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:50 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 0x10d892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d896768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf2b47cb40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a2d30>, '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 0x10d556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d597be8>, '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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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 05:03:50 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 05:03:50 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 05:03:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rDKAYbI2RU/Render', 05:03:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:50 INFO - 'HOME': '/Users/cltbld', 05:03:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:50 INFO - 'LOGNAME': 'cltbld', 05:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:50 INFO - 'MOZ_NO_REMOTE': '1', 05:03:50 INFO - 'NO_EM_RESTART': '1', 05:03:50 INFO - 'PAGER': '/bin/cat', 05:03:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:50 INFO - 'PWD': '/builds/slave/test', 05:03:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:50 INFO - 'SHELL': '/bin/bash', 05:03:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ebb2os4jb7/Listeners', 05:03:50 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 05:03:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:50 INFO - 'USER': 'cltbld', 05:03:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:50 INFO - 'XPC_FLAGS': '0x0', 05:03:50 INFO - 'XPC_SERVICE_NAME': '0', 05:03:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:03:50 INFO - Cleaning up... 05:03:51 INFO - Return code: 0 05:03:51 INFO - Running post-action listener: _resource_record_post_action 05:03:51 INFO - Running post-action listener: _start_resource_monitoring 05:03:51 INFO - Starting resource monitoring. 05:03:51 INFO - [mozharness: 2017-05-16 12:03:51.026811Z] Finished create-virtualenv step (success) 05:03:51 INFO - [mozharness: 2017-05-16 12:03:51.027754Z] Running install step. 05:03:51 INFO - Running pre-action listener: _resource_record_pre_action 05:03:51 INFO - Running main action method: install 05:03:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:03:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:03:51 INFO - Reading from file tmpfile_stdout 05:03:51 INFO - Detecting whether we're running mozinstall >=1.0... 05:03:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:03:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:03:51 INFO - Reading from file tmpfile_stdout 05:03:51 INFO - Output received: 05:03:51 INFO - Usage: mozinstall [options] installer 05:03:51 INFO - Options: 05:03:51 INFO - -h, --help show this help message and exit 05:03:51 INFO - -d DEST, --destination=DEST 05:03:51 INFO - Directory to install application into. [default: 05:03:51 INFO - "/builds/slave/test"] 05:03:51 INFO - --app=APP Application being installed. [default: firefox] 05:03:51 INFO - mkdir: /builds/slave/test/build/application 05:03:51 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'] 05:03:51 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 05:04:12 INFO - Reading from file tmpfile_stdout 05:04:12 INFO - Output received: 05:04:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:04:12 INFO - Running post-action listener: _resource_record_post_action 05:04:12 INFO - [mozharness: 2017-05-16 12:04:12.391721Z] Finished install step (success) 05:04:12 INFO - [mozharness: 2017-05-16 12:04:12.391889Z] Running run-tests step. 05:04:12 INFO - Running pre-action listener: _resource_record_pre_action 05:04:12 INFO - Running main action method: run_tests 05:04:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:04:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:04:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:04:12 INFO - 2017-05-16 05:04:12.406 screenresolution[1625:7516] starting screenresolution argv=screenresolution get 05:04:12 INFO - 2017-05-16 05:04:12.408 screenresolution[1625:7516] Display 0: 1600x1200x32@60 05:04:12 INFO - 2017-05-16 05:04:12.414 screenresolution[1626:7518] starting screenresolution argv=screenresolution list 05:04:12 INFO - Available Modes on Display 0 05:04:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:04:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:04:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:04:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:04:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:04:12 INFO - Intel Iris: 05:04:12 INFO - Chipset Model: Intel Iris 05:04:12 INFO - Type: GPU 05:04:12 INFO - Bus: Built-In 05:04:12 INFO - VRAM (Dynamic, Max): 1536 MB 05:04:12 INFO - Vendor: Intel (0x8086) 05:04:12 INFO - Device ID: 0x0a2e 05:04:12 INFO - Revision ID: 0x0009 05:04:12 INFO - Displays: 05:04:12 INFO - Display: 05:04:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:04:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:04:12 INFO - Display Serial Number: 1600x1200 60 05:04:12 INFO - Main Display: Yes 05:04:12 INFO - Mirror: Off 05:04:12 INFO - Online: Yes 05:04:12 INFO - Rotation: Supported 05:04:12 INFO - Return code: 0 05:04:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:04:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:04:12 INFO - Python 2.7.3 05:04:12 INFO - Return code: 0 05:04:12 INFO - grabbing minidump binary from tooltool 05:04:12 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')]} 05:04:12 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 0x10d5a2d30>, '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 0x10d556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d597be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:04:12 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 05:04:12 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 05:04:12 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 05:04:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:04:12 INFO - Return code: 0 05:04:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:04:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:04:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:04:12 INFO - ENV: RUST_BACKTRACE is now 1 05:04:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:04:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:04:12 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/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0336', '--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 05:04:12 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/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0336 --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 05:04:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rDKAYbI2RU/Render', 05:04:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:12 INFO - 'HOME': '/Users/cltbld', 05:04:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:12 INFO - 'LOGNAME': 'cltbld', 05:04:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:04:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:04:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:12 INFO - 'MOZ_NO_REMOTE': '1', 05:04:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:04:12 INFO - 'NO_EM_RESTART': '1', 05:04:12 INFO - 'PAGER': '/bin/cat', 05:04:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:12 INFO - 'PWD': '/builds/slave/test', 05:04:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:04:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:12 INFO - 'RUST_BACKTRACE': '1', 05:04:12 INFO - 'SHELL': '/bin/bash', 05:04:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ebb2os4jb7/Listeners', 05:04:12 INFO - 'TMPDIR': '/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/', 05:04:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:12 INFO - 'USER': 'cltbld', 05:04:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:12 INFO - 'XPC_FLAGS': '0x0', 05:04:12 INFO - 'XPC_SERVICE_NAME': '0', 05:04:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:12 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/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0336', '--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 05:04:13 INFO - mozversion application_buildid: 20170516033820 05:04:13 INFO - mozversion application_changeset: 49365d675cbb2a8368cda3e4858a2a0d0634c249 05:04:13 INFO - mozversion application_display_name: Nightly 05:04:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:04:13 INFO - mozversion application_name: Firefox 05:04:13 INFO - mozversion application_remotingname: firefox 05:04:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:04:13 INFO - mozversion application_vendor: Mozilla 05:04:13 INFO - mozversion application_version: 55.0a1 05:04:13 INFO - mozversion platform_buildid: 20170516033820 05:04:13 INFO - mozversion platform_changeset: 49365d675cbb2a8368cda3e4858a2a0d0634c249 05:04:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:04:13 INFO - mozversion platform_version: 55.0a1 05:04:13 INFO - using testdate: 1494936253 05:04:13 INFO - actual date: 1494936253 05:04:13 INFO - starting webserver on 'localhost:49227' 05:04:13 INFO - SUITE-START | Running 2 tests 05:04:13 INFO - TEST-START | dromaeo_css 05:04:13 INFO - operating with platform_type : mac_ 05:04:13 INFO - Initialising browser for dromaeo_css test... 05:04:13 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmpEa3KYK/profile http://localhost:49227/getInfo.html) 05:04:13 INFO - PID 1636 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2932: String.localeCompare is deprecated; use String.prototype.localeCompare instead 05:04:15 INFO - PID 1636 | 2017-05-16 05:04:15.372 plugin-container[1638:7685] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9837, name = 'com.apple.tsm.portname' 05:04:15 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:04:15 INFO - PID 1636 | 2017-05-16 05:04:15.375 plugin-container[1638:7685] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 05:04:15 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:04:15 INFO - PID 1636 | 2017-05-16 05:04:15.375 plugin-container[1638:7685] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:04:15 INFO - PID 1636 | __metrics Screen width/height:1600/1200 05:04:15 INFO - PID 1636 | colorDepth:24 05:04:15 INFO - PID 1636 | Browser inner width/height: 1024/691 05:04:15 INFO - PID 1636 | __metrics 05:04:15 INFO - PID 1636 | 05:04:15 INFO - PID 1636 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 05:04:15 INFO - PID 1636 | 05:04:16 INFO - TEST-INFO | 1636: exit 0 05:04:16 INFO - Browser initialized. 05:04:16 INFO - Running cycle 1/1 for dromaeo_css test... 05:04:16 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmpEa3KYK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:04:18 INFO - PID 1640 | 2017-05-16 05:04:18.155 plugin-container[1641:7830] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 05:04:18 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:04:18 INFO - PID 1640 | 2017-05-16 05:04:18.156 plugin-container[1641:7830] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 05:04:18 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:04:18 INFO - PID 1640 | 2017-05-16 05:04:18.157 plugin-container[1641:7830] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:04:18 INFO - PID 1640 | 2017-05-16 05:04:18.891 plugin-container[1642:7880] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 05:04:18 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:04:18 INFO - PID 1640 | 2017-05-16 05:04:18.892 plugin-container[1642:7880] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 05:04:18 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:04:18 INFO - PID 1640 | 2017-05-16 05:04:18.892 plugin-container[1642:7880] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:04:28 INFO - PID 1640 | RSS: Main: 234356736 05:04:28 INFO - PID 1640 | 05:07:32 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 05:07:33 INFO - PID 1640 | RSS: Main: 258973696 05:07:33 INFO - PID 1640 | 05:10:35 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 05:10:36 INFO - PID 1640 | RSS: Main: 265510912 05:10:36 INFO - PID 1640 | 05:13:37 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 05:13:38 INFO - PID 1640 | RSS: Main: 269082624 05:13:38 INFO - PID 1640 | 05:16:40 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 05:16:40 INFO - PID 1640 | RSS: Main: 267354112 05:16:40 INFO - PID 1640 | 05:19:43 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 05:19:44 INFO - PID 1640 | RSS: Main: 268652544 05:19:44 INFO - PID 1640 | 05:22:46 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 05:22:46 INFO - PID 1640 | RSS: Main: 270008320 05:22:46 INFO - PID 1640 | 05:22:46 INFO - PID 1640 | __start_tp_report 05:22:46 INFO - PID 1640 | _x_x_mozilla_page_load 05:22:46 INFO - PID 1640 | _x_x_mozilla_page_load_details 05:22:46 INFO - PID 1640 | |i|pagename|runs| 05:22:46 INFO - PID 1640 | |0;dojo.html;3148.1972097115245;3232.191952011997;3605.1167463971333;3623.9637603623987;3640.035390621484;5418.783248670054;5939.703014849257;6148.200733904598;6236.968815155918;6248.906266406002;6166.969165154179;6171.783987560436;6180.660063696495;6221.40896614822;6255.468285195764;5460.290162278918;5465.289512363387;5466.508014278724;5477.561795056386;5484.341878974528;5516.034693928566;5562.193481945115;5580.441955804427;5587.608867379285;5603.691796951166;5467.371252305996;5468.371137319219;5482.451754824526;5532.059549876531;5562.805301814437;4575.153596584642;5471.534919531845;5489.725513724298;5506.201600767866;5507.311586051744;5433.782648694048;5436.945630543683;5451.890962180773;5489.011977843987;5490.450954904518;5093.770780314895;6136.335271946702;6222.284437289724;6246.281677607042;6251;6604.141461610008;6658.400743933021;6684.131062961833;6695.966520167368;6697.162854643169;6779.525433219676;6803.183617965876;6804.945233488817;6807.353301436356;6816.4546836252985;6127.372786728385;6319.715612797343;6327.620342779448;6335.493160547191;6353.809385718436;6740.090087838177;6779.661016949133;6847.246802851682;6859.5541289816;6942.791716248521;6558.868822623521;6602.60384376939;6622.569532980341;6625.701843417058;6631;6515.055316979012;6714.9664251679405;6774.45804335652;6788.219354774165;6789.864202716017;6518.511111666644;6534.313897040837;6568.6058836469965;6616.206055273398;6661.800145995628;6684.064231007663;6695.799126026227;6707.262201157869;6708.6310252936555;6721.193456785217;5295.391030031518;5304.389995150607;5324.946750532445;5331.1470164773455;5366.356037275552;3927.3323534999117;3969.2656858481273;4705.694129881546;4714.976425117921;4723.7874295656775;6377.8724425512155;6393.552451328455;6404.551681382259;6418.711157997901;6426.1003459515705;6845.863082738318;6853.17761868569;6856.108705868305;6869.416099631588;6870.20992585849;6286.779962701375;6309.589876658002;6358.713857876407;6425.453836423956;6426.967865160645;5843.678597677169;5879.294484661782;5897.351291357947;5956;6051.667158306335;6967.756128535477;6995.370416662525;7010.929890701028;7021.789346319619;7039.859202815813;6791.592504449749;6833;6857.485688573477;6866.34769696878;6879.174499060145;5814.244148260699;6687.297833727389;6986.056882320821;7041.718331266692;7048.330408611174;5604.383517812956;5869.06095024794;6473.611583305017;6510.218773746991;6589.769358072444;6774.153230846145;6808.863822723617;6815.761448349284;6827.726890924308;6832.145981752281;6567.44176744982;6570.47436205112;6585.242697089896;6585.604863708193;6590.9670451647435;6372.107904893226;6454.870902582016;6507.28419873823;6546.738130474728;6601.438877695449;5443.482869127427;6281.7173227203975;6411.230652321751;6447.6453795041725;6498.155239818793;6399.296077431572;6497.220333560003;6544.149260596092;6545.5090868184125;6581.901271480836;6341.8731625368155;6362.459190968634;6382.074599183;6393.552451328363;6398.072279519537 05:22:46 INFO - PID 1640 | |1;ext.html;732.644667336342;779.6569509415857;822.494166087856;1210.1710328425029;1267.391652007036;38506.229875402474;39374;39402.80298598503;39409.80295098524;39419.0145246369;39828;40158.19683606326;40178.19643607126;40204.99487512813;40304.59695403045;7867.370610351172;8164.836703265931;8383.455075420108;8584.914150858505;8589.055203927563;8551.444156129832;8612.48325100493;8630.439021463617;8663.350248731338;8671.739847804576;8590.78523036926;8672.436291641023;8680.8697869532;8687.305015598737;8691.130886911289;7331.450141239428;8623.827523449527;8631.007434145027;8648.048714641385;8702.651893924236;8301.086880443147;8334.041585217656;8368.665253389858;8410.537420441873;8434.662613495453;29582.112536623936;37228.813855930544;37541;37612;37761.622383776084;3637.4543818427446;3658.579263384718;3672.4491326301268;3674.5223120994365;3678.834452449619;24717.5056498869;25395.87302063478;26095.869520652464;27671.723282767318;30630.38739225203;3079.8614062367246;3123.9375212495625;3165.4776961801276;3165.968340316613;3168.413843438971;273704;294905;299209.99999999785;299945.5002724994;308626;47226.76386618045;47422.76288618535;47440.7627961858;47482.76258618685;47584.81037974009;48486.5151348482;48678.26982595264;48994.755026224644;49059.26411103836;49165.508344916096;12818.55135070268;14295.356034056009;14377.78433323501;14410.783838242434;14420.278986050656;10969.396683182342;11573.594924177782;11703.122265830098;11730.824037639442;11736.002439792543;13145.60563183106;13267.004974626749;13283.601491955256;13295.268760218281;13360.59918202436;5100.770465329069;5964.671943043088;5997.340292567814;6035.758569657252;6058.787942421994;1013.5287091502339;1030.3457304611625;1037.190991027;1062.3413483640193;1071.0895738622264;22922.31233063011;23443.648345274836;24271.665058421957;24659.630105548433;25000.624990625154;1035.7100011996654;1072.830614630057;1097.6158344579494;1115.570505355444;1120.38938778366;12077.758444831054;12650.493980240872;12753.744925101446;12764.297963611905;12808.871911280954;315760;317772;319061;319975;321755;53354.19968700472;53661.1950820738;53724.73137634287;53749.1937620936;53826.19260711093;1331.17467170355;1481.1335368809152;1497.6854860479204;1537.1699282387608;1544.9768253476207;20040.599188015865;24456.144034958692;24458.388540286636;24472.388190295384;24582.63126053075;38189;38432.03919901913;38469.42295865508;38496.6150338493;38599;278649;281623;282172;282191;283574;34177.65822341745;34714.13214669654;36667;36709.898703038954;36745.08137296589;43031.13937721291;44845;46742.831429214544;46781;46881.06237875292;26007.349816254744;26328.736712632628;28853.7114628851;29317.973870914415;29903;30610.387792243586;31418.842905785325;31460.528092079094;31555.684443155274;31598.52602210923;37048;37316.813415932746;37935.62064379431;37990.81004594959;38037.239255215296;30936;31666;31862.84068579735;32081.518777218826;32248.83875580607;22137.778622213573;23092.191773287854;24074;24099;24462.388440289134 05:22:46 INFO - PID 1640 | |2;jquery.html;388.79976811941845;419.98740037798876;427.32909332348225;439.48141193391797;452.7374123008655;18607.25570977161;19748.913809740458;21340.4664883378;22147.778522214772;22204.55590888181;18429.907850460728;22665.660015099744;23024.769752302473;23035.884820575873;23047.542868569722;5463.945360546383;5928.199693041449;5955.689748255377;6112.297085835142;6175.814725558241;5418.916216756645;5430.484104010113;5622.465865742768;5716.1997320375185;6043.184170137041;6039.969800151015;6083.908741368883;6098.32918378978;6100.359462256466;6110.602810817282;5950.613210141349;6017.939820601781;6048.546359023091;6067.393260673897;6086.513078953673;5248.947510524922;5429.239906413105;5430.3755068167275;5436.157395603688;5445.019896418643;26560.86719566409;26732.33169170767;27064.59403108955;27239.182824515297;27316.45367092647;2170.565886822626;2259.164109279577;2260.08466571039;2291.1866287467874;2292.56441276157;17781.555461113574;18025.188866501037;18038.458846234633;18076;18274.45176644703;2062.8659137156037;2158.5143342747915;2195.5169862630196;2199.1533259694975;2202.9559408811892;142786.28606857004;164000.17999910042;173668;174262.99999999872;175648;12678.556250531186;12681.970884658595;12856.871431285754;12938.223706577635;13055.151415158174;16768.245428955724;16776.83223167777;16779.748303775454;16781.328746850235;16800.411985580446;8727.825443491094;9071.138241867013;9098.772530686785;9166.220871225887;9209.401388909699;8620.353473489387;8626.5255410953;8688.174623410765;8780.297576193889;8815.691450799191;7932.881006784903;7968.920310796818;7989.960050199828;8043.959780201061;8088.352931765445;1934.7001214811726;2137.935861924114;2191.9780802192095;2204.889755512218;2374.9244728077183;820.6512232301368;973.4354074636693;988.1650005745134;1006.8137394582026;1041.073444634276;18462.81537184638;20850.58298834015;21630;21670.133194672035;21693.457663558223;913.4108500017452;973.2360097323567;1006.6677996261217;1028.573142146001;1039.3555995282823;10980.341179529254;11235.932586404278;11306.491207895664;11306.94346528262;11329.376884271443;513955;535574;541091;541341;541724;129072;134575;138388;139444.30277848546;140298;1178.8408564843808;1191.737817680109;1224.989383758403;1226.576830993288;1230.7907655698552;22161;22443.990020449444;23084.653730194397;23482.530349392575;23495.88252058729;43026;47961.8009549764;57708.84582308414;58083.12875306789;58467.415325846196;77900.8314875266;78784;79701;80299.59850200711;80387;7535.359494443033;8802.779930501789;8980.595873185592;9003.954980225057;9079.773005674911;10620.575176992834;13728.725425491633;13960.162390256617;14421.92789036048;14556.708865822837;12147.453364598437;12147.81778273309;12294.569690060805;12309.138360314691;12336.81494777597;11147.331160130418;11461.312321260752;11465.713357166138;11723.589674361698;11730.530778769094;11993.880061199625;12179.451924663234;12221.633350999484;12453.25280483174;12513.31176785249;8897.555122243732;9033.141851524095;9097.545122743968;9126.726198214064;9143.314251431302;11888.405579721153;12023.75952480928;12121.818172727593;12152.453139608562;12256.509739610316 05:22:46 INFO - PID 1640 | |3;mootools.html;1300.3433266200568;1338.049984510997;1420.9147451152933;1480.9925950370246;1501.9173945433004;11747.882521174786;11976.700582485442;12220.266783992956;12231.327276999762;12237.694057648563;11961.700957476067;11979.880201197986;12172.513099476033;12180.51277948881;12219.144659873813;2567.910123145695;2597.0910181436534;2606.895724171031;2610.1125617290118;2610.4909542639193;2519.496100779842;2566.679165104362;2580.9741902581018;2581.354661334666;2586;2538.5938249880023;2561.2188282573807;2591.6889973203242;2595.4679290745353;2599.8310109842896;2204.4458656647025;2521.382261345973;2547.477767057758;2569.100814714844;2570.8586027768465;2485.465624890657;2486.8880900359527;2549.6048112542585;2577.574700174469;2582.9483410331686;8826.514541700204;11783;11785.587504437304;11789.05687545003;11809.350485723282;6580.407763301278;6722.226943901465;6784.76253331136;6802.2857599951785;6817.625030623314;3708.740388172829;3931.783751893645;3967.2263908537557;4056.290149223874;4248.214080395106;1090.3457925244868;1223.0582451512287;1245.9501619935193;1253.7555176740539;1285.3123578885252;16109.83890161095;16449.67100657992;16555.751663725056;16692.41576544815;16714.16429178536;5504.201890725903;5581.079121944915;5719.456651618089;5736.598438109376;5747.942520574741;10444.798848132563;11548.59579914699;11588.478518466689;11732;11745;6275.435210831047;6491.285958544557;6569.277379488252;6580.309067547934;6587.802365928934;5882.2073866588435;6206.286277078104;6303.905441418382;6306.400891915261;6314.494840412756;4260.786960651955;4394.187075390999;4411.316245981878;4418.955810441918;4478.081993191388;1259.4962015193996;1281.9102662813514;1286.8713128687057;1299.0516922646332;1300.0379719007867;786.9921300787033;789.4881302306135;808.1110778144113;810.1938571121772;831.966721331152;6116.143739876421;6142.201513803267;6314.494840412756;6360.52296077796;6394.424501794768;585.6778771675562;602.524006035236;603.0652488642586;605.2283338743138;619.8729260501586;3935.2129574085297;4179.331306990867;4193.643540299047;4247.405363249148;4272.91454170921;14355.712885742227;15084.24578771057;15113.3199006045;15151.924240378949;15155.696886062216;14480.986330956732;14798.112113273239;15033.548993530212;15169.165695886832;15183.392664293304;8325.542095184703;8572.35707321941;8739;8747.956260218658;8839.955800220958;6954.095967524189;7014.298570142945;7171.964140179266;7187.676554554953;7335.266473352622;13237.602871913858;13357.732845342844;13681.726365472834;13766.449342026206;13774;13793.931030344784;13794;13887.444502219798;13901.026928114934;14231.07498012626;9315.441073535609;9315.580798864203;9464.19554337889;9467.763305917408;9471.005544418013;10365.896341036794;10513.421761803174;10637.521311540844;10671.679849604525;10679.412632304988;8975.506347150707;8975.775605609912;9160.862587061332;9233.215176710055;9288.907110928803;8665.393422460367;8794.164554367326;8897.555122243992;8917.331200159884;9070.90929090727;8117.228863257981;8126.187381261827;8148.551829649189;8210.220029097227;8247.257746802816;6000.119942427572;6042.3655516170165;6103.664298463627;6123.540734445023;6143.139960405636;5477.780888764405;5979.790707325223;5998.70006499682;6155.1075094112;6178.320384757763 05:22:46 INFO - PID 1640 | |4;prototype.html;326.54610091972165;394.6605918909737;398.9421533877586;404.1331344266548;406.40664629640725;6206.441420272175;6228.719707613157;6368;6371.936280637192;6550.5414620976435;4895.902081958358;5192.922106168401;5918.4673379395845;6186.907196392058;6392.529718164825;4342.3065004648215;4509.301058335946;4595.402597662299;4595.839145629895;4632.652551058667;4200.789960501978;4629.513901040393;4640.698354606957;4644.164050470914;4656.743879086649;4021;4073.8981525461954;4542.772861356919;4594.793234304448;4625.5836974672275;4481.282994720846;4507.594316511513;4549.31760235964;4579.931301030487;4613.492515823256;3036.620422447194;3893.0851249956236;3894.357431023878;3914;3921.725479216456;5777.804434314976;5868.178455016301;6219.1915051043525;6255.624662520263;6283.183186185766;4551.590356867898;5145.897082058338;5271.393789714193;5508.724563771835;5529.502344788988;5549.861253468655;6752.189737231514;6779.796606101825;6801.591904485746;6816.727330906758;4323.373110898933;4448.621867141297;4462.3752674625575;4463.709858859163;4482.0249523029415;7023.911293749477;7082.185548661867;7181.599588080327;7263.745768898063;7275.708971641075;4194.433751443577;4980.551750342486;5087.8982420351385;5140.434552199255;5157.767900444489;5253.317068781034;5272.78908843642;5274.26160337553;5291.814786482531;5298.947010529922;5589.441055894378;5738.885222295531;5817.563682723812;5871.706414679249;5874.5887787854435;5641.323041235078;6445.452136568444;6908.100272425123;6913.861722765517;6945.201301850251;1420.872121509069;1541.7070756556218;1745.3367720266217;1769.2922830867756;1785.9374921877672;1029.552144817007;1048.951048951049;1163.6043745126697;1188.7028242939266;1247.3638444393425;5594.552435805126;5625.662460252398;5998.3701711320555;6409.230892292955;6543;6212.347703491068;7037.331453511908;7068.893966590505;7097.645117744092;7201.603911784798;5832.88334233313;5957.851053723692;6285.182926219562;6423.967880160662;6456.160699109086;3179.2687681832845;3457.9827100864336;3559.715222782223;3698.4082546792383;3698.4267438547067;6953.443724502028;7051.54164979285;7117.217106118323;7290.562566245936;7316.341529262392;6713.429358504484;7295.4528410368375;7299.087614048244;7300.525465844703;7307.378872795872;6683.298253683488;6973.05863708403;7253.766859633875;7290.927090729131;7352.639761644006;6573.671316434255;7099.893501597377;7149.248434133462;7189.173245076674;7206.351428371472;6108.602940808833;6654;6816.965915170393;6916.481263905127;7045.647717614202;5337.786488540415;6700.296468870698;7190.245024272376;7335.596542190231;7355.2277010913085;3949.5063117110362;4463.129689710475;4542.295944128666;4552.88617784558;4574.4510658721165;5973.611715238496;6276.497880169484;6701.061851340719;6724.159480064992;6919.308069193041;6819.215790184192;6836.402167176441;6842.118417435245;6855.211650660238;6860.862782744217;6460.935390646221;6536.7712130075215;6760.628165450751;6768.566588884289;6793.626350550569;6548.967255163694;6564.409203171737;6659.234188068434;6665.933340666726;6673.1992160941;6648.174525826407;6715.462762978852;6801.489888258499;6802.571459993259;6831;6578.276389597045;6632.933670663425;6634.436072933855;6645.3022432643875;6651.470161862895 05:22:46 INFO - PID 1640 | |5;yui.html;790.6205021589636;823.682882090395;834.5576844272535;855.5038077914807;863.0506442912795;16308.918455407722;16694.499165025038;16747.078910659908;18893;19271.421857344303;17808.1986310616;18188;18337.08314584272;18452.985085820274;18701.90649046753;808.5068108453838;965.7682156282477;976.501983988164;977.0717818072824;977.2572844638055;1003.0871906565027;1004.7689031522755;1006.2754816532085;1013.4729940430972;1015.0103648942296;1055.456439933435;1058.62418841311;1061.8831928487898;1062.3572738493217;1062.5909025025383;955.8709574207462;1033.6847261585212;1061.4798748613161;1065.1798115451136;1085.8045551800712;750.2722359311459;751.7594369801611;758.2417582417582;763.6830715253163;779.9337056350217;16821.990680559207;16890.662186756195;17070;17077.487675369757;17123;17212.655746885117;17293.394731184475;17314.91342543292;17543;17568;14237.50168744089;14264.144151350953;14551.490697825526;14561.708765824624;14567.635809104751;3492.1793378556017;3495.3009398120225;3552.7513074084823;3553.964460355415;3561.216532362876;119147.40426297899;137341.3132934329;137579;137948;138143;13595.252261125535;13673.589792306248;13933.164010159422;13982.091164074303;14139.575812725634;21153.25963591298;24706;25538.489230215662;26211.868940655175;26217.213483595522;7844.607769611496;7971.920280797118;7987.520748755093;8038.236367545074;8050.476719013245;3947.131631041166;3999.0002499375155;4001.579834117434;4002.039510517455;4017.397390391465;4357.411749413852;4384.846530371486;4393.253146965024;4463.665225108065;4467.955320446819;930.6649606141783;942.1520631431766;958;958.9856152157723;960.673371053845;630.2625927664643;638.8211300835774;652.0740548421253;657.5890068707058;670.081987676876;9757.90242097584;10351;11673.824892626617;13153.934230328787;13265.734685306239;608.6287364707613;659.7196191618543;665.8680243585924;668.3383450384182;672.3948446398281;5312.9734351328;5379.843333683277;5388.973055134699;5433.592480563973;5722.799702010493;101980;103502;104455;104561;107000;84043.5797821007;86638.56680716682;87346;87859.1214087851;88830;80293;80325.393492131;81894.59052704698;82552.58723706343;83370.16629833746;11759.470823812779;12034.63896083119;12632.989360850926;12664.366781660696;12691.365431728193;32649.020529384157;38329.658461953695;39686.801565991984;41190.764277071736;41937.79031104825;69964.30035699578;72367;73341.63329183533;73793.6310318445;74653.62673186816;15252.618684532976;15686.450974215848;16091.114988675736;16194.595135121244;16228.432004879773;17395.304187832346;17653.38213162533;17694.646107078042;17967.910160449635;18155.36456224026;14923.62690932692;16302.755458667893;16427.58931026734;16461.34154633849;16495.34018639241;15858.52424427269;15954.521364359089;16481.423150189687;16532.256048478088;16537.586560335607;15407.61480962985;15477.148756818484;15935.442259520862;16088.11515366666;16090.758638620197;11455.59905403307;11628.53485860556;11736.589219377282;11842.526298947945;11880.940595296968;9896.653617123366;11875.228110172811;12652.240865548096;12680.23918564889;12748.345195463802 05:22:46 INFO - PID 1640 | __end_tp_report 05:22:46 INFO - PID 1640 | __start_cc_report 05:22:46 INFO - PID 1640 | _x_x_mozilla_cycle_collect,335 05:22:46 INFO - PID 1640 | __end_cc_report 05:22:46 INFO - PID 1640 | __startTimestamp1494937366373__endTimestamp 05:22:46 INFO - PID 1640 | 05:22:46 INFO - PID 1640 | ------- Summary: start ------- 05:22:46 INFO - PID 1640 | Number of tests: 6 05:22:46 INFO - PID 1640 | 05:22:46 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:6157.90 Median:6404.55 stddev:780.84 (12.2%) stddev-sans-first:746.69 05:22:46 INFO - PID 1640 | Values: 3148.2 3232.2 3605.1 3624.0 3640.0 5418.8 5939.7 6148.2 6237.0 6248.9 6167.0 6171.8 6180.7 6221.4 6255.5 5460.3 5465.3 5466.5 5477.6 5484.3 5516.0 5562.2 5580.4 5587.6 5603.7 5467.4 5468.4 5482.5 5532.1 5562.8 4575.2 5471.5 5489.7 5506.2 5507.3 5433.8 5436.9 5451.9 5489.0 5490.5 5093.8 6136.3 6222.3 6246.3 6251.0 6604.1 6658.4 6684.1 6696.0 6697.2 6779.5 6803.2 6804.9 6807.4 6816.5 6127.4 6319.7 6327.6 6335.5 6353.8 6740.1 6779.7 6847.2 6859.6 6942.8 6558.9 6602.6 6622.6 6625.7 6631.0 6515.1 6715.0 6774.5 6788.2 6789.9 6518.5 6534.3 6568.6 6616.2 6661.8 6684.1 6695.8 6707.3 6708.6 6721.2 5295.4 5304.4 5324.9 5331.1 5366.4 3927.3 3969.3 4705.7 4715.0 4723.8 6377.9 6393.6 6404.6 6418.7 6426.1 6845.9 6853.2 6856.1 6869.4 6870.2 6286.8 6309.6 6358.7 6425.5 6427.0 5843.7 5879.3 5897.4 5956.0 6051.7 6967.8 6995.4 7010.9 7021.8 7039.9 6791.6 6833.0 6857.5 6866.3 6879.2 5814.2 6687.3 6986.1 7041.7 7048.3 5604.4 5869.1 6473.6 6510.2 6589.8 6774.2 6808.9 6815.8 6827.7 6832.1 6567.4 6570.5 6585.2 6585.6 6591.0 6372.1 6454.9 6507.3 6546.7 6601.4 5443.5 6281.7 6411.2 6447.6 6498.2 6399.3 6497.2 6544.1 6545.5 6581.9 6341.9 6362.5 6382.1 6393.6 6398.1 05:22:46 INFO - PID 1640 | 05:22:46 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:44971.95 Median:176918.26 stddev:78400.73 (44.3%) stddev-sans-first:78550.30 05:22:46 INFO - PID 1640 | Values: 732.6 779.7 822.5 1210.2 1267.4 38506.2 39374.0 39402.8 39409.8 39419.0 39828.0 40158.2 40178.2 40205.0 40304.6 7867.4 8164.8 8383.5 8584.9 8589.1 8551.4 8612.5 8630.4 8663.4 8671.7 8590.8 8672.4 8680.9 8687.3 8691.1 7331.5 8623.8 8631.0 8648.0 8702.7 8301.1 8334.0 8368.7 8410.5 8434.7 29582.1 37228.8 37541.0 37612.0 37761.6 3637.5 3658.6 3672.4 3674.5 3678.8 24717.5 25395.9 26095.9 27671.7 30630.4 3079.9 3123.9 3165.5 3166.0 3168.4 273704.0 294905.0 299210.0 299945.5 308626.0 47226.8 47422.8 47440.8 47482.8 47584.8 48486.5 48678.3 48994.8 49059.3 49165.5 12818.6 14295.4 14377.8 14410.8 14420.3 10969.4 11573.6 11703.1 11730.8 11736.0 13145.6 13267.0 13283.6 13295.3 13360.6 5100.8 5964.7 5997.3 6035.8 6058.8 1013.5 1030.3 1037.2 1062.3 1071.1 22922.3 23443.6 24271.7 24659.6 25000.6 1035.7 1072.8 1097.6 1115.6 1120.4 12077.8 12650.5 12753.7 12764.3 12808.9 315760.0 317772.0 319061.0 319975.0 321755.0 53354.2 53661.2 53724.7 53749.2 53826.2 1331.2 1481.1 1497.7 1537.2 1545.0 20040.6 24456.1 24458.4 24472.4 24582.6 38189.0 38432.0 38469.4 38496.6 38599.0 278649.0 281623.0 282172.0 282191.0 283574.0 34177.7 34714.1 36667.0 36709.9 36745.1 43031.1 44845.0 46742.8 46781.0 46881.1 26007.3 26328.7 28853.7 29318.0 29903.0 30610.4 31418.8 31460.5 31555.7 31598.5 37048.0 37316.8 37935.6 37990.8 38037.2 30936.0 31666.0 31862.8 32081.5 32248.8 22137.8 23092.2 24074.0 24099.0 24462.4 05:22:46 INFO - PID 1640 | 05:22:46 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:35736.21 Median:12478.42 stddev:91607.38 (734.1%) stddev-sans-first:91825.91 05:22:46 INFO - PID 1640 | Values: 388.8 420.0 427.3 439.5 452.7 18607.3 19748.9 21340.5 22147.8 22204.6 18429.9 22665.7 23024.8 23035.9 23047.5 5463.9 5928.2 5955.7 6112.3 6175.8 5418.9 5430.5 5622.5 5716.2 6043.2 6040.0 6083.9 6098.3 6100.4 6110.6 5950.6 6017.9 6048.5 6067.4 6086.5 5248.9 5429.2 5430.4 5436.2 5445.0 26560.9 26732.3 27064.6 27239.2 27316.5 2170.6 2259.2 2260.1 2291.2 2292.6 17781.6 18025.2 18038.5 18076.0 18274.5 2062.9 2158.5 2195.5 2199.2 2203.0 142786.3 164000.2 173668.0 174263.0 175648.0 12678.6 12682.0 12856.9 12938.2 13055.2 16768.2 16776.8 16779.7 16781.3 16800.4 8727.8 9071.1 9098.8 9166.2 9209.4 8620.4 8626.5 8688.2 8780.3 8815.7 7932.9 7968.9 7990.0 8044.0 8088.4 1934.7 2137.9 2192.0 2204.9 2374.9 820.7 973.4 988.2 1006.8 1041.1 18462.8 20850.6 21630.0 21670.1 21693.5 913.4 973.2 1006.7 1028.6 1039.4 10980.3 11235.9 11306.5 11306.9 11329.4 513955.0 535574.0 541091.0 541341.0 541724.0 129072.0 134575.0 138388.0 139444.3 140298.0 1178.8 1191.7 1225.0 1226.6 1230.8 22161.0 22444.0 23084.7 23482.5 23495.9 43026.0 47961.8 57708.8 58083.1 58467.4 77900.8 78784.0 79701.0 80299.6 80387.0 7535.4 8802.8 8980.6 9004.0 9079.8 10620.6 13728.7 13960.2 14421.9 14556.7 12147.5 12147.8 12294.6 12309.1 12336.8 11147.3 11461.3 11465.7 11723.6 11730.5 11993.9 12179.5 12221.6 12453.3 12513.3 8897.6 9033.1 9097.5 9126.7 9143.3 11888.4 12023.8 12121.8 12152.5 12256.5 05:22:46 INFO - PID 1640 | 05:22:46 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:7129.12 Median:3933.50 stddev:4527.57 (115.1%) stddev-sans-first:4519.08 05:22:46 INFO - PID 1640 | Values: 1300.3 1338.0 1420.9 1481.0 1501.9 11747.9 11976.7 12220.3 12231.3 12237.7 11961.7 11979.9 12172.5 12180.5 12219.1 2567.9 2597.1 2606.9 2610.1 2610.5 2519.5 2566.7 2581.0 2581.4 2586.0 2538.6 2561.2 2591.7 2595.5 2599.8 2204.4 2521.4 2547.5 2569.1 2570.9 2485.5 2486.9 2549.6 2577.6 2582.9 8826.5 11783.0 11785.6 11789.1 11809.4 6580.4 6722.2 6784.8 6802.3 6817.6 3708.7 3931.8 3967.2 4056.3 4248.2 1090.3 1223.1 1246.0 1253.8 1285.3 16109.8 16449.7 16555.8 16692.4 16714.2 5504.2 5581.1 5719.5 5736.6 5747.9 10444.8 11548.6 11588.5 11732.0 11745.0 6275.4 6491.3 6569.3 6580.3 6587.8 5882.2 6206.3 6303.9 6306.4 6314.5 4260.8 4394.2 4411.3 4419.0 4478.1 1259.5 1281.9 1286.9 1299.1 1300.0 787.0 789.5 808.1 810.2 832.0 6116.1 6142.2 6314.5 6360.5 6394.4 585.7 602.5 603.1 605.2 619.9 3935.2 4179.3 4193.6 4247.4 4272.9 14355.7 15084.2 15113.3 15151.9 15155.7 14481.0 14798.1 15033.5 15169.2 15183.4 8325.5 8572.4 8739.0 8748.0 8840.0 6954.1 7014.3 7172.0 7187.7 7335.3 13237.6 13357.7 13681.7 13766.4 13774.0 13793.9 13794.0 13887.4 13901.0 14231.1 9315.4 9315.6 9464.2 9467.8 9471.0 10365.9 10513.4 10637.5 10671.7 10679.4 8975.5 8975.8 9160.9 9233.2 9288.9 8665.4 8794.2 8897.6 8917.3 9070.9 8117.2 8126.2 8148.6 8210.2 8247.3 6000.1 6042.4 6103.7 6123.5 6143.1 5477.8 5979.8 5998.7 6155.1 6178.3 05:22:46 INFO - PID 1640 | 05:22:46 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5500.99 Median:6196.67 stddev:1734.18 (28.0%) stddev-sans-first:1695.00 05:22:46 INFO - PID 1640 | Values: 326.5 394.7 398.9 404.1 406.4 6206.4 6228.7 6368.0 6371.9 6550.5 4895.9 5192.9 5918.5 6186.9 6392.5 4342.3 4509.3 4595.4 4595.8 4632.7 4200.8 4629.5 4640.7 4644.2 4656.7 4021.0 4073.9 4542.8 4594.8 4625.6 4481.3 4507.6 4549.3 4579.9 4613.5 3036.6 3893.1 3894.4 3914.0 3921.7 5777.8 5868.2 6219.2 6255.6 6283.2 4551.6 5145.9 5271.4 5508.7 5529.5 5549.9 6752.2 6779.8 6801.6 6816.7 4323.4 4448.6 4462.4 4463.7 4482.0 7023.9 7082.2 7181.6 7263.7 7275.7 4194.4 4980.6 5087.9 5140.4 5157.8 5253.3 5272.8 5274.3 5291.8 5298.9 5589.4 5738.9 5817.6 5871.7 5874.6 5641.3 6445.5 6908.1 6913.9 6945.2 1420.9 1541.7 1745.3 1769.3 1785.9 1029.6 1049.0 1163.6 1188.7 1247.4 5594.6 5625.7 5998.4 6409.2 6543.0 6212.3 7037.3 7068.9 7097.6 7201.6 5832.9 5957.9 6285.2 6424.0 6456.2 3179.3 3458.0 3559.7 3698.4 3698.4 6953.4 7051.5 7117.2 7290.6 7316.3 6713.4 7295.5 7299.1 7300.5 7307.4 6683.3 6973.1 7253.8 7290.9 7352.6 6573.7 7099.9 7149.2 7189.2 7206.4 6108.6 6654.0 6817.0 6916.5 7045.6 5337.8 6700.3 7190.2 7335.6 7355.2 3949.5 4463.1 4542.3 4552.9 4574.5 5973.6 6276.5 6701.1 6724.2 6919.3 6819.2 6836.4 6842.1 6855.2 6860.9 6460.9 6536.8 6760.6 6768.6 6793.6 6549.0 6564.4 6659.2 6665.9 6673.2 6648.2 6715.5 6801.5 6802.6 6831.0 6578.3 6632.9 6634.4 6645.3 6651.5 05:22:46 INFO - PID 1640 | 05:22:46 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:22352.04 Median:18171.68 stddev:31837.72 (175.2%) stddev-sans-first:31885.87 05:22:46 INFO - PID 1640 | Values: 790.6 823.7 834.6 855.5 863.1 16308.9 16694.5 16747.1 18893.0 19271.4 17808.2 18188.0 18337.1 18453.0 18701.9 808.5 965.8 976.5 977.1 977.3 1003.1 1004.8 1006.3 1013.5 1015.0 1055.5 1058.6 1061.9 1062.4 1062.6 955.9 1033.7 1061.5 1065.2 1085.8 750.3 751.8 758.2 763.7 779.9 16822.0 16890.7 17070.0 17077.5 17123.0 17212.7 17293.4 17314.9 17543.0 17568.0 14237.5 14264.1 14551.5 14561.7 14567.6 3492.2 3495.3 3552.8 3554.0 3561.2 119147.4 137341.3 137579.0 137948.0 138143.0 13595.3 13673.6 13933.2 13982.1 14139.6 21153.3 24706.0 25538.5 26211.9 26217.2 7844.6 7971.9 7987.5 8038.2 8050.5 3947.1 3999.0 4001.6 4002.0 4017.4 4357.4 4384.8 4393.3 4463.7 4468.0 930.7 942.2 958.0 959.0 960.7 630.3 638.8 652.1 657.6 670.1 9757.9 10351.0 11673.8 13153.9 13265.7 608.6 659.7 665.9 668.3 672.4 5313.0 5379.8 5389.0 5433.6 5722.8 101980.0 103502.0 104455.0 104561.0 107000.0 84043.6 86638.6 87346.0 87859.1 88830.0 80293.0 80325.4 81894.6 82552.6 83370.2 11759.5 12034.6 12633.0 12664.4 12691.4 32649.0 38329.7 39686.8 41190.8 41937.8 69964.3 72367.0 73341.6 73793.6 74653.6 15252.6 15686.5 16091.1 16194.6 16228.4 17395.3 17653.4 17694.6 17967.9 18155.4 14923.6 16302.8 16427.6 16461.3 16495.3 15858.5 15954.5 16481.4 16532.3 16537.6 15407.6 15477.1 15935.4 16088.1 16090.8 11455.6 11628.5 11736.6 11842.5 11880.9 9896.7 11875.2 12652.2 12680.2 12748.3 05:22:46 INFO - PID 1640 | -------- Summary: end -------- 05:22:46 INFO - PID 1640 | 05:22:46 INFO - TEST-INFO | 1640: exit 0 05:22:47 INFO - TEST-OK | dromaeo_css | took 1114006ms 05:22:47 INFO - TEST-START | kraken 05:22:47 INFO - operating with platform_type : mac_ 05:22:47 INFO - Initialising browser for kraken test... 05:22:47 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmpYpW_6O/profile http://localhost:49227/getInfo.html) 05:22:47 INFO - PID 1650 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2932: String.localeCompare is deprecated; use String.prototype.localeCompare instead 05:22:49 INFO - PID 1650 | 2017-05-16 05:22:49.358 plugin-container[1651:10134] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9137, name = 'com.apple.tsm.portname' 05:22:49 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:22:49 INFO - PID 1650 | 2017-05-16 05:22:49.361 plugin-container[1651:10134] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 05:22:49 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:22:49 INFO - PID 1650 | 2017-05-16 05:22:49.361 plugin-container[1651:10134] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:22:49 INFO - PID 1650 | __metrics Screen width/height:1600/1200 05:22:49 INFO - PID 1650 | colorDepth:24 05:22:49 INFO - PID 1650 | Browser inner width/height: 1024/691 05:22:49 INFO - PID 1650 | __metrics 05:22:49 INFO - PID 1650 | 05:22:49 INFO - PID 1650 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 05:22:49 INFO - PID 1650 | 05:22:50 INFO - TEST-INFO | 1650: exit 0 05:22:50 INFO - Browser initialized. 05:22:50 INFO - Running cycle 1/1 for kraken test... 05:22:50 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/T/tmpYpW_6O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:22:52 INFO - PID 1653 | 2017-05-16 05:22:52.203 plugin-container[1654:10280] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 05:22:52 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:22:52 INFO - PID 1653 | 2017-05-16 05:22:52.203 plugin-container[1654:10280] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 05:22:52 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:22:52 INFO - PID 1653 | 2017-05-16 05:22:52.203 plugin-container[1654:10280] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:22:52 INFO - PID 1653 | 2017-05-16 05:22:52.857 plugin-container[1655:10329] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 05:22:52 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:22:52 INFO - PID 1653 | 2017-05-16 05:22:52.858 plugin-container[1655:10329] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9803, name = 'com.apple.CFPasteboardClient' 05:22:52 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:22:52 INFO - PID 1653 | 2017-05-16 05:22:52.858 plugin-container[1655:10329] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:23:02 INFO - PID 1653 | RSS: Main: 235286528 05:23:02 INFO - PID 1653 | 05:23:04 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 05:23:05 INFO - PID 1653 | RSS: Main: 248107008 05:23:05 INFO - PID 1653 | 05:23:08 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 05:23:09 INFO - PID 1653 | RSS: Main: 256446464 05:23:09 INFO - PID 1653 | 05:23:12 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 05:23:13 INFO - PID 1653 | RSS: Main: 258215936 05:23:13 INFO - PID 1653 | 05:23:15 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 05:23:15 INFO - PID 1653 | RSS: Main: 260272128 05:23:15 INFO - PID 1653 | 05:23:17 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:23:18 INFO - PID 1653 | RSS: Main: 261050368 05:23:18 INFO - PID 1653 | 05:23:21 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 05:23:21 INFO - PID 1653 | RSS: Main: 266915840 05:23:21 INFO - PID 1653 | 05:23:24 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 05:23:25 INFO - PID 1653 | RSS: Main: 266772480 05:23:25 INFO - PID 1653 | 05:23:28 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 05:23:28 INFO - PID 1653 | RSS: Main: 265752576 05:23:28 INFO - PID 1653 | 05:23:30 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:23:30 INFO - PID 1653 | RSS: Main: 265261056 05:23:30 INFO - PID 1653 | 05:23:32 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:23:33 INFO - PID 1653 | RSS: Main: 265281536 05:23:33 INFO - PID 1653 | 05:23:35 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:23:36 INFO - PID 1653 | RSS: Main: 265183232 05:23:36 INFO - PID 1653 | 05:23:38 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:23:39 INFO - PID 1653 | RSS: Main: 264830976 05:23:39 INFO - PID 1653 | 05:23:42 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:23:43 INFO - PID 1653 | RSS: Main: 265072640 05:23:43 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 05:23:45 INFO - PID 1653 | RSS: Main: 267423744 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | __start_tp_report 05:23:45 INFO - PID 1653 | _x_x_mozilla_page_load 05:23:45 INFO - PID 1653 | _x_x_mozilla_page_load_details 05:23:45 INFO - PID 1653 | |i|pagename|runs| 05:23:45 INFO - PID 1653 | |0;ai-astar;105;115;114;115;116;118;114;115;114;115 05:23:45 INFO - PID 1653 | |1;audio-beat-detection;140;128;143;186;114;117;129;114;131;149 05:23:45 INFO - PID 1653 | |2;audio-dft;194;193;195;202;201;211;261;280;199;196 05:23:45 INFO - PID 1653 | |3;audio-fft;82;58;60;86;68;61;73;76;80;70 05:23:45 INFO - PID 1653 | |4;audio-oscillator;78;72;85;72;71;68;85;69;73;92 05:23:45 INFO - PID 1653 | |5;imaging-gaussian-blur;92;92;92;116;93;92;92;91;92;99 05:23:45 INFO - PID 1653 | |6;imaging-darkroom;98;98;99;97;97;98;99;97;102;99 05:23:45 INFO - PID 1653 | |7;imaging-desaturate;92;96;90;93;97;91;92;91;90;90 05:23:45 INFO - PID 1653 | |8;json-parse-financial;51;52;51;51;50;51;50;52;52;50 05:23:45 INFO - PID 1653 | |9;json-stringify-tinderbox;57;64;59;85;66;62;56;60;59;58 05:23:45 INFO - PID 1653 | |10;stanford-crypto-aes;83;83;85;84;95;90;81;84;82;81 05:23:45 INFO - PID 1653 | |11;stanford-crypto-ccm;148;146;146;133;136;137;141;154;212;124 05:23:45 INFO - PID 1653 | |12;stanford-crypto-pbkdf2;205;226;233;176;232;213;180;245;228;186 05:23:45 INFO - PID 1653 | |13;stanford-crypto-sha256-iterative;68;68;62;60;98;69;66;69;67;68 05:23:45 INFO - PID 1653 | __end_tp_report 05:23:45 INFO - PID 1653 | __start_cc_report 05:23:45 INFO - PID 1653 | _x_x_mozilla_cycle_collect,918 05:23:45 INFO - PID 1653 | __end_cc_report 05:23:45 INFO - PID 1653 | __startTimestamp1494937425461__endTimestamp 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | ------- Summary: start ------- 05:23:45 INFO - PID 1653 | Number of tests: 14 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.00 stddev:3.41 (3.0%) stddev-sans-first:1.27 05:23:45 INFO - PID 1653 | Values: 105.0 115.0 114.0 115.0 116.0 118.0 114.0 115.0 114.0 115.0 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:135.50 stddev:21.58 (15.9%) stddev-sans-first:22.82 05:23:45 INFO - PID 1653 | Values: 140.0 128.0 143.0 186.0 114.0 117.0 129.0 114.0 131.0 149.0 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | [#2] audio-dft Cycles:10 Average:213.20 Median:201.50 stddev:30.97 (15.4%) stddev-sans-first:32.06 05:23:45 INFO - PID 1653 | Values: 194.0 193.0 195.0 202.0 201.0 211.0 261.0 280.0 199.0 196.0 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | [#3] audio-fft Cycles:10 Average:71.40 Median:74.50 stddev:9.74 (13.1%) stddev-sans-first:9.55 05:23:45 INFO - PID 1653 | Values: 82.0 58.0 60.0 86.0 68.0 61.0 73.0 76.0 80.0 70.0 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:75.50 stddev:8.15 (10.8%) stddev-sans-first:8.63 05:23:45 INFO - PID 1653 | Values: 78.0 72.0 85.0 72.0 71.0 68.0 85.0 69.0 73.0 92.0 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:95.10 Median:92.00 stddev:7.68 (8.3%) stddev-sans-first:8.06 05:23:45 INFO - PID 1653 | Values: 92.0 92.0 92.0 116.0 93.0 92.0 92.0 91.0 92.0 99.0 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | [#6] imaging-darkroom Cycles:10 Average:98.40 Median:98.00 stddev:1.51 (1.5%) stddev-sans-first:1.59 05:23:45 INFO - PID 1653 | Values: 98.0 98.0 99.0 97.0 97.0 98.0 99.0 97.0 102.0 99.0 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | [#7] imaging-desaturate Cycles:10 Average:92.20 Median:92.00 stddev:2.49 (2.7%) stddev-sans-first:2.64 05:23:45 INFO - PID 1653 | Values: 92.0 96.0 90.0 93.0 97.0 91.0 92.0 91.0 90.0 90.0 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:0.82 (1.6%) stddev-sans-first:0.87 05:23:45 INFO - PID 1653 | Values: 51.0 52.0 51.0 51.0 50.0 51.0 50.0 52.0 52.0 50.0 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:61.00 stddev:8.46 (13.9%) stddev-sans-first:8.73 05:23:45 INFO - PID 1653 | Values: 57.0 64.0 59.0 85.0 66.0 62.0 56.0 60.0 59.0 58.0 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:84.00 stddev:4.42 (5.3%) stddev-sans-first:4.64 05:23:45 INFO - PID 1653 | Values: 83.0 83.0 85.0 84.0 95.0 90.0 81.0 84.0 82.0 81.0 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:146.00 stddev:24.16 (16.5%) stddev-sans-first:25.63 05:23:45 INFO - PID 1653 | Values: 148.0 146.0 146.0 133.0 136.0 137.0 141.0 154.0 212.0 124.0 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:212.40 Median:227.00 stddev:24.55 (10.8%) stddev-sans-first:25.90 05:23:45 INFO - PID 1653 | Values: 205.0 226.0 233.0 176.0 232.0 213.0 180.0 245.0 228.0 186.0 05:23:45 INFO - PID 1653 | 05:23:45 INFO - PID 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:68.00 stddev:10.46 (15.4%) stddev-sans-first:11.08 05:23:45 INFO - PID 1653 | Values: 68.0 68.0 62.0 60.0 98.0 69.0 66.0 69.0 67.0 68.0 05:23:45 INFO - PID 1653 | -------- Summary: end -------- 05:23:45 INFO - PID 1653 | 05:23:46 INFO - TEST-INFO | 1653: exit 0 05:23:46 INFO - TEST-OK | kraken | took 59423ms 05:23:46 INFO - SUITE-END | took 1173s 05:23:46 INFO - Completed test suite (00:19:33) 05:23:46 INFO - javascript benchmark 05:23:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7946.095672391915, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3148.1972097115245, 3232.191952011997, 3605.1167463971333, 3623.9637603623987, 3640.035390621484, 5418.783248670054, 5939.703014849257, 6148.200733904598, 6236.968815155918, 6248.906266406002, 6166.969165154179, 6171.783987560436, 6180.660063696495, 6221.40896614822, 6255.468285195764, 5460.290162278918, 5465.289512363387, 5466.508014278724, 5477.561795056386, 5484.341878974528, 5516.034693928566, 5562.193481945115, 5580.441955804427, 5587.608867379285, 5603.691796951166, 5467.371252305996, 5468.371137319219, 5482.451754824526, 5532.059549876531, 5562.805301814437, 4575.153596584642, 5471.534919531845, 5489.725513724298, 5506.201600767866, 5507.311586051744, 5433.782648694048, 5436.945630543683, 5451.890962180773, 5489.011977843987, 5490.450954904518, 5093.770780314895, 6136.335271946702, 6222.284437289724, 6246.281677607042, 6251.0, 6604.141461610008, 6658.400743933021, 6684.131062961833, 6695.966520167368, 6697.162854643169, 6779.525433219676, 6803.183617965876, 6804.945233488817, 6807.353301436356, 6816.4546836252985, 6127.372786728385, 6319.715612797343, 6327.620342779448, 6335.493160547191, 6353.809385718436, 6740.090087838177, 6779.661016949133, 6847.246802851682, 6859.5541289816, 6942.791716248521, 6558.868822623521, 6602.60384376939, 6622.569532980341, 6625.701843417058, 6631.0, 6515.055316979012, 6714.9664251679405, 6774.45804335652, 6788.219354774165, 6789.864202716017, 6518.511111666644, 6534.313897040837, 6568.6058836469965, 6616.206055273398, 6661.800145995628, 6684.064231007663, 6695.799126026227, 6707.262201157869, 6708.6310252936555, 6721.193456785217, 5295.391030031518, 5304.389995150607, 5324.946750532445, 5331.1470164773455, 5366.356037275552, 3927.3323534999117, 3969.2656858481273, 4705.694129881546, 4714.976425117921, 4723.7874295656775, 6377.8724425512155, 6393.552451328455, 6404.551681382259, 6418.711157997901, 6426.1003459515705, 6845.863082738318, 6853.17761868569, 6856.108705868305, 6869.416099631588, 6870.20992585849, 6286.779962701375, 6309.589876658002, 6358.713857876407, 6425.453836423956, 6426.967865160645, 5843.678597677169, 5879.294484661782, 5897.351291357947, 5956.0, 6051.667158306335, 6967.756128535477, 6995.370416662525, 7010.929890701028, 7021.789346319619, 7039.859202815813, 6791.592504449749, 6833.0, 6857.485688573477, 6866.34769696878, 6879.174499060145, 5814.244148260699, 6687.297833727389, 6986.056882320821, 7041.718331266692, 7048.330408611174, 5604.383517812956, 5869.06095024794, 6473.611583305017, 6510.218773746991, 6589.769358072444, 6774.153230846145, 6808.863822723617, 6815.761448349284, 6827.726890924308, 6832.145981752281, 6567.44176744982, 6570.47436205112, 6585.242697089896, 6585.604863708193, 6590.9670451647435, 6372.107904893226, 6454.870902582016, 6507.28419873823, 6546.738130474728, 6601.438877695449, 5443.482869127427, 6281.7173227203975, 6411.230652321751, 6447.6453795041725, 6498.155239818793, 6399.296077431572, 6497.220333560003, 6544.149260596092, 6545.5090868184125, 6581.901271480836, 6341.8731625368155, 6362.459190968634, 6382.074599183, 6393.552451328363, 6398.072279519537], "value": 6102.176941430775, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.644667336342, 779.6569509415857, 822.494166087856, 1210.1710328425029, 1267.391652007036, 38506.229875402474, 39374.0, 39402.80298598503, 39409.80295098524, 39419.0145246369, 39828.0, 40158.19683606326, 40178.19643607126, 40204.99487512813, 40304.59695403045, 7867.370610351172, 8164.836703265931, 8383.455075420108, 8584.914150858505, 8589.055203927563, 8551.444156129832, 8612.48325100493, 8630.439021463617, 8663.350248731338, 8671.739847804576, 8590.78523036926, 8672.436291641023, 8680.8697869532, 8687.305015598737, 8691.130886911289, 7331.450141239428, 8623.827523449527, 8631.007434145027, 8648.048714641385, 8702.651893924236, 8301.086880443147, 8334.041585217656, 8368.665253389858, 8410.537420441873, 8434.662613495453, 29582.112536623936, 37228.813855930544, 37541.0, 37612.0, 37761.622383776084, 3637.4543818427446, 3658.579263384718, 3672.4491326301268, 3674.5223120994365, 3678.834452449619, 24717.5056498869, 25395.87302063478, 26095.869520652464, 27671.723282767318, 30630.38739225203, 3079.8614062367246, 3123.9375212495625, 3165.4776961801276, 3165.968340316613, 3168.413843438971, 273704.0, 294905.0, 299209.99999999785, 299945.5002724994, 308626.0, 47226.76386618045, 47422.76288618535, 47440.7627961858, 47482.76258618685, 47584.81037974009, 48486.5151348482, 48678.26982595264, 48994.755026224644, 49059.26411103836, 49165.508344916096, 12818.55135070268, 14295.356034056009, 14377.78433323501, 14410.783838242434, 14420.278986050656, 10969.396683182342, 11573.594924177782, 11703.122265830098, 11730.824037639442, 11736.002439792543, 13145.60563183106, 13267.004974626749, 13283.601491955256, 13295.268760218281, 13360.59918202436, 5100.770465329069, 5964.671943043088, 5997.340292567814, 6035.758569657252, 6058.787942421994, 1013.5287091502339, 1030.3457304611625, 1037.190991027, 1062.3413483640193, 1071.0895738622264, 22922.31233063011, 23443.648345274836, 24271.665058421957, 24659.630105548433, 25000.624990625154, 1035.7100011996654, 1072.830614630057, 1097.6158344579494, 1115.570505355444, 1120.38938778366, 12077.758444831054, 12650.493980240872, 12753.744925101446, 12764.297963611905, 12808.871911280954, 315760.0, 317772.0, 319061.0, 319975.0, 321755.0, 53354.19968700472, 53661.1950820738, 53724.73137634287, 53749.1937620936, 53826.19260711093, 1331.17467170355, 1481.1335368809152, 1497.6854860479204, 1537.1699282387608, 1544.9768253476207, 20040.599188015865, 24456.144034958692, 24458.388540286636, 24472.388190295384, 24582.63126053075, 38189.0, 38432.03919901913, 38469.42295865508, 38496.6150338493, 38599.0, 278649.0, 281623.0, 282172.0, 282191.0, 283574.0, 34177.65822341745, 34714.13214669654, 36667.0, 36709.898703038954, 36745.08137296589, 43031.13937721291, 44845.0, 46742.831429214544, 46781.0, 46881.06237875292, 26007.349816254744, 26328.736712632628, 28853.7114628851, 29317.973870914415, 29903.0, 30610.387792243586, 31418.842905785325, 31460.528092079094, 31555.684443155274, 31598.52602210923, 37048.0, 37316.813415932746, 37935.62064379431, 37990.81004594959, 38037.239255215296, 30936.0, 31666.0, 31862.84068579735, 32081.518777218826, 32248.83875580607, 22137.778622213573, 23092.191773287854, 24074.0, 24099.0, 24462.388440289134], "value": 17510.214176293124, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.79976811941845, 419.98740037798876, 427.32909332348225, 439.48141193391797, 452.7374123008655, 18607.25570977161, 19748.913809740458, 21340.4664883378, 22147.778522214772, 22204.55590888181, 18429.907850460728, 22665.660015099744, 23024.769752302473, 23035.884820575873, 23047.542868569722, 5463.945360546383, 5928.199693041449, 5955.689748255377, 6112.297085835142, 6175.814725558241, 5418.916216756645, 5430.484104010113, 5622.465865742768, 5716.1997320375185, 6043.184170137041, 6039.969800151015, 6083.908741368883, 6098.32918378978, 6100.359462256466, 6110.602810817282, 5950.613210141349, 6017.939820601781, 6048.546359023091, 6067.393260673897, 6086.513078953673, 5248.947510524922, 5429.239906413105, 5430.3755068167275, 5436.157395603688, 5445.019896418643, 26560.86719566409, 26732.33169170767, 27064.59403108955, 27239.182824515297, 27316.45367092647, 2170.565886822626, 2259.164109279577, 2260.08466571039, 2291.1866287467874, 2292.56441276157, 17781.555461113574, 18025.188866501037, 18038.458846234633, 18076.0, 18274.45176644703, 2062.8659137156037, 2158.5143342747915, 2195.5169862630196, 2199.1533259694975, 2202.9559408811892, 142786.28606857004, 164000.17999910042, 173668.0, 174262.99999999872, 175648.0, 12678.556250531186, 12681.970884658595, 12856.871431285754, 12938.223706577635, 13055.151415158174, 16768.245428955724, 16776.83223167777, 16779.748303775454, 16781.328746850235, 16800.411985580446, 8727.825443491094, 9071.138241867013, 9098.772530686785, 9166.220871225887, 9209.401388909699, 8620.353473489387, 8626.5255410953, 8688.174623410765, 8780.297576193889, 8815.691450799191, 7932.881006784903, 7968.920310796818, 7989.960050199828, 8043.959780201061, 8088.352931765445, 1934.7001214811726, 2137.935861924114, 2191.9780802192095, 2204.889755512218, 2374.9244728077183, 820.6512232301368, 973.4354074636693, 988.1650005745134, 1006.8137394582026, 1041.073444634276, 18462.81537184638, 20850.58298834015, 21630.0, 21670.133194672035, 21693.457663558223, 913.4108500017452, 973.2360097323567, 1006.6677996261217, 1028.573142146001, 1039.3555995282823, 10980.341179529254, 11235.932586404278, 11306.491207895664, 11306.94346528262, 11329.376884271443, 513955.0, 535574.0, 541091.0, 541341.0, 541724.0, 129072.0, 134575.0, 138388.0, 139444.30277848546, 140298.0, 1178.8408564843808, 1191.737817680109, 1224.989383758403, 1226.576830993288, 1230.7907655698552, 22161.0, 22443.990020449444, 23084.653730194397, 23482.530349392575, 23495.88252058729, 43026.0, 47961.8009549764, 57708.84582308414, 58083.12875306789, 58467.415325846196, 77900.8314875266, 78784.0, 79701.0, 80299.59850200711, 80387.0, 7535.359494443033, 8802.779930501789, 8980.595873185592, 9003.954980225057, 9079.773005674911, 10620.575176992834, 13728.725425491633, 13960.162390256617, 14421.92789036048, 14556.708865822837, 12147.453364598437, 12147.81778273309, 12294.569690060805, 12309.138360314691, 12336.81494777597, 11147.331160130418, 11461.312321260752, 11465.713357166138, 11723.589674361698, 11730.530778769094, 11993.880061199625, 12179.451924663234, 12221.633350999484, 12453.25280483174, 12513.31176785249, 8897.555122243732, 9033.141851524095, 9097.545122743968, 9126.726198214064, 9143.314251431302, 11888.405579721153, 12023.75952480928, 12121.818172727593, 12152.453139608562, 12256.509739610316], "value": 10530.129710469102, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.3433266200568, 1338.049984510997, 1420.9147451152933, 1480.9925950370246, 1501.9173945433004, 11747.882521174786, 11976.700582485442, 12220.266783992956, 12231.327276999762, 12237.694057648563, 11961.700957476067, 11979.880201197986, 12172.513099476033, 12180.51277948881, 12219.144659873813, 2567.910123145695, 2597.0910181436534, 2606.895724171031, 2610.1125617290118, 2610.4909542639193, 2519.496100779842, 2566.679165104362, 2580.9741902581018, 2581.354661334666, 2586.0, 2538.5938249880023, 2561.2188282573807, 2591.6889973203242, 2595.4679290745353, 2599.8310109842896, 2204.4458656647025, 2521.382261345973, 2547.477767057758, 2569.100814714844, 2570.8586027768465, 2485.465624890657, 2486.8880900359527, 2549.6048112542585, 2577.574700174469, 2582.9483410331686, 8826.514541700204, 11783.0, 11785.587504437304, 11789.05687545003, 11809.350485723282, 6580.407763301278, 6722.226943901465, 6784.76253331136, 6802.2857599951785, 6817.625030623314, 3708.740388172829, 3931.783751893645, 3967.2263908537557, 4056.290149223874, 4248.214080395106, 1090.3457925244868, 1223.0582451512287, 1245.9501619935193, 1253.7555176740539, 1285.3123578885252, 16109.83890161095, 16449.67100657992, 16555.751663725056, 16692.41576544815, 16714.16429178536, 5504.201890725903, 5581.079121944915, 5719.456651618089, 5736.598438109376, 5747.942520574741, 10444.798848132563, 11548.59579914699, 11588.478518466689, 11732.0, 11745.0, 6275.435210831047, 6491.285958544557, 6569.277379488252, 6580.309067547934, 6587.802365928934, 5882.2073866588435, 6206.286277078104, 6303.905441418382, 6306.400891915261, 6314.494840412756, 4260.786960651955, 4394.187075390999, 4411.316245981878, 4418.955810441918, 4478.081993191388, 1259.4962015193996, 1281.9102662813514, 1286.8713128687057, 1299.0516922646332, 1300.0379719007867, 786.9921300787033, 789.4881302306135, 808.1110778144113, 810.1938571121772, 831.966721331152, 6116.143739876421, 6142.201513803267, 6314.494840412756, 6360.52296077796, 6394.424501794768, 585.6778771675562, 602.524006035236, 603.0652488642586, 605.2283338743138, 619.8729260501586, 3935.2129574085297, 4179.331306990867, 4193.643540299047, 4247.405363249148, 4272.91454170921, 14355.712885742227, 15084.24578771057, 15113.3199006045, 15151.924240378949, 15155.696886062216, 14480.986330956732, 14798.112113273239, 15033.548993530212, 15169.165695886832, 15183.392664293304, 8325.542095184703, 8572.35707321941, 8739.0, 8747.956260218658, 8839.955800220958, 6954.095967524189, 7014.298570142945, 7171.964140179266, 7187.676554554953, 7335.266473352622, 13237.602871913858, 13357.732845342844, 13681.726365472834, 13766.449342026206, 13774.0, 13793.931030344784, 13794.0, 13887.444502219798, 13901.026928114934, 14231.07498012626, 9315.441073535609, 9315.580798864203, 9464.19554337889, 9467.763305917408, 9471.005544418013, 10365.896341036794, 10513.421761803174, 10637.521311540844, 10671.679849604525, 10679.412632304988, 8975.506347150707, 8975.775605609912, 9160.862587061332, 9233.215176710055, 9288.907110928803, 8665.393422460367, 8794.164554367326, 8897.555122243992, 8917.331200159884, 9070.90929090727, 8117.228863257981, 8126.187381261827, 8148.551829649189, 8210.220029097227, 8247.257746802816, 6000.119942427572, 6042.3655516170165, 6103.664298463627, 6123.540734445023, 6143.139960405636, 5477.780888764405, 5979.790707325223, 5998.70006499682, 6155.1075094112, 6178.320384757763], "value": 5341.219642322195, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.54610091972165, 394.6605918909737, 398.9421533877586, 404.1331344266548, 406.40664629640725, 6206.441420272175, 6228.719707613157, 6368.0, 6371.936280637192, 6550.5414620976435, 4895.902081958358, 5192.922106168401, 5918.4673379395845, 6186.907196392058, 6392.529718164825, 4342.3065004648215, 4509.301058335946, 4595.402597662299, 4595.839145629895, 4632.652551058667, 4200.789960501978, 4629.513901040393, 4640.698354606957, 4644.164050470914, 4656.743879086649, 4021.0, 4073.8981525461954, 4542.772861356919, 4594.793234304448, 4625.5836974672275, 4481.282994720846, 4507.594316511513, 4549.31760235964, 4579.931301030487, 4613.492515823256, 3036.620422447194, 3893.0851249956236, 3894.357431023878, 3914.0, 3921.725479216456, 5777.804434314976, 5868.178455016301, 6219.1915051043525, 6255.624662520263, 6283.183186185766, 4551.590356867898, 5145.897082058338, 5271.393789714193, 5508.724563771835, 5529.502344788988, 5549.861253468655, 6752.189737231514, 6779.796606101825, 6801.591904485746, 6816.727330906758, 4323.373110898933, 4448.621867141297, 4462.3752674625575, 4463.709858859163, 4482.0249523029415, 7023.911293749477, 7082.185548661867, 7181.599588080327, 7263.745768898063, 7275.708971641075, 4194.433751443577, 4980.551750342486, 5087.8982420351385, 5140.434552199255, 5157.767900444489, 5253.317068781034, 5272.78908843642, 5274.26160337553, 5291.814786482531, 5298.947010529922, 5589.441055894378, 5738.885222295531, 5817.563682723812, 5871.706414679249, 5874.5887787854435, 5641.323041235078, 6445.452136568444, 6908.100272425123, 6913.861722765517, 6945.201301850251, 1420.872121509069, 1541.7070756556218, 1745.3367720266217, 1769.2922830867756, 1785.9374921877672, 1029.552144817007, 1048.951048951049, 1163.6043745126697, 1188.7028242939266, 1247.3638444393425, 5594.552435805126, 5625.662460252398, 5998.3701711320555, 6409.230892292955, 6543.0, 6212.347703491068, 7037.331453511908, 7068.893966590505, 7097.645117744092, 7201.603911784798, 5832.88334233313, 5957.851053723692, 6285.182926219562, 6423.967880160662, 6456.160699109086, 3179.2687681832845, 3457.9827100864336, 3559.715222782223, 3698.4082546792383, 3698.4267438547067, 6953.443724502028, 7051.54164979285, 7117.217106118323, 7290.562566245936, 7316.341529262392, 6713.429358504484, 7295.4528410368375, 7299.087614048244, 7300.525465844703, 7307.378872795872, 6683.298253683488, 6973.05863708403, 7253.766859633875, 7290.927090729131, 7352.639761644006, 6573.671316434255, 7099.893501597377, 7149.248434133462, 7189.173245076674, 7206.351428371472, 6108.602940808833, 6654.0, 6816.965915170393, 6916.481263905127, 7045.647717614202, 5337.786488540415, 6700.296468870698, 7190.245024272376, 7335.596542190231, 7355.2277010913085, 3949.5063117110362, 4463.129689710475, 4542.295944128666, 4552.88617784558, 4574.4510658721165, 5973.611715238496, 6276.497880169484, 6701.061851340719, 6724.159480064992, 6919.308069193041, 6819.215790184192, 6836.402167176441, 6842.118417435245, 6855.211650660238, 6860.862782744217, 6460.935390646221, 6536.7712130075215, 6760.628165450751, 6768.566588884289, 6793.626350550569, 6548.967255163694, 6564.409203171737, 6659.234188068434, 6665.933340666726, 6673.1992160941, 6648.174525826407, 6715.462762978852, 6801.489888258499, 6802.571459993259, 6831.0, 6578.276389597045, 6632.933670663425, 6634.436072933855, 6645.3022432643875, 6651.470161862895], "value": 4959.303709582296, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.6205021589636, 823.682882090395, 834.5576844272535, 855.5038077914807, 863.0506442912795, 16308.918455407722, 16694.499165025038, 16747.078910659908, 18893.0, 19271.421857344303, 17808.1986310616, 18188.0, 18337.08314584272, 18452.985085820274, 18701.90649046753, 808.5068108453838, 965.7682156282477, 976.501983988164, 977.0717818072824, 977.2572844638055, 1003.0871906565027, 1004.7689031522755, 1006.2754816532085, 1013.4729940430972, 1015.0103648942296, 1055.456439933435, 1058.62418841311, 1061.8831928487898, 1062.3572738493217, 1062.5909025025383, 955.8709574207462, 1033.6847261585212, 1061.4798748613161, 1065.1798115451136, 1085.8045551800712, 750.2722359311459, 751.7594369801611, 758.2417582417582, 763.6830715253163, 779.9337056350217, 16821.990680559207, 16890.662186756195, 17070.0, 17077.487675369757, 17123.0, 17212.655746885117, 17293.394731184475, 17314.91342543292, 17543.0, 17568.0, 14237.50168744089, 14264.144151350953, 14551.490697825526, 14561.708765824624, 14567.635809104751, 3492.1793378556017, 3495.3009398120225, 3552.7513074084823, 3553.964460355415, 3561.216532362876, 119147.40426297899, 137341.3132934329, 137579.0, 137948.0, 138143.0, 13595.252261125535, 13673.589792306248, 13933.164010159422, 13982.091164074303, 14139.575812725634, 21153.25963591298, 24706.0, 25538.489230215662, 26211.868940655175, 26217.213483595522, 7844.607769611496, 7971.920280797118, 7987.520748755093, 8038.236367545074, 8050.476719013245, 3947.131631041166, 3999.0002499375155, 4001.579834117434, 4002.039510517455, 4017.397390391465, 4357.411749413852, 4384.846530371486, 4393.253146965024, 4463.665225108065, 4467.955320446819, 930.6649606141783, 942.1520631431766, 958.0, 958.9856152157723, 960.673371053845, 630.2625927664643, 638.8211300835774, 652.0740548421253, 657.5890068707058, 670.081987676876, 9757.90242097584, 10351.0, 11673.824892626617, 13153.934230328787, 13265.734685306239, 608.6287364707613, 659.7196191618543, 665.8680243585924, 668.3383450384182, 672.3948446398281, 5312.9734351328, 5379.843333683277, 5388.973055134699, 5433.592480563973, 5722.799702010493, 101980.0, 103502.0, 104455.0, 104561.0, 107000.0, 84043.5797821007, 86638.56680716682, 87346.0, 87859.1214087851, 88830.0, 80293.0, 80325.393492131, 81894.59052704698, 82552.58723706343, 83370.16629833746, 11759.470823812779, 12034.63896083119, 12632.989360850926, 12664.366781660696, 12691.365431728193, 32649.020529384157, 38329.658461953695, 39686.801565991984, 41190.764277071736, 41937.79031104825, 69964.30035699578, 72367.0, 73341.63329183533, 73793.6310318445, 74653.62673186816, 15252.618684532976, 15686.450974215848, 16091.114988675736, 16194.595135121244, 16228.432004879773, 17395.304187832346, 17653.38213162533, 17694.646107078042, 17967.910160449635, 18155.36456224026, 14923.62690932692, 16302.755458667893, 16427.58931026734, 16461.34154633849, 16495.34018639241, 15858.52424427269, 15954.521364359089, 16481.423150189687, 16532.256048478088, 16537.586560335607, 15407.61480962985, 15477.148756818484, 15935.442259520862, 16088.11515366666, 16090.758638620197, 11455.59905403307, 11628.53485860556, 11736.589219377282, 11842.526298947945, 11880.940595296968, 9896.653617123366, 11875.228110172811, 12652.240865548096, 12680.23918564889, 12748.345195463802], "value": 8445.43525266012, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1524.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 114.0, 115.0, 116.0, 118.0, 114.0, 115.0, 114.0, 115.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 128.0, 143.0, 186.0, 114.0, 117.0, 129.0, 114.0, 131.0, 149.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 193.0, 195.0, 202.0, 201.0, 211.0, 261.0, 280.0, 199.0, 196.0], "value": 213.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 60.0, 86.0, 68.0, 61.0, 73.0, 76.0, 80.0, 70.0], "value": 71.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 85.0, 72.0, 71.0, 68.0, 85.0, 69.0, 73.0, 92.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 116.0, 93.0, 92.0, 92.0, 91.0, 92.0, 99.0], "value": 95.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 99.0, 97.0, 97.0, 98.0, 99.0, 97.0, 102.0, 99.0], "value": 98.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 96.0, 90.0, 93.0, 97.0, 91.0, 92.0, 91.0, 90.0, 90.0], "value": 92.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 51.0, 51.0, 50.0, 51.0, 50.0, 52.0, 52.0, 50.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 64.0, 59.0, 85.0, 66.0, 62.0, 56.0, 60.0, 59.0, 58.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 85.0, 84.0, 95.0, 90.0, 81.0, 84.0, 82.0, 81.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 146.0, 133.0, 136.0, 137.0, 141.0, 154.0, 212.0, 124.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 226.0, 233.0, 176.0, 232.0, 213.0, 180.0, 245.0, 228.0, 186.0], "value": 212.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 62.0, 60.0, 98.0, 69.0, 66.0, 69.0, 67.0, 68.0], "value": 69.5, "unit": "score"}]}]} 05:23:46 INFO - Return code: 0 05:23:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:23:46 INFO - # TBPL SUCCESS # 05:23:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:23:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:23:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:23:46 INFO - 2017-05-16 05:23:46.808 screenresolution[1660:10727] starting screenresolution argv=screenresolution get 05:23:46 INFO - 2017-05-16 05:23:46.812 screenresolution[1660:10727] Display 0: 1600x1200x32@60 05:23:46 INFO - 2017-05-16 05:23:46.818 screenresolution[1661:10730] starting screenresolution argv=screenresolution list 05:23:46 INFO - Available Modes on Display 0 05:23:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:23:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:23:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:23:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:23:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:23:47 INFO - Intel Iris: 05:23:47 INFO - Chipset Model: Intel Iris 05:23:47 INFO - Type: GPU 05:23:47 INFO - Bus: Built-In 05:23:47 INFO - VRAM (Dynamic, Max): 1536 MB 05:23:47 INFO - Vendor: Intel (0x8086) 05:23:47 INFO - Device ID: 0x0a2e 05:23:47 INFO - Revision ID: 0x0009 05:23:47 INFO - Displays: 05:23:47 INFO - Display: 05:23:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:23:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:23:47 INFO - Display Serial Number: 1600x1200 60 05:23:47 INFO - Main Display: Yes 05:23:47 INFO - Mirror: Off 05:23:47 INFO - Online: Yes 05:23:47 INFO - Rotation: Supported 05:23:47 INFO - Return code: 0 05:23:47 INFO - Running post-action listener: _resource_record_post_action 05:23:47 INFO - [mozharness: 2017-05-16 12:23:47.060224Z] Finished run-tests step (success) 05:23:47 INFO - Running post-run listener: _resource_record_post_run 05:23:47 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 39275520; Write bytes: 530159616; Read time: 260; Write time: 1678 05:23:47 INFO - TinderboxPrint: CPU usage
54.1% 05:23:47 INFO - TinderboxPrint: I/O read bytes / time
39,275,520 / 260 05:23:47 INFO - TinderboxPrint: I/O write bytes / time
530,159,616 / 1,678 05:23:47 INFO - TinderboxPrint: CPU idle
2,196.4 (45.9%) 05:23:47 INFO - TinderboxPrint: CPU system
957.2 (20.0%) 05:23:47 INFO - TinderboxPrint: CPU user
1,628.0 (34.0%) 05:23:47 INFO - TinderboxPrint: Swap in / out
253,566,976 / 0 05:23:47 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 217428480; Write bytes: 208861184; Read time: 16917; Write time: 474 05:23:47 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 31876096; Write bytes: 310312960; Read time: 190; Write time: 1168 05:23:47 INFO - Running post-run listener: _upload_blobber_files 05:23:47 INFO - Blob upload gear active. 05:23:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:23:47 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 05:23:47 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'] 05:23:47 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 05:23:47 INFO - (blobuploader) - INFO - Open directory for files ... 05:23:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:23:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:23:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:49 INFO - (blobuploader) - INFO - Done attempting. 05:23:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:23:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:23:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:50 INFO - (blobuploader) - INFO - Done attempting. 05:23:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:23:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:23:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:51 INFO - (blobuploader) - INFO - Done attempting. 05:23:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:23:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:23:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:53 INFO - (blobuploader) - INFO - Done attempting. 05:23:53 INFO - (blobuploader) - INFO - Iteration through files over. 05:23:53 INFO - Return code: 0 05:23:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:23:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:23:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fbe3036632e97482344716977de416aa90437cba45079d50c62602a5098baf37285cfc9d7cd44d664ac1d5519031ed8b13df380ca8489c49cef7cb4fd506bdf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c96b65f4182a3bc358ceca482f1aba3f3b9808f2e180d13a4892b0a1937db46537220ce3ff63cfe3f06625ada2c8ef4c3403a3e74a7e77ff677b75e325c4a717", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25646cfa8daddb2c1cf21bb8fb545efa74f7ff00a217474e3b88b83dc21fdf58387e01ff5abbe01baab5db0420badbfe91129cb05c9f7e318cf91a9f0a8cdf4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57aa13ec1fb07ef1747e2055aadb4ec5df591aa8cc36d6ae584d668f412a94a2d92cc7e463ff5594f2d391897fcb92f2cf0a4efdf131406fe643043ed153c8cd"} 05:23:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:23:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:23:53 INFO - Contents: 05:23:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fbe3036632e97482344716977de416aa90437cba45079d50c62602a5098baf37285cfc9d7cd44d664ac1d5519031ed8b13df380ca8489c49cef7cb4fd506bdf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c96b65f4182a3bc358ceca482f1aba3f3b9808f2e180d13a4892b0a1937db46537220ce3ff63cfe3f06625ada2c8ef4c3403a3e74a7e77ff677b75e325c4a717", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25646cfa8daddb2c1cf21bb8fb545efa74f7ff00a217474e3b88b83dc21fdf58387e01ff5abbe01baab5db0420badbfe91129cb05c9f7e318cf91a9f0a8cdf4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57aa13ec1fb07ef1747e2055aadb4ec5df591aa8cc36d6ae584d668f412a94a2d92cc7e463ff5594f2d391897fcb92f2cf0a4efdf131406fe643043ed153c8cd"} 05:23:53 INFO - Running post-run listener: copy_logs_to_upload_dir 05:23:53 INFO - Copying logs to upload dir... 05:23:53 INFO - mkdir: /builds/slave/test/build/upload/logs 05:23:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.520260 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-16 05:23:53.794389) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 05:23:53.797628) ========= 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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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/9fbe3036632e97482344716977de416aa90437cba45079d50c62602a5098baf37285cfc9d7cd44d664ac1d5519031ed8b13df380ca8489c49cef7cb4fd506bdf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c96b65f4182a3bc358ceca482f1aba3f3b9808f2e180d13a4892b0a1937db46537220ce3ff63cfe3f06625ada2c8ef4c3403a3e74a7e77ff677b75e325c4a717", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25646cfa8daddb2c1cf21bb8fb545efa74f7ff00a217474e3b88b83dc21fdf58387e01ff5abbe01baab5db0420badbfe91129cb05c9f7e318cf91a9f0a8cdf4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57aa13ec1fb07ef1747e2055aadb4ec5df591aa8cc36d6ae584d668f412a94a2d92cc7e463ff5594f2d391897fcb92f2cf0a4efdf131406fe643043ed153c8cd"} build_url:https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011178 build_url: 'https://queue.taskcluster.net/v1/task/A7pZ2d4ETx-gYBsagkAOtw/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/9fbe3036632e97482344716977de416aa90437cba45079d50c62602a5098baf37285cfc9d7cd44d664ac1d5519031ed8b13df380ca8489c49cef7cb4fd506bdf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c96b65f4182a3bc358ceca482f1aba3f3b9808f2e180d13a4892b0a1937db46537220ce3ff63cfe3f06625ada2c8ef4c3403a3e74a7e77ff677b75e325c4a717", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25646cfa8daddb2c1cf21bb8fb545efa74f7ff00a217474e3b88b83dc21fdf58387e01ff5abbe01baab5db0420badbfe91129cb05c9f7e318cf91a9f0a8cdf4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57aa13ec1fb07ef1747e2055aadb4ec5df591aa8cc36d6ae584d668f412a94a2d92cc7e463ff5594f2d391897fcb92f2cf0a4efdf131406fe643043ed153c8cd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 05:23:53.831221) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 05:23:53.831542) ========= 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.rDKAYbI2RU/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.Ebb2os4jb7/Listeners TMPDIR=/var/folders/rx/f1wvrtx55vx08syn1stbjr9000000w/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.005179 ========= master_lag: 0.42 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 05:23:54.260330) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 05:23:54.260640) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 05:23:54.261114) ========= ========= Total master_lag: 0.58 =========