builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0112 starttime: 1495768884.49 results: success (0) revision: 862e534ade6d17176b09aeeb5dae63fdb53eddeb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 20:21:24.488956) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 20:21:24.489371) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 20:21:24.489691) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 20:21:24.513038) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 20:21:24.513332) ========= 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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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-25 20:21:24-- 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% 8.02M=0.001s 2017-05-25 20:21:24 (8.02 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.083732 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 20:21:24.619054) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 20:21:24.619384) ========= 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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.022448 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 20:21:24.668016) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 20:21:24.668409) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 862e534ade6d17176b09aeeb5dae63fdb53eddeb --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 862e534ade6d17176b09aeeb5dae63fdb53eddeb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qvV3H7sNT0/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.KuUPBQpIRF/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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-25 20:21:24,741 truncating revision to first 12 chars 2017-05-25 20:21:24,741 Setting DEBUG logging. 2017-05-25 20:21:24,741 attempt 1/10 2017-05-25 20:21:24,741 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/862e534ade6d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 20:21:26,131 unpacking tar archive at: mozilla-central-862e534ade6d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.644378 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 20:21:26.334226) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 20:21:26.334538) ========= script_repo_revision: 862e534ade6d17176b09aeeb5dae63fdb53eddeb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 20:21:26.334897) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 20:21:26.335160) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 20:21:26.354241) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 14 secs) (at 2017-05-25 20:21:26.354592) ========= /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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 20:21:26 INFO - MultiFileLogger online at 20170525 20:21:26 in /builds/slave/test 20:21:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:21:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:21:26 INFO - {'append_to_log': False, 20:21:26 INFO - 'base_work_dir': '/builds/slave/test', 20:21:26 INFO - 'blob_upload_branch': 'Firefox', 20:21:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:21:26 INFO - 'branch': 'Firefox', 20:21:26 INFO - 'buildbot_json_path': 'buildprops.json', 20:21:26 INFO - 'config_files': ('talos/mac_config.py',), 20:21:26 INFO - 'default_actions': ('clobber', 20:21:26 INFO - 'read-buildbot-config', 20:21:26 INFO - 'download-and-extract', 20:21:26 INFO - 'populate-webroot', 20:21:26 INFO - 'create-virtualenv', 20:21:26 INFO - 'install', 20:21:26 INFO - 'run-tests'), 20:21:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:21:26 INFO - 'download_minidump_stackwalk': True, 20:21:26 INFO - 'download_symbols': 'ondemand', 20:21:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:21:26 INFO - 'tooltool.py': '/tools/tooltool.py', 20:21:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:21:26 INFO - '/tools/misc-python/virtualenv.py')}, 20:21:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:21:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:21:26 INFO - 'gecko_profile': False, 20:21:26 INFO - 'gecko_profile_interval': 0, 20:21:26 INFO - 'installer_path': 'installer.exe', 20:21:26 INFO - 'log_level': 'info', 20:21:26 INFO - 'log_name': 'talos', 20:21:26 INFO - 'log_to_console': True, 20:21:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:21:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:21:26 INFO - 'opt_config_files': (), 20:21:26 INFO - 'pip_index': False, 20:21:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:21:26 INFO - 'cmd': ('bash', 20:21:26 INFO - '-c', 20:21:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:21:26 INFO - 'enabled': True, 20:21:26 INFO - 'halt_on_failure': False, 20:21:26 INFO - 'name': 'check_screen_resolution'},), 20:21:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:21:26 INFO - 'cmd': ('bash', 20:21:26 INFO - '-c', 20:21:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:21:26 INFO - 'enabled': True, 20:21:26 INFO - 'halt_on_failure': False, 20:21:26 INFO - 'name': 'check_screen_resolution'},), 20:21:26 INFO - 'run_cmd_checks_enabled': True, 20:21:26 INFO - 'suite': 'dromaeojs-e10s', 20:21:26 INFO - 'system_bits': '32', 20:21:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:21:26 INFO - 'title': 't-yosemite-r7-0112', 20:21:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:21:26 INFO - 'use_talos_json': True, 20:21:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:21:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:21:26 INFO - 'work_dir': 'build'} 20:21:26 INFO - [mozharness: 2017-05-26 03:21:26.574441Z] Running clobber step. 20:21:26 INFO - Running pre-action listener: _resource_record_pre_action 20:21:26 INFO - Running main action method: clobber 20:21:26 INFO - rmtree: /builds/slave/test/build 20:21:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:21:26 INFO - Running post-action listener: _resource_record_post_action 20:21:26 INFO - [mozharness: 2017-05-26 03:21:26.733468Z] Finished clobber step (success) 20:21:26 INFO - [mozharness: 2017-05-26 03:21:26.733614Z] Running read-buildbot-config step. 20:21:26 INFO - Running pre-action listener: _resource_record_pre_action 20:21:26 INFO - Running main action method: read_buildbot_config 20:21:26 INFO - Using buildbot properties: 20:21:26 INFO - { 20:21:26 INFO - "project": "", 20:21:26 INFO - "product": "firefox", 20:21:26 INFO - "who": "ryanvm@gmail.com", 20:21:26 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 20:21:26 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 20:21:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 20:21:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 20:21:26 INFO - "stage_platform": "macosx64", 20:21:26 INFO - "basedir": "/builds/slave/test", 20:21:26 INFO - "buildnumber": 85, 20:21:26 INFO - "platform": "yosemite_r7", 20:21:26 INFO - "parent_task_id": "dfLImuXqQ1OYhCBHk_kUIw", 20:21:26 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:21:26 INFO - "slavebuilddir": "test", 20:21:26 INFO - "taskId": "cJ5buapnQ7-VD6sR6CrSrw", 20:21:26 INFO - "branch": "mozilla-central", 20:21:26 INFO - "script_repo_revision": "production", 20:21:26 INFO - "revision": "862e534ade6d17176b09aeeb5dae63fdb53eddeb", 20:21:26 INFO - "slavename": "t-yosemite-r7-0112", 20:21:26 INFO - "repo_path": "mozilla-central" 20:21:26 INFO - } 20:21:26 INFO - Finding installer, test and symbols from parent task. 20:21:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cJ5buapnQ7-VD6sR6CrSrw'}, attempt #1 20:21:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cJ5buapnQ7-VD6sR6CrSrw'}, attempt #1 20:21:28 INFO - Task dependencies: d3NdhIFcQ2u8SPBa_Bkj2w dfLImuXqQ1OYhCBHk_kUIw 20:21:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw'}, attempt #1 20:21:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:21:28 INFO - Set test_url: https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/test_packages.json 20:21:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/target.crashreporter-symbols.zip 20:21:28 INFO - Running post-action listener: _resource_record_post_action 20:21:28 INFO - [mozharness: 2017-05-26 03:21:28.579910Z] Finished read-buildbot-config step (success) 20:21:28 INFO - [mozharness: 2017-05-26 03:21:28.580032Z] Running download-and-extract step. 20:21:28 INFO - Running pre-action listener: _resource_record_pre_action 20:21:28 INFO - Running main action method: download_and_extract 20:21:28 INFO - mkdir: /builds/slave/test/build/tests 20:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:21:28 INFO - trying https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 20:21:28 INFO - Downloading https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 20:21:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 20:21:29 INFO - Downloaded 1325 bytes. 20:21:29 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 20:21:29 INFO - Using the following test package requirements: 20:21:29 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:21:29 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 20:21:29 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 20:21:29 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:21:29 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 20:21:29 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:21:29 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 20:21:29 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:21:29 INFO - u'jsshell-mac.zip'], 20:21:29 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:21:29 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 20:21:29 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 20:21:29 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:21:29 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 20:21:29 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:21:29 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 20:21:29 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:21:29 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 20:21:29 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:21:29 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 20:21:29 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:21:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 20:21:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:21:29 INFO - Fetch https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 20:21:31 INFO - Content-Length response header: 57627200 20:21:31 INFO - Bytes received: 57627200 20:21:49 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:21:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 20:21:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:21:49 INFO - Fetch https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 20:21:51 INFO - Content-Length response header: 57627200 20:21:51 INFO - Bytes received: 57627200 20:22:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 20:22:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:22:08 INFO - Fetch https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 20:22:09 INFO - Content-Length response header: 14411888 20:22:09 INFO - Bytes received: 14411888 20:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:09 INFO - trying https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:22:09 INFO - Downloading https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 20:22:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 20:22:11 INFO - Downloaded 67229882 bytes. 20:22:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:22:11 INFO - mkdir: /builds/slave/test/properties 20:22:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:22:11 INFO - Writing to file /builds/slave/test/properties/build_url 20:22:11 INFO - Contents: 20:22:11 INFO - build_url:https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:22:11 INFO - Running post-action listener: _resource_record_post_action 20:22:11 INFO - Running post-action listener: set_extra_try_arguments 20:22:11 INFO - [mozharness: 2017-05-26 03:22:11.916580Z] Finished download-and-extract step (success) 20:22:11 INFO - [mozharness: 2017-05-26 03:22:11.916709Z] Running populate-webroot step. 20:22:11 INFO - Running pre-action listener: _resource_record_pre_action 20:22:11 INFO - Running main action method: populate_webroot 20:22:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:22:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:22:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:22:11 INFO - '--tppagecycles', 20:22:11 INFO - '7'], 20:22:11 INFO - 'tests': ['tsvgm']}}, 20:22:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:22:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:22:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:22:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:22:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:22:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:22:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:22:11 INFO - 'talos_options': ['--disable-e10s'], 20:22:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:22:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:22:11 INFO - 'talos_options': ['--disable-e10s'], 20:22:11 INFO - 'tests': ['damp', 'tps']}, 20:22:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:22:11 INFO - 'tests': ['damp', 'tps']}, 20:22:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:22:11 INFO - 'tests': ['dromaeo_dom']}, 20:22:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:22:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:22:11 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 20:22:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 20:22:11 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 20:22:11 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 20:22:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:22:11 INFO - 'tests': ['a11yr', 20:22:11 INFO - 'ts_paint', 20:22:11 INFO - 'tpaint', 20:22:11 INFO - 'sessionrestore', 20:22:11 INFO - 'sessionrestore_no_auto_restore', 20:22:11 INFO - 'tabpaint']}, 20:22:11 INFO - 'other-e10s': {'tests': ['a11yr', 20:22:11 INFO - 'ts_paint', 20:22:11 INFO - 'tpaint', 20:22:11 INFO - 'sessionrestore', 20:22:11 INFO - 'sessionrestore_no_auto_restore', 20:22:11 INFO - 'tabpaint']}, 20:22:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:22:11 INFO - 'ts_paint', 20:22:11 INFO - 'tpaint', 20:22:11 INFO - 'sessionrestore', 20:22:11 INFO - 'sessionrestore_no_auto_restore', 20:22:11 INFO - 'tabpaint']}, 20:22:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:22:11 INFO - 'ts_paint', 20:22:11 INFO - 'tpaint', 20:22:11 INFO - 'sessionrestore', 20:22:11 INFO - 'sessionrestore_no_auto_restore', 20:22:11 INFO - 'tabpaint']}, 20:22:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:22:11 INFO - 'tests': ['a11yr', 20:22:11 INFO - 'ts_paint', 20:22:11 INFO - 'tpaint', 20:22:11 INFO - 'sessionrestore', 20:22:11 INFO - 'sessionrestore_no_auto_restore', 20:22:11 INFO - 'tabpaint']}, 20:22:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:22:11 INFO - 'tests': ['a11yr', 20:22:11 INFO - 'ts_paint', 20:22:11 INFO - 'tpaint', 20:22:11 INFO - 'sessionrestore', 20:22:11 INFO - 'sessionrestore_no_auto_restore', 20:22:11 INFO - 'tabpaint']}, 20:22:11 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 20:22:11 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 20:22:11 INFO - 'bloom_basic_ref']}, 20:22:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:22:11 INFO - 'tests': ['tsvgx', 20:22:11 INFO - 'tsvgr_opacity', 20:22:11 INFO - 'tart', 20:22:11 INFO - 'tscrollx', 20:22:11 INFO - 'cart', 20:22:11 INFO - 'tsvg_static']}, 20:22:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:22:11 INFO - 'tsvgr_opacity', 20:22:11 INFO - 'tart', 20:22:11 INFO - 'tscrollx', 20:22:11 INFO - 'cart', 20:22:11 INFO - 'tsvg_static']}, 20:22:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:22:11 INFO - 'talos_options': ['--disable-e10s'], 20:22:11 INFO - 'tests': ['tp5o']}, 20:22:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:22:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:22:11 INFO - 'talos_options': ['--disable-e10s', 20:22:11 INFO - '--xperf_path', 20:22:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:11 INFO - 'tests': ['tp5n']}, 20:22:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:22:11 INFO - 'talos_options': ['--xperf_path', 20:22:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:11 INFO - 'tests': ['tp5n']}}, 20:22:11 INFO - 'talos.zip': {'path': '', 20:22:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:22:11 INFO - Running post-action listener: _resource_record_post_action 20:22:11 INFO - [mozharness: 2017-05-26 03:22:11.937896Z] Finished populate-webroot step (success) 20:22:11 INFO - [mozharness: 2017-05-26 03:22:11.938048Z] Running create-virtualenv step. 20:22:11 INFO - Running pre-action listener: _resource_record_pre_action 20:22:11 INFO - Running main action method: create_virtualenv 20:22:11 INFO - Creating virtualenv /builds/slave/test/build/venv 20:22:11 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:22:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:22:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:22:12 INFO - Using real prefix '/tools/python27' 20:22:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:22:12 INFO - Installing distribute.............................................................................................................................................................................................done. 20:22:15 INFO - Installing pip.................done. 20:22:15 INFO - Return code: 0 20:22:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:22:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:22:15 INFO - Reading from file tmpfile_stdout 20:22:15 INFO - Output received: 20:22:15 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:22:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:22:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd628dac6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7fcd30>, '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 0x10f7b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f1be8>, '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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:22:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:22:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:22:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvV3H7sNT0/Render', 20:22:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:15 INFO - 'HOME': '/Users/cltbld', 20:22:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:15 INFO - 'LOGNAME': 'cltbld', 20:22:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:15 INFO - 'MOZ_NO_REMOTE': '1', 20:22:15 INFO - 'NO_EM_RESTART': '1', 20:22:15 INFO - 'PAGER': '/bin/cat', 20:22:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:15 INFO - 'PWD': '/builds/slave/test', 20:22:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:15 INFO - 'SHELL': '/bin/bash', 20:22:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KuUPBQpIRF/Listeners', 20:22:15 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 20:22:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:15 INFO - 'USER': 'cltbld', 20:22:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:15 INFO - 'XPC_FLAGS': '0x0', 20:22:15 INFO - 'XPC_SERVICE_NAME': '0', 20:22:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:22:16 INFO - Cleaning up... 20:22:16 INFO - Return code: 0 20:22:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:16 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 0x10faecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd628dac6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7fcd30>, '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 0x10f7b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f1be8>, '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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvV3H7sNT0/Render', 20:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:16 INFO - 'HOME': '/Users/cltbld', 20:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:16 INFO - 'LOGNAME': 'cltbld', 20:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:16 INFO - 'MOZ_NO_REMOTE': '1', 20:22:16 INFO - 'NO_EM_RESTART': '1', 20:22:16 INFO - 'PAGER': '/bin/cat', 20:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:16 INFO - 'PWD': '/builds/slave/test', 20:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:16 INFO - 'SHELL': '/bin/bash', 20:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KuUPBQpIRF/Listeners', 20:22:16 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 20:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:16 INFO - 'USER': 'cltbld', 20:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:16 INFO - 'XPC_FLAGS': '0x0', 20:22:16 INFO - 'XPC_SERVICE_NAME': '0', 20:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:16 INFO - Downloading/unpacking psutil>=3.1.1 20:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:22:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:18 INFO - Installing collected packages: psutil 20:22:18 INFO - Running setup.py install for psutil 20:22:18 INFO - building 'psutil._psutil_osx' extension 20:22:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:22:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 20:22:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:22:18 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 20:22:18 INFO - building 'psutil._psutil_posix' extension 20:22:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:22:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:22:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:22:18 INFO - ^ 20:22:18 INFO - 1 warning generated. 20:22:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:22:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:19 INFO - Successfully installed psutil 20:22:19 INFO - Cleaning up... 20:22:19 INFO - Return code: 0 20:22:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:22:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:19 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 0x10faecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd628dac6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7fcd30>, '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 0x10f7b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f1be8>, '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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:22:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:22:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:22:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvV3H7sNT0/Render', 20:22:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:19 INFO - 'HOME': '/Users/cltbld', 20:22:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:19 INFO - 'LOGNAME': 'cltbld', 20:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:19 INFO - 'MOZ_NO_REMOTE': '1', 20:22:19 INFO - 'NO_EM_RESTART': '1', 20:22:19 INFO - 'PAGER': '/bin/cat', 20:22:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:19 INFO - 'PWD': '/builds/slave/test', 20:22:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:19 INFO - 'SHELL': '/bin/bash', 20:22:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KuUPBQpIRF/Listeners', 20:22:19 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 20:22:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:19 INFO - 'USER': 'cltbld', 20:22:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:19 INFO - 'XPC_FLAGS': '0x0', 20:22:19 INFO - 'XPC_SERVICE_NAME': '0', 20:22:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:22:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:22:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:22:20 INFO - Installing collected packages: mozsystemmonitor 20:22:20 INFO - Running setup.py install for mozsystemmonitor 20:22:21 INFO - Successfully installed mozsystemmonitor 20:22:21 INFO - Cleaning up... 20:22:21 INFO - Return code: 0 20:22:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd628dac6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7fcd30>, '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 0x10f7b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f1be8>, '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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:22:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvV3H7sNT0/Render', 20:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:21 INFO - 'HOME': '/Users/cltbld', 20:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:21 INFO - 'LOGNAME': 'cltbld', 20:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:21 INFO - 'MOZ_NO_REMOTE': '1', 20:22:21 INFO - 'NO_EM_RESTART': '1', 20:22:21 INFO - 'PAGER': '/bin/cat', 20:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:21 INFO - 'PWD': '/builds/slave/test', 20:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:21 INFO - 'SHELL': '/bin/bash', 20:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KuUPBQpIRF/Listeners', 20:22:21 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 20:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:21 INFO - 'USER': 'cltbld', 20:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:21 INFO - 'XPC_FLAGS': '0x0', 20:22:21 INFO - 'XPC_SERVICE_NAME': '0', 20:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:22 INFO - Downloading/unpacking jsonschema==2.5.1 20:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:22:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:24 INFO - Downloading functools32-3.2.3-2.tar.gz 20:22:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:22:24 INFO - warning: no files found matching '*.txt' 20:22:24 INFO - no previously-included directories found matching 'build' 20:22:24 INFO - no previously-included directories found matching 'dist' 20:22:24 INFO - no previously-included directories found matching '.git*' 20:22:24 INFO - Installing collected packages: jsonschema, functools32 20:22:24 INFO - Running setup.py install for functools32 20:22:24 INFO - warning: no files found matching '*.txt' 20:22:24 INFO - no previously-included directories found matching 'build' 20:22:24 INFO - no previously-included directories found matching 'dist' 20:22:24 INFO - no previously-included directories found matching '.git*' 20:22:24 INFO - Successfully installed jsonschema functools32 20:22:24 INFO - Cleaning up... 20:22:24 INFO - Return code: 0 20:22:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd628dac6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7fcd30>, '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 0x10f7b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f1be8>, '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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:22:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:22:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvV3H7sNT0/Render', 20:22:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:24 INFO - 'HOME': '/Users/cltbld', 20:22:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:24 INFO - 'LOGNAME': 'cltbld', 20:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:24 INFO - 'MOZ_NO_REMOTE': '1', 20:22:24 INFO - 'NO_EM_RESTART': '1', 20:22:24 INFO - 'PAGER': '/bin/cat', 20:22:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:24 INFO - 'PWD': '/builds/slave/test', 20:22:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:24 INFO - 'SHELL': '/bin/bash', 20:22:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KuUPBQpIRF/Listeners', 20:22:24 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 20:22:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:24 INFO - 'USER': 'cltbld', 20:22:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:24 INFO - 'XPC_FLAGS': '0x0', 20:22:24 INFO - 'XPC_SERVICE_NAME': '0', 20:22:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:22:24 INFO - Cleaning up... 20:22:24 INFO - Return code: 0 20:22:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd628dac6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7fcd30>, '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 0x10f7b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f1be8>, '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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:22:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:22:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvV3H7sNT0/Render', 20:22:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:24 INFO - 'HOME': '/Users/cltbld', 20:22:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:24 INFO - 'LOGNAME': 'cltbld', 20:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:24 INFO - 'MOZ_NO_REMOTE': '1', 20:22:24 INFO - 'NO_EM_RESTART': '1', 20:22:24 INFO - 'PAGER': '/bin/cat', 20:22:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:24 INFO - 'PWD': '/builds/slave/test', 20:22:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:24 INFO - 'SHELL': '/bin/bash', 20:22:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KuUPBQpIRF/Listeners', 20:22:24 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 20:22:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:24 INFO - 'USER': 'cltbld', 20:22:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:24 INFO - 'XPC_FLAGS': '0x0', 20:22:24 INFO - 'XPC_SERVICE_NAME': '0', 20:22:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:25 INFO - Downloading/unpacking blobuploader==1.2.4 20:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:26 INFO - Downloading blobuploader-1.2.4.tar.gz 20:22:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:22:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:22:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:27 INFO - Downloading docopt-0.6.1.tar.gz 20:22:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:22:27 INFO - Installing collected packages: blobuploader, requests, docopt 20:22:27 INFO - Running setup.py install for blobuploader 20:22:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:22:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:22:27 INFO - Running setup.py install for requests 20:22:28 INFO - Running setup.py install for docopt 20:22:28 INFO - Successfully installed blobuploader requests docopt 20:22:28 INFO - Cleaning up... 20:22:28 INFO - Return code: 0 20:22:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:28 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 0x10faecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd628dac6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7fcd30>, '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 0x10f7b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f1be8>, '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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:22:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:22:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:22:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvV3H7sNT0/Render', 20:22:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:28 INFO - 'HOME': '/Users/cltbld', 20:22:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:28 INFO - 'LOGNAME': 'cltbld', 20:22:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:28 INFO - 'MOZ_NO_REMOTE': '1', 20:22:28 INFO - 'NO_EM_RESTART': '1', 20:22:28 INFO - 'PAGER': '/bin/cat', 20:22:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:28 INFO - 'PWD': '/builds/slave/test', 20:22:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:28 INFO - 'SHELL': '/bin/bash', 20:22:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KuUPBQpIRF/Listeners', 20:22:28 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 20:22:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:28 INFO - 'USER': 'cltbld', 20:22:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:28 INFO - 'XPC_FLAGS': '0x0', 20:22:28 INFO - 'XPC_SERVICE_NAME': '0', 20:22:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:28 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ThcNiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:28 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-1Dg4rz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:28 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-joPTdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:28 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ZffHov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:29 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-w73N9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:29 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-0A0Ku_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:29 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-51d_QU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:29 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-kAdYv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:29 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-pcjvl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:29 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-XcGiZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:29 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-jay3Xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:29 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-bid1Cc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:29 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ZRdjYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:30 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ONvlU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:30 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-CeGhlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:30 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ilmVlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:30 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-TvqFVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:22:30 INFO - Running setup.py install for manifestparser 20:22:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:22:30 INFO - Running setup.py install for mozcrash 20:22:30 INFO - Running setup.py install for mozdebug 20:22:30 INFO - Running setup.py install for mozdevice 20:22:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:22:31 INFO - Running setup.py install for mozfile 20:22:31 INFO - Running setup.py install for mozhttpd 20:22:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:22:31 INFO - Running setup.py install for mozinfo 20:22:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:22:31 INFO - Running setup.py install for mozInstall 20:22:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:22:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:22:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:22:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:22:31 INFO - Running setup.py install for mozleak 20:22:31 INFO - Running setup.py install for mozlog 20:22:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:22:31 INFO - Running setup.py install for moznetwork 20:22:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:22:32 INFO - Running setup.py install for mozprocess 20:22:32 INFO - Running setup.py install for mozprofile 20:22:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:22:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:22:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:22:32 INFO - Running setup.py install for mozrunner 20:22:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:22:32 INFO - Running setup.py install for mozscreenshot 20:22:32 INFO - Running setup.py install for moztest 20:22:32 INFO - Running setup.py install for mozversion 20:22:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:22:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:22:32 INFO - Cleaning up... 20:22:32 INFO - Return code: 0 20:22:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd628dac6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7fcd30>, '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 0x10f7b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f1be8>, '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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:22:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:22:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:22:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvV3H7sNT0/Render', 20:22:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:32 INFO - 'HOME': '/Users/cltbld', 20:22:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:32 INFO - 'LOGNAME': 'cltbld', 20:22:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:32 INFO - 'MOZ_NO_REMOTE': '1', 20:22:32 INFO - 'NO_EM_RESTART': '1', 20:22:32 INFO - 'PAGER': '/bin/cat', 20:22:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:32 INFO - 'PWD': '/builds/slave/test', 20:22:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:32 INFO - 'SHELL': '/bin/bash', 20:22:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KuUPBQpIRF/Listeners', 20:22:32 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 20:22:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:32 INFO - 'USER': 'cltbld', 20:22:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:32 INFO - 'XPC_FLAGS': '0x0', 20:22:32 INFO - 'XPC_SERVICE_NAME': '0', 20:22:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:33 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-4k7hQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:33 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-VpWvct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:33 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-kdS54k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:33 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-4cdfWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:33 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)) 20:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:33 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-zbjjxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:33 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-02GRup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:33 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-UPFHqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:33 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-xALnOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-KBOr3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-QlyEK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-0v6PhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-d4r12u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-_9lQtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-KZr6S8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-xIO5gY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Exnrtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:34 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-GKMbjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:22:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:22:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:22:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:22:35 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)) 20:22:35 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)) 20:22:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:36 INFO - Downloading blessings-1.6.tar.gz 20:22:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:22:36 INFO - Installing collected packages: blessings 20:22:36 INFO - Running setup.py install for blessings 20:22:36 INFO - Successfully installed blessings 20:22:36 INFO - Cleaning up... 20:22:36 INFO - Return code: 0 20:22:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:22:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:22:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:22:37 INFO - Reading from file tmpfile_stdout 20:22:37 INFO - Current package versions: 20:22:37 INFO - blessings == 1.6 20:22:37 INFO - blobuploader == 1.2.4 20:22:37 INFO - docopt == 0.6.1 20:22:37 INFO - functools32 == 3.2.3-2 20:22:37 INFO - jsonschema == 2.5.1 20:22:37 INFO - manifestparser == 1.1 20:22:37 INFO - mozInstall == 1.12 20:22:37 INFO - mozcrash == 1.0 20:22:37 INFO - mozdebug == 0.1 20:22:37 INFO - mozdevice == 0.51 20:22:37 INFO - mozfile == 1.2 20:22:37 INFO - mozhttpd == 0.7 20:22:37 INFO - mozinfo == 0.9 20:22:37 INFO - mozleak == 0.1 20:22:37 INFO - mozlog == 3.4 20:22:37 INFO - moznetwork == 0.27 20:22:37 INFO - mozprocess == 0.25 20:22:37 INFO - mozprofile == 0.28 20:22:37 INFO - mozrunner == 6.13 20:22:37 INFO - mozscreenshot == 0.1 20:22:37 INFO - mozsystemmonitor == 0.3 20:22:37 INFO - moztest == 0.8 20:22:37 INFO - mozversion == 1.4 20:22:37 INFO - psutil == 3.1.1 20:22:37 INFO - requests == 1.2.3 20:22:37 INFO - wsgiref == 0.1.2 20:22:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22: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')]} 20:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22: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')]} 20:22:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:37 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 0x10faecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd628dac6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7fcd30>, '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 0x10f7b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f1be8>, '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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:22:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:22:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvV3H7sNT0/Render', 20:22:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:37 INFO - 'HOME': '/Users/cltbld', 20:22:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:37 INFO - 'LOGNAME': 'cltbld', 20:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:37 INFO - 'MOZ_NO_REMOTE': '1', 20:22:37 INFO - 'NO_EM_RESTART': '1', 20:22:37 INFO - 'PAGER': '/bin/cat', 20:22:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:37 INFO - 'PWD': '/builds/slave/test', 20:22:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:37 INFO - 'SHELL': '/bin/bash', 20:22:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KuUPBQpIRF/Listeners', 20:22:37 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 20:22:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:37 INFO - 'USER': 'cltbld', 20:22:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:37 INFO - 'XPC_FLAGS': '0x0', 20:22:37 INFO - 'XPC_SERVICE_NAME': '0', 20:22:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:22:37 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:22: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 20:22: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 20:22: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 20:22: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 20:22:38 INFO - Downloading marionette_driver-1.4.0.tar.gz 20:22:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 20:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:22:38 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)) 20:22:38 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)) 20:22:38 INFO - Installing collected packages: marionette-driver 20:22:38 INFO - Running setup.py install for marionette-driver 20:22:39 INFO - Successfully installed marionette-driver 20:22:39 INFO - Cleaning up... 20:22:39 INFO - Return code: 0 20:22:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:22:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:39 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 0x10faecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faf0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd628dac6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7fcd30>, '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 0x10f7b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f1be8>, '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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:22:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:22:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:22:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvV3H7sNT0/Render', 20:22:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:39 INFO - 'HOME': '/Users/cltbld', 20:22:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:39 INFO - 'LOGNAME': 'cltbld', 20:22:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:39 INFO - 'MOZ_NO_REMOTE': '1', 20:22:39 INFO - 'NO_EM_RESTART': '1', 20:22:39 INFO - 'PAGER': '/bin/cat', 20:22:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:39 INFO - 'PWD': '/builds/slave/test', 20:22:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:39 INFO - 'SHELL': '/bin/bash', 20:22:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KuUPBQpIRF/Listeners', 20:22:39 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 20:22:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:39 INFO - 'USER': 'cltbld', 20:22:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:39 INFO - 'XPC_FLAGS': '0x0', 20:22:39 INFO - 'XPC_SERVICE_NAME': '0', 20:22:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:22:39 INFO - Cleaning up... 20:22:39 INFO - Return code: 0 20:22:39 INFO - Running post-action listener: _resource_record_post_action 20:22:39 INFO - Running post-action listener: _start_resource_monitoring 20:22:39 INFO - Starting resource monitoring. 20:22:39 INFO - [mozharness: 2017-05-26 03:22:39.470011Z] Finished create-virtualenv step (success) 20:22:39 INFO - [mozharness: 2017-05-26 03:22:39.470983Z] Running install step. 20:22:39 INFO - Running pre-action listener: _resource_record_pre_action 20:22:39 INFO - Running main action method: install 20:22:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:22:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:22:39 INFO - Reading from file tmpfile_stdout 20:22:39 INFO - Detecting whether we're running mozinstall >=1.0... 20:22:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:22:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:22:39 INFO - Reading from file tmpfile_stdout 20:22:39 INFO - Output received: 20:22:39 INFO - Usage: mozinstall [options] installer 20:22:39 INFO - Options: 20:22:39 INFO - -h, --help show this help message and exit 20:22:39 INFO - -d DEST, --destination=DEST 20:22:39 INFO - Directory to install application into. [default: 20:22:39 INFO - "/builds/slave/test"] 20:22:39 INFO - --app=APP Application being installed. [default: firefox] 20:22:39 INFO - mkdir: /builds/slave/test/build/application 20:22:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:22:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:23:01 INFO - Reading from file tmpfile_stdout 20:23:01 INFO - Output received: 20:23:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:23:01 INFO - Running post-action listener: _resource_record_post_action 20:23:01 INFO - [mozharness: 2017-05-26 03:23:01.134912Z] Finished install step (success) 20:23:01 INFO - [mozharness: 2017-05-26 03:23:01.135043Z] Running run-tests step. 20:23:01 INFO - Running pre-action listener: _resource_record_pre_action 20:23:01 INFO - Running main action method: run_tests 20:23:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:23:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:23:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:23:01 INFO - 2017-05-25 20:23:01.149 screenresolution[1886:9569] starting screenresolution argv=screenresolution get 20:23:01 INFO - 2017-05-25 20:23:01.152 screenresolution[1886:9569] Display 0: 1600x1200x32@60 20:23:01 INFO - 2017-05-25 20:23:01.158 screenresolution[1887:9571] starting screenresolution argv=screenresolution list 20:23:01 INFO - Available Modes on Display 0 20:23:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:23:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:23:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:23:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:23:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:23:01 INFO - Intel Iris: 20:23:01 INFO - Chipset Model: Intel Iris 20:23:01 INFO - Type: GPU 20:23:01 INFO - Bus: Built-In 20:23:01 INFO - VRAM (Dynamic, Max): 1536 MB 20:23:01 INFO - Vendor: Intel (0x8086) 20:23:01 INFO - Device ID: 0x0a2e 20:23:01 INFO - Revision ID: 0x0009 20:23:01 INFO - Displays: 20:23:01 INFO - Display: 20:23:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:23:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:23:01 INFO - Display Serial Number: 1600x1200 60 20:23:01 INFO - Main Display: Yes 20:23:01 INFO - Mirror: Off 20:23:01 INFO - Online: Yes 20:23:01 INFO - Rotation: Supported 20:23:01 INFO - Return code: 0 20:23:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:23:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:23:01 INFO - Python 2.7.3 20:23:01 INFO - Return code: 0 20:23:01 INFO - grabbing minidump binary from tooltool 20:23:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:01 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 0x10f7fcd30>, '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 0x10f7b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7f1be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:23:01 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:23:01 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 20:23:01 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:23:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:23:01 INFO - Return code: 0 20:23:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:23:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:23:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:23:01 INFO - ENV: RUST_BACKTRACE is now 1 20:23:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:23:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:23:01 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/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:23:01 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/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0112 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:23:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qvV3H7sNT0/Render', 20:23:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:01 INFO - 'HOME': '/Users/cltbld', 20:23:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:01 INFO - 'LOGNAME': 'cltbld', 20:23:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:23:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:23:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:01 INFO - 'MOZ_NO_REMOTE': '1', 20:23:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:23:01 INFO - 'NO_EM_RESTART': '1', 20:23:01 INFO - 'PAGER': '/bin/cat', 20:23:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:01 INFO - 'PWD': '/builds/slave/test', 20:23:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:23:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:01 INFO - 'RUST_BACKTRACE': '1', 20:23:01 INFO - 'SHELL': '/bin/bash', 20:23:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KuUPBQpIRF/Listeners', 20:23:01 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 20:23:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:01 INFO - 'USER': 'cltbld', 20:23:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:01 INFO - 'XPC_FLAGS': '0x0', 20:23:01 INFO - 'XPC_SERVICE_NAME': '0', 20:23:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:01 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/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:23:01 INFO - mozversion application_buildid: 20170525183506 20:23:01 INFO - mozversion application_changeset: 862e534ade6d17176b09aeeb5dae63fdb53eddeb 20:23:01 INFO - mozversion application_display_name: Nightly 20:23:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:23:01 INFO - mozversion application_name: Firefox 20:23:01 INFO - mozversion application_remotingname: firefox 20:23:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:23:01 INFO - mozversion application_vendor: Mozilla 20:23:01 INFO - mozversion application_version: 55.0a1 20:23:01 INFO - mozversion platform_buildid: 20170525183506 20:23:01 INFO - mozversion platform_changeset: 862e534ade6d17176b09aeeb5dae63fdb53eddeb 20:23:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:23:01 INFO - mozversion platform_version: 55.0a1 20:23:01 INFO - using testdate: 1495768981 20:23:01 INFO - actual date: 1495768981 20:23:01 INFO - starting webserver on 'localhost:49361' 20:23:01 INFO - SUITE-START | Running 2 tests 20:23:01 INFO - TEST-START | dromaeo_css 20:23:01 INFO - operating with platform_type : mac_ 20:23:01 INFO - Initialising browser for dromaeo_css test... 20:23:01 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49361/getInfo.html -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpz9FFXw/profile 20:23:01 INFO - TEST-INFO | started process 1897 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49361/getInfo.html -foreground) 20:23:35 INFO - TEST-INFO | 1897: killed by SIGTERM 20:23:35 INFO - Browser initialized. 20:23:35 INFO - Running cycle 1/1 for dromaeo_css test... 20:23:35 INFO - TEST-INFO | started process 1904 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpz9FFXw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:23:37 INFO - PID 1904 | 2017-05-25 20:23:37.129 plugin-container[1905:10036] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 20:23:37 INFO - PID 1904 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:23:37 INFO - PID 1904 | 2017-05-25 20:23:37.129 plugin-container[1905:10036] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 20:23:37 INFO - PID 1904 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:23:37 INFO - PID 1904 | 2017-05-25 20:23:37.129 plugin-container[1905:10036] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:23:37 INFO - PID 1904 | 2017-05-25 20:23:37.826 plugin-container[1906:10080] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4843, name = 'com.apple.tsm.portname' 20:23:37 INFO - PID 1904 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:23:37 INFO - PID 1904 | 2017-05-25 20:23:37.827 plugin-container[1906:10080] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450f, name = 'com.apple.CFPasteboardClient' 20:23:37 INFO - PID 1904 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:23:37 INFO - PID 1904 | 2017-05-25 20:23:37.827 plugin-container[1906:10080] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:23:47 INFO - PID 1904 | RSS: Main: 242987008 20:23:47 INFO - PID 1904 | 20:26:49 INFO - PID 1904 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49361/tests/dromaeo/cssquery-ext.html) 20:26:50 INFO - PID 1904 | RSS: Main: 255397888 20:26:50 INFO - PID 1904 | 20:29:52 INFO - PID 1904 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-ext.html (next: http://localhost:49361/tests/dromaeo/cssquery-jquery.html) 20:29:53 INFO - PID 1904 | RSS: Main: 270430208 20:29:53 INFO - PID 1904 | 20:32:55 INFO - PID 1904 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49361/tests/dromaeo/cssquery-mootools.html) 20:32:55 INFO - PID 1904 | RSS: Main: 269467648 20:32:55 INFO - PID 1904 | 20:35:57 INFO - PID 1904 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49361/tests/dromaeo/cssquery-prototype.html) 20:35:58 INFO - PID 1904 | RSS: Main: 274149376 20:35:58 INFO - PID 1904 | 20:39:00 INFO - PID 1904 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49361/tests/dromaeo/cssquery-yui.html) 20:39:01 INFO - PID 1904 | RSS: Main: 274767872 20:39:01 INFO - PID 1904 | 20:42:03 INFO - PID 1904 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-yui.html (next: http://localhost:49361/tests/dromaeo/cssquery-dojo.html) 20:42:03 INFO - PID 1904 | RSS: Main: 275804160 20:42:03 INFO - PID 1904 | 20:42:03 INFO - PID 1904 | __start_tp_report 20:42:03 INFO - PID 1904 | _x_x_mozilla_page_load 20:42:03 INFO - PID 1904 | _x_x_mozilla_page_load_details 20:42:03 INFO - PID 1904 | |i|pagename|runs| 20:42:03 INFO - PID 1904 | |0;dojo.html;3446.4313388290934;3576.2311103112843;3603.4955106285142;3605.8737944171953;3607.206414588791;6099.268087829461;6114.084479019522;6205.069239614055;6216.533759967998;6230.750769969201;6149.754009839601;6169.5989760665525;6173.660448675321;6255.562110652245;6288.213973253343;5176.5858731301405;5370.43610420906;5401.621886467939;5405.486478784509;5465.988792073459;3941.546722126949;4799.208130658437;5463.562914966813;5531.668099913998;5568.470995255444;4899;4901.975490122562;5326.893462130735;5408.756605952742;5448.182772584105;4469;5451.318585176853;5462.535684466825;5462.7268636568015;5486;5355.919661205046;5390.110631745796;5436.238926550286;5474.178873169017;5479.232907392969;5325.281087053237;5470.398256191816;6086.9391306087255;6179.412955769195;6203.131561581382;6640.369164929372;6721.462283017347;6730.427913627347;6733.730650773963;6746.123004009496;6810.693518791666;6840.589564626139;6866.141732283465;6885.896711549331;6900.2409734929115;6258.74965001404;6347.1114044033875;6351.2060992375955;6363.9045414318825;6367.649779262092;6796.218434880052;6869.725210991604;6942.652867356612;6959.652017399109;7004.299570042955;6404.7117879695525;6457.806265812033;6461.64460954652;6508.381703738138;6624.205095388585;6689.4982876284475;6782.525223234326;6788.355106264897;6789.762358317534;6817.863642727118;6687.933120668828;6706.295838936939;6711.228208756055;6734.797956061326;6763.391294783493;6518.152640156844;6583.637899915455;6599.868002639921;6701;6701.32986701326;5359.437259087739;5406.972965135228;5416.187571864252;5422.240886275925;5442.374126975369;4709.764511774398;4745.786439610226;4764.880877978078;4777.355057067251;4783.904321913542;6241.126242326078;6275.6862156891975;6280.246370435577;6296.433321001132;6301.117843501914;6780.559263647847;6823;6831.6242606656115;6832.453403727789;6850;6339.2709838368255;6352.904706429407;6378.45783108441;6381.202349706286;6392.977123660192;5778.566607504454;5847.9415205848245;5856.180134781134;5900.1739756434135;5976.910346344721;5752.223449834302;6861.348171923659;6963.547369420971;6996.790096297119;7005.124359455068;6781;6824.044633751179;6860.279670634711;6860.828479288058;6878.140232470942;6748.527603067738;6939.410150137295;6983.511154219156;6994;7003.1596208453275;6494.675266236574;6579.769708060195;6642.83392915181;6660.500462465237;6695.06269122333;6789.93210067893;6824.488163387667;6834.555753875982;6836.38472537474;6861.862762744817;6378.48972082223;6436.131122298617;6537.248216455168;6617;6636.933630663824;6534.738610455719;6595.142631457879;6641.501887358564;6641.900371494528;6661.134052573135;6483.319251478715;6520.739170433129;6526.379993900572;6556.770513032021;6561;5516.448355164452;6405.391487808651;6487.481001519962;6666.933330666825;6730.899036514554;5507.697076660662;6366.5225108118;6396.488280937607;6411.903821442776;6420.0048992406255 20:42:03 INFO - PID 1904 | |1;ext.html;676.6853413162878;731.8436869745799;824.2952275804189;1056.392574269795;1220.7314390834013;31798.68201317989;40086.79956600216;40104.197916041696;40137;40211.99470013251;39965.80017099918;40115;40440;40506.59493405065;40585.59414405855;7791.220877912206;8239.79400514989;8438.451500652438;8458.87311690325;8475.49147051179;8409.873851892227;8417.95791021044;8519.701810436636;8544.914550854473;8546.05993340732;8461.450005749606;8481;8533.573321333908;8541.658333666677;8563.957180214122;8368.456050356708;8467.66129354825;8518.659253629847;8526.701565445179;8531.445456045369;8168.836623267502;8242.134575869508;8269.21442462965;8299.253067223919;8339.041010283834;37503;37576.06059848498;37710.62289377098;37779.4333085004;37809.62190378116;3508.3509550733197;3536;3539.522164507784;3543.2382037861726;3556.0754203906913;29837;30106.24734381636;30125.246868828235;30220.8488957556;30313.69686303131;3064.4330798802293;3086.5833112529745;3099.6745341739243;3103.9689603103907;3115.190050586841;233307;233566;277300.26997300313;296513;297516;47299.76350118296;47417.05165896663;47443;47490.05019899583;47555.76222118867;50344.24483632748;50486.495135048914;50606.49393506018;50642.24036639454;50680;13669.794953075712;13881.167129972033;13928.233947133005;13959.162450253018;13969;11493;11499.77000459986;11526.250793698382;11535.596254131233;11564;13234.933825330812;13241.470341186438;13291.734165316639;13316.267605281802;13378.197308161542;6067;6067.939320606825;6076.696165191723;6104.572679912363;6133.877322453526;962.6149540183983;995.5171741705267;997.1275134257525;1015.1320620869199;1033.9844902326472;24020.7597924022;24127.396815079763;24160.791960401908;24202.87898560496;24267.514649706907;1037.8287582548794;1084.8643919510062;1115.9107271418268;1117.620009196877;1122.147168152194;12336.62990110298;12336.876631233572;12358.691032724253;12374.938125309316;12387.442565084593;302584;303466;304150;304719;305355;53598;53893.73053134788;53953.73023134938;54104.64738381493;54107.45892541103;1488.2409970914912;1499.677569322601;1532.256855425118;1532.3487517804888;1548.3032635314153;18727.625447491246;23616.055357785495;23673.934672940104;23801.76198238065;23916.52166956686;38199.2360152801;38228.04429889275;38325.61674383332;38393.4240986391;38467.42298865463;215852.60368490286;241338;252365;254714;256387;29386;30872.91944781922;34052.978410647724;34720;35389;36722.7147049852;37153.442698360086;43281.701548953584;44703;45100.548994510944;27229.86385068062;29469.410611788073;29992.850035750555;30544.236394090327;31342.68657313398;30203;30510;30911.381772364875;31456.685433145376;31459.84270078635;36158.457623135146;36708.81645591755;37288.627113728515;37313.88058358253;37532.437013445364;30536.389272214874;30826.537601936438;31334.686653133176;31379.587918543264;31716.36567268688;22914.42713932165;24148;24662.753372466046;24707.62938555887;24845 20:42:03 INFO - PID 1904 | |2;jquery.html;398.33279257244106;425.7907542579075;434.6479351725103;442.63261492960845;447.2932766229358;19354.32259870903;21231.787682123173;21885.67171492425;22024.779752202474;22078.77921220787;21218.89390553045;21273;21319;21390.57218855622;21541.892290538526;5563.777448902049;7146.4282857371545;7153.427725781951;7160.85678286434;7168.784936451915;6071.696415179245;6660.966695166541;6923.688434020456;6937.861242775141;7023.2976702329615;6876.243613202544;6895.172579290492;6907.620080895549;6909.412699920513;6915.1010368652005;6659.234188068385;6884.931150688504;6982.650867456658;7020.981957616117;7041.260667629927;6814.727410903558;6861.691223894937;6863.725450981955;6866.279040700704;6869.656517174171;29278.70721292781;29701.851490742407;29722;29784.553231701542;29851.552226716616;2147.818699715166;2157.5037741319584;2176.9346919592435;2307.031046960264;2311.664808602743;18920;18962.43112706621;19057;19193.616127677367;19432.931188784612;2270.8751018693965;2306.723193216808;2329.9650505242435;2334.031376978552;2348.5420343033115;163450;165428.17285913613;167194;167451;169447;23451.530969380517;25667.229983100537;25718.87140564285;25741.970321186942;25758.48483030329;40760.59239407568;41374.75875723733;41379;41494.37758433626;41539.1692166161;10553.102986246102;10843.024127828534;10952.780944381067;10968.506417211245;11134.610288639858;8541.829163416696;8577.871331930026;8591.785205369792;8695.130486951255;8704.216620504185;10174.186065114773;10668.413237272025;11526.711832204095;11761.353410522534;11798.764024719458;4724.732128578302;4844.709317440965;4864.246041863516;4900.583450406684;4920.950790492049;771.7530390275058;800.4516905919347;883.9867401988976;924.089771574998;926.7636752628036;16210.351585936667;19287.710684339745;20394.082266298054;20496.38510844677;20865.269715559883;877.9078196789372;982.9384264993789;992.8560358748085;1000.1448761308976;1008.2589296866797;11226.101911847032;11288.492017859051;11307.49116289769;11307.643082829949;11334.829977550344;508564;524435;531109;532369;534894;119728.40135799265;119972;120461.79538204681;121249;122635;1141.657502749172;1153.1812413186565;1175.976480470403;1176.053277111933;1183.6094088950626;18105.004224767763;18793.812061879205;18853.15160817794;18878.905605471886;19250.22999080021;55951.44048559625;55961;56179;56682;57413.425865740806;64215.07354779364;74582;74695.62652186705;75082.62458687671;75850.86223706539;10857.83713244286;13075.738485230433;13319.400626972003;13491.05562610608;13495;13296.670332966625;13389.598312050653;13803.447862085404;14341.282935853375;14359.784603230752;11609.187356884937;11789.410529473664;11902;12038.157328986887;12137.817932731192;11155.944220278847;11162.218644694793;11333.036691881283;11455.885441145481;11474.082073434272;11912.880871191177;12159.148859580098;12170.939145304217;12181.69545761363;12239.571614993789;12713;12725.300108493751;12746;13029.413676384775;13059.542915997894;9006.414583052081;11411.37237451915;12037.638870833889;12253.938730306292;12350.62948111558 20:42:03 INFO - PID 1904 | |3;mootools.html;1164.813629819229;1412.8657777511132;1414.2009764483066;1418.0995068131733;1419.5031738891387;11704.005159561437;11723.413829308543;12262.754744905096;12300.323482208474;12366.381680915942;11982.70043248917;12088.456019479123;12194.878051219484;12258.57095001675;12315.322657253848;2566.9743302567017;2591.5594348960626;2591.857447840368;2612.242449689588;2617.2802479318243;2533.4679717259396;2574.5365834149848;2577.0336123953516;2577.3685447065495;2579.1617724239695;2530.3800568860656;2531.291238453236;2546.1343143331264;2556.2331300609835;2562.385027593383;2305.3545007397956;2337.3572267626455;2518.4711210645787;2525.1666949906485;2531.2026711585977;2490.3151633300804;2498.5127900059315;2512.987435062813;2520.609305557623;2604.882780274892;9693.757656058584;11770.352630605314;11941.94029029858;12017.459214335377;12039.157258991874;6426.132472116251;6672.633005184713;6678.732850685967;6691.230508491489;6704.664766761691;4092.8362865485346;4240.533541310453;4312.978435107836;4332.458442694663;4344.56554344563;1241.1126044877865;1243.3783108445778;1248.5130798988403;1249.8312727781724;1250.1686378558247;13271.535496257584;15353.769693454607;15763.448279310283;16593.83406165935;16624.418145364976;5356.196570514454;5401.054815407288;5608.719564021782;5610;5619.550435965195;11845.466953987092;11918.854606170973;11993.700157495958;12035.097367697457;12059.638210853689;6314.463270622048;6369.076483909807;6447.967760161169;6459.773907913294;6469.870602587922;5842.795502157489;6077.058056001327;6113.755449782047;6124.387561243929;6159.384061593894;4284.700070995001;4373.825046998148;4414.867553973386;4442.3336499524685;4522.841700540465;1254.7176885200756;1276.9297688627214;1277.7444511097817;1292.6315999939973;1293.2111412038648;772.818387678895;773.5552057566922;785.3050050705167;789.8973133492609;792.2592376128339;6789.830254243585;7009.2990700929495;7069.92930070703;7107.609081500567;7128;895.91936725695;941.0730430525927;959.5346257065318;963.4075043848123;964.609333220033;3608.891733248007;3833.50164478616;4168.785965752117;4414.448193975753;4529.048899731028;14527.201003944672;14766.261686915826;14838;14993.175375354242;15048.398064077533;14224.359903804354;14568.708625827425;14717.411303547738;14794.556163315117;14982.10107393538;8147.388945829087;8324.25081742634;8405.747827565183;8457.746267611981;8604.268637165786;6376.744930202925;6567.343265673395;6772.5936443813525;6962.373386395248;7157.320054594805;12730.490780369051;12826.294553799258;12982.15615984958;13121.737565248832;13138.014648901562;10647.520861561094;12871.549495767604;13258.933705331412;13302.866971330162;13341.733165336833;7685.654145563575;7961.880571791544;8014.078380986028;9583.856242156235;9635.036496350309;10615.787684246427;10922.126229901747;10923.453827308444;10935.617253396093;11028.44857757125;7451.664675089525;9345.15893569582;9365;9379.390339627902;9430.292728045286;8086.231807978233;9120.543972801466;9250.306227033134;9268;9383.01478344764;8203.630836612247;8311.625976831177;8373.66505339804;8389.538575378412;8397;6114.541409394402;6131.662758548323;6198.101275315144;6257;6322.2729386119345;5506.697131657797;5582.246396736388;5634.985702573576;5638.126090455986;5645.4072322405555 20:42:03 INFO - PID 1904 | |4;prototype.html;349.5490816846267;383.45357865042325;387.63755888244475;391.6670829794674;400.9498812648419;6112.29708583513;6117.54118441117;6131.294901086377;6141.969290153543;6149.3543177966285;4580.496145424001;5831.620944638596;5963.135345374929;5993.490553302963;6018.699065046752;3964.2071585682834;4543.636509079266;4552.90894182117;4554.2713165893465;4559.908801823962;4495.955040449587;4518.502964673883;4528.524504926984;4536.432945881765;4551.634509647092;3810.1427178884805;4379.34309853523;4493.73037617744;4514.119746649389;4516.254817955033;4297.806598703035;4375.687293811844;4467.17357288901;4479.9552004479865;4487.6634252431195;3979.6418322350837;3980.20395920817;3994.720369574131;4006.6794656427414;4017.0760725033115;5809.477147056743;5871.5908182036;5886.528367908023;5920.467157955804;5935.228420305376;5340;5431.972840135774;5457.181422786574;5470.316210473691;5475.589330800166;5384.24620553119;6178.41305076017;6435.938070218408;6473.74105035798;6512.479001679854;4377.299632058855;4538.273876179828;4547.226971414834;4595.402597662316;4609.585137337655;5939.515665672389;6603.933960660428;6799.5920244785475;6809.353111454404;6823.454123670094;4727.763611819464;4880.853574392774;4900.117978763857;4914.655974081851;4924.852254432372;4143.751374917515;4385.035292235703;4478.776061196927;4749.050189962021;5007.172382080515;4671.322658214608;5092.083424983539;5330.626856120034;5383.873370018899;5403.83788486346;5803.970980145156;6634.83412914681;6653.234877988996;6690.765823196165;6724.697388617524;1462.495439073518;1505.3526983397026;1509.539590424909;1519.3239008641049;1519.6352875309847;905.3119629081945;923.5520772425368;938.7559234598917;951.9619215231452;1010.1867996263079;5133.9743301284;5251.291075704808;6058.878822423615;6104.298005729398;6119.326874043852;6289.811305660837;6766.748151591972;6781.152355955505;6803.897941530881;6821.863562728718;5096.872578185501;5656.783791484768;5917.497012753878;6032.306284777307;6131.202943617302;3358.404757740103;3557.537520122419;3558.0327934353527;3565.6969157621375;3571.6964058054837;6003.519718422515;6604.0093985902495;6906.862308294013;6972.396348839795;6998.300169983063;5806.535477161817;5977.760889564455;6992.755253566101;6997.895031574531;7052;5375.892482150413;5576.860578485489;6587.440067594308;6760.966195169091;6936.34104760047;6790.456763458813;6799.660016999229;6818.590884546943;6820.93179068203;6824.962891513229;5685.914711279417;6162.630242185483;6419.871602568016;6661.966690166712;6765.376309685738;6350.618962862243;6440.937245354464;7054.541454805421;7077.610731409822;7092.751753688596;3859.571958375419;4276.3799249109325;4355.586219309161;4410.316400957857;4420.58004489573;6833.487488438486;6850.725970961105;6850.903855383314;6860.4168645663685;6900.965495172492;6522.34776522344;6636.7345306189145;6650.46796256308;6650.767223147167;6651.208810977696;6559.572958005569;6600;6627.37039981201;6662.766803161866;6682.498812589172;6022.7289771959495;6320.841978950563;6504.60972341661;6544.28012918569;6550.541462097607;5512.80705175317;6151.815445536641;6572.441342485942;6628.900566491603;6640.601563906181;6466.838329041811;6485.481161506974;6486.578372405778;6490.643014634241;6497.25281592604 20:42:03 INFO - PID 1904 | |5;yui.html;837.5351679817699;842.5618678287294;844.8648216285395;868.882700835387;882.8587426011835;14695.853041469582;15126.168060756654;15716.607084822885;16891.577710557227;17381.130943452823;17373.478795636118;17877.463676089737;17925.462236132906;17996.910015449936;18003.369882054132;944.0700909604052;963.1476143612917;967.908048735369;983.4640121133999;987.6543209876558;852.9744107676748;938.6949241496541;1005.1255407795192;1005.492226425654;1006.8993100689945;931.7111695374422;1043.1811028342715;1051.6582110814015;1052.0373857919994;1052.2476429353012;1019.6686077024997;1031.1441503552035;1031.6750223679555;1034.8654674892252;1037.7976294622551;753.773867839646;766.203148725325;766.2758693035084;767.3745897093906;768.0936494935974;17245.482635520955;17369.913150434295;17388.56528586783;17436.47690569285;17602.735958960628;17819.46541603754;17858.28566857324;17899.373521926802;18323.54191145232;18427.539311517186;14669.853301466956;14991.850081499155;15010.924945375204;15017.324220410108;15037.774433383507;3514.929701405983;3533.116720819795;3544.4860495228304;3547.6274991126074;3672.559292884844;111634;120803.3959830204;135753;135776.34907462067;136518.31740841232;13242.602721918358;13560.728785424237;13726.176429414067;13764.31178441094;14060.929695351459;25273.115440959846;25831.483370332488;25877.353066173495;26064.86967565188;26324.21027369182;6835.58986460814;7036.401905838061;7580.848383032308;7873.212678732196;8008.479448835807;3889.358255887747;3976.76139431635;3995.32079546472;4015.056461731491;4018.678505719594;4147.543770185277;4178.289690752579;4209.200251952119;4230.725002874803;4232;928.1600151862575;935.6257497001254;942.0485309837114;946.914777670013;946.9763255918657;552.4116815591383;608.869093144976;643.2923783837834;664.8637029408959;680.3400701319712;11106.222564420576;12237.082218833622;12443.440045197987;12468.314242716557;12479.188852724545;647.498188903594;649.2371463530333;656.2584279763931;664.8902931016424;681.4718593090395;5685.602007859493;5792.304923409218;5797.420257974253;5831;5919.289685237799;144441;148977;150753.24623377033;151919.24040379727;153612;125365.74634253723;125552.37223814006;127679;130729.34635326763;131606;81444;104059.95940040654;108342;110405.31309676435;110754;10768.569257229623;11011.44942752843;11253.52422467056;11527.654170374566;11917.821232681343;40314.19371612493;44761.55238447574;46312.53687463082;47388;47477.287840681725;75599.62200188964;76683;78295.60852195931;78926;80450.19549804661;16924.238409271366;17299.13504324804;17643.55891102733;17753;17835.019073950538;18759.71860422122;18883.527911802314;19115.617687645892;19120.521986950436;19124.426267212006;16031.278592463133;17171.227294771514;17974.10129493494;18009.459716208534;18427;16738.58153546171;17317.30730770755;17678.911605441892;18209.180586873357;18266.543336416697;14622.926885365505;16672.749908751135;16871.578210544834;17095.743563846303;17324.480265592054;11687;11962.880371196177;11989.64031079069;12082.577109801116;12088.697782555506;11181.273217240854;13478.932605336911;13485.258310793;13488.595342139752;13573.660658483617 20:42:03 INFO - PID 1904 | __end_tp_report 20:42:03 INFO - PID 1904 | __start_cc_report 20:42:03 INFO - PID 1904 | _x_x_mozilla_cycle_collect,398 20:42:03 INFO - PID 1904 | __end_cc_report 20:42:03 INFO - PID 1904 | __startTimestamp1495770123458__endTimestamp 20:42:03 INFO - PID 1904 | 20:42:03 INFO - PID 1904 | ------- Summary: start ------- 20:42:03 INFO - PID 1904 | Number of tests: 6 20:42:03 INFO - PID 1904 | 20:42:03 INFO - PID 1904 | [#0] dojo.html Cycles:165 Average:6158.84 Median:6405.39 stddev:788.25 (12.3%) stddev-sans-first:761.41 20:42:03 INFO - PID 1904 | Values: 3446.4 3576.2 3603.5 3605.9 3607.2 6099.3 6114.1 6205.1 6216.5 6230.8 6149.8 6169.6 6173.7 6255.6 6288.2 5176.6 5370.4 5401.6 5405.5 5466.0 3941.5 4799.2 5463.6 5531.7 5568.5 4899.0 4902.0 5326.9 5408.8 5448.2 4469.0 5451.3 5462.5 5462.7 5486.0 5355.9 5390.1 5436.2 5474.2 5479.2 5325.3 5470.4 6086.9 6179.4 6203.1 6640.4 6721.5 6730.4 6733.7 6746.1 6810.7 6840.6 6866.1 6885.9 6900.2 6258.7 6347.1 6351.2 6363.9 6367.6 6796.2 6869.7 6942.7 6959.7 7004.3 6404.7 6457.8 6461.6 6508.4 6624.2 6689.5 6782.5 6788.4 6789.8 6817.9 6687.9 6706.3 6711.2 6734.8 6763.4 6518.2 6583.6 6599.9 6701.0 6701.3 5359.4 5407.0 5416.2 5422.2 5442.4 4709.8 4745.8 4764.9 4777.4 4783.9 6241.1 6275.7 6280.2 6296.4 6301.1 6780.6 6823.0 6831.6 6832.5 6850.0 6339.3 6352.9 6378.5 6381.2 6393.0 5778.6 5847.9 5856.2 5900.2 5976.9 5752.2 6861.3 6963.5 6996.8 7005.1 6781.0 6824.0 6860.3 6860.8 6878.1 6748.5 6939.4 6983.5 6994.0 7003.2 6494.7 6579.8 6642.8 6660.5 6695.1 6789.9 6824.5 6834.6 6836.4 6861.9 6378.5 6436.1 6537.2 6617.0 6636.9 6534.7 6595.1 6641.5 6641.9 6661.1 6483.3 6520.7 6526.4 6556.8 6561.0 5516.4 6405.4 6487.5 6666.9 6730.9 5507.7 6366.5 6396.5 6411.9 6420.0 20:42:03 INFO - PID 1904 | 20:42:03 INFO - PID 1904 | [#1] ext.html Cycles:180 Average:42700.16 Median:31757.52 stddev:71592.16 (225.4%) stddev-sans-first:71723.46 20:42:03 INFO - PID 1904 | Values: 676.7 731.8 824.3 1056.4 1220.7 31798.7 40086.8 40104.2 40137.0 40212.0 39965.8 40115.0 40440.0 40506.6 40585.6 7791.2 8239.8 8438.5 8458.9 8475.5 8409.9 8418.0 8519.7 8544.9 8546.1 8461.5 8481.0 8533.6 8541.7 8564.0 8368.5 8467.7 8518.7 8526.7 8531.4 8168.8 8242.1 8269.2 8299.3 8339.0 37503.0 37576.1 37710.6 37779.4 37809.6 3508.4 3536.0 3539.5 3543.2 3556.1 29837.0 30106.2 30125.2 30220.8 30313.7 3064.4 3086.6 3099.7 3104.0 3115.2 233307.0 233566.0 277300.3 296513.0 297516.0 47299.8 47417.1 47443.0 47490.1 47555.8 50344.2 50486.5 50606.5 50642.2 50680.0 13669.8 13881.2 13928.2 13959.2 13969.0 11493.0 11499.8 11526.3 11535.6 11564.0 13234.9 13241.5 13291.7 13316.3 13378.2 6067.0 6067.9 6076.7 6104.6 6133.9 962.6 995.5 997.1 1015.1 1034.0 24020.8 24127.4 24160.8 24202.9 24267.5 1037.8 1084.9 1115.9 1117.6 1122.1 12336.6 12336.9 12358.7 12374.9 12387.4 302584.0 303466.0 304150.0 304719.0 305355.0 53598.0 53893.7 53953.7 54104.6 54107.5 1488.2 1499.7 1532.3 1532.3 1548.3 18727.6 23616.1 23673.9 23801.8 23916.5 38199.2 38228.0 38325.6 38393.4 38467.4 215852.6 241338.0 252365.0 254714.0 256387.0 29386.0 30872.9 34053.0 34720.0 35389.0 36722.7 37153.4 43281.7 44703.0 45100.5 27229.9 29469.4 29992.9 30544.2 31342.7 30203.0 30510.0 30911.4 31456.7 31459.8 36158.5 36708.8 37288.6 37313.9 37532.4 30536.4 30826.5 31334.7 31379.6 31716.4 22914.4 24148.0 24662.8 24707.6 24845.0 20:42:03 INFO - PID 1904 | 20:42:03 INFO - PID 1904 | [#2] jquery.html Cycles:180 Average:36516.45 Median:12031.30 stddev:89528.14 (744.1%) stddev-sans-first:89738.22 20:42:03 INFO - PID 1904 | Values: 398.3 425.8 434.6 442.6 447.3 19354.3 21231.8 21885.7 22024.8 22078.8 21218.9 21273.0 21319.0 21390.6 21541.9 5563.8 7146.4 7153.4 7160.9 7168.8 6071.7 6661.0 6923.7 6937.9 7023.3 6876.2 6895.2 6907.6 6909.4 6915.1 6659.2 6884.9 6982.7 7021.0 7041.3 6814.7 6861.7 6863.7 6866.3 6869.7 29278.7 29701.9 29722.0 29784.6 29851.6 2147.8 2157.5 2176.9 2307.0 2311.7 18920.0 18962.4 19057.0 19193.6 19432.9 2270.9 2306.7 2330.0 2334.0 2348.5 163450.0 165428.2 167194.0 167451.0 169447.0 23451.5 25667.2 25718.9 25742.0 25758.5 40760.6 41374.8 41379.0 41494.4 41539.2 10553.1 10843.0 10952.8 10968.5 11134.6 8541.8 8577.9 8591.8 8695.1 8704.2 10174.2 10668.4 11526.7 11761.4 11798.8 4724.7 4844.7 4864.2 4900.6 4921.0 771.8 800.5 884.0 924.1 926.8 16210.4 19287.7 20394.1 20496.4 20865.3 877.9 982.9 992.9 1000.1 1008.3 11226.1 11288.5 11307.5 11307.6 11334.8 508564.0 524435.0 531109.0 532369.0 534894.0 119728.4 119972.0 120461.8 121249.0 122635.0 1141.7 1153.2 1176.0 1176.1 1183.6 18105.0 18793.8 18853.2 18878.9 19250.2 55951.4 55961.0 56179.0 56682.0 57413.4 64215.1 74582.0 74695.6 75082.6 75850.9 10857.8 13075.7 13319.4 13491.1 13495.0 13296.7 13389.6 13803.4 14341.3 14359.8 11609.2 11789.4 11902.0 12038.2 12137.8 11155.9 11162.2 11333.0 11455.9 11474.1 11912.9 12159.1 12170.9 12181.7 12239.6 12713.0 12725.3 12746.0 13029.4 13059.5 9006.4 11411.4 12037.6 12253.9 12350.6 20:42:03 INFO - PID 1904 | 20:42:03 INFO - PID 1904 | [#3] mootools.html Cycles:180 Average:7051.81 Median:3963.17 stddev:4402.39 (111.1%) stddev-sans-first:4392.51 20:42:03 INFO - PID 1904 | Values: 1164.8 1412.9 1414.2 1418.1 1419.5 11704.0 11723.4 12262.8 12300.3 12366.4 11982.7 12088.5 12194.9 12258.6 12315.3 2567.0 2591.6 2591.9 2612.2 2617.3 2533.5 2574.5 2577.0 2577.4 2579.2 2530.4 2531.3 2546.1 2556.2 2562.4 2305.4 2337.4 2518.5 2525.2 2531.2 2490.3 2498.5 2513.0 2520.6 2604.9 9693.8 11770.4 11941.9 12017.5 12039.2 6426.1 6672.6 6678.7 6691.2 6704.7 4092.8 4240.5 4313.0 4332.5 4344.6 1241.1 1243.4 1248.5 1249.8 1250.2 13271.5 15353.8 15763.4 16593.8 16624.4 5356.2 5401.1 5608.7 5610.0 5619.6 11845.5 11918.9 11993.7 12035.1 12059.6 6314.5 6369.1 6448.0 6459.8 6469.9 5842.8 6077.1 6113.8 6124.4 6159.4 4284.7 4373.8 4414.9 4442.3 4522.8 1254.7 1276.9 1277.7 1292.6 1293.2 772.8 773.6 785.3 789.9 792.3 6789.8 7009.3 7069.9 7107.6 7128.0 895.9 941.1 959.5 963.4 964.6 3608.9 3833.5 4168.8 4414.4 4529.0 14527.2 14766.3 14838.0 14993.2 15048.4 14224.4 14568.7 14717.4 14794.6 14982.1 8147.4 8324.3 8405.7 8457.7 8604.3 6376.7 6567.3 6772.6 6962.4 7157.3 12730.5 12826.3 12982.2 13121.7 13138.0 10647.5 12871.5 13258.9 13302.9 13341.7 7685.7 7961.9 8014.1 9583.9 9635.0 10615.8 10922.1 10923.5 10935.6 11028.4 7451.7 9345.2 9365.0 9379.4 9430.3 8086.2 9120.5 9250.3 9268.0 9383.0 8203.6 8311.6 8373.7 8389.5 8397.0 6114.5 6131.7 6198.1 6257.0 6322.3 5506.7 5582.2 5635.0 5638.1 5645.4 20:42:03 INFO - PID 1904 | 20:42:03 INFO - PID 1904 | [#4] prototype.html Cycles:180 Average:5300.92 Median:5951.33 stddev:1652.45 (27.8%) stddev-sans-first:1614.76 20:42:03 INFO - PID 1904 | Values: 349.5 383.5 387.6 391.7 400.9 6112.3 6117.5 6131.3 6142.0 6149.4 4580.5 5831.6 5963.1 5993.5 6018.7 3964.2 4543.6 4552.9 4554.3 4559.9 4496.0 4518.5 4528.5 4536.4 4551.6 3810.1 4379.3 4493.7 4514.1 4516.3 4297.8 4375.7 4467.2 4480.0 4487.7 3979.6 3980.2 3994.7 4006.7 4017.1 5809.5 5871.6 5886.5 5920.5 5935.2 5340.0 5432.0 5457.2 5470.3 5475.6 5384.2 6178.4 6435.9 6473.7 6512.5 4377.3 4538.3 4547.2 4595.4 4609.6 5939.5 6603.9 6799.6 6809.4 6823.5 4727.8 4880.9 4900.1 4914.7 4924.9 4143.8 4385.0 4478.8 4749.1 5007.2 4671.3 5092.1 5330.6 5383.9 5403.8 5804.0 6634.8 6653.2 6690.8 6724.7 1462.5 1505.4 1509.5 1519.3 1519.6 905.3 923.6 938.8 952.0 1010.2 5134.0 5251.3 6058.9 6104.3 6119.3 6289.8 6766.7 6781.2 6803.9 6821.9 5096.9 5656.8 5917.5 6032.3 6131.2 3358.4 3557.5 3558.0 3565.7 3571.7 6003.5 6604.0 6906.9 6972.4 6998.3 5806.5 5977.8 6992.8 6997.9 7052.0 5375.9 5576.9 6587.4 6761.0 6936.3 6790.5 6799.7 6818.6 6820.9 6825.0 5685.9 6162.6 6419.9 6662.0 6765.4 6350.6 6440.9 7054.5 7077.6 7092.8 3859.6 4276.4 4355.6 4410.3 4420.6 6833.5 6850.7 6850.9 6860.4 6901.0 6522.3 6636.7 6650.5 6650.8 6651.2 6559.6 6600.0 6627.4 6662.8 6682.5 6022.7 6320.8 6504.6 6544.3 6550.5 5512.8 6151.8 6572.4 6628.9 6640.6 6466.8 6485.5 6486.6 6490.6 6497.3 20:42:03 INFO - PID 1904 | 20:42:03 INFO - PID 1904 | [#5] yui.html Cycles:180 Average:25713.90 Median:17912.42 stddev:39226.40 (219.0%) stddev-sans-first:39291.97 20:42:03 INFO - PID 1904 | Values: 837.5 842.6 844.9 868.9 882.9 14695.9 15126.2 15716.6 16891.6 17381.1 17373.5 17877.5 17925.5 17996.9 18003.4 944.1 963.1 967.9 983.5 987.7 853.0 938.7 1005.1 1005.5 1006.9 931.7 1043.2 1051.7 1052.0 1052.2 1019.7 1031.1 1031.7 1034.9 1037.8 753.8 766.2 766.3 767.4 768.1 17245.5 17369.9 17388.6 17436.5 17602.7 17819.5 17858.3 17899.4 18323.5 18427.5 14669.9 14991.9 15010.9 15017.3 15037.8 3514.9 3533.1 3544.5 3547.6 3672.6 111634.0 120803.4 135753.0 135776.3 136518.3 13242.6 13560.7 13726.2 13764.3 14060.9 25273.1 25831.5 25877.4 26064.9 26324.2 6835.6 7036.4 7580.8 7873.2 8008.5 3889.4 3976.8 3995.3 4015.1 4018.7 4147.5 4178.3 4209.2 4230.7 4232.0 928.2 935.6 942.0 946.9 947.0 552.4 608.9 643.3 664.9 680.3 11106.2 12237.1 12443.4 12468.3 12479.2 647.5 649.2 656.3 664.9 681.5 5685.6 5792.3 5797.4 5831.0 5919.3 144441.0 148977.0 150753.2 151919.2 153612.0 125365.7 125552.4 127679.0 130729.3 131606.0 81444.0 104060.0 108342.0 110405.3 110754.0 10768.6 11011.4 11253.5 11527.7 11917.8 40314.2 44761.6 46312.5 47388.0 47477.3 75599.6 76683.0 78295.6 78926.0 80450.2 16924.2 17299.1 17643.6 17753.0 17835.0 18759.7 18883.5 19115.6 19120.5 19124.4 16031.3 17171.2 17974.1 18009.5 18427.0 16738.6 17317.3 17678.9 18209.2 18266.5 14622.9 16672.7 16871.6 17095.7 17324.5 11687.0 11962.9 11989.6 12082.6 12088.7 11181.3 13478.9 13485.3 13488.6 13573.7 20:42:03 INFO - PID 1904 | -------- Summary: end -------- 20:42:03 INFO - PID 1904 | 20:42:03 INFO - TEST-INFO | 1904: exit 0 20:42:04 INFO - TEST-OK | dromaeo_css | took 1142214ms 20:42:04 INFO - TEST-START | kraken 20:42:04 INFO - operating with platform_type : mac_ 20:42:04 INFO - Initialising browser for kraken test... 20:42:04 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49361/getInfo.html -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpX6OG60/profile 20:42:04 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49361/getInfo.html -foreground) 20:42:37 INFO - TEST-INFO | 1913: killed by SIGTERM 20:42:37 INFO - Browser initialized. 20:42:37 INFO - Running cycle 1/1 for kraken test... 20:42:37 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpX6OG60/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:42:39 INFO - PID 1917 | 2017-05-25 20:42:39.247 plugin-container[1918:12482] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 20:42:39 INFO - PID 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:42:39 INFO - PID 1917 | 2017-05-25 20:42:39.247 plugin-container[1918:12482] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e03, name = 'com.apple.CFPasteboardClient' 20:42:39 INFO - PID 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:42:39 INFO - PID 1917 | 2017-05-25 20:42:39.247 plugin-container[1918:12482] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:42:40 INFO - PID 1917 | 2017-05-25 20:42:40.023 plugin-container[1919:12527] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 20:42:40 INFO - PID 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:42:40 INFO - PID 1917 | 2017-05-25 20:42:40.024 plugin-container[1919:12527] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x490b, name = 'com.apple.CFPasteboardClient' 20:42:40 INFO - PID 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:42:40 INFO - PID 1917 | 2017-05-25 20:42:40.024 plugin-container[1919:12527] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:42:49 INFO - PID 1917 | RSS: Main: 243056640 20:42:49 INFO - PID 1917 | 20:42:51 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49361/tests/kraken/driver.html?testName=audio-beat-detection) 20:42:52 INFO - PID 1917 | RSS: Main: 257175552 20:42:52 INFO - PID 1917 | 20:42:55 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49361/tests/kraken/driver.html?testName=audio-dft) 20:42:55 INFO - PID 1917 | RSS: Main: 266215424 20:42:55 INFO - PID 1917 | 20:42:59 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49361/tests/kraken/driver.html?testName=audio-fft) 20:43:00 INFO - PID 1917 | RSS: Main: 269205504 20:43:00 INFO - PID 1917 | 20:43:01 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49361/tests/kraken/driver.html?testName=audio-oscillator) 20:43:02 INFO - PID 1917 | RSS: Main: 271548416 20:43:02 INFO - PID 1917 | 20:43:04 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49361/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:43:05 INFO - PID 1917 | RSS: Main: 272011264 20:43:05 INFO - PID 1917 | 20:43:07 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49361/tests/kraken/driver.html?testName=imaging-darkroom) 20:43:08 INFO - PID 1917 | RSS: Main: 276770816 20:43:08 INFO - PID 1917 | 20:43:11 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49361/tests/kraken/driver.html?testName=imaging-desaturate) 20:43:12 INFO - PID 1917 | RSS: Main: 279703552 20:43:12 INFO - PID 1917 | 20:43:15 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49361/tests/kraken/driver.html?testName=json-parse-financial) 20:43:15 INFO - PID 1917 | RSS: Main: 279224320 20:43:15 INFO - PID 1917 | 20:43:17 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49361/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:43:17 INFO - PID 1917 | RSS: Main: 278872064 20:43:17 INFO - PID 1917 | 20:43:19 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:43:20 INFO - PID 1917 | RSS: Main: 278712320 20:43:20 INFO - PID 1917 | 20:43:22 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:43:23 INFO - PID 1917 | RSS: Main: 278663168 20:43:23 INFO - PID 1917 | 20:43:25 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:43:26 INFO - PID 1917 | RSS: Main: 278278144 20:43:26 INFO - PID 1917 | 20:43:29 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:43:30 INFO - PID 1917 | RSS: Main: 278511616 20:43:30 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49361/tests/kraken/driver.html?testName=ai-astar) 20:43:32 INFO - PID 1917 | RSS: Main: 280211456 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | __start_tp_report 20:43:32 INFO - PID 1917 | _x_x_mozilla_page_load 20:43:32 INFO - PID 1917 | _x_x_mozilla_page_load_details 20:43:32 INFO - PID 1917 | |i|pagename|runs| 20:43:32 INFO - PID 1917 | |0;ai-astar;101;114;116;115;114;116;115;114;116;114 20:43:32 INFO - PID 1917 | |1;audio-beat-detection;140;109;112;146;110;110;110;113;136;109 20:43:32 INFO - PID 1917 | |2;audio-dft;198;197;201;197;198;210;208;223;245;239 20:43:32 INFO - PID 1917 | |3;audio-fft;81;61;59;83;58;58;61;59;78;63 20:43:32 INFO - PID 1917 | |4;audio-oscillator;84;69;82;72;67;68;84;77;86;89 20:43:32 INFO - PID 1917 | |5;imaging-gaussian-blur;90;90;90;90;89;90;91;90;93;89 20:43:32 INFO - PID 1917 | |6;imaging-darkroom;97;98;97;98;96;96;96;97;97;96 20:43:32 INFO - PID 1917 | |7;imaging-desaturate;91;96;92;91;109;94;90;118;143;90 20:43:32 INFO - PID 1917 | |8;json-parse-financial;51;52;52;51;53;52;52;53;52;50 20:43:32 INFO - PID 1917 | |9;json-stringify-tinderbox;57;59;58;70;64;59;55;57;56;56 20:43:32 INFO - PID 1917 | |10;stanford-crypto-aes;84;85;89;87;88;86;89;89;124;90 20:43:32 INFO - PID 1917 | |11;stanford-crypto-ccm;136;139;136;164;157;134;139;140;146;147 20:43:32 INFO - PID 1917 | |12;stanford-crypto-pbkdf2;189;189;219;190;208;253;192;202;212;182 20:43:32 INFO - PID 1917 | |13;stanford-crypto-sha256-iterative;68;71;66;68;67;68;66;69;71;68 20:43:32 INFO - PID 1917 | __end_tp_report 20:43:32 INFO - PID 1917 | __start_cc_report 20:43:32 INFO - PID 1917 | _x_x_mozilla_cycle_collect,1058 20:43:32 INFO - PID 1917 | __end_cc_report 20:43:32 INFO - PID 1917 | __startTimestamp1495770212482__endTimestamp 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | ------- Summary: start ------- 20:43:32 INFO - PID 1917 | Number of tests: 14 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | [#0] ai-astar Cycles:10 Average:113.50 Median:115.00 stddev:4.48 (3.9%) stddev-sans-first:0.93 20:43:32 INFO - PID 1917 | Values: 101.0 114.0 116.0 115.0 114.0 116.0 115.0 114.0 116.0 114.0 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | [#1] audio-beat-detection Cycles:10 Average:119.50 Median:112.50 stddev:14.85 (13.2%) stddev-sans-first:13.77 20:43:32 INFO - PID 1917 | Values: 140.0 109.0 112.0 146.0 110.0 110.0 110.0 113.0 136.0 109.0 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | [#2] audio-dft Cycles:10 Average:211.60 Median:209.00 stddev:18.01 (8.6%) stddev-sans-first:18.42 20:43:32 INFO - PID 1917 | Values: 198.0 197.0 201.0 197.0 198.0 210.0 208.0 223.0 245.0 239.0 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | [#3] audio-fft Cycles:10 Average:66.10 Median:62.00 stddev:10.24 (16.5%) stddev-sans-first:9.33 20:43:32 INFO - PID 1917 | Values: 81.0 61.0 59.0 83.0 58.0 58.0 61.0 59.0 78.0 63.0 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:83.00 stddev:8.24 (9.9%) stddev-sans-first:8.43 20:43:32 INFO - PID 1917 | Values: 84.0 69.0 82.0 72.0 67.0 68.0 84.0 77.0 86.0 89.0 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | [#5] imaging-gaussian-blur Cycles:10 Average:90.20 Median:90.00 stddev:1.14 (1.3%) stddev-sans-first:1.20 20:43:32 INFO - PID 1917 | Values: 90.0 90.0 90.0 90.0 89.0 90.0 91.0 90.0 93.0 89.0 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | [#6] imaging-darkroom Cycles:10 Average:96.80 Median:97.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 20:43:32 INFO - PID 1917 | Values: 97.0 98.0 97.0 98.0 96.0 96.0 96.0 97.0 97.0 96.0 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | [#7] imaging-desaturate Cycles:10 Average:101.40 Median:91.00 stddev:17.36 (19.1%) stddev-sans-first:18.00 20:43:32 INFO - PID 1917 | Values: 91.0 96.0 92.0 91.0 109.0 94.0 90.0 118.0 143.0 90.0 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:0.92 (1.8%) stddev-sans-first:0.93 20:43:32 INFO - PID 1917 | Values: 51.0 52.0 52.0 51.0 53.0 52.0 52.0 53.0 52.0 50.0 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:58.50 stddev:4.58 (7.8%) stddev-sans-first:4.80 20:43:32 INFO - PID 1917 | Values: 57.0 59.0 58.0 70.0 64.0 59.0 55.0 57.0 56.0 56.0 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:88.50 stddev:11.72 (13.2%) stddev-sans-first:12.15 20:43:32 INFO - PID 1917 | Values: 84.0 85.0 89.0 87.0 88.0 86.0 89.0 89.0 124.0 90.0 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | [#11] stanford-crypto-ccm Cycles:10 Average:143.80 Median:143.00 stddev:9.86 (6.9%) stddev-sans-first:10.05 20:43:32 INFO - PID 1917 | Values: 136.0 139.0 136.0 164.0 157.0 134.0 139.0 140.0 146.0 147.0 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:203.60 Median:205.00 stddev:21.04 (10.3%) stddev-sans-first:21.64 20:43:32 INFO - PID 1917 | Values: 189.0 189.0 219.0 190.0 208.0 253.0 192.0 202.0 212.0 182.0 20:43:32 INFO - PID 1917 | 20:43:32 INFO - PID 1917 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:1.75 (2.6%) stddev-sans-first:1.86 20:43:32 INFO - PID 1917 | Values: 68.0 71.0 66.0 68.0 67.0 68.0 66.0 69.0 71.0 68.0 20:43:32 INFO - PID 1917 | -------- Summary: end -------- 20:43:32 INFO - PID 1917 | 20:43:33 INFO - TEST-INFO | 1917: exit 0 20:43:33 INFO - TEST-OK | kraken | took 89304ms 20:43:33 INFO - SUITE-END | took 1231s 20:43:33 INFO - Completed test suite (00:20:31) 20:43:33 INFO - javascript benchmark 20:43:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8087.480483387641, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3446.4313388290934, 3576.2311103112843, 3603.4955106285142, 3605.8737944171953, 3607.206414588791, 6099.268087829461, 6114.084479019522, 6205.069239614055, 6216.533759967998, 6230.750769969201, 6149.754009839601, 6169.5989760665525, 6173.660448675321, 6255.562110652245, 6288.213973253343, 5176.5858731301405, 5370.43610420906, 5401.621886467939, 5405.486478784509, 5465.988792073459, 3941.546722126949, 4799.208130658437, 5463.562914966813, 5531.668099913998, 5568.470995255444, 4899.0, 4901.975490122562, 5326.893462130735, 5408.756605952742, 5448.182772584105, 4469.0, 5451.318585176853, 5462.535684466825, 5462.7268636568015, 5486.0, 5355.919661205046, 5390.110631745796, 5436.238926550286, 5474.178873169017, 5479.232907392969, 5325.281087053237, 5470.398256191816, 6086.9391306087255, 6179.412955769195, 6203.131561581382, 6640.369164929372, 6721.462283017347, 6730.427913627347, 6733.730650773963, 6746.123004009496, 6810.693518791666, 6840.589564626139, 6866.141732283465, 6885.896711549331, 6900.2409734929115, 6258.74965001404, 6347.1114044033875, 6351.2060992375955, 6363.9045414318825, 6367.649779262092, 6796.218434880052, 6869.725210991604, 6942.652867356612, 6959.652017399109, 7004.299570042955, 6404.7117879695525, 6457.806265812033, 6461.64460954652, 6508.381703738138, 6624.205095388585, 6689.4982876284475, 6782.525223234326, 6788.355106264897, 6789.762358317534, 6817.863642727118, 6687.933120668828, 6706.295838936939, 6711.228208756055, 6734.797956061326, 6763.391294783493, 6518.152640156844, 6583.637899915455, 6599.868002639921, 6701.0, 6701.32986701326, 5359.437259087739, 5406.972965135228, 5416.187571864252, 5422.240886275925, 5442.374126975369, 4709.764511774398, 4745.786439610226, 4764.880877978078, 4777.355057067251, 4783.904321913542, 6241.126242326078, 6275.6862156891975, 6280.246370435577, 6296.433321001132, 6301.117843501914, 6780.559263647847, 6823.0, 6831.6242606656115, 6832.453403727789, 6850.0, 6339.2709838368255, 6352.904706429407, 6378.45783108441, 6381.202349706286, 6392.977123660192, 5778.566607504454, 5847.9415205848245, 5856.180134781134, 5900.1739756434135, 5976.910346344721, 5752.223449834302, 6861.348171923659, 6963.547369420971, 6996.790096297119, 7005.124359455068, 6781.0, 6824.044633751179, 6860.279670634711, 6860.828479288058, 6878.140232470942, 6748.527603067738, 6939.410150137295, 6983.511154219156, 6994.0, 7003.1596208453275, 6494.675266236574, 6579.769708060195, 6642.83392915181, 6660.500462465237, 6695.06269122333, 6789.93210067893, 6824.488163387667, 6834.555753875982, 6836.38472537474, 6861.862762744817, 6378.48972082223, 6436.131122298617, 6537.248216455168, 6617.0, 6636.933630663824, 6534.738610455719, 6595.142631457879, 6641.501887358564, 6641.900371494528, 6661.134052573135, 6483.319251478715, 6520.739170433129, 6526.379993900572, 6556.770513032021, 6561.0, 5516.448355164452, 6405.391487808651, 6487.481001519962, 6666.933330666825, 6730.899036514554, 5507.697076660662, 6366.5225108118, 6396.488280937607, 6411.903821442776, 6420.0048992406255], "value": 6104.168621578947, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.6853413162878, 731.8436869745799, 824.2952275804189, 1056.392574269795, 1220.7314390834013, 31798.68201317989, 40086.79956600216, 40104.197916041696, 40137.0, 40211.99470013251, 39965.80017099918, 40115.0, 40440.0, 40506.59493405065, 40585.59414405855, 7791.220877912206, 8239.79400514989, 8438.451500652438, 8458.87311690325, 8475.49147051179, 8409.873851892227, 8417.95791021044, 8519.701810436636, 8544.914550854473, 8546.05993340732, 8461.450005749606, 8481.0, 8533.573321333908, 8541.658333666677, 8563.957180214122, 8368.456050356708, 8467.66129354825, 8518.659253629847, 8526.701565445179, 8531.445456045369, 8168.836623267502, 8242.134575869508, 8269.21442462965, 8299.253067223919, 8339.041010283834, 37503.0, 37576.06059848498, 37710.62289377098, 37779.4333085004, 37809.62190378116, 3508.3509550733197, 3536.0, 3539.522164507784, 3543.2382037861726, 3556.0754203906913, 29837.0, 30106.24734381636, 30125.246868828235, 30220.8488957556, 30313.69686303131, 3064.4330798802293, 3086.5833112529745, 3099.6745341739243, 3103.9689603103907, 3115.190050586841, 233307.0, 233566.0, 277300.26997300313, 296513.0, 297516.0, 47299.76350118296, 47417.05165896663, 47443.0, 47490.05019899583, 47555.76222118867, 50344.24483632748, 50486.495135048914, 50606.49393506018, 50642.24036639454, 50680.0, 13669.794953075712, 13881.167129972033, 13928.233947133005, 13959.162450253018, 13969.0, 11493.0, 11499.77000459986, 11526.250793698382, 11535.596254131233, 11564.0, 13234.933825330812, 13241.470341186438, 13291.734165316639, 13316.267605281802, 13378.197308161542, 6067.0, 6067.939320606825, 6076.696165191723, 6104.572679912363, 6133.877322453526, 962.6149540183983, 995.5171741705267, 997.1275134257525, 1015.1320620869199, 1033.9844902326472, 24020.7597924022, 24127.396815079763, 24160.791960401908, 24202.87898560496, 24267.514649706907, 1037.8287582548794, 1084.8643919510062, 1115.9107271418268, 1117.620009196877, 1122.147168152194, 12336.62990110298, 12336.876631233572, 12358.691032724253, 12374.938125309316, 12387.442565084593, 302584.0, 303466.0, 304150.0, 304719.0, 305355.0, 53598.0, 53893.73053134788, 53953.73023134938, 54104.64738381493, 54107.45892541103, 1488.2409970914912, 1499.677569322601, 1532.256855425118, 1532.3487517804888, 1548.3032635314153, 18727.625447491246, 23616.055357785495, 23673.934672940104, 23801.76198238065, 23916.52166956686, 38199.2360152801, 38228.04429889275, 38325.61674383332, 38393.4240986391, 38467.42298865463, 215852.60368490286, 241338.0, 252365.0, 254714.0, 256387.0, 29386.0, 30872.91944781922, 34052.978410647724, 34720.0, 35389.0, 36722.7147049852, 37153.442698360086, 43281.701548953584, 44703.0, 45100.548994510944, 27229.86385068062, 29469.410611788073, 29992.850035750555, 30544.236394090327, 31342.68657313398, 30203.0, 30510.0, 30911.381772364875, 31456.685433145376, 31459.84270078635, 36158.457623135146, 36708.81645591755, 37288.627113728515, 37313.88058358253, 37532.437013445364, 30536.389272214874, 30826.537601936438, 31334.686653133176, 31379.587918543264, 31716.36567268688, 22914.42713932165, 24148.0, 24662.753372466046, 24707.62938555887, 24845.0], "value": 17297.456477582702, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.33279257244106, 425.7907542579075, 434.6479351725103, 442.63261492960845, 447.2932766229358, 19354.32259870903, 21231.787682123173, 21885.67171492425, 22024.779752202474, 22078.77921220787, 21218.89390553045, 21273.0, 21319.0, 21390.57218855622, 21541.892290538526, 5563.777448902049, 7146.4282857371545, 7153.427725781951, 7160.85678286434, 7168.784936451915, 6071.696415179245, 6660.966695166541, 6923.688434020456, 6937.861242775141, 7023.2976702329615, 6876.243613202544, 6895.172579290492, 6907.620080895549, 6909.412699920513, 6915.1010368652005, 6659.234188068385, 6884.931150688504, 6982.650867456658, 7020.981957616117, 7041.260667629927, 6814.727410903558, 6861.691223894937, 6863.725450981955, 6866.279040700704, 6869.656517174171, 29278.70721292781, 29701.851490742407, 29722.0, 29784.553231701542, 29851.552226716616, 2147.818699715166, 2157.5037741319584, 2176.9346919592435, 2307.031046960264, 2311.664808602743, 18920.0, 18962.43112706621, 19057.0, 19193.616127677367, 19432.931188784612, 2270.8751018693965, 2306.723193216808, 2329.9650505242435, 2334.031376978552, 2348.5420343033115, 163450.0, 165428.17285913613, 167194.0, 167451.0, 169447.0, 23451.530969380517, 25667.229983100537, 25718.87140564285, 25741.970321186942, 25758.48483030329, 40760.59239407568, 41374.75875723733, 41379.0, 41494.37758433626, 41539.1692166161, 10553.102986246102, 10843.024127828534, 10952.780944381067, 10968.506417211245, 11134.610288639858, 8541.829163416696, 8577.871331930026, 8591.785205369792, 8695.130486951255, 8704.216620504185, 10174.186065114773, 10668.413237272025, 11526.711832204095, 11761.353410522534, 11798.764024719458, 4724.732128578302, 4844.709317440965, 4864.246041863516, 4900.583450406684, 4920.950790492049, 771.7530390275058, 800.4516905919347, 883.9867401988976, 924.089771574998, 926.7636752628036, 16210.351585936667, 19287.710684339745, 20394.082266298054, 20496.38510844677, 20865.269715559883, 877.9078196789372, 982.9384264993789, 992.8560358748085, 1000.1448761308976, 1008.2589296866797, 11226.101911847032, 11288.492017859051, 11307.49116289769, 11307.643082829949, 11334.829977550344, 508564.0, 524435.0, 531109.0, 532369.0, 534894.0, 119728.40135799265, 119972.0, 120461.79538204681, 121249.0, 122635.0, 1141.657502749172, 1153.1812413186565, 1175.976480470403, 1176.053277111933, 1183.6094088950626, 18105.004224767763, 18793.812061879205, 18853.15160817794, 18878.905605471886, 19250.22999080021, 55951.44048559625, 55961.0, 56179.0, 56682.0, 57413.425865740806, 64215.07354779364, 74582.0, 74695.62652186705, 75082.62458687671, 75850.86223706539, 10857.83713244286, 13075.738485230433, 13319.400626972003, 13491.05562610608, 13495.0, 13296.670332966625, 13389.598312050653, 13803.447862085404, 14341.282935853375, 14359.784603230752, 11609.187356884937, 11789.410529473664, 11902.0, 12038.157328986887, 12137.817932731192, 11155.944220278847, 11162.218644694793, 11333.036691881283, 11455.885441145481, 11474.082073434272, 11912.880871191177, 12159.148859580098, 12170.939145304217, 12181.69545761363, 12239.571614993789, 12713.0, 12725.300108493751, 12746.0, 13029.413676384775, 13059.542915997894, 9006.414583052081, 11411.37237451915, 12037.638870833889, 12253.938730306292, 12350.62948111558], "value": 11760.776703828935, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1164.813629819229, 1412.8657777511132, 1414.2009764483066, 1418.0995068131733, 1419.5031738891387, 11704.005159561437, 11723.413829308543, 12262.754744905096, 12300.323482208474, 12366.381680915942, 11982.70043248917, 12088.456019479123, 12194.878051219484, 12258.57095001675, 12315.322657253848, 2566.9743302567017, 2591.5594348960626, 2591.857447840368, 2612.242449689588, 2617.2802479318243, 2533.4679717259396, 2574.5365834149848, 2577.0336123953516, 2577.3685447065495, 2579.1617724239695, 2530.3800568860656, 2531.291238453236, 2546.1343143331264, 2556.2331300609835, 2562.385027593383, 2305.3545007397956, 2337.3572267626455, 2518.4711210645787, 2525.1666949906485, 2531.2026711585977, 2490.3151633300804, 2498.5127900059315, 2512.987435062813, 2520.609305557623, 2604.882780274892, 9693.757656058584, 11770.352630605314, 11941.94029029858, 12017.459214335377, 12039.157258991874, 6426.132472116251, 6672.633005184713, 6678.732850685967, 6691.230508491489, 6704.664766761691, 4092.8362865485346, 4240.533541310453, 4312.978435107836, 4332.458442694663, 4344.56554344563, 1241.1126044877865, 1243.3783108445778, 1248.5130798988403, 1249.8312727781724, 1250.1686378558247, 13271.535496257584, 15353.769693454607, 15763.448279310283, 16593.83406165935, 16624.418145364976, 5356.196570514454, 5401.054815407288, 5608.719564021782, 5610.0, 5619.550435965195, 11845.466953987092, 11918.854606170973, 11993.700157495958, 12035.097367697457, 12059.638210853689, 6314.463270622048, 6369.076483909807, 6447.967760161169, 6459.773907913294, 6469.870602587922, 5842.795502157489, 6077.058056001327, 6113.755449782047, 6124.387561243929, 6159.384061593894, 4284.700070995001, 4373.825046998148, 4414.867553973386, 4442.3336499524685, 4522.841700540465, 1254.7176885200756, 1276.9297688627214, 1277.7444511097817, 1292.6315999939973, 1293.2111412038648, 772.818387678895, 773.5552057566922, 785.3050050705167, 789.8973133492609, 792.2592376128339, 6789.830254243585, 7009.2990700929495, 7069.92930070703, 7107.609081500567, 7128.0, 895.91936725695, 941.0730430525927, 959.5346257065318, 963.4075043848123, 964.609333220033, 3608.891733248007, 3833.50164478616, 4168.785965752117, 4414.448193975753, 4529.048899731028, 14527.201003944672, 14766.261686915826, 14838.0, 14993.175375354242, 15048.398064077533, 14224.359903804354, 14568.708625827425, 14717.411303547738, 14794.556163315117, 14982.10107393538, 8147.388945829087, 8324.25081742634, 8405.747827565183, 8457.746267611981, 8604.268637165786, 6376.744930202925, 6567.343265673395, 6772.5936443813525, 6962.373386395248, 7157.320054594805, 12730.490780369051, 12826.294553799258, 12982.15615984958, 13121.737565248832, 13138.014648901562, 10647.520861561094, 12871.549495767604, 13258.933705331412, 13302.866971330162, 13341.733165336833, 7685.654145563575, 7961.880571791544, 8014.078380986028, 9583.856242156235, 9635.036496350309, 10615.787684246427, 10922.126229901747, 10923.453827308444, 10935.617253396093, 11028.44857757125, 7451.664675089525, 9345.15893569582, 9365.0, 9379.390339627902, 9430.292728045286, 8086.231807978233, 9120.543972801466, 9250.306227033134, 9268.0, 9383.01478344764, 8203.630836612247, 8311.625976831177, 8373.66505339804, 8389.538575378412, 8397.0, 6114.541409394402, 6131.662758548323, 6198.101275315144, 6257.0, 6322.2729386119345, 5506.697131657797, 5582.246396736388, 5634.985702573576, 5638.126090455986, 5645.4072322405555], "value": 5363.926405819913, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.5490816846267, 383.45357865042325, 387.63755888244475, 391.6670829794674, 400.9498812648419, 6112.29708583513, 6117.54118441117, 6131.294901086377, 6141.969290153543, 6149.3543177966285, 4580.496145424001, 5831.620944638596, 5963.135345374929, 5993.490553302963, 6018.699065046752, 3964.2071585682834, 4543.636509079266, 4552.90894182117, 4554.2713165893465, 4559.908801823962, 4495.955040449587, 4518.502964673883, 4528.524504926984, 4536.432945881765, 4551.634509647092, 3810.1427178884805, 4379.34309853523, 4493.73037617744, 4514.119746649389, 4516.254817955033, 4297.806598703035, 4375.687293811844, 4467.17357288901, 4479.9552004479865, 4487.6634252431195, 3979.6418322350837, 3980.20395920817, 3994.720369574131, 4006.6794656427414, 4017.0760725033115, 5809.477147056743, 5871.5908182036, 5886.528367908023, 5920.467157955804, 5935.228420305376, 5340.0, 5431.972840135774, 5457.181422786574, 5470.316210473691, 5475.589330800166, 5384.24620553119, 6178.41305076017, 6435.938070218408, 6473.74105035798, 6512.479001679854, 4377.299632058855, 4538.273876179828, 4547.226971414834, 4595.402597662316, 4609.585137337655, 5939.515665672389, 6603.933960660428, 6799.5920244785475, 6809.353111454404, 6823.454123670094, 4727.763611819464, 4880.853574392774, 4900.117978763857, 4914.655974081851, 4924.852254432372, 4143.751374917515, 4385.035292235703, 4478.776061196927, 4749.050189962021, 5007.172382080515, 4671.322658214608, 5092.083424983539, 5330.626856120034, 5383.873370018899, 5403.83788486346, 5803.970980145156, 6634.83412914681, 6653.234877988996, 6690.765823196165, 6724.697388617524, 1462.495439073518, 1505.3526983397026, 1509.539590424909, 1519.3239008641049, 1519.6352875309847, 905.3119629081945, 923.5520772425368, 938.7559234598917, 951.9619215231452, 1010.1867996263079, 5133.9743301284, 5251.291075704808, 6058.878822423615, 6104.298005729398, 6119.326874043852, 6289.811305660837, 6766.748151591972, 6781.152355955505, 6803.897941530881, 6821.863562728718, 5096.872578185501, 5656.783791484768, 5917.497012753878, 6032.306284777307, 6131.202943617302, 3358.404757740103, 3557.537520122419, 3558.0327934353527, 3565.6969157621375, 3571.6964058054837, 6003.519718422515, 6604.0093985902495, 6906.862308294013, 6972.396348839795, 6998.300169983063, 5806.535477161817, 5977.760889564455, 6992.755253566101, 6997.895031574531, 7052.0, 5375.892482150413, 5576.860578485489, 6587.440067594308, 6760.966195169091, 6936.34104760047, 6790.456763458813, 6799.660016999229, 6818.590884546943, 6820.93179068203, 6824.962891513229, 5685.914711279417, 6162.630242185483, 6419.871602568016, 6661.966690166712, 6765.376309685738, 6350.618962862243, 6440.937245354464, 7054.541454805421, 7077.610731409822, 7092.751753688596, 3859.571958375419, 4276.3799249109325, 4355.586219309161, 4410.316400957857, 4420.58004489573, 6833.487488438486, 6850.725970961105, 6850.903855383314, 6860.4168645663685, 6900.965495172492, 6522.34776522344, 6636.7345306189145, 6650.46796256308, 6650.767223147167, 6651.208810977696, 6559.572958005569, 6600.0, 6627.37039981201, 6662.766803161866, 6682.498812589172, 6022.7289771959495, 6320.841978950563, 6504.60972341661, 6544.28012918569, 6550.541462097607, 5512.80705175317, 6151.815445536641, 6572.441342485942, 6628.900566491603, 6640.601563906181, 6466.838329041811, 6485.481161506974, 6486.578372405778, 6490.643014634241, 6497.25281592604], "value": 4772.701476365297, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [837.5351679817699, 842.5618678287294, 844.8648216285395, 868.882700835387, 882.8587426011835, 14695.853041469582, 15126.168060756654, 15716.607084822885, 16891.577710557227, 17381.130943452823, 17373.478795636118, 17877.463676089737, 17925.462236132906, 17996.910015449936, 18003.369882054132, 944.0700909604052, 963.1476143612917, 967.908048735369, 983.4640121133999, 987.6543209876558, 852.9744107676748, 938.6949241496541, 1005.1255407795192, 1005.492226425654, 1006.8993100689945, 931.7111695374422, 1043.1811028342715, 1051.6582110814015, 1052.0373857919994, 1052.2476429353012, 1019.6686077024997, 1031.1441503552035, 1031.6750223679555, 1034.8654674892252, 1037.7976294622551, 753.773867839646, 766.203148725325, 766.2758693035084, 767.3745897093906, 768.0936494935974, 17245.482635520955, 17369.913150434295, 17388.56528586783, 17436.47690569285, 17602.735958960628, 17819.46541603754, 17858.28566857324, 17899.373521926802, 18323.54191145232, 18427.539311517186, 14669.853301466956, 14991.850081499155, 15010.924945375204, 15017.324220410108, 15037.774433383507, 3514.929701405983, 3533.116720819795, 3544.4860495228304, 3547.6274991126074, 3672.559292884844, 111634.0, 120803.3959830204, 135753.0, 135776.34907462067, 136518.31740841232, 13242.602721918358, 13560.728785424237, 13726.176429414067, 13764.31178441094, 14060.929695351459, 25273.115440959846, 25831.483370332488, 25877.353066173495, 26064.86967565188, 26324.21027369182, 6835.58986460814, 7036.401905838061, 7580.848383032308, 7873.212678732196, 8008.479448835807, 3889.358255887747, 3976.76139431635, 3995.32079546472, 4015.056461731491, 4018.678505719594, 4147.543770185277, 4178.289690752579, 4209.200251952119, 4230.725002874803, 4232.0, 928.1600151862575, 935.6257497001254, 942.0485309837114, 946.914777670013, 946.9763255918657, 552.4116815591383, 608.869093144976, 643.2923783837834, 664.8637029408959, 680.3400701319712, 11106.222564420576, 12237.082218833622, 12443.440045197987, 12468.314242716557, 12479.188852724545, 647.498188903594, 649.2371463530333, 656.2584279763931, 664.8902931016424, 681.4718593090395, 5685.602007859493, 5792.304923409218, 5797.420257974253, 5831.0, 5919.289685237799, 144441.0, 148977.0, 150753.24623377033, 151919.24040379727, 153612.0, 125365.74634253723, 125552.37223814006, 127679.0, 130729.34635326763, 131606.0, 81444.0, 104059.95940040654, 108342.0, 110405.31309676435, 110754.0, 10768.569257229623, 11011.44942752843, 11253.52422467056, 11527.654170374566, 11917.821232681343, 40314.19371612493, 44761.55238447574, 46312.53687463082, 47388.0, 47477.287840681725, 75599.62200188964, 76683.0, 78295.60852195931, 78926.0, 80450.19549804661, 16924.238409271366, 17299.13504324804, 17643.55891102733, 17753.0, 17835.019073950538, 18759.71860422122, 18883.527911802314, 19115.617687645892, 19120.521986950436, 19124.426267212006, 16031.278592463133, 17171.227294771514, 17974.10129493494, 18009.459716208534, 18427.0, 16738.58153546171, 17317.30730770755, 17678.911605441892, 18209.180586873357, 18266.543336416697, 14622.926885365505, 16672.749908751135, 16871.578210544834, 17095.743563846303, 17324.480265592054, 11687.0, 11962.880371196177, 11989.64031079069, 12082.577109801116, 12088.697782555506, 11181.273217240854, 13478.932605336911, 13485.258310793, 13488.595342139752, 13573.660658483617], "value": 8801.472542300122, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1494.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 116.0, 115.0, 114.0, 116.0, 115.0, 114.0, 116.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 109.0, 112.0, 146.0, 110.0, 110.0, 110.0, 113.0, 136.0, 109.0], "value": 119.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 197.0, 201.0, 197.0, 198.0, 210.0, 208.0, 223.0, 245.0, 239.0], "value": 211.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 59.0, 83.0, 58.0, 58.0, 61.0, 59.0, 78.0, 63.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 69.0, 82.0, 72.0, 67.0, 68.0, 84.0, 77.0, 86.0, 89.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 90.0, 90.0, 89.0, 90.0, 91.0, 90.0, 93.0, 89.0], "value": 90.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 98.0, 96.0, 96.0, 96.0, 97.0, 97.0, 96.0], "value": 96.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 92.0, 91.0, 109.0, 94.0, 90.0, 118.0, 143.0, 90.0], "value": 101.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 51.0, 53.0, 52.0, 52.0, 53.0, 52.0, 50.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 58.0, 70.0, 64.0, 59.0, 55.0, 57.0, 56.0, 56.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 89.0, 87.0, 88.0, 86.0, 89.0, 89.0, 124.0, 90.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 139.0, 136.0, 164.0, 157.0, 134.0, 139.0, 140.0, 146.0, 147.0], "value": 143.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 189.0, 219.0, 190.0, 208.0, 253.0, 192.0, 202.0, 212.0, 182.0], "value": 203.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 66.0, 68.0, 67.0, 68.0, 66.0, 69.0, 71.0, 68.0], "value": 68.2, "unit": "score"}]}]} 20:43:33 INFO - Return code: 0 20:43:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:43:33 INFO - # TBPL SUCCESS # 20:43:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:43:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:43:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:43:33 INFO - 2017-05-25 20:43:33.790 screenresolution[1924:12903] starting screenresolution argv=screenresolution get 20:43:33 INFO - 2017-05-25 20:43:33.792 screenresolution[1924:12903] Display 0: 1600x1200x32@60 20:43:33 INFO - 2017-05-25 20:43:33.798 screenresolution[1925:12906] starting screenresolution argv=screenresolution list 20:43:33 INFO - Available Modes on Display 0 20:43:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:43:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:43:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:43:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:43:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:43:34 INFO - Intel Iris: 20:43:34 INFO - Chipset Model: Intel Iris 20:43:34 INFO - Type: GPU 20:43:34 INFO - Bus: Built-In 20:43:34 INFO - VRAM (Dynamic, Max): 1536 MB 20:43:34 INFO - Vendor: Intel (0x8086) 20:43:34 INFO - Device ID: 0x0a2e 20:43:34 INFO - Revision ID: 0x0009 20:43:34 INFO - Displays: 20:43:34 INFO - Display: 20:43:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:43:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:43:34 INFO - Display Serial Number: 1600x1200 60 20:43:34 INFO - Main Display: Yes 20:43:34 INFO - Mirror: Off 20:43:34 INFO - Online: Yes 20:43:34 INFO - Rotation: Supported 20:43:34 INFO - Return code: 0 20:43:34 INFO - Running post-action listener: _resource_record_post_action 20:43:34 INFO - [mozharness: 2017-05-26 03:43:34.034808Z] Finished run-tests step (success) 20:43:34 INFO - Running post-run listener: _resource_record_post_run 20:43:34 INFO - Total resource usage - Wall time: 1254s; CPU: 53.0%; Read bytes: 4125184; Write bytes: 563298304; Read time: 127; Write time: 1934 20:43:34 INFO - TinderboxPrint: CPU usage
53.3% 20:43:34 INFO - TinderboxPrint: I/O read bytes / time
4,125,184 / 127 20:43:34 INFO - TinderboxPrint: I/O write bytes / time
563,298,304 / 1,934 20:43:34 INFO - TinderboxPrint: CPU idle
2,344.7 (46.7%) 20:43:34 INFO - TinderboxPrint: CPU system
1,016.0 (20.2%) 20:43:34 INFO - TinderboxPrint: CPU user
1,657.0 (33.0%) 20:43:34 INFO - TinderboxPrint: Swap in / out
249,212,928 / 0 20:43:34 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230781440; Write bytes: 226471936; Read time: 16772; Write time: 409 20:43:34 INFO - run-tests - Wall time: 1233s; CPU: 53.0%; Read bytes: 3260928; Write bytes: 317501440; Read time: 123; Write time: 1475 20:43:34 INFO - Running post-run listener: _upload_blobber_files 20:43:34 INFO - Blob upload gear active. 20:43:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:43:34 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 20:43:34 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'] 20:43:34 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 20:43:34 INFO - (blobuploader) - INFO - Open directory for files ... 20:43:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:43:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:43:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:43:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:43:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:43:35 INFO - (blobuploader) - INFO - Done attempting. 20:43:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:43:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:43:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:43:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:43:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:43:36 INFO - (blobuploader) - INFO - Done attempting. 20:43:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:43:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:43:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:43:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:43:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:43:37 INFO - (blobuploader) - INFO - Done attempting. 20:43:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:43:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:43:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:43:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:43:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:43:40 INFO - (blobuploader) - INFO - Done attempting. 20:43:40 INFO - (blobuploader) - INFO - Iteration through files over. 20:43:40 INFO - Return code: 0 20:43:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:43:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:43:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dea76b3c48d73052d79fe2388a7eb901eef95153332bf2fa20a5fa311c4dbb5486ec8b5a42299ca2717ee68c3e21f1f12be998d7823ee4a4c7c9fe0f1f63267d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3718d244edac34077bdc70f71cfba33f7ce4e3ce2bfb7d0ad8e725455d8a4fc09f25f37caf7234a2bd63ea5d5fc3c1e8389fb4a8091501bfc778ca6072061c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a8f149b0736ad43fcd1e445f7ed9f41e8a5039f9132021ce3c8e0569699d6eb4d08d1fdf8ff7a53c17a763bfb7c6b38c4c31158a67bc424059b12f719e55807", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22eae80ee24f1472b033746370668c9b65e7893d8114723354b72fabd3240888cbcfeca83a90bb08c41d5ca5b76932ede875b20d9fe7e65fbaa588a0e68f03c9"} 20:43:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:43:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:43:40 INFO - Contents: 20:43:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dea76b3c48d73052d79fe2388a7eb901eef95153332bf2fa20a5fa311c4dbb5486ec8b5a42299ca2717ee68c3e21f1f12be998d7823ee4a4c7c9fe0f1f63267d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3718d244edac34077bdc70f71cfba33f7ce4e3ce2bfb7d0ad8e725455d8a4fc09f25f37caf7234a2bd63ea5d5fc3c1e8389fb4a8091501bfc778ca6072061c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a8f149b0736ad43fcd1e445f7ed9f41e8a5039f9132021ce3c8e0569699d6eb4d08d1fdf8ff7a53c17a763bfb7c6b38c4c31158a67bc424059b12f719e55807", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22eae80ee24f1472b033746370668c9b65e7893d8114723354b72fabd3240888cbcfeca83a90bb08c41d5ca5b76932ede875b20d9fe7e65fbaa588a0e68f03c9"} 20:43:40 INFO - Running post-run listener: copy_logs_to_upload_dir 20:43:40 INFO - Copying logs to upload dir... 20:43:40 INFO - mkdir: /builds/slave/test/build/upload/logs 20:43:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1334.086103 ========= master_lag: 0.46 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 14 secs) (at 2017-05-25 20:43:40.904187) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 20:43:40.908767) ========= 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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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/dea76b3c48d73052d79fe2388a7eb901eef95153332bf2fa20a5fa311c4dbb5486ec8b5a42299ca2717ee68c3e21f1f12be998d7823ee4a4c7c9fe0f1f63267d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3718d244edac34077bdc70f71cfba33f7ce4e3ce2bfb7d0ad8e725455d8a4fc09f25f37caf7234a2bd63ea5d5fc3c1e8389fb4a8091501bfc778ca6072061c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a8f149b0736ad43fcd1e445f7ed9f41e8a5039f9132021ce3c8e0569699d6eb4d08d1fdf8ff7a53c17a763bfb7c6b38c4c31158a67bc424059b12f719e55807", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22eae80ee24f1472b033746370668c9b65e7893d8114723354b72fabd3240888cbcfeca83a90bb08c41d5ca5b76932ede875b20d9fe7e65fbaa588a0e68f03c9"} build_url:https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011492 build_url: 'https://queue.taskcluster.net/v1/task/dfLImuXqQ1OYhCBHk_kUIw/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/dea76b3c48d73052d79fe2388a7eb901eef95153332bf2fa20a5fa311c4dbb5486ec8b5a42299ca2717ee68c3e21f1f12be998d7823ee4a4c7c9fe0f1f63267d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3718d244edac34077bdc70f71cfba33f7ce4e3ce2bfb7d0ad8e725455d8a4fc09f25f37caf7234a2bd63ea5d5fc3c1e8389fb4a8091501bfc778ca6072061c8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a8f149b0736ad43fcd1e445f7ed9f41e8a5039f9132021ce3c8e0569699d6eb4d08d1fdf8ff7a53c17a763bfb7c6b38c4c31158a67bc424059b12f719e55807", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22eae80ee24f1472b033746370668c9b65e7893d8114723354b72fabd3240888cbcfeca83a90bb08c41d5ca5b76932ede875b20d9fe7e65fbaa588a0e68f03c9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 20:43:40.942851) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 20:43:40.943176) ========= 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.qvV3H7sNT0/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.KuUPBQpIRF/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.005291 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 20:43:40.992033) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 20:43:40.992348) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 20:43:40.992650) ========= ========= Total master_lag: 0.60 =========