builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0130 starttime: 1495429659.73 results: success (0) revision: 367944041b558ffe29edf64e8c83879a348de0ca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.729550) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.730079) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.730381) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.753156) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.753484) ========= 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.dNT7CtzbnB/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.XVvM02IiK9/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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-21 22:07:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.92M=0.001s 2017-05-21 22:07:39 (7.92 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153634 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.929056) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.929376) ========= 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.dNT7CtzbnB/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.XVvM02IiK9/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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.023224 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.977709) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-21 22:07:39.978061) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 367944041b558ffe29edf64e8c83879a348de0ca --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 367944041b558ffe29edf64e8c83879a348de0ca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dNT7CtzbnB/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.XVvM02IiK9/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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-21 22:07:40,075 truncating revision to first 12 chars 2017-05-21 22:07:40,075 Setting DEBUG logging. 2017-05-21 22:07:40,075 attempt 1/10 2017-05-21 22:07:40,075 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/367944041b55?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-21 22:07:41,109 unpacking tar archive at: mozilla-central-367944041b55/testing/mozharness/ program finished with exit code 0 elapsedTime=1.301858 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-21 22:07:41.302478) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:41.302865) ========= script_repo_revision: 367944041b558ffe29edf64e8c83879a348de0ca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:41.303316) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:41.303592) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:41.322255) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-21 22:07:41.322665) ========= /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.dNT7CtzbnB/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.XVvM02IiK9/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 22:07:41 INFO - MultiFileLogger online at 20170521 22:07:41 in /builds/slave/test 22:07:41 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 22:07:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:07:41 INFO - {'append_to_log': False, 22:07:41 INFO - 'base_work_dir': '/builds/slave/test', 22:07:41 INFO - 'blob_upload_branch': 'Firefox', 22:07:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:07:41 INFO - 'branch': 'Firefox', 22:07:41 INFO - 'buildbot_json_path': 'buildprops.json', 22:07:41 INFO - 'config_files': ('talos/mac_config.py',), 22:07:41 INFO - 'default_actions': ('clobber', 22:07:41 INFO - 'read-buildbot-config', 22:07:41 INFO - 'download-and-extract', 22:07:41 INFO - 'populate-webroot', 22:07:41 INFO - 'create-virtualenv', 22:07:41 INFO - 'install', 22:07:41 INFO - 'run-tests'), 22:07:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:07:41 INFO - 'download_minidump_stackwalk': True, 22:07:41 INFO - 'download_symbols': 'ondemand', 22:07:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:07:41 INFO - 'tooltool.py': '/tools/tooltool.py', 22:07:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:07:41 INFO - '/tools/misc-python/virtualenv.py')}, 22:07:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:07:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:07:41 INFO - 'gecko_profile': False, 22:07:41 INFO - 'gecko_profile_interval': 0, 22:07:41 INFO - 'installer_path': 'installer.exe', 22:07:41 INFO - 'log_level': 'info', 22:07:41 INFO - 'log_name': 'talos', 22:07:41 INFO - 'log_to_console': True, 22:07:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:07:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:07:41 INFO - 'opt_config_files': (), 22:07:41 INFO - 'pip_index': False, 22:07:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:07:41 INFO - 'cmd': ('bash', 22:07:41 INFO - '-c', 22:07:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:07:41 INFO - 'enabled': True, 22:07:41 INFO - 'halt_on_failure': False, 22:07:41 INFO - 'name': 'check_screen_resolution'},), 22:07:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:07:41 INFO - 'cmd': ('bash', 22:07:41 INFO - '-c', 22:07:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:07:41 INFO - 'enabled': True, 22:07:41 INFO - 'halt_on_failure': False, 22:07:41 INFO - 'name': 'check_screen_resolution'},), 22:07:41 INFO - 'run_cmd_checks_enabled': True, 22:07:41 INFO - 'suite': 'dromaeojs-e10s', 22:07:41 INFO - 'system_bits': '32', 22:07:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:07:41 INFO - 'title': 't-yosemite-r7-0130', 22:07:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:07:41 INFO - 'use_talos_json': True, 22:07:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:07:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:07:41 INFO - 'work_dir': 'build'} 22:07:41 INFO - [mozharness: 2017-05-22 05:07:41.540465Z] Running clobber step. 22:07:41 INFO - Running pre-action listener: _resource_record_pre_action 22:07:41 INFO - Running main action method: clobber 22:07:41 INFO - rmtree: /builds/slave/test/build 22:07:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:07:44 INFO - Running post-action listener: _resource_record_post_action 22:07:44 INFO - [mozharness: 2017-05-22 05:07:44.091134Z] Finished clobber step (success) 22:07:44 INFO - [mozharness: 2017-05-22 05:07:44.091330Z] Running read-buildbot-config step. 22:07:44 INFO - Running pre-action listener: _resource_record_pre_action 22:07:44 INFO - Running main action method: read_buildbot_config 22:07:44 INFO - Using buildbot properties: 22:07:44 INFO - { 22:07:44 INFO - "project": "", 22:07:44 INFO - "product": "firefox", 22:07:44 INFO - "who": "ihsiao@mozilla.com", 22:07:44 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 22:07:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 22:07:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 22:07:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 22:07:44 INFO - "stage_platform": "macosx64", 22:07:44 INFO - "basedir": "/builds/slave/test", 22:07:44 INFO - "buildnumber": 61, 22:07:44 INFO - "platform": "yosemite_r7", 22:07:44 INFO - "parent_task_id": "VhaFt28oRZSQJdgmE_hNHQ", 22:07:44 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 22:07:44 INFO - "slavebuilddir": "test", 22:07:44 INFO - "taskId": "J0xeyDNjRXmi-BH49i8Tgw", 22:07:44 INFO - "branch": "mozilla-central", 22:07:44 INFO - "script_repo_revision": "production", 22:07:44 INFO - "revision": "367944041b558ffe29edf64e8c83879a348de0ca", 22:07:44 INFO - "slavename": "t-yosemite-r7-0130", 22:07:44 INFO - "repo_path": "mozilla-central" 22:07:44 INFO - } 22:07:44 INFO - Finding installer, test and symbols from parent task. 22:07:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J0xeyDNjRXmi-BH49i8Tgw'}, attempt #1 22:07:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J0xeyDNjRXmi-BH49i8Tgw'}, attempt #1 22:07:45 INFO - Task dependencies: Xz2EESGoR1mDhMcGiHY7kg VhaFt28oRZSQJdgmE_hNHQ 22:07:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ'}, attempt #1 22:07:45 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:07:45 INFO - Set test_url: https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/test_packages.json 22:07:45 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/target.crashreporter-symbols.zip 22:07:45 INFO - Running post-action listener: _resource_record_post_action 22:07:45 INFO - [mozharness: 2017-05-22 05:07:45.785161Z] Finished read-buildbot-config step (success) 22:07:45 INFO - [mozharness: 2017-05-22 05:07:45.785249Z] Running download-and-extract step. 22:07:45 INFO - Running pre-action listener: _resource_record_pre_action 22:07:45 INFO - Running main action method: download_and_extract 22:07:45 INFO - mkdir: /builds/slave/test/build/tests 22:07:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:07:45 INFO - trying https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 22:07:45 INFO - Downloading https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/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 22:07:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/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 22:07:46 INFO - Downloaded 1325 bytes. 22:07:46 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:07:46 INFO - Using the following test package requirements: 22:07:46 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:46 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 22:07:46 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:07:46 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:46 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 22:07:46 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:46 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 22:07:46 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:46 INFO - u'jsshell-mac.zip'], 22:07:46 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:46 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 22:07:46 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:07:46 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:46 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 22:07:46 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:46 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 22:07:46 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:46 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 22:07:46 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:46 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 22:07:46 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:07:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:07:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:07:46 INFO - Fetch https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:07:48 INFO - Content-Length response header: 57622781 22:07:48 INFO - Bytes received: 57622781 22:08:06 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 22:08:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:08:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:08:06 INFO - Fetch https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:08:08 INFO - Content-Length response header: 57622781 22:08:08 INFO - Bytes received: 57622781 22:08:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 22:08:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:08:25 INFO - Fetch https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 22:08:26 INFO - Content-Length response header: 14410000 22:08:26 INFO - Bytes received: 14410000 22:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:27 INFO - trying https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:08:27 INFO - Downloading https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 22:08:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/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 22:08:29 INFO - Downloaded 68063529 bytes. 22:08:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:08:29 INFO - mkdir: /builds/slave/test/properties 22:08:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:08:29 INFO - Writing to file /builds/slave/test/properties/build_url 22:08:29 INFO - Contents: 22:08:29 INFO - build_url:https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:08:29 INFO - Running post-action listener: _resource_record_post_action 22:08:29 INFO - Running post-action listener: set_extra_try_arguments 22:08:29 INFO - [mozharness: 2017-05-22 05:08:29.025238Z] Finished download-and-extract step (success) 22:08:29 INFO - [mozharness: 2017-05-22 05:08:29.025372Z] Running populate-webroot step. 22:08:29 INFO - Running pre-action listener: _resource_record_pre_action 22:08:29 INFO - Running main action method: populate_webroot 22:08:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:08:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:08:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:08:29 INFO - '--tppagecycles', 22:08:29 INFO - '7'], 22:08:29 INFO - 'tests': ['tsvgm']}}, 22:08:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:08:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:08:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:08:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:08:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:08:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:08:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:08:29 INFO - 'talos_options': ['--disable-e10s'], 22:08:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:08:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:08:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:08:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:08:29 INFO - 'talos_options': ['--disable-e10s'], 22:08:29 INFO - 'tests': ['damp', 'tps']}, 22:08:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:08:29 INFO - 'tests': ['damp', 'tps']}, 22:08:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:08:29 INFO - 'tests': ['dromaeo_dom']}, 22:08:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:08:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:08:29 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 22:08:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:08:29 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 22:08:29 INFO - 'tests': ['ts_paint', 'tp5o']}, 22:08:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:08:29 INFO - 'tests': ['a11yr', 22:08:29 INFO - 'ts_paint', 22:08:29 INFO - 'tpaint', 22:08:29 INFO - 'sessionrestore', 22:08:29 INFO - 'sessionrestore_no_auto_restore', 22:08:29 INFO - 'tabpaint']}, 22:08:29 INFO - 'other-e10s': {'tests': ['a11yr', 22:08:29 INFO - 'ts_paint', 22:08:29 INFO - 'tpaint', 22:08:29 INFO - 'sessionrestore', 22:08:29 INFO - 'sessionrestore_no_auto_restore', 22:08:29 INFO - 'tabpaint']}, 22:08:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:08:29 INFO - 'ts_paint', 22:08:29 INFO - 'tpaint', 22:08:29 INFO - 'sessionrestore', 22:08:29 INFO - 'sessionrestore_no_auto_restore', 22:08:29 INFO - 'tabpaint']}, 22:08:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:08:29 INFO - 'ts_paint', 22:08:29 INFO - 'tpaint', 22:08:29 INFO - 'sessionrestore', 22:08:29 INFO - 'sessionrestore_no_auto_restore', 22:08:29 INFO - 'tabpaint']}, 22:08:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:08:29 INFO - 'tests': ['a11yr', 22:08:29 INFO - 'ts_paint', 22:08:29 INFO - 'tpaint', 22:08:29 INFO - 'sessionrestore', 22:08:29 INFO - 'sessionrestore_no_auto_restore', 22:08:29 INFO - 'tabpaint']}, 22:08:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:08:29 INFO - 'tests': ['a11yr', 22:08:29 INFO - 'ts_paint', 22:08:29 INFO - 'tpaint', 22:08:29 INFO - 'sessionrestore', 22:08:29 INFO - 'sessionrestore_no_auto_restore', 22:08:29 INFO - 'tabpaint']}, 22:08:29 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 22:08:29 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 22:08:29 INFO - 'bloom_basic_ref']}, 22:08:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:08:29 INFO - 'tests': ['tsvgx', 22:08:29 INFO - 'tsvgr_opacity', 22:08:29 INFO - 'tart', 22:08:29 INFO - 'tscrollx', 22:08:29 INFO - 'cart', 22:08:29 INFO - 'tsvg_static']}, 22:08:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:08:29 INFO - 'tsvgr_opacity', 22:08:29 INFO - 'tart', 22:08:29 INFO - 'tscrollx', 22:08:29 INFO - 'cart', 22:08:29 INFO - 'tsvg_static']}, 22:08:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:08:29 INFO - 'talos_options': ['--disable-e10s'], 22:08:29 INFO - 'tests': ['tp5o']}, 22:08:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:08:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:08:29 INFO - 'talos_options': ['--disable-e10s', 22:08:29 INFO - '--xperf_path', 22:08:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:08:29 INFO - 'tests': ['tp5n']}, 22:08:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:08:29 INFO - 'talos_options': ['--xperf_path', 22:08:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:08:29 INFO - 'tests': ['tp5n']}}, 22:08:29 INFO - 'talos.zip': {'path': '', 22:08:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:08:29 INFO - Running post-action listener: _resource_record_post_action 22:08:29 INFO - [mozharness: 2017-05-22 05:08:29.046262Z] Finished populate-webroot step (success) 22:08:29 INFO - [mozharness: 2017-05-22 05:08:29.046436Z] Running create-virtualenv step. 22:08:29 INFO - Running pre-action listener: _resource_record_pre_action 22:08:29 INFO - Running main action method: create_virtualenv 22:08:29 INFO - Creating virtualenv /builds/slave/test/build/venv 22:08:29 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 22:08:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:08:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:08:29 INFO - Using real prefix '/tools/python27' 22:08:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:08:29 INFO - Installing distribute.............................................................................................................................................................................................done. 22:08:32 INFO - Installing pip.................done. 22:08:32 INFO - Return code: 0 22:08:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:08:33 INFO - Reading from file tmpfile_stdout 22:08:33 INFO - Output received: 22:08:33 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) 22:08:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, '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.dNT7CtzbnB/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.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 22:08:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:08:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 22:08:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:33 INFO - 'HOME': '/Users/cltbld', 22:08:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:33 INFO - 'LOGNAME': 'cltbld', 22:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:33 INFO - 'MOZ_NO_REMOTE': '1', 22:08:33 INFO - 'NO_EM_RESTART': '1', 22:08:33 INFO - 'PAGER': '/bin/cat', 22:08:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:33 INFO - 'PWD': '/builds/slave/test', 22:08:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:33 INFO - 'SHELL': '/bin/bash', 22:08:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 22:08:33 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 22:08:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:33 INFO - 'USER': 'cltbld', 22:08:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:33 INFO - 'XPC_FLAGS': '0x0', 22:08:33 INFO - 'XPC_SERVICE_NAME': '0', 22:08:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:33 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 22:08:33 INFO - Cleaning up... 22:08:33 INFO - Return code: 0 22:08:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, '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.dNT7CtzbnB/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.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 22:08:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:08:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 22:08:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:33 INFO - 'HOME': '/Users/cltbld', 22:08:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:33 INFO - 'LOGNAME': 'cltbld', 22:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:33 INFO - 'MOZ_NO_REMOTE': '1', 22:08:33 INFO - 'NO_EM_RESTART': '1', 22:08:33 INFO - 'PAGER': '/bin/cat', 22:08:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:33 INFO - 'PWD': '/builds/slave/test', 22:08:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:33 INFO - 'SHELL': '/bin/bash', 22:08:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 22:08:33 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 22:08:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:33 INFO - 'USER': 'cltbld', 22:08:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:33 INFO - 'XPC_FLAGS': '0x0', 22:08:33 INFO - 'XPC_SERVICE_NAME': '0', 22:08:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:33 INFO - Downloading/unpacking psutil>=3.1.1 22:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:08:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:08:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:08:35 INFO - Installing collected packages: psutil 22:08:35 INFO - Running setup.py install for psutil 22:08:35 INFO - building 'psutil._psutil_osx' extension 22:08:35 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 22:08:35 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 22:08:35 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 22:08:36 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 22:08:36 INFO - building 'psutil._psutil_posix' extension 22:08:36 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 22:08:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:08:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:08:36 INFO - ^ 22:08:36 INFO - 1 warning generated. 22:08:36 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 22:08:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:08:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:08:36 INFO - Successfully installed psutil 22:08:36 INFO - Cleaning up... 22:08:36 INFO - Return code: 0 22:08:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, '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.dNT7CtzbnB/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.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 22:08:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:08:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:08:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 22:08:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:37 INFO - 'HOME': '/Users/cltbld', 22:08:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:37 INFO - 'LOGNAME': 'cltbld', 22:08:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:37 INFO - 'MOZ_NO_REMOTE': '1', 22:08:37 INFO - 'NO_EM_RESTART': '1', 22:08:37 INFO - 'PAGER': '/bin/cat', 22:08:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:37 INFO - 'PWD': '/builds/slave/test', 22:08:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:37 INFO - 'SHELL': '/bin/bash', 22:08:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 22:08:37 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 22:08:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:37 INFO - 'USER': 'cltbld', 22:08:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:37 INFO - 'XPC_FLAGS': '0x0', 22:08:37 INFO - 'XPC_SERVICE_NAME': '0', 22:08:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:08:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:08:38 INFO - Installing collected packages: mozsystemmonitor 22:08:38 INFO - Running setup.py install for mozsystemmonitor 22:08:39 INFO - Successfully installed mozsystemmonitor 22:08:39 INFO - Cleaning up... 22:08:39 INFO - Return code: 0 22:08:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, '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.dNT7CtzbnB/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.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 22:08:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:08:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 22:08:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:39 INFO - 'HOME': '/Users/cltbld', 22:08:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:39 INFO - 'LOGNAME': 'cltbld', 22:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:39 INFO - 'MOZ_NO_REMOTE': '1', 22:08:39 INFO - 'NO_EM_RESTART': '1', 22:08:39 INFO - 'PAGER': '/bin/cat', 22:08:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:39 INFO - 'PWD': '/builds/slave/test', 22:08:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:39 INFO - 'SHELL': '/bin/bash', 22:08:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 22:08:39 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 22:08:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:39 INFO - 'USER': 'cltbld', 22:08:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:39 INFO - 'XPC_FLAGS': '0x0', 22:08:39 INFO - 'XPC_SERVICE_NAME': '0', 22:08:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:39 INFO - Downloading/unpacking jsonschema==2.5.1 22:08: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 22:08: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 22:08: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 22:08: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 22:08:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:08:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:40 INFO - Downloading functools32-3.2.3-2.tar.gz 22:08:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:08:41 INFO - warning: no files found matching '*.txt' 22:08:41 INFO - no previously-included directories found matching 'build' 22:08:41 INFO - no previously-included directories found matching 'dist' 22:08:41 INFO - no previously-included directories found matching '.git*' 22:08:41 INFO - Installing collected packages: jsonschema, functools32 22:08:41 INFO - Running setup.py install for functools32 22:08:41 INFO - warning: no files found matching '*.txt' 22:08:41 INFO - no previously-included directories found matching 'build' 22:08:41 INFO - no previously-included directories found matching 'dist' 22:08:41 INFO - no previously-included directories found matching '.git*' 22:08:41 INFO - Successfully installed jsonschema functools32 22:08:41 INFO - Cleaning up... 22:08:41 INFO - Return code: 0 22:08:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:41 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 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, '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.dNT7CtzbnB/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.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 22:08:41 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 22:08:41 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 22:08:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 22:08:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:41 INFO - 'HOME': '/Users/cltbld', 22:08:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:41 INFO - 'LOGNAME': 'cltbld', 22:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:41 INFO - 'MOZ_NO_REMOTE': '1', 22:08:41 INFO - 'NO_EM_RESTART': '1', 22:08:41 INFO - 'PAGER': '/bin/cat', 22:08:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:41 INFO - 'PWD': '/builds/slave/test', 22:08:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:41 INFO - 'SHELL': '/bin/bash', 22:08:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 22:08:41 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 22:08:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:41 INFO - 'USER': 'cltbld', 22:08:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:41 INFO - 'XPC_FLAGS': '0x0', 22:08:41 INFO - 'XPC_SERVICE_NAME': '0', 22:08:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:08:41 INFO - Cleaning up... 22:08:41 INFO - Return code: 0 22:08:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:41 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 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, '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.dNT7CtzbnB/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.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 22:08:41 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 22:08:41 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 22:08:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 22:08:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:41 INFO - 'HOME': '/Users/cltbld', 22:08:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:41 INFO - 'LOGNAME': 'cltbld', 22:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:41 INFO - 'MOZ_NO_REMOTE': '1', 22:08:41 INFO - 'NO_EM_RESTART': '1', 22:08:41 INFO - 'PAGER': '/bin/cat', 22:08:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:41 INFO - 'PWD': '/builds/slave/test', 22:08:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:41 INFO - 'SHELL': '/bin/bash', 22:08:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 22:08:41 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 22:08:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:41 INFO - 'USER': 'cltbld', 22:08:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:41 INFO - 'XPC_FLAGS': '0x0', 22:08:41 INFO - 'XPC_SERVICE_NAME': '0', 22:08:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:41 INFO - Downloading/unpacking blobuploader==1.2.4 22:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:43 INFO - Downloading blobuploader-1.2.4.tar.gz 22:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:08:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:08:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:44 INFO - Downloading docopt-0.6.1.tar.gz 22:08:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:08:44 INFO - Installing collected packages: blobuploader, requests, docopt 22:08:44 INFO - Running setup.py install for blobuploader 22:08:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:08:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:08:44 INFO - Running setup.py install for requests 22:08:44 INFO - Running setup.py install for docopt 22:08:44 INFO - Successfully installed blobuploader requests docopt 22:08:44 INFO - Cleaning up... 22:08:45 INFO - Return code: 0 22:08:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:45 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 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, '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.dNT7CtzbnB/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.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 22:08:45 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 22:08:45 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 22:08:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 22:08:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:45 INFO - 'HOME': '/Users/cltbld', 22:08:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:45 INFO - 'LOGNAME': 'cltbld', 22:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:45 INFO - 'MOZ_NO_REMOTE': '1', 22:08:45 INFO - 'NO_EM_RESTART': '1', 22:08:45 INFO - 'PAGER': '/bin/cat', 22:08:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:45 INFO - 'PWD': '/builds/slave/test', 22:08:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:45 INFO - 'SHELL': '/bin/bash', 22:08:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 22:08:45 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 22:08:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:45 INFO - 'USER': 'cltbld', 22:08:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:45 INFO - 'XPC_FLAGS': '0x0', 22:08:45 INFO - 'XPC_SERVICE_NAME': '0', 22:08:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:08:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-iAFfMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:08:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-rwii9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:08:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-s6Fe_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:08:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-VBmWVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:08:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-8myfRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:08:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-alBzGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:08:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-Hxy32t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-ehhl6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-AutSIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-pG2CIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-T0mTVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-jFr3iO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-onvZYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-62LwQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-3rP8V_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-vOItvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-lcdiDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:08:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:08:47 INFO - Running setup.py install for manifestparser 22:08:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:08:47 INFO - Running setup.py install for mozcrash 22:08:47 INFO - Running setup.py install for mozdebug 22:08:47 INFO - Running setup.py install for mozdevice 22:08:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:08:47 INFO - Running setup.py install for mozfile 22:08:47 INFO - Running setup.py install for mozhttpd 22:08:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:08:47 INFO - Running setup.py install for mozinfo 22:08:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:08:48 INFO - Running setup.py install for mozInstall 22:08:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:08:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:08:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:08:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:08:48 INFO - Running setup.py install for mozleak 22:08:48 INFO - Running setup.py install for mozlog 22:08:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:08:48 INFO - Running setup.py install for moznetwork 22:08:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:08:48 INFO - Running setup.py install for mozprocess 22:08:48 INFO - Running setup.py install for mozprofile 22:08:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:08:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:08:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:08:48 INFO - Running setup.py install for mozrunner 22:08:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:08:49 INFO - Running setup.py install for mozscreenshot 22:08:49 INFO - Running setup.py install for moztest 22:08:49 INFO - Running setup.py install for mozversion 22:08:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:08:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:08:49 INFO - Cleaning up... 22:08:49 INFO - Return code: 0 22:08:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:49 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 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, '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.dNT7CtzbnB/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.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 22:08:49 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 22:08:49 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 22:08:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 22:08:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:49 INFO - 'HOME': '/Users/cltbld', 22:08:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:49 INFO - 'LOGNAME': 'cltbld', 22:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:49 INFO - 'MOZ_NO_REMOTE': '1', 22:08:49 INFO - 'NO_EM_RESTART': '1', 22:08:49 INFO - 'PAGER': '/bin/cat', 22:08:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:49 INFO - 'PWD': '/builds/slave/test', 22:08:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:49 INFO - 'SHELL': '/bin/bash', 22:08:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 22:08:49 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 22:08:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:49 INFO - 'USER': 'cltbld', 22:08:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:49 INFO - 'XPC_FLAGS': '0x0', 22:08:49 INFO - 'XPC_SERVICE_NAME': '0', 22:08:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:08:49 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-UBPMBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:08:50 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)) 22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-stHknA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:08:50 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)) 22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-jyiSXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:08:50 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)) 22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-4mdY8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:08:50 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)) 22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-xYxzmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:08:50 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)) 22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-c6i5tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:08:50 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)) 22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-CTh3wD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:08:50 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)) 22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-2v425K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:08:50 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)) 22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-NPOWnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:08:50 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)) 22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-cIw9sJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:08:50 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)) 22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-KBxcRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:08:51 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)) 22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:08:51 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-Yyqzgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:08:51 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)) 22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:08:51 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-LJg2W3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:08:51 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)) 22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:08:51 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-fC654Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:08:51 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)) 22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:51 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-POMV3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:51 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)) 22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:08:51 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-9JPZdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:08:51 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)) 22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:08:51 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-LNvdXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:08:51 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)) 22:08:51 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)) 22:08:51 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)) 22:08:51 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)) 22:08:51 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)) 22:08:51 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)) 22:08:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:08:51 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 22:08:51 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 22:08:51 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 22:08:51 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 22:08:53 INFO - Downloading blessings-1.6.tar.gz 22:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:08:53 INFO - Installing collected packages: blessings 22:08:53 INFO - Running setup.py install for blessings 22:08:53 INFO - Successfully installed blessings 22:08:53 INFO - Cleaning up... 22:08:53 INFO - Return code: 0 22:08:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:08:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:08:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:08:53 INFO - Reading from file tmpfile_stdout 22:08:53 INFO - Current package versions: 22:08:53 INFO - blessings == 1.6 22:08:53 INFO - blobuploader == 1.2.4 22:08:53 INFO - docopt == 0.6.1 22:08:53 INFO - functools32 == 3.2.3-2 22:08:53 INFO - jsonschema == 2.5.1 22:08:53 INFO - manifestparser == 1.1 22:08:53 INFO - mozInstall == 1.12 22:08:53 INFO - mozcrash == 1.0 22:08:53 INFO - mozdebug == 0.1 22:08:53 INFO - mozdevice == 0.50 22:08:53 INFO - mozfile == 1.2 22:08:53 INFO - mozhttpd == 0.7 22:08:53 INFO - mozinfo == 0.9 22:08:53 INFO - mozleak == 0.1 22:08:53 INFO - mozlog == 3.4 22:08:53 INFO - moznetwork == 0.27 22:08:53 INFO - mozprocess == 0.25 22:08:53 INFO - mozprofile == 0.28 22:08:53 INFO - mozrunner == 6.13 22:08:53 INFO - mozscreenshot == 0.1 22:08:53 INFO - mozsystemmonitor == 0.3 22:08:53 INFO - moztest == 0.8 22:08:53 INFO - mozversion == 1.4 22:08:53 INFO - psutil == 3.1.1 22:08:53 INFO - requests == 1.2.3 22:08:53 INFO - wsgiref == 0.1.2 22:08:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:08:53 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')]} 22:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:53 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')]} 22:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:53 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 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, '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.dNT7CtzbnB/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.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 22:08:53 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 22:08:53 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 22:08:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 22:08:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:53 INFO - 'HOME': '/Users/cltbld', 22:08:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:53 INFO - 'LOGNAME': 'cltbld', 22:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:53 INFO - 'MOZ_NO_REMOTE': '1', 22:08:53 INFO - 'NO_EM_RESTART': '1', 22:08:53 INFO - 'PAGER': '/bin/cat', 22:08:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:53 INFO - 'PWD': '/builds/slave/test', 22:08:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:53 INFO - 'SHELL': '/bin/bash', 22:08:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 22:08:53 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 22:08:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:53 INFO - 'USER': 'cltbld', 22:08:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:53 INFO - 'XPC_FLAGS': '0x0', 22:08:53 INFO - 'XPC_SERVICE_NAME': '0', 22:08:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:53 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)) 22:08:53 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)) 22:08:53 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)) 22:08:53 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)) 22:08:53 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)) 22:08:53 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)) 22:08:53 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)) 22:08:53 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)) 22:08:53 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)) 22:08:53 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)) 22:08:53 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)) 22:08:53 INFO - Cleaning up... 22:08:53 INFO - Return code: 0 22:08:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:08:53 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')]} 22:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:53 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')]} 22:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:53 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 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, '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.dNT7CtzbnB/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.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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 22:08:53 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 22:08:53 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 22:08:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 22:08:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:53 INFO - 'HOME': '/Users/cltbld', 22:08:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:53 INFO - 'LOGNAME': 'cltbld', 22:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:53 INFO - 'MOZ_NO_REMOTE': '1', 22:08:53 INFO - 'NO_EM_RESTART': '1', 22:08:53 INFO - 'PAGER': '/bin/cat', 22:08:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:53 INFO - 'PWD': '/builds/slave/test', 22:08:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:53 INFO - 'SHELL': '/bin/bash', 22:08:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 22:08:53 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 22:08:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:53 INFO - 'USER': 'cltbld', 22:08:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:53 INFO - 'XPC_FLAGS': '0x0', 22:08:53 INFO - 'XPC_SERVICE_NAME': '0', 22:08:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:08:54 INFO - Cleaning up... 22:08:54 INFO - Return code: 0 22:08:54 INFO - Running post-action listener: _resource_record_post_action 22:08:54 INFO - Running post-action listener: _start_resource_monitoring 22:08:54 INFO - Starting resource monitoring. 22:08:54 INFO - [mozharness: 2017-05-22 05:08:54.278009Z] Finished create-virtualenv step (success) 22:08:54 INFO - [mozharness: 2017-05-22 05:08:54.279096Z] Running install step. 22:08:54 INFO - Running pre-action listener: _resource_record_pre_action 22:08:54 INFO - Running main action method: install 22:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:08:54 INFO - Reading from file tmpfile_stdout 22:08:54 INFO - Detecting whether we're running mozinstall >=1.0... 22:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:08:54 INFO - Reading from file tmpfile_stdout 22:08:54 INFO - Output received: 22:08:54 INFO - Usage: mozinstall [options] installer 22:08:54 INFO - Options: 22:08:54 INFO - -h, --help show this help message and exit 22:08:54 INFO - -d DEST, --destination=DEST 22:08:54 INFO - Directory to install application into. [default: 22:08:54 INFO - "/builds/slave/test"] 22:08:54 INFO - --app=APP Application being installed. [default: firefox] 22:08:54 INFO - mkdir: /builds/slave/test/build/application 22:08:54 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'] 22:08:54 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 22:09:15 INFO - Reading from file tmpfile_stdout 22:09:15 INFO - Output received: 22:09:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:09:15 INFO - Running post-action listener: _resource_record_post_action 22:09:15 INFO - [mozharness: 2017-05-22 05:09:15.813935Z] Finished install step (success) 22:09:15 INFO - [mozharness: 2017-05-22 05:09:15.814074Z] Running run-tests step. 22:09:15 INFO - Running pre-action listener: _resource_record_pre_action 22:09:15 INFO - Running main action method: run_tests 22:09:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:09:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:09:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:09:15 INFO - 2017-05-21 22:09:15.828 screenresolution[3452:53071] starting screenresolution argv=screenresolution get 22:09:15 INFO - 2017-05-21 22:09:15.831 screenresolution[3452:53071] Display 0: 1600x1200x32@60 22:09:15 INFO - 2017-05-21 22:09:15.837 screenresolution[3453:53073] starting screenresolution argv=screenresolution list 22:09:15 INFO - Available Modes on Display 0 22:09:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:09:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:09:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:09:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:09:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:09:16 INFO - Intel Iris: 22:09:16 INFO - Chipset Model: Intel Iris 22:09:16 INFO - Type: GPU 22:09:16 INFO - Bus: Built-In 22:09:16 INFO - VRAM (Dynamic, Max): 1536 MB 22:09:16 INFO - Vendor: Intel (0x8086) 22:09:16 INFO - Device ID: 0x0a2e 22:09:16 INFO - Revision ID: 0x0009 22:09:16 INFO - Displays: 22:09:16 INFO - Display: 22:09:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:09:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:09:16 INFO - Display Serial Number: 1600x1200 60 22:09:16 INFO - Main Display: Yes 22:09:16 INFO - Mirror: Off 22:09:16 INFO - Online: Yes 22:09:16 INFO - Rotation: Supported 22:09:16 INFO - Return code: 0 22:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:09:16 INFO - Python 2.7.3 22:09:16 INFO - Return code: 0 22:09:16 INFO - grabbing minidump binary from tooltool 22:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:16 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 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:09:16 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 22:09:16 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 22:09:16 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 22:09:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:09:16 INFO - Return code: 0 22:09:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:09:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:09:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:09:16 INFO - ENV: RUST_BACKTRACE is now 1 22:09:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:09:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:09:16 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/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--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 22:09:16 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/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0130 --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 22:09:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 22:09:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:16 INFO - 'HOME': '/Users/cltbld', 22:09:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:16 INFO - 'LOGNAME': 'cltbld', 22:09:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:09:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:16 INFO - 'MOZ_NO_REMOTE': '1', 22:09:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:09:16 INFO - 'NO_EM_RESTART': '1', 22:09:16 INFO - 'PAGER': '/bin/cat', 22:09:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:16 INFO - 'PWD': '/builds/slave/test', 22:09:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:09:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:16 INFO - 'RUST_BACKTRACE': '1', 22:09:16 INFO - 'SHELL': '/bin/bash', 22:09:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 22:09:16 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 22:09:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:16 INFO - 'USER': 'cltbld', 22:09:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:16 INFO - 'XPC_FLAGS': '0x0', 22:09:16 INFO - 'XPC_SERVICE_NAME': '0', 22:09:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:16 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/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--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 22:09:16 INFO - mozversion application_buildid: 20170521203316 22:09:16 INFO - mozversion application_changeset: 367944041b558ffe29edf64e8c83879a348de0ca 22:09:16 INFO - mozversion application_display_name: Nightly 22:09:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:09:16 INFO - mozversion application_name: Firefox 22:09:16 INFO - mozversion application_remotingname: firefox 22:09:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:09:16 INFO - mozversion application_vendor: Mozilla 22:09:16 INFO - mozversion application_version: 55.0a1 22:09:16 INFO - mozversion platform_buildid: 20170521203316 22:09:16 INFO - mozversion platform_changeset: 367944041b558ffe29edf64e8c83879a348de0ca 22:09:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:09:16 INFO - mozversion platform_version: 55.0a1 22:09:16 INFO - using testdate: 1495429756 22:09:16 INFO - actual date: 1495429756 22:09:16 INFO - starting webserver on 'localhost:61897' 22:09:16 INFO - SUITE-START | Running 2 tests 22:09:16 INFO - TEST-START | dromaeo_css 22:09:16 INFO - operating with platform_type : mac_ 22:09:16 INFO - Initialising browser for dromaeo_css test... 22:09:16 INFO - TEST-INFO | started process 3463 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpEZ_vGR/profile http://localhost:61897/getInfo.html) 22:09:16 INFO - PID 3463 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2970: String.localeCompare is deprecated; use String.prototype.localeCompare instead 22:09:18 INFO - PID 3463 | 2017-05-21 22:09:18.934 plugin-container[3465:53245] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4b, name = 'com.apple.tsm.portname' 22:09:18 INFO - PID 3463 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:09:18 INFO - PID 3463 | 2017-05-21 22:09:18.937 plugin-container[3465:53245] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa303, name = 'com.apple.CFPasteboardClient' 22:09:18 INFO - PID 3463 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:09:18 INFO - PID 3463 | 2017-05-21 22:09:18.937 plugin-container[3465:53245] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:09:19 INFO - PID 3463 | __metrics Screen width/height:1600/1200 22:09:19 INFO - PID 3463 | colorDepth:24 22:09:19 INFO - PID 3463 | Browser inner width/height: 1024/691 22:09:19 INFO - PID 3463 | __metrics 22:09:19 INFO - PID 3463 | 2017-05-21 22:09:19.513 plugin-container[3466:53301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 22:09:19 INFO - PID 3463 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:09:19 INFO - PID 3463 | 2017-05-21 22:09:19.513 plugin-container[3466:53301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 22:09:19 INFO - PID 3463 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:09:19 INFO - PID 3463 | 2017-05-21 22:09:19.513 plugin-container[3466:53301] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:09:20 INFO - TEST-INFO | 3463: exit 0 22:09:20 INFO - Browser initialized. 22:09:20 INFO - Running cycle 1/1 for dromaeo_css test... 22:09:20 INFO - TEST-INFO | started process 3467 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpEZ_vGR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:09:22 INFO - PID 3467 | 2017-05-21 22:09:22.153 plugin-container[3468:53399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 22:09:22 INFO - PID 3467 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:09:22 INFO - PID 3467 | 2017-05-21 22:09:22.156 plugin-container[3468:53399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 22:09:22 INFO - PID 3467 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:09:22 INFO - PID 3467 | 2017-05-21 22:09:22.156 plugin-container[3468:53399] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:09:22 INFO - PID 3467 | 2017-05-21 22:09:22.933 plugin-container[3469:53445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 22:09:22 INFO - PID 3467 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:09:22 INFO - PID 3467 | 2017-05-21 22:09:22.934 plugin-container[3469:53445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 22:09:22 INFO - PID 3467 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:09:22 INFO - PID 3467 | 2017-05-21 22:09:22.934 plugin-container[3469:53445] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:09:32 INFO - PID 3467 | RSS: Main: 242745344 22:09:32 INFO - PID 3467 | 22:12:35 INFO - PID 3467 | Cycle 1(1): loaded http://localhost:61897/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61897/tests/dromaeo/cssquery-ext.html) 22:12:36 INFO - PID 3467 | RSS: Main: 264818688 22:12:36 INFO - PID 3467 | 22:15:38 INFO - PID 3467 | Cycle 1(1): loaded http://localhost:61897/tests/dromaeo/cssquery-ext.html (next: http://localhost:61897/tests/dromaeo/cssquery-jquery.html) 22:15:39 INFO - PID 3467 | RSS: Main: 270639104 22:15:39 INFO - PID 3467 | 22:18:40 INFO - PID 3467 | Cycle 1(1): loaded http://localhost:61897/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61897/tests/dromaeo/cssquery-mootools.html) 22:18:41 INFO - PID 3467 | RSS: Main: 274116608 22:18:41 INFO - PID 3467 | 22:21:43 INFO - PID 3467 | Cycle 1(1): loaded http://localhost:61897/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61897/tests/dromaeo/cssquery-prototype.html) 22:21:43 INFO - PID 3467 | RSS: Main: 271831040 22:21:43 INFO - PID 3467 | 22:24:46 INFO - PID 3467 | Cycle 1(1): loaded http://localhost:61897/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61897/tests/dromaeo/cssquery-yui.html) 22:24:47 INFO - PID 3467 | RSS: Main: 273403904 22:24:47 INFO - PID 3467 | 22:27:48 INFO - PID 3467 | Cycle 1(1): loaded http://localhost:61897/tests/dromaeo/cssquery-yui.html (next: http://localhost:61897/tests/dromaeo/cssquery-dojo.html) 22:27:49 INFO - PID 3467 | RSS: Main: 274542592 22:27:49 INFO - PID 3467 | 22:27:49 INFO - PID 3467 | __start_tp_report 22:27:49 INFO - PID 3467 | _x_x_mozilla_page_load 22:27:49 INFO - PID 3467 | _x_x_mozilla_page_load_details 22:27:49 INFO - PID 3467 | |i|pagename|runs| 22:27:49 INFO - PID 3467 | |0;dojo.html;2868.627078479797;3527.1182204448905;3562.091666625012;3570.8571657133716;3592.461130830375;4663.323818046386;5420.322459692538;5668.631538949965;6104.816855494342;6121.6020958637655;6139.522803442366;6151.8769624607485;6152.661603611799;6171.598846075013;6192.597481163722;5425.864353391157;5491.945080549203;5536.2249285099915;5605.327360716719;5616.213730077772;4330;5492.450754924515;5606.691631960241;5613.186088017235;5627.7467513961765;4601.792919318638;4754.952450475503;4899.583535399495;5491.478309560605;5610.214569960209;4906.681065730716;5380;5476.397596264408;5496.890062198733;5507.779688812443;5199.350081239845;5441.700706461183;5466.398696143421;5510.889782204334;5538;6095.05526643371;6191.628502289877;6233.0338797486465;6276;6310.92714238576;6477.028445733178;6778.084958530634;6781.38967492928;6793.694283757242;6818;5901.203337549418;6705.832354191184;6844.93155068448;6850.691718872663;6860.108185935796;6293.748250070037;6308.400701933309;6322.873542529123;6345.238571371418;6356.300806911236;7008.544444611084;7021;7032.015517827508;7034.2965703429245;7035.92964070363;6397.808065758035;6454.677266136674;6570.540062195696;6590.670466476657;6631.104800851823;6739.427148692416;6830.282820303796;6842.4183944364295;6849.760258390932;6855.760048398282;6044.607100538451;6379.3620637936765;6500;6530.020496925499;6648.1025061616065;6615.702293396808;6661.034150048215;6692.765753198712;6711.127553418024;6716.966415167991;5341.4925582069645;5388.191771234347;5397.217403476474;5399.622026458189;5449.455054494598;4706.811727530929;4753.025629745894;4803.5916947059195;4813.879653008633;4842.782074806642;6314.905276420857;6339.936600634027;6359.395857393541;6377.681115944184;6422.6467544285515;6882.03651488792;6885.931140688629;6886.690098945459;6890.517663763489;6915.273896240923;6252.406021427957;6366.140571022853;6382.808515744535;6407.359264073649;6417.711202995876;5877.647341159544;5980.880382392328;6003.699815009232;6007.609505382136;6045.516358691294;6962.512624116263;7005.124359455068;7044.612546310104;7047.119110111236;7048.964755176191;6796.150481189851;6819.318068193141;6834.8633027340165;6853.554518956252;6905.482088843457;6796.93203067963;6863.828404289933;6933.791986240421;7028.543144695578;7051.43588512928;6695.832604184934;6751.189857217166;6777.423918966943;6777.966110169417;6785.457163426815;6799.777707208488;6876.621785801829;6909.205441374306;6911.3779759821855;6919.757808476679;6428.228612566335;6474.320196379313;6629.370209830058;6631.0716499689115;6685.8997115044285;5924.526037917042;6198.132261483486;6547.541672083099;6614.437772789367;6663.533552651462;6289.528285378524;6295.1186833842385;6402.295747467868;6468.514861385509;6507.7722279721875;5624.915626265692;6496.317886621837;6512.772052978123;6682.064510968566;6715;6300.527460440394;6331.113644089924;6438.098666186644;6449.322821103904;6491.350864913471 22:27:49 INFO - PID 3467 | |1;ext.html;795.8209402884348;810.335524869607;824.5052968219068;1222.7248869004475;1277.2591896699914;38500.22999540008;38774.61225387752;38807;38823.835284941495;38931;39249.95375947931;39261.80369098158;39547.40678889819;39594.8020259899;39622.00944976377;7558.395328373717;7798.5320880747195;7916.881246781303;8258.587070646474;8524.275436587897;7742;8227.95886020569;8435.072142064368;8535.658573657047;8565.772899046231;8508.276796472308;8560.571971401436;8579.056303806576;8581.056083830774;8591.35564832637;8440.029396619375;8527.616257268437;8536.317094632415;8548.871766923501;8562.571871406468;7115.5730656160795;7921.287084162453;8250.917490825135;8297.83404331916;8310.25207731301;36907;36916.63083369159;36919.630803692155;36931;36938.89183324505;3642.453631955201;3642.763220390666;3682.760620559655;3683.0055884911194;3683.0608194910396;30122.945696900722;30235.09294721162;30279.848600757075;30353.241168970733;30412.695873041208;3045.4670432674193;3096.628404591441;3109.7667674924473;3117.283024904262;3123.687631236881;299832;302032.9796702027;304405;304424;305988;46711.766441168256;46845.765771170925;46846.76576617095;46953;47039.76480117646;49127;49167.508324917006;51546.74226628843;54511.727441363335;54597.72701136469;13947.86052139486;13990;14031.78952315716;14162.575122746333;14166.079204851652;11176.050035747052;11221;11256.943715281372;11343.659690209306;11395.544178232944;14053.400120977447;14064.085834420934;14090.084144530572;14090.295485225697;14153.85846141546;6223.564350495421;6286.874262514724;6304.621722696653;6306.801707675527;6309.495240380758;1018.2414101494434;1050.3855244681795;1057.461393672205;1094.5731164845718;1100.8844071372391;24125;24286.635700464507;24422.26733198007;24436.8232129822;24466.87766561156;1030.6341248856697;1099.7133353975867;1144.6794897428733;1170.7365842685329;1179.6637958181877;12309.522857257007;12556.372181390894;12568.74862502763;16483.83516164847;16515.33938642465;339127;341109.58890411275;341202;342068;342975;56283.71858140683;56622;56896.146557801665;56913;56961;1497.161589509878;1501.5795577238391;1542.3367951780617;1543.8919275650596;1549.147968617256;26294.62315469964;29595.40809183847;30280.84859575688;30457.086287411414;30506.694933050385;38776.030599235244;38890.02774930649;38897.80551097226;38898;39413.21173576455;294550;295220;295400;295748;296246;32354.676453235166;33032.83483582567;34216;34318.656813431546;34358.82820585981;41119.177616446905;41560;42924;42974.140517190106;43276;29251.268718282212;29823.10530684083;30325.545116823705;30337.089887303417;30704.232394190323;29300.853495732386;29832.552511711907;30336.84831575828;30358.69641303559;30464.695353046187;29157.7084229155;33839;36133.457998129525;36490.452643210905;36590.26819463649;29636.555451668675;29716.85141574278;30235.244118897204;30597.847010764803;30791.384172316877;22021.669674954566;22890.771092288865;23540.411489712893;24049.75950240475;24467.38831529226 22:27:49 INFO - PID 3467 | |2;jquery.html;436.7641473604254;441.2036274524484;445.5633479190394;459.7976890168326;463.7912939177369;20951;21272.57454850904;21577.784222157774;21806.781932180675;21810;21541.815200163986;21672.34982950514;21964.670529942025;22062.558748825013;22721.659175112385;5965.403459654043;6030.698465076762;6230.096635987779;6242.531810114237;6248.718807653644;5934.080217566284;6085;6156.907646385308;6157.230346206724;6165.383461653843;5892.175095486636;6001.189839371695;6078.787242446493;6130.908036379458;6147.846303842417;6052.667103309316;6094.116353128816;6128.877422451571;6141;6141.938580614182;5373.194020896858;5383.300170977787;5448.019356515825;5450.345958484968;5473.124300112003;18985.62028759431;26729.663516824083;27516.03693870705;27603.86198069017;27645.86177069122;2043.948901277465;2243.9439014024615;2248.7638797926147;2267.0591704442636;2267.501149747053;13739.519116830963;16417.42539011129;17127;18024.188911498884;18092.54768630795;2065.896705164751;2160.25471212433;2179.455136215946;2192.561487702466;2210.2264207527396;161735;162521.9999999988;164512;164784.3521564781;167985.16007420007;12760;12771.808422873479;12841.357932103358;12987.896028837466;13167.934160329138;16347.34610615586;16529.00825950447;16532.256048477848;16558.83441165573;16570.337186512646;8147.837043259102;8588.711693246063;8753;9074.45553266806;9252.444853308823;8623.655053797778;8631.741047768577;8642.740717778477;8795.780105497413;8812.515311657791;6661.966690166615;7983.481073730305;8015.318697910627;8057.234562716532;8070.435069545193;2177.466520702438;2179.2372669565843;2195.9121635134734;2196.099599164335;2373.4778348763243;830.9542975136426;946.9857952130586;976.3848775271533;977.5161295158941;1014.4826138669332;15465.690686186213;15994.840051599567;18124.093795310182;21375.03812328449;21390.82350470739;865.6710450029075;992.8709267795141;999.3054826895238;1010.1968934696934;1035.2649618770756;9617.471039092778;10593.84109238362;10612.310199836986;10913.39976301311;11043;536976.3151184297;539030;540114;557540;558036;96233.51883240679;128048;137376;140323;141924.72463276054;1182.266994463421;1201.1711918776016;1262.4093642011132;1266.4301064521003;1269.4224128021729;21331.78668213298;22203.888980554995;22476.32571022872;22710;22796.658050128928;57978.420215797305;58144.63915206292;59788;60546.394536055835;61571.46071348073;75341.6232918832;77167.05496505341;80495.98760031046;81234.59382703049;82386.17613824025;8800.95599522024;8828.470291782514;8854.468731876108;9217.677381291622;9289.953550232205;14329.9283503586;14352.282385880873;14740.13869211824;14886.18126003037;15040.924795375953;9782.804343913223;11017.559297628006;12009.879901200877;12150.116731906215;12270.141090123605;11692.00617947484;11931.343776092051;12004.75990480168;12086.697832554257;12163.087768417226;11744.530218791495;12097.395130243629;12253.816192757295;12506.499740010298;12738.172018818746;8820.514871682119;9016;9137.761248493896;9166.495842728715;9368.531573421438;11803;11993.64019079429;12027.338496382434;12118.151729378858;12349.876501234872 22:27:49 INFO - PID 3467 | |3;mootools.html;1414.724128794885;1429.9564947172157;1477.79559658878;1488.5459934719913;1489.9031562948408;10077.294589378746;10415.270931034831;11513.251638643482;11833.171677982535;11996.700082497931;11544.307341559512;11870.584529541467;11891.881081189185;11907.7618447631;11914.10644201684;2509.222141136249;2553.3744232663025;2564;2566.0633868638;2571.627114068459;2493.9002439902383;2560.4111054457485;2560.7695307422327;2560.7695307422327;2563.5257477366745;2507.4608959073794;2521.3318470605213;2526.68416447944;2536.3278731136265;2543.9618405723836;2468.7037555493366;2472.134752836502;2475.3192871960173;2493.7880280176028;2495.201535508638;2444.340028192378;2457.5699252630898;2466.1491785334033;2469.469064151216;2472.5920223163157;11424.828627570592;11568.190226684135;11571.884281157165;11606.129540284428;11663.766724665544;6340.556161068727;6504.8699026019685;6557.114789503404;6558.573692709959;6565.737370505174;3937.5471820740695;4212.662986961066;4251.319788833772;4287.378330142144;4316.920769807548;1204.4339160594507;1211.9757604847941;1256.824044633752;1268.5560053981126;1271.974560508794;13649.38577763993;16287.83712162875;16418.26117824701;16447.91776041124;16450.424235151833;5104.821331253463;5603.8792241551855;5770.42295770428;6020.247469066367;6100.420460056288;13542.864571354161;13627.727445451035;13725.656858578615;13750;13793.655158621115;6666.500012499082;6681.1314529110905;6708.765193218312;6711.697973591103;6712.6979285931275;5888.234529511222;6142.969285153546;6180.536459765535;6231.937680623136;6265.561410701298;4114.6091121343425;4275.251830929575;4311.288637374798;4315.611594956469;4317.805698743564;1082.7888561730545;1270.6315168601002;1276.495784165268;1284.6017734502334;1287.8905293050195;786.9842603147905;797.7833803449786;809.6113865344668;813.4265342933336;839.6557411461272;5642.887142257132;6085.174447665771;6320.241571011508;6362.231417318928;6378.681065946684;593.8337265565649;595.6396380190009;608.3034925010837;611.0071134406589;612.788587937161;3632.654897784706;3810.8094595270127;3876.8836934891997;3980.104476492766;4275.551067137968;13700.45198192061;14588.927055364868;14910.105393676415;15012.249387530579;15014.774778378332;12127.02983761297;13721.725565488834;13739;13961.022728409116;14920.552383428514;7383.81540461482;8441.535715535581;8600.440971336842;8629.223369896614;8724.302055835646;6638;6843.589384636938;6872.69072891711;6961;6998.580085194904;12690.936545317214;13542.932285338511;13620.250886201355;13697.863021370058;13786.034977551475;13192.406341714453;13229.404676789374;13485.797713034117;13921.58235252944;13963;8504;8822.558872056243;9172.633094676139;9188.21900138469;9351.672691455766;10939.671809845717;11047.226694131334;11059.72350691239;11072.501737421997;11076.612318569134;8854.335924805537;9039.186473217442;9044.728658140266;9046.365983355812;9276.62893484253;7669.159401743653;8440.53577053268;8850.849389579313;8873.157050080232;8962.462252264884;7227.7831665050135;8090.312323452573;8102.108768035393;8156.592170391576;8188.50868947865;5034.622403319692;5636.84444688846;5914.112883067574;6086.60437071589;6124.479419249235;5580.972095139498;5960.165576819336;6108.602940808833;6211.844703882439;6217.347178546188 22:27:49 INFO - PID 3467 | |4;prototype.html;342.39567163955616;399.19960479239137;399.5485101834926;403.21574537524526;412.6348181859054;5031.647784655075;6089.269287685478;6111.7249723762425;6120.969395153029;6286.2456505219325;6004.919114559378;6122.877542449149;6146.508279337653;6151.600145990507;6160.829442405951;4359.324304732756;4470.597646211832;4474.955250447487;4485.461744590653;4502.909941801162;4246.957530424703;4447.088346888896;4465.866024019285;4494.68537202396;4512.435945506812;4459.554044595547;4473.9552604473865;4480.708753930984;4513.480949690778;4521.299198624219;4358.607725304738;4424.800883960197;4437.334399840018;4452.376667266585;4475.104979004196;3358.80762329372;3780.7731536107644;3800.6959443244473;3815.4276858471176;3816.3321418751607;5441.80280338325;5450.454954504517;5534.861628459281;5665.376808551097;5801.970990145064;5228;5272.710000949946;5294.549963253128;5324.281222035053;5349.277847490617;5420.970015697005;5657.321121465409;5823.816068974134;6433.549651524395;6544.280129185786;4129.752214867118;4314.374415709736;4342.478902531717;4349.412829268072;4394.670399720002;6778.559393639398;6832.555883867532;6998.230194678581;7064.894026589605;7108.822279443004;4386.363977223285;4398.23030969579;4407.316865885793;4415.536368681306;4430.734155950653;3582.0507565495163;3908.4304865566455;3960.4257382679925;4482.932756008662;4482.977585112054;3987.5813039631003;4282.678799090081;4490.595846373842;4500.729956202638;5357.035733567917;6293.905591416133;6609.050330152617;6609.867802644017;6629.602223866584;6667.86664266712;1449.5723761490385;1498.9400423982918;1499.1529785671062;1509.7131545006414;1512.8260250071382;886.2334081019872;931.729798358482;1000.5047501486665;1009.0666133826328;1018.6248564438098;5339.679619222859;6251.78118765841;6259.186305780219;6274.686265686789;6289.7169627366875;5835.708214589254;6791.864162716718;6804.9931526419605;6867.381935625718;6905.723771049102;6164.3835616438;6166.260048794174;6284.748610055546;6301.338359472189;6311.400416960381;3591.712662986955;3597.9100522486624;3606.9819650902105;3614.8734794282072;3615.240799432096;6463.903041454382;6865.910340346863;7031.542949708252;7074.92925070753;7104.538205016657;5883.382244864313;6475.838104047437;7063.470239731938;7098.3256590623805;7111.146662400545;6221.329415644791;6505.837354066186;7051.365377116084;7137.393321567621;7163.78508644731;6991.545549539263;6997.1253593300835;7002.334778196063;7011.26381729911;7021.507364885392;5786.627482732228;6681.532292739462;6863.519553631198;6872.69072891711;6900.481893983316;6869.793906182823;7194;7199.832073887473;7213.350798428167;7230.13238411394;3872.5159355080614;4073.531543872374;4419.18245124653;4425.919817685683;4436.800343984464;6411.455026322628;6452.869968375247;6871.025908702571;6952.339527744856;6971.581705097507;6668.333166683293;6712.328767123444;6727.394534491919;6757.391834734897;6767.492438067263;5062.987402519584;6105.603135796159;6405.295417504164;6763.6618169092335;6790.660466976533;5023.874403139951;6555.4427873629365;6584.8353791155605;6592.43964263043;6622.966885165543;5806.983777838776;6417.2299324081405;6436.8390790230615;6737.9326206737305;6779.525433219627;5744.569157313134;6443.162388889415;6483.935160648333;6523;6529.608223506605 22:27:49 INFO - PID 3467 | |5;yui.html;835.9791005224872;849.295085079384;863.0851297624522;866.8439680857443;879.2614204068581;16445.50663480096;17089.230984605692;17117.914410427948;18675.06624668768;19269.421917342468;17847.375341863004;18438.354657586955;18603.720944185847;18607.441776746684;18871;960.3709570231509;1002.413588050991;1025.84612308154;1028.5702873006526;1033.534909290822;1000.0749306891133;1011.9342242754198;1017.6845177994808;1018.210886562916;1018.836986082227;1057.1965306367179;1073.5598404654093;1075.983860242093;1076.8438576406456;1079.7732476179972;1057.3338796558157;1059.607945060325;1074.049466222399;1074.978500429995;1077.4720387010348;762.2796457347808;769.707511145761;779.8869163971249;781.7889169924146;782.1708988472237;16536.834631653648;16928.66142677152;17153.228104735317;17179.398721044705;17184.656306873916;16751;17004.489865303938;17083.743743843854;17263.827361726475;17336.566585835328;13922.651933701638;14120.152790832582;14225.857741422557;14341.354639041268;14462.855371446361;3098.349346637209;3518.8064656443885;3541.8052007139595;3576.5708115026105;3576.7675101118343;94358.64103397401;108717.45641271822;129673;132094;132535;12009.699757506132;13427.530036448677;13516.053876228767;13727.039107262395;13794.517191898236;25210.873945630156;25794.613080803803;25889.22332330033;26167.345816354744;26294.6055809163;7856.803579910434;7939.761807145679;7978.60106994648;7979.3217576505485;7988.760337189894;3848.692104631679;3937.5078115235597;3941.4481972523868;3947.940780888289;3949.4273330366937;3860.3244432224246;4163.770992595436;4329.718568293051;4375.912481750347;4377.058932329564;776.3517462918526;916.2349438219162;948.5636607160645;955.6225291010151;958.5782255807422;648.3386945315751;668.3283300283184;669.778972938929;678.7861823525574;687.3744892148221;11073.667789966314;12001.759964800654;12012.279263244232;12151.453184606715;12383.876161238453;644.5616980453243;650.5933791380387;654.7839213059679;683.6274230544366;685.0204207982533;5394.0290747665795;5659.603827732019;5670.262865827416;5694.487496125285;5703.030484817591;106898.98257630553;107185;111172.02607603504;111900;113501;65778;87706.56146719225;88062;88523;97929.51035244777;75244;77314;80340.59829700814;81347;83519.58240208881;10725.41010244444;11393.031592314746;12161.939190304345;12752.617421477371;12955.481780728665;40226.59773402228;41543.792281038404;41878.58121418747;42047;42103;58327.708361457924;68614;71870.28129718636;72956;74482.62758686172;16502;16818.747718784474;16835.915820420818;17216.225269862636;17337.95972241619;18226;18487.075646217905;18578.232140630844;18624.813751862308;18928.33750818715;14169.929150354183;16339.509814705578;17375.7393639093;17435.738463923306;17569;16107.919460402623;16451.506454806375;17098.316067357664;17156.05641689719;17169.399071032454;16272.349106035626;16291.266892990081;16471.505854824376;16690.582735431224;16745.413910513074;11754.823677645014;11790.469428875893;11862.822057669315;11901.523939042341;12352.444140013542;11753.840653829839;13285;13692.52076177329;13873.930630346784;13895.444182232597 22:27:49 INFO - PID 3467 | __end_tp_report 22:27:49 INFO - PID 3467 | __start_cc_report 22:27:49 INFO - PID 3467 | _x_x_mozilla_cycle_collect,343 22:27:49 INFO - PID 3467 | __end_cc_report 22:27:49 INFO - PID 3467 | __startTimestamp1495430869038__endTimestamp 22:27:49 INFO - PID 3467 | 22:27:49 INFO - PID 3467 | ------- Summary: start ------- 22:27:49 INFO - PID 3467 | Number of tests: 6 22:27:49 INFO - PID 3467 | 22:27:49 INFO - PID 3467 | [#0] dojo.html Cycles:165 Average:6172.03 Median:6397.81 stddev:801.12 (12.5%) stddev-sans-first:760.51 22:27:49 INFO - PID 3467 | Values: 2868.6 3527.1 3562.1 3570.9 3592.5 4663.3 5420.3 5668.6 6104.8 6121.6 6139.5 6151.9 6152.7 6171.6 6192.6 5425.9 5491.9 5536.2 5605.3 5616.2 4330.0 5492.5 5606.7 5613.2 5627.7 4601.8 4755.0 4899.6 5491.5 5610.2 4906.7 5380.0 5476.4 5496.9 5507.8 5199.4 5441.7 5466.4 5510.9 5538.0 6095.1 6191.6 6233.0 6276.0 6310.9 6477.0 6778.1 6781.4 6793.7 6818.0 5901.2 6705.8 6844.9 6850.7 6860.1 6293.7 6308.4 6322.9 6345.2 6356.3 7008.5 7021.0 7032.0 7034.3 7035.9 6397.8 6454.7 6570.5 6590.7 6631.1 6739.4 6830.3 6842.4 6849.8 6855.8 6044.6 6379.4 6500.0 6530.0 6648.1 6615.7 6661.0 6692.8 6711.1 6717.0 5341.5 5388.2 5397.2 5399.6 5449.5 4706.8 4753.0 4803.6 4813.9 4842.8 6314.9 6339.9 6359.4 6377.7 6422.6 6882.0 6885.9 6886.7 6890.5 6915.3 6252.4 6366.1 6382.8 6407.4 6417.7 5877.6 5980.9 6003.7 6007.6 6045.5 6962.5 7005.1 7044.6 7047.1 7049.0 6796.2 6819.3 6834.9 6853.6 6905.5 6796.9 6863.8 6933.8 7028.5 7051.4 6695.8 6751.2 6777.4 6778.0 6785.5 6799.8 6876.6 6909.2 6911.4 6919.8 6428.2 6474.3 6629.4 6631.1 6685.9 5924.5 6198.1 6547.5 6614.4 6663.5 6289.5 6295.1 6402.3 6468.5 6507.8 5624.9 6496.3 6512.8 6682.1 6715.0 6300.5 6331.1 6438.1 6449.3 6491.4 22:27:49 INFO - PID 3467 | 22:27:49 INFO - PID 3467 | [#1] ext.html Cycles:180 Average:46402.77 Median:186483.00 stddev:82425.31 (44.2%) stddev-sans-first:82585.40 22:27:49 INFO - PID 3467 | Values: 795.8 810.3 824.5 1222.7 1277.3 38500.2 38774.6 38807.0 38823.8 38931.0 39250.0 39261.8 39547.4 39594.8 39622.0 7558.4 7798.5 7916.9 8258.6 8524.3 7742.0 8228.0 8435.1 8535.7 8565.8 8508.3 8560.6 8579.1 8581.1 8591.4 8440.0 8527.6 8536.3 8548.9 8562.6 7115.6 7921.3 8250.9 8297.8 8310.3 36907.0 36916.6 36919.6 36931.0 36938.9 3642.5 3642.8 3682.8 3683.0 3683.1 30122.9 30235.1 30279.8 30353.2 30412.7 3045.5 3096.6 3109.8 3117.3 3123.7 299832.0 302033.0 304405.0 304424.0 305988.0 46711.8 46845.8 46846.8 46953.0 47039.8 49127.0 49167.5 51546.7 54511.7 54597.7 13947.9 13990.0 14031.8 14162.6 14166.1 11176.1 11221.0 11256.9 11343.7 11395.5 14053.4 14064.1 14090.1 14090.3 14153.9 6223.6 6286.9 6304.6 6306.8 6309.5 1018.2 1050.4 1057.5 1094.6 1100.9 24125.0 24286.6 24422.3 24436.8 24466.9 1030.6 1099.7 1144.7 1170.7 1179.7 12309.5 12556.4 12568.7 16483.8 16515.3 339127.0 341109.6 341202.0 342068.0 342975.0 56283.7 56622.0 56896.1 56913.0 56961.0 1497.2 1501.6 1542.3 1543.9 1549.1 26294.6 29595.4 30280.8 30457.1 30506.7 38776.0 38890.0 38897.8 38898.0 39413.2 294550.0 295220.0 295400.0 295748.0 296246.0 32354.7 33032.8 34216.0 34318.7 34358.8 41119.2 41560.0 42924.0 42974.1 43276.0 29251.3 29823.1 30325.5 30337.1 30704.2 29300.9 29832.6 30336.8 30358.7 30464.7 29157.7 33839.0 36133.5 36490.5 36590.3 29636.6 29716.9 30235.2 30597.8 30791.4 22021.7 22890.8 23540.4 24049.8 24467.4 22:27:49 INFO - PID 3467 | 22:27:49 INFO - PID 3467 | [#2] jquery.html Cycles:180 Average:35853.94 Median:2267.28 stddev:93156.86 (4108.7%) stddev-sans-first:93380.23 22:27:49 INFO - PID 3467 | Values: 436.8 441.2 445.6 459.8 463.8 20951.0 21272.6 21577.8 21806.8 21810.0 21541.8 21672.3 21964.7 22062.6 22721.7 5965.4 6030.7 6230.1 6242.5 6248.7 5934.1 6085.0 6156.9 6157.2 6165.4 5892.2 6001.2 6078.8 6130.9 6147.8 6052.7 6094.1 6128.9 6141.0 6141.9 5373.2 5383.3 5448.0 5450.3 5473.1 18985.6 26729.7 27516.0 27603.9 27645.9 2043.9 2243.9 2248.8 2267.1 2267.5 13739.5 16417.4 17127.0 18024.2 18092.5 2065.9 2160.3 2179.5 2192.6 2210.2 161735.0 162522.0 164512.0 164784.4 167985.2 12760.0 12771.8 12841.4 12987.9 13167.9 16347.3 16529.0 16532.3 16558.8 16570.3 8147.8 8588.7 8753.0 9074.5 9252.4 8623.7 8631.7 8642.7 8795.8 8812.5 6662.0 7983.5 8015.3 8057.2 8070.4 2177.5 2179.2 2195.9 2196.1 2373.5 831.0 947.0 976.4 977.5 1014.5 15465.7 15994.8 18124.1 21375.0 21390.8 865.7 992.9 999.3 1010.2 1035.3 9617.5 10593.8 10612.3 10913.4 11043.0 536976.3 539030.0 540114.0 557540.0 558036.0 96233.5 128048.0 137376.0 140323.0 141924.7 1182.3 1201.2 1262.4 1266.4 1269.4 21331.8 22203.9 22476.3 22710.0 22796.7 57978.4 58144.6 59788.0 60546.4 61571.5 75341.6 77167.1 80496.0 81234.6 82386.2 8801.0 8828.5 8854.5 9217.7 9290.0 14329.9 14352.3 14740.1 14886.2 15040.9 9782.8 11017.6 12009.9 12150.1 12270.1 11692.0 11931.3 12004.8 12086.7 12163.1 11744.5 12097.4 12253.8 12506.5 12738.2 8820.5 9016.0 9137.8 9166.5 9368.5 11803.0 11993.6 12027.3 12118.2 12349.9 22:27:49 INFO - PID 3467 | 22:27:49 INFO - PID 3467 | [#3] mootools.html Cycles:180 Average:7044.49 Median:3721.73 stddev:4467.12 (120.0%) stddev-sans-first:4459.62 22:27:49 INFO - PID 3467 | Values: 1414.7 1430.0 1477.8 1488.5 1489.9 10077.3 10415.3 11513.3 11833.2 11996.7 11544.3 11870.6 11891.9 11907.8 11914.1 2509.2 2553.4 2564.0 2566.1 2571.6 2493.9 2560.4 2560.8 2560.8 2563.5 2507.5 2521.3 2526.7 2536.3 2544.0 2468.7 2472.1 2475.3 2493.8 2495.2 2444.3 2457.6 2466.1 2469.5 2472.6 11424.8 11568.2 11571.9 11606.1 11663.8 6340.6 6504.9 6557.1 6558.6 6565.7 3937.5 4212.7 4251.3 4287.4 4316.9 1204.4 1212.0 1256.8 1268.6 1272.0 13649.4 16287.8 16418.3 16447.9 16450.4 5104.8 5603.9 5770.4 6020.2 6100.4 13542.9 13627.7 13725.7 13750.0 13793.7 6666.5 6681.1 6708.8 6711.7 6712.7 5888.2 6143.0 6180.5 6231.9 6265.6 4114.6 4275.3 4311.3 4315.6 4317.8 1082.8 1270.6 1276.5 1284.6 1287.9 787.0 797.8 809.6 813.4 839.7 5642.9 6085.2 6320.2 6362.2 6378.7 593.8 595.6 608.3 611.0 612.8 3632.7 3810.8 3876.9 3980.1 4275.6 13700.5 14588.9 14910.1 15012.2 15014.8 12127.0 13721.7 13739.0 13961.0 14920.6 7383.8 8441.5 8600.4 8629.2 8724.3 6638.0 6843.6 6872.7 6961.0 6998.6 12690.9 13542.9 13620.3 13697.9 13786.0 13192.4 13229.4 13485.8 13921.6 13963.0 8504.0 8822.6 9172.6 9188.2 9351.7 10939.7 11047.2 11059.7 11072.5 11076.6 8854.3 9039.2 9044.7 9046.4 9276.6 7669.2 8440.5 8850.8 8873.2 8962.5 7227.8 8090.3 8102.1 8156.6 8188.5 5034.6 5636.8 5914.1 6086.6 6124.5 5581.0 5960.2 6108.6 6211.8 6217.3 22:27:49 INFO - PID 3467 | 22:27:49 INFO - PID 3467 | [#4] prototype.html Cycles:180 Average:5302.58 Median:6047.09 stddev:1720.41 (28.5%) stddev-sans-first:1684.47 22:27:49 INFO - PID 3467 | Values: 342.4 399.2 399.5 403.2 412.6 5031.6 6089.3 6111.7 6121.0 6286.2 6004.9 6122.9 6146.5 6151.6 6160.8 4359.3 4470.6 4475.0 4485.5 4502.9 4247.0 4447.1 4465.9 4494.7 4512.4 4459.6 4474.0 4480.7 4513.5 4521.3 4358.6 4424.8 4437.3 4452.4 4475.1 3358.8 3780.8 3800.7 3815.4 3816.3 5441.8 5450.5 5534.9 5665.4 5802.0 5228.0 5272.7 5294.5 5324.3 5349.3 5421.0 5657.3 5823.8 6433.5 6544.3 4129.8 4314.4 4342.5 4349.4 4394.7 6778.6 6832.6 6998.2 7064.9 7108.8 4386.4 4398.2 4407.3 4415.5 4430.7 3582.1 3908.4 3960.4 4482.9 4483.0 3987.6 4282.7 4490.6 4500.7 5357.0 6293.9 6609.1 6609.9 6629.6 6667.9 1449.6 1498.9 1499.2 1509.7 1512.8 886.2 931.7 1000.5 1009.1 1018.6 5339.7 6251.8 6259.2 6274.7 6289.7 5835.7 6791.9 6805.0 6867.4 6905.7 6164.4 6166.3 6284.7 6301.3 6311.4 3591.7 3597.9 3607.0 3614.9 3615.2 6463.9 6865.9 7031.5 7074.9 7104.5 5883.4 6475.8 7063.5 7098.3 7111.1 6221.3 6505.8 7051.4 7137.4 7163.8 6991.5 6997.1 7002.3 7011.3 7021.5 5786.6 6681.5 6863.5 6872.7 6900.5 6869.8 7194.0 7199.8 7213.4 7230.1 3872.5 4073.5 4419.2 4425.9 4436.8 6411.5 6452.9 6871.0 6952.3 6971.6 6668.3 6712.3 6727.4 6757.4 6767.5 5063.0 6105.6 6405.3 6763.7 6790.7 5023.9 6555.4 6584.8 6592.4 6623.0 5807.0 6417.2 6436.8 6737.9 6779.5 5744.6 6443.2 6483.9 6523.0 6529.6 22:27:49 INFO - PID 3467 | 22:27:49 INFO - PID 3467 | [#5] yui.html Cycles:180 Average:22138.58 Median:17708.19 stddev:30744.96 (173.6%) stddev-sans-first:30789.60 22:27:49 INFO - PID 3467 | Values: 836.0 849.3 863.1 866.8 879.3 16445.5 17089.2 17117.9 18675.1 19269.4 17847.4 18438.4 18603.7 18607.4 18871.0 960.4 1002.4 1025.8 1028.6 1033.5 1000.1 1011.9 1017.7 1018.2 1018.8 1057.2 1073.6 1076.0 1076.8 1079.8 1057.3 1059.6 1074.0 1075.0 1077.5 762.3 769.7 779.9 781.8 782.2 16536.8 16928.7 17153.2 17179.4 17184.7 16751.0 17004.5 17083.7 17263.8 17336.6 13922.7 14120.2 14225.9 14341.4 14462.9 3098.3 3518.8 3541.8 3576.6 3576.8 94358.6 108717.5 129673.0 132094.0 132535.0 12009.7 13427.5 13516.1 13727.0 13794.5 25210.9 25794.6 25889.2 26167.3 26294.6 7856.8 7939.8 7978.6 7979.3 7988.8 3848.7 3937.5 3941.4 3947.9 3949.4 3860.3 4163.8 4329.7 4375.9 4377.1 776.4 916.2 948.6 955.6 958.6 648.3 668.3 669.8 678.8 687.4 11073.7 12001.8 12012.3 12151.5 12383.9 644.6 650.6 654.8 683.6 685.0 5394.0 5659.6 5670.3 5694.5 5703.0 106899.0 107185.0 111172.0 111900.0 113501.0 65778.0 87706.6 88062.0 88523.0 97929.5 75244.0 77314.0 80340.6 81347.0 83519.6 10725.4 11393.0 12161.9 12752.6 12955.5 40226.6 41543.8 41878.6 42047.0 42103.0 58327.7 68614.0 71870.3 72956.0 74482.6 16502.0 16818.7 16835.9 17216.2 17338.0 18226.0 18487.1 18578.2 18624.8 18928.3 14169.9 16339.5 17375.7 17435.7 17569.0 16107.9 16451.5 17098.3 17156.1 17169.4 16272.3 16291.3 16471.5 16690.6 16745.4 11754.8 11790.5 11862.8 11901.5 12352.4 11753.8 13285.0 13692.5 13873.9 13895.4 22:27:49 INFO - PID 3467 | -------- Summary: end -------- 22:27:49 INFO - PID 3467 | 22:27:49 INFO - TEST-INFO | 3467: exit 0 22:27:49 INFO - TEST-OK | dromaeo_css | took 1113272ms 22:27:49 INFO - TEST-START | kraken 22:27:49 INFO - operating with platform_type : mac_ 22:27:49 INFO - Initialising browser for kraken test... 22:27:49 INFO - TEST-INFO | started process 3475 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmp9wYXJV/profile http://localhost:61897/getInfo.html) 22:27:50 INFO - PID 3475 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2970: String.localeCompare is deprecated; use String.prototype.localeCompare instead 22:27:52 INFO - PID 3475 | 2017-05-21 22:27:52.152 plugin-container[3476:55551] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 22:27:52 INFO - PID 3475 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:27:52 INFO - PID 3475 | 2017-05-21 22:27:52.156 plugin-container[3476:55551] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 22:27:52 INFO - PID 3475 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:27:52 INFO - PID 3475 | 2017-05-21 22:27:52.157 plugin-container[3476:55551] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:27:52 INFO - PID 3475 | __metrics Screen width/height:1600/1200 22:27:52 INFO - PID 3475 | colorDepth:24 22:27:52 INFO - PID 3475 | Browser inner width/height: 1024/691 22:27:52 INFO - PID 3475 | __metrics 22:27:53 INFO - TEST-INFO | 3475: exit 0 22:27:53 INFO - Browser initialized. 22:27:53 INFO - Running cycle 1/1 for kraken test... 22:27:53 INFO - TEST-INFO | started process 3478 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmp9wYXJV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:27:55 INFO - PID 3478 | 2017-05-21 22:27:55.519 plugin-container[3479:55693] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 22:27:55 INFO - PID 3478 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:27:55 INFO - PID 3478 | 2017-05-21 22:27:55.521 plugin-container[3479:55693] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 22:27:55 INFO - PID 3478 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:27:55 INFO - PID 3478 | 2017-05-21 22:27:55.521 plugin-container[3479:55693] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:27:56 INFO - PID 3478 | 2017-05-21 22:27:56.260 plugin-container[3480:55739] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 22:27:56 INFO - PID 3478 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:27:56 INFO - PID 3478 | 2017-05-21 22:27:56.260 plugin-container[3480:55739] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 22:27:56 INFO - PID 3478 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:27:56 INFO - PID 3478 | 2017-05-21 22:27:56.260 plugin-container[3480:55739] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:28:05 INFO - PID 3478 | RSS: Main: 242073600 22:28:05 INFO - PID 3478 | 22:28:08 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61897/tests/kraken/driver.html?testName=audio-beat-detection) 22:28:09 INFO - PID 3478 | RSS: Main: 253722624 22:28:09 INFO - PID 3478 | 22:28:11 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61897/tests/kraken/driver.html?testName=audio-dft) 22:28:12 INFO - PID 3478 | RSS: Main: 262823936 22:28:12 INFO - PID 3478 | 22:28:16 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61897/tests/kraken/driver.html?testName=audio-fft) 22:28:16 INFO - PID 3478 | RSS: Main: 264994816 22:28:16 INFO - PID 3478 | 22:28:18 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61897/tests/kraken/driver.html?testName=audio-oscillator) 22:28:19 INFO - PID 3478 | RSS: Main: 266854400 22:28:19 INFO - PID 3478 | 22:28:21 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61897/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:28:21 INFO - PID 3478 | RSS: Main: 267321344 22:28:21 INFO - PID 3478 | 22:28:24 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61897/tests/kraken/driver.html?testName=imaging-darkroom) 22:28:25 INFO - PID 3478 | RSS: Main: 276287488 22:28:25 INFO - PID 3478 | 22:28:28 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61897/tests/kraken/driver.html?testName=imaging-desaturate) 22:28:29 INFO - PID 3478 | RSS: Main: 276733952 22:28:29 INFO - PID 3478 | 22:28:32 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61897/tests/kraken/driver.html?testName=json-parse-financial) 22:28:32 INFO - PID 3478 | RSS: Main: 275644416 22:28:32 INFO - PID 3478 | 22:28:34 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61897/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:28:34 INFO - PID 3478 | RSS: Main: 275222528 22:28:34 INFO - PID 3478 | 22:28:36 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:28:37 INFO - PID 3478 | RSS: Main: 275324928 22:28:37 INFO - PID 3478 | 22:28:39 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:28:40 INFO - PID 3478 | RSS: Main: 274952192 22:28:40 INFO - PID 3478 | 22:28:43 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:28:43 INFO - PID 3478 | RSS: Main: 275222528 22:28:43 INFO - PID 3478 | 22:28:47 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:28:47 INFO - PID 3478 | RSS: Main: 275464192 22:28:47 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61897/tests/kraken/driver.html?testName=ai-astar) 22:28:49 INFO - PID 3478 | RSS: Main: 277032960 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | __start_tp_report 22:28:49 INFO - PID 3478 | _x_x_mozilla_page_load 22:28:49 INFO - PID 3478 | _x_x_mozilla_page_load_details 22:28:49 INFO - PID 3478 | |i|pagename|runs| 22:28:49 INFO - PID 3478 | |0;ai-astar;148;167;116;113;116;113;113;116;113;114 22:28:49 INFO - PID 3478 | |1;audio-beat-detection;140;110;114;154;117;150;98;149;164;148 22:28:49 INFO - PID 3478 | |2;audio-dft;200;202;196;217;208;271;277;204;199;201 22:28:49 INFO - PID 3478 | |3;audio-fft;81;56;60;116;79;58;62;81;93;83 22:28:49 INFO - PID 3478 | |4;audio-oscillator;80;70;86;71;68;69;90;70;69;89 22:28:49 INFO - PID 3478 | |5;imaging-gaussian-blur;89;89;90;95;102;136;136;127;89;92 22:28:49 INFO - PID 3478 | |6;imaging-darkroom;94;96;95;96;97;96;96;96;96;96 22:28:49 INFO - PID 3478 | |7;imaging-desaturate;93;94;90;122;138;91;95;92;92;103 22:28:49 INFO - PID 3478 | |8;json-parse-financial;49;49;53;50;51;49;50;52;51;49 22:28:49 INFO - PID 3478 | |9;json-stringify-tinderbox;55;61;61;72;67;62;57;59;59;60 22:28:49 INFO - PID 3478 | |10;stanford-crypto-aes;81;84;85;86;87;102;107;105;110;83 22:28:49 INFO - PID 3478 | |11;stanford-crypto-ccm;142;150;147;140;136;144;156;161;190;149 22:28:49 INFO - PID 3478 | |12;stanford-crypto-pbkdf2;228;281;189;184;181;186;182;189;183;184 22:28:49 INFO - PID 3478 | |13;stanford-crypto-sha256-iterative;71;71;67;69;70;69;66;70;66;69 22:28:49 INFO - PID 3478 | __end_tp_report 22:28:49 INFO - PID 3478 | __start_cc_report 22:28:49 INFO - PID 3478 | _x_x_mozilla_cycle_collect,914 22:28:49 INFO - PID 3478 | __end_cc_report 22:28:49 INFO - PID 3478 | __startTimestamp1495430929589__endTimestamp 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | ------- Summary: start ------- 22:28:49 INFO - PID 3478 | Number of tests: 14 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | [#0] ai-astar Cycles:10 Average:122.90 Median:116.00 stddev:18.82 (16.2%) stddev-sans-first:17.64 22:28:49 INFO - PID 3478 | Values: 148.0 167.0 116.0 113.0 116.0 113.0 113.0 116.0 113.0 114.0 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | [#1] audio-beat-detection Cycles:10 Average:134.40 Median:149.50 stddev:22.54 (15.1%) stddev-sans-first:23.82 22:28:49 INFO - PID 3478 | Values: 140.0 110.0 114.0 154.0 117.0 150.0 98.0 149.0 164.0 148.0 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | [#2] audio-dft Cycles:10 Average:217.50 Median:206.00 stddev:30.37 (14.7%) stddev-sans-first:31.54 22:28:49 INFO - PID 3478 | Values: 200.0 202.0 196.0 217.0 208.0 271.0 277.0 204.0 199.0 201.0 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | [#3] audio-fft Cycles:10 Average:76.90 Median:80.00 stddev:18.75 (23.4%) stddev-sans-first:19.83 22:28:49 INFO - PID 3478 | Values: 81.0 56.0 60.0 116.0 79.0 58.0 62.0 81.0 93.0 83.0 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | [#4] audio-oscillator Cycles:10 Average:76.20 Median:75.50 stddev:9.07 (12.0%) stddev-sans-first:9.51 22:28:49 INFO - PID 3478 | Values: 80.0 70.0 86.0 71.0 68.0 69.0 90.0 70.0 69.0 89.0 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | [#5] imaging-gaussian-blur Cycles:10 Average:104.50 Median:89.00 stddev:20.21 (22.7%) stddev-sans-first:20.64 22:28:49 INFO - PID 3478 | Values: 89.0 89.0 90.0 95.0 102.0 136.0 136.0 127.0 89.0 92.0 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:96.00 stddev:0.79 (0.8%) stddev-sans-first:0.50 22:28:49 INFO - PID 3478 | Values: 94.0 96.0 95.0 96.0 97.0 96.0 96.0 96.0 96.0 96.0 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | [#7] imaging-desaturate Cycles:10 Average:101.00 Median:92.00 stddev:16.15 (17.5%) stddev-sans-first:16.86 22:28:49 INFO - PID 3478 | Values: 93.0 94.0 90.0 122.0 138.0 91.0 95.0 92.0 92.0 103.0 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.50 stddev:1.42 (2.8%) stddev-sans-first:1.42 22:28:49 INFO - PID 3478 | Values: 49.0 49.0 53.0 50.0 51.0 49.0 50.0 52.0 51.0 49.0 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:61.00 stddev:4.92 (8.1%) stddev-sans-first:4.66 22:28:49 INFO - PID 3478 | Values: 55.0 61.0 61.0 72.0 67.0 62.0 57.0 59.0 59.0 60.0 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:83.50 stddev:11.47 (13.7%) stddev-sans-first:11.31 22:28:49 INFO - PID 3478 | Values: 81.0 84.0 85.0 86.0 87.0 102.0 107.0 105.0 110.0 83.0 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | [#11] stanford-crypto-ccm Cycles:10 Average:151.50 Median:149.50 stddev:15.42 (10.3%) stddev-sans-first:15.97 22:28:49 INFO - PID 3478 | Values: 142.0 150.0 147.0 140.0 136.0 144.0 156.0 161.0 190.0 149.0 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.70 Median:187.50 stddev:32.06 (17.1%) stddev-sans-first:32.21 22:28:49 INFO - PID 3478 | Values: 228.0 281.0 189.0 184.0 181.0 186.0 182.0 189.0 183.0 184.0 22:28:49 INFO - PID 3478 | 22:28:49 INFO - PID 3478 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.50 stddev:1.87 (2.7%) stddev-sans-first:1.81 22:28:49 INFO - PID 3478 | Values: 71.0 71.0 67.0 69.0 70.0 69.0 66.0 70.0 66.0 69.0 22:28:49 INFO - PID 3478 | -------- Summary: end -------- 22:28:49 INFO - PID 3478 | 22:28:50 INFO - TEST-INFO | 3478: exit 0 22:28:50 INFO - TEST-OK | kraken | took 60803ms 22:28:50 INFO - SUITE-END | took 1174s 22:28:50 INFO - Completed test suite (00:19:34) 22:28:50 INFO - javascript benchmark 22:28:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7912.532079168813, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2868.627078479797, 3527.1182204448905, 3562.091666625012, 3570.8571657133716, 3592.461130830375, 4663.323818046386, 5420.322459692538, 5668.631538949965, 6104.816855494342, 6121.6020958637655, 6139.522803442366, 6151.8769624607485, 6152.661603611799, 6171.598846075013, 6192.597481163722, 5425.864353391157, 5491.945080549203, 5536.2249285099915, 5605.327360716719, 5616.213730077772, 4330.0, 5492.450754924515, 5606.691631960241, 5613.186088017235, 5627.7467513961765, 4601.792919318638, 4754.952450475503, 4899.583535399495, 5491.478309560605, 5610.214569960209, 4906.681065730716, 5380.0, 5476.397596264408, 5496.890062198733, 5507.779688812443, 5199.350081239845, 5441.700706461183, 5466.398696143421, 5510.889782204334, 5538.0, 6095.05526643371, 6191.628502289877, 6233.0338797486465, 6276.0, 6310.92714238576, 6477.028445733178, 6778.084958530634, 6781.38967492928, 6793.694283757242, 6818.0, 5901.203337549418, 6705.832354191184, 6844.93155068448, 6850.691718872663, 6860.108185935796, 6293.748250070037, 6308.400701933309, 6322.873542529123, 6345.238571371418, 6356.300806911236, 7008.544444611084, 7021.0, 7032.015517827508, 7034.2965703429245, 7035.92964070363, 6397.808065758035, 6454.677266136674, 6570.540062195696, 6590.670466476657, 6631.104800851823, 6739.427148692416, 6830.282820303796, 6842.4183944364295, 6849.760258390932, 6855.760048398282, 6044.607100538451, 6379.3620637936765, 6500.0, 6530.020496925499, 6648.1025061616065, 6615.702293396808, 6661.034150048215, 6692.765753198712, 6711.127553418024, 6716.966415167991, 5341.4925582069645, 5388.191771234347, 5397.217403476474, 5399.622026458189, 5449.455054494598, 4706.811727530929, 4753.025629745894, 4803.5916947059195, 4813.879653008633, 4842.782074806642, 6314.905276420857, 6339.936600634027, 6359.395857393541, 6377.681115944184, 6422.6467544285515, 6882.03651488792, 6885.931140688629, 6886.690098945459, 6890.517663763489, 6915.273896240923, 6252.406021427957, 6366.140571022853, 6382.808515744535, 6407.359264073649, 6417.711202995876, 5877.647341159544, 5980.880382392328, 6003.699815009232, 6007.609505382136, 6045.516358691294, 6962.512624116263, 7005.124359455068, 7044.612546310104, 7047.119110111236, 7048.964755176191, 6796.150481189851, 6819.318068193141, 6834.8633027340165, 6853.554518956252, 6905.482088843457, 6796.93203067963, 6863.828404289933, 6933.791986240421, 7028.543144695578, 7051.43588512928, 6695.832604184934, 6751.189857217166, 6777.423918966943, 6777.966110169417, 6785.457163426815, 6799.777707208488, 6876.621785801829, 6909.205441374306, 6911.3779759821855, 6919.757808476679, 6428.228612566335, 6474.320196379313, 6629.370209830058, 6631.0716499689115, 6685.8997115044285, 5924.526037917042, 6198.132261483486, 6547.541672083099, 6614.437772789367, 6663.533552651462, 6289.528285378524, 6295.1186833842385, 6402.295747467868, 6468.514861385509, 6507.7722279721875, 5624.915626265692, 6496.317886621837, 6512.772052978123, 6682.064510968566, 6715.0, 6300.527460440394, 6331.113644089924, 6438.098666186644, 6449.322821103904, 6491.350864913471], "value": 6114.524622329393, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.8209402884348, 810.335524869607, 824.5052968219068, 1222.7248869004475, 1277.2591896699914, 38500.22999540008, 38774.61225387752, 38807.0, 38823.835284941495, 38931.0, 39249.95375947931, 39261.80369098158, 39547.40678889819, 39594.8020259899, 39622.00944976377, 7558.395328373717, 7798.5320880747195, 7916.881246781303, 8258.587070646474, 8524.275436587897, 7742.0, 8227.95886020569, 8435.072142064368, 8535.658573657047, 8565.772899046231, 8508.276796472308, 8560.571971401436, 8579.056303806576, 8581.056083830774, 8591.35564832637, 8440.029396619375, 8527.616257268437, 8536.317094632415, 8548.871766923501, 8562.571871406468, 7115.5730656160795, 7921.287084162453, 8250.917490825135, 8297.83404331916, 8310.25207731301, 36907.0, 36916.63083369159, 36919.630803692155, 36931.0, 36938.89183324505, 3642.453631955201, 3642.763220390666, 3682.760620559655, 3683.0055884911194, 3683.0608194910396, 30122.945696900722, 30235.09294721162, 30279.848600757075, 30353.241168970733, 30412.695873041208, 3045.4670432674193, 3096.628404591441, 3109.7667674924473, 3117.283024904262, 3123.687631236881, 299832.0, 302032.9796702027, 304405.0, 304424.0, 305988.0, 46711.766441168256, 46845.765771170925, 46846.76576617095, 46953.0, 47039.76480117646, 49127.0, 49167.508324917006, 51546.74226628843, 54511.727441363335, 54597.72701136469, 13947.86052139486, 13990.0, 14031.78952315716, 14162.575122746333, 14166.079204851652, 11176.050035747052, 11221.0, 11256.943715281372, 11343.659690209306, 11395.544178232944, 14053.400120977447, 14064.085834420934, 14090.084144530572, 14090.295485225697, 14153.85846141546, 6223.564350495421, 6286.874262514724, 6304.621722696653, 6306.801707675527, 6309.495240380758, 1018.2414101494434, 1050.3855244681795, 1057.461393672205, 1094.5731164845718, 1100.8844071372391, 24125.0, 24286.635700464507, 24422.26733198007, 24436.8232129822, 24466.87766561156, 1030.6341248856697, 1099.7133353975867, 1144.6794897428733, 1170.7365842685329, 1179.6637958181877, 12309.522857257007, 12556.372181390894, 12568.74862502763, 16483.83516164847, 16515.33938642465, 339127.0, 341109.58890411275, 341202.0, 342068.0, 342975.0, 56283.71858140683, 56622.0, 56896.146557801665, 56913.0, 56961.0, 1497.161589509878, 1501.5795577238391, 1542.3367951780617, 1543.8919275650596, 1549.147968617256, 26294.62315469964, 29595.40809183847, 30280.84859575688, 30457.086287411414, 30506.694933050385, 38776.030599235244, 38890.02774930649, 38897.80551097226, 38898.0, 39413.21173576455, 294550.0, 295220.0, 295400.0, 295748.0, 296246.0, 32354.676453235166, 33032.83483582567, 34216.0, 34318.656813431546, 34358.82820585981, 41119.177616446905, 41560.0, 42924.0, 42974.140517190106, 43276.0, 29251.268718282212, 29823.10530684083, 30325.545116823705, 30337.089887303417, 30704.232394190323, 29300.853495732386, 29832.552511711907, 30336.84831575828, 30358.69641303559, 30464.695353046187, 29157.7084229155, 33839.0, 36133.457998129525, 36490.452643210905, 36590.26819463649, 29636.555451668675, 29716.85141574278, 30235.244118897204, 30597.847010764803, 30791.384172316877, 22021.669674954566, 22890.771092288865, 23540.411489712893, 24049.75950240475, 24467.38831529226], "value": 17780.17290588505, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.7641473604254, 441.2036274524484, 445.5633479190394, 459.7976890168326, 463.7912939177369, 20951.0, 21272.57454850904, 21577.784222157774, 21806.781932180675, 21810.0, 21541.815200163986, 21672.34982950514, 21964.670529942025, 22062.558748825013, 22721.659175112385, 5965.403459654043, 6030.698465076762, 6230.096635987779, 6242.531810114237, 6248.718807653644, 5934.080217566284, 6085.0, 6156.907646385308, 6157.230346206724, 6165.383461653843, 5892.175095486636, 6001.189839371695, 6078.787242446493, 6130.908036379458, 6147.846303842417, 6052.667103309316, 6094.116353128816, 6128.877422451571, 6141.0, 6141.938580614182, 5373.194020896858, 5383.300170977787, 5448.019356515825, 5450.345958484968, 5473.124300112003, 18985.62028759431, 26729.663516824083, 27516.03693870705, 27603.86198069017, 27645.86177069122, 2043.948901277465, 2243.9439014024615, 2248.7638797926147, 2267.0591704442636, 2267.501149747053, 13739.519116830963, 16417.42539011129, 17127.0, 18024.188911498884, 18092.54768630795, 2065.896705164751, 2160.25471212433, 2179.455136215946, 2192.561487702466, 2210.2264207527396, 161735.0, 162521.9999999988, 164512.0, 164784.3521564781, 167985.16007420007, 12760.0, 12771.808422873479, 12841.357932103358, 12987.896028837466, 13167.934160329138, 16347.34610615586, 16529.00825950447, 16532.256048477848, 16558.83441165573, 16570.337186512646, 8147.837043259102, 8588.711693246063, 8753.0, 9074.45553266806, 9252.444853308823, 8623.655053797778, 8631.741047768577, 8642.740717778477, 8795.780105497413, 8812.515311657791, 6661.966690166615, 7983.481073730305, 8015.318697910627, 8057.234562716532, 8070.435069545193, 2177.466520702438, 2179.2372669565843, 2195.9121635134734, 2196.099599164335, 2373.4778348763243, 830.9542975136426, 946.9857952130586, 976.3848775271533, 977.5161295158941, 1014.4826138669332, 15465.690686186213, 15994.840051599567, 18124.093795310182, 21375.03812328449, 21390.82350470739, 865.6710450029075, 992.8709267795141, 999.3054826895238, 1010.1968934696934, 1035.2649618770756, 9617.471039092778, 10593.84109238362, 10612.310199836986, 10913.39976301311, 11043.0, 536976.3151184297, 539030.0, 540114.0, 557540.0, 558036.0, 96233.51883240679, 128048.0, 137376.0, 140323.0, 141924.72463276054, 1182.266994463421, 1201.1711918776016, 1262.4093642011132, 1266.4301064521003, 1269.4224128021729, 21331.78668213298, 22203.888980554995, 22476.32571022872, 22710.0, 22796.658050128928, 57978.420215797305, 58144.63915206292, 59788.0, 60546.394536055835, 61571.46071348073, 75341.6232918832, 77167.05496505341, 80495.98760031046, 81234.59382703049, 82386.17613824025, 8800.95599522024, 8828.470291782514, 8854.468731876108, 9217.677381291622, 9289.953550232205, 14329.9283503586, 14352.282385880873, 14740.13869211824, 14886.18126003037, 15040.924795375953, 9782.804343913223, 11017.559297628006, 12009.879901200877, 12150.116731906215, 12270.141090123605, 11692.00617947484, 11931.343776092051, 12004.75990480168, 12086.697832554257, 12163.087768417226, 11744.530218791495, 12097.395130243629, 12253.816192757295, 12506.499740010298, 12738.172018818746, 8820.514871682119, 9016.0, 9137.761248493896, 9166.495842728715, 9368.531573421438, 11803.0, 11993.64019079429, 12027.338496382434, 12118.151729378858, 12349.876501234872], "value": 10517.641879769553, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.724128794885, 1429.9564947172157, 1477.79559658878, 1488.5459934719913, 1489.9031562948408, 10077.294589378746, 10415.270931034831, 11513.251638643482, 11833.171677982535, 11996.700082497931, 11544.307341559512, 11870.584529541467, 11891.881081189185, 11907.7618447631, 11914.10644201684, 2509.222141136249, 2553.3744232663025, 2564.0, 2566.0633868638, 2571.627114068459, 2493.9002439902383, 2560.4111054457485, 2560.7695307422327, 2560.7695307422327, 2563.5257477366745, 2507.4608959073794, 2521.3318470605213, 2526.68416447944, 2536.3278731136265, 2543.9618405723836, 2468.7037555493366, 2472.134752836502, 2475.3192871960173, 2493.7880280176028, 2495.201535508638, 2444.340028192378, 2457.5699252630898, 2466.1491785334033, 2469.469064151216, 2472.5920223163157, 11424.828627570592, 11568.190226684135, 11571.884281157165, 11606.129540284428, 11663.766724665544, 6340.556161068727, 6504.8699026019685, 6557.114789503404, 6558.573692709959, 6565.737370505174, 3937.5471820740695, 4212.662986961066, 4251.319788833772, 4287.378330142144, 4316.920769807548, 1204.4339160594507, 1211.9757604847941, 1256.824044633752, 1268.5560053981126, 1271.974560508794, 13649.38577763993, 16287.83712162875, 16418.26117824701, 16447.91776041124, 16450.424235151833, 5104.821331253463, 5603.8792241551855, 5770.42295770428, 6020.247469066367, 6100.420460056288, 13542.864571354161, 13627.727445451035, 13725.656858578615, 13750.0, 13793.655158621115, 6666.500012499082, 6681.1314529110905, 6708.765193218312, 6711.697973591103, 6712.6979285931275, 5888.234529511222, 6142.969285153546, 6180.536459765535, 6231.937680623136, 6265.561410701298, 4114.6091121343425, 4275.251830929575, 4311.288637374798, 4315.611594956469, 4317.805698743564, 1082.7888561730545, 1270.6315168601002, 1276.495784165268, 1284.6017734502334, 1287.8905293050195, 786.9842603147905, 797.7833803449786, 809.6113865344668, 813.4265342933336, 839.6557411461272, 5642.887142257132, 6085.174447665771, 6320.241571011508, 6362.231417318928, 6378.681065946684, 593.8337265565649, 595.6396380190009, 608.3034925010837, 611.0071134406589, 612.788587937161, 3632.654897784706, 3810.8094595270127, 3876.8836934891997, 3980.104476492766, 4275.551067137968, 13700.45198192061, 14588.927055364868, 14910.105393676415, 15012.249387530579, 15014.774778378332, 12127.02983761297, 13721.725565488834, 13739.0, 13961.022728409116, 14920.552383428514, 7383.81540461482, 8441.535715535581, 8600.440971336842, 8629.223369896614, 8724.302055835646, 6638.0, 6843.589384636938, 6872.69072891711, 6961.0, 6998.580085194904, 12690.936545317214, 13542.932285338511, 13620.250886201355, 13697.863021370058, 13786.034977551475, 13192.406341714453, 13229.404676789374, 13485.797713034117, 13921.58235252944, 13963.0, 8504.0, 8822.558872056243, 9172.633094676139, 9188.21900138469, 9351.672691455766, 10939.671809845717, 11047.226694131334, 11059.72350691239, 11072.501737421997, 11076.612318569134, 8854.335924805537, 9039.186473217442, 9044.728658140266, 9046.365983355812, 9276.62893484253, 7669.159401743653, 8440.53577053268, 8850.849389579313, 8873.157050080232, 8962.462252264884, 7227.7831665050135, 8090.312323452573, 8102.108768035393, 8156.592170391576, 8188.50868947865, 5034.622403319692, 5636.84444688846, 5914.112883067574, 6086.60437071589, 6124.479419249235, 5580.972095139498, 5960.165576819336, 6108.602940808833, 6211.844703882439, 6217.347178546188], "value": 5286.89966867025, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.39567163955616, 399.19960479239137, 399.5485101834926, 403.21574537524526, 412.6348181859054, 5031.647784655075, 6089.269287685478, 6111.7249723762425, 6120.969395153029, 6286.2456505219325, 6004.919114559378, 6122.877542449149, 6146.508279337653, 6151.600145990507, 6160.829442405951, 4359.324304732756, 4470.597646211832, 4474.955250447487, 4485.461744590653, 4502.909941801162, 4246.957530424703, 4447.088346888896, 4465.866024019285, 4494.68537202396, 4512.435945506812, 4459.554044595547, 4473.9552604473865, 4480.708753930984, 4513.480949690778, 4521.299198624219, 4358.607725304738, 4424.800883960197, 4437.334399840018, 4452.376667266585, 4475.104979004196, 3358.80762329372, 3780.7731536107644, 3800.6959443244473, 3815.4276858471176, 3816.3321418751607, 5441.80280338325, 5450.454954504517, 5534.861628459281, 5665.376808551097, 5801.970990145064, 5228.0, 5272.710000949946, 5294.549963253128, 5324.281222035053, 5349.277847490617, 5420.970015697005, 5657.321121465409, 5823.816068974134, 6433.549651524395, 6544.280129185786, 4129.752214867118, 4314.374415709736, 4342.478902531717, 4349.412829268072, 4394.670399720002, 6778.559393639398, 6832.555883867532, 6998.230194678581, 7064.894026589605, 7108.822279443004, 4386.363977223285, 4398.23030969579, 4407.316865885793, 4415.536368681306, 4430.734155950653, 3582.0507565495163, 3908.4304865566455, 3960.4257382679925, 4482.932756008662, 4482.977585112054, 3987.5813039631003, 4282.678799090081, 4490.595846373842, 4500.729956202638, 5357.035733567917, 6293.905591416133, 6609.050330152617, 6609.867802644017, 6629.602223866584, 6667.86664266712, 1449.5723761490385, 1498.9400423982918, 1499.1529785671062, 1509.7131545006414, 1512.8260250071382, 886.2334081019872, 931.729798358482, 1000.5047501486665, 1009.0666133826328, 1018.6248564438098, 5339.679619222859, 6251.78118765841, 6259.186305780219, 6274.686265686789, 6289.7169627366875, 5835.708214589254, 6791.864162716718, 6804.9931526419605, 6867.381935625718, 6905.723771049102, 6164.3835616438, 6166.260048794174, 6284.748610055546, 6301.338359472189, 6311.400416960381, 3591.712662986955, 3597.9100522486624, 3606.9819650902105, 3614.8734794282072, 3615.240799432096, 6463.903041454382, 6865.910340346863, 7031.542949708252, 7074.92925070753, 7104.538205016657, 5883.382244864313, 6475.838104047437, 7063.470239731938, 7098.3256590623805, 7111.146662400545, 6221.329415644791, 6505.837354066186, 7051.365377116084, 7137.393321567621, 7163.78508644731, 6991.545549539263, 6997.1253593300835, 7002.334778196063, 7011.26381729911, 7021.507364885392, 5786.627482732228, 6681.532292739462, 6863.519553631198, 6872.69072891711, 6900.481893983316, 6869.793906182823, 7194.0, 7199.832073887473, 7213.350798428167, 7230.13238411394, 3872.5159355080614, 4073.531543872374, 4419.18245124653, 4425.919817685683, 4436.800343984464, 6411.455026322628, 6452.869968375247, 6871.025908702571, 6952.339527744856, 6971.581705097507, 6668.333166683293, 6712.328767123444, 6727.394534491919, 6757.391834734897, 6767.492438067263, 5062.987402519584, 6105.603135796159, 6405.295417504164, 6763.6618169092335, 6790.660466976533, 5023.874403139951, 6555.4427873629365, 6584.8353791155605, 6592.43964263043, 6622.966885165543, 5806.983777838776, 6417.2299324081405, 6436.8390790230615, 6737.9326206737305, 6779.525433219627, 5744.569157313134, 6443.162388889415, 6483.935160648333, 6523.0, 6529.608223506605], "value": 4760.61344143508, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [835.9791005224872, 849.295085079384, 863.0851297624522, 866.8439680857443, 879.2614204068581, 16445.50663480096, 17089.230984605692, 17117.914410427948, 18675.06624668768, 19269.421917342468, 17847.375341863004, 18438.354657586955, 18603.720944185847, 18607.441776746684, 18871.0, 960.3709570231509, 1002.413588050991, 1025.84612308154, 1028.5702873006526, 1033.534909290822, 1000.0749306891133, 1011.9342242754198, 1017.6845177994808, 1018.210886562916, 1018.836986082227, 1057.1965306367179, 1073.5598404654093, 1075.983860242093, 1076.8438576406456, 1079.7732476179972, 1057.3338796558157, 1059.607945060325, 1074.049466222399, 1074.978500429995, 1077.4720387010348, 762.2796457347808, 769.707511145761, 779.8869163971249, 781.7889169924146, 782.1708988472237, 16536.834631653648, 16928.66142677152, 17153.228104735317, 17179.398721044705, 17184.656306873916, 16751.0, 17004.489865303938, 17083.743743843854, 17263.827361726475, 17336.566585835328, 13922.651933701638, 14120.152790832582, 14225.857741422557, 14341.354639041268, 14462.855371446361, 3098.349346637209, 3518.8064656443885, 3541.8052007139595, 3576.5708115026105, 3576.7675101118343, 94358.64103397401, 108717.45641271822, 129673.0, 132094.0, 132535.0, 12009.699757506132, 13427.530036448677, 13516.053876228767, 13727.039107262395, 13794.517191898236, 25210.873945630156, 25794.613080803803, 25889.22332330033, 26167.345816354744, 26294.6055809163, 7856.803579910434, 7939.761807145679, 7978.60106994648, 7979.3217576505485, 7988.760337189894, 3848.692104631679, 3937.5078115235597, 3941.4481972523868, 3947.940780888289, 3949.4273330366937, 3860.3244432224246, 4163.770992595436, 4329.718568293051, 4375.912481750347, 4377.058932329564, 776.3517462918526, 916.2349438219162, 948.5636607160645, 955.6225291010151, 958.5782255807422, 648.3386945315751, 668.3283300283184, 669.778972938929, 678.7861823525574, 687.3744892148221, 11073.667789966314, 12001.759964800654, 12012.279263244232, 12151.453184606715, 12383.876161238453, 644.5616980453243, 650.5933791380387, 654.7839213059679, 683.6274230544366, 685.0204207982533, 5394.0290747665795, 5659.603827732019, 5670.262865827416, 5694.487496125285, 5703.030484817591, 106898.98257630553, 107185.0, 111172.02607603504, 111900.0, 113501.0, 65778.0, 87706.56146719225, 88062.0, 88523.0, 97929.51035244777, 75244.0, 77314.0, 80340.59829700814, 81347.0, 83519.58240208881, 10725.41010244444, 11393.031592314746, 12161.939190304345, 12752.617421477371, 12955.481780728665, 40226.59773402228, 41543.792281038404, 41878.58121418747, 42047.0, 42103.0, 58327.708361457924, 68614.0, 71870.28129718636, 72956.0, 74482.62758686172, 16502.0, 16818.747718784474, 16835.915820420818, 17216.225269862636, 17337.95972241619, 18226.0, 18487.075646217905, 18578.232140630844, 18624.813751862308, 18928.33750818715, 14169.929150354183, 16339.509814705578, 17375.7393639093, 17435.738463923306, 17569.0, 16107.919460402623, 16451.506454806375, 17098.316067357664, 17156.05641689719, 17169.399071032454, 16272.349106035626, 16291.266892990081, 16471.505854824376, 16690.582735431224, 16745.413910513074, 11754.823677645014, 11790.469428875893, 11862.822057669315, 11901.523939042341, 12352.444140013542, 11753.840653829839, 13285.0, 13692.52076177329, 13873.930630346784, 13895.444182232597], "value": 8526.683392810302, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 167.0, 116.0, 113.0, 116.0, 113.0, 113.0, 116.0, 113.0, 114.0], "value": 122.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 110.0, 114.0, 154.0, 117.0, 150.0, 98.0, 149.0, 164.0, 148.0], "value": 134.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 202.0, 196.0, 217.0, 208.0, 271.0, 277.0, 204.0, 199.0, 201.0], "value": 217.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 56.0, 60.0, 116.0, 79.0, 58.0, 62.0, 81.0, 93.0, 83.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 70.0, 86.0, 71.0, 68.0, 69.0, 90.0, 70.0, 69.0, 89.0], "value": 76.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 90.0, 95.0, 102.0, 136.0, 136.0, 127.0, 89.0, 92.0], "value": 104.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 90.0, 122.0, 138.0, 91.0, 95.0, 92.0, 92.0, 103.0], "value": 101.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 53.0, 50.0, 51.0, 49.0, 50.0, 52.0, 51.0, 49.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 61.0, 61.0, 72.0, 67.0, 62.0, 57.0, 59.0, 59.0, 60.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 85.0, 86.0, 87.0, 102.0, 107.0, 105.0, 110.0, 83.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 150.0, 147.0, 140.0, 136.0, 144.0, 156.0, 161.0, 190.0, 149.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [228.0, 281.0, 189.0, 184.0, 181.0, 186.0, 182.0, 189.0, 183.0, 184.0], "value": 198.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 67.0, 69.0, 70.0, 69.0, 66.0, 70.0, 66.0, 69.0], "value": 68.8, "unit": "score"}]}]} 22:28:50 INFO - Return code: 0 22:28:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:28:50 INFO - # TBPL SUCCESS # 22:28:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:28:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:28:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:28:50 INFO - 2017-05-21 22:28:50.929 screenresolution[3485:56120] starting screenresolution argv=screenresolution get 22:28:50 INFO - 2017-05-21 22:28:50.931 screenresolution[3485:56120] Display 0: 1600x1200x32@60 22:28:50 INFO - 2017-05-21 22:28:50.937 screenresolution[3486:56125] starting screenresolution argv=screenresolution list 22:28:50 INFO - Available Modes on Display 0 22:28:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:28:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:28:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:28:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:28:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:28:51 INFO - Intel Iris: 22:28:51 INFO - Chipset Model: Intel Iris 22:28:51 INFO - Type: GPU 22:28:51 INFO - Bus: Built-In 22:28:51 INFO - VRAM (Dynamic, Max): 1536 MB 22:28:51 INFO - Vendor: Intel (0x8086) 22:28:51 INFO - Device ID: 0x0a2e 22:28:51 INFO - Revision ID: 0x0009 22:28:51 INFO - Displays: 22:28:51 INFO - Display: 22:28:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:28:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:28:51 INFO - Display Serial Number: 1600x1200 60 22:28:51 INFO - Main Display: Yes 22:28:51 INFO - Mirror: Off 22:28:51 INFO - Online: Yes 22:28:51 INFO - Rotation: Supported 22:28:51 INFO - Return code: 0 22:28:51 INFO - Running post-action listener: _resource_record_post_action 22:28:51 INFO - [mozharness: 2017-05-22 05:28:51.179915Z] Finished run-tests step (success) 22:28:51 INFO - Running post-run listener: _resource_record_post_run 22:28:51 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 233472; Write bytes: 565697536; Read time: 2; Write time: 1734 22:28:51 INFO - TinderboxPrint: CPU usage
54.3% 22:28:51 INFO - TinderboxPrint: I/O read bytes / time
233,472 / 2 22:28:51 INFO - TinderboxPrint: I/O write bytes / time
565,697,536 / 1,734 22:28:51 INFO - TinderboxPrint: CPU idle
2,184.7 (45.7%) 22:28:51 INFO - TinderboxPrint: CPU system
966.1 (20.2%) 22:28:51 INFO - TinderboxPrint: CPU user
1,634.9 (34.2%) 22:28:51 INFO - TinderboxPrint: Swap in / out
244,576,256 / 0 22:28:51 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 233980416; Write bytes: 230725120; Read time: 16857; Write time: 483 22:28:51 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 233472; Write bytes: 315627008; Read time: 2; Write time: 1204 22:28:51 INFO - Running post-run listener: _upload_blobber_files 22:28:51 INFO - Blob upload gear active. 22:28:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:28:51 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 22:28:51 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'] 22:28:51 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 22:28:52 INFO - (blobuploader) - INFO - Open directory for files ... 22:28:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:28:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:28:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:28:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:28:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:28:52 INFO - (blobuploader) - INFO - Done attempting. 22:28:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:28:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:28:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:28:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:28:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:28:54 INFO - (blobuploader) - INFO - Done attempting. 22:28:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:28:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:28:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:28:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:28:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:28:55 INFO - (blobuploader) - INFO - Done attempting. 22:28:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:28:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:28:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:28:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:28:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:28:57 INFO - (blobuploader) - INFO - Done attempting. 22:28:57 INFO - (blobuploader) - INFO - Iteration through files over. 22:28:57 INFO - Return code: 0 22:28:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:28:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:28:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fb3062874401c26096393434aa87bebde0166e829ce143a5220aaadf4501a40d52ef2725fc209cb9577e3790461eaa1071fb986728cfc861866b321596b3f60", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/325f3e68fbbbc72d1316f9d357eba4dd9259d90d71287d6fc746ec894e599dec13e110a5cab44b329f05c1fd5cb4aaea3d9be1e2b5d46410007a2c495da32608", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02f795364fb29760b6c51c9ceea644251779638dc70a95d32d67a32496cbb9bff119cf91ccef0bf0fa2bd36c711f43ae103d530f296bc393c23c1d305c61d837", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c87455d4776729fa775d218e9b79eb542387330365d190410b3a680d97e10192b9db3476163e90f40a7eec3ee669a05d880a0a2f6d4b5cc9b80da33ea965ed3a"} 22:28:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:28:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:28:57 INFO - Contents: 22:28:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fb3062874401c26096393434aa87bebde0166e829ce143a5220aaadf4501a40d52ef2725fc209cb9577e3790461eaa1071fb986728cfc861866b321596b3f60", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/325f3e68fbbbc72d1316f9d357eba4dd9259d90d71287d6fc746ec894e599dec13e110a5cab44b329f05c1fd5cb4aaea3d9be1e2b5d46410007a2c495da32608", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02f795364fb29760b6c51c9ceea644251779638dc70a95d32d67a32496cbb9bff119cf91ccef0bf0fa2bd36c711f43ae103d530f296bc393c23c1d305c61d837", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c87455d4776729fa775d218e9b79eb542387330365d190410b3a680d97e10192b9db3476163e90f40a7eec3ee669a05d880a0a2f6d4b5cc9b80da33ea965ed3a"} 22:28:57 INFO - Running post-run listener: copy_logs_to_upload_dir 22:28:57 INFO - Copying logs to upload dir... 22:28:57 INFO - mkdir: /builds/slave/test/build/upload/logs 22:28:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.870179 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-21 22:28:57.244580) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-21 22:28:57.252481) ========= 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.dNT7CtzbnB/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.XVvM02IiK9/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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/1fb3062874401c26096393434aa87bebde0166e829ce143a5220aaadf4501a40d52ef2725fc209cb9577e3790461eaa1071fb986728cfc861866b321596b3f60", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/325f3e68fbbbc72d1316f9d357eba4dd9259d90d71287d6fc746ec894e599dec13e110a5cab44b329f05c1fd5cb4aaea3d9be1e2b5d46410007a2c495da32608", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02f795364fb29760b6c51c9ceea644251779638dc70a95d32d67a32496cbb9bff119cf91ccef0bf0fa2bd36c711f43ae103d530f296bc393c23c1d305c61d837", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c87455d4776729fa775d218e9b79eb542387330365d190410b3a680d97e10192b9db3476163e90f40a7eec3ee669a05d880a0a2f6d4b5cc9b80da33ea965ed3a"} build_url:https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011107 build_url: 'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/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/1fb3062874401c26096393434aa87bebde0166e829ce143a5220aaadf4501a40d52ef2725fc209cb9577e3790461eaa1071fb986728cfc861866b321596b3f60", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/325f3e68fbbbc72d1316f9d357eba4dd9259d90d71287d6fc746ec894e599dec13e110a5cab44b329f05c1fd5cb4aaea3d9be1e2b5d46410007a2c495da32608", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02f795364fb29760b6c51c9ceea644251779638dc70a95d32d67a32496cbb9bff119cf91ccef0bf0fa2bd36c711f43ae103d530f296bc393c23c1d305c61d837", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c87455d4776729fa775d218e9b79eb542387330365d190410b3a680d97e10192b9db3476163e90f40a7eec3ee669a05d880a0a2f6d4b5cc9b80da33ea965ed3a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-21 22:28:57.288457) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:28:57.288764) ========= 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.dNT7CtzbnB/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.XVvM02IiK9/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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.005286 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:28:57.337574) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-21 22:28:57.337855) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-21 22:28:57.338156) ========= ========= Total master_lag: 0.19 =========