builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0198 starttime: 1496075804.79 results: success (0) revision: 34ac1a5d6576d6775491c8a882710a1520551da6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 09:36:44.788686) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 09:36:44.789190) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 09:36:44.789510) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 09:36:44.817027) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 09:36:44.817777) ========= 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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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-29 09:36:44-- 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% 65.3M=0s 2017-05-29 09:36:44 (65.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.163008 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 09:36:45.007231) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 09:36:45.007581) ========= 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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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.028606 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 09:36:45.063604) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 09:36:45.064093) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 34ac1a5d6576d6775491c8a882710a1520551da6 --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 34ac1a5d6576d6775491c8a882710a1520551da6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZMm6mVvW9f/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.R64O8lvjt6/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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-29 09:36:45,157 truncating revision to first 12 chars 2017-05-29 09:36:45,157 Setting DEBUG logging. 2017-05-29 09:36:45,158 attempt 1/10 2017-05-29 09:36:45,158 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/34ac1a5d6576?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-29 09:36:45,814 unpacking tar archive at: mozilla-central-34ac1a5d6576/testing/mozharness/ program finished with exit code 0 elapsedTime=0.936122 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 09:36:46.022395) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 09:36:46.022786) ========= script_repo_revision: 34ac1a5d6576d6775491c8a882710a1520551da6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 09:36:46.023237) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 09:36:46.023561) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 09:36:46.042521) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-29 09:36:46.042892) ========= /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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 09:36:46 INFO - MultiFileLogger online at 20170529 09:36:46 in /builds/slave/test 09:36:46 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 09:36:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:36:46 INFO - {'append_to_log': False, 09:36:46 INFO - 'base_work_dir': '/builds/slave/test', 09:36:46 INFO - 'blob_upload_branch': 'Firefox', 09:36:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:36:46 INFO - 'branch': 'Firefox', 09:36:46 INFO - 'buildbot_json_path': 'buildprops.json', 09:36:46 INFO - 'config_files': ('talos/mac_config.py',), 09:36:46 INFO - 'default_actions': ('clobber', 09:36:46 INFO - 'read-buildbot-config', 09:36:46 INFO - 'download-and-extract', 09:36:46 INFO - 'populate-webroot', 09:36:46 INFO - 'create-virtualenv', 09:36:46 INFO - 'install', 09:36:46 INFO - 'setup-mitmproxy', 09:36:46 INFO - 'run-tests'), 09:36:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:36:46 INFO - 'download_minidump_stackwalk': True, 09:36:46 INFO - 'download_symbols': 'ondemand', 09:36:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:36:46 INFO - 'tooltool.py': '/tools/tooltool.py', 09:36:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:36:46 INFO - '/tools/misc-python/virtualenv.py')}, 09:36:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:36:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:36:46 INFO - 'gecko_profile': False, 09:36:46 INFO - 'gecko_profile_interval': 0, 09:36:46 INFO - 'installer_path': 'installer.exe', 09:36:46 INFO - 'log_level': 'info', 09:36:46 INFO - 'log_name': 'talos', 09:36:46 INFO - 'log_to_console': True, 09:36:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:36:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:36:46 INFO - 'opt_config_files': (), 09:36:46 INFO - 'pip_index': False, 09:36:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:46 INFO - 'cmd': ('bash', 09:36:46 INFO - '-c', 09:36:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:46 INFO - 'enabled': True, 09:36:46 INFO - 'halt_on_failure': False, 09:36:46 INFO - 'name': 'check_screen_resolution'},), 09:36:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:46 INFO - 'cmd': ('bash', 09:36:46 INFO - '-c', 09:36:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:46 INFO - 'enabled': True, 09:36:46 INFO - 'halt_on_failure': False, 09:36:46 INFO - 'name': 'check_screen_resolution'},), 09:36:46 INFO - 'run_cmd_checks_enabled': True, 09:36:46 INFO - 'suite': 'dromaeojs-e10s', 09:36:46 INFO - 'system_bits': '32', 09:36:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:36:46 INFO - 'title': 't-yosemite-r7-0198', 09:36:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:36:46 INFO - 'use_talos_json': True, 09:36:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:36:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:36:46 INFO - 'work_dir': 'build'} 09:36:46 INFO - [mozharness: 2017-05-29 16:36:46.247840Z] Running clobber step. 09:36:46 INFO - Running pre-action listener: _resource_record_pre_action 09:36:46 INFO - Running main action method: clobber 09:36:46 INFO - rmtree: /builds/slave/test/build 09:36:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:36:47 INFO - Running post-action listener: _resource_record_post_action 09:36:47 INFO - [mozharness: 2017-05-29 16:36:47.729540Z] Finished clobber step (success) 09:36:47 INFO - [mozharness: 2017-05-29 16:36:47.729721Z] Running read-buildbot-config step. 09:36:47 INFO - Running pre-action listener: _resource_record_pre_action 09:36:47 INFO - Running main action method: read_buildbot_config 09:36:47 INFO - Using buildbot properties: 09:36:47 INFO - { 09:36:47 INFO - "project": "", 09:36:47 INFO - "product": "firefox", 09:36:47 INFO - "who": "ffxbld@noreply.mozilla.org", 09:36:47 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 09:36:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:36:47 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:36:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:36:47 INFO - "stage_platform": "macosx64", 09:36:47 INFO - "basedir": "/builds/slave/test", 09:36:47 INFO - "buildnumber": 4, 09:36:47 INFO - "platform": "yosemite_r7", 09:36:47 INFO - "parent_task_id": "KKCJP0IgS36w1plce9Jklg", 09:36:47 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:36:47 INFO - "slavebuilddir": "test", 09:36:47 INFO - "taskId": "UzG11A3qQEGUjUcrHjJTIw", 09:36:47 INFO - "branch": "mozilla-central", 09:36:47 INFO - "script_repo_revision": "production", 09:36:47 INFO - "revision": "34ac1a5d6576d6775491c8a882710a1520551da6", 09:36:47 INFO - "slavename": "t-yosemite-r7-0198", 09:36:47 INFO - "repo_path": "mozilla-central" 09:36:47 INFO - } 09:36:47 INFO - Finding installer, test and symbols from parent task. 09:36:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UzG11A3qQEGUjUcrHjJTIw'}, attempt #1 09:36:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UzG11A3qQEGUjUcrHjJTIw'}, attempt #1 09:36:48 INFO - Task dependencies: dCvjIq1KTe6BFuMg-LxaoA KKCJP0IgS36w1plce9Jklg 09:36:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg'}, attempt #1 09:36:49 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:36:49 INFO - Set test_url: https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/test_packages.json 09:36:49 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/target.crashreporter-symbols.zip 09:36:49 INFO - Running post-action listener: _resource_record_post_action 09:36:49 INFO - [mozharness: 2017-05-29 16:36:49.434204Z] Finished read-buildbot-config step (success) 09:36:49 INFO - [mozharness: 2017-05-29 16:36:49.434309Z] Running download-and-extract step. 09:36:49 INFO - Running pre-action listener: _resource_record_pre_action 09:36:49 INFO - Running main action method: download_and_extract 09:36:49 INFO - mkdir: /builds/slave/test/build/tests 09:36:49 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')]} 09:36:49 INFO - trying https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:36:49 INFO - Downloading https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/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 09:36:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/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 09:36:50 INFO - Downloaded 1325 bytes. 09:36:50 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:36:50 INFO - Using the following test package requirements: 09:36:50 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:50 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:36:50 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:36:50 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:50 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:36:50 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:50 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:36:50 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:50 INFO - u'jsshell-mac.zip'], 09:36:50 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:50 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:36:50 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:36:50 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:50 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:36:50 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:50 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:36:50 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:50 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:36:50 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:50 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:36:50 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:36:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:36:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:36:50 INFO - Fetch https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:36:52 INFO - Content-Length response header: 57632111 09:36:52 INFO - Bytes received: 57632111 09:37:10 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 09:37:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:37:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:37:10 INFO - Fetch https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:37:12 INFO - Content-Length response header: 57632111 09:37:12 INFO - Bytes received: 57632111 09:37:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:37:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:37:29 INFO - Fetch https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:37:30 INFO - Content-Length response header: 14447425 09:37:30 INFO - Bytes received: 14447425 09:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:30 INFO - trying https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:37:30 INFO - Downloading https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:37:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/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 09:37:32 INFO - Downloaded 67801443 bytes. 09:37:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:37:32 INFO - mkdir: /builds/slave/test/properties 09:37:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:37:32 INFO - Writing to file /builds/slave/test/properties/build_url 09:37:32 INFO - Contents: 09:37:32 INFO - build_url:https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:37:32 INFO - Running post-action listener: _resource_record_post_action 09:37:32 INFO - Running post-action listener: set_extra_try_arguments 09:37:32 INFO - [mozharness: 2017-05-29 16:37:32.834476Z] Finished download-and-extract step (success) 09:37:32 INFO - [mozharness: 2017-05-29 16:37:32.834639Z] Running populate-webroot step. 09:37:32 INFO - Running pre-action listener: _resource_record_pre_action 09:37:32 INFO - Running main action method: populate_webroot 09:37:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:37:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:37:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:37:32 INFO - '--tppagecycles', 09:37:32 INFO - '7'], 09:37:32 INFO - 'tests': ['tsvgm']}}, 09:37:32 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:37:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:37:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:37:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:37:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:37:32 INFO - 'talos_options': ['--disable-e10s'], 09:37:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:37:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:37:32 INFO - 'talos_options': ['--disable-e10s'], 09:37:32 INFO - 'tests': ['damp', 'tps']}, 09:37:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:37:32 INFO - 'tests': ['damp', 'tps']}, 09:37:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:37:32 INFO - 'tests': ['dromaeo_dom']}, 09:37:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:37:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:37:32 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:37:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:37:32 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:37:32 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:37:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:37:32 INFO - 'tests': ['a11yr', 09:37:32 INFO - 'ts_paint', 09:37:32 INFO - 'tpaint', 09:37:32 INFO - 'sessionrestore', 09:37:32 INFO - 'sessionrestore_no_auto_restore', 09:37:32 INFO - 'tabpaint']}, 09:37:32 INFO - 'other-e10s': {'tests': ['a11yr', 09:37:32 INFO - 'ts_paint', 09:37:32 INFO - 'tpaint', 09:37:32 INFO - 'sessionrestore', 09:37:32 INFO - 'sessionrestore_no_auto_restore', 09:37:32 INFO - 'tabpaint']}, 09:37:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:37:32 INFO - 'ts_paint', 09:37:32 INFO - 'tpaint', 09:37:32 INFO - 'sessionrestore', 09:37:32 INFO - 'sessionrestore_no_auto_restore', 09:37:32 INFO - 'tabpaint']}, 09:37:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:37:32 INFO - 'ts_paint', 09:37:32 INFO - 'tpaint', 09:37:32 INFO - 'sessionrestore', 09:37:32 INFO - 'sessionrestore_no_auto_restore', 09:37:32 INFO - 'tabpaint']}, 09:37:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:37:32 INFO - 'tests': ['a11yr', 09:37:32 INFO - 'ts_paint', 09:37:32 INFO - 'tpaint', 09:37:32 INFO - 'sessionrestore', 09:37:32 INFO - 'sessionrestore_no_auto_restore', 09:37:32 INFO - 'tabpaint']}, 09:37:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:37:32 INFO - 'tests': ['a11yr', 09:37:32 INFO - 'ts_paint', 09:37:32 INFO - 'tpaint', 09:37:32 INFO - 'sessionrestore', 09:37:32 INFO - 'sessionrestore_no_auto_restore', 09:37:32 INFO - 'tabpaint']}, 09:37:32 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:37:32 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:37:32 INFO - 'bloom_basic_ref']}, 09:37:32 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 09:37:32 INFO - 'talos_options': ['--mitmproxy', 09:37:32 INFO - 'mitmproxy-recording-1.mp', 09:37:32 INFO - '--firstNonBlankPaint'], 09:37:32 INFO - 'tests': ['Quantum_1']}, 09:37:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:37:32 INFO - 'tests': ['tsvgx', 09:37:32 INFO - 'tsvgr_opacity', 09:37:32 INFO - 'tart', 09:37:32 INFO - 'tscrollx', 09:37:32 INFO - 'cart', 09:37:32 INFO - 'tsvg_static']}, 09:37:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:37:32 INFO - 'tsvgr_opacity', 09:37:32 INFO - 'tart', 09:37:32 INFO - 'tscrollx', 09:37:32 INFO - 'cart', 09:37:32 INFO - 'tsvg_static']}, 09:37:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:37:32 INFO - 'talos_options': ['--disable-e10s'], 09:37:32 INFO - 'tests': ['tp5o']}, 09:37:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:37:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:37:32 INFO - 'talos_options': ['--disable-e10s', 09:37:32 INFO - '--xperf_path', 09:37:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:32 INFO - 'tests': ['tp5n']}, 09:37:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:37:32 INFO - 'talos_options': ['--xperf_path', 09:37:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:32 INFO - 'tests': ['tp5n']}}, 09:37:32 INFO - 'talos.zip': {'path': '', 09:37:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:37:32 INFO - Running post-action listener: _resource_record_post_action 09:37:32 INFO - [mozharness: 2017-05-29 16:37:32.856421Z] Finished populate-webroot step (success) 09:37:32 INFO - [mozharness: 2017-05-29 16:37:32.856560Z] Running create-virtualenv step. 09:37:32 INFO - Running pre-action listener: _resource_record_pre_action 09:37:32 INFO - Running main action method: create_virtualenv 09:37:32 INFO - Creating virtualenv /builds/slave/test/build/venv 09:37:32 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 09:37:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:37:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:37:32 INFO - Using real prefix '/tools/python27' 09:37:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:37:33 INFO - Installing distribute.............................................................................................................................................................................................done. 09:37:36 INFO - Installing pip.................done. 09:37:36 INFO - Return code: 0 09:37:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:37:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:37:36 INFO - Reading from file tmpfile_stdout 09:37:36 INFO - Output received: 09:37:36 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) 09:37:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dddbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dddf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd041d60dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd64c60>, '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 0x10daf9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22be8>, '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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 09:37:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:37:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:37:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMm6mVvW9f/Render', 09:37:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:36 INFO - 'HOME': '/Users/cltbld', 09:37:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:36 INFO - 'LOGNAME': 'cltbld', 09:37:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:36 INFO - 'MOZ_NO_REMOTE': '1', 09:37:36 INFO - 'NO_EM_RESTART': '1', 09:37:36 INFO - 'PAGER': '/bin/cat', 09:37:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:36 INFO - 'PWD': '/builds/slave/test', 09:37:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:36 INFO - 'SHELL': '/bin/bash', 09:37:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R64O8lvjt6/Listeners', 09:37:36 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 09:37:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:36 INFO - 'USER': 'cltbld', 09:37:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:36 INFO - 'XPC_FLAGS': '0x0', 09:37:36 INFO - 'XPC_SERVICE_NAME': '0', 09:37:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:37 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 09:37:37 INFO - Cleaning up... 09:37:37 INFO - Return code: 0 09:37:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:37:37 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')]} 09:37:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:37 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')]} 09:37:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:37 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 0x10dddbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dddf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd041d60dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd64c60>, '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 0x10daf9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22be8>, '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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 09:37:37 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 09:37:37 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 09:37:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMm6mVvW9f/Render', 09:37:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:37 INFO - 'HOME': '/Users/cltbld', 09:37:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:37 INFO - 'LOGNAME': 'cltbld', 09:37:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:37 INFO - 'MOZ_NO_REMOTE': '1', 09:37:37 INFO - 'NO_EM_RESTART': '1', 09:37:37 INFO - 'PAGER': '/bin/cat', 09:37:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:37 INFO - 'PWD': '/builds/slave/test', 09:37:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:37 INFO - 'SHELL': '/bin/bash', 09:37:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R64O8lvjt6/Listeners', 09:37:37 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 09:37:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:37 INFO - 'USER': 'cltbld', 09:37:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:37 INFO - 'XPC_FLAGS': '0x0', 09:37:37 INFO - 'XPC_SERVICE_NAME': '0', 09:37:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:37 INFO - Downloading/unpacking psutil>=3.1.1 09:37: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 09:37: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 09:37: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 09:37: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 09:37:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:37:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:39 INFO - Installing collected packages: psutil 09:37:39 INFO - Running setup.py install for psutil 09:37:39 INFO - building 'psutil._psutil_osx' extension 09:37:39 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 09:37:40 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 09:37:40 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 09:37:40 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 09:37:40 INFO - building 'psutil._psutil_posix' extension 09:37:40 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 09:37:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:37:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:37:40 INFO - ^ 09:37:40 INFO - 1 warning generated. 09:37:40 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 09:37:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:40 INFO - Successfully installed psutil 09:37:40 INFO - Cleaning up... 09:37:40 INFO - Return code: 0 09:37:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:37:40 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')]} 09:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:40 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')]} 09:37:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:40 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 0x10dddbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dddf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd041d60dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd64c60>, '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 0x10daf9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22be8>, '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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 09:37:40 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 09:37:40 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 09:37:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMm6mVvW9f/Render', 09:37:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:40 INFO - 'HOME': '/Users/cltbld', 09:37:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:40 INFO - 'LOGNAME': 'cltbld', 09:37:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:40 INFO - 'MOZ_NO_REMOTE': '1', 09:37:40 INFO - 'NO_EM_RESTART': '1', 09:37:40 INFO - 'PAGER': '/bin/cat', 09:37:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:40 INFO - 'PWD': '/builds/slave/test', 09:37:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:40 INFO - 'SHELL': '/bin/bash', 09:37:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R64O8lvjt6/Listeners', 09:37:40 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 09:37:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:40 INFO - 'USER': 'cltbld', 09:37:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:40 INFO - 'XPC_FLAGS': '0x0', 09:37:40 INFO - 'XPC_SERVICE_NAME': '0', 09:37:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:37:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:37:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:37:43 INFO - Installing collected packages: mozsystemmonitor 09:37:43 INFO - Running setup.py install for mozsystemmonitor 09:37:43 INFO - Successfully installed mozsystemmonitor 09:37:43 INFO - Cleaning up... 09:37:43 INFO - Return code: 0 09:37:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:37:43 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')]} 09:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:43 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')]} 09:37:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:43 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 0x10dddbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dddf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd041d60dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd64c60>, '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 0x10daf9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22be8>, '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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 09:37:43 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 09:37:43 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 09:37:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMm6mVvW9f/Render', 09:37:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:43 INFO - 'HOME': '/Users/cltbld', 09:37:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:43 INFO - 'LOGNAME': 'cltbld', 09:37:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:43 INFO - 'MOZ_NO_REMOTE': '1', 09:37:43 INFO - 'NO_EM_RESTART': '1', 09:37:43 INFO - 'PAGER': '/bin/cat', 09:37:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:43 INFO - 'PWD': '/builds/slave/test', 09:37:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:43 INFO - 'SHELL': '/bin/bash', 09:37:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R64O8lvjt6/Listeners', 09:37:43 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 09:37:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:43 INFO - 'USER': 'cltbld', 09:37:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:43 INFO - 'XPC_FLAGS': '0x0', 09:37:43 INFO - 'XPC_SERVICE_NAME': '0', 09:37:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:43 INFO - Downloading/unpacking jsonschema==2.5.1 09:37:43 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 09:37:43 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 09:37:43 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 09:37:43 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 09:37:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:37:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:37:45 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 09:37:45 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 09:37:45 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 09:37:45 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 09:37:45 INFO - Downloading functools32-3.2.3-2.tar.gz 09:37:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:37:45 INFO - warning: no files found matching '*.txt' 09:37:45 INFO - no previously-included directories found matching 'build' 09:37:45 INFO - no previously-included directories found matching 'dist' 09:37:45 INFO - no previously-included directories found matching '.git*' 09:37:45 INFO - Installing collected packages: jsonschema, functools32 09:37:45 INFO - Running setup.py install for functools32 09:37:45 INFO - warning: no files found matching '*.txt' 09:37:45 INFO - no previously-included directories found matching 'build' 09:37:45 INFO - no previously-included directories found matching 'dist' 09:37:45 INFO - no previously-included directories found matching '.git*' 09:37:45 INFO - Successfully installed jsonschema functools32 09:37:45 INFO - Cleaning up... 09:37:45 INFO - Return code: 0 09:37:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:37:45 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')]} 09:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:45 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')]} 09:37:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:45 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 0x10dddbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dddf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd041d60dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd64c60>, '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 0x10daf9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22be8>, '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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 09:37:45 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 09:37:45 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 09:37:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMm6mVvW9f/Render', 09:37:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:45 INFO - 'HOME': '/Users/cltbld', 09:37:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:45 INFO - 'LOGNAME': 'cltbld', 09:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:45 INFO - 'MOZ_NO_REMOTE': '1', 09:37:45 INFO - 'NO_EM_RESTART': '1', 09:37:45 INFO - 'PAGER': '/bin/cat', 09:37:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:45 INFO - 'PWD': '/builds/slave/test', 09:37:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:45 INFO - 'SHELL': '/bin/bash', 09:37:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R64O8lvjt6/Listeners', 09:37:45 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 09:37:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:45 INFO - 'USER': 'cltbld', 09:37:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:45 INFO - 'XPC_FLAGS': '0x0', 09:37:45 INFO - 'XPC_SERVICE_NAME': '0', 09:37:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:37:46 INFO - Cleaning up... 09:37:46 INFO - Return code: 0 09:37:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37: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')]} 09:37:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dddbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dddf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd041d60dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd64c60>, '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 0x10daf9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22be8>, '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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 09:37:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:37:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMm6mVvW9f/Render', 09:37:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:46 INFO - 'HOME': '/Users/cltbld', 09:37:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:46 INFO - 'LOGNAME': 'cltbld', 09:37:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:46 INFO - 'MOZ_NO_REMOTE': '1', 09:37:46 INFO - 'NO_EM_RESTART': '1', 09:37:46 INFO - 'PAGER': '/bin/cat', 09:37:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:46 INFO - 'PWD': '/builds/slave/test', 09:37:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:46 INFO - 'SHELL': '/bin/bash', 09:37:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R64O8lvjt6/Listeners', 09:37:46 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 09:37:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:46 INFO - 'USER': 'cltbld', 09:37:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:46 INFO - 'XPC_FLAGS': '0x0', 09:37:46 INFO - 'XPC_SERVICE_NAME': '0', 09:37:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:46 INFO - Downloading/unpacking blobuploader==1.2.4 09:37:46 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 09:37:46 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 09:37:46 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 09:37:46 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 09:37:47 INFO - Downloading blobuploader-1.2.4.tar.gz 09:37:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:37:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:37:47 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 09:37:47 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 09:37:47 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 09:37:47 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 09:37:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:37:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:37: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 09:37: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 09:37: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 09:37: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 09:37:48 INFO - Downloading docopt-0.6.1.tar.gz 09:37:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:37:48 INFO - Installing collected packages: blobuploader, requests, docopt 09:37:48 INFO - Running setup.py install for blobuploader 09:37:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:37:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:37:48 INFO - Running setup.py install for requests 09:37:49 INFO - Running setup.py install for docopt 09:37:49 INFO - Successfully installed blobuploader requests docopt 09:37:49 INFO - Cleaning up... 09:37:49 INFO - Return code: 0 09:37:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:49 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')]} 09:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:49 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')]} 09:37:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:49 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 0x10dddbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dddf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd041d60dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd64c60>, '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 0x10daf9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22be8>, '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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 09:37:49 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 09:37:49 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 09:37:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMm6mVvW9f/Render', 09:37:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:49 INFO - 'HOME': '/Users/cltbld', 09:37:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:49 INFO - 'LOGNAME': 'cltbld', 09:37:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:49 INFO - 'MOZ_NO_REMOTE': '1', 09:37:49 INFO - 'NO_EM_RESTART': '1', 09:37:49 INFO - 'PAGER': '/bin/cat', 09:37:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:49 INFO - 'PWD': '/builds/slave/test', 09:37:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:49 INFO - 'SHELL': '/bin/bash', 09:37:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R64O8lvjt6/Listeners', 09:37:49 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 09:37:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:49 INFO - 'USER': 'cltbld', 09:37:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:49 INFO - 'XPC_FLAGS': '0x0', 09:37:49 INFO - 'XPC_SERVICE_NAME': '0', 09:37:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:49 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-6PBYkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:49 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-maM95Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:50 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-exc9Dx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:50 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-ba1AKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:50 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Tg7y3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:50 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-VacH4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:50 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-oZDU0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:50 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-F5pmb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:50 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Djrzfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:50 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-6ZkF4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:50 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-d4qybN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:50 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-7mfM22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:51 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Li5toF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:51 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-aLZBVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:51 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-uO7h8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:51 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-u7J2_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:51 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-3KViCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:37:51 INFO - Running setup.py install for manifestparser 09:37:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:37:51 INFO - Running setup.py install for mozcrash 09:37:51 INFO - Running setup.py install for mozdebug 09:37:52 INFO - Running setup.py install for mozdevice 09:37:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:37:52 INFO - Running setup.py install for mozfile 09:37:52 INFO - Running setup.py install for mozhttpd 09:37:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:37:52 INFO - Running setup.py install for mozinfo 09:37:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:37:52 INFO - Running setup.py install for mozInstall 09:37:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:37:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:37:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:37:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:37:52 INFO - Running setup.py install for mozleak 09:37:52 INFO - Running setup.py install for mozlog 09:37:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:37:53 INFO - Running setup.py install for moznetwork 09:37:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:37:53 INFO - Running setup.py install for mozprocess 09:37:53 INFO - Running setup.py install for mozprofile 09:37:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:37:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:37:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:37:53 INFO - Running setup.py install for mozrunner 09:37:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:37:53 INFO - Running setup.py install for mozscreenshot 09:37:53 INFO - Running setup.py install for moztest 09:37:53 INFO - Running setup.py install for mozversion 09:37:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:37:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:37:54 INFO - Cleaning up... 09:37:54 INFO - Return code: 0 09:37:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:54 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')]} 09:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:54 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')]} 09:37:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:54 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 0x10dddbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dddf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd041d60dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd64c60>, '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 0x10daf9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22be8>, '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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 09:37:54 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 09:37:54 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 09:37:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMm6mVvW9f/Render', 09:37:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:54 INFO - 'HOME': '/Users/cltbld', 09:37:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:54 INFO - 'LOGNAME': 'cltbld', 09:37:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:54 INFO - 'MOZ_NO_REMOTE': '1', 09:37:54 INFO - 'NO_EM_RESTART': '1', 09:37:54 INFO - 'PAGER': '/bin/cat', 09:37:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:54 INFO - 'PWD': '/builds/slave/test', 09:37:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:54 INFO - 'SHELL': '/bin/bash', 09:37:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R64O8lvjt6/Listeners', 09:37:54 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 09:37:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:54 INFO - 'USER': 'cltbld', 09:37:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:54 INFO - 'XPC_FLAGS': '0x0', 09:37:54 INFO - 'XPC_SERVICE_NAME': '0', 09:37:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:54 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-pFi3eb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:54 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)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:54 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-FqvRXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:54 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)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:54 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-8s2TWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:54 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)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:54 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-FBGW_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:54 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-4B9nnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:54 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)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:54 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-FaWhCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:55 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)) 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:55 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-hiDibN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:55 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)) 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:55 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-XSEK8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:55 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)) 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:55 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-comQOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:55 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)) 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:55 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-UWrN_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:55 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)) 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:55 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-O3Aj_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:55 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)) 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:55 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-lw9CGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:55 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)) 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:55 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-khhEZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:55 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)) 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:55 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-KKh0n2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:55 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)) 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:55 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-4B3s6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:56 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)) 09:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:56 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Serq3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:56 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)) 09:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:56 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-TESnCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:56 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)) 09:37:56 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)) 09:37:56 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)) 09:37:56 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)) 09:37:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:37:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:37:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:37:56 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 09:37:56 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 09:37:56 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 09:37:56 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 09:37:57 INFO - Downloading blessings-1.6.tar.gz 09:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:37:57 INFO - Installing collected packages: blessings 09:37:57 INFO - Running setup.py install for blessings 09:37:57 INFO - Successfully installed blessings 09:37:57 INFO - Cleaning up... 09:37:57 INFO - Return code: 0 09:37:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:37:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:58 INFO - Reading from file tmpfile_stdout 09:37:58 INFO - Current package versions: 09:37:58 INFO - blessings == 1.6 09:37:58 INFO - blobuploader == 1.2.4 09:37:58 INFO - docopt == 0.6.1 09:37:58 INFO - functools32 == 3.2.3-2 09:37:58 INFO - jsonschema == 2.5.1 09:37:58 INFO - manifestparser == 1.1 09:37:58 INFO - mozInstall == 1.12 09:37:58 INFO - mozcrash == 1.0 09:37:58 INFO - mozdebug == 0.1 09:37:58 INFO - mozdevice == 0.51 09:37:58 INFO - mozfile == 1.2 09:37:58 INFO - mozhttpd == 0.7 09:37:58 INFO - mozinfo == 0.9 09:37:58 INFO - mozleak == 0.1 09:37:58 INFO - mozlog == 3.4 09:37:58 INFO - moznetwork == 0.27 09:37:58 INFO - mozprocess == 0.25 09:37:58 INFO - mozprofile == 0.28 09:37:58 INFO - mozrunner == 6.13 09:37:58 INFO - mozscreenshot == 0.1 09:37:58 INFO - mozsystemmonitor == 0.3 09:37:58 INFO - moztest == 0.8 09:37:58 INFO - mozversion == 1.4 09:37:58 INFO - psutil == 3.1.1 09:37:58 INFO - requests == 1.2.3 09:37:58 INFO - wsgiref == 0.1.2 09:37:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:58 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 0x10dddbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dddf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd041d60dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd64c60>, '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 0x10daf9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22be8>, '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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 09:37:58 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 09:37:58 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 09:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMm6mVvW9f/Render', 09:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:58 INFO - 'HOME': '/Users/cltbld', 09:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:58 INFO - 'LOGNAME': 'cltbld', 09:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:58 INFO - 'MOZ_NO_REMOTE': '1', 09:37:58 INFO - 'NO_EM_RESTART': '1', 09:37:58 INFO - 'PAGER': '/bin/cat', 09:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:58 INFO - 'PWD': '/builds/slave/test', 09:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:58 INFO - 'SHELL': '/bin/bash', 09:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R64O8lvjt6/Listeners', 09:37:58 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 09:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:58 INFO - 'USER': 'cltbld', 09:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:58 INFO - 'XPC_FLAGS': '0x0', 09:37:58 INFO - 'XPC_SERVICE_NAME': '0', 09:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:37:58 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 09:37:58 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 09:37:58 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 09:37:58 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 09:37:59 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:38:00 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)) 09:38:00 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)) 09:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:38:00 INFO - Installing collected packages: marionette-driver 09:38:00 INFO - Running setup.py install for marionette-driver 09:38:00 INFO - Successfully installed marionette-driver 09:38:00 INFO - Cleaning up... 09:38:00 INFO - Return code: 0 09:38:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dddbc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dddf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd041d60dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd64c60>, '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 0x10daf9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22be8>, '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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 09:38:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:38:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:38:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMm6mVvW9f/Render', 09:38:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:00 INFO - 'HOME': '/Users/cltbld', 09:38:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:00 INFO - 'LOGNAME': 'cltbld', 09:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:00 INFO - 'MOZ_NO_REMOTE': '1', 09:38:00 INFO - 'NO_EM_RESTART': '1', 09:38:00 INFO - 'PAGER': '/bin/cat', 09:38:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:00 INFO - 'PWD': '/builds/slave/test', 09:38:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:00 INFO - 'SHELL': '/bin/bash', 09:38:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R64O8lvjt6/Listeners', 09:38:00 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 09:38:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:00 INFO - 'USER': 'cltbld', 09:38:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:00 INFO - 'XPC_FLAGS': '0x0', 09:38:00 INFO - 'XPC_SERVICE_NAME': '0', 09:38:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:38:00 INFO - Cleaning up... 09:38:00 INFO - Return code: 0 09:38:00 INFO - Running post-action listener: _resource_record_post_action 09:38:00 INFO - Running post-action listener: _start_resource_monitoring 09:38:00 INFO - Starting resource monitoring. 09:38:00 INFO - [mozharness: 2017-05-29 16:38:00.535281Z] Finished create-virtualenv step (success) 09:38:00 INFO - [mozharness: 2017-05-29 16:38:00.536322Z] Running install step. 09:38:00 INFO - Running pre-action listener: _resource_record_pre_action 09:38:00 INFO - Running main action method: install 09:38:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:00 INFO - Reading from file tmpfile_stdout 09:38:00 INFO - Detecting whether we're running mozinstall >=1.0... 09:38:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:38:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:38:00 INFO - Reading from file tmpfile_stdout 09:38:00 INFO - Output received: 09:38:00 INFO - Usage: mozinstall [options] installer 09:38:00 INFO - Options: 09:38:00 INFO - -h, --help show this help message and exit 09:38:00 INFO - -d DEST, --destination=DEST 09:38:00 INFO - Directory to install application into. [default: 09:38:00 INFO - "/builds/slave/test"] 09:38:00 INFO - --app=APP Application being installed. [default: firefox] 09:38:00 INFO - mkdir: /builds/slave/test/build/application 09:38:00 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'] 09:38:00 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 09:38:22 INFO - Reading from file tmpfile_stdout 09:38:22 INFO - Output received: 09:38:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:38:22 INFO - Running post-action listener: _resource_record_post_action 09:38:22 INFO - [mozharness: 2017-05-29 16:38:22.440957Z] Finished install step (success) 09:38:22 INFO - [mozharness: 2017-05-29 16:38:22.441049Z] Running setup-mitmproxy step. 09:38:22 INFO - Running pre-action listener: _resource_record_pre_action 09:38:22 INFO - Running main action method: setup_mitmproxy 09:38:22 INFO - Skipping: mitmproxy is not required 09:38:22 INFO - Running post-action listener: _resource_record_post_action 09:38:22 INFO - [mozharness: 2017-05-29 16:38:22.441705Z] Finished setup-mitmproxy step (success) 09:38:22 INFO - [mozharness: 2017-05-29 16:38:22.441871Z] Running run-tests step. 09:38:22 INFO - Running pre-action listener: _resource_record_pre_action 09:38:22 INFO - Running main action method: run_tests 09:38:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:22 INFO - 2017-05-29 09:38:22.454 screenresolution[1624:7354] starting screenresolution argv=screenresolution get 09:38:22 INFO - 2017-05-29 09:38:22.457 screenresolution[1624:7354] Display 0: 1600x1200x32@60 09:38:22 INFO - 2017-05-29 09:38:22.462 screenresolution[1625:7357] starting screenresolution argv=screenresolution list 09:38:22 INFO - Available Modes on Display 0 09:38:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:22 INFO - Intel Iris: 09:38:22 INFO - Chipset Model: Intel Iris 09:38:22 INFO - Type: GPU 09:38:22 INFO - Bus: Built-In 09:38:22 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:22 INFO - Vendor: Intel (0x8086) 09:38:22 INFO - Device ID: 0x0a2e 09:38:22 INFO - Revision ID: 0x0009 09:38:22 INFO - Displays: 09:38:22 INFO - Display: 09:38:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:22 INFO - Display Serial Number: 1600x1200 60 09:38:22 INFO - Main Display: Yes 09:38:22 INFO - Mirror: Off 09:38:22 INFO - Online: Yes 09:38:22 INFO - Rotation: Supported 09:38:22 INFO - Return code: 0 09:38:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:38:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:38:22 INFO - Python 2.7.3 09:38:22 INFO - Return code: 0 09:38:22 INFO - grabbing minidump binary from tooltool 09:38: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')]} 09:38:22 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 0x10dd64c60>, '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 0x10daf9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:38:22 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 09:38:22 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 09:38:22 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 09:38:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:38:22 INFO - Return code: 0 09:38:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:38:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:38:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:38:22 INFO - ENV: RUST_BACKTRACE is now 1 09:38:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:38:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:38:22 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/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0198', '--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 09:38:22 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/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0198 --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 09:38:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZMm6mVvW9f/Render', 09:38:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:22 INFO - 'HOME': '/Users/cltbld', 09:38:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:22 INFO - 'LOGNAME': 'cltbld', 09:38:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:38:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:38:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:22 INFO - 'MOZ_NO_REMOTE': '1', 09:38:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:38:22 INFO - 'NO_EM_RESTART': '1', 09:38:22 INFO - 'PAGER': '/bin/cat', 09:38:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:22 INFO - 'PWD': '/builds/slave/test', 09:38:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:38:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:22 INFO - 'RUST_BACKTRACE': '1', 09:38:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:38:22 INFO - 'SHELL': '/bin/bash', 09:38:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R64O8lvjt6/Listeners', 09:38:22 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 09:38:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:22 INFO - 'USER': 'cltbld', 09:38:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:22 INFO - 'XPC_FLAGS': '0x0', 09:38:22 INFO - 'XPC_SERVICE_NAME': '0', 09:38:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:22 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/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0198', '--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 09:38:23 INFO - mozversion application_buildid: 20170529082035 09:38:23 INFO - mozversion application_changeset: 34ac1a5d6576d6775491c8a882710a1520551da6 09:38:23 INFO - mozversion application_display_name: Nightly 09:38:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:38:23 INFO - mozversion application_name: Firefox 09:38:23 INFO - mozversion application_remotingname: firefox 09:38:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:38:23 INFO - mozversion application_vendor: Mozilla 09:38:23 INFO - mozversion application_version: 55.0a1 09:38:23 INFO - mozversion platform_buildid: 20170529082035 09:38:23 INFO - mozversion platform_changeset: 34ac1a5d6576d6775491c8a882710a1520551da6 09:38:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:38:23 INFO - mozversion platform_version: 55.0a1 09:38:23 INFO - using testdate: 1496075903 09:38:23 INFO - actual date: 1496075903 09:38:23 INFO - starting webserver on 'localhost:49223' 09:38:23 INFO - SUITE-START | Running 2 tests 09:38:23 INFO - TEST-START | dromaeo_css 09:38:23 INFO - operating with platform_type : mac_ 09:38:23 INFO - Initialising browser for dromaeo_css test... 09:38:23 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49223/getInfo.html -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpDlW4hT/profile 09:38:23 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49223/getInfo.html -foreground) 09:38:57 INFO - TEST-INFO | 1635: killed by SIGTERM 09:38:57 INFO - Browser initialized. 09:38:57 INFO - Running cycle 1/1 for dromaeo_css test... 09:38:57 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpDlW4hT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:59 INFO - PID 1642 | 2017-05-29 09:38:59.428 plugin-container[1643:7857] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 09:38:59 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:59 INFO - PID 1642 | 2017-05-29 09:38:59.429 plugin-container[1643:7857] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b23, name = 'com.apple.CFPasteboardClient' 09:38:59 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:59 INFO - PID 1642 | 2017-05-29 09:38:59.429 plugin-container[1643:7857] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:59 INFO - PID 1642 | 2017-05-29 09:38:59.946 plugin-container[1644:7898] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b43, name = 'com.apple.tsm.portname' 09:38:59 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:59 INFO - PID 1642 | 2017-05-29 09:38:59.947 plugin-container[1644:7898] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x490b, name = 'com.apple.CFPasteboardClient' 09:38:59 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:59 INFO - PID 1642 | 2017-05-29 09:38:59.947 plugin-container[1644:7898] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:39:09 INFO - PID 1642 | RSS: Main: 261828608 09:39:09 INFO - PID 1642 | 09:42:12 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49223/tests/dromaeo/cssquery-ext.html) 09:42:14 INFO - PID 1642 | RSS: Main: 267440128 09:42:14 INFO - PID 1642 | 09:45:16 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-ext.html (next: http://localhost:49223/tests/dromaeo/cssquery-jquery.html) 09:45:16 INFO - PID 1642 | RSS: Main: 283193344 09:45:16 INFO - PID 1642 | 09:48:18 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49223/tests/dromaeo/cssquery-mootools.html) 09:48:18 INFO - PID 1642 | RSS: Main: 282222592 09:48:18 INFO - PID 1642 | 09:51:20 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49223/tests/dromaeo/cssquery-prototype.html) 09:51:21 INFO - PID 1642 | RSS: Main: 285970432 09:51:21 INFO - PID 1642 | 09:54:24 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49223/tests/dromaeo/cssquery-yui.html) 09:54:24 INFO - PID 1642 | RSS: Main: 286150656 09:54:24 INFO - PID 1642 | 09:57:26 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-yui.html (next: http://localhost:49223/tests/dromaeo/cssquery-dojo.html) 09:57:26 INFO - PID 1642 | RSS: Main: 285995008 09:57:26 INFO - PID 1642 | 09:57:26 INFO - PID 1642 | __start_tp_report 09:57:26 INFO - PID 1642 | _x_x_mozilla_page_load 09:57:26 INFO - PID 1642 | _x_x_mozilla_page_load_details 09:57:26 INFO - PID 1642 | |i|pagename|runs| 09:57:26 INFO - PID 1642 | |0;dojo.html;3562.358775420424;3582.7492075554724;3622.9456558151614;3634.382155033644;3640.7815531068136;5625.381208067114;6119.5330196898485;6140.938590614092;6176.289726681433;6209.937900620993;4874.585660218886;5925.111233315005;6140.780336713802;6162.568620196588;6233.1585235993125;5387.24578559002;5388.838334849941;5398.73006349682;5399.757010934498;5409.864753381177;5464.972675136639;5508.614396992212;5508.9724551377185;5522.088855338884;5536.778528858841;5338.012467693489;5391.110466772978;5438.293021907166;5493.176023596453;5508.3114610673665;5432.972835135839;5444.91832622511;5481.561475081984;5487.259220005328;5506.91739623902;4701.459332176775;5211.957608478281;5359.598030147755;5434.076207044773;5442.12925931853;6103.908441373339;6196.845078873019;6203.87592248157;6205.22434695663;6207.410296021871;5470.589705772043;5620.494155526022;6470.773522926722;6594.406503414716;6746.865062698767;6844.452443804484;6848.828779280508;6860.245373008965;6865.382115609569;6866.725330986755;6202.410770976705;6290.056491526308;6312.621242725451;6319.178506794179;6329.556931014785;6753.932460675429;6833.58998460094;6851;6909.550879192836;6948.791536253921;6430.485561155097;6447.097406363113;6507.154069970874;6609.834754131089;6628.569143005786;6684.16447944007;6783.152105986752;6786.864262714718;6815.420689241371;6821.795346139624;6699.732010719614;6701.765438209639;6708.698108585126;6717.361850624183;6747.662616869137;6517.348265173445;6565.737370505221;6627.3372662733345;6703.363180497845;6756.966215168893;5357.624966252403;5372.973135134377;5384.057789886754;5405.270288511001;5446.537044351275;4790.233562630032;4791.95208047922;4822.083804077214;4837.4920633333695;4850.369451971221;6188.195534580475;6280.968595157086;6342.080398342307;6391.712372943229;6427.389398007182;6691.866162676719;6802.591844489347;6814.761483348059;6821.3519715626935;6835.282295358916;6371.585846919936;6381.680915954184;6389.201349831271;6394;6407.807765766941;5819.592628516048;5980.85047873798;6001;6021.307549631761;6028.125921741411;6796.694148763218;6887;6947.40947019509;6978.232394436709;7000.334968178218;6704.865902682017;6805.489588280998;6819.761308354184;6824.249332573314;6839.487038472234;6962.3733863950465;6994.5803251805055;6995.055667484824;7020.192677841909;7033.331833475811;5465.508104270635;6494.090827284279;6559.967200163968;6579.342065793382;6652.4345430637;6799.796006119825;6813;6816.182058153054;6820.624865632438;6831.487638427038;6373.203349581302;6458.773942911976;6524.086627872007;6592.208934927839;6635.236947750877;6405.03924411342;6442.09810626522;6535.836604084936;6537.901931471127;6539.019147127969;6350.555461117678;6439.581427207217;6444.130042444398;6470.870582588416;6489.967550162408;6561.081448597296;6562.737490500326;6581.736730530725;6588.934110658832;6598.406143447113;6028.487578555872;6255.906161407492;6313.715882785377;6318.462930650946;6385.233771947396 09:57:26 INFO - PID 1642 | |1;ext.html;794.4478587381768;828.3994104274401;845.3828705045319;1268.9999999999993;1307.0458565247375;39935.80032099839;40525.79737101318;40606.18787624246;40607.59392406075;40639.59360406395;40488.79755601225;40718.38922416166;41113.794431027876;41290.38064429036;41979.37030944538;7923.4057445691515;8107.513549187038;8365.12166222547;8486.78783030423;8490.702825401113;8320.334373250156;8327.084020757711;8453.450525715809;8455.53494557799;8455.915440845605;8364.707235246768;8401.03388110369;8420.157984201562;8451.492910425393;8452.915470845275;8368.079511253758;8418.116097809734;8454;8469.534175620338;8472.237498625092;8249.917500825035;8282.461639993442;8291.917080829175;8295.502269863828;8337.207965243293;37121;37210.813945930095;37260.578062426124;37302.456266654626;37349.81325093385;3561.3411518869093;3569.0542006368332;3611.079174810433;3611.205534782344;3615.5480564929385;27160.728392716017;27923.301917452023;30242.243943901358;30645.540316895265;30726;3056.877724891002;3117.407692538433;3128.2023084113634;3139.02690166047;3150.763692723032;225493;228804;260568;281916.59041704866;282224.5888770564;47841;48024.55926322216;48053.75973120112;48128.5187148131;48257.5174248253;47344.28983565249;50719.73200670013;50850.98298034092;53101.93796124056;54860.725696371264;14563.854361456462;14729.779053314209;14810.555683329301;14818.925905370405;14832.961692681409;11908.404579770977;11914.940425297818;11996.880031199751;12024.639260822189;12050.156489045683;13848.030637855454;13950.302484875716;13953.581392558037;13990;14074.788878166835;5199.714015729096;6219.813405597839;6267.9373206268265;6268.247810262797;6296.968515157395;1001.2390583156853;1017.8473229015707;1024.262530977695;1043.7547176413536;1047.4710271312938;23525.764742352356;24741.62887556688;24755.267131300636;24855.254342369757;24857.13000045026;1110.838928355384;1112.2992514715838;1147.5983405808067;1151.3264740126951;1153.4809335798925;12556.30940298274;12726.872731272753;12730.363481825872;12750.872491274968;12840.807387889188;313445;314196;314974;315337;316252;54427.18359224615;54629.726851365485;54925;55101.89796204054;55191;1469.6840179361488;1497.633079895411;1529.06726896593;1529.3729570875887;1532.1802835483186;23952;24149;24184.637230441207;24216;24289.635655465536;38091;38102.475900963655;38238.808805956905;38274.0431489215;38536.036599085244;254618.7269063643;255380.44619553565;255785;256331;256599.7170014138;34336.96989090331;34737.478937815446;35378;35564;35650.8217458911;43254;44074;44761.55238447704;44856;44938.651840444836;29846.55230171506;29927;30298.69701303047;30791;31135.84432077825;29879.850600747726;30024.69975300219;30500.08499745011;30978.69021309848;31245;37305.44041839429;37307;37351.626483735905;37400.812995934844;37417.43873841949;24134;27424.58863117015;29245.70754292515;31119.533207002365;31199.688003120587;20934.895325523274;21313.360599182048;21389.70635995271;23195.188168414024;23870 09:57:26 INFO - PID 1642 | |2;jquery.html;409.73162578511085;422.8118985650321;436.517647998961;445.93979812725274;451.6003337046713;16637.750433743502;18567.72148417775;19018.61962760744;20816.687749683748;21667.241646542374;19129.52176195596;21875.343739687796;22582;23302;23425.297241082753;5930.673812940287;6007.639541627516;6159.846003849895;6214.471769899564;6215.223097112861;6075.513958883279;6096.1770161028135;6152.07718842175;6183.010718285075;6185.288691800455;5944;6126.938730612681;6127.203463549755;6156.138140660311;6166.167567378414;5592.384837667853;5849.824505264849;5989.281286245657;6053.03151495763;6078.057901025348;5429;5444.8366549003595;5450.345958484968;5459.754311056012;5462.535684466825;19941.302054428026;26654.333641659112;27844;27992.440151197065;28005.57991630127;2097.916083356664;2182.858114222586;2184.355615093551;2188.4966457714654;2190.791874771894;17786.91106544459;17875.910620446815;18086.457406277834;18165.455036348798;18542.443726688085;2130.3182981445943;2154.2137119951262;2180.890955452221;2186.2238905188588;2196.879171645559;161076;167548.99999999878;168988;172416.13791931086;173609;12576.937115314366;12969.870301297055;13059.869401306056;13121.540746073842;13129.540466083641;16443.424480143378;16508.504744857673;16518;16564.088975106486;16568.585785355463;8354.456960297683;8587.957060214783;8976.326775491734;9149.222316103205;9386.624535018524;8850.645974160961;8877.422967507091;8887.600057997406;9048.683296084737;9050.502222377701;6292.7660680457775;7438.776836694908;8065;8122.431429799975;8157.75526734199;2215.8227341812617;2233.5421238646363;2234.798868101846;2235.2847088931712;2421.152596591179;893.7029156933694;909.8180363927108;941.9952900235455;997.486294558303;1004.1757804732522;14475.85524144745;20228.190872365234;20786.168553258;21004.474888127614;21057.368278951348;943.6744323208482;999.9150072243941;1015.4009134610615;1042.0361165921493;1043.5147656339839;8129.552874591836;10368.481575921174;10550.577976880992;10571.995660412249;10618.946905265424;466877;562472;565144.6103415827;570205.1489742525;577064;115644.99999999831;126645;130021;132227;138085.23829523355;1165.8018136916744;1203.668991027457;1235.5613757116273;1235.9443825027715;1241.7392347606924;18254.81745182531;21163.047662855515;21437.571248575252;21843.781562184176;22032.11871525121;57266.7136664314;57277.85444291174;57332.426675732706;57470.7126464365;57667.423325767886;75877.24122758923;75894;76929;77013;78051.82922256284;8723.12768723148;8847.51338676379;8990.370674052754;9005.23455506263;9384.718458446258;14136.646583835487;14174.716505670036;14466.493672721404;14538.200398978157;14611.415543378145;9271.536423178948;10293.485325733534;11234.382108983757;12030.15788894769;12196.329201893626;11312.15158863105;11391.772164556829;11398.316101033965;11730.88269117298;12077.094217933865;11908.702282443008;12136.696582585506;12177.939110304393;12292.139550231397;12374.81437778416;9106.180443760093;9111.225545828678;9123.452592844185;9199.678011269574;9230.446173229628;9728.854067188857;11885.168038237587;11890.69202387754;11930.940345298219;12104.031677465606 09:57:26 INFO - PID 1642 | |3;mootools.html;1401.6425811418087;1438.4321580409826;1477.0325436838875;1477.2170749502761;1487.613220562654;11677.139100920276;11889.702757431069;12089.879101208997;12121.575744848931;12227.266364018144;12095.516179352837;12118.757624847498;12335.136540442172;12351.629451116458;12357.567485138023;2517.6852893388327;2559.9616005759926;2579.3938424470234;2586.1465716313664;2586.857722825244;2511.1462102885016;2547.350425641468;2559.616057591367;2562.423454722691;2568.126836875462;2535.543602151612;2544.7836933860644;2549.7195308516048;2550.081970490622;2558.6801649793774;2502.762237587426;2503.68703912011;2512.133314006674;2521.2814347910758;2522.445062086338;2491.9626205606933;2493.3891196656937;2502.2993561802723;2508.0344067533947;2511.899524019037;11651.359175245358;11657.533698652043;11799.587014454539;11817.231879927776;11867.881321186851;6417.358264173592;6572.277049524549;6644.269130395653;6675.299093595199;6675.532712710112;4097.959020409788;4248.787560621988;4255.234057869582;4347.826086956518;4353.934690979638;1240.1566934484547;1255.8430196225472;1285.768561658901;1285.8971282297393;1287.0025730059185;15437.228138593138;15851.286692098998;16490.83509164917;16587.502374928772;16772.245248963827;5506.752196151183;5558.166275058693;5680.233168522279;5736.9673458777825;5754.280714910637;11950.820737688942;12093.637190884288;12154.270743755404;12156.270623762603;12177;6407.135036769976;6465.418112369909;6509.1863517060365;6529.2817790043055;6543.247526534415;6057.878842423127;6175.6912154393;6178.598391104564;6182.381761823872;6232.937670623326;3999.420084087791;4392.670549708785;4469.530699276529;4552.3171524270965;4581.541845815392;1249.0382405547678;1264.051961029228;1268.2644066441442;1283.646997075793;1287.9420426080849;750.936170425509;770.6724642027228;793.6190628498354;796.219704689396;803.3091541274499;6447.615002123829;6576.408123268929;6647.966760166169;6682.298358672367;6799.456043516507;877.7849426890452;935.8502639577771;942.8208640358447;970.9124189248429;978.3787295067683;3586.6413358663926;3613.1689711366535;3790.7346485746284;4041.2119636671155;4169;12029.879701203052;14903.925480372745;15030.849691503163;15226.238688065552;15250.389984400721;14466.421343146367;15003.949723519248;15208.46770363032;15263.771043434357;15385.769213461806;8393.202645748644;8572.314214862796;8575.571221438902;8586.65653373858;8632.438891472035;5513.228148059355;6602.207735071822;6698.363655452724;6889.931100688929;6930.306969303029;13014.544490942772;13448.596542103753;13591.388387522387;13702.45190192381;13704.794428083786;10264.743381415525;13667.794983075062;13940.721185576434;13940.860591394361;14130.50543231023;7817.296443320129;7936.6428510716005;9247.39891907024;9325.487098209664;9534.332596718163;11034.393108378796;11086.722831929266;11156.442177891235;11228.887711122785;11257.21199516026;9238.630454781733;9300.953495232481;9350.205232555309;9485.383450075724;9515.476648784383;9033.277337812828;9060.728178154666;9213.907860921305;9241.815163696823;9377.531123443938;8324.458910170819;8333.916660833314;8384.455010424303;8422.157784221528;8424.747257582283;5856.385079566755;5943.257092863392;6000.789972350947;6091.634501929899;6093.725782339894;5888.528917686489;5931.1992880960515;6042.486388657014;6186.566940314315;6195.576866523571 09:57:26 INFO - PID 1642 | |4;prototype.html;361.91314084619694;396.5241709948063;397.03718482679494;398.6969902873817;407.519127429633;6126.111713801496;6139.693015349231;6158.84602884927;6176.938230617692;6198.814035578935;5487.79268560917;5539.058360078786;5967.313758917715;5978.34238233795;6053.667048312352;4257.425247591582;4340.131973605276;4408.757518336491;4560.5862182723295;4584.541545845422;3985.3623420252766;4617.399738034051;4618.953810461886;4619.5611416915335;4635.258358662614;4496.280595104784;4531.773411329436;4548.590626843584;4578.908421831561;4589.452853666214;4496.235639941217;4545.454545454567;4573.839915602938;4574.61115805157;4588.7476188809605;4052.8784136475656;4069.1454794493193;4075.673946084336;4084.4077608746566;4086.979565102185;5793.942060579382;5837.854053648693;5922.615030023035;6017.097435384683;6073;4296.37702533131;4852.126617208901;5043.646944713872;5449.891002179934;5490.423505531901;6204.348543402968;6408.942524483455;6410.519211059189;6422.3577642235405;6473.870522589522;3681.6134305897763;4587.564181402761;4599.747013914233;4603.723776573383;4611.792469338854;5138.049460849717;5537.889242215173;5555.361133469622;6074.936886044925;6304.824835431768;4410.80151393188;4644.7213167210075;4780.784864681098;4781.617470602343;4913.950860491421;3917.490726205575;4347.065380943112;4833.719064447854;4996.4503904570465;5003.4746351633285;5416.423122461514;5536.280283563111;5562.888742225133;5573.191887176336;5595.860103497445;6580.473562115019;6590.670466476657;6597.967010164918;6614.669266536654;6659.967705005732;1293.1077556485996;1522.8324884262722;1561.5471513261027;1672.7741754862939;1730.230047628793;1077.1167642533205;1188.7563049574817;1199.124639013525;1202.8736982616874;1208.4803534480259;5614.803481878114;5629.339344893267;6260.655663938527;6276.340984196685;6347.301796802348;5544.392126283414;6658.334166583303;6778.661066946633;6793.626350550767;6815.318468153244;5420.349558053059;5894.17481552583;6187.226596675416;6223.222097237845;6257.499400047986;3047.4362242985117;3517.613062563116;3524.400851855191;3536.3457760314423;3571.142925697866;6165.9826128689165;6180.845478862974;6514.413702766774;7146.749863754848;7215.71137154508;5927.229460170151;6426.903596446057;6525.641089740015;7047.788566343018;7093.680784364716;5877.970610146922;6886.10480637514;7037.683304251424;7040.647967601702;7042.683079261343;6499.28507864144;6780.93219067803;6837.247902730596;6845.911996841011;6866.622335771381;5652.519535839499;6210.534209934363;6526.738930442729;6754.358335958076;6775.86448271022;6271.404216599416;6762.256151823394;6903.9978406261625;7065.328793764585;7108.431325493844;3669.43123815809;3690.55713314404;3970.7889093825506;4147.502299724052;4410.29435290352;6403.719256148695;6531.706073226811;6764.391204791593;6851.760188393382;6882.380585747307;5314.28257185275;6349.713623721088;6551.50863685216;6594.934050659432;6619.371159739817;6356.364363563606;6450.709718062604;6520.5002849345865;6546.476281897532;6618.338166183536;5607.943920560742;5627.577931655061;5940.6435613863305;6141.324454310112;6484.578502397329;5367.946320536745;6590.934090659032;6611.644612854449;6621.635810030494;6623.966880165568;6256.217972753406;6314.652694101685;6398.1362516059735;6402.815479136375;6406.540468147335 09:57:26 INFO - PID 1642 | |5;yui.html;779.2752739951844;827.8882350882636;840.1010918317403;860.6960454910811;869.5347988825979;14943.402263909431;15489.922550387233;17071.743923841135;17081.316747330107;17510.036947967856;17617.82382176178;18444.98552579611;18457.44627661169;18457.81542184578;18458.077096145207;961.2838435365662;987.5753426026805;1001.639409812467;1011.9291649584532;1012.5949620151924;959.4818797849153;1007.10367772682;1011.4588695048151;1017.1811691588268;1020.8111499372603;1041.4324193314662;1063.6968463987764;1063.7074804428805;1063.8351055586397;1067.0503252105593;1039.4958445154093;1059.4914441068254;1059.5285098131296;1061.273027975839;1061.7027232374946;732.3811379384412;775.5075527040314;779.5868189859384;780.4146889832625;781.9609019549;17906.194221259946;18045.639087218184;18057.187426565837;18100.366487172887;18341.26634934601;14907.180105094216;14991.550253492413;15289.61775955599;15299.235038248155;15300.770488442571;14754.26228688572;14847.331870065873;14968.700625987529;15059;15083.698326033418;3047.6647568767416;3604.387254166798;3631.364511210528;3635.818209089535;3654.3787556115526;96276.51861740646;127586;133044;140482;141422;11867.643970680894;13238.53665121716;13612.863871361358;13695.726085478234;13709.588712338646;24941.750582494307;26314.736852631613;26669.0665826699;27002.864985674947;27013.729862701515;6682.264950855402;6943.965280173667;7619;7874.72438464651;7921.089074756362;3860.8069596520063;3937.9958110682164;3969.761814291152;3970.146418520032;3972.503437070366;4243.193793179286;4360.106178233518;4420.668449866273;4461.486929003141;4464.397306363664;929.3030227329502;938.6761567259284;949.8480243161199;949.9050094990583;952.1097773581585;556.3199138601401;656.7110471392572;662.5660192573872;677.8847595908708;695.3046953046953;11341;12425.751484970431;12531.122821402414;12583.87416125827;12683.746325073447;639.8304449320933;646.4828137489989;647.6276141218724;684.090160087083;697.3375293471223;5420.83737487876;5731.197632331477;5811.680357580289;5816.82549523515;6003.0695242237525;131142.34428827793;132967.3351633255;133125.33437332753;133795.3310233462;137128.62871371358;113322.86677133288;113949.43025284987;114099.42950285361;114940;115173;98350;99602.50596241062;99996;100378;106587.46706266575;11151.330920144494;11210.43947802623;11643.767124657628;11746.823797643214;11808.291502509877;43192.35211471767;43447.13105737931;43733.78133109314;43748.781256094786;45060.77469612631;74640;77609.2239077602;84099.5795021021;85349.57325213583;85520.57239713761;15476.922615386851;17365.21856516485;17674.116294184983;17680.73478897792;18046.819531804515;18176.363827266512;18470.168842402392;18716.719249211546;18759.718604220674;18808.435746927615;14955.85044149588;16245.51263462098;17129.97220166794;17906.5523361917;18367.35714249995;16989.06560139204;17098.572535686708;18111.909440452713;18228.997405142843;18323.083845807923;16994.49016529506;17261.65476690434;17777.644447111245;18130.81869181344;18131;8094.959525202572;9429.858552121861;10586.89413105859;10945.616903408343;11292.943535282271;12223.572174973833;13066.608001759962;13135.540256090992;14402.063865848686;14402.567922962327 09:57:26 INFO - PID 1642 | __end_tp_report 09:57:26 INFO - PID 1642 | __start_cc_report 09:57:26 INFO - PID 1642 | _x_x_mozilla_cycle_collect,449 09:57:26 INFO - PID 1642 | __end_cc_report 09:57:26 INFO - PID 1642 | __startTimestamp1496077046829__endTimestamp 09:57:26 INFO - PID 1642 | 09:57:26 INFO - PID 1642 | ------- Summary: start ------- 09:57:26 INFO - PID 1642 | Number of tests: 6 09:57:26 INFO - PID 1642 | 09:57:26 INFO - PID 1642 | [#0] dojo.html Cycles:165 Average:6165.40 Median:6394.00 stddev:741.50 (11.6%) stddev-sans-first:715.10 09:57:26 INFO - PID 1642 | Values: 3562.4 3582.7 3622.9 3634.4 3640.8 5625.4 6119.5 6140.9 6176.3 6209.9 4874.6 5925.1 6140.8 6162.6 6233.2 5387.2 5388.8 5398.7 5399.8 5409.9 5465.0 5508.6 5509.0 5522.1 5536.8 5338.0 5391.1 5438.3 5493.2 5508.3 5433.0 5444.9 5481.6 5487.3 5506.9 4701.5 5212.0 5359.6 5434.1 5442.1 6103.9 6196.8 6203.9 6205.2 6207.4 5470.6 5620.5 6470.8 6594.4 6746.9 6844.5 6848.8 6860.2 6865.4 6866.7 6202.4 6290.1 6312.6 6319.2 6329.6 6753.9 6833.6 6851.0 6909.6 6948.8 6430.5 6447.1 6507.2 6609.8 6628.6 6684.2 6783.2 6786.9 6815.4 6821.8 6699.7 6701.8 6708.7 6717.4 6747.7 6517.3 6565.7 6627.3 6703.4 6757.0 5357.6 5373.0 5384.1 5405.3 5446.5 4790.2 4792.0 4822.1 4837.5 4850.4 6188.2 6281.0 6342.1 6391.7 6427.4 6691.9 6802.6 6814.8 6821.4 6835.3 6371.6 6381.7 6389.2 6394.0 6407.8 5819.6 5980.9 6001.0 6021.3 6028.1 6796.7 6887.0 6947.4 6978.2 7000.3 6704.9 6805.5 6819.8 6824.2 6839.5 6962.4 6994.6 6995.1 7020.2 7033.3 5465.5 6494.1 6560.0 6579.3 6652.4 6799.8 6813.0 6816.2 6820.6 6831.5 6373.2 6458.8 6524.1 6592.2 6635.2 6405.0 6442.1 6535.8 6537.9 6539.0 6350.6 6439.6 6444.1 6470.9 6490.0 6561.1 6562.7 6581.7 6588.9 6598.4 6028.5 6255.9 6313.7 6318.5 6385.2 09:57:26 INFO - PID 1642 | 09:57:26 INFO - PID 1642 | [#1] ext.html Cycles:180 Average:43251.12 Median:315794.50 stddev:72530.40 (23.0%) stddev-sans-first:72663.81 09:57:26 INFO - PID 1642 | Values: 794.4 828.4 845.4 1269.0 1307.0 39935.8 40525.8 40606.2 40607.6 40639.6 40488.8 40718.4 41113.8 41290.4 41979.4 7923.4 8107.5 8365.1 8486.8 8490.7 8320.3 8327.1 8453.5 8455.5 8455.9 8364.7 8401.0 8420.2 8451.5 8452.9 8368.1 8418.1 8454.0 8469.5 8472.2 8249.9 8282.5 8291.9 8295.5 8337.2 37121.0 37210.8 37260.6 37302.5 37349.8 3561.3 3569.1 3611.1 3611.2 3615.5 27160.7 27923.3 30242.2 30645.5 30726.0 3056.9 3117.4 3128.2 3139.0 3150.8 225493.0 228804.0 260568.0 281916.6 282224.6 47841.0 48024.6 48053.8 48128.5 48257.5 47344.3 50719.7 50851.0 53101.9 54860.7 14563.9 14729.8 14810.6 14818.9 14833.0 11908.4 11914.9 11996.9 12024.6 12050.2 13848.0 13950.3 13953.6 13990.0 14074.8 5199.7 6219.8 6267.9 6268.2 6297.0 1001.2 1017.8 1024.3 1043.8 1047.5 23525.8 24741.6 24755.3 24855.3 24857.1 1110.8 1112.3 1147.6 1151.3 1153.5 12556.3 12726.9 12730.4 12750.9 12840.8 313445.0 314196.0 314974.0 315337.0 316252.0 54427.2 54629.7 54925.0 55101.9 55191.0 1469.7 1497.6 1529.1 1529.4 1532.2 23952.0 24149.0 24184.6 24216.0 24289.6 38091.0 38102.5 38238.8 38274.0 38536.0 254618.7 255380.4 255785.0 256331.0 256599.7 34337.0 34737.5 35378.0 35564.0 35650.8 43254.0 44074.0 44761.6 44856.0 44938.7 29846.6 29927.0 30298.7 30791.0 31135.8 29879.9 30024.7 30500.1 30978.7 31245.0 37305.4 37307.0 37351.6 37400.8 37417.4 24134.0 27424.6 29245.7 31119.5 31199.7 20934.9 21313.4 21389.7 23195.2 23870.0 09:57:26 INFO - PID 1642 | 09:57:26 INFO - PID 1642 | [#2] jquery.html Cycles:180 Average:35819.95 Median:2234.17 stddev:93804.00 (4198.6%) stddev-sans-first:94029.47 09:57:26 INFO - PID 1642 | Values: 409.7 422.8 436.5 445.9 451.6 16637.8 18567.7 19018.6 20816.7 21667.2 19129.5 21875.3 22582.0 23302.0 23425.3 5930.7 6007.6 6159.8 6214.5 6215.2 6075.5 6096.2 6152.1 6183.0 6185.3 5944.0 6126.9 6127.2 6156.1 6166.2 5592.4 5849.8 5989.3 6053.0 6078.1 5429.0 5444.8 5450.3 5459.8 5462.5 19941.3 26654.3 27844.0 27992.4 28005.6 2097.9 2182.9 2184.4 2188.5 2190.8 17786.9 17875.9 18086.5 18165.5 18542.4 2130.3 2154.2 2180.9 2186.2 2196.9 161076.0 167549.0 168988.0 172416.1 173609.0 12576.9 12969.9 13059.9 13121.5 13129.5 16443.4 16508.5 16518.0 16564.1 16568.6 8354.5 8588.0 8976.3 9149.2 9386.6 8850.6 8877.4 8887.6 9048.7 9050.5 6292.8 7438.8 8065.0 8122.4 8157.8 2215.8 2233.5 2234.8 2235.3 2421.2 893.7 909.8 942.0 997.5 1004.2 14475.9 20228.2 20786.2 21004.5 21057.4 943.7 999.9 1015.4 1042.0 1043.5 8129.6 10368.5 10550.6 10572.0 10618.9 466877.0 562472.0 565144.6 570205.1 577064.0 115645.0 126645.0 130021.0 132227.0 138085.2 1165.8 1203.7 1235.6 1235.9 1241.7 18254.8 21163.0 21437.6 21843.8 22032.1 57266.7 57277.9 57332.4 57470.7 57667.4 75877.2 75894.0 76929.0 77013.0 78051.8 8723.1 8847.5 8990.4 9005.2 9384.7 14136.6 14174.7 14466.5 14538.2 14611.4 9271.5 10293.5 11234.4 12030.2 12196.3 11312.2 11391.8 11398.3 11730.9 12077.1 11908.7 12136.7 12177.9 12292.1 12374.8 9106.2 9111.2 9123.5 9199.7 9230.4 9728.9 11885.2 11890.7 11930.9 12104.0 09:57:26 INFO - PID 1642 | 09:57:26 INFO - PID 1642 | [#3] mootools.html Cycles:180 Average:7138.98 Median:3599.91 stddev:4511.19 (125.3%) stddev-sans-first:4503.25 09:57:26 INFO - PID 1642 | Values: 1401.6 1438.4 1477.0 1477.2 1487.6 11677.1 11889.7 12089.9 12121.6 12227.3 12095.5 12118.8 12335.1 12351.6 12357.6 2517.7 2560.0 2579.4 2586.1 2586.9 2511.1 2547.4 2559.6 2562.4 2568.1 2535.5 2544.8 2549.7 2550.1 2558.7 2502.8 2503.7 2512.1 2521.3 2522.4 2492.0 2493.4 2502.3 2508.0 2511.9 11651.4 11657.5 11799.6 11817.2 11867.9 6417.4 6572.3 6644.3 6675.3 6675.5 4098.0 4248.8 4255.2 4347.8 4353.9 1240.2 1255.8 1285.8 1285.9 1287.0 15437.2 15851.3 16490.8 16587.5 16772.2 5506.8 5558.2 5680.2 5737.0 5754.3 11950.8 12093.6 12154.3 12156.3 12177.0 6407.1 6465.4 6509.2 6529.3 6543.2 6057.9 6175.7 6178.6 6182.4 6232.9 3999.4 4392.7 4469.5 4552.3 4581.5 1249.0 1264.1 1268.3 1283.6 1287.9 750.9 770.7 793.6 796.2 803.3 6447.6 6576.4 6648.0 6682.3 6799.5 877.8 935.9 942.8 970.9 978.4 3586.6 3613.2 3790.7 4041.2 4169.0 12029.9 14903.9 15030.8 15226.2 15250.4 14466.4 15003.9 15208.5 15263.8 15385.8 8393.2 8572.3 8575.6 8586.7 8632.4 5513.2 6602.2 6698.4 6889.9 6930.3 13014.5 13448.6 13591.4 13702.5 13704.8 10264.7 13667.8 13940.7 13940.9 14130.5 7817.3 7936.6 9247.4 9325.5 9534.3 11034.4 11086.7 11156.4 11228.9 11257.2 9238.6 9301.0 9350.2 9485.4 9515.5 9033.3 9060.7 9213.9 9241.8 9377.5 8324.5 8333.9 8384.5 8422.2 8424.7 5856.4 5943.3 6000.8 6091.6 6093.7 5888.5 5931.2 6042.5 6186.6 6195.6 09:57:26 INFO - PID 1642 | 09:57:26 INFO - PID 1642 | [#4] prototype.html Cycles:180 Average:5284.71 Median:5857.91 stddev:1623.56 (27.7%) stddev-sans-first:1585.51 09:57:26 INFO - PID 1642 | Values: 361.9 396.5 397.0 398.7 407.5 6126.1 6139.7 6158.8 6176.9 6198.8 5487.8 5539.1 5967.3 5978.3 6053.7 4257.4 4340.1 4408.8 4560.6 4584.5 3985.4 4617.4 4619.0 4619.6 4635.3 4496.3 4531.8 4548.6 4578.9 4589.5 4496.2 4545.5 4573.8 4574.6 4588.7 4052.9 4069.1 4075.7 4084.4 4087.0 5793.9 5837.9 5922.6 6017.1 6073.0 4296.4 4852.1 5043.6 5449.9 5490.4 6204.3 6408.9 6410.5 6422.4 6473.9 3681.6 4587.6 4599.7 4603.7 4611.8 5138.0 5537.9 5555.4 6074.9 6304.8 4410.8 4644.7 4780.8 4781.6 4914.0 3917.5 4347.1 4833.7 4996.5 5003.5 5416.4 5536.3 5562.9 5573.2 5595.9 6580.5 6590.7 6598.0 6614.7 6660.0 1293.1 1522.8 1561.5 1672.8 1730.2 1077.1 1188.8 1199.1 1202.9 1208.5 5614.8 5629.3 6260.7 6276.3 6347.3 5544.4 6658.3 6778.7 6793.6 6815.3 5420.3 5894.2 6187.2 6223.2 6257.5 3047.4 3517.6 3524.4 3536.3 3571.1 6166.0 6180.8 6514.4 7146.7 7215.7 5927.2 6426.9 6525.6 7047.8 7093.7 5878.0 6886.1 7037.7 7040.6 7042.7 6499.3 6780.9 6837.2 6845.9 6866.6 5652.5 6210.5 6526.7 6754.4 6775.9 6271.4 6762.3 6904.0 7065.3 7108.4 3669.4 3690.6 3970.8 4147.5 4410.3 6403.7 6531.7 6764.4 6851.8 6882.4 5314.3 6349.7 6551.5 6594.9 6619.4 6356.4 6450.7 6520.5 6546.5 6618.3 5607.9 5627.6 5940.6 6141.3 6484.6 5367.9 6590.9 6611.6 6621.6 6624.0 6256.2 6314.7 6398.1 6402.8 6406.5 09:57:26 INFO - PID 1642 | 09:57:26 INFO - PID 1642 | [#5] yui.html Cycles:180 Average:24804.44 Median:18121.36 stddev:36876.05 (203.5%) stddev-sans-first:36935.37 09:57:26 INFO - PID 1642 | Values: 779.3 827.9 840.1 860.7 869.5 14943.4 15489.9 17071.7 17081.3 17510.0 17617.8 18445.0 18457.4 18457.8 18458.1 961.3 987.6 1001.6 1011.9 1012.6 959.5 1007.1 1011.5 1017.2 1020.8 1041.4 1063.7 1063.7 1063.8 1067.1 1039.5 1059.5 1059.5 1061.3 1061.7 732.4 775.5 779.6 780.4 782.0 17906.2 18045.6 18057.2 18100.4 18341.3 14907.2 14991.6 15289.6 15299.2 15300.8 14754.3 14847.3 14968.7 15059.0 15083.7 3047.7 3604.4 3631.4 3635.8 3654.4 96276.5 127586.0 133044.0 140482.0 141422.0 11867.6 13238.5 13612.9 13695.7 13709.6 24941.8 26314.7 26669.1 27002.9 27013.7 6682.3 6944.0 7619.0 7874.7 7921.1 3860.8 3938.0 3969.8 3970.1 3972.5 4243.2 4360.1 4420.7 4461.5 4464.4 929.3 938.7 949.8 949.9 952.1 556.3 656.7 662.6 677.9 695.3 11341.0 12425.8 12531.1 12583.9 12683.7 639.8 646.5 647.6 684.1 697.3 5420.8 5731.2 5811.7 5816.8 6003.1 131142.3 132967.3 133125.3 133795.3 137128.6 113322.9 113949.4 114099.4 114940.0 115173.0 98350.0 99602.5 99996.0 100378.0 106587.5 11151.3 11210.4 11643.8 11746.8 11808.3 43192.4 43447.1 43733.8 43748.8 45060.8 74640.0 77609.2 84099.6 85349.6 85520.6 15476.9 17365.2 17674.1 17680.7 18046.8 18176.4 18470.2 18716.7 18759.7 18808.4 14955.9 16245.5 17130.0 17906.6 18367.4 16989.1 17098.6 18111.9 18229.0 18323.1 16994.5 17261.7 17777.6 18130.8 18131.0 8095.0 9429.9 10586.9 10945.6 11292.9 12223.6 13066.6 13135.5 14402.1 14402.6 09:57:26 INFO - PID 1642 | -------- Summary: end -------- 09:57:26 INFO - PID 1642 | 09:57:27 INFO - TEST-INFO | 1642: exit 0 09:57:27 INFO - TEST-OK | dromaeo_css | took 1144272ms 09:57:27 INFO - TEST-START | kraken 09:57:27 INFO - operating with platform_type : mac_ 09:57:27 INFO - Initialising browser for kraken test... 09:57:27 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49223/getInfo.html -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpcLEWOT/profile 09:57:27 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49223/getInfo.html -foreground) 09:58:01 INFO - TEST-INFO | 1653: killed by SIGTERM 09:58:01 INFO - Browser initialized. 09:58:01 INFO - Running cycle 1/1 for kraken test... 09:58:01 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpcLEWOT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:58:03 INFO - PID 1657 | 2017-05-29 09:58:03.872 plugin-container[1658:10505] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 09:58:03 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:58:03 INFO - PID 1657 | 2017-05-29 09:58:03.873 plugin-container[1658:10505] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e13, name = 'com.apple.CFPasteboardClient' 09:58:03 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:58:03 INFO - PID 1657 | 2017-05-29 09:58:03.873 plugin-container[1658:10505] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:58:04 INFO - PID 1657 | 2017-05-29 09:58:04.307 plugin-container[1659:10545] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 09:58:04 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:58:04 INFO - PID 1657 | 2017-05-29 09:58:04.308 plugin-container[1659:10545] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9717, name = 'com.apple.CFPasteboardClient' 09:58:04 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:58:04 INFO - PID 1657 | 2017-05-29 09:58:04.308 plugin-container[1659:10545] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:58:13 INFO - PID 1657 | RSS: Main: 261476352 09:58:13 INFO - PID 1657 | 09:58:16 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-beat-detection) 09:58:17 INFO - PID 1657 | RSS: Main: 271921152 09:58:17 INFO - PID 1657 | 09:58:19 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-dft) 09:58:20 INFO - PID 1657 | RSS: Main: 279638016 09:58:20 INFO - PID 1657 | 09:58:24 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-fft) 09:58:24 INFO - PID 1657 | RSS: Main: 281518080 09:58:24 INFO - PID 1657 | 09:58:26 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-oscillator) 09:58:27 INFO - PID 1657 | RSS: Main: 282583040 09:58:27 INFO - PID 1657 | 09:58:29 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:58:29 INFO - PID 1657 | RSS: Main: 284643328 09:58:29 INFO - PID 1657 | 09:58:32 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-darkroom) 09:58:33 INFO - PID 1657 | RSS: Main: 288641024 09:58:33 INFO - PID 1657 | 09:58:36 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-desaturate) 09:58:37 INFO - PID 1657 | RSS: Main: 292126720 09:58:37 INFO - PID 1657 | 09:58:40 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49223/tests/kraken/driver.html?testName=json-parse-financial) 09:58:40 INFO - PID 1657 | RSS: Main: 290361344 09:58:40 INFO - PID 1657 | 09:58:42 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49223/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:58:43 INFO - PID 1657 | RSS: Main: 290279424 09:58:43 INFO - PID 1657 | 09:58:44 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:58:45 INFO - PID 1657 | RSS: Main: 290037760 09:58:45 INFO - PID 1657 | 09:58:47 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:58:48 INFO - PID 1657 | RSS: Main: 290123776 09:58:48 INFO - PID 1657 | 09:58:51 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:58:52 INFO - PID 1657 | RSS: Main: 290156544 09:58:52 INFO - PID 1657 | 09:58:55 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:58:55 INFO - PID 1657 | RSS: Main: 289988608 09:58:55 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49223/tests/kraken/driver.html?testName=ai-astar) 09:58:57 INFO - PID 1657 | RSS: Main: 291852288 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | __start_tp_report 09:58:57 INFO - PID 1657 | _x_x_mozilla_page_load 09:58:57 INFO - PID 1657 | _x_x_mozilla_page_load_details 09:58:57 INFO - PID 1657 | |i|pagename|runs| 09:58:57 INFO - PID 1657 | |0;ai-astar;102;117;171;169;115;114;115;114;114;115 09:58:57 INFO - PID 1657 | |1;audio-beat-detection;137;101;134;187;145;104;111;112;134;112 09:58:57 INFO - PID 1657 | |2;audio-dft;203;209;230;236;202;272;194;250;198;205 09:58:57 INFO - PID 1657 | |3;audio-fft;82;65;86;111;85;58;59;59;78;61 09:58:57 INFO - PID 1657 | |4;audio-oscillator;81;69;85;70;72;69;86;69;72;91 09:58:57 INFO - PID 1657 | |5;imaging-gaussian-blur;89;89;88;125;96;87;98;88;89;89 09:58:57 INFO - PID 1657 | |6;imaging-darkroom;127;96;96;97;96;96;99;98;154;109 09:58:57 INFO - PID 1657 | |7;imaging-desaturate;92;94;91;91;100;89;91;104;87;137 09:58:57 INFO - PID 1657 | |8;json-parse-financial;51;51;54;54;62;65;67;47;46;77 09:58:57 INFO - PID 1657 | |9;json-stringify-tinderbox;57;55;60;56;60;63;74;54;64;75 09:58:57 INFO - PID 1657 | |10;stanford-crypto-aes;86;88;89;76;103;115;112;88;124;88 09:58:57 INFO - PID 1657 | |11;stanford-crypto-ccm;156;160;144;154;165;161;139;136;153;141 09:58:57 INFO - PID 1657 | |12;stanford-crypto-pbkdf2;193;190;187;186;184;184;202;202;183;195 09:58:57 INFO - PID 1657 | |13;stanford-crypto-sha256-iterative;67;68;67;69;72;66;69;66;69;68 09:58:57 INFO - PID 1657 | __end_tp_report 09:58:57 INFO - PID 1657 | __start_cc_report 09:58:57 INFO - PID 1657 | _x_x_mozilla_cycle_collect,1188 09:58:57 INFO - PID 1657 | __end_cc_report 09:58:57 INFO - PID 1657 | __startTimestamp1496077137935__endTimestamp 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | ------- Summary: start ------- 09:58:57 INFO - PID 1657 | Number of tests: 14 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | [#0] ai-astar Cycles:10 Average:124.60 Median:115.00 stddev:24.28 (21.1%) stddev-sans-first:24.34 09:58:57 INFO - PID 1657 | Values: 102.0 117.0 171.0 169.0 115.0 114.0 115.0 114.0 114.0 115.0 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:134.00 stddev:25.88 (19.3%) stddev-sans-first:27.23 09:58:57 INFO - PID 1657 | Values: 137.0 101.0 134.0 187.0 145.0 104.0 111.0 112.0 134.0 112.0 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | [#2] audio-dft Cycles:10 Average:219.90 Median:219.50 stddev:25.99 (11.8%) stddev-sans-first:26.84 09:58:57 INFO - PID 1657 | Values: 203.0 209.0 230.0 236.0 202.0 272.0 194.0 250.0 198.0 205.0 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | [#3] audio-fft Cycles:10 Average:74.40 Median:71.50 stddev:17.22 (24.1%) stddev-sans-first:18.04 09:58:57 INFO - PID 1657 | Values: 82.0 65.0 86.0 111.0 85.0 58.0 59.0 59.0 78.0 61.0 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | [#4] audio-oscillator Cycles:10 Average:76.40 Median:76.50 stddev:8.46 (11.1%) stddev-sans-first:8.81 09:58:57 INFO - PID 1657 | Values: 81.0 69.0 85.0 70.0 72.0 69.0 86.0 69.0 72.0 91.0 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:93.80 Median:89.00 stddev:11.55 (13.0%) stddev-sans-first:12.12 09:58:57 INFO - PID 1657 | Values: 89.0 89.0 88.0 125.0 96.0 87.0 98.0 88.0 89.0 89.0 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | [#6] imaging-darkroom Cycles:10 Average:106.80 Median:96.00 stddev:19.27 (20.1%) stddev-sans-first:19.00 09:58:57 INFO - PID 1657 | Values: 127.0 96.0 96.0 97.0 96.0 96.0 99.0 98.0 154.0 109.0 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:91.00 stddev:14.76 (16.2%) stddev-sans-first:15.51 09:58:57 INFO - PID 1657 | Values: 92.0 94.0 91.0 91.0 100.0 89.0 91.0 104.0 87.0 137.0 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:9.99 (17.2%) stddev-sans-first:10.33 09:58:57 INFO - PID 1657 | Values: 51.0 51.0 54.0 54.0 62.0 65.0 67.0 47.0 46.0 77.0 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:61.80 Median:61.50 stddev:7.45 (12.1%) stddev-sans-first:7.70 09:58:57 INFO - PID 1657 | Values: 57.0 55.0 60.0 56.0 60.0 63.0 74.0 54.0 64.0 75.0 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | [#10] stanford-crypto-aes Cycles:10 Average:96.90 Median:87.00 stddev:15.57 (17.9%) stddev-sans-first:16.01 09:58:57 INFO - PID 1657 | Values: 86.0 88.0 89.0 76.0 103.0 115.0 112.0 88.0 124.0 88.0 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:150.90 Median:155.00 stddev:10.18 (6.6%) stddev-sans-first:10.63 09:58:57 INFO - PID 1657 | Values: 156.0 160.0 144.0 154.0 165.0 161.0 139.0 136.0 153.0 141.0 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.60 Median:191.50 stddev:7.18 (3.8%) stddev-sans-first:7.57 09:58:57 INFO - PID 1657 | Values: 193.0 190.0 187.0 186.0 184.0 184.0 202.0 202.0 183.0 195.0 09:58:57 INFO - PID 1657 | 09:58:57 INFO - PID 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.50 stddev:1.79 (2.6%) stddev-sans-first:1.86 09:58:57 INFO - PID 1657 | Values: 67.0 68.0 67.0 69.0 72.0 66.0 69.0 66.0 69.0 68.0 09:58:57 INFO - PID 1657 | -------- Summary: end -------- 09:58:57 INFO - PID 1657 | 09:58:58 INFO - TEST-INFO | 1657: exit 0 09:58:58 INFO - TEST-OK | kraken | took 91513ms 09:58:58 INFO - SUITE-END | took 1235s 09:58:59 INFO - Completed test suite (00:20:35) 09:58:59 INFO - javascript benchmark 09:58:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7954.4869951291685, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3562.358775420424, 3582.7492075554724, 3622.9456558151614, 3634.382155033644, 3640.7815531068136, 5625.381208067114, 6119.5330196898485, 6140.938590614092, 6176.289726681433, 6209.937900620993, 4874.585660218886, 5925.111233315005, 6140.780336713802, 6162.568620196588, 6233.1585235993125, 5387.24578559002, 5388.838334849941, 5398.73006349682, 5399.757010934498, 5409.864753381177, 5464.972675136639, 5508.614396992212, 5508.9724551377185, 5522.088855338884, 5536.778528858841, 5338.012467693489, 5391.110466772978, 5438.293021907166, 5493.176023596453, 5508.3114610673665, 5432.972835135839, 5444.91832622511, 5481.561475081984, 5487.259220005328, 5506.91739623902, 4701.459332176775, 5211.957608478281, 5359.598030147755, 5434.076207044773, 5442.12925931853, 6103.908441373339, 6196.845078873019, 6203.87592248157, 6205.22434695663, 6207.410296021871, 5470.589705772043, 5620.494155526022, 6470.773522926722, 6594.406503414716, 6746.865062698767, 6844.452443804484, 6848.828779280508, 6860.245373008965, 6865.382115609569, 6866.725330986755, 6202.410770976705, 6290.056491526308, 6312.621242725451, 6319.178506794179, 6329.556931014785, 6753.932460675429, 6833.58998460094, 6851.0, 6909.550879192836, 6948.791536253921, 6430.485561155097, 6447.097406363113, 6507.154069970874, 6609.834754131089, 6628.569143005786, 6684.16447944007, 6783.152105986752, 6786.864262714718, 6815.420689241371, 6821.795346139624, 6699.732010719614, 6701.765438209639, 6708.698108585126, 6717.361850624183, 6747.662616869137, 6517.348265173445, 6565.737370505221, 6627.3372662733345, 6703.363180497845, 6756.966215168893, 5357.624966252403, 5372.973135134377, 5384.057789886754, 5405.270288511001, 5446.537044351275, 4790.233562630032, 4791.95208047922, 4822.083804077214, 4837.4920633333695, 4850.369451971221, 6188.195534580475, 6280.968595157086, 6342.080398342307, 6391.712372943229, 6427.389398007182, 6691.866162676719, 6802.591844489347, 6814.761483348059, 6821.3519715626935, 6835.282295358916, 6371.585846919936, 6381.680915954184, 6389.201349831271, 6394.0, 6407.807765766941, 5819.592628516048, 5980.85047873798, 6001.0, 6021.307549631761, 6028.125921741411, 6796.694148763218, 6887.0, 6947.40947019509, 6978.232394436709, 7000.334968178218, 6704.865902682017, 6805.489588280998, 6819.761308354184, 6824.249332573314, 6839.487038472234, 6962.3733863950465, 6994.5803251805055, 6995.055667484824, 7020.192677841909, 7033.331833475811, 5465.508104270635, 6494.090827284279, 6559.967200163968, 6579.342065793382, 6652.4345430637, 6799.796006119825, 6813.0, 6816.182058153054, 6820.624865632438, 6831.487638427038, 6373.203349581302, 6458.773942911976, 6524.086627872007, 6592.208934927839, 6635.236947750877, 6405.03924411342, 6442.09810626522, 6535.836604084936, 6537.901931471127, 6539.019147127969, 6350.555461117678, 6439.581427207217, 6444.130042444398, 6470.870582588416, 6489.967550162408, 6561.081448597296, 6562.737490500326, 6581.736730530725, 6588.934110658832, 6598.406143447113, 6028.487578555872, 6255.906161407492, 6313.715882785377, 6318.462930650946, 6385.233771947396], "value": 6116.271756760022, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.4478587381768, 828.3994104274401, 845.3828705045319, 1268.9999999999993, 1307.0458565247375, 39935.80032099839, 40525.79737101318, 40606.18787624246, 40607.59392406075, 40639.59360406395, 40488.79755601225, 40718.38922416166, 41113.794431027876, 41290.38064429036, 41979.37030944538, 7923.4057445691515, 8107.513549187038, 8365.12166222547, 8486.78783030423, 8490.702825401113, 8320.334373250156, 8327.084020757711, 8453.450525715809, 8455.53494557799, 8455.915440845605, 8364.707235246768, 8401.03388110369, 8420.157984201562, 8451.492910425393, 8452.915470845275, 8368.079511253758, 8418.116097809734, 8454.0, 8469.534175620338, 8472.237498625092, 8249.917500825035, 8282.461639993442, 8291.917080829175, 8295.502269863828, 8337.207965243293, 37121.0, 37210.813945930095, 37260.578062426124, 37302.456266654626, 37349.81325093385, 3561.3411518869093, 3569.0542006368332, 3611.079174810433, 3611.205534782344, 3615.5480564929385, 27160.728392716017, 27923.301917452023, 30242.243943901358, 30645.540316895265, 30726.0, 3056.877724891002, 3117.407692538433, 3128.2023084113634, 3139.02690166047, 3150.763692723032, 225493.0, 228804.0, 260568.0, 281916.59041704866, 282224.5888770564, 47841.0, 48024.55926322216, 48053.75973120112, 48128.5187148131, 48257.5174248253, 47344.28983565249, 50719.73200670013, 50850.98298034092, 53101.93796124056, 54860.725696371264, 14563.854361456462, 14729.779053314209, 14810.555683329301, 14818.925905370405, 14832.961692681409, 11908.404579770977, 11914.940425297818, 11996.880031199751, 12024.639260822189, 12050.156489045683, 13848.030637855454, 13950.302484875716, 13953.581392558037, 13990.0, 14074.788878166835, 5199.714015729096, 6219.813405597839, 6267.9373206268265, 6268.247810262797, 6296.968515157395, 1001.2390583156853, 1017.8473229015707, 1024.262530977695, 1043.7547176413536, 1047.4710271312938, 23525.764742352356, 24741.62887556688, 24755.267131300636, 24855.254342369757, 24857.13000045026, 1110.838928355384, 1112.2992514715838, 1147.5983405808067, 1151.3264740126951, 1153.4809335798925, 12556.30940298274, 12726.872731272753, 12730.363481825872, 12750.872491274968, 12840.807387889188, 313445.0, 314196.0, 314974.0, 315337.0, 316252.0, 54427.18359224615, 54629.726851365485, 54925.0, 55101.89796204054, 55191.0, 1469.6840179361488, 1497.633079895411, 1529.06726896593, 1529.3729570875887, 1532.1802835483186, 23952.0, 24149.0, 24184.637230441207, 24216.0, 24289.635655465536, 38091.0, 38102.475900963655, 38238.808805956905, 38274.0431489215, 38536.036599085244, 254618.7269063643, 255380.44619553565, 255785.0, 256331.0, 256599.7170014138, 34336.96989090331, 34737.478937815446, 35378.0, 35564.0, 35650.8217458911, 43254.0, 44074.0, 44761.55238447704, 44856.0, 44938.651840444836, 29846.55230171506, 29927.0, 30298.69701303047, 30791.0, 31135.84432077825, 29879.850600747726, 30024.69975300219, 30500.08499745011, 30978.69021309848, 31245.0, 37305.44041839429, 37307.0, 37351.626483735905, 37400.812995934844, 37417.43873841949, 24134.0, 27424.58863117015, 29245.70754292515, 31119.533207002365, 31199.688003120587, 20934.895325523274, 21313.360599182048, 21389.70635995271, 23195.188168414024, 23870.0], "value": 17542.40158624663, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.73162578511085, 422.8118985650321, 436.517647998961, 445.93979812725274, 451.6003337046713, 16637.750433743502, 18567.72148417775, 19018.61962760744, 20816.687749683748, 21667.241646542374, 19129.52176195596, 21875.343739687796, 22582.0, 23302.0, 23425.297241082753, 5930.673812940287, 6007.639541627516, 6159.846003849895, 6214.471769899564, 6215.223097112861, 6075.513958883279, 6096.1770161028135, 6152.07718842175, 6183.010718285075, 6185.288691800455, 5944.0, 6126.938730612681, 6127.203463549755, 6156.138140660311, 6166.167567378414, 5592.384837667853, 5849.824505264849, 5989.281286245657, 6053.03151495763, 6078.057901025348, 5429.0, 5444.8366549003595, 5450.345958484968, 5459.754311056012, 5462.535684466825, 19941.302054428026, 26654.333641659112, 27844.0, 27992.440151197065, 28005.57991630127, 2097.916083356664, 2182.858114222586, 2184.355615093551, 2188.4966457714654, 2190.791874771894, 17786.91106544459, 17875.910620446815, 18086.457406277834, 18165.455036348798, 18542.443726688085, 2130.3182981445943, 2154.2137119951262, 2180.890955452221, 2186.2238905188588, 2196.879171645559, 161076.0, 167548.99999999878, 168988.0, 172416.13791931086, 173609.0, 12576.937115314366, 12969.870301297055, 13059.869401306056, 13121.540746073842, 13129.540466083641, 16443.424480143378, 16508.504744857673, 16518.0, 16564.088975106486, 16568.585785355463, 8354.456960297683, 8587.957060214783, 8976.326775491734, 9149.222316103205, 9386.624535018524, 8850.645974160961, 8877.422967507091, 8887.600057997406, 9048.683296084737, 9050.502222377701, 6292.7660680457775, 7438.776836694908, 8065.0, 8122.431429799975, 8157.75526734199, 2215.8227341812617, 2233.5421238646363, 2234.798868101846, 2235.2847088931712, 2421.152596591179, 893.7029156933694, 909.8180363927108, 941.9952900235455, 997.486294558303, 1004.1757804732522, 14475.85524144745, 20228.190872365234, 20786.168553258, 21004.474888127614, 21057.368278951348, 943.6744323208482, 999.9150072243941, 1015.4009134610615, 1042.0361165921493, 1043.5147656339839, 8129.552874591836, 10368.481575921174, 10550.577976880992, 10571.995660412249, 10618.946905265424, 466877.0, 562472.0, 565144.6103415827, 570205.1489742525, 577064.0, 115644.99999999831, 126645.0, 130021.0, 132227.0, 138085.23829523355, 1165.8018136916744, 1203.668991027457, 1235.5613757116273, 1235.9443825027715, 1241.7392347606924, 18254.81745182531, 21163.047662855515, 21437.571248575252, 21843.781562184176, 22032.11871525121, 57266.7136664314, 57277.85444291174, 57332.426675732706, 57470.7126464365, 57667.423325767886, 75877.24122758923, 75894.0, 76929.0, 77013.0, 78051.82922256284, 8723.12768723148, 8847.51338676379, 8990.370674052754, 9005.23455506263, 9384.718458446258, 14136.646583835487, 14174.716505670036, 14466.493672721404, 14538.200398978157, 14611.415543378145, 9271.536423178948, 10293.485325733534, 11234.382108983757, 12030.15788894769, 12196.329201893626, 11312.15158863105, 11391.772164556829, 11398.316101033965, 11730.88269117298, 12077.094217933865, 11908.702282443008, 12136.696582585506, 12177.939110304393, 12292.139550231397, 12374.81437778416, 9106.180443760093, 9111.225545828678, 9123.452592844185, 9199.678011269574, 9230.446173229628, 9728.854067188857, 11885.168038237587, 11890.69202387754, 11930.940345298219, 12104.031677465606], "value": 10442.282076588492, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.6425811418087, 1438.4321580409826, 1477.0325436838875, 1477.2170749502761, 1487.613220562654, 11677.139100920276, 11889.702757431069, 12089.879101208997, 12121.575744848931, 12227.266364018144, 12095.516179352837, 12118.757624847498, 12335.136540442172, 12351.629451116458, 12357.567485138023, 2517.6852893388327, 2559.9616005759926, 2579.3938424470234, 2586.1465716313664, 2586.857722825244, 2511.1462102885016, 2547.350425641468, 2559.616057591367, 2562.423454722691, 2568.126836875462, 2535.543602151612, 2544.7836933860644, 2549.7195308516048, 2550.081970490622, 2558.6801649793774, 2502.762237587426, 2503.68703912011, 2512.133314006674, 2521.2814347910758, 2522.445062086338, 2491.9626205606933, 2493.3891196656937, 2502.2993561802723, 2508.0344067533947, 2511.899524019037, 11651.359175245358, 11657.533698652043, 11799.587014454539, 11817.231879927776, 11867.881321186851, 6417.358264173592, 6572.277049524549, 6644.269130395653, 6675.299093595199, 6675.532712710112, 4097.959020409788, 4248.787560621988, 4255.234057869582, 4347.826086956518, 4353.934690979638, 1240.1566934484547, 1255.8430196225472, 1285.768561658901, 1285.8971282297393, 1287.0025730059185, 15437.228138593138, 15851.286692098998, 16490.83509164917, 16587.502374928772, 16772.245248963827, 5506.752196151183, 5558.166275058693, 5680.233168522279, 5736.9673458777825, 5754.280714910637, 11950.820737688942, 12093.637190884288, 12154.270743755404, 12156.270623762603, 12177.0, 6407.135036769976, 6465.418112369909, 6509.1863517060365, 6529.2817790043055, 6543.247526534415, 6057.878842423127, 6175.6912154393, 6178.598391104564, 6182.381761823872, 6232.937670623326, 3999.420084087791, 4392.670549708785, 4469.530699276529, 4552.3171524270965, 4581.541845815392, 1249.0382405547678, 1264.051961029228, 1268.2644066441442, 1283.646997075793, 1287.9420426080849, 750.936170425509, 770.6724642027228, 793.6190628498354, 796.219704689396, 803.3091541274499, 6447.615002123829, 6576.408123268929, 6647.966760166169, 6682.298358672367, 6799.456043516507, 877.7849426890452, 935.8502639577771, 942.8208640358447, 970.9124189248429, 978.3787295067683, 3586.6413358663926, 3613.1689711366535, 3790.7346485746284, 4041.2119636671155, 4169.0, 12029.879701203052, 14903.925480372745, 15030.849691503163, 15226.238688065552, 15250.389984400721, 14466.421343146367, 15003.949723519248, 15208.46770363032, 15263.771043434357, 15385.769213461806, 8393.202645748644, 8572.314214862796, 8575.571221438902, 8586.65653373858, 8632.438891472035, 5513.228148059355, 6602.207735071822, 6698.363655452724, 6889.931100688929, 6930.306969303029, 13014.544490942772, 13448.596542103753, 13591.388387522387, 13702.45190192381, 13704.794428083786, 10264.743381415525, 13667.794983075062, 13940.721185576434, 13940.860591394361, 14130.50543231023, 7817.296443320129, 7936.6428510716005, 9247.39891907024, 9325.487098209664, 9534.332596718163, 11034.393108378796, 11086.722831929266, 11156.442177891235, 11228.887711122785, 11257.21199516026, 9238.630454781733, 9300.953495232481, 9350.205232555309, 9485.383450075724, 9515.476648784383, 9033.277337812828, 9060.728178154666, 9213.907860921305, 9241.815163696823, 9377.531123443938, 8324.458910170819, 8333.916660833314, 8384.455010424303, 8422.157784221528, 8424.747257582283, 5856.385079566755, 5943.257092863392, 6000.789972350947, 6091.634501929899, 6093.725782339894, 5888.528917686489, 5931.1992880960515, 6042.486388657014, 6186.566940314315, 6195.576866523571], "value": 5407.622429685484, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.91314084619694, 396.5241709948063, 397.03718482679494, 398.6969902873817, 407.519127429633, 6126.111713801496, 6139.693015349231, 6158.84602884927, 6176.938230617692, 6198.814035578935, 5487.79268560917, 5539.058360078786, 5967.313758917715, 5978.34238233795, 6053.667048312352, 4257.425247591582, 4340.131973605276, 4408.757518336491, 4560.5862182723295, 4584.541545845422, 3985.3623420252766, 4617.399738034051, 4618.953810461886, 4619.5611416915335, 4635.258358662614, 4496.280595104784, 4531.773411329436, 4548.590626843584, 4578.908421831561, 4589.452853666214, 4496.235639941217, 4545.454545454567, 4573.839915602938, 4574.61115805157, 4588.7476188809605, 4052.8784136475656, 4069.1454794493193, 4075.673946084336, 4084.4077608746566, 4086.979565102185, 5793.942060579382, 5837.854053648693, 5922.615030023035, 6017.097435384683, 6073.0, 4296.37702533131, 4852.126617208901, 5043.646944713872, 5449.891002179934, 5490.423505531901, 6204.348543402968, 6408.942524483455, 6410.519211059189, 6422.3577642235405, 6473.870522589522, 3681.6134305897763, 4587.564181402761, 4599.747013914233, 4603.723776573383, 4611.792469338854, 5138.049460849717, 5537.889242215173, 5555.361133469622, 6074.936886044925, 6304.824835431768, 4410.80151393188, 4644.7213167210075, 4780.784864681098, 4781.617470602343, 4913.950860491421, 3917.490726205575, 4347.065380943112, 4833.719064447854, 4996.4503904570465, 5003.4746351633285, 5416.423122461514, 5536.280283563111, 5562.888742225133, 5573.191887176336, 5595.860103497445, 6580.473562115019, 6590.670466476657, 6597.967010164918, 6614.669266536654, 6659.967705005732, 1293.1077556485996, 1522.8324884262722, 1561.5471513261027, 1672.7741754862939, 1730.230047628793, 1077.1167642533205, 1188.7563049574817, 1199.124639013525, 1202.8736982616874, 1208.4803534480259, 5614.803481878114, 5629.339344893267, 6260.655663938527, 6276.340984196685, 6347.301796802348, 5544.392126283414, 6658.334166583303, 6778.661066946633, 6793.626350550767, 6815.318468153244, 5420.349558053059, 5894.17481552583, 6187.226596675416, 6223.222097237845, 6257.499400047986, 3047.4362242985117, 3517.613062563116, 3524.400851855191, 3536.3457760314423, 3571.142925697866, 6165.9826128689165, 6180.845478862974, 6514.413702766774, 7146.749863754848, 7215.71137154508, 5927.229460170151, 6426.903596446057, 6525.641089740015, 7047.788566343018, 7093.680784364716, 5877.970610146922, 6886.10480637514, 7037.683304251424, 7040.647967601702, 7042.683079261343, 6499.28507864144, 6780.93219067803, 6837.247902730596, 6845.911996841011, 6866.622335771381, 5652.519535839499, 6210.534209934363, 6526.738930442729, 6754.358335958076, 6775.86448271022, 6271.404216599416, 6762.256151823394, 6903.9978406261625, 7065.328793764585, 7108.431325493844, 3669.43123815809, 3690.55713314404, 3970.7889093825506, 4147.502299724052, 4410.29435290352, 6403.719256148695, 6531.706073226811, 6764.391204791593, 6851.760188393382, 6882.380585747307, 5314.28257185275, 6349.713623721088, 6551.50863685216, 6594.934050659432, 6619.371159739817, 6356.364363563606, 6450.709718062604, 6520.5002849345865, 6546.476281897532, 6618.338166183536, 5607.943920560742, 5627.577931655061, 5940.6435613863305, 6141.324454310112, 6484.578502397329, 5367.946320536745, 6590.934090659032, 6611.644612854449, 6621.635810030494, 6623.966880165568, 6256.217972753406, 6314.652694101685, 6398.1362516059735, 6402.815479136375, 6406.540468147335], "value": 4788.770491080589, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.2752739951844, 827.8882350882636, 840.1010918317403, 860.6960454910811, 869.5347988825979, 14943.402263909431, 15489.922550387233, 17071.743923841135, 17081.316747330107, 17510.036947967856, 17617.82382176178, 18444.98552579611, 18457.44627661169, 18457.81542184578, 18458.077096145207, 961.2838435365662, 987.5753426026805, 1001.639409812467, 1011.9291649584532, 1012.5949620151924, 959.4818797849153, 1007.10367772682, 1011.4588695048151, 1017.1811691588268, 1020.8111499372603, 1041.4324193314662, 1063.6968463987764, 1063.7074804428805, 1063.8351055586397, 1067.0503252105593, 1039.4958445154093, 1059.4914441068254, 1059.5285098131296, 1061.273027975839, 1061.7027232374946, 732.3811379384412, 775.5075527040314, 779.5868189859384, 780.4146889832625, 781.9609019549, 17906.194221259946, 18045.639087218184, 18057.187426565837, 18100.366487172887, 18341.26634934601, 14907.180105094216, 14991.550253492413, 15289.61775955599, 15299.235038248155, 15300.770488442571, 14754.26228688572, 14847.331870065873, 14968.700625987529, 15059.0, 15083.698326033418, 3047.6647568767416, 3604.387254166798, 3631.364511210528, 3635.818209089535, 3654.3787556115526, 96276.51861740646, 127586.0, 133044.0, 140482.0, 141422.0, 11867.643970680894, 13238.53665121716, 13612.863871361358, 13695.726085478234, 13709.588712338646, 24941.750582494307, 26314.736852631613, 26669.0665826699, 27002.864985674947, 27013.729862701515, 6682.264950855402, 6943.965280173667, 7619.0, 7874.72438464651, 7921.089074756362, 3860.8069596520063, 3937.9958110682164, 3969.761814291152, 3970.146418520032, 3972.503437070366, 4243.193793179286, 4360.106178233518, 4420.668449866273, 4461.486929003141, 4464.397306363664, 929.3030227329502, 938.6761567259284, 949.8480243161199, 949.9050094990583, 952.1097773581585, 556.3199138601401, 656.7110471392572, 662.5660192573872, 677.8847595908708, 695.3046953046953, 11341.0, 12425.751484970431, 12531.122821402414, 12583.87416125827, 12683.746325073447, 639.8304449320933, 646.4828137489989, 647.6276141218724, 684.090160087083, 697.3375293471223, 5420.83737487876, 5731.197632331477, 5811.680357580289, 5816.82549523515, 6003.0695242237525, 131142.34428827793, 132967.3351633255, 133125.33437332753, 133795.3310233462, 137128.62871371358, 113322.86677133288, 113949.43025284987, 114099.42950285361, 114940.0, 115173.0, 98350.0, 99602.50596241062, 99996.0, 100378.0, 106587.46706266575, 11151.330920144494, 11210.43947802623, 11643.767124657628, 11746.823797643214, 11808.291502509877, 43192.35211471767, 43447.13105737931, 43733.78133109314, 43748.781256094786, 45060.77469612631, 74640.0, 77609.2239077602, 84099.5795021021, 85349.57325213583, 85520.57239713761, 15476.922615386851, 17365.21856516485, 17674.116294184983, 17680.73478897792, 18046.819531804515, 18176.363827266512, 18470.168842402392, 18716.719249211546, 18759.718604220674, 18808.435746927615, 14955.85044149588, 16245.51263462098, 17129.97220166794, 17906.5523361917, 18367.35714249995, 16989.06560139204, 17098.572535686708, 18111.909440452713, 18228.997405142843, 18323.083845807923, 16994.49016529506, 17261.65476690434, 17777.644447111245, 18130.81869181344, 18131.0, 8094.959525202572, 9429.858552121861, 10586.89413105859, 10945.616903408343, 11292.943535282271, 12223.572174973833, 13066.608001759962, 13135.540256090992, 14402.063865848686, 14402.567922962327], "value": 8730.536305155863, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1546.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 117.0, 171.0, 169.0, 115.0, 114.0, 115.0, 114.0, 114.0, 115.0], "value": 124.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 101.0, 134.0, 187.0, 145.0, 104.0, 111.0, 112.0, 134.0, 112.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 209.0, 230.0, 236.0, 202.0, 272.0, 194.0, 250.0, 198.0, 205.0], "value": 219.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 65.0, 86.0, 111.0, 85.0, 58.0, 59.0, 59.0, 78.0, 61.0], "value": 74.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 69.0, 85.0, 70.0, 72.0, 69.0, 86.0, 69.0, 72.0, 91.0], "value": 76.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 88.0, 125.0, 96.0, 87.0, 98.0, 88.0, 89.0, 89.0], "value": 93.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 96.0, 96.0, 97.0, 96.0, 96.0, 99.0, 98.0, 154.0, 109.0], "value": 106.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 91.0, 91.0, 100.0, 89.0, 91.0, 104.0, 87.0, 137.0], "value": 97.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 54.0, 54.0, 62.0, 65.0, 67.0, 47.0, 46.0, 77.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 60.0, 56.0, 60.0, 63.0, 74.0, 54.0, 64.0, 75.0], "value": 61.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 89.0, 76.0, 103.0, 115.0, 112.0, 88.0, 124.0, 88.0], "value": 96.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 160.0, 144.0, 154.0, 165.0, 161.0, 139.0, 136.0, 153.0, 141.0], "value": 150.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 190.0, 187.0, 186.0, 184.0, 184.0, 202.0, 202.0, 183.0, 195.0], "value": 190.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 69.0, 72.0, 66.0, 69.0, 66.0, 69.0, 68.0], "value": 68.1, "unit": "score"}]}]} 09:58:59 INFO - Return code: 0 09:58:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:58:59 INFO - # TBPL SUCCESS # 09:58:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:58:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:58:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:58:59 INFO - 2017-05-29 09:58:59.251 screenresolution[1664:10933] starting screenresolution argv=screenresolution get 09:58:59 INFO - 2017-05-29 09:58:59.254 screenresolution[1664:10933] Display 0: 1600x1200x32@60 09:58:59 INFO - 2017-05-29 09:58:59.261 screenresolution[1665:10936] starting screenresolution argv=screenresolution list 09:58:59 INFO - Available Modes on Display 0 09:58:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:58:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:58:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:58:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:58:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:58:59 INFO - Intel Iris: 09:58:59 INFO - Chipset Model: Intel Iris 09:58:59 INFO - Type: GPU 09:58:59 INFO - Bus: Built-In 09:58:59 INFO - VRAM (Dynamic, Max): 1536 MB 09:58:59 INFO - Vendor: Intel (0x8086) 09:58:59 INFO - Device ID: 0x0a2e 09:58:59 INFO - Revision ID: 0x0009 09:58:59 INFO - Displays: 09:58:59 INFO - Display: 09:58:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:58:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:58:59 INFO - Display Serial Number: 1600x1200 60 09:58:59 INFO - Main Display: Yes 09:58:59 INFO - Mirror: Off 09:58:59 INFO - Online: Yes 09:58:59 INFO - Rotation: Supported 09:58:59 INFO - Return code: 0 09:58:59 INFO - Running post-action listener: _resource_record_post_action 09:58:59 INFO - [mozharness: 2017-05-29 16:58:59.501262Z] Finished run-tests step (success) 09:58:59 INFO - Running post-run listener: _resource_record_post_run 09:58:59 INFO - Total resource usage - Wall time: 1258s; CPU: 53.0%; Read bytes: 41752064; Write bytes: 570997760; Read time: 469; Write time: 1929 09:58:59 INFO - TinderboxPrint: CPU usage
53.2% 09:58:59 INFO - TinderboxPrint: I/O read bytes / time
41,752,064 / 469 09:58:59 INFO - TinderboxPrint: I/O write bytes / time
570,997,760 / 1,929 09:58:59 INFO - TinderboxPrint: CPU idle
2,358.1 (46.8%) 09:58:59 INFO - TinderboxPrint: CPU system
1,013.5 (20.1%) 09:58:59 INFO - TinderboxPrint: CPU user
1,662.0 (33.0%) 09:58:59 INFO - TinderboxPrint: Swap in / out
287,051,776 / 0 09:58:59 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 226443776; Write bytes: 221669376; Read time: 17046; Write time: 446 09:58:59 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:58:59 INFO - run-tests - Wall time: 1237s; CPU: 53.0%; Read bytes: 40744448; Write bytes: 321000448; Read time: 449; Write time: 1418 09:58:59 INFO - Running post-run listener: _upload_blobber_files 09:58:59 INFO - Blob upload gear active. 09:58:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:58:59 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 09:58:59 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'] 09:58:59 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 09:59:00 INFO - (blobuploader) - INFO - Open directory for files ... 09:59:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:59:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:59:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:01 INFO - (blobuploader) - INFO - Done attempting. 09:59:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:59:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:59:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:02 INFO - (blobuploader) - INFO - Done attempting. 09:59:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:59:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:59:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:03 INFO - (blobuploader) - INFO - Done attempting. 09:59:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:59:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:59:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:05 INFO - (blobuploader) - INFO - Done attempting. 09:59:05 INFO - (blobuploader) - INFO - Iteration through files over. 09:59:05 INFO - Return code: 0 09:59:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:59:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:59:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40cbf9f27b2bf00ded856eaab099f0e25b52421a8886c37673700412545dcf93329025484d4c26f9a3b2ab5b0963493465365612d6fa6b1744a0b83455eca9c9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da8dabba94b8c8f4964e4194b3cf3c0efc8e58a7ed7401520c7c60045e50984c07cc2c00a45f5863521770e251670420c7f87a36c43bbc674924a834a6068814", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a490f60cefc989a2eaaf8b27137ad497d8e8e1c2cd2e4b2ad605dc0861e5f00fb662edc3ac274f2265cc559993bfe99e7840749f3531059e946adeca3d131cab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/409e9607c57ac26fd5bd96dcdc46733aef0e2865724622893480fac2a0a8839396a6946a8797c198994827142a4902e9d5854d92ba94311e80ea3b59ff75c2bd"} 09:59:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:59:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:59:05 INFO - Contents: 09:59:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40cbf9f27b2bf00ded856eaab099f0e25b52421a8886c37673700412545dcf93329025484d4c26f9a3b2ab5b0963493465365612d6fa6b1744a0b83455eca9c9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da8dabba94b8c8f4964e4194b3cf3c0efc8e58a7ed7401520c7c60045e50984c07cc2c00a45f5863521770e251670420c7f87a36c43bbc674924a834a6068814", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a490f60cefc989a2eaaf8b27137ad497d8e8e1c2cd2e4b2ad605dc0861e5f00fb662edc3ac274f2265cc559993bfe99e7840749f3531059e946adeca3d131cab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/409e9607c57ac26fd5bd96dcdc46733aef0e2865724622893480fac2a0a8839396a6946a8797c198994827142a4902e9d5854d92ba94311e80ea3b59ff75c2bd"} 09:59:05 INFO - Running post-run listener: copy_logs_to_upload_dir 09:59:05 INFO - Copying logs to upload dir... 09:59:05 INFO - mkdir: /builds/slave/test/build/upload/logs 09:59:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.859523 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-29 09:59:05.942378) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 09:59:05.946198) ========= 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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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/40cbf9f27b2bf00ded856eaab099f0e25b52421a8886c37673700412545dcf93329025484d4c26f9a3b2ab5b0963493465365612d6fa6b1744a0b83455eca9c9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da8dabba94b8c8f4964e4194b3cf3c0efc8e58a7ed7401520c7c60045e50984c07cc2c00a45f5863521770e251670420c7f87a36c43bbc674924a834a6068814", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a490f60cefc989a2eaaf8b27137ad497d8e8e1c2cd2e4b2ad605dc0861e5f00fb662edc3ac274f2265cc559993bfe99e7840749f3531059e946adeca3d131cab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/409e9607c57ac26fd5bd96dcdc46733aef0e2865724622893480fac2a0a8839396a6946a8797c198994827142a4902e9d5854d92ba94311e80ea3b59ff75c2bd"} build_url:https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010828 build_url: 'https://queue.taskcluster.net/v1/task/KKCJP0IgS36w1plce9Jklg/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/40cbf9f27b2bf00ded856eaab099f0e25b52421a8886c37673700412545dcf93329025484d4c26f9a3b2ab5b0963493465365612d6fa6b1744a0b83455eca9c9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da8dabba94b8c8f4964e4194b3cf3c0efc8e58a7ed7401520c7c60045e50984c07cc2c00a45f5863521770e251670420c7f87a36c43bbc674924a834a6068814", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a490f60cefc989a2eaaf8b27137ad497d8e8e1c2cd2e4b2ad605dc0861e5f00fb662edc3ac274f2265cc559993bfe99e7840749f3531059e946adeca3d131cab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/409e9607c57ac26fd5bd96dcdc46733aef0e2865724622893480fac2a0a8839396a6946a8797c198994827142a4902e9d5854d92ba94311e80ea3b59ff75c2bd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 09:59:05.978987) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 09:59:05.979324) ========= 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.ZMm6mVvW9f/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.R64O8lvjt6/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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.005229 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 09:59:06.028235) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 09:59:06.028600) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 09:59:06.028995) ========= ========= Total master_lag: 0.18 =========