builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0078 starttime: 1495662624.76 results: success (0) revision: c575ecde53030c41c12ba228d8fe78c3ed896e76 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 14:50:24.765274) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 14:50:24.766160) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 14:50:24.766855) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 14:50:24.792895) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 14:50:24.793611) ========= 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.0TiupBQwEf/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.dB2LZC7G6L/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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-24 14:50: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% 23.3M=0s 2017-05-24 14:50:24 (23.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130748 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 14:50:24.949901) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 14:50:24.950273) ========= 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.0TiupBQwEf/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.dB2LZC7G6L/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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.029052 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 14:50:25.000707) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 14:50:25.001093) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c575ecde53030c41c12ba228d8fe78c3ed896e76 --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 c575ecde53030c41c12ba228d8fe78c3ed896e76 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0TiupBQwEf/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.dB2LZC7G6L/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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-24 14:50:25,091 truncating revision to first 12 chars 2017-05-24 14:50:25,091 Setting DEBUG logging. 2017-05-24 14:50:25,091 attempt 1/10 2017-05-24 14:50:25,091 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c575ecde5303?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 14:50:25,335 unpacking tar archive at: mozilla-central-c575ecde5303/testing/mozharness/ program finished with exit code 0 elapsedTime=0.515620 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 14:50:25.542183) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 14:50:25.542909) ========= script_repo_revision: c575ecde53030c41c12ba228d8fe78c3ed896e76 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 14:50:25.543783) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 14:50:25.544599) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 14:50:25.567275) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-05-24 14:50:25.567933) ========= /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.0TiupBQwEf/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.dB2LZC7G6L/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 14:50:25 INFO - MultiFileLogger online at 20170524 14:50:25 in /builds/slave/test 14:50:25 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 14:50:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:50:25 INFO - {'append_to_log': False, 14:50:25 INFO - 'base_work_dir': '/builds/slave/test', 14:50:25 INFO - 'blob_upload_branch': 'Firefox', 14:50:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:50:25 INFO - 'branch': 'Firefox', 14:50:25 INFO - 'buildbot_json_path': 'buildprops.json', 14:50:25 INFO - 'config_files': ('talos/mac_config.py',), 14:50:25 INFO - 'default_actions': ('clobber', 14:50:25 INFO - 'read-buildbot-config', 14:50:25 INFO - 'download-and-extract', 14:50:25 INFO - 'populate-webroot', 14:50:25 INFO - 'create-virtualenv', 14:50:25 INFO - 'install', 14:50:25 INFO - 'run-tests'), 14:50:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:50:25 INFO - 'download_minidump_stackwalk': True, 14:50:25 INFO - 'download_symbols': 'ondemand', 14:50:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:50:25 INFO - 'tooltool.py': '/tools/tooltool.py', 14:50:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:50:25 INFO - '/tools/misc-python/virtualenv.py')}, 14:50:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:50:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:50:25 INFO - 'gecko_profile': False, 14:50:25 INFO - 'gecko_profile_interval': 0, 14:50:25 INFO - 'installer_path': 'installer.exe', 14:50:25 INFO - 'log_level': 'info', 14:50:25 INFO - 'log_name': 'talos', 14:50:25 INFO - 'log_to_console': True, 14:50:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:50:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:50:25 INFO - 'opt_config_files': (), 14:50:25 INFO - 'pip_index': False, 14:50:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:50:25 INFO - 'cmd': ('bash', 14:50:25 INFO - '-c', 14:50:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:50:25 INFO - 'enabled': True, 14:50:25 INFO - 'halt_on_failure': False, 14:50:25 INFO - 'name': 'check_screen_resolution'},), 14:50:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:50:25 INFO - 'cmd': ('bash', 14:50:25 INFO - '-c', 14:50:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:50:25 INFO - 'enabled': True, 14:50:25 INFO - 'halt_on_failure': False, 14:50:25 INFO - 'name': 'check_screen_resolution'},), 14:50:25 INFO - 'run_cmd_checks_enabled': True, 14:50:25 INFO - 'suite': 'dromaeojs-e10s', 14:50:25 INFO - 'system_bits': '32', 14:50:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:50:25 INFO - 'title': 't-yosemite-r7-0078', 14:50:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:50:25 INFO - 'use_talos_json': True, 14:50:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:50:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:50:25 INFO - 'work_dir': 'build'} 14:50:25 INFO - [mozharness: 2017-05-24 21:50:25.773880Z] Running clobber step. 14:50:25 INFO - Running pre-action listener: _resource_record_pre_action 14:50:25 INFO - Running main action method: clobber 14:50:25 INFO - rmtree: /builds/slave/test/build 14:50:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:50:28 INFO - Running post-action listener: _resource_record_post_action 14:50:28 INFO - [mozharness: 2017-05-24 21:50:28.313260Z] Finished clobber step (success) 14:50:28 INFO - [mozharness: 2017-05-24 21:50:28.313383Z] Running read-buildbot-config step. 14:50:28 INFO - Running pre-action listener: _resource_record_pre_action 14:50:28 INFO - Running main action method: read_buildbot_config 14:50:28 INFO - Using buildbot properties: 14:50:28 INFO - { 14:50:28 INFO - "project": "", 14:50:28 INFO - "product": "firefox", 14:50:28 INFO - "who": "ryanvm@gmail.com", 14:50:28 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 14:50:28 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:50:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:50:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:50:28 INFO - "stage_platform": "macosx64", 14:50:28 INFO - "basedir": "/builds/slave/test", 14:50:28 INFO - "buildnumber": 82, 14:50:28 INFO - "platform": "yosemite_r7", 14:50:28 INFO - "parent_task_id": "Zy6byYiHQ2ibGkXwT8zY7A", 14:50:28 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 14:50:28 INFO - "slavebuilddir": "test", 14:50:28 INFO - "taskId": "Y6rrPpKQREGfDP63wAMYiQ", 14:50:28 INFO - "branch": "mozilla-central", 14:50:28 INFO - "script_repo_revision": "production", 14:50:28 INFO - "revision": "c575ecde53030c41c12ba228d8fe78c3ed896e76", 14:50:28 INFO - "slavename": "t-yosemite-r7-0078", 14:50:28 INFO - "repo_path": "mozilla-central" 14:50:28 INFO - } 14:50:28 INFO - Finding installer, test and symbols from parent task. 14:50:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y6rrPpKQREGfDP63wAMYiQ'}, attempt #1 14:50:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y6rrPpKQREGfDP63wAMYiQ'}, attempt #1 14:50:29 INFO - Task dependencies: YYcz-AgHTyKS-w5wEBbolw Zy6byYiHQ2ibGkXwT8zY7A 14:50:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A'}, attempt #1 14:50:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:50:30 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/test_packages.json 14:50:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/target.crashreporter-symbols.zip 14:50:30 INFO - Running post-action listener: _resource_record_post_action 14:50:30 INFO - [mozharness: 2017-05-24 21:50:30.129021Z] Finished read-buildbot-config step (success) 14:50:30 INFO - [mozharness: 2017-05-24 21:50:30.129121Z] Running download-and-extract step. 14:50:30 INFO - Running pre-action listener: _resource_record_pre_action 14:50:30 INFO - Running main action method: download_and_extract 14:50:30 INFO - mkdir: /builds/slave/test/build/tests 14:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:50:30 INFO - trying https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:50:30 INFO - Downloading https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/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 14:50:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/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 14:50:31 INFO - Downloaded 1325 bytes. 14:50:31 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:50:31 INFO - Using the following test package requirements: 14:50:31 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:50:31 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:50:31 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:50:31 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:50:31 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:50:31 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:50:31 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:50:31 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:50:31 INFO - u'jsshell-mac.zip'], 14:50:31 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:50:31 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:50:31 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:50:31 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:50:31 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:50:31 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:50:31 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:50:31 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:50:31 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:50:31 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:50:31 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:50:31 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:50:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:50:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:50:31 INFO - Fetch https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:50:32 INFO - Content-Length response header: 57625628 14:50:32 INFO - Bytes received: 57625628 14:50:50 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:50:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:50:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:50:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:50:52 INFO - Content-Length response header: 57625628 14:50:52 INFO - Bytes received: 57625628 14:51:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:51:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:51:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:51:11 INFO - Content-Length response header: 14411859 14:51:11 INFO - Bytes received: 14411859 14:51:11 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')]} 14:51:11 INFO - trying https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:51:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:51:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/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 14:51:13 INFO - Downloaded 68068383 bytes. 14:51:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:51:13 INFO - mkdir: /builds/slave/test/properties 14:51:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:51:13 INFO - Writing to file /builds/slave/test/properties/build_url 14:51:13 INFO - Contents: 14:51:13 INFO - build_url:https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:51:13 INFO - Running post-action listener: _resource_record_post_action 14:51:13 INFO - Running post-action listener: set_extra_try_arguments 14:51:13 INFO - [mozharness: 2017-05-24 21:51:13.745208Z] Finished download-and-extract step (success) 14:51:13 INFO - [mozharness: 2017-05-24 21:51:13.745328Z] Running populate-webroot step. 14:51:13 INFO - Running pre-action listener: _resource_record_pre_action 14:51:13 INFO - Running main action method: populate_webroot 14:51:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:51:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:51:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:51:13 INFO - '--tppagecycles', 14:51:13 INFO - '7'], 14:51:13 INFO - 'tests': ['tsvgm']}}, 14:51:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:51:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:51:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:51:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:51:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:51:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:51:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:51:13 INFO - 'talos_options': ['--disable-e10s'], 14:51:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:51:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:51:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:51:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:51:13 INFO - 'talos_options': ['--disable-e10s'], 14:51:13 INFO - 'tests': ['damp', 'tps']}, 14:51:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:51:13 INFO - 'tests': ['damp', 'tps']}, 14:51:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:51:13 INFO - 'tests': ['dromaeo_dom']}, 14:51:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:51:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:51:13 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:51:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:51:13 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:51:13 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:51:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:51:13 INFO - 'tests': ['a11yr', 14:51:13 INFO - 'ts_paint', 14:51:13 INFO - 'tpaint', 14:51:13 INFO - 'sessionrestore', 14:51:13 INFO - 'sessionrestore_no_auto_restore', 14:51:13 INFO - 'tabpaint']}, 14:51:13 INFO - 'other-e10s': {'tests': ['a11yr', 14:51:13 INFO - 'ts_paint', 14:51:13 INFO - 'tpaint', 14:51:13 INFO - 'sessionrestore', 14:51:13 INFO - 'sessionrestore_no_auto_restore', 14:51:13 INFO - 'tabpaint']}, 14:51:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:51:13 INFO - 'ts_paint', 14:51:13 INFO - 'tpaint', 14:51:13 INFO - 'sessionrestore', 14:51:13 INFO - 'sessionrestore_no_auto_restore', 14:51:13 INFO - 'tabpaint']}, 14:51:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:51:13 INFO - 'ts_paint', 14:51:13 INFO - 'tpaint', 14:51:13 INFO - 'sessionrestore', 14:51:13 INFO - 'sessionrestore_no_auto_restore', 14:51:13 INFO - 'tabpaint']}, 14:51:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:51:13 INFO - 'tests': ['a11yr', 14:51:13 INFO - 'ts_paint', 14:51:13 INFO - 'tpaint', 14:51:13 INFO - 'sessionrestore', 14:51:13 INFO - 'sessionrestore_no_auto_restore', 14:51:13 INFO - 'tabpaint']}, 14:51:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:51:13 INFO - 'tests': ['a11yr', 14:51:13 INFO - 'ts_paint', 14:51:13 INFO - 'tpaint', 14:51:13 INFO - 'sessionrestore', 14:51:13 INFO - 'sessionrestore_no_auto_restore', 14:51:13 INFO - 'tabpaint']}, 14:51:13 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:51:13 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:51:13 INFO - 'bloom_basic_ref']}, 14:51:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:51:13 INFO - 'tests': ['tsvgx', 14:51:13 INFO - 'tsvgr_opacity', 14:51:13 INFO - 'tart', 14:51:13 INFO - 'tscrollx', 14:51:13 INFO - 'cart', 14:51:13 INFO - 'tsvg_static']}, 14:51:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:51:13 INFO - 'tsvgr_opacity', 14:51:13 INFO - 'tart', 14:51:13 INFO - 'tscrollx', 14:51:13 INFO - 'cart', 14:51:13 INFO - 'tsvg_static']}, 14:51:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:51:13 INFO - 'talos_options': ['--disable-e10s'], 14:51:13 INFO - 'tests': ['tp5o']}, 14:51:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:51:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:51:13 INFO - 'talos_options': ['--disable-e10s', 14:51:13 INFO - '--xperf_path', 14:51:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:51:13 INFO - 'tests': ['tp5n']}, 14:51:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:51:13 INFO - 'talos_options': ['--xperf_path', 14:51:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:51:13 INFO - 'tests': ['tp5n']}}, 14:51:13 INFO - 'talos.zip': {'path': '', 14:51:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:51:13 INFO - Running post-action listener: _resource_record_post_action 14:51:13 INFO - [mozharness: 2017-05-24 21:51:13.766749Z] Finished populate-webroot step (success) 14:51:13 INFO - [mozharness: 2017-05-24 21:51:13.766880Z] Running create-virtualenv step. 14:51:13 INFO - Running pre-action listener: _resource_record_pre_action 14:51:13 INFO - Running main action method: create_virtualenv 14:51:13 INFO - Creating virtualenv /builds/slave/test/build/venv 14:51:13 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 14:51:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:51:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:51:13 INFO - Using real prefix '/tools/python27' 14:51:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:51:14 INFO - Installing distribute.............................................................................................................................................................................................done. 14:51:17 INFO - Installing pip.................done. 14:51:17 INFO - Return code: 0 14:51:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:51:17 INFO - Reading from file tmpfile_stdout 14:51:17 INFO - Output received: 14:51:17 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) 14:51:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:51:17 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')]} 14:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:17 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')]} 14:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:17 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 0x109106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe50d8b380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e16d30>, '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 0x108dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e0bbe8>, '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.0TiupBQwEf/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.dB2LZC7G6L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 14:51:17 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 14:51:17 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 14:51:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TiupBQwEf/Render', 14:51:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:51:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:51:17 INFO - 'HOME': '/Users/cltbld', 14:51:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:51:17 INFO - 'LOGNAME': 'cltbld', 14:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:17 INFO - 'MOZ_NO_REMOTE': '1', 14:51:17 INFO - 'NO_EM_RESTART': '1', 14:51:17 INFO - 'PAGER': '/bin/cat', 14:51:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:17 INFO - 'PWD': '/builds/slave/test', 14:51:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:51:17 INFO - 'SHELL': '/bin/bash', 14:51:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dB2LZC7G6L/Listeners', 14:51:17 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 14:51:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:51:17 INFO - 'USER': 'cltbld', 14:51:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:51:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:17 INFO - 'XPC_FLAGS': '0x0', 14:51:17 INFO - 'XPC_SERVICE_NAME': '0', 14:51:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:51:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:18 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 14:51:18 INFO - Cleaning up... 14:51:18 INFO - Return code: 0 14:51:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:51:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe50d8b380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e16d30>, '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 0x108dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e0bbe8>, '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.0TiupBQwEf/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.dB2LZC7G6L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 14:51:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:51:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:51:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TiupBQwEf/Render', 14:51:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:51:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:51:18 INFO - 'HOME': '/Users/cltbld', 14:51:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:51:18 INFO - 'LOGNAME': 'cltbld', 14:51:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:18 INFO - 'MOZ_NO_REMOTE': '1', 14:51:18 INFO - 'NO_EM_RESTART': '1', 14:51:18 INFO - 'PAGER': '/bin/cat', 14:51:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:51:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:18 INFO - 'PWD': '/builds/slave/test', 14:51:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:51:18 INFO - 'SHELL': '/bin/bash', 14:51:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dB2LZC7G6L/Listeners', 14:51:18 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 14:51:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:51:18 INFO - 'USER': 'cltbld', 14:51:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:51:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:51:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:18 INFO - 'XPC_FLAGS': '0x0', 14:51:18 INFO - 'XPC_SERVICE_NAME': '0', 14:51:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:51:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:18 INFO - Downloading/unpacking psutil>=3.1.1 14:51:18 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 14:51:18 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 14:51:18 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 14:51:18 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 14:51:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:51:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:51:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:51:20 INFO - Installing collected packages: psutil 14:51:20 INFO - Running setup.py install for psutil 14:51:20 INFO - building 'psutil._psutil_osx' extension 14:51:20 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 14:51:20 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 14:51:21 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 14:51:21 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 14:51:21 INFO - building 'psutil._psutil_posix' extension 14:51:21 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 14:51:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:51:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:51:21 INFO - ^ 14:51:21 INFO - 1 warning generated. 14:51:21 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 14:51:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:51:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:51:21 INFO - Successfully installed psutil 14:51:21 INFO - Cleaning up... 14:51:21 INFO - Return code: 0 14:51:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:51: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')]} 14:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51: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')]} 14:51:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:22 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 0x109106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe50d8b380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e16d30>, '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 0x108dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e0bbe8>, '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.0TiupBQwEf/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.dB2LZC7G6L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 14:51:22 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 14:51:22 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 14:51:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TiupBQwEf/Render', 14:51:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:51:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:51:22 INFO - 'HOME': '/Users/cltbld', 14:51:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:51:22 INFO - 'LOGNAME': 'cltbld', 14:51:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:22 INFO - 'MOZ_NO_REMOTE': '1', 14:51:22 INFO - 'NO_EM_RESTART': '1', 14:51:22 INFO - 'PAGER': '/bin/cat', 14:51:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:51:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:22 INFO - 'PWD': '/builds/slave/test', 14:51:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:51:22 INFO - 'SHELL': '/bin/bash', 14:51:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dB2LZC7G6L/Listeners', 14:51:22 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 14:51:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:51:22 INFO - 'USER': 'cltbld', 14:51:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:51:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:51:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:22 INFO - 'XPC_FLAGS': '0x0', 14:51:22 INFO - 'XPC_SERVICE_NAME': '0', 14:51:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:51:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:51: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 14:51: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 14:51: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 14:51: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 14:51:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:51:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:51:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:51:24 INFO - Installing collected packages: mozsystemmonitor 14:51:24 INFO - Running setup.py install for mozsystemmonitor 14:51:24 INFO - Successfully installed mozsystemmonitor 14:51:24 INFO - Cleaning up... 14:51:24 INFO - Return code: 0 14:51:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:51: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')]} 14:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51: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')]} 14:51:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51: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', '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 0x109106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe50d8b380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e16d30>, '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 0x108dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e0bbe8>, '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.0TiupBQwEf/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.dB2LZC7G6L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 14:51: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:51: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 jsonschema==2.5.1 14:51:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TiupBQwEf/Render', 14:51:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:51:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:51:24 INFO - 'HOME': '/Users/cltbld', 14:51:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:51:24 INFO - 'LOGNAME': 'cltbld', 14:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:24 INFO - 'MOZ_NO_REMOTE': '1', 14:51:24 INFO - 'NO_EM_RESTART': '1', 14:51:24 INFO - 'PAGER': '/bin/cat', 14:51:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:51:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:24 INFO - 'PWD': '/builds/slave/test', 14:51:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:51:24 INFO - 'SHELL': '/bin/bash', 14:51:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dB2LZC7G6L/Listeners', 14:51:24 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 14:51:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:51:24 INFO - 'USER': 'cltbld', 14:51:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:51:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:24 INFO - 'XPC_FLAGS': '0x0', 14:51:24 INFO - 'XPC_SERVICE_NAME': '0', 14:51:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:51:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:24 INFO - Downloading/unpacking jsonschema==2.5.1 14:51: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 14:51: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 14:51: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 14:51: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 14:51:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:51:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:51: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 14:51: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 14:51: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 14:51: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 14:51:26 INFO - Downloading functools32-3.2.3-2.tar.gz 14:51:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:51:26 INFO - warning: no files found matching '*.txt' 14:51:26 INFO - no previously-included directories found matching 'build' 14:51:26 INFO - no previously-included directories found matching 'dist' 14:51:26 INFO - no previously-included directories found matching '.git*' 14:51:26 INFO - Installing collected packages: jsonschema, functools32 14:51:26 INFO - Running setup.py install for functools32 14:51:26 INFO - warning: no files found matching '*.txt' 14:51:26 INFO - no previously-included directories found matching 'build' 14:51:26 INFO - no previously-included directories found matching 'dist' 14:51:26 INFO - no previously-included directories found matching '.git*' 14:51:26 INFO - Successfully installed jsonschema functools32 14:51:26 INFO - Cleaning up... 14:51:26 INFO - Return code: 0 14:51:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:51:26 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')]} 14:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:26 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')]} 14:51:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:26 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 0x109106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe50d8b380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e16d30>, '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 0x108dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e0bbe8>, '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.0TiupBQwEf/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.dB2LZC7G6L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 14:51:26 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 14:51:26 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 14:51:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TiupBQwEf/Render', 14:51:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:51:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:51:26 INFO - 'HOME': '/Users/cltbld', 14:51:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:51:26 INFO - 'LOGNAME': 'cltbld', 14:51:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:26 INFO - 'MOZ_NO_REMOTE': '1', 14:51:26 INFO - 'NO_EM_RESTART': '1', 14:51:26 INFO - 'PAGER': '/bin/cat', 14:51:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:51:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:26 INFO - 'PWD': '/builds/slave/test', 14:51:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:51:26 INFO - 'SHELL': '/bin/bash', 14:51:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dB2LZC7G6L/Listeners', 14:51:26 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 14:51:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:51:26 INFO - 'USER': 'cltbld', 14:51:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:51:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:51:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:26 INFO - 'XPC_FLAGS': '0x0', 14:51:26 INFO - 'XPC_SERVICE_NAME': '0', 14:51:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:51:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:51:26 INFO - Cleaning up... 14:51:26 INFO - Return code: 0 14:51:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:51:26 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')]} 14:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:26 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')]} 14:51:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:26 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 0x109106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe50d8b380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e16d30>, '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 0x108dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e0bbe8>, '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.0TiupBQwEf/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.dB2LZC7G6L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 14:51:26 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 14:51:26 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 14:51:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TiupBQwEf/Render', 14:51:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:51:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:51:26 INFO - 'HOME': '/Users/cltbld', 14:51:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:51:26 INFO - 'LOGNAME': 'cltbld', 14:51:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:26 INFO - 'MOZ_NO_REMOTE': '1', 14:51:26 INFO - 'NO_EM_RESTART': '1', 14:51:26 INFO - 'PAGER': '/bin/cat', 14:51:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:51:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:26 INFO - 'PWD': '/builds/slave/test', 14:51:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:51:26 INFO - 'SHELL': '/bin/bash', 14:51:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dB2LZC7G6L/Listeners', 14:51:26 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 14:51:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:51:26 INFO - 'USER': 'cltbld', 14:51:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:51:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:51:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:26 INFO - 'XPC_FLAGS': '0x0', 14:51:26 INFO - 'XPC_SERVICE_NAME': '0', 14:51:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:51:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:26 INFO - Downloading/unpacking blobuploader==1.2.4 14:51: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 14:51: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 14:51: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 14:51: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 14:51:28 INFO - Downloading blobuploader-1.2.4.tar.gz 14:51:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:51:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:51:28 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 14:51:28 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 14:51:28 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 14:51:28 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 14:51:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:51:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:51:28 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 14:51:28 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 14:51:28 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 14:51:28 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 14:51:29 INFO - Downloading docopt-0.6.1.tar.gz 14:51:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:51:29 INFO - Installing collected packages: blobuploader, requests, docopt 14:51:29 INFO - Running setup.py install for blobuploader 14:51:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:51:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:51:29 INFO - Running setup.py install for requests 14:51:29 INFO - Running setup.py install for docopt 14:51:30 INFO - Successfully installed blobuploader requests docopt 14:51:30 INFO - Cleaning up... 14:51:30 INFO - Return code: 0 14:51:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:30 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 0x109106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe50d8b380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e16d30>, '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 0x108dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e0bbe8>, '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.0TiupBQwEf/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.dB2LZC7G6L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 14:51:30 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 14:51:30 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 14:51:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TiupBQwEf/Render', 14:51:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:51:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:51:30 INFO - 'HOME': '/Users/cltbld', 14:51:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:51:30 INFO - 'LOGNAME': 'cltbld', 14:51:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:30 INFO - 'MOZ_NO_REMOTE': '1', 14:51:30 INFO - 'NO_EM_RESTART': '1', 14:51:30 INFO - 'PAGER': '/bin/cat', 14:51:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:51:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:30 INFO - 'PWD': '/builds/slave/test', 14:51:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:51:30 INFO - 'SHELL': '/bin/bash', 14:51:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dB2LZC7G6L/Listeners', 14:51:30 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 14:51:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:51:30 INFO - 'USER': 'cltbld', 14:51:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:51:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:51:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:30 INFO - 'XPC_FLAGS': '0x0', 14:51:30 INFO - 'XPC_SERVICE_NAME': '0', 14:51:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:51:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:51:30 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-OQd_v8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:51:30 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-pHKvfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:51:30 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-9R38nI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:51:30 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-874iVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:51:30 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-JayHJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:51:30 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-LFlOEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:51:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-J2Hv4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:51:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ok2S_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:51:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-_uua_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:51:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-PacsM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:51:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-6y3rwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:51:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-tF7DXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:51:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-5tM7mt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:51:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-gXSxMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:51:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-AObCIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:51:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:51:31 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-iOyJt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:51:32 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-h7ZsEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:51:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:51:32 INFO - Running setup.py install for manifestparser 14:51:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:51:32 INFO - Running setup.py install for mozcrash 14:51:32 INFO - Running setup.py install for mozdebug 14:51:32 INFO - Running setup.py install for mozdevice 14:51:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:51:32 INFO - Running setup.py install for mozfile 14:51:32 INFO - Running setup.py install for mozhttpd 14:51:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:51:33 INFO - Running setup.py install for mozinfo 14:51:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:51:33 INFO - Running setup.py install for mozInstall 14:51:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:51:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:51:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:51:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:51:33 INFO - Running setup.py install for mozleak 14:51:33 INFO - Running setup.py install for mozlog 14:51:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:51:33 INFO - Running setup.py install for moznetwork 14:51:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:51:33 INFO - Running setup.py install for mozprocess 14:51:33 INFO - Running setup.py install for mozprofile 14:51:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:51:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:51:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:51:34 INFO - Running setup.py install for mozrunner 14:51:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:51:34 INFO - Running setup.py install for mozscreenshot 14:51:34 INFO - Running setup.py install for moztest 14:51:34 INFO - Running setup.py install for mozversion 14:51:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:51:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:51:34 INFO - Cleaning up... 14:51:34 INFO - Return code: 0 14:51:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:51:34 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')]} 14:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:34 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')]} 14:51:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:34 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 0x109106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe50d8b380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e16d30>, '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 0x108dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e0bbe8>, '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.0TiupBQwEf/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.dB2LZC7G6L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 14:51:34 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 14:51:34 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 14:51:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TiupBQwEf/Render', 14:51:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:51:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:51:34 INFO - 'HOME': '/Users/cltbld', 14:51:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:51:34 INFO - 'LOGNAME': 'cltbld', 14:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:34 INFO - 'MOZ_NO_REMOTE': '1', 14:51:34 INFO - 'NO_EM_RESTART': '1', 14:51:34 INFO - 'PAGER': '/bin/cat', 14:51:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:34 INFO - 'PWD': '/builds/slave/test', 14:51:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:51:34 INFO - 'SHELL': '/bin/bash', 14:51:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dB2LZC7G6L/Listeners', 14:51:34 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 14:51:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:51:34 INFO - 'USER': 'cltbld', 14:51:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:51:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:34 INFO - 'XPC_FLAGS': '0x0', 14:51:34 INFO - 'XPC_SERVICE_NAME': '0', 14:51:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:51:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:51:34 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-VAvbz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:51:35 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)) 14:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:51:35 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-OUCdE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:51:35 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)) 14:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:51:35 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-1DpKNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:51:35 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)) 14:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:51:35 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-1hED_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:51:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:51:35 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-oeK1vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:51:35 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)) 14:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:51:35 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-Ia3fHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:51:35 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)) 14:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:51:35 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-J68JEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:51:35 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)) 14:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:51:35 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-CrMduD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:51:35 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)) 14:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:51:35 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-nqdRkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:51:35 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)) 14:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:51:35 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-HpRhT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:51:36 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)) 14:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:51:36 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-CGLdoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:51:36 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)) 14:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:51:36 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-2EtAf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:51:36 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)) 14:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:51:36 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-O7WoOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:51:36 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)) 14:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:51:36 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-PFbEYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:51:36 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)) 14:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:51:36 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-eYjyQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:51:36 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)) 14:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:51:36 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-__BBuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:51:36 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)) 14:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:51:36 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-j0qgJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:51:36 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)) 14:51:36 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)) 14:51:36 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)) 14:51:36 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)) 14:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:51:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:51:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:51:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:51:38 INFO - Downloading blessings-1.6.tar.gz 14:51:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:51:38 INFO - Installing collected packages: blessings 14:51:38 INFO - Running setup.py install for blessings 14:51:38 INFO - Successfully installed blessings 14:51:38 INFO - Cleaning up... 14:51:38 INFO - Return code: 0 14:51:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:51:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:51:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:51:38 INFO - Reading from file tmpfile_stdout 14:51:38 INFO - Current package versions: 14:51:38 INFO - blessings == 1.6 14:51:38 INFO - blobuploader == 1.2.4 14:51:38 INFO - docopt == 0.6.1 14:51:38 INFO - functools32 == 3.2.3-2 14:51:38 INFO - jsonschema == 2.5.1 14:51:38 INFO - manifestparser == 1.1 14:51:38 INFO - mozInstall == 1.12 14:51:38 INFO - mozcrash == 1.0 14:51:38 INFO - mozdebug == 0.1 14:51:38 INFO - mozdevice == 0.50 14:51:38 INFO - mozfile == 1.2 14:51:38 INFO - mozhttpd == 0.7 14:51:38 INFO - mozinfo == 0.9 14:51:38 INFO - mozleak == 0.1 14:51:38 INFO - mozlog == 3.4 14:51:38 INFO - moznetwork == 0.27 14:51:38 INFO - mozprocess == 0.25 14:51:38 INFO - mozprofile == 0.28 14:51:38 INFO - mozrunner == 6.13 14:51:38 INFO - mozscreenshot == 0.1 14:51:38 INFO - mozsystemmonitor == 0.3 14:51:38 INFO - moztest == 0.8 14:51:38 INFO - mozversion == 1.4 14:51:38 INFO - psutil == 3.1.1 14:51:38 INFO - requests == 1.2.3 14:51:38 INFO - wsgiref == 0.1.2 14:51:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:51:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:38 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 0x109106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe50d8b380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e16d30>, '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 0x108dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e0bbe8>, '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.0TiupBQwEf/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.dB2LZC7G6L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 14:51:38 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 14:51:38 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 14:51:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TiupBQwEf/Render', 14:51:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:51:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:51:38 INFO - 'HOME': '/Users/cltbld', 14:51:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:51:38 INFO - 'LOGNAME': 'cltbld', 14:51:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:38 INFO - 'MOZ_NO_REMOTE': '1', 14:51:38 INFO - 'NO_EM_RESTART': '1', 14:51:38 INFO - 'PAGER': '/bin/cat', 14:51:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:51:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:38 INFO - 'PWD': '/builds/slave/test', 14:51:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:51:38 INFO - 'SHELL': '/bin/bash', 14:51:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dB2LZC7G6L/Listeners', 14:51:38 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 14:51:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:51:38 INFO - 'USER': 'cltbld', 14:51:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:51:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:51:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:38 INFO - 'XPC_FLAGS': '0x0', 14:51:38 INFO - 'XPC_SERVICE_NAME': '0', 14:51:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:51:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:39 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)) 14:51:39 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)) 14:51:39 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)) 14:51:39 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)) 14:51:39 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)) 14:51:39 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)) 14:51:39 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)) 14:51:39 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)) 14:51:39 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)) 14:51:39 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:51:39 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 14:51:39 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 14:51:39 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 14:51:39 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 14:51:40 INFO - Downloading marionette_driver-1.4.0.tar.gz 14:51:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 14:51:40 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)) 14:51:40 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)) 14:51:40 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)) 14:51:40 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)) 14:51:40 INFO - Installing collected packages: marionette-driver 14:51:40 INFO - Running setup.py install for marionette-driver 14:51:40 INFO - Successfully installed marionette-driver 14:51:40 INFO - Cleaning up... 14:51:40 INFO - Return code: 0 14:51:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109106b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10910a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe50d8b380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e16d30>, '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 0x108dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e0bbe8>, '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.0TiupBQwEf/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.dB2LZC7G6L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 14:51:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:51:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:51:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TiupBQwEf/Render', 14:51:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:51:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:51:40 INFO - 'HOME': '/Users/cltbld', 14:51:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:51:40 INFO - 'LOGNAME': 'cltbld', 14:51:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:40 INFO - 'MOZ_NO_REMOTE': '1', 14:51:40 INFO - 'NO_EM_RESTART': '1', 14:51:40 INFO - 'PAGER': '/bin/cat', 14:51:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:51:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:40 INFO - 'PWD': '/builds/slave/test', 14:51:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:51:40 INFO - 'SHELL': '/bin/bash', 14:51:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dB2LZC7G6L/Listeners', 14:51:40 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 14:51:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:51:40 INFO - 'USER': 'cltbld', 14:51:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:51:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:51:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:40 INFO - 'XPC_FLAGS': '0x0', 14:51:40 INFO - 'XPC_SERVICE_NAME': '0', 14:51:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:51:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:51:41 INFO - Cleaning up... 14:51:41 INFO - Return code: 0 14:51:41 INFO - Running post-action listener: _resource_record_post_action 14:51:41 INFO - Running post-action listener: _start_resource_monitoring 14:51:41 INFO - Starting resource monitoring. 14:51:41 INFO - [mozharness: 2017-05-24 21:51:41.080295Z] Finished create-virtualenv step (success) 14:51:41 INFO - [mozharness: 2017-05-24 21:51:41.081258Z] Running install step. 14:51:41 INFO - Running pre-action listener: _resource_record_pre_action 14:51:41 INFO - Running main action method: install 14:51:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:51:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:51:41 INFO - Reading from file tmpfile_stdout 14:51:41 INFO - Detecting whether we're running mozinstall >=1.0... 14:51:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:51:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:51:41 INFO - Reading from file tmpfile_stdout 14:51:41 INFO - Output received: 14:51:41 INFO - Usage: mozinstall [options] installer 14:51:41 INFO - Options: 14:51:41 INFO - -h, --help show this help message and exit 14:51:41 INFO - -d DEST, --destination=DEST 14:51:41 INFO - Directory to install application into. [default: 14:51:41 INFO - "/builds/slave/test"] 14:51:41 INFO - --app=APP Application being installed. [default: firefox] 14:51:41 INFO - mkdir: /builds/slave/test/build/application 14:51:41 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'] 14:51:41 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 14:52:02 INFO - Reading from file tmpfile_stdout 14:52:02 INFO - Output received: 14:52:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:52:02 INFO - Running post-action listener: _resource_record_post_action 14:52:02 INFO - [mozharness: 2017-05-24 21:52:02.488453Z] Finished install step (success) 14:52:02 INFO - [mozharness: 2017-05-24 21:52:02.488610Z] Running run-tests step. 14:52:02 INFO - Running pre-action listener: _resource_record_pre_action 14:52:02 INFO - Running main action method: run_tests 14:52:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:52:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:52:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:52:02 INFO - 2017-05-24 14:52:02.503 screenresolution[1629:7616] starting screenresolution argv=screenresolution get 14:52:02 INFO - 2017-05-24 14:52:02.506 screenresolution[1629:7616] Display 0: 1600x1200x32@60 14:52:02 INFO - 2017-05-24 14:52:02.512 screenresolution[1630:7619] starting screenresolution argv=screenresolution list 14:52:02 INFO - Available Modes on Display 0 14:52:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:52:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:52:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:52:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:52:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:52:02 INFO - Intel Iris: 14:52:02 INFO - Chipset Model: Intel Iris 14:52:02 INFO - Type: GPU 14:52:02 INFO - Bus: Built-In 14:52:02 INFO - VRAM (Dynamic, Max): 1536 MB 14:52:02 INFO - Vendor: Intel (0x8086) 14:52:02 INFO - Device ID: 0x0a2e 14:52:02 INFO - Revision ID: 0x0009 14:52:02 INFO - Displays: 14:52:02 INFO - Display: 14:52:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:52:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:52:02 INFO - Display Serial Number: 1600x1200 60 14:52:02 INFO - Main Display: Yes 14:52:02 INFO - Mirror: Off 14:52:02 INFO - Online: Yes 14:52:02 INFO - Rotation: Supported 14:52:02 INFO - Return code: 0 14:52:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:52:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:52:02 INFO - Python 2.7.3 14:52:02 INFO - Return code: 0 14:52:02 INFO - grabbing minidump binary from tooltool 14:52:02 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')]} 14:52:02 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 0x108e16d30>, '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 0x108dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e0bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:52:02 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 14:52:02 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 14:52:02 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 14:52:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:52:02 INFO - Return code: 0 14:52:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:52:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:52:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:52:02 INFO - ENV: RUST_BACKTRACE is now 1 14:52:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:52:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:52:02 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/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0078', '--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 14:52:02 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/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0078 --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 14:52:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TiupBQwEf/Render', 14:52:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:52:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:52:02 INFO - 'HOME': '/Users/cltbld', 14:52:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:52:02 INFO - 'LOGNAME': 'cltbld', 14:52:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:52:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:52:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:02 INFO - 'MOZ_NO_REMOTE': '1', 14:52:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:52:02 INFO - 'NO_EM_RESTART': '1', 14:52:02 INFO - 'PAGER': '/bin/cat', 14:52:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:52:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:02 INFO - 'PWD': '/builds/slave/test', 14:52:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:52:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:52:02 INFO - 'RUST_BACKTRACE': '1', 14:52:02 INFO - 'SHELL': '/bin/bash', 14:52:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dB2LZC7G6L/Listeners', 14:52:02 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 14:52:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:52:02 INFO - 'USER': 'cltbld', 14:52:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:52:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:52:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:02 INFO - 'XPC_FLAGS': '0x0', 14:52:02 INFO - 'XPC_SERVICE_NAME': '0', 14:52:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:52:02 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/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0078', '--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 14:52:03 INFO - mozversion application_buildid: 20170524133555 14:52:03 INFO - mozversion application_changeset: c575ecde53030c41c12ba228d8fe78c3ed896e76 14:52:03 INFO - mozversion application_display_name: Nightly 14:52:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:52:03 INFO - mozversion application_name: Firefox 14:52:03 INFO - mozversion application_remotingname: firefox 14:52:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:52:03 INFO - mozversion application_vendor: Mozilla 14:52:03 INFO - mozversion application_version: 55.0a1 14:52:03 INFO - mozversion platform_buildid: 20170524133555 14:52:03 INFO - mozversion platform_changeset: c575ecde53030c41c12ba228d8fe78c3ed896e76 14:52:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:52:03 INFO - mozversion platform_version: 55.0a1 14:52:03 INFO - using testdate: 1495662723 14:52:03 INFO - actual date: 1495662723 14:52:03 INFO - starting webserver on 'localhost:49230' 14:52:03 INFO - SUITE-START | Running 2 tests 14:52:03 INFO - TEST-START | dromaeo_css 14:52:03 INFO - operating with platform_type : mac_ 14:52:03 INFO - Initialising browser for dromaeo_css test... 14:52:03 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpPXCssr/profile 14:52:03 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 14:52:36 INFO - TEST-INFO | 1640: killed by SIGTERM 14:52:36 INFO - Browser initialized. 14:52:36 INFO - Running cycle 1/1 for dromaeo_css test... 14:52:36 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpPXCssr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:52:38 INFO - PID 1650 | 2017-05-24 14:52:38.464 plugin-container[1651:8348] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9843, name = 'com.apple.tsm.portname' 14:52:38 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:52:38 INFO - PID 1650 | 2017-05-24 14:52:38.464 plugin-container[1651:8348] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a07, name = 'com.apple.CFPasteboardClient' 14:52:38 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:52:38 INFO - PID 1650 | 2017-05-24 14:52:38.464 plugin-container[1651:8348] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:52:39 INFO - PID 1650 | 2017-05-24 14:52:39.192 plugin-container[1652:8392] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 14:52:39 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:52:39 INFO - PID 1650 | 2017-05-24 14:52:39.192 plugin-container[1652:8392] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x971f, name = 'com.apple.CFPasteboardClient' 14:52:39 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:52:39 INFO - PID 1650 | 2017-05-24 14:52:39.192 plugin-container[1652:8392] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:52:48 INFO - PID 1650 | RSS: Main: 246407168 14:52:48 INFO - PID 1650 | 14:55:50 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49230/tests/dromaeo/cssquery-ext.html) 14:55:52 INFO - PID 1650 | RSS: Main: 259850240 14:55:52 INFO - PID 1650 | 14:58:54 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-ext.html (next: http://localhost:49230/tests/dromaeo/cssquery-jquery.html) 14:58:55 INFO - PID 1650 | RSS: Main: 273817600 14:58:55 INFO - PID 1650 | 15:01:56 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49230/tests/dromaeo/cssquery-mootools.html) 15:01:57 INFO - PID 1650 | RSS: Main: 275222528 15:01:57 INFO - PID 1650 | 15:04:58 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49230/tests/dromaeo/cssquery-prototype.html) 15:04:59 INFO - PID 1650 | RSS: Main: 279662592 15:04:59 INFO - PID 1650 | 15:08:02 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49230/tests/dromaeo/cssquery-yui.html) 15:08:03 INFO - PID 1650 | RSS: Main: 279789568 15:08:03 INFO - PID 1650 | 15:11:04 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-yui.html (next: http://localhost:49230/tests/dromaeo/cssquery-dojo.html) 15:11:04 INFO - PID 1650 | RSS: Main: 281432064 15:11:04 INFO - PID 1650 | 15:11:04 INFO - PID 1650 | __start_tp_report 15:11:04 INFO - PID 1650 | _x_x_mozilla_page_load 15:11:04 INFO - PID 1650 | _x_x_mozilla_page_load_details 15:11:04 INFO - PID 1650 | |i|pagename|runs| 15:11:04 INFO - PID 1650 | |0;dojo.html;2904.535274356102;3527.7177825773924;3565.9480453266283;3615.493830863679;3616.6925811306037;6112.594670127751;6154.753809847606;6205.751769929208;6209.782657606985;6237.220347456568;5140.794368225276;6145.801568694106;6165.475934545569;6176.752929882799;6210.19267495225;4779.068081724048;5374.274472946161;5410.269613602152;5418.241446197536;5461.972690136564;5458.235846981426;5501.449855014506;5520.66875987442;5531.557475401958;5571.9442805572035;5325.547328477084;5402.567794576464;5443;5460.781568737246;5513;5390.757415916293;5414.6480478769145;5440.91838622421;5476.561875050026;5500.50495455411;4661;5409.513143817076;5423.430539793344;5440;5464.808731694411;6142.04798256271;6266.968665156691;6283.560150789401;6302.968485157545;6319.0837328587095;6374.266959299694;6501.382368675016;6526.314736952599;6620.37106474889;6651.135352404205;6749.3250674932115;6766.120404347404;6767.492438067165;6777.983302504615;6779.118714567075;6061.090836374579;6076.118962750376;6176.197094377747;6304.65324407162;6324.08300796382;6661.134052573135;6732.259451460335;6784.050232967388;6835.726570937107;6848.212455567574;6310.747570097236;6373.362663733682;6510.479161667055;6538.673066346663;6568.901466478007;6575.572587781779;6590.868182636416;6598.175228096488;6708.060871477997;6711.127553418024;6590.472762179014;6601.933980660132;6618.437432818265;6634.502412319095;6645.169353830771;6371.362863713684;6430.485561155097;6619.139511863427;6636.137302150786;6649.368310010541;5303.390110137307;5305.071612467803;5347.117725575236;5349.1976203569775;5391.245225668409;4783.904321913542;4788.066327066189;4813.879653008703;4849.345338379344;4877.512248775094;6197.752089916353;6238;6333.176687030748;6334.651594162273;6347.206599175103;6577.638229897306;6661.533692641468;6688.097106890507;6726.125603671491;6727.495437842182;6296.338884417162;6324.778632747832;6329.968350158219;6332.968335158294;6351.809445716636;5921.5558833086825;5936.910946335808;5946.316173640087;5946.732397042144;5964.671943043174;5420.674759514442;5492.725363731797;6798.388145066968;6866.931330686829;6870.862582748417;6763.256041835296;6778;6785.728570857111;6787.151606049244;6793.66031698423;6796.762113326207;6875.802751720474;6921.342472465108;6949.895751563629;6951.513394062367;6395.6482393468805;6461.063145843732;6502.382273683992;6622.304658010839;6635.23694775107;6693.129893114059;6706.93293067063;6729.629870357177;6755.628440435823;6769.593824370553;6256.31180570146;6512.804615861532;6518.902216466662;6528.37980391862;6529.347065293433;6349.015902535114;6415.069814876723;6497.610143391223;6523.608583485006;6524.478041756743;5222.503862133091;5393.487618676219;5857.82843431324;6403.263624683034;6432.549721519449;6534.281229064704;6562.5406221564035;6564.081028655896;6568.540202185801;6568.967155164385;4986.950130498793;6201.844953876189;6241.31345551998;6355.618662880242;6401.519886008475 15:11:04 INFO - PID 1650 | |1;ext.html;678.5419841606914;745.2547452547452;833.5665453963937;1215.246547140773;1278.3224890807876;39549.80225098874;40759.981000475;40779.796101019456;40791;40830.79584602077;40179.79910100453;40491;40600.79699601505;40659;40689.57586484474;7835.764927052198;8015.599220039004;8259.463134896212;8460.238578527931;8467.364947628921;7677.923220767804;8161.694128939371;8390.958045209765;8507.319414446829;8514.744557663249;8466.365022623328;8474.406791524596;8488.066312705598;8511.787205319855;8520.659173633047;8472;8486.490810551386;8492.108328625467;8498.235158835703;8523.445976011539;8286.544240066794;8323.875141872877;8342.833143337099;8346.749597512084;8364.58177091149;37812.24375512474;37848.62151378478;37875.67435139784;38061.429078563844;38137.42793858094;3568.2863427314383;3595.694365978895;3603.261331427051;3604.4953706481115;3608.891733248007;30981;31079.844600777076;31126.354259887936;31182.532262016088;31272;3032.7877048606606;3064.988553777248;3117.8129312241113;3132.9530057049164;3133.545635882784;246408;249524;267799.66100169375;270210;308038;48162.51837481651;48378.75810620995;48392.51607483951;48439.031219375414;48577.299794507024;50366.748166258934;50503;50544.74727626412;55315.61710957258;55932;14287.78568321476;14448.711025779425;14497.782533262009;14501.492447764278;14530.200838953959;11492.94253528727;11533.653990380302;11552.422378881023;11622.88377116235;11669.124815638861;11763.647090587296;14324.14055156694;14330.85669143316;14345.569632910821;14373.425062997363;5486.396496385394;6104.138237837502;6203.8138855834395;6243.843903902439;6281.623102613858;988.7429268390271;1011.9494025298706;1017.9032991865761;1048.7954848804563;1055.7413433708791;22530.873456327117;22923.197688080836;24719;24811.25566233016;24848.751512484643;1076.8653918260218;1077.7467295185625;1116.7040734205443;1159.976800463986;1170.3387586013876;11645.41772911368;12179.451924663412;12520.686982825502;12757.724227577168;12772.680682983;303747;308831;309031.4548427244;311665;314612.4269378639;57982.7100864493;58529.707351462974;58538.70730646405;58647.70676146592;58690;1472.2417954753312;1501.3619211834925;1502.684436268396;1512.19853477657;1517.590250632323;28040;29083;31129;31505.054848354586;31746.52380214345;39390;39581.01047473836;39656.40515392214;39668.83477234849;39698.40452393159;297915;298190.50904745335;299443;299923;300154;35458.29083418369;35485;35552;35603.465948010286;35619.46570801492;44321;44353.33469997888;45719.77140114278;46272.30591541197;46395.53604463912;29435.85282073576;30974.845125774227;31127.84436077805;31645.36709265848;32001;30879.919202827794;31098.844505777328;31301.8434907824;31671;31848.885289014772;36367.818160909024;36581.63418365782;37372;37490.062748431505;38117.42823857589;22894.771052289263;28660.713392866637;30700.692993070676;31386.843065784524;31718.207044824063;22680.319590412313;22942;23165.652515212623;24554.63168052445;25109.870055847165 15:11:04 INFO - PID 1650 | |2;jquery.html;404.5873209326489;427.93153095504726;438.24402904988887;452.5271091709164;453.03503537465195;21422.46443838903;21445.14219431223;22381.552368952653;22390.992405341756;22525.77474225257;19462.90268548655;19682.409527714157;22107.889460552717;23188.88405557974;23243.767562324414;6046.274447066358;6338.841528961767;6358.205224346957;6369.4585960193435;6374.458171055466;5956.612820166697;6235.06474028897;6239.188905442286;6302.0861975013595;6347.555671103025;5332.440093790155;6188.102725104857;6206.596571222856;6243.344448832875;6243.375662433766;4956.2565615157655;6055.878882422371;6174.475169610589;6230.781922632709;6231.937680623181;4796.256580230035;5501.944980550224;5510.614257002011;5533.612647114704;5538.141588053858;22357.552848943094;24810.255692329258;25462.618060729103;27836.860815695993;28669.41632295963;2093.2568938027084;2174.0542863854275;2179.193698331612;2185.0276626901036;2189.627763280246;17476.73784893215;17820;17898.821011789845;18120.546986325317;18200.544986375313;1932.7584051993501;2165.7509386420447;2171.457135716071;2217.889105544716;2224.088123869222;144913.55086449106;159524;160861;162223;163354;12498.625041248777;12853.550125745554;13088.803667944989;13113.541026064233;13129.146605470805;16684.416045438353;16686.165691715367;16694.165291735364;16742.74885876713;16810.327586896386;8784.824303513893;8924.107589241154;9024;9159.587818548182;9177.03641117673;8493.10822363643;8540.786480337916;8546.529940853312;8713.782155446037;8741.737747867573;7029.980652805315;7943.721969731032;7952.483088599222;8116.918830811616;8158.143394943564;2090.7491101067967;2237.474193564511;2241.7197850268717;2246.2362796649218;2394.413368724639;928.5218112671979;931.5714771205186;944.4805357053593;1009.5204777730667;1018.8777346718441;17184.742228866577;20597.691034634492;21129.943502824797;21260.468488287916;21392.465188370414;975.0298453039271;977.9608815647437;1003.8895721470633;1025.933314334566;1040.8022475729588;10696.946515267375;11023.283486573348;11054.613088541862;11085;11098.944505277583;532466;538820;540706;545413;546440;132635.37870038417;133312;135207;138369;138481.61518384688;1177.8763229860913;1211.357980270458;1239.9194052386747;1242.2546472116658;1246.993765031169;22462.550748985257;22740.886295568416;22827.31518054461;22910.54178916379;23028;58492.415075848694;58691.4130858686;59399.40600594111;59838.102428462735;60583.485412863265;63244;73430.63284683542;77455.22544774706;77838.61080694561;79100;8881.73354799357;8921.464712117293;8968.641254349754;9145.679901203692;9351.532423378667;14125.576232713034;14236.288185590885;14539.127652340894;14606.415743370571;14684.926575367055;11971.221870578385;12074.517019319128;12091.39543022863;12184.329861857328;12243.061665200466;9908.702738917844;10470.371777693363;11320.320780753182;11621.88378116208;11635.418229088342;11927.165098443025;12232.633021009384;12418.627441176779;12446;12566.246025238515;8952.865707014269;9037.096290371173;9097;9141.725748227564;9335.673251436167;11963.940180299043;12124.151309408257;12267.631971040882;12293.877061229274;12369.319687417279 15:11:04 INFO - PID 1650 | |3;mootools.html;1332.3804430939615;1339.7521458530166;1432.7606620273466;1436.5187662133178;1443.9494617688379;11011.779764404717;11966.76066478671;11983.041356691465;12183.878161218385;12284.63146105616;12009.639710808668;12080.637580872566;12216.93891530541;12220.144589878711;12244.387780610956;2512.849229046254;2536.6321883326905;2559.001989224195;2563.8718064096815;2567.6020216866414;2493.463905260369;2536.6195070739445;2538.010176031349;2542.694876614809;2545.872706364684;2188.365374041526;2512.1207577347964;2520;2521.621756736486;2528.064616092039;2143.871367717934;2461.4584791345965;2476.5170791695627;2493.7755601995727;2500.7999360051153;2447.5104979004273;2472.147109247325;2485.962710559343;2490.9626355604682;2556.041484443334;11744.236624619372;11938.761224775542;11953.701157471045;11955.521779128825;11963.282203067844;6452.7741529046725;6705.932940670628;6712.966435167842;6722.932770672329;6726.293739157416;4022.5172979242384;4226.535081141072;4294.549072347421;4306.600354884675;4322.243607368698;1037.3723897042332;1220.8412906322121;1228.1464382254337;1228.2937311046185;1241.8199361092682;15203.771943420857;15630.140342281284;16352.591185220346;16534.007959522467;16693.081880496567;4975.402951645826;5488.984537860509;5492.395836457987;5686.943130568724;5700.458456446631;11820.995215406787;11982.88017119835;11989.700257493632;12003.579874704517;12005.159638825198;6367.108604795332;6457.677116144174;6464.127342808754;6473.773417930444;6477.514186435942;5084.720340381315;6007.27912650476;6138.815835524942;6161.537884658668;6180.412860778219;4349.608125399842;4388.2320593896575;4440.111977604492;4469.977650111794;4476.1495315890525;1255.3597665190819;1275.948962041508;1278.4758249117817;1289.864564220762;1292.7349893271858;768.1358471719316;779.3842864137381;803.7669075967904;804.6580203413525;806.5201205282847;5769.8557536061935;6649.501287403464;7009.544379615309;7024.578525288499;7040.577565346096;906.3338446242007;942.721897040361;942.7831598732192;947.7726344384039;948.7106432538004;4172.603602657743;4368.694191406571;4388.639521748269;4490.079533695584;4504.932426013613;11283.66149015531;14659.973801833768;15026.248687565578;15154.696906061818;15322.770158447409;14953.626159345886;15154.848451515565;15167.696646067017;15173.772393414107;15219.847801522064;8456.83086338282;8545.2309292164;8605.35459840527;8618.052014278424;8632;6141.692915354125;6311.96844015777;6796.76211332601;7005.929940700527;7018.368346848808;12596.055295852664;12693.4287957044;12787.61637150887;12826.871731282941;12873.22760634365;13285.535006274735;13733.587992360246;13791.931040344734;13970.301484925916;14196.503122390666;9276.768080798034;9414.246860251027;9455.385399948982;9471.810563788547;9483.715488535356;9648.421094734338;10155.441450720282;10621.681349559525;10768.515416806105;10809.297395669257;9157.542122893696;9162.725118246462;9206.769830754285;9309.255259579353;9394.107559781809;8970.461772293684;8971.91028089737;8985.146411090936;8998.595063222301;9031.63873445055;7026.754063607749;8298.21166989135;8367.954005749281;8373.288270497076;8384.203500667427;5648.745806438638;5871.266091738533;5954.851128721816;6033.276006879202;6230.813075607739;5706.942930570641;5834.970825145847;6032.849178770566;6226.470749986302;6320.715567799368 15:11:04 INFO - PID 1650 | |4;prototype.html;377.3377722097718;379.40433519374574;399.3769719237989;405.74843596969885;411.4712594316304;5918.3489816120245;5960.4337587929185;6292.653904035276;6472.087560597733;6493.253275873264;5036.34527511423;6200;6294.402031806982;6296.811095667137;6313.305536391004;3843.8270277837564;4544.500104988449;4557.39965427312;4562.832654489108;4578.175928332898;4128.339465685491;4137.441445404877;4313.835264478589;4544.500104988449;4864.270359446076;4784.880377990543;4799.784009719571;4820.710757354553;4846.442659094197;4848.733319667417;4191.3293872980175;4783.7129772213775;4823.927641085386;4826.444958829745;4827.589654879322;4203.864956461738;4283.336082907154;4324.091940692459;4332.176886391576;4346.630536404409;5058.721770302632;5994.250718660168;6111.633302001894;6136.509079273647;6174.475169610589;5608.327000759935;5762.538996880239;5771.220885180489;5776.33572139201;5782.797602083907;5677.801276955287;5943.435373639541;6034.758609655609;6186.04093380237;6678;4937.790241390882;4953.5541801237705;4958;4958.404991401019;4961.454240033593;5658.264425624684;6754.257031726506;7084.291570842926;7164.426845852319;7307.953772943584;4759;4772.928406073912;4779.880502987384;4786.95213047865;4814.735189564608;3892.727509074394;4196.727212731163;4311.8706438806885;4860.805567777249;4861.368022157097;4963.627727920348;5551.9722401387735;5555.833325000256;5570.944290557123;5612.775488980476;5649.022842005265;5948.881022379528;6793.694283757242;6796.422304104207;6821.863562728718;1532.3947040918774;1765.0821572782338;1769.637224369027;1775.529484686559;1775.8133824432166;1207.2394391533278;1211.927284362941;1239.908880185449;1266.9619911402672;1267.2903174222465;5638.802641907513;5697.572682048863;6282.968585157137;6346;6426.614403135827;5617.662940223599;6224;6468.6442245676;7086.539374940716;7092.4680648951535;5771.961047011579;6104.4200800923845;6338.302786693553;6340.498246245125;6405.03924411342;3641.3809652359164;3664.5052917856283;3670.7063434925144;3689.505750171185;3794.6395092466173;5953.719950210725;6914.239433662293;7164.641767911688;7273.23631018746;7296;5554.472325129106;6961.37347638715;7177.391964517628;7275.726747819111;7288.744893928687;6228.166184386094;6878.449724022066;7006.894896576556;7204.171520275236;7211.567305961764;5882.970375220542;6984.650767461505;7024.4029257513685;7033.472489563405;7066.222715501188;6307.96846015767;6493.610383377012;6707.0488984846515;6717.488565072819;6953.922142067988;5854.882902342015;6643.734250629921;6701.664916754045;7028.262032486516;7216.005357776464;4158.293090174738;4362.25841606934;4509.503954565061;4511.255642818898;4530.161920044802;5919.4968427684125;6467.676616169267;6976;6980.755673551402;6991.615461149482;6583.275839657729;6748.392644662004;6749.4600431964345;6771.424428923597;6839.93160068393;6368.745250189941;6716.1604799400075;6739.460843132636;6746.684396542627;6784.7964561063245;5624.240727501735;6101.9389806103145;6660.46902177189;6672.766453174309;6687.057694614354;6577.375149360803;6677.732890684319;6764;6771;6781.254062053072;5454.509094181542;6232.220972378453;6586.275509693834;6603.702833372606;6611.942089265694 15:11:04 INFO - PID 1650 | |5;yui.html;792.7859477940959;826.2109685250582;838.656150978099;856.3063918226239;856.5289090999948;13702.794458083124;16364.999999999985;16814.579635509104;18113.456596302098;19516.609667806635;17428.912855435738;18034.458966231035;18254.81745182551;18267.72598411025;18267.90866045668;789.6288744290174;943.9952800235989;952.2096659772407;954.9045095490465;957.5882370580648;905.1762895764855;994.1599348550463;1003.8393856982884;1023.3143793658267;1025.1491262252312;1057.9576816927313;1070.9571617135305;1073.8335557988485;1077.9407132607703;1078.3152698036724;1044.9320794148393;1053.6101642392287;1066.7546464313252;1068.0654427376007;1069.887661795512;788.9723859664941;795.8686816675241;797.4178849439942;797.752696664036;797.972070977513;16756.9945803251;16851.73611979094;16946.745798813026;16997.745033824503;17036.14819259032;17789.377371791925;17822;18085;18141.183646735928;18219.72670409945;14743.77884331736;14860.108393496424;14897.255137243203;15004.174770387624;15023.699526009419;3484.5992710838323;3564.8752293669854;3587.820608969541;3596.946045809289;3604.1530240393486;131942.680573194;135503;145384;149818;150244;13240.801387979189;13521.256330901699;13709.245991470365;13764.17414955106;13847.169169849842;25700.74299257021;26331.473370532865;26626.334341641228;26637.200883973514;26684.733152668614;6453.580517266457;7002.649867506604;7692.92307076922;7928.960355198187;8014.398920081017;3965.1673148638533;4042.8584999524874;4049.0889549851636;4054.8986275342763;4059.6955228357992;4198.538160802309;4250.744955302692;4275.273203555341;4292.527821939584;4359.738415695068;951.191487235844;962.3888830592624;964.81668482988;965.7199412170532;970.3789574672215;610.1671218786324;637.512303088138;674.5210900260772;679.683946964654;686.821426429132;10340.948295258475;11124.165687573464;11543.942280288546;12255.387230638433;12326.81509777354;628.9591176573508;653.5555822040963;662.4766434516774;667.2693400726197;677.7831094049956;5277.683338999673;5743.827685169452;5758.424157584292;5764.452377024176;5766.798162064308;137571.95420891696;139182;140164;142294.28852855877;142720;120241;121622;122569;123095.76904231022;125384;102083.48958255161;102484;102559;103279.4836025815;104647.99999999847;11114.610988615359;11570.537178513101;11578.884211158118;11582.07343412508;11783.646490605295;42002.78998604987;42252.78873605735;42255.1548969025;42270;42288;54258;55333.33999980007;55533.44466555283;55533.72233138808;56141.71929140328;14060.578182654537;17109.230084645973;17199.828001719823;17851.821481785017;18027.819721802614;18893.055347232308;19220.423387298404;19222.8077719221;19326.613467730847;19453.4163975081;17591.82408175902;17826.643467130325;18310.176042077874;18432.907835460737;18450;17102.315907363565;17407.86848854871;17990.19044143043;18033.909830450764;18113;16753.32986680519;17456.214470349118;17646.294148233927;17711.82288177153;17712.025838579;11460.484278207334;11734.94132529332;11997.400129993292;12407.379631018232;12431.875681243071;11678.6496405108;13291.268980206181;13338.733225335633;13588.777010068747;14298.285085745463 15:11:04 INFO - PID 1650 | __end_tp_report 15:11:04 INFO - PID 1650 | __start_cc_report 15:11:04 INFO - PID 1650 | _x_x_mozilla_cycle_collect,401 15:11:04 INFO - PID 1650 | __end_cc_report 15:11:04 INFO - PID 1650 | __startTimestamp1495663864932__endTimestamp 15:11:04 INFO - PID 1650 | 15:11:04 INFO - PID 1650 | ------- Summary: start ------- 15:11:04 INFO - PID 1650 | Number of tests: 6 15:11:04 INFO - PID 1650 | 15:11:04 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6101.61 Median:6334.65 stddev:741.62 (11.7%) stddev-sans-first:700.20 15:11:04 INFO - PID 1650 | Values: 2904.5 3527.7 3565.9 3615.5 3616.7 6112.6 6154.8 6205.8 6209.8 6237.2 5140.8 6145.8 6165.5 6176.8 6210.2 4779.1 5374.3 5410.3 5418.2 5462.0 5458.2 5501.4 5520.7 5531.6 5571.9 5325.5 5402.6 5443.0 5460.8 5513.0 5390.8 5414.6 5440.9 5476.6 5500.5 4661.0 5409.5 5423.4 5440.0 5464.8 6142.0 6267.0 6283.6 6303.0 6319.1 6374.3 6501.4 6526.3 6620.4 6651.1 6749.3 6766.1 6767.5 6778.0 6779.1 6061.1 6076.1 6176.2 6304.7 6324.1 6661.1 6732.3 6784.1 6835.7 6848.2 6310.7 6373.4 6510.5 6538.7 6568.9 6575.6 6590.9 6598.2 6708.1 6711.1 6590.5 6601.9 6618.4 6634.5 6645.2 6371.4 6430.5 6619.1 6636.1 6649.4 5303.4 5305.1 5347.1 5349.2 5391.2 4783.9 4788.1 4813.9 4849.3 4877.5 6197.8 6238.0 6333.2 6334.7 6347.2 6577.6 6661.5 6688.1 6726.1 6727.5 6296.3 6324.8 6330.0 6333.0 6351.8 5921.6 5936.9 5946.3 5946.7 5964.7 5420.7 5492.7 6798.4 6866.9 6870.9 6763.3 6778.0 6785.7 6787.2 6793.7 6796.8 6875.8 6921.3 6949.9 6951.5 6395.6 6461.1 6502.4 6622.3 6635.2 6693.1 6706.9 6729.6 6755.6 6769.6 6256.3 6512.8 6518.9 6528.4 6529.3 6349.0 6415.1 6497.6 6523.6 6524.5 5222.5 5393.5 5857.8 6403.3 6432.5 6534.3 6562.5 6564.1 6568.5 6569.0 4987.0 6201.8 6241.3 6355.6 6401.5 15:11:04 INFO - PID 1650 | 15:11:04 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:45143.59 Median:33729.65 stddev:76761.52 (227.6%) stddev-sans-first:76904.25 15:11:04 INFO - PID 1650 | Values: 678.5 745.3 833.6 1215.2 1278.3 39549.8 40760.0 40779.8 40791.0 40830.8 40179.8 40491.0 40600.8 40659.0 40689.6 7835.8 8015.6 8259.5 8460.2 8467.4 7677.9 8161.7 8391.0 8507.3 8514.7 8466.4 8474.4 8488.1 8511.8 8520.7 8472.0 8486.5 8492.1 8498.2 8523.4 8286.5 8323.9 8342.8 8346.7 8364.6 37812.2 37848.6 37875.7 38061.4 38137.4 3568.3 3595.7 3603.3 3604.5 3608.9 30981.0 31079.8 31126.4 31182.5 31272.0 3032.8 3065.0 3117.8 3133.0 3133.5 246408.0 249524.0 267799.7 270210.0 308038.0 48162.5 48378.8 48392.5 48439.0 48577.3 50366.7 50503.0 50544.7 55315.6 55932.0 14287.8 14448.7 14497.8 14501.5 14530.2 11492.9 11533.7 11552.4 11622.9 11669.1 11763.6 14324.1 14330.9 14345.6 14373.4 5486.4 6104.1 6203.8 6243.8 6281.6 988.7 1011.9 1017.9 1048.8 1055.7 22530.9 22923.2 24719.0 24811.3 24848.8 1076.9 1077.7 1116.7 1160.0 1170.3 11645.4 12179.5 12520.7 12757.7 12772.7 303747.0 308831.0 309031.5 311665.0 314612.4 57982.7 58529.7 58538.7 58647.7 58690.0 1472.2 1501.4 1502.7 1512.2 1517.6 28040.0 29083.0 31129.0 31505.1 31746.5 39390.0 39581.0 39656.4 39668.8 39698.4 297915.0 298190.5 299443.0 299923.0 300154.0 35458.3 35485.0 35552.0 35603.5 35619.5 44321.0 44353.3 45719.8 46272.3 46395.5 29435.9 30974.8 31127.8 31645.4 32001.0 30879.9 31098.8 31301.8 31671.0 31848.9 36367.8 36581.6 37372.0 37490.1 38117.4 22894.8 28660.7 30700.7 31386.8 31718.2 22680.3 22942.0 23165.7 24554.6 25109.9 15:11:04 INFO - PID 1650 | 15:11:04 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:35693.16 Median:12354.39 stddev:92138.73 (745.8%) stddev-sans-first:92359.11 15:11:04 INFO - PID 1650 | Values: 404.6 427.9 438.2 452.5 453.0 21422.5 21445.1 22381.6 22391.0 22525.8 19462.9 19682.4 22107.9 23188.9 23243.8 6046.3 6338.8 6358.2 6369.5 6374.5 5956.6 6235.1 6239.2 6302.1 6347.6 5332.4 6188.1 6206.6 6243.3 6243.4 4956.3 6055.9 6174.5 6230.8 6231.9 4796.3 5501.9 5510.6 5533.6 5538.1 22357.6 24810.3 25462.6 27836.9 28669.4 2093.3 2174.1 2179.2 2185.0 2189.6 17476.7 17820.0 17898.8 18120.5 18200.5 1932.8 2165.8 2171.5 2217.9 2224.1 144913.6 159524.0 160861.0 162223.0 163354.0 12498.6 12853.6 13088.8 13113.5 13129.1 16684.4 16686.2 16694.2 16742.7 16810.3 8784.8 8924.1 9024.0 9159.6 9177.0 8493.1 8540.8 8546.5 8713.8 8741.7 7030.0 7943.7 7952.5 8116.9 8158.1 2090.7 2237.5 2241.7 2246.2 2394.4 928.5 931.6 944.5 1009.5 1018.9 17184.7 20597.7 21129.9 21260.5 21392.5 975.0 978.0 1003.9 1025.9 1040.8 10696.9 11023.3 11054.6 11085.0 11098.9 532466.0 538820.0 540706.0 545413.0 546440.0 132635.4 133312.0 135207.0 138369.0 138481.6 1177.9 1211.4 1239.9 1242.3 1247.0 22462.6 22740.9 22827.3 22910.5 23028.0 58492.4 58691.4 59399.4 59838.1 60583.5 63244.0 73430.6 77455.2 77838.6 79100.0 8881.7 8921.5 8968.6 9145.7 9351.5 14125.6 14236.3 14539.1 14606.4 14684.9 11971.2 12074.5 12091.4 12184.3 12243.1 9908.7 10470.4 11320.3 11621.9 11635.4 11927.2 12232.6 12418.6 12446.0 12566.2 8952.9 9037.1 9097.0 9141.7 9335.7 11963.9 12124.2 12267.6 12293.9 12369.3 15:11:04 INFO - PID 1650 | 15:11:04 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:7099.76 Median:4199.57 stddev:4473.84 (106.5%) stddev-sans-first:4465.40 15:11:04 INFO - PID 1650 | Values: 1332.4 1339.8 1432.8 1436.5 1443.9 11011.8 11966.8 11983.0 12183.9 12284.6 12009.6 12080.6 12216.9 12220.1 12244.4 2512.8 2536.6 2559.0 2563.9 2567.6 2493.5 2536.6 2538.0 2542.7 2545.9 2188.4 2512.1 2520.0 2521.6 2528.1 2143.9 2461.5 2476.5 2493.8 2500.8 2447.5 2472.1 2486.0 2491.0 2556.0 11744.2 11938.8 11953.7 11955.5 11963.3 6452.8 6705.9 6713.0 6722.9 6726.3 4022.5 4226.5 4294.5 4306.6 4322.2 1037.4 1220.8 1228.1 1228.3 1241.8 15203.8 15630.1 16352.6 16534.0 16693.1 4975.4 5489.0 5492.4 5686.9 5700.5 11821.0 11982.9 11989.7 12003.6 12005.2 6367.1 6457.7 6464.1 6473.8 6477.5 5084.7 6007.3 6138.8 6161.5 6180.4 4349.6 4388.2 4440.1 4470.0 4476.1 1255.4 1275.9 1278.5 1289.9 1292.7 768.1 779.4 803.8 804.7 806.5 5769.9 6649.5 7009.5 7024.6 7040.6 906.3 942.7 942.8 947.8 948.7 4172.6 4368.7 4388.6 4490.1 4504.9 11283.7 14660.0 15026.2 15154.7 15322.8 14953.6 15154.8 15167.7 15173.8 15219.8 8456.8 8545.2 8605.4 8618.1 8632.0 6141.7 6312.0 6796.8 7005.9 7018.4 12596.1 12693.4 12787.6 12826.9 12873.2 13285.5 13733.6 13791.9 13970.3 14196.5 9276.8 9414.2 9455.4 9471.8 9483.7 9648.4 10155.4 10621.7 10768.5 10809.3 9157.5 9162.7 9206.8 9309.3 9394.1 8970.5 8971.9 8985.1 8998.6 9031.6 7026.8 8298.2 8368.0 8373.3 8384.2 5648.7 5871.3 5954.9 6033.3 6230.8 5706.9 5835.0 6032.8 6226.5 6320.7 15:11:04 INFO - PID 1650 | 15:11:04 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5441.60 Median:5931.47 stddev:1644.03 (27.7%) stddev-sans-first:1604.10 15:11:04 INFO - PID 1650 | Values: 377.3 379.4 399.4 405.7 411.5 5918.3 5960.4 6292.7 6472.1 6493.3 5036.3 6200.0 6294.4 6296.8 6313.3 3843.8 4544.5 4557.4 4562.8 4578.2 4128.3 4137.4 4313.8 4544.5 4864.3 4784.9 4799.8 4820.7 4846.4 4848.7 4191.3 4783.7 4823.9 4826.4 4827.6 4203.9 4283.3 4324.1 4332.2 4346.6 5058.7 5994.3 6111.6 6136.5 6174.5 5608.3 5762.5 5771.2 5776.3 5782.8 5677.8 5943.4 6034.8 6186.0 6678.0 4937.8 4953.6 4958.0 4958.4 4961.5 5658.3 6754.3 7084.3 7164.4 7308.0 4759.0 4772.9 4779.9 4787.0 4814.7 3892.7 4196.7 4311.9 4860.8 4861.4 4963.6 5552.0 5555.8 5570.9 5612.8 5649.0 5948.9 6793.7 6796.4 6821.9 1532.4 1765.1 1769.6 1775.5 1775.8 1207.2 1211.9 1239.9 1267.0 1267.3 5638.8 5697.6 6283.0 6346.0 6426.6 5617.7 6224.0 6468.6 7086.5 7092.5 5772.0 6104.4 6338.3 6340.5 6405.0 3641.4 3664.5 3670.7 3689.5 3794.6 5953.7 6914.2 7164.6 7273.2 7296.0 5554.5 6961.4 7177.4 7275.7 7288.7 6228.2 6878.4 7006.9 7204.2 7211.6 5883.0 6984.7 7024.4 7033.5 7066.2 6308.0 6493.6 6707.0 6717.5 6953.9 5854.9 6643.7 6701.7 7028.3 7216.0 4158.3 4362.3 4509.5 4511.3 4530.2 5919.5 6467.7 6976.0 6980.8 6991.6 6583.3 6748.4 6749.5 6771.4 6839.9 6368.7 6716.2 6739.5 6746.7 6784.8 5624.2 6101.9 6660.5 6672.8 6687.1 6577.4 6677.7 6764.0 6771.0 6781.3 5454.5 6232.2 6586.3 6603.7 6611.9 15:11:04 INFO - PID 1650 | 15:11:04 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:25042.01 Median:18059.73 stddev:38357.47 (212.4%) stddev-sans-first:38421.86 15:11:04 INFO - PID 1650 | Values: 792.8 826.2 838.7 856.3 856.5 13702.8 16365.0 16814.6 18113.5 19516.6 17428.9 18034.5 18254.8 18267.7 18267.9 789.6 944.0 952.2 954.9 957.6 905.2 994.2 1003.8 1023.3 1025.1 1058.0 1071.0 1073.8 1077.9 1078.3 1044.9 1053.6 1066.8 1068.1 1069.9 789.0 795.9 797.4 797.8 798.0 16757.0 16851.7 16946.7 16997.7 17036.1 17789.4 17822.0 18085.0 18141.2 18219.7 14743.8 14860.1 14897.3 15004.2 15023.7 3484.6 3564.9 3587.8 3596.9 3604.2 131942.7 135503.0 145384.0 149818.0 150244.0 13240.8 13521.3 13709.2 13764.2 13847.2 25700.7 26331.5 26626.3 26637.2 26684.7 6453.6 7002.6 7692.9 7929.0 8014.4 3965.2 4042.9 4049.1 4054.9 4059.7 4198.5 4250.7 4275.3 4292.5 4359.7 951.2 962.4 964.8 965.7 970.4 610.2 637.5 674.5 679.7 686.8 10340.9 11124.2 11543.9 12255.4 12326.8 629.0 653.6 662.5 667.3 677.8 5277.7 5743.8 5758.4 5764.5 5766.8 137572.0 139182.0 140164.0 142294.3 142720.0 120241.0 121622.0 122569.0 123095.8 125384.0 102083.5 102484.0 102559.0 103279.5 104648.0 11114.6 11570.5 11578.9 11582.1 11783.6 42002.8 42252.8 42255.2 42270.0 42288.0 54258.0 55333.3 55533.4 55533.7 56141.7 14060.6 17109.2 17199.8 17851.8 18027.8 18893.1 19220.4 19222.8 19326.6 19453.4 17591.8 17826.6 18310.2 18432.9 18450.0 17102.3 17407.9 17990.2 18033.9 18113.0 16753.3 17456.2 17646.3 17711.8 17712.0 11460.5 11734.9 11997.4 12407.4 12431.9 11678.6 13291.3 13338.7 13588.8 14298.3 15:11:04 INFO - PID 1650 | -------- Summary: end -------- 15:11:04 INFO - PID 1650 | 15:11:05 INFO - TEST-INFO | 1650: exit 0 15:11:05 INFO - TEST-OK | dromaeo_css | took 1142278ms 15:11:05 INFO - TEST-START | kraken 15:11:05 INFO - operating with platform_type : mac_ 15:11:05 INFO - Initialising browser for kraken test... 15:11:05 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpNesh8_/profile 15:11:05 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 15:11:38 INFO - TEST-INFO | 1660: killed by SIGTERM 15:11:38 INFO - Browser initialized. 15:11:38 INFO - Running cycle 1/1 for kraken test... 15:11:38 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpNesh8_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:11:40 INFO - PID 1664 | 2017-05-24 15:11:40.873 plugin-container[1665:11055] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 15:11:40 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:11:40 INFO - PID 1664 | 2017-05-24 15:11:40.874 plugin-container[1665:11055] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d13, name = 'com.apple.CFPasteboardClient' 15:11:40 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:11:40 INFO - PID 1664 | 2017-05-24 15:11:40.874 plugin-container[1665:11055] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:11:41 INFO - PID 1664 | 2017-05-24 15:11:41.618 plugin-container[1666:11101] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x483f, name = 'com.apple.tsm.portname' 15:11:41 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:11:41 INFO - PID 1664 | 2017-05-24 15:11:41.619 plugin-container[1666:11101] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 15:11:41 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:11:41 INFO - PID 1664 | 2017-05-24 15:11:41.619 plugin-container[1666:11101] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:11:50 INFO - PID 1664 | RSS: Main: 246374400 15:11:50 INFO - PID 1664 | 15:11:53 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection) 15:11:54 INFO - PID 1664 | RSS: Main: 260173824 15:11:54 INFO - PID 1664 | 15:11:57 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-dft) 15:11:57 INFO - PID 1664 | RSS: Main: 269189120 15:11:57 INFO - PID 1664 | 15:12:01 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-fft) 15:12:01 INFO - PID 1664 | RSS: Main: 272195584 15:12:01 INFO - PID 1664 | 15:12:03 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator) 15:12:04 INFO - PID 1664 | RSS: Main: 274681856 15:12:04 INFO - PID 1664 | 15:12:06 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:12:06 INFO - PID 1664 | RSS: Main: 274632704 15:12:06 INFO - PID 1664 | 15:12:09 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom) 15:12:10 INFO - PID 1664 | RSS: Main: 279838720 15:12:10 INFO - PID 1664 | 15:12:13 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate) 15:12:14 INFO - PID 1664 | RSS: Main: 283156480 15:12:14 INFO - PID 1664 | 15:12:17 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial) 15:12:17 INFO - PID 1664 | RSS: Main: 282071040 15:12:17 INFO - PID 1664 | 15:12:19 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:12:20 INFO - PID 1664 | RSS: Main: 281706496 15:12:20 INFO - PID 1664 | 15:12:21 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:12:22 INFO - PID 1664 | RSS: Main: 281460736 15:12:22 INFO - PID 1664 | 15:12:24 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:12:25 INFO - PID 1664 | RSS: Main: 281645056 15:12:25 INFO - PID 1664 | 15:12:28 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:12:28 INFO - PID 1664 | RSS: Main: 281190400 15:12:28 INFO - PID 1664 | 15:12:31 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:12:32 INFO - PID 1664 | RSS: Main: 281436160 15:12:32 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49230/tests/kraken/driver.html?testName=ai-astar) 15:12:34 INFO - PID 1664 | RSS: Main: 283303936 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | __start_tp_report 15:12:34 INFO - PID 1664 | _x_x_mozilla_page_load 15:12:34 INFO - PID 1664 | _x_x_mozilla_page_load_details 15:12:34 INFO - PID 1664 | |i|pagename|runs| 15:12:34 INFO - PID 1664 | |0;ai-astar;103;119;120;134;119;115;116;115;116;115 15:12:34 INFO - PID 1664 | |1;audio-beat-detection;129;99;137;178;137;145;110;113;135;111 15:12:34 INFO - PID 1664 | |2;audio-dft;201;195;195;209;215;207;214;201;203;195 15:12:34 INFO - PID 1664 | |3;audio-fft;81;58;57;86;52;86;51;53;90;55 15:12:34 INFO - PID 1664 | |4;audio-oscillator;85;91;113;99;98;86;88;71;71;88 15:12:34 INFO - PID 1664 | |5;imaging-gaussian-blur;90;92;89;96;130;96;105;100;90;90 15:12:34 INFO - PID 1664 | |6;imaging-darkroom;97;97;97;96;97;98;98;97;97;101 15:12:34 INFO - PID 1664 | |7;imaging-desaturate;92;95;123;131;93;80;145;139;95;92 15:12:34 INFO - PID 1664 | |8;json-parse-financial;51;50;51;53;49;52;52;50;50;51 15:12:34 INFO - PID 1664 | |9;json-stringify-tinderbox;54;54;57;64;65;58;52;56;58;56 15:12:34 INFO - PID 1664 | |10;stanford-crypto-aes;83;88;86;86;87;86;85;86;121;86 15:12:34 INFO - PID 1664 | |11;stanford-crypto-ccm;142;137;139;134;139;165;132;145;141;156 15:12:34 INFO - PID 1664 | |12;stanford-crypto-pbkdf2;180;184;187;182;185;189;188;188;187;216 15:12:34 INFO - PID 1664 | |13;stanford-crypto-sha256-iterative;70;65;67;68;67;68;66;70;63;67 15:12:34 INFO - PID 1664 | __end_tp_report 15:12:34 INFO - PID 1664 | __start_cc_report 15:12:34 INFO - PID 1664 | _x_x_mozilla_cycle_collect,1073 15:12:34 INFO - PID 1664 | __end_cc_report 15:12:34 INFO - PID 1664 | __startTimestamp1495663954461__endTimestamp 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | ------- Summary: start ------- 15:12:34 INFO - PID 1664 | Number of tests: 14 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | [#0] ai-astar Cycles:10 Average:117.20 Median:117.50 stddev:7.57 (6.4%) stddev-sans-first:6.04 15:12:34 INFO - PID 1664 | Values: 103.0 119.0 120.0 134.0 119.0 115.0 116.0 115.0 116.0 115.0 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:137.00 stddev:22.76 (16.6%) stddev-sans-first:24.14 15:12:34 INFO - PID 1664 | Values: 129.0 99.0 137.0 178.0 137.0 145.0 110.0 113.0 135.0 111.0 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | [#2] audio-dft Cycles:10 Average:203.50 Median:205.00 stddev:7.56 (3.7%) stddev-sans-first:7.97 15:12:34 INFO - PID 1664 | Values: 201.0 195.0 195.0 209.0 215.0 207.0 214.0 201.0 203.0 195.0 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | [#3] audio-fft Cycles:10 Average:66.90 Median:69.50 stddev:16.50 (23.7%) stddev-sans-first:16.69 15:12:34 INFO - PID 1664 | Values: 81.0 58.0 57.0 86.0 52.0 86.0 51.0 53.0 90.0 55.0 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:88.00 stddev:12.63 (14.4%) stddev-sans-first:13.31 15:12:34 INFO - PID 1664 | Values: 85.0 91.0 113.0 99.0 98.0 86.0 88.0 71.0 71.0 88.0 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:97.80 Median:90.00 stddev:12.44 (13.8%) stddev-sans-first:12.87 15:12:34 INFO - PID 1664 | Values: 90.0 92.0 89.0 96.0 130.0 96.0 105.0 100.0 90.0 90.0 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | [#6] imaging-darkroom Cycles:10 Average:97.50 Median:97.00 stddev:1.35 (1.4%) stddev-sans-first:1.42 15:12:34 INFO - PID 1664 | Values: 97.0 97.0 97.0 96.0 97.0 98.0 98.0 97.0 97.0 101.0 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | [#7] imaging-desaturate Cycles:10 Average:108.50 Median:92.00 stddev:23.43 (25.5%) stddev-sans-first:24.08 15:12:34 INFO - PID 1664 | Values: 92.0 95.0 123.0 131.0 93.0 80.0 145.0 139.0 95.0 92.0 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:1.20 (2.3%) stddev-sans-first:1.27 15:12:34 INFO - PID 1664 | Values: 51.0 50.0 51.0 53.0 49.0 52.0 52.0 50.0 50.0 51.0 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:57.40 Median:57.50 stddev:4.20 (7.3%) stddev-sans-first:4.27 15:12:34 INFO - PID 1664 | Values: 54.0 54.0 57.0 64.0 65.0 58.0 52.0 56.0 58.0 56.0 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:86.00 stddev:11.18 (13.0%) stddev-sans-first:11.61 15:12:34 INFO - PID 1664 | Values: 83.0 88.0 86.0 86.0 87.0 86.0 85.0 86.0 121.0 86.0 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:143.00 Median:141.50 stddev:10.18 (7.2%) stddev-sans-first:10.79 15:12:34 INFO - PID 1664 | Values: 142.0 137.0 139.0 134.0 139.0 165.0 132.0 145.0 141.0 156.0 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.60 Median:187.50 stddev:10.05 (5.4%) stddev-sans-first:10.16 15:12:34 INFO - PID 1664 | Values: 180.0 184.0 187.0 182.0 185.0 189.0 188.0 188.0 187.0 216.0 15:12:34 INFO - PID 1664 | 15:12:34 INFO - PID 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:2.13 (3.2%) stddev-sans-first:1.99 15:12:34 INFO - PID 1664 | Values: 70.0 65.0 67.0 68.0 67.0 68.0 66.0 70.0 63.0 67.0 15:12:34 INFO - PID 1664 | -------- Summary: end -------- 15:12:34 INFO - PID 1664 | 15:12:35 INFO - TEST-INFO | 1664: exit 0 15:12:35 INFO - TEST-OK | kraken | took 89803ms 15:12:35 INFO - SUITE-END | took 1232s 15:12:35 INFO - Completed test suite (00:20:32) 15:12:35 INFO - javascript benchmark 15:12:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8015.654665666512, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2904.535274356102, 3527.7177825773924, 3565.9480453266283, 3615.493830863679, 3616.6925811306037, 6112.594670127751, 6154.753809847606, 6205.751769929208, 6209.782657606985, 6237.220347456568, 5140.794368225276, 6145.801568694106, 6165.475934545569, 6176.752929882799, 6210.19267495225, 4779.068081724048, 5374.274472946161, 5410.269613602152, 5418.241446197536, 5461.972690136564, 5458.235846981426, 5501.449855014506, 5520.66875987442, 5531.557475401958, 5571.9442805572035, 5325.547328477084, 5402.567794576464, 5443.0, 5460.781568737246, 5513.0, 5390.757415916293, 5414.6480478769145, 5440.91838622421, 5476.561875050026, 5500.50495455411, 4661.0, 5409.513143817076, 5423.430539793344, 5440.0, 5464.808731694411, 6142.04798256271, 6266.968665156691, 6283.560150789401, 6302.968485157545, 6319.0837328587095, 6374.266959299694, 6501.382368675016, 6526.314736952599, 6620.37106474889, 6651.135352404205, 6749.3250674932115, 6766.120404347404, 6767.492438067165, 6777.983302504615, 6779.118714567075, 6061.090836374579, 6076.118962750376, 6176.197094377747, 6304.65324407162, 6324.08300796382, 6661.134052573135, 6732.259451460335, 6784.050232967388, 6835.726570937107, 6848.212455567574, 6310.747570097236, 6373.362663733682, 6510.479161667055, 6538.673066346663, 6568.901466478007, 6575.572587781779, 6590.868182636416, 6598.175228096488, 6708.060871477997, 6711.127553418024, 6590.472762179014, 6601.933980660132, 6618.437432818265, 6634.502412319095, 6645.169353830771, 6371.362863713684, 6430.485561155097, 6619.139511863427, 6636.137302150786, 6649.368310010541, 5303.390110137307, 5305.071612467803, 5347.117725575236, 5349.1976203569775, 5391.245225668409, 4783.904321913542, 4788.066327066189, 4813.879653008703, 4849.345338379344, 4877.512248775094, 6197.752089916353, 6238.0, 6333.176687030748, 6334.651594162273, 6347.206599175103, 6577.638229897306, 6661.533692641468, 6688.097106890507, 6726.125603671491, 6727.495437842182, 6296.338884417162, 6324.778632747832, 6329.968350158219, 6332.968335158294, 6351.809445716636, 5921.5558833086825, 5936.910946335808, 5946.316173640087, 5946.732397042144, 5964.671943043174, 5420.674759514442, 5492.725363731797, 6798.388145066968, 6866.931330686829, 6870.862582748417, 6763.256041835296, 6778.0, 6785.728570857111, 6787.151606049244, 6793.66031698423, 6796.762113326207, 6875.802751720474, 6921.342472465108, 6949.895751563629, 6951.513394062367, 6395.6482393468805, 6461.063145843732, 6502.382273683992, 6622.304658010839, 6635.23694775107, 6693.129893114059, 6706.93293067063, 6729.629870357177, 6755.628440435823, 6769.593824370553, 6256.31180570146, 6512.804615861532, 6518.902216466662, 6528.37980391862, 6529.347065293433, 6349.015902535114, 6415.069814876723, 6497.610143391223, 6523.608583485006, 6524.478041756743, 5222.503862133091, 5393.487618676219, 5857.82843431324, 6403.263624683034, 6432.549721519449, 6534.281229064704, 6562.5406221564035, 6564.081028655896, 6568.540202185801, 6568.967155164385, 4986.950130498793, 6201.844953876189, 6241.31345551998, 6355.618662880242, 6401.519886008475], "value": 6052.414518419653, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.5419841606914, 745.2547452547452, 833.5665453963937, 1215.246547140773, 1278.3224890807876, 39549.80225098874, 40759.981000475, 40779.796101019456, 40791.0, 40830.79584602077, 40179.79910100453, 40491.0, 40600.79699601505, 40659.0, 40689.57586484474, 7835.764927052198, 8015.599220039004, 8259.463134896212, 8460.238578527931, 8467.364947628921, 7677.923220767804, 8161.694128939371, 8390.958045209765, 8507.319414446829, 8514.744557663249, 8466.365022623328, 8474.406791524596, 8488.066312705598, 8511.787205319855, 8520.659173633047, 8472.0, 8486.490810551386, 8492.108328625467, 8498.235158835703, 8523.445976011539, 8286.544240066794, 8323.875141872877, 8342.833143337099, 8346.749597512084, 8364.58177091149, 37812.24375512474, 37848.62151378478, 37875.67435139784, 38061.429078563844, 38137.42793858094, 3568.2863427314383, 3595.694365978895, 3603.261331427051, 3604.4953706481115, 3608.891733248007, 30981.0, 31079.844600777076, 31126.354259887936, 31182.532262016088, 31272.0, 3032.7877048606606, 3064.988553777248, 3117.8129312241113, 3132.9530057049164, 3133.545635882784, 246408.0, 249524.0, 267799.66100169375, 270210.0, 308038.0, 48162.51837481651, 48378.75810620995, 48392.51607483951, 48439.031219375414, 48577.299794507024, 50366.748166258934, 50503.0, 50544.74727626412, 55315.61710957258, 55932.0, 14287.78568321476, 14448.711025779425, 14497.782533262009, 14501.492447764278, 14530.200838953959, 11492.94253528727, 11533.653990380302, 11552.422378881023, 11622.88377116235, 11669.124815638861, 11763.647090587296, 14324.14055156694, 14330.85669143316, 14345.569632910821, 14373.425062997363, 5486.396496385394, 6104.138237837502, 6203.8138855834395, 6243.843903902439, 6281.623102613858, 988.7429268390271, 1011.9494025298706, 1017.9032991865761, 1048.7954848804563, 1055.7413433708791, 22530.873456327117, 22923.197688080836, 24719.0, 24811.25566233016, 24848.751512484643, 1076.8653918260218, 1077.7467295185625, 1116.7040734205443, 1159.976800463986, 1170.3387586013876, 11645.41772911368, 12179.451924663412, 12520.686982825502, 12757.724227577168, 12772.680682983, 303747.0, 308831.0, 309031.4548427244, 311665.0, 314612.4269378639, 57982.7100864493, 58529.707351462974, 58538.70730646405, 58647.70676146592, 58690.0, 1472.2417954753312, 1501.3619211834925, 1502.684436268396, 1512.19853477657, 1517.590250632323, 28040.0, 29083.0, 31129.0, 31505.054848354586, 31746.52380214345, 39390.0, 39581.01047473836, 39656.40515392214, 39668.83477234849, 39698.40452393159, 297915.0, 298190.50904745335, 299443.0, 299923.0, 300154.0, 35458.29083418369, 35485.0, 35552.0, 35603.465948010286, 35619.46570801492, 44321.0, 44353.33469997888, 45719.77140114278, 46272.30591541197, 46395.53604463912, 29435.85282073576, 30974.845125774227, 31127.84436077805, 31645.36709265848, 32001.0, 30879.919202827794, 31098.844505777328, 31301.8434907824, 31671.0, 31848.885289014772, 36367.818160909024, 36581.63418365782, 37372.0, 37490.062748431505, 38117.42823857589, 22894.771052289263, 28660.713392866637, 30700.692993070676, 31386.843065784524, 31718.207044824063, 22680.319590412313, 22942.0, 23165.652515212623, 24554.63168052445, 25109.870055847165], "value": 17817.559844969488, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.5873209326489, 427.93153095504726, 438.24402904988887, 452.5271091709164, 453.03503537465195, 21422.46443838903, 21445.14219431223, 22381.552368952653, 22390.992405341756, 22525.77474225257, 19462.90268548655, 19682.409527714157, 22107.889460552717, 23188.88405557974, 23243.767562324414, 6046.274447066358, 6338.841528961767, 6358.205224346957, 6369.4585960193435, 6374.458171055466, 5956.612820166697, 6235.06474028897, 6239.188905442286, 6302.0861975013595, 6347.555671103025, 5332.440093790155, 6188.102725104857, 6206.596571222856, 6243.344448832875, 6243.375662433766, 4956.2565615157655, 6055.878882422371, 6174.475169610589, 6230.781922632709, 6231.937680623181, 4796.256580230035, 5501.944980550224, 5510.614257002011, 5533.612647114704, 5538.141588053858, 22357.552848943094, 24810.255692329258, 25462.618060729103, 27836.860815695993, 28669.41632295963, 2093.2568938027084, 2174.0542863854275, 2179.193698331612, 2185.0276626901036, 2189.627763280246, 17476.73784893215, 17820.0, 17898.821011789845, 18120.546986325317, 18200.544986375313, 1932.7584051993501, 2165.7509386420447, 2171.457135716071, 2217.889105544716, 2224.088123869222, 144913.55086449106, 159524.0, 160861.0, 162223.0, 163354.0, 12498.625041248777, 12853.550125745554, 13088.803667944989, 13113.541026064233, 13129.146605470805, 16684.416045438353, 16686.165691715367, 16694.165291735364, 16742.74885876713, 16810.327586896386, 8784.824303513893, 8924.107589241154, 9024.0, 9159.587818548182, 9177.03641117673, 8493.10822363643, 8540.786480337916, 8546.529940853312, 8713.782155446037, 8741.737747867573, 7029.980652805315, 7943.721969731032, 7952.483088599222, 8116.918830811616, 8158.143394943564, 2090.7491101067967, 2237.474193564511, 2241.7197850268717, 2246.2362796649218, 2394.413368724639, 928.5218112671979, 931.5714771205186, 944.4805357053593, 1009.5204777730667, 1018.8777346718441, 17184.742228866577, 20597.691034634492, 21129.943502824797, 21260.468488287916, 21392.465188370414, 975.0298453039271, 977.9608815647437, 1003.8895721470633, 1025.933314334566, 1040.8022475729588, 10696.946515267375, 11023.283486573348, 11054.613088541862, 11085.0, 11098.944505277583, 532466.0, 538820.0, 540706.0, 545413.0, 546440.0, 132635.37870038417, 133312.0, 135207.0, 138369.0, 138481.61518384688, 1177.8763229860913, 1211.357980270458, 1239.9194052386747, 1242.2546472116658, 1246.993765031169, 22462.550748985257, 22740.886295568416, 22827.31518054461, 22910.54178916379, 23028.0, 58492.415075848694, 58691.4130858686, 59399.40600594111, 59838.102428462735, 60583.485412863265, 63244.0, 73430.63284683542, 77455.22544774706, 77838.61080694561, 79100.0, 8881.73354799357, 8921.464712117293, 8968.641254349754, 9145.679901203692, 9351.532423378667, 14125.576232713034, 14236.288185590885, 14539.127652340894, 14606.415743370571, 14684.926575367055, 11971.221870578385, 12074.517019319128, 12091.39543022863, 12184.329861857328, 12243.061665200466, 9908.702738917844, 10470.371777693363, 11320.320780753182, 11621.88378116208, 11635.418229088342, 11927.165098443025, 12232.633021009384, 12418.627441176779, 12446.0, 12566.246025238515, 8952.865707014269, 9037.096290371173, 9097.0, 9141.725748227564, 9335.673251436167, 11963.940180299043, 12124.151309408257, 12267.631971040882, 12293.877061229274, 12369.319687417279], "value": 10567.91871612581, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.3804430939615, 1339.7521458530166, 1432.7606620273466, 1436.5187662133178, 1443.9494617688379, 11011.779764404717, 11966.76066478671, 11983.041356691465, 12183.878161218385, 12284.63146105616, 12009.639710808668, 12080.637580872566, 12216.93891530541, 12220.144589878711, 12244.387780610956, 2512.849229046254, 2536.6321883326905, 2559.001989224195, 2563.8718064096815, 2567.6020216866414, 2493.463905260369, 2536.6195070739445, 2538.010176031349, 2542.694876614809, 2545.872706364684, 2188.365374041526, 2512.1207577347964, 2520.0, 2521.621756736486, 2528.064616092039, 2143.871367717934, 2461.4584791345965, 2476.5170791695627, 2493.7755601995727, 2500.7999360051153, 2447.5104979004273, 2472.147109247325, 2485.962710559343, 2490.9626355604682, 2556.041484443334, 11744.236624619372, 11938.761224775542, 11953.701157471045, 11955.521779128825, 11963.282203067844, 6452.7741529046725, 6705.932940670628, 6712.966435167842, 6722.932770672329, 6726.293739157416, 4022.5172979242384, 4226.535081141072, 4294.549072347421, 4306.600354884675, 4322.243607368698, 1037.3723897042332, 1220.8412906322121, 1228.1464382254337, 1228.2937311046185, 1241.8199361092682, 15203.771943420857, 15630.140342281284, 16352.591185220346, 16534.007959522467, 16693.081880496567, 4975.402951645826, 5488.984537860509, 5492.395836457987, 5686.943130568724, 5700.458456446631, 11820.995215406787, 11982.88017119835, 11989.700257493632, 12003.579874704517, 12005.159638825198, 6367.108604795332, 6457.677116144174, 6464.127342808754, 6473.773417930444, 6477.514186435942, 5084.720340381315, 6007.27912650476, 6138.815835524942, 6161.537884658668, 6180.412860778219, 4349.608125399842, 4388.2320593896575, 4440.111977604492, 4469.977650111794, 4476.1495315890525, 1255.3597665190819, 1275.948962041508, 1278.4758249117817, 1289.864564220762, 1292.7349893271858, 768.1358471719316, 779.3842864137381, 803.7669075967904, 804.6580203413525, 806.5201205282847, 5769.8557536061935, 6649.501287403464, 7009.544379615309, 7024.578525288499, 7040.577565346096, 906.3338446242007, 942.721897040361, 942.7831598732192, 947.7726344384039, 948.7106432538004, 4172.603602657743, 4368.694191406571, 4388.639521748269, 4490.079533695584, 4504.932426013613, 11283.66149015531, 14659.973801833768, 15026.248687565578, 15154.696906061818, 15322.770158447409, 14953.626159345886, 15154.848451515565, 15167.696646067017, 15173.772393414107, 15219.847801522064, 8456.83086338282, 8545.2309292164, 8605.35459840527, 8618.052014278424, 8632.0, 6141.692915354125, 6311.96844015777, 6796.76211332601, 7005.929940700527, 7018.368346848808, 12596.055295852664, 12693.4287957044, 12787.61637150887, 12826.871731282941, 12873.22760634365, 13285.535006274735, 13733.587992360246, 13791.931040344734, 13970.301484925916, 14196.503122390666, 9276.768080798034, 9414.246860251027, 9455.385399948982, 9471.810563788547, 9483.715488535356, 9648.421094734338, 10155.441450720282, 10621.681349559525, 10768.515416806105, 10809.297395669257, 9157.542122893696, 9162.725118246462, 9206.769830754285, 9309.255259579353, 9394.107559781809, 8970.461772293684, 8971.91028089737, 8985.146411090936, 8998.595063222301, 9031.63873445055, 7026.754063607749, 8298.21166989135, 8367.954005749281, 8373.288270497076, 8384.203500667427, 5648.745806438638, 5871.266091738533, 5954.851128721816, 6033.276006879202, 6230.813075607739, 5706.942930570641, 5834.970825145847, 6032.849178770566, 6226.470749986302, 6320.715567799368], "value": 5374.553080787213, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.3377722097718, 379.40433519374574, 399.3769719237989, 405.74843596969885, 411.4712594316304, 5918.3489816120245, 5960.4337587929185, 6292.653904035276, 6472.087560597733, 6493.253275873264, 5036.34527511423, 6200.0, 6294.402031806982, 6296.811095667137, 6313.305536391004, 3843.8270277837564, 4544.500104988449, 4557.39965427312, 4562.832654489108, 4578.175928332898, 4128.339465685491, 4137.441445404877, 4313.835264478589, 4544.500104988449, 4864.270359446076, 4784.880377990543, 4799.784009719571, 4820.710757354553, 4846.442659094197, 4848.733319667417, 4191.3293872980175, 4783.7129772213775, 4823.927641085386, 4826.444958829745, 4827.589654879322, 4203.864956461738, 4283.336082907154, 4324.091940692459, 4332.176886391576, 4346.630536404409, 5058.721770302632, 5994.250718660168, 6111.633302001894, 6136.509079273647, 6174.475169610589, 5608.327000759935, 5762.538996880239, 5771.220885180489, 5776.33572139201, 5782.797602083907, 5677.801276955287, 5943.435373639541, 6034.758609655609, 6186.04093380237, 6678.0, 4937.790241390882, 4953.5541801237705, 4958.0, 4958.404991401019, 4961.454240033593, 5658.264425624684, 6754.257031726506, 7084.291570842926, 7164.426845852319, 7307.953772943584, 4759.0, 4772.928406073912, 4779.880502987384, 4786.95213047865, 4814.735189564608, 3892.727509074394, 4196.727212731163, 4311.8706438806885, 4860.805567777249, 4861.368022157097, 4963.627727920348, 5551.9722401387735, 5555.833325000256, 5570.944290557123, 5612.775488980476, 5649.022842005265, 5948.881022379528, 6793.694283757242, 6796.422304104207, 6821.863562728718, 1532.3947040918774, 1765.0821572782338, 1769.637224369027, 1775.529484686559, 1775.8133824432166, 1207.2394391533278, 1211.927284362941, 1239.908880185449, 1266.9619911402672, 1267.2903174222465, 5638.802641907513, 5697.572682048863, 6282.968585157137, 6346.0, 6426.614403135827, 5617.662940223599, 6224.0, 6468.6442245676, 7086.539374940716, 7092.4680648951535, 5771.961047011579, 6104.4200800923845, 6338.302786693553, 6340.498246245125, 6405.03924411342, 3641.3809652359164, 3664.5052917856283, 3670.7063434925144, 3689.505750171185, 3794.6395092466173, 5953.719950210725, 6914.239433662293, 7164.641767911688, 7273.23631018746, 7296.0, 5554.472325129106, 6961.37347638715, 7177.391964517628, 7275.726747819111, 7288.744893928687, 6228.166184386094, 6878.449724022066, 7006.894896576556, 7204.171520275236, 7211.567305961764, 5882.970375220542, 6984.650767461505, 7024.4029257513685, 7033.472489563405, 7066.222715501188, 6307.96846015767, 6493.610383377012, 6707.0488984846515, 6717.488565072819, 6953.922142067988, 5854.882902342015, 6643.734250629921, 6701.664916754045, 7028.262032486516, 7216.005357776464, 4158.293090174738, 4362.25841606934, 4509.503954565061, 4511.255642818898, 4530.161920044802, 5919.4968427684125, 6467.676616169267, 6976.0, 6980.755673551402, 6991.615461149482, 6583.275839657729, 6748.392644662004, 6749.4600431964345, 6771.424428923597, 6839.93160068393, 6368.745250189941, 6716.1604799400075, 6739.460843132636, 6746.684396542627, 6784.7964561063245, 5624.240727501735, 6101.9389806103145, 6660.46902177189, 6672.766453174309, 6687.057694614354, 6577.375149360803, 6677.732890684319, 6764.0, 6771.0, 6781.254062053072, 5454.509094181542, 6232.220972378453, 6586.275509693834, 6603.702833372606, 6611.942089265694], "value": 4944.541775026608, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.7859477940959, 826.2109685250582, 838.656150978099, 856.3063918226239, 856.5289090999948, 13702.794458083124, 16364.999999999985, 16814.579635509104, 18113.456596302098, 19516.609667806635, 17428.912855435738, 18034.458966231035, 18254.81745182551, 18267.72598411025, 18267.90866045668, 789.6288744290174, 943.9952800235989, 952.2096659772407, 954.9045095490465, 957.5882370580648, 905.1762895764855, 994.1599348550463, 1003.8393856982884, 1023.3143793658267, 1025.1491262252312, 1057.9576816927313, 1070.9571617135305, 1073.8335557988485, 1077.9407132607703, 1078.3152698036724, 1044.9320794148393, 1053.6101642392287, 1066.7546464313252, 1068.0654427376007, 1069.887661795512, 788.9723859664941, 795.8686816675241, 797.4178849439942, 797.752696664036, 797.972070977513, 16756.9945803251, 16851.73611979094, 16946.745798813026, 16997.745033824503, 17036.14819259032, 17789.377371791925, 17822.0, 18085.0, 18141.183646735928, 18219.72670409945, 14743.77884331736, 14860.108393496424, 14897.255137243203, 15004.174770387624, 15023.699526009419, 3484.5992710838323, 3564.8752293669854, 3587.820608969541, 3596.946045809289, 3604.1530240393486, 131942.680573194, 135503.0, 145384.0, 149818.0, 150244.0, 13240.801387979189, 13521.256330901699, 13709.245991470365, 13764.17414955106, 13847.169169849842, 25700.74299257021, 26331.473370532865, 26626.334341641228, 26637.200883973514, 26684.733152668614, 6453.580517266457, 7002.649867506604, 7692.92307076922, 7928.960355198187, 8014.398920081017, 3965.1673148638533, 4042.8584999524874, 4049.0889549851636, 4054.8986275342763, 4059.6955228357992, 4198.538160802309, 4250.744955302692, 4275.273203555341, 4292.527821939584, 4359.738415695068, 951.191487235844, 962.3888830592624, 964.81668482988, 965.7199412170532, 970.3789574672215, 610.1671218786324, 637.512303088138, 674.5210900260772, 679.683946964654, 686.821426429132, 10340.948295258475, 11124.165687573464, 11543.942280288546, 12255.387230638433, 12326.81509777354, 628.9591176573508, 653.5555822040963, 662.4766434516774, 667.2693400726197, 677.7831094049956, 5277.683338999673, 5743.827685169452, 5758.424157584292, 5764.452377024176, 5766.798162064308, 137571.95420891696, 139182.0, 140164.0, 142294.28852855877, 142720.0, 120241.0, 121622.0, 122569.0, 123095.76904231022, 125384.0, 102083.48958255161, 102484.0, 102559.0, 103279.4836025815, 104647.99999999847, 11114.610988615359, 11570.537178513101, 11578.884211158118, 11582.07343412508, 11783.646490605295, 42002.78998604987, 42252.78873605735, 42255.1548969025, 42270.0, 42288.0, 54258.0, 55333.33999980007, 55533.44466555283, 55533.72233138808, 56141.71929140328, 14060.578182654537, 17109.230084645973, 17199.828001719823, 17851.821481785017, 18027.819721802614, 18893.055347232308, 19220.423387298404, 19222.8077719221, 19326.613467730847, 19453.4163975081, 17591.82408175902, 17826.643467130325, 18310.176042077874, 18432.907835460737, 18450.0, 17102.315907363565, 17407.86848854871, 17990.19044143043, 18033.909830450764, 18113.0, 16753.32986680519, 17456.214470349118, 17646.294148233927, 17711.82288177153, 17712.025838579, 11460.484278207334, 11734.94132529332, 11997.400129993292, 12407.379631018232, 12431.875681243071, 11678.6496405108, 13291.268980206181, 13338.733225335633, 13588.777010068747, 14298.285085745463], "value": 8757.272508591515, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1506.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 119.0, 120.0, 134.0, 119.0, 115.0, 116.0, 115.0, 116.0, 115.0], "value": 117.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 99.0, 137.0, 178.0, 137.0, 145.0, 110.0, 113.0, 135.0, 111.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 195.0, 195.0, 209.0, 215.0, 207.0, 214.0, 201.0, 203.0, 195.0], "value": 203.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 57.0, 86.0, 52.0, 86.0, 51.0, 53.0, 90.0, 55.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 91.0, 113.0, 99.0, 98.0, 86.0, 88.0, 71.0, 71.0, 88.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 89.0, 96.0, 130.0, 96.0, 105.0, 100.0, 90.0, 90.0], "value": 97.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 96.0, 97.0, 98.0, 98.0, 97.0, 97.0, 101.0], "value": 97.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 123.0, 131.0, 93.0, 80.0, 145.0, 139.0, 95.0, 92.0], "value": 108.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 51.0, 53.0, 49.0, 52.0, 52.0, 50.0, 50.0, 51.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 57.0, 64.0, 65.0, 58.0, 52.0, 56.0, 58.0, 56.0], "value": 57.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 86.0, 86.0, 87.0, 86.0, 85.0, 86.0, 121.0, 86.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 137.0, 139.0, 134.0, 139.0, 165.0, 132.0, 145.0, 141.0, 156.0], "value": 143.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 184.0, 187.0, 182.0, 185.0, 189.0, 188.0, 188.0, 187.0, 216.0], "value": 188.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 65.0, 67.0, 68.0, 67.0, 68.0, 66.0, 70.0, 63.0, 67.0], "value": 67.1, "unit": "score"}]}]} 15:12:35 INFO - Return code: 0 15:12:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:12:35 INFO - # TBPL SUCCESS # 15:12:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:12:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:12:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:12:35 INFO - 2017-05-24 15:12:35.771 screenresolution[1671:11480] starting screenresolution argv=screenresolution get 15:12:35 INFO - 2017-05-24 15:12:35.774 screenresolution[1671:11480] Display 0: 1600x1200x32@60 15:12:35 INFO - 2017-05-24 15:12:35.780 screenresolution[1672:11483] starting screenresolution argv=screenresolution list 15:12:35 INFO - Available Modes on Display 0 15:12:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:12:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:12:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:12:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:12:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:12:36 INFO - Intel Iris: 15:12:36 INFO - Chipset Model: Intel Iris 15:12:36 INFO - Type: GPU 15:12:36 INFO - Bus: Built-In 15:12:36 INFO - VRAM (Dynamic, Max): 1536 MB 15:12:36 INFO - Vendor: Intel (0x8086) 15:12:36 INFO - Device ID: 0x0a2e 15:12:36 INFO - Revision ID: 0x0009 15:12:36 INFO - Displays: 15:12:36 INFO - Display: 15:12:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:12:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:12:36 INFO - Display Serial Number: 1600x1200 60 15:12:36 INFO - Main Display: Yes 15:12:36 INFO - Mirror: Off 15:12:36 INFO - Online: Yes 15:12:36 INFO - Rotation: Supported 15:12:36 INFO - Return code: 0 15:12:36 INFO - Running post-action listener: _resource_record_post_action 15:12:36 INFO - [mozharness: 2017-05-24 22:12:36.024787Z] Finished run-tests step (success) 15:12:36 INFO - Running post-run listener: _resource_record_post_run 15:12:36 INFO - Total resource usage - Wall time: 1254s; CPU: 54.0%; Read bytes: 50822144; Write bytes: 567069696; Read time: 595; Write time: 1854 15:12:36 INFO - TinderboxPrint: CPU usage
53.5% 15:12:36 INFO - TinderboxPrint: I/O read bytes / time
50,822,144 / 595 15:12:36 INFO - TinderboxPrint: I/O write bytes / time
567,069,696 / 1,854 15:12:36 INFO - TinderboxPrint: CPU idle
2,330.8 (46.5%) 15:12:36 INFO - TinderboxPrint: CPU system
1,019.8 (20.3%) 15:12:36 INFO - TinderboxPrint: CPU user
1,667.1 (33.2%) 15:12:36 INFO - TinderboxPrint: Swap in / out
286,433,280 / 0 15:12:36 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 238060032; Write bytes: 233072640; Read time: 16921; Write time: 481 15:12:36 INFO - run-tests - Wall time: 1234s; CPU: 54.0%; Read bytes: 48675840; Write bytes: 317760512; Read time: 566; Write time: 1333 15:12:36 INFO - Running post-run listener: _upload_blobber_files 15:12:36 INFO - Blob upload gear active. 15:12:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:12:36 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 15:12:36 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'] 15:12:36 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 15:12:36 INFO - (blobuploader) - INFO - Open directory for files ... 15:12:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:12:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:12:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:12:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:12:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:12:37 INFO - (blobuploader) - INFO - Done attempting. 15:12:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:12:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:12:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:12:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:12:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:12:38 INFO - (blobuploader) - INFO - Done attempting. 15:12:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:12:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:12:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:12:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:12:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:12:39 INFO - (blobuploader) - INFO - Done attempting. 15:12:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:12:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:12:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:12:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:12:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:12:41 INFO - (blobuploader) - INFO - Done attempting. 15:12:41 INFO - (blobuploader) - INFO - Iteration through files over. 15:12:41 INFO - Return code: 0 15:12:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:12:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:12:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d2e24f60f631763bd6b4d5f0165a40ddfc797690d4a118b692d504c52e1b7e8d7b029e5f49599b36d032b6ba3be147393f1ddc7e8bd6b078be63b0c3a61de3e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e924a21ab3ec3ae875552b5e596e47444cd6653677be5c1f892efc34fc9fd540c45926c70f87e76fcf7f37b69a3d21c2379acdd89133fcea909ad758fd8bfa62", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16ed87fa9ed68305a4ba19b680bbc6193d24307c8c801af1b498595a30efe634c9a5d0cc7589548d1bc02786af44d0651a26b1ce7bcddcbb28aa514ed20fd8bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2107c19055c123be551b1101cf9d20168fa7cdf6ae1fa1811fe9ade19a50d11851f730a6f6c7f720f8b754baa5dc8279a53e61d7a8518ee05db4cb1b5322d7e6"} 15:12:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:12:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:12:41 INFO - Contents: 15:12:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d2e24f60f631763bd6b4d5f0165a40ddfc797690d4a118b692d504c52e1b7e8d7b029e5f49599b36d032b6ba3be147393f1ddc7e8bd6b078be63b0c3a61de3e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e924a21ab3ec3ae875552b5e596e47444cd6653677be5c1f892efc34fc9fd540c45926c70f87e76fcf7f37b69a3d21c2379acdd89133fcea909ad758fd8bfa62", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16ed87fa9ed68305a4ba19b680bbc6193d24307c8c801af1b498595a30efe634c9a5d0cc7589548d1bc02786af44d0651a26b1ce7bcddcbb28aa514ed20fd8bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2107c19055c123be551b1101cf9d20168fa7cdf6ae1fa1811fe9ade19a50d11851f730a6f6c7f720f8b754baa5dc8279a53e61d7a8518ee05db4cb1b5322d7e6"} 15:12:41 INFO - Running post-run listener: copy_logs_to_upload_dir 15:12:41 INFO - Copying logs to upload dir... 15:12:41 INFO - mkdir: /builds/slave/test/build/upload/logs 15:12:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.090941 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-05-24 15:12:41.702467) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 15:12:41.705777) ========= 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.0TiupBQwEf/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.dB2LZC7G6L/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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/7d2e24f60f631763bd6b4d5f0165a40ddfc797690d4a118b692d504c52e1b7e8d7b029e5f49599b36d032b6ba3be147393f1ddc7e8bd6b078be63b0c3a61de3e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e924a21ab3ec3ae875552b5e596e47444cd6653677be5c1f892efc34fc9fd540c45926c70f87e76fcf7f37b69a3d21c2379acdd89133fcea909ad758fd8bfa62", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16ed87fa9ed68305a4ba19b680bbc6193d24307c8c801af1b498595a30efe634c9a5d0cc7589548d1bc02786af44d0651a26b1ce7bcddcbb28aa514ed20fd8bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2107c19055c123be551b1101cf9d20168fa7cdf6ae1fa1811fe9ade19a50d11851f730a6f6c7f720f8b754baa5dc8279a53e61d7a8518ee05db4cb1b5322d7e6"} build_url:https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010934 build_url: 'https://queue.taskcluster.net/v1/task/Zy6byYiHQ2ibGkXwT8zY7A/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/7d2e24f60f631763bd6b4d5f0165a40ddfc797690d4a118b692d504c52e1b7e8d7b029e5f49599b36d032b6ba3be147393f1ddc7e8bd6b078be63b0c3a61de3e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e924a21ab3ec3ae875552b5e596e47444cd6653677be5c1f892efc34fc9fd540c45926c70f87e76fcf7f37b69a3d21c2379acdd89133fcea909ad758fd8bfa62", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16ed87fa9ed68305a4ba19b680bbc6193d24307c8c801af1b498595a30efe634c9a5d0cc7589548d1bc02786af44d0651a26b1ce7bcddcbb28aa514ed20fd8bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2107c19055c123be551b1101cf9d20168fa7cdf6ae1fa1811fe9ade19a50d11851f730a6f6c7f720f8b754baa5dc8279a53e61d7a8518ee05db4cb1b5322d7e6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 15:12:41.739297) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 15:12:41.739672) ========= 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.0TiupBQwEf/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.dB2LZC7G6L/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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.005157 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 15:12:41.788663) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 15:12:41.789041) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 15:12:41.789669) ========= ========= Total master_lag: 0.18 =========