builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0138 starttime: 1496051005.44 results: success (0) revision: fc4d115e549ae73dc220bdeb486de0bd47bacedc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 02:43:25.444523) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 02:43:25.444977) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 02:43:25.451029) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 02:43:25.685705) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-29 02:43:25.686153) ========= 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.ASDIJQcszG/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.1wNGdZmVh3/Listeners TMPDIR=/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-29 02:43:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.2M=0.001s 2017-05-29 02:43:28 (15.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.629705 ========= master_lag: 2.31 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-29 02:43:28.622873) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 02:43:28.623152) ========= 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.ASDIJQcszG/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.1wNGdZmVh3/Listeners TMPDIR=/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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.029954 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 02:43:28.705026) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-29 02:43:28.705314) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fc4d115e549ae73dc220bdeb486de0bd47bacedc --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 fc4d115e549ae73dc220bdeb486de0bd47bacedc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ASDIJQcszG/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.1wNGdZmVh3/Listeners TMPDIR=/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-29 02:43:28,809 truncating revision to first 12 chars 2017-05-29 02:43:28,809 Setting DEBUG logging. 2017-05-29 02:43:28,809 attempt 1/10 2017-05-29 02:43:28,809 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fc4d115e549a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-29 02:43:29,656 unpacking tar archive at: mozilla-central-fc4d115e549a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.133636 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-29 02:43:29.865633) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 02:43:29.866146) ========= script_repo_revision: fc4d115e549ae73dc220bdeb486de0bd47bacedc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 02:43:29.867040) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 02:43:29.867688) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 02:43:29.889285) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-05-29 02:43:29.890023) ========= /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.ASDIJQcszG/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.1wNGdZmVh3/Listeners TMPDIR=/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 02:43:30 INFO - MultiFileLogger online at 20170529 02:43:30 in /builds/slave/test 02:43:30 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 02:43:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:43:30 INFO - {'append_to_log': False, 02:43:30 INFO - 'base_work_dir': '/builds/slave/test', 02:43:30 INFO - 'blob_upload_branch': 'Firefox', 02:43:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:43:30 INFO - 'branch': 'Firefox', 02:43:30 INFO - 'buildbot_json_path': 'buildprops.json', 02:43:30 INFO - 'config_files': ('talos/mac_config.py',), 02:43:30 INFO - 'default_actions': ('clobber', 02:43:30 INFO - 'read-buildbot-config', 02:43:30 INFO - 'download-and-extract', 02:43:30 INFO - 'populate-webroot', 02:43:30 INFO - 'create-virtualenv', 02:43:30 INFO - 'install', 02:43:30 INFO - 'setup-mitmproxy', 02:43:30 INFO - 'run-tests'), 02:43:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:43:30 INFO - 'download_minidump_stackwalk': True, 02:43:30 INFO - 'download_symbols': 'ondemand', 02:43:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:43:30 INFO - 'tooltool.py': '/tools/tooltool.py', 02:43:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:43:30 INFO - '/tools/misc-python/virtualenv.py')}, 02:43:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:43:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:43:30 INFO - 'gecko_profile': False, 02:43:30 INFO - 'gecko_profile_interval': 0, 02:43:30 INFO - 'installer_path': 'installer.exe', 02:43:30 INFO - 'log_level': 'info', 02:43:30 INFO - 'log_name': 'talos', 02:43:30 INFO - 'log_to_console': True, 02:43:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:43:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:43:30 INFO - 'opt_config_files': (), 02:43:30 INFO - 'pip_index': False, 02:43:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:43:30 INFO - 'cmd': ('bash', 02:43:30 INFO - '-c', 02:43:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:43:30 INFO - 'enabled': True, 02:43:30 INFO - 'halt_on_failure': False, 02:43:30 INFO - 'name': 'check_screen_resolution'},), 02:43:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:43:30 INFO - 'cmd': ('bash', 02:43:30 INFO - '-c', 02:43:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:43:30 INFO - 'enabled': True, 02:43:30 INFO - 'halt_on_failure': False, 02:43:30 INFO - 'name': 'check_screen_resolution'},), 02:43:30 INFO - 'run_cmd_checks_enabled': True, 02:43:30 INFO - 'suite': 'dromaeojs-e10s', 02:43:30 INFO - 'system_bits': '32', 02:43:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:43:30 INFO - 'title': 't-yosemite-r7-0138', 02:43:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:43:30 INFO - 'use_talos_json': True, 02:43:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:43:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:43:30 INFO - 'work_dir': 'build'} 02:43:30 INFO - [mozharness: 2017-05-29 09:43:30.104015Z] Running clobber step. 02:43:30 INFO - Running pre-action listener: _resource_record_pre_action 02:43:30 INFO - Running main action method: clobber 02:43:30 INFO - rmtree: /builds/slave/test/build 02:43:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:43:32 INFO - Running post-action listener: _resource_record_post_action 02:43:32 INFO - [mozharness: 2017-05-29 09:43:32.660992Z] Finished clobber step (success) 02:43:32 INFO - [mozharness: 2017-05-29 09:43:32.661182Z] Running read-buildbot-config step. 02:43:32 INFO - Running pre-action listener: _resource_record_pre_action 02:43:32 INFO - Running main action method: read_buildbot_config 02:43:32 INFO - Using buildbot properties: 02:43:32 INFO - { 02:43:32 INFO - "project": "", 02:43:32 INFO - "product": "firefox", 02:43:32 INFO - "who": "cbook@mozilla.com", 02:43:32 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 02:43:32 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 02:43:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 02:43:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 02:43:32 INFO - "stage_platform": "macosx64", 02:43:32 INFO - "basedir": "/builds/slave/test", 02:43:32 INFO - "buildnumber": 3, 02:43:32 INFO - "platform": "yosemite_r7", 02:43:32 INFO - "parent_task_id": "Slbt3NNMROOseFIeVK1-5w", 02:43:32 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:43:32 INFO - "slavebuilddir": "test", 02:43:32 INFO - "taskId": "Anxsa_qVTkiwJXKoBq_Dxg", 02:43:32 INFO - "branch": "mozilla-central", 02:43:32 INFO - "script_repo_revision": "production", 02:43:32 INFO - "revision": "fc4d115e549ae73dc220bdeb486de0bd47bacedc", 02:43:32 INFO - "slavename": "t-yosemite-r7-0138", 02:43:32 INFO - "repo_path": "mozilla-central" 02:43:32 INFO - } 02:43:32 INFO - Finding installer, test and symbols from parent task. 02:43:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Anxsa_qVTkiwJXKoBq_Dxg'}, attempt #1 02:43:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Anxsa_qVTkiwJXKoBq_Dxg'}, attempt #1 02:43:33 INFO - Task dependencies: axY68dIMTBqvbOjZkBbWMQ Slbt3NNMROOseFIeVK1-5w 02:43:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w'}, attempt #1 02:43:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:43:34 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/test_packages.json 02:43:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/target.crashreporter-symbols.zip 02:43:34 INFO - Running post-action listener: _resource_record_post_action 02:43:34 INFO - [mozharness: 2017-05-29 09:43:34.026790Z] Finished read-buildbot-config step (success) 02:43:34 INFO - [mozharness: 2017-05-29 09:43:34.026897Z] Running download-and-extract step. 02:43:34 INFO - Running pre-action listener: _resource_record_pre_action 02:43:34 INFO - Running main action method: download_and_extract 02:43:34 INFO - mkdir: /builds/slave/test/build/tests 02:43:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:43:34 INFO - trying https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 02:43:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/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 02:43:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/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 02:43:34 INFO - Downloaded 1325 bytes. 02:43:34 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:43:34 INFO - Using the following test package requirements: 02:43:34 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:43:34 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 02:43:34 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:43:34 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:43:34 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 02:43:34 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:43:34 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 02:43:34 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:43:34 INFO - u'jsshell-mac.zip'], 02:43:34 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:43:34 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 02:43:34 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:43:34 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:43:34 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 02:43:34 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:43:34 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 02:43:34 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:43:34 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 02:43:34 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:43:34 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 02:43:34 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:43:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:43:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:43:34 INFO - Fetch https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:43:36 INFO - Content-Length response header: 57634295 02:43:36 INFO - Bytes received: 57634295 02:43:54 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 02:43:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:43:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:43:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:43:56 INFO - Content-Length response header: 57634295 02:43:56 INFO - Bytes received: 57634295 02:44:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 02:44:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:44:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 02:44:15 INFO - Content-Length response header: 14447425 02:44:15 INFO - Bytes received: 14447425 02:44:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:15 INFO - trying https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:44:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 02:44:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/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 02:44:17 INFO - Downloaded 67821069 bytes. 02:44:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:44:17 INFO - mkdir: /builds/slave/test/properties 02:44:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:44:17 INFO - Writing to file /builds/slave/test/properties/build_url 02:44:17 INFO - Contents: 02:44:17 INFO - build_url:https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:44:17 INFO - Running post-action listener: _resource_record_post_action 02:44:17 INFO - Running post-action listener: set_extra_try_arguments 02:44:17 INFO - [mozharness: 2017-05-29 09:44:17.341761Z] Finished download-and-extract step (success) 02:44:17 INFO - [mozharness: 2017-05-29 09:44:17.341884Z] Running populate-webroot step. 02:44:17 INFO - Running pre-action listener: _resource_record_pre_action 02:44:17 INFO - Running main action method: populate_webroot 02:44:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:44:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:44:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:44:17 INFO - '--tppagecycles', 02:44:17 INFO - '7'], 02:44:17 INFO - 'tests': ['tsvgm']}}, 02:44:17 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:44:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:44:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:44:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:44:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:44:17 INFO - 'talos_options': ['--disable-e10s'], 02:44:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:44:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:44:17 INFO - 'talos_options': ['--disable-e10s'], 02:44:17 INFO - 'tests': ['damp', 'tps']}, 02:44:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:44:17 INFO - 'tests': ['damp', 'tps']}, 02:44:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:44:17 INFO - 'tests': ['dromaeo_dom']}, 02:44:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:44:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:44:17 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:44:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:44:17 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:44:17 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:44:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:44:17 INFO - 'tests': ['a11yr', 02:44:17 INFO - 'ts_paint', 02:44:17 INFO - 'tpaint', 02:44:17 INFO - 'sessionrestore', 02:44:17 INFO - 'sessionrestore_no_auto_restore', 02:44:17 INFO - 'tabpaint']}, 02:44:17 INFO - 'other-e10s': {'tests': ['a11yr', 02:44:17 INFO - 'ts_paint', 02:44:17 INFO - 'tpaint', 02:44:17 INFO - 'sessionrestore', 02:44:17 INFO - 'sessionrestore_no_auto_restore', 02:44:17 INFO - 'tabpaint']}, 02:44:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:44:17 INFO - 'ts_paint', 02:44:17 INFO - 'tpaint', 02:44:17 INFO - 'sessionrestore', 02:44:17 INFO - 'sessionrestore_no_auto_restore', 02:44:17 INFO - 'tabpaint']}, 02:44:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:44:17 INFO - 'ts_paint', 02:44:17 INFO - 'tpaint', 02:44:17 INFO - 'sessionrestore', 02:44:17 INFO - 'sessionrestore_no_auto_restore', 02:44:17 INFO - 'tabpaint']}, 02:44:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:44:17 INFO - 'tests': ['a11yr', 02:44:17 INFO - 'ts_paint', 02:44:17 INFO - 'tpaint', 02:44:17 INFO - 'sessionrestore', 02:44:17 INFO - 'sessionrestore_no_auto_restore', 02:44:17 INFO - 'tabpaint']}, 02:44:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:44:17 INFO - 'tests': ['a11yr', 02:44:17 INFO - 'ts_paint', 02:44:17 INFO - 'tpaint', 02:44:17 INFO - 'sessionrestore', 02:44:17 INFO - 'sessionrestore_no_auto_restore', 02:44:17 INFO - 'tabpaint']}, 02:44:17 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 02:44:17 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 02:44:17 INFO - 'bloom_basic_ref']}, 02:44:17 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 02:44:17 INFO - 'talos_options': ['--mitmproxy', 02:44:17 INFO - 'mitmproxy-recording-1.mp', 02:44:17 INFO - '--firstNonBlankPaint'], 02:44:17 INFO - 'tests': ['Quantum_1']}, 02:44:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:44:17 INFO - 'tests': ['tsvgx', 02:44:17 INFO - 'tsvgr_opacity', 02:44:17 INFO - 'tart', 02:44:17 INFO - 'tscrollx', 02:44:17 INFO - 'cart', 02:44:17 INFO - 'tsvg_static']}, 02:44:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:44:17 INFO - 'tsvgr_opacity', 02:44:17 INFO - 'tart', 02:44:17 INFO - 'tscrollx', 02:44:17 INFO - 'cart', 02:44:17 INFO - 'tsvg_static']}, 02:44:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:44:17 INFO - 'talos_options': ['--disable-e10s'], 02:44:17 INFO - 'tests': ['tp5o']}, 02:44:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:44:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:44:17 INFO - 'talos_options': ['--disable-e10s', 02:44:17 INFO - '--xperf_path', 02:44:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:17 INFO - 'tests': ['tp5n']}, 02:44:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:44:17 INFO - 'talos_options': ['--xperf_path', 02:44:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:17 INFO - 'tests': ['tp5n']}}, 02:44:17 INFO - 'talos.zip': {'path': '', 02:44:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:44:17 INFO - Running post-action listener: _resource_record_post_action 02:44:17 INFO - [mozharness: 2017-05-29 09:44:17.363576Z] Finished populate-webroot step (success) 02:44:17 INFO - [mozharness: 2017-05-29 09:44:17.363725Z] Running create-virtualenv step. 02:44:17 INFO - Running pre-action listener: _resource_record_pre_action 02:44:17 INFO - Running main action method: create_virtualenv 02:44:17 INFO - Creating virtualenv /builds/slave/test/build/venv 02:44:17 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 02:44:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:44:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:44:17 INFO - Using real prefix '/tools/python27' 02:44:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:44:18 INFO - Installing distribute.............................................................................................................................................................................................done. 02:44:21 INFO - Installing pip.................done. 02:44:21 INFO - Return code: 0 02:44:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:44:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:44:21 INFO - Reading from file tmpfile_stdout 02:44:21 INFO - Output received: 02:44:21 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) 02:44:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10768cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90047bd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107615c60>, '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 0x1073aacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073d3be8>, '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.ASDIJQcszG/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.1wNGdZmVh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 02:44:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:44:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:44:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ASDIJQcszG/Render', 02:44:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:21 INFO - 'HOME': '/Users/cltbld', 02:44:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:21 INFO - 'LOGNAME': 'cltbld', 02:44:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:21 INFO - 'MOZ_NO_REMOTE': '1', 02:44:21 INFO - 'NO_EM_RESTART': '1', 02:44:21 INFO - 'PAGER': '/bin/cat', 02:44:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:21 INFO - 'PWD': '/builds/slave/test', 02:44:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:21 INFO - 'SHELL': '/bin/bash', 02:44:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1wNGdZmVh3/Listeners', 02:44:21 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 02:44:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:21 INFO - 'USER': 'cltbld', 02:44:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:21 INFO - 'XPC_FLAGS': '0x0', 02:44:21 INFO - 'XPC_SERVICE_NAME': '0', 02:44:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:21 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 02:44:21 INFO - Cleaning up... 02:44:21 INFO - Return code: 0 02:44:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10768cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90047bd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107615c60>, '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 0x1073aacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073d3be8>, '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.ASDIJQcszG/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.1wNGdZmVh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 02:44:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:44:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:44:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ASDIJQcszG/Render', 02:44:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:21 INFO - 'HOME': '/Users/cltbld', 02:44:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:21 INFO - 'LOGNAME': 'cltbld', 02:44:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:21 INFO - 'MOZ_NO_REMOTE': '1', 02:44:21 INFO - 'NO_EM_RESTART': '1', 02:44:21 INFO - 'PAGER': '/bin/cat', 02:44:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:21 INFO - 'PWD': '/builds/slave/test', 02:44:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:21 INFO - 'SHELL': '/bin/bash', 02:44:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1wNGdZmVh3/Listeners', 02:44:21 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 02:44:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:21 INFO - 'USER': 'cltbld', 02:44:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:21 INFO - 'XPC_FLAGS': '0x0', 02:44:21 INFO - 'XPC_SERVICE_NAME': '0', 02:44:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:21 INFO - Downloading/unpacking psutil>=3.1.1 02:44:21 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 02:44:21 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 02:44:21 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 02:44:21 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 02:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:44:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:24 INFO - Installing collected packages: psutil 02:44:24 INFO - Running setup.py install for psutil 02:44:24 INFO - building 'psutil._psutil_osx' extension 02:44:24 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 02:44:25 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 02:44:25 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 02:44:25 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 02:44:25 INFO - building 'psutil._psutil_posix' extension 02:44:25 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 02:44:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:44:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:44:25 INFO - ^ 02:44:25 INFO - 1 warning generated. 02:44:25 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 02:44:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:25 INFO - Successfully installed psutil 02:44:25 INFO - Cleaning up... 02:44:25 INFO - Return code: 0 02:44:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:44:25 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')]} 02:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:25 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')]} 02:44:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:25 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 0x10768cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90047bd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107615c60>, '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 0x1073aacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073d3be8>, '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.ASDIJQcszG/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.1wNGdZmVh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 02:44:25 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 02:44:25 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 02:44:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ASDIJQcszG/Render', 02:44:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:25 INFO - 'HOME': '/Users/cltbld', 02:44:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:25 INFO - 'LOGNAME': 'cltbld', 02:44:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:25 INFO - 'MOZ_NO_REMOTE': '1', 02:44:25 INFO - 'NO_EM_RESTART': '1', 02:44:25 INFO - 'PAGER': '/bin/cat', 02:44:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:25 INFO - 'PWD': '/builds/slave/test', 02:44:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:25 INFO - 'SHELL': '/bin/bash', 02:44:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1wNGdZmVh3/Listeners', 02:44:25 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 02:44:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:25 INFO - 'USER': 'cltbld', 02:44:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:25 INFO - 'XPC_FLAGS': '0x0', 02:44:25 INFO - 'XPC_SERVICE_NAME': '0', 02:44:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:44:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:44:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:44:27 INFO - Installing collected packages: mozsystemmonitor 02:44:27 INFO - Running setup.py install for mozsystemmonitor 02:44:27 INFO - Successfully installed mozsystemmonitor 02:44:27 INFO - Cleaning up... 02:44:27 INFO - Return code: 0 02:44:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:44: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')]} 02:44:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44: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')]} 02:44:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:27 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 0x10768cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90047bd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107615c60>, '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 0x1073aacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073d3be8>, '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.ASDIJQcszG/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.1wNGdZmVh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 02:44:27 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 02:44:27 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 02:44:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ASDIJQcszG/Render', 02:44:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:27 INFO - 'HOME': '/Users/cltbld', 02:44:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:27 INFO - 'LOGNAME': 'cltbld', 02:44:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:27 INFO - 'MOZ_NO_REMOTE': '1', 02:44:27 INFO - 'NO_EM_RESTART': '1', 02:44:27 INFO - 'PAGER': '/bin/cat', 02:44:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:27 INFO - 'PWD': '/builds/slave/test', 02:44:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:27 INFO - 'SHELL': '/bin/bash', 02:44:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1wNGdZmVh3/Listeners', 02:44:27 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 02:44:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:27 INFO - 'USER': 'cltbld', 02:44:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:27 INFO - 'XPC_FLAGS': '0x0', 02:44:27 INFO - 'XPC_SERVICE_NAME': '0', 02:44:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:27 INFO - Downloading/unpacking jsonschema==2.5.1 02:44:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:44:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:29 INFO - Downloading functools32-3.2.3-2.tar.gz 02:44:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:44:29 INFO - warning: no files found matching '*.txt' 02:44:29 INFO - no previously-included directories found matching 'build' 02:44:29 INFO - no previously-included directories found matching 'dist' 02:44:29 INFO - no previously-included directories found matching '.git*' 02:44:29 INFO - Installing collected packages: jsonschema, functools32 02:44:29 INFO - Running setup.py install for functools32 02:44:29 INFO - warning: no files found matching '*.txt' 02:44:29 INFO - no previously-included directories found matching 'build' 02:44:29 INFO - no previously-included directories found matching 'dist' 02:44:29 INFO - no previously-included directories found matching '.git*' 02:44:29 INFO - Successfully installed jsonschema functools32 02:44:29 INFO - Cleaning up... 02:44:29 INFO - Return code: 0 02:44:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:44:29 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')]} 02:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:29 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')]} 02:44:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:29 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 0x10768cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90047bd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107615c60>, '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 0x1073aacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073d3be8>, '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.ASDIJQcszG/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.1wNGdZmVh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 02:44:29 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 02:44:29 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 02:44:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ASDIJQcszG/Render', 02:44:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:29 INFO - 'HOME': '/Users/cltbld', 02:44:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:29 INFO - 'LOGNAME': 'cltbld', 02:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:29 INFO - 'MOZ_NO_REMOTE': '1', 02:44:29 INFO - 'NO_EM_RESTART': '1', 02:44:29 INFO - 'PAGER': '/bin/cat', 02:44:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:29 INFO - 'PWD': '/builds/slave/test', 02:44:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:29 INFO - 'SHELL': '/bin/bash', 02:44:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1wNGdZmVh3/Listeners', 02:44:29 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 02:44:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:29 INFO - 'USER': 'cltbld', 02:44:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:29 INFO - 'XPC_FLAGS': '0x0', 02:44:29 INFO - 'XPC_SERVICE_NAME': '0', 02:44:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:44:30 INFO - Cleaning up... 02:44:30 INFO - Return code: 0 02:44:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:30 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 0x10768cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90047bd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107615c60>, '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 0x1073aacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073d3be8>, '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.ASDIJQcszG/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.1wNGdZmVh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 02:44:30 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 02:44:30 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 02:44:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ASDIJQcszG/Render', 02:44:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:30 INFO - 'HOME': '/Users/cltbld', 02:44:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:30 INFO - 'LOGNAME': 'cltbld', 02:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:30 INFO - 'MOZ_NO_REMOTE': '1', 02:44:30 INFO - 'NO_EM_RESTART': '1', 02:44:30 INFO - 'PAGER': '/bin/cat', 02:44:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:30 INFO - 'PWD': '/builds/slave/test', 02:44:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:30 INFO - 'SHELL': '/bin/bash', 02:44:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1wNGdZmVh3/Listeners', 02:44:30 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 02:44:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:30 INFO - 'USER': 'cltbld', 02:44:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:30 INFO - 'XPC_FLAGS': '0x0', 02:44:30 INFO - 'XPC_SERVICE_NAME': '0', 02:44:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:30 INFO - Downloading/unpacking blobuploader==1.2.4 02:44:30 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 02:44:30 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 02:44:30 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 02:44:30 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 02:44:31 INFO - Downloading blobuploader-1.2.4.tar.gz 02:44:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:44:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:44:31 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 02:44:31 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 02:44:31 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 02:44:31 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 02:44:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:44:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:44:32 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 02:44:32 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 02:44:32 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 02:44:32 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 02:44:32 INFO - Downloading docopt-0.6.1.tar.gz 02:44:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:44:32 INFO - Installing collected packages: blobuploader, requests, docopt 02:44:32 INFO - Running setup.py install for blobuploader 02:44:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:44:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:44:32 INFO - Running setup.py install for requests 02:44:33 INFO - Running setup.py install for docopt 02:44:33 INFO - Successfully installed blobuploader requests docopt 02:44:33 INFO - Cleaning up... 02:44:33 INFO - Return code: 0 02:44:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44: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')]} 02:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44: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')]} 02:44:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:33 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 0x10768cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90047bd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107615c60>, '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 0x1073aacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073d3be8>, '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.ASDIJQcszG/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.1wNGdZmVh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 02:44:33 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 02:44:33 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 02:44:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ASDIJQcszG/Render', 02:44:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:33 INFO - 'HOME': '/Users/cltbld', 02:44:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:33 INFO - 'LOGNAME': 'cltbld', 02:44:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:33 INFO - 'MOZ_NO_REMOTE': '1', 02:44:33 INFO - 'NO_EM_RESTART': '1', 02:44:33 INFO - 'PAGER': '/bin/cat', 02:44:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:33 INFO - 'PWD': '/builds/slave/test', 02:44:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:33 INFO - 'SHELL': '/bin/bash', 02:44:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1wNGdZmVh3/Listeners', 02:44:33 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 02:44:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:33 INFO - 'USER': 'cltbld', 02:44:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:33 INFO - 'XPC_FLAGS': '0x0', 02:44:33 INFO - 'XPC_SERVICE_NAME': '0', 02:44:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:44:33 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-ohLHQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:44:34 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-Yt4U05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:44:34 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-ciANLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:44:34 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-0j5Xeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:44:34 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-sD0FDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:44:34 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-qQK2iO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:44:34 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-wySwFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:44:34 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-7MhUhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:44:34 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-nbmY3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:44:34 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-mLnKVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:44:34 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-ykjjVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:44:35 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-Zq_wFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:44:35 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-fpWtwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:44:35 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-p_tqIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:35 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-5Zfdrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:44:35 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-d75GX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:44:35 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-BqG5ID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:44:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:44:35 INFO - Running setup.py install for manifestparser 02:44:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:44:35 INFO - Running setup.py install for mozcrash 02:44:35 INFO - Running setup.py install for mozdebug 02:44:36 INFO - Running setup.py install for mozdevice 02:44:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:44:36 INFO - Running setup.py install for mozfile 02:44:36 INFO - Running setup.py install for mozhttpd 02:44:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:44:36 INFO - Running setup.py install for mozinfo 02:44:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:44:36 INFO - Running setup.py install for mozInstall 02:44:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:44:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:44:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:44:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:44:36 INFO - Running setup.py install for mozleak 02:44:37 INFO - Running setup.py install for mozlog 02:44:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:44:37 INFO - Running setup.py install for moznetwork 02:44:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:44:37 INFO - Running setup.py install for mozprocess 02:44:37 INFO - Running setup.py install for mozprofile 02:44:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:44:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:44:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:44:37 INFO - Running setup.py install for mozrunner 02:44:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:44:37 INFO - Running setup.py install for mozscreenshot 02:44:37 INFO - Running setup.py install for moztest 02:44:38 INFO - Running setup.py install for mozversion 02:44:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:44:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:44:38 INFO - Cleaning up... 02:44:38 INFO - Return code: 0 02:44:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:38 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 0x10768cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90047bd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107615c60>, '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 0x1073aacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073d3be8>, '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.ASDIJQcszG/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.1wNGdZmVh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 02:44:38 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 02:44:38 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 02:44:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ASDIJQcszG/Render', 02:44:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:38 INFO - 'HOME': '/Users/cltbld', 02:44:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:38 INFO - 'LOGNAME': 'cltbld', 02:44:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:38 INFO - 'MOZ_NO_REMOTE': '1', 02:44:38 INFO - 'NO_EM_RESTART': '1', 02:44:38 INFO - 'PAGER': '/bin/cat', 02:44:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:38 INFO - 'PWD': '/builds/slave/test', 02:44:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:38 INFO - 'SHELL': '/bin/bash', 02:44:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1wNGdZmVh3/Listeners', 02:44:38 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 02:44:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:38 INFO - 'USER': 'cltbld', 02:44:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:38 INFO - 'XPC_FLAGS': '0x0', 02:44:38 INFO - 'XPC_SERVICE_NAME': '0', 02:44:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:44:38 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-VTlOpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:44:38 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)) 02:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:44:38 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-hyQUPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:44:38 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)) 02:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:44:38 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-8y6vF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:44:38 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)) 02:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:44:38 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-3ar3lR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:44:39 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-Q9ltCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:44:39 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)) 02:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:44:39 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-TV7w_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:44:39 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)) 02:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:44:39 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-9CwXe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:44:39 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)) 02:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:44:39 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-iTqL57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:44:39 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)) 02:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:44:39 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-7S37wb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:44:39 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)) 02:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:44:39 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-GcbY5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:44:39 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)) 02:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:44:39 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-eOSfnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:44:39 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)) 02:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:44:39 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-_0ubKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:44:39 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)) 02:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:44:39 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-5sviiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:44:39 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)) 02:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:44:39 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-yoIlMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:44:40 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)) 02:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:40 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-I2JIHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:40 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)) 02:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:44:40 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-IHXx6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:44:40 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)) 02:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:44:40 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-Px7gk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:44:40 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)) 02:44:40 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)) 02:44:40 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)) 02:44:40 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)) 02:44:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:44:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:44:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:44: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 02:44: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 02:44: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 02:44: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 02:44:41 INFO - Downloading blessings-1.6.tar.gz 02:44:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:44:41 INFO - Installing collected packages: blessings 02:44:41 INFO - Running setup.py install for blessings 02:44:42 INFO - Successfully installed blessings 02:44:42 INFO - Cleaning up... 02:44:42 INFO - Return code: 0 02:44:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:44:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:44:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:44:42 INFO - Reading from file tmpfile_stdout 02:44:42 INFO - Current package versions: 02:44:42 INFO - blessings == 1.6 02:44:42 INFO - blobuploader == 1.2.4 02:44:42 INFO - docopt == 0.6.1 02:44:42 INFO - functools32 == 3.2.3-2 02:44:42 INFO - jsonschema == 2.5.1 02:44:42 INFO - manifestparser == 1.1 02:44:42 INFO - mozInstall == 1.12 02:44:42 INFO - mozcrash == 1.0 02:44:42 INFO - mozdebug == 0.1 02:44:42 INFO - mozdevice == 0.51 02:44:42 INFO - mozfile == 1.2 02:44:42 INFO - mozhttpd == 0.7 02:44:42 INFO - mozinfo == 0.9 02:44:42 INFO - mozleak == 0.1 02:44:42 INFO - mozlog == 3.4 02:44:42 INFO - moznetwork == 0.27 02:44:42 INFO - mozprocess == 0.25 02:44:42 INFO - mozprofile == 0.28 02:44:42 INFO - mozrunner == 6.13 02:44:42 INFO - mozscreenshot == 0.1 02:44:42 INFO - mozsystemmonitor == 0.3 02:44:42 INFO - moztest == 0.8 02:44:42 INFO - mozversion == 1.4 02:44:42 INFO - psutil == 3.1.1 02:44:42 INFO - requests == 1.2.3 02:44:42 INFO - wsgiref == 0.1.2 02:44:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:42 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')]} 02:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:42 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')]} 02:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:42 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 0x10768cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90047bd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107615c60>, '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 0x1073aacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073d3be8>, '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.ASDIJQcszG/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.1wNGdZmVh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 02:44:42 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 02:44:42 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 02:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ASDIJQcszG/Render', 02:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:42 INFO - 'HOME': '/Users/cltbld', 02:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:42 INFO - 'LOGNAME': 'cltbld', 02:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:42 INFO - 'MOZ_NO_REMOTE': '1', 02:44:42 INFO - 'NO_EM_RESTART': '1', 02:44:42 INFO - 'PAGER': '/bin/cat', 02:44:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:42 INFO - 'PWD': '/builds/slave/test', 02:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:42 INFO - 'SHELL': '/bin/bash', 02:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1wNGdZmVh3/Listeners', 02:44:42 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 02:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:42 INFO - 'USER': 'cltbld', 02:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:42 INFO - 'XPC_FLAGS': '0x0', 02:44:42 INFO - 'XPC_SERVICE_NAME': '0', 02:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:42 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)) 02:44:42 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)) 02:44:42 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)) 02:44:42 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)) 02:44:42 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)) 02:44:42 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)) 02:44:42 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)) 02:44:42 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)) 02:44:42 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)) 02:44:42 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:44:42 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 02:44:42 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 02:44:42 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 02:44:42 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 02:44:44 INFO - Downloading marionette_driver-1.4.0.tar.gz 02:44:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 02:44:44 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)) 02:44:44 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)) 02:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:44:44 INFO - Installing collected packages: marionette-driver 02:44:44 INFO - Running setup.py install for marionette-driver 02:44:44 INFO - Successfully installed marionette-driver 02:44:44 INFO - Cleaning up... 02:44:44 INFO - Return code: 0 02:44:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:44:44 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')]} 02:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:44 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')]} 02:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:44 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 0x10768cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107690768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90047bd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107615c60>, '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 0x1073aacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073d3be8>, '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.ASDIJQcszG/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.1wNGdZmVh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 02:44:44 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 02:44:44 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 02:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ASDIJQcszG/Render', 02:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:44 INFO - 'HOME': '/Users/cltbld', 02:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:44 INFO - 'LOGNAME': 'cltbld', 02:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:44 INFO - 'MOZ_NO_REMOTE': '1', 02:44:44 INFO - 'NO_EM_RESTART': '1', 02:44:44 INFO - 'PAGER': '/bin/cat', 02:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:44 INFO - 'PWD': '/builds/slave/test', 02:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:44 INFO - 'SHELL': '/bin/bash', 02:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1wNGdZmVh3/Listeners', 02:44:44 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 02:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:44 INFO - 'USER': 'cltbld', 02:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:44 INFO - 'XPC_FLAGS': '0x0', 02:44:44 INFO - 'XPC_SERVICE_NAME': '0', 02:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:44:44 INFO - Cleaning up... 02:44:44 INFO - Return code: 0 02:44:44 INFO - Running post-action listener: _resource_record_post_action 02:44:44 INFO - Running post-action listener: _start_resource_monitoring 02:44:44 INFO - Starting resource monitoring. 02:44:44 INFO - [mozharness: 2017-05-29 09:44:44.761977Z] Finished create-virtualenv step (success) 02:44:44 INFO - [mozharness: 2017-05-29 09:44:44.762961Z] Running install step. 02:44:44 INFO - Running pre-action listener: _resource_record_pre_action 02:44:44 INFO - Running main action method: install 02:44:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:44:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:44:45 INFO - Reading from file tmpfile_stdout 02:44:45 INFO - Detecting whether we're running mozinstall >=1.0... 02:44:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:44:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:44:45 INFO - Reading from file tmpfile_stdout 02:44:45 INFO - Output received: 02:44:45 INFO - Usage: mozinstall [options] installer 02:44:45 INFO - Options: 02:44:45 INFO - -h, --help show this help message and exit 02:44:45 INFO - -d DEST, --destination=DEST 02:44:45 INFO - Directory to install application into. [default: 02:44:45 INFO - "/builds/slave/test"] 02:44:45 INFO - --app=APP Application being installed. [default: firefox] 02:44:45 INFO - mkdir: /builds/slave/test/build/application 02:44:45 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'] 02:44:45 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 02:45:06 INFO - Reading from file tmpfile_stdout 02:45:06 INFO - Output received: 02:45:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:45:06 INFO - Running post-action listener: _resource_record_post_action 02:45:06 INFO - [mozharness: 2017-05-29 09:45:06.334886Z] Finished install step (success) 02:45:06 INFO - [mozharness: 2017-05-29 09:45:06.335014Z] Running setup-mitmproxy step. 02:45:06 INFO - Running pre-action listener: _resource_record_pre_action 02:45:06 INFO - Running main action method: setup_mitmproxy 02:45:06 INFO - Skipping: mitmproxy is not required 02:45:06 INFO - Running post-action listener: _resource_record_post_action 02:45:06 INFO - [mozharness: 2017-05-29 09:45:06.335661Z] Finished setup-mitmproxy step (success) 02:45:06 INFO - [mozharness: 2017-05-29 09:45:06.335779Z] Running run-tests step. 02:45:06 INFO - Running pre-action listener: _resource_record_pre_action 02:45:06 INFO - Running main action method: run_tests 02:45:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:45:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:45:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:45:06 INFO - 2017-05-29 02:45:06.350 screenresolution[1630:8515] starting screenresolution argv=screenresolution get 02:45:06 INFO - 2017-05-29 02:45:06.353 screenresolution[1630:8515] Display 0: 1600x1200x32@60 02:45:06 INFO - 2017-05-29 02:45:06.358 screenresolution[1631:8517] starting screenresolution argv=screenresolution list 02:45:06 INFO - Available Modes on Display 0 02:45:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:45:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:45:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:45:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:45:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:45:06 INFO - Intel Iris: 02:45:06 INFO - Chipset Model: Intel Iris 02:45:06 INFO - Type: GPU 02:45:06 INFO - Bus: Built-In 02:45:06 INFO - VRAM (Dynamic, Max): 1536 MB 02:45:06 INFO - Vendor: Intel (0x8086) 02:45:06 INFO - Device ID: 0x0a2e 02:45:06 INFO - Revision ID: 0x0009 02:45:06 INFO - Displays: 02:45:06 INFO - Display: 02:45:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:45:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:45:06 INFO - Display Serial Number: 1600x1200 60 02:45:06 INFO - Main Display: Yes 02:45:06 INFO - Mirror: Off 02:45:06 INFO - Online: Yes 02:45:06 INFO - Rotation: Supported 02:45:06 INFO - Return code: 0 02:45:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:45:06 INFO - Python 2.7.3 02:45:06 INFO - Return code: 0 02:45:06 INFO - grabbing minidump binary from tooltool 02:45:06 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')]} 02:45:06 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 0x107615c60>, '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 0x1073aacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073d3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:45:06 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 02:45:06 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 02:45:06 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 02:45:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:45:06 INFO - Return code: 0 02:45:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:45:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:45:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:45:06 INFO - ENV: RUST_BACKTRACE is now 1 02:45:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:45:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:45:06 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/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0138', '--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 02:45:06 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/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0138 --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 02:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ASDIJQcszG/Render', 02:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:06 INFO - 'HOME': '/Users/cltbld', 02:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:06 INFO - 'LOGNAME': 'cltbld', 02:45:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:06 INFO - 'MOZ_NO_REMOTE': '1', 02:45:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:06 INFO - 'NO_EM_RESTART': '1', 02:45:06 INFO - 'PAGER': '/bin/cat', 02:45:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:06 INFO - 'PWD': '/builds/slave/test', 02:45:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:06 INFO - 'RUST_BACKTRACE': '1', 02:45:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:45:06 INFO - 'SHELL': '/bin/bash', 02:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1wNGdZmVh3/Listeners', 02:45:06 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 02:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:06 INFO - 'USER': 'cltbld', 02:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:06 INFO - 'XPC_FLAGS': '0x0', 02:45:06 INFO - 'XPC_SERVICE_NAME': '0', 02:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:06 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/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0138', '--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 02:45:06 INFO - mozversion application_buildid: 20170529012044 02:45:06 INFO - mozversion application_changeset: fc4d115e549ae73dc220bdeb486de0bd47bacedc 02:45:06 INFO - mozversion application_display_name: Nightly 02:45:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:45:06 INFO - mozversion application_name: Firefox 02:45:06 INFO - mozversion application_remotingname: firefox 02:45:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:45:06 INFO - mozversion application_vendor: Mozilla 02:45:06 INFO - mozversion application_version: 55.0a1 02:45:06 INFO - mozversion platform_buildid: 20170529012044 02:45:06 INFO - mozversion platform_changeset: fc4d115e549ae73dc220bdeb486de0bd47bacedc 02:45:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:45:06 INFO - mozversion platform_version: 55.0a1 02:45:07 INFO - using testdate: 1496051106 02:45:07 INFO - actual date: 1496051106 02:45:07 INFO - starting webserver on 'localhost:49228' 02:45:07 INFO - SUITE-START | Running 2 tests 02:45:07 INFO - TEST-START | dromaeo_css 02:45:07 INFO - operating with platform_type : mac_ 02:45:07 INFO - Initialising browser for dromaeo_css test... 02:45:07 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/tmpo6vM1G/profile 02:45:07 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 02:45:41 INFO - TEST-INFO | 1641: killed by SIGTERM 02:45:41 INFO - Browser initialized. 02:45:41 INFO - Running cycle 1/1 for dromaeo_css test... 02:45:41 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/tmpo6vM1G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:45:43 INFO - PID 1649 | 2017-05-29 02:45:43.296 plugin-container[1650:9021] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 02:45:43 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:45:43 INFO - PID 1649 | 2017-05-29 02:45:43.297 plugin-container[1650:9021] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 02:45:43 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:45:43 INFO - PID 1649 | 2017-05-29 02:45:43.297 plugin-container[1650:9021] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:45:44 INFO - PID 1649 | 2017-05-29 02:45:44.013 plugin-container[1651:9064] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 02:45:44 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:45:44 INFO - PID 1649 | 2017-05-29 02:45:44.013 plugin-container[1651:9064] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 02:45:44 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:45:44 INFO - PID 1649 | 2017-05-29 02:45:44.013 plugin-container[1651:9064] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:45:53 INFO - PID 1649 | RSS: Main: 263368704 02:45:53 INFO - PID 1649 | 02:48:55 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 02:48:56 INFO - PID 1649 | RSS: Main: 269074432 02:48:56 INFO - PID 1649 | 02:51:58 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 02:51:59 INFO - PID 1649 | RSS: Main: 284012544 02:51:59 INFO - PID 1649 | 02:55:00 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 02:55:01 INFO - PID 1649 | RSS: Main: 283021312 02:55:01 INFO - PID 1649 | 02:58:03 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 02:58:03 INFO - PID 1649 | RSS: Main: 286646272 02:58:03 INFO - PID 1649 | 03:01:06 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 03:01:07 INFO - PID 1649 | RSS: Main: 286871552 03:01:07 INFO - PID 1649 | 03:04:08 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 03:04:08 INFO - PID 1649 | RSS: Main: 288927744 03:04:08 INFO - PID 1649 | 03:04:08 INFO - PID 1649 | __start_tp_report 03:04:08 INFO - PID 1649 | _x_x_mozilla_page_load 03:04:08 INFO - PID 1649 | _x_x_mozilla_page_load_details 03:04:08 INFO - PID 1649 | |i|pagename|runs| 03:04:08 INFO - PID 1649 | |0;dojo.html;3048.436039332724;3100.224943764059;3622.963770362297;3642.7450078494517;3658.908527286819;6089.665068421235;6150.723217455208;6171.722272497737;6234.096056071867;6294.213223347082;6145.248604147819;6167.722452489637;6182.659953702545;6201.224846894138;6216.595921265114;4488.371627972078;4497.482789479202;4543.681942264043;4776.949071204347;5283.973580132094;5219.660722053073;5287.021900948318;5426.8371948841595;5578.525825304854;5654.236678048473;5025.597952163818;5069.960658065088;5568.443155684439;5618.101103823389;5660.2075709400715;5431.538319242869;5487.64330318532;5522.088855338864;5545.195946587723;5546.417626149237;5447.945520544784;5456.645318054314;5466.988607107658;5478.479544443271;5504.724763761836;6177.289611694668;6278.686065696743;6289.245290565115;6292.968535157341;6305.0542418637115;5551.9722401388135;5980.073088671263;6496.837579060514;6623.172103487064;6714.764983225613;6663.86672266552;6851.143607049119;6854.485913556453;6857.417119544794;6884.552504087268;6333.778317758903;6342.714577843962;6345.49236061114;6353.904691429632;6364.872702545923;6761.628110453972;6836.931630683729;6863.553868998499;6937.167539895144;6995.755148569878;6337.588056776295;6621.470282377399;6633.303503132198;6638.17022872141;6647.966760166169;6666.266710661824;6704.932950670528;6780.762673306411;6788.151481064867;6800.489963252776;6666.83332916681;6708.53040287185;6712.194536655535;6712.295209003082;6716.731330746716;6535.509836762262;6565.638889861008;6596.10952521413;6617.172853393326;6714.563553369016;5363.061464243741;5368.731563421813;5381.1121165007335;5387.676739395649;5431.293931788842;4796.904061918742;4801.135795556764;4813.78337974792;4816.975915120402;4848.757562121879;6282.528810339243;6285.120083188357;6315.936840631627;6373.649449280334;6399.232092148972;6752.561083529555;6808.727650894008;6816.182058153054;6828.795136145924;6831.077804496429;6273.843153921097;6330.082138090043;6335.619862808247;6373.649449280334;6384.648844313608;5884.705764711747;5951.285845698544;5972.283326000821;6015.879682406415;6036.3058248302;6222.81331560054;6890.138732658418;6951.617661028692;6980.162380514371;7016.894746578703;6815.659217039228;6824.112865327475;6830.21452532965;6839.2818754032105;6844.965775171092;6945.444364450933;6966.756163534252;6979.790606281819;7019.385541325626;7028.9297107030325;5541.556675466034;6241.937580624317;6527.281998980014;6549.7380104795275;6624.768133115318;6819.727210911508;6836.794896153124;6841.863162736817;6850.520463567503;6871.793846184622;6409.583377080475;6488.318726533835;6493.22081350241;6540;6600.141981542561;4971.502849715;5388.757505912165;6543.083968244545;6562.639054852029;6590.472762178918;5323.920141197807;5474.507294343528;6414.839629009312;6443.6455994920725;6495.545311828127;6413;6558.80323590293;6565.835854103686;6577.375149360803;6599.571027883172;6346.90479642815;6392;6449.7420103195345;6457.580257283262;6479.967600161969 03:04:08 INFO - PID 1649 | |1;ext.html;716.6130289643593;784.3920961255027;838.1492784823405;1129.8983091521761;1250.0811903251115;39834.00414989627;39847.80076099619;40458.39312410309;40673;40719;40737;40815;40971.79514102425;41051;41137.38293925586;7919.485233459835;8091.838163236732;8115.959420202891;8261.17388261175;8355.456895301786;7059.39995100417;8430;8432.325413966899;8436.325093992466;8449.36629752771;8382;8400.537970411626;8463.915360846375;8464.365172612048;8506.617202225914;8411.915880841174;8435.40952133351;8438;8444.071152173256;8504.27713644341;8284.420090593661;8320.417570770049;8347.165283471664;8377.49735015901;8379.539125348103;38011;38053.80973095144;38141.23717525634;38269.23461530782;38356.041098972746;3601.4957905892934;3638.2905333459985;3652.2695460907926;3653.488511608377;3656.7384252432867;30706.69293307083;30916.690833091605;30994.76020959159;31055.913043043613;31314.530282045787;2999.5350720638335;3087.120170751325;3093.613298337708;3108.782385233035;3120.8127512349333;252622;256049;284554;310021;311160;48909.51090489049;48937;49011.50988490141;49024.754876225394;49048.26427603589;42789;50211;50990.49009509932;51090;51174.74412627913;12912.483500660057;13571.050026498247;14161;14222.786658200135;14384.56846294592;11621.825672614917;11711.004564611932;11730.472128754227;11737.943585077213;11749.588764393206;12456.065795065406;12825.294608796607;13881.889448844067;13889;13889.236092015037;6061.727222275008;6076.787312444043;6092.238470191226;6123.4488895999575;6125.908111378333;940.374650857186;972.601233494264;1004.9748756281151;1010.9039641234218;1046.8798385727198;22851.428714281945;24619;24718.876405618215;24874.751252487244;24990.125345612818;1015.4668798880647;1083.4474418046696;1116.3525155409843;1125.150511363915;1129.271619805221;10792.406417647106;12619.30593817349;12705.682357941125;12747.235165890077;12858.678533036562;312640;313446;315079;315092;318115;54619.45380546223;55700.44299556952;55704;55833;56166;1510.9574393668333;1520.2854658310355;1548.9767653485208;1559.5165498695442;1560.1964988031173;22839.20062797794;24403.51192976095;24535.50928981446;24557.631635525126;24609.507809843344;39104;39278;39294.607053929096;39383.40924886186;39390;265153;265242;265374;266400.3359966375;268296;32683.50974735333;32992.83503582467;34913.778017769255;34947.30105397929;35051.64948350586;36892.63107368999;43545.78227108844;43907.780461098766;44659.553404465536;45644.77177614091;30077.398452031273;30450.086497405115;30985;31192.688073118978;31482;30525.23686907845;30535.9312424073;31095.68904310928;31267.84366078155;31526.054218373487;37728.43407348947;37869.81065094657;37993.62006380012;38073.048173795876;38135.42796858105;23769.881150594138;29264.707352926198;29958;30416.239594010327;30946.226344341572;22980;24568.823956768003;24571.262862114167;24817.62773558362;25210.74789252084 03:04:08 INFO - PID 1649 | |2;jquery.html;411.8784958437259;412.5874125874126;429.411705962831;436.0407104370385;453.623492501224;16995.660086798256;19453.221871125173;21079.894600526994;22024.559508809813;22163.667544986816;16815.579610509743;18822.52943676409;19140.904295478504;22587.435314117156;22610.095596176176;5814.738336774855;5861.944849927011;5965.164876917235;6035.969820150893;6045.33501314855;5881.176635271044;5928.318243402021;5991.700414979256;6002.339742628307;6011.489023433013;5751.18192085877;5779.826605201851;5892.793752218673;5892.941070589303;5902.409759024106;5829.708514574254;5852.736626851802;5911.822645320647;5914.260717410324;5947.375525569818;5241.030409374278;5282.0756367635895;5293.444188360244;5323;5324.094903866313;27208.591871121753;27451.17646470609;27768.166954991382;27834.304142396602;28155.694070249327;2127.489402543394;2225.4658881868395;2240.215924426454;2253.5718213539376;2258.288639078685;17620.295188192456;17986.28054877803;17999.73000404995;18299;18405.447836564923;2011.89345859778;2106.09437941685;2124.564464284818;2199.285232299509;2204.680321353411;115330;147351;147462.99999999892;147867.26066369706;150617.24691376474;12662.467506498737;12807;12939.935300323437;12952.352382380843;12965.805512917314;16817.327306907588;17005.149742512825;17121;17170.313187472362;17229.65540689179;8940.90006321647;9237.30720195988;9245.260379169786;9249.12133347331;9320.813583728288;8616.439931404439;8729.170728780755;8750.824983500293;8908.376413651113;8910.910890891008;7990.960045199853;8080.474769139987;8117.878231826528;8147.83704325922;8193.918060819315;2183.2140429445385;2199.120351859237;2200.7689192634543;2222.277734755545;2224.443889027743;924.3806649544821;956.7608097975506;961.5576834655997;977.237754551451;1053.7102296868422;21094.89452552758;21703.78296217049;21730.13079476835;21889.139423149212;22206.00072996719;1025.5077562769911;1028.9022542858415;1067.8184708599556;1072.3351522056375;1074.2050882347164;10832.133429325635;11118.666440006651;11198.608048718255;11262.774744505065;11270.042046425982;537647;543826.2808685931;549323;557382;558127;126459.36770316273;130572;134205;137718;138474;1041.338749893823;1219.1039585904352;1253.6489782860813;1254.868238834909;1254.8807863252794;22113.557728845655;22384;22546.887265564223;22612.98241579166;22850.62896226232;49727.75136124296;54164.72917635387;57338.69959753119;57513.42486575248;58113.4188658108;65022.02466962905;80008.59995699984;80525;80914.7862782047;81716;7777;8302.460340077876;8882.733517994213;8941.552922353987;9159.992400836036;13254.680430924742;13638.727225455237;13657.25935401324;13984.37070331817;14024.719505609626;11444.828327574927;11515.827262591236;11674.88325116772;11730.941345293219;11828.408579570814;9552.42685438876;9878.555465004236;10718.303310284806;10967.780644387227;11187.888121118685;11599.362035087814;11608;11802.586909458129;11929.821052684043;12032;7778.844423111619;8842.867356989522;8987.865182022144;9001.729948101567;9296.860547091665;11703.941480292544;11834.934855863014;12021.218620789621;12033.819492707791;12114.15200935926 03:04:08 INFO - PID 1649 | |3;mootools.html;1186.721120536674;1406.6342750884774;1413.8444771075174;1415.796572913024;1436.3464623596262;11695.5906543271;11846.229995050317;12103.576374826895;12151.635450936465;12424.254544727322;11904.583339583116;12187.634370968863;12257.387130643454;12265.63203103906;12324.38378081097;2542.3644088977708;2589.2491177558486;2600.0379859451937;2600.4149066460086;2608.621749846271;2521.5839386501207;2550.979608156743;2562.6668533090674;2570.958761701514;2577.007851976993;2491.1530079772874;2519.962200566993;2524.924252272435;2527.886245118965;2528.6586310847983;2448.6694296270043;2482.9379265518414;2485.403503159238;2499.850008999466;2501.524710305036;2433.9878300608384;2495.438526331579;2498.312963934932;2538.4288535079645;2540.1998370513415;11933.582324618597;11937.761244775056;12036.81944770829;12049.277043377338;12071.034317254598;6535.738570457223;6733.393994540465;6783.796486105425;6784.3894049535775;6792.93207067928;3962.385830196324;4121.29937910556;4186.183694179636;4188.68584856137;4199.4330765346895;1257.9182353147107;1278.6867217531765;1282.487005197919;1288.1369482446783;1304.1914013311712;15626.296816643278;15983.680326393407;16824.07467589282;16852;16968.74546881798;5518.889622207534;5587.05020146576;5733.254676891977;5740.339860915965;5774.105013722879;11848.526058957545;11955.462004209832;11977.161598688008;11985.820212696817;11995.160338776202;6372.681365931685;6564.7374105036215;6573.57271777333;6579.703913323912;6581.3747693969;6036.607620504653;6125.877482450326;6189.876202475925;6196.690165491707;6225.28409232935;4396.560343965578;4453.465584129925;4461.286194208911;4510.233260345683;4572.199865023607;1277.067740549404;1281.8397700287464;1290.6837824733;1306.888914442283;1315.7500074985721;778.7430148051129;780.7267456390218;798.8935324575505;801.8837268596021;803.6383627367708;6717.899231511531;7054.6472676365975;7108.715651373887;7134.536255143399;7157.284271572905;923.3121324613206;955.1880901233895;958.434523631061;975.2734213011352;979.0845559401982;4468.597826195658;4477.283634618446;4627.768611569475;4640.744759038218;4683.2038553446;13957.511487098105;14618;14631.634209144644;15011.32449039774;15037;12454.750904981849;14802.889783266299;15013.849861501463;15039.548813535612;15072.773908391382;7802.297793198526;8004.879926801103;8195.754127376189;8519.57402129891;8586.871196932176;6278.277998030285;6487.448566871869;7229.855402891808;7232.45756568249;7327.560346379234;13287.867121328663;13559.254241016839;13580.93209533946;13652.590422287347;13836.723265534834;13924.930375348464;13933.72132557323;14065.437382504586;14085.436582536993;14288.928555357157;7835.804104897423;9269.72190834276;9574.138327550554;9577.23382129414;9581.616735330508;10787.190960677824;10799.784004320027;10859.34843909368;10874;10916.781664366827;8912;9218.26253899673;9292.39599426035;9296.814063718823;9375.296852735935;8971.685990990283;8989.730308090768;9089.454632722058;9235.76910577241;9291.76770580741;8225.136360682041;8319.251267385722;8367.707130250412;8411.873821892554;8436.789080273042;6115.938840611537;6150.569460137747;6207.906881396873;6235;6358.554901156875;6133.509319254538;6194.318624951162;6289.0252010938375;6372.458341041063;6430.2926678066315 03:04:08 INFO - PID 1649 | |4;prototype.html;368.69398399328554;383.63937898375525;397.64212209011885;408.92639324921504;409.8155829876556;5381.623286369956;6143.139960405536;6153.169322141515;6283.669655082802;6342.778002769904;6002.549808764338;6120.510359171267;6178.382161783819;6183;6229.283632382278;3952.940705889414;4742.454617718972;4752.287156926454;4755.502016864677;4792.329073929653;4294.20557196918;4459.818148190732;4504.324351347292;4531.27499600064;4778.546038126373;4047.2107938951917;4424.756638384888;4431.49037860647;4453.955460445402;4457.264551349023;3300.1089705779427;3535.487354333607;4206.64243539298;4484.45286376203;4493.977530112361;3191.840407979592;3729.4219395993664;3819.140693344003;3824.177801772604;3841.7118716096124;6007.89723596742;6008.669523176223;6009.609375390585;6015.3383127855905;6035.275766907999;4650.023495066006;4701.976490117561;5313.442088580682;5322.973385133049;5323.920141197885;6290.402411770874;6320.873582528324;6530.869382612321;6614.081916244331;6636.93363066368;4165.520965089023;4319.460067491564;4343.457067866517;4369.80335884886;4373.475182978063;5273.815416460444;5651.745671444794;6071.726772295257;6670.866582668319;7043.506954513186;4291.592298731615;4394.912101757947;4400.613806650895;4403.471583410012;4408.4269045024585;4477.462704475419;4479.484859241164;4483.1706134364595;4489.349044388546;4490.887727806831;4730.645201609893;5203.479652034766;5321.175217841241;5343.946560534345;5366.758495867695;6600.141981542465;6603.801885943333;6605.612821307579;6616.735330586819;6682.164729408824;1347.8652134786444;1495.9476418325307;1519.749241375183;1531.5099168266047;1539.7305471542436;970.5058000937663;981.622075500923;1001.998001998002;1012.4583347908833;1016.1515134862473;5530.391656917735;6171.981623032239;6303.842403939938;6332.620042797447;6336.651484168415;5739.827805165852;6352;6777.289084366293;6864.627074585103;6916.308369163044;4932.459783211649;5891.762729826784;6237.594556353823;6272.905906411407;6279.874402511924;3353.84907679155;3412.4710669846213;3556.2752065248333;3571.339302229044;3598.6684926577336;6785.694643741043;6987.065432518354;7020.122484689414;7052.788416347517;7083.468739844532;6731.629760363227;6785.1518560179975;6961.338672826175;6978.5812851228075;7031;5523.404000119937;6728.192616886005;6777.559458635074;7032.753853615099;7084.858302834017;6674.332566743286;6856.5200435969;6861.348171923659;6872.62200578973;6881.13985751781;6723.932760672331;6783;6804.863902722017;6828.692708828214;6835.658217089225;5862.267216597926;6407.295197528361;6542.574732642363;6771.356721111487;7099.538529995534;3890.9221815562964;4354.9782251088545;4373.781310934504;4395.428594282723;4421.093675796518;5952.267946982246;6800.625965571941;6845.132500137406;6879.277675843964;6880.518363714492;5766.279215098113;6409.615423074631;6533.314002029719;6600.768973085919;6629.635370054693;5659.405762394889;5912.694049320854;6595.109660196004;6602.6986506746625;6614.570052946543;5779.8729247797955;6265.028920517327;6467.932791089605;6480.870382592416;6483.805485835433;6035.370449978569;6285.2457705075685;6480.773172938924;6597.406233439014;6625.271220165681;5006.349174607278;6447.3552644735155;6467.611943283418;6477.708503117276;6479.967600162157 03:04:08 INFO - PID 1649 | |5;yui.html;830.7964548685572;838.4843321357365;852.3905407633545;860.2859626509997;869.8216865542562;16329.83670163298;16779.412720554785;17475.213615387307;17515;19606.803931960676;18239.81760182398;18598.81401185988;18640.627187456244;18681.626367472643;18747.437576872682;972.1930797438127;989.7525618595351;998.9500524973759;999.1856636840952;1003.9548220330066;998.9900100999006;1001.5843424978625;1005.3816902604918;1005.4419797012655;1007.4408703169738;1066.029912779371;1073.4900922062036;1073.5598404654093;1075.2150929821235;1078.7788503356833;1045.3518818332607;1058.899404556566;1068.326954018963;1070.6734445993968;1071.710638127709;775.4959276470283;784.4195295481303;785.7878372839358;786.6420778545748;793.2939683681528;17646.823531764647;18104;18219.362322318655;18291.176897039666;18346.816531834644;15556.144412057332;17760.91119544407;17779;18118.72821907659;18150.00174990375;14667.63330916725;14717.852821471755;14726.558203253919;14853.183074930874;14893.255337233095;3515.138790996197;3599.8740044098595;3607.9278414431565;3612.981935090334;3622.3660859349507;132729.3363533186;163586;163620;164400;167560;13298.800517992238;13948.58154255374;14067.085639433404;14255.78616320756;14275;23108.19121330779;23640.88179559091;24437.633435498483;26687.733122668913;26852;7892.4869883457395;7903.486273392211;7939;7950;7957;3987.282289187916;4038.3134867072667;4040.4343391925154;4059.5331536873045;4063.349864021742;4438.689291749546;4576.176288268143;4584.587387135174;4599.241125214369;4616.492185859553;922.197688011421;961.7211008807508;962.8411312133419;974.3277138774223;978.5449765858909;643.3791391307317;665.1286814273318;677.5595862689192;677.617146312332;680.9421199198124;11558.422078896021;12433.37833108341;12492.312922789333;12536.059795515373;12722.872771272354;646.9547131700735;646.9583969808698;650.339904996429;656.6224420957968;682.2086379799508;5532.585056120807;5667.801626943037;5692.430756924275;5829.300483941955;5891.939450898879;138377;138652;138984.30507847396;140202.29898850643;143166.28416858058;92871;121290.39354803346;121979.39010304892;125971;126892;85056;95636.52181739047;96114;96945.54581681281;97075.51462242784;11257.662270131908;11384.601538946097;11500.137489688139;11657.708557286136;11704.473298701749;45617.77191114023;45699.771501142284;45704.77147614241;46506.53493465157;46676.53323466722;59882.10176847264;59967.00329966968;60392;60534.39465605288;60865.87011948243;14523.056001359877;14651.633709157357;14664.70670586561;15103;15109.848901510844;13417.463301468222;13537.255450949899;13597;13651.976101792206;13684.178949263076;14857.885658575435;15108.093514389171;15131.697366052396;15202.467913623412;15285.465008724641;14692.926535367254;15184.848151518343;15296.847031529987;15378.461753838563;15378.923475356618;14386.424543018162;14672.413103475741;14892.255387230378;15002.774958375852;15091.094534327976;10391.948040259751;10781;10844.065949992892;10982.913246483142;11132.721681958015;13493.257870817199;14455.78316325277;14527.49153779655;14604.780928286296;14633.122012679274 03:04:08 INFO - PID 1649 | __end_tp_report 03:04:08 INFO - PID 1649 | __start_cc_report 03:04:08 INFO - PID 1649 | _x_x_mozilla_cycle_collect,456 03:04:08 INFO - PID 1649 | __end_cc_report 03:04:08 INFO - PID 1649 | __startTimestamp1496052248916__endTimestamp 03:04:08 INFO - PID 1649 | 03:04:08 INFO - PID 1649 | ------- Summary: start ------- 03:04:08 INFO - PID 1649 | Number of tests: 6 03:04:08 INFO - PID 1649 | 03:04:08 INFO - PID 1649 | [#0] dojo.html Cycles:165 Average:6135.40 Median:6373.65 stddev:791.07 (12.4%) stddev-sans-first:755.52 03:04:08 INFO - PID 1649 | Values: 3048.4 3100.2 3623.0 3642.7 3658.9 6089.7 6150.7 6171.7 6234.1 6294.2 6145.2 6167.7 6182.7 6201.2 6216.6 4488.4 4497.5 4543.7 4776.9 5284.0 5219.7 5287.0 5426.8 5578.5 5654.2 5025.6 5070.0 5568.4 5618.1 5660.2 5431.5 5487.6 5522.1 5545.2 5546.4 5447.9 5456.6 5467.0 5478.5 5504.7 6177.3 6278.7 6289.2 6293.0 6305.1 5552.0 5980.1 6496.8 6623.2 6714.8 6663.9 6851.1 6854.5 6857.4 6884.6 6333.8 6342.7 6345.5 6353.9 6364.9 6761.6 6836.9 6863.6 6937.2 6995.8 6337.6 6621.5 6633.3 6638.2 6648.0 6666.3 6704.9 6780.8 6788.2 6800.5 6666.8 6708.5 6712.2 6712.3 6716.7 6535.5 6565.6 6596.1 6617.2 6714.6 5363.1 5368.7 5381.1 5387.7 5431.3 4796.9 4801.1 4813.8 4817.0 4848.8 6282.5 6285.1 6315.9 6373.6 6399.2 6752.6 6808.7 6816.2 6828.8 6831.1 6273.8 6330.1 6335.6 6373.6 6384.6 5884.7 5951.3 5972.3 6015.9 6036.3 6222.8 6890.1 6951.6 6980.2 7016.9 6815.7 6824.1 6830.2 6839.3 6845.0 6945.4 6966.8 6979.8 7019.4 7028.9 5541.6 6241.9 6527.3 6549.7 6624.8 6819.7 6836.8 6841.9 6850.5 6871.8 6409.6 6488.3 6493.2 6540.0 6600.1 4971.5 5388.8 6543.1 6562.6 6590.5 5323.9 5474.5 6414.8 6443.6 6495.5 6413.0 6558.8 6565.8 6577.4 6599.6 6346.9 6392.0 6449.7 6457.6 6480.0 03:04:08 INFO - PID 1649 | 03:04:08 INFO - PID 1649 | [#1] ext.html Cycles:180 Average:44343.24 Median:32838.17 stddev:75686.61 (230.5%) stddev-sans-first:75828.05 03:04:08 INFO - PID 1649 | Values: 716.6 784.4 838.1 1129.9 1250.1 39834.0 39847.8 40458.4 40673.0 40719.0 40737.0 40815.0 40971.8 41051.0 41137.4 7919.5 8091.8 8116.0 8261.2 8355.5 7059.4 8430.0 8432.3 8436.3 8449.4 8382.0 8400.5 8463.9 8464.4 8506.6 8411.9 8435.4 8438.0 8444.1 8504.3 8284.4 8320.4 8347.2 8377.5 8379.5 38011.0 38053.8 38141.2 38269.2 38356.0 3601.5 3638.3 3652.3 3653.5 3656.7 30706.7 30916.7 30994.8 31055.9 31314.5 2999.5 3087.1 3093.6 3108.8 3120.8 252622.0 256049.0 284554.0 310021.0 311160.0 48909.5 48937.0 49011.5 49024.8 49048.3 42789.0 50211.0 50990.5 51090.0 51174.7 12912.5 13571.1 14161.0 14222.8 14384.6 11621.8 11711.0 11730.5 11737.9 11749.6 12456.1 12825.3 13881.9 13889.0 13889.2 6061.7 6076.8 6092.2 6123.4 6125.9 940.4 972.6 1005.0 1010.9 1046.9 22851.4 24619.0 24718.9 24874.8 24990.1 1015.5 1083.4 1116.4 1125.2 1129.3 10792.4 12619.3 12705.7 12747.2 12858.7 312640.0 313446.0 315079.0 315092.0 318115.0 54619.5 55700.4 55704.0 55833.0 56166.0 1511.0 1520.3 1549.0 1559.5 1560.2 22839.2 24403.5 24535.5 24557.6 24609.5 39104.0 39278.0 39294.6 39383.4 39390.0 265153.0 265242.0 265374.0 266400.3 268296.0 32683.5 32992.8 34913.8 34947.3 35051.6 36892.6 43545.8 43907.8 44659.6 45644.8 30077.4 30450.1 30985.0 31192.7 31482.0 30525.2 30535.9 31095.7 31267.8 31526.1 37728.4 37869.8 37993.6 38073.0 38135.4 23769.9 29264.7 29958.0 30416.2 30946.2 22980.0 24568.8 24571.3 24817.6 25210.7 03:04:08 INFO - PID 1649 | 03:04:08 INFO - PID 1649 | [#2] jquery.html Cycles:180 Average:35306.85 Median:12312.11 stddev:92885.45 (754.4%) stddev-sans-first:93109.07 03:04:08 INFO - PID 1649 | Values: 411.9 412.6 429.4 436.0 453.6 16995.7 19453.2 21079.9 22024.6 22163.7 16815.6 18822.5 19140.9 22587.4 22610.1 5814.7 5861.9 5965.2 6036.0 6045.3 5881.2 5928.3 5991.7 6002.3 6011.5 5751.2 5779.8 5892.8 5892.9 5902.4 5829.7 5852.7 5911.8 5914.3 5947.4 5241.0 5282.1 5293.4 5323.0 5324.1 27208.6 27451.2 27768.2 27834.3 28155.7 2127.5 2225.5 2240.2 2253.6 2258.3 17620.3 17986.3 17999.7 18299.0 18405.4 2011.9 2106.1 2124.6 2199.3 2204.7 115330.0 147351.0 147463.0 147867.3 150617.2 12662.5 12807.0 12939.9 12952.4 12965.8 16817.3 17005.1 17121.0 17170.3 17229.7 8940.9 9237.3 9245.3 9249.1 9320.8 8616.4 8729.2 8750.8 8908.4 8910.9 7991.0 8080.5 8117.9 8147.8 8193.9 2183.2 2199.1 2200.8 2222.3 2224.4 924.4 956.8 961.6 977.2 1053.7 21094.9 21703.8 21730.1 21889.1 22206.0 1025.5 1028.9 1067.8 1072.3 1074.2 10832.1 11118.7 11198.6 11262.8 11270.0 537647.0 543826.3 549323.0 557382.0 558127.0 126459.4 130572.0 134205.0 137718.0 138474.0 1041.3 1219.1 1253.6 1254.9 1254.9 22113.6 22384.0 22546.9 22613.0 22850.6 49727.8 54164.7 57338.7 57513.4 58113.4 65022.0 80008.6 80525.0 80914.8 81716.0 7777.0 8302.5 8882.7 8941.6 9160.0 13254.7 13638.7 13657.3 13984.4 14024.7 11444.8 11515.8 11674.9 11730.9 11828.4 9552.4 9878.6 10718.3 10967.8 11187.9 11599.4 11608.0 11802.6 11929.8 12032.0 7778.8 8842.9 8987.9 9001.7 9296.9 11703.9 11834.9 12021.2 12033.8 12114.2 03:04:08 INFO - PID 1649 | 03:04:08 INFO - PID 1649 | [#3] mootools.html Cycles:180 Average:7205.64 Median:4041.84 stddev:4510.50 (111.6%) stddev-sans-first:4500.48 03:04:08 INFO - PID 1649 | Values: 1186.7 1406.6 1413.8 1415.8 1436.3 11695.6 11846.2 12103.6 12151.6 12424.3 11904.6 12187.6 12257.4 12265.6 12324.4 2542.4 2589.2 2600.0 2600.4 2608.6 2521.6 2551.0 2562.7 2571.0 2577.0 2491.2 2520.0 2524.9 2527.9 2528.7 2448.7 2482.9 2485.4 2499.9 2501.5 2434.0 2495.4 2498.3 2538.4 2540.2 11933.6 11937.8 12036.8 12049.3 12071.0 6535.7 6733.4 6783.8 6784.4 6792.9 3962.4 4121.3 4186.2 4188.7 4199.4 1257.9 1278.7 1282.5 1288.1 1304.2 15626.3 15983.7 16824.1 16852.0 16968.7 5518.9 5587.1 5733.3 5740.3 5774.1 11848.5 11955.5 11977.2 11985.8 11995.2 6372.7 6564.7 6573.6 6579.7 6581.4 6036.6 6125.9 6189.9 6196.7 6225.3 4396.6 4453.5 4461.3 4510.2 4572.2 1277.1 1281.8 1290.7 1306.9 1315.8 778.7 780.7 798.9 801.9 803.6 6717.9 7054.6 7108.7 7134.5 7157.3 923.3 955.2 958.4 975.3 979.1 4468.6 4477.3 4627.8 4640.7 4683.2 13957.5 14618.0 14631.6 15011.3 15037.0 12454.8 14802.9 15013.8 15039.5 15072.8 7802.3 8004.9 8195.8 8519.6 8586.9 6278.3 6487.4 7229.9 7232.5 7327.6 13287.9 13559.3 13580.9 13652.6 13836.7 13924.9 13933.7 14065.4 14085.4 14288.9 7835.8 9269.7 9574.1 9577.2 9581.6 10787.2 10799.8 10859.3 10874.0 10916.8 8912.0 9218.3 9292.4 9296.8 9375.3 8971.7 8989.7 9089.5 9235.8 9291.8 8225.1 8319.3 8367.7 8411.9 8436.8 6115.9 6150.6 6207.9 6235.0 6358.6 6133.5 6194.3 6289.0 6372.5 6430.3 03:04:08 INFO - PID 1649 | 03:04:08 INFO - PID 1649 | [#4] prototype.html Cycles:180 Average:5283.16 Median:6009.14 stddev:1687.38 (28.1%) stddev-sans-first:1651.31 03:04:08 INFO - PID 1649 | Values: 368.7 383.6 397.6 408.9 409.8 5381.6 6143.1 6153.2 6283.7 6342.8 6002.5 6120.5 6178.4 6183.0 6229.3 3952.9 4742.5 4752.3 4755.5 4792.3 4294.2 4459.8 4504.3 4531.3 4778.5 4047.2 4424.8 4431.5 4454.0 4457.3 3300.1 3535.5 4206.6 4484.5 4494.0 3191.8 3729.4 3819.1 3824.2 3841.7 6007.9 6008.7 6009.6 6015.3 6035.3 4650.0 4702.0 5313.4 5323.0 5323.9 6290.4 6320.9 6530.9 6614.1 6636.9 4165.5 4319.5 4343.5 4369.8 4373.5 5273.8 5651.7 6071.7 6670.9 7043.5 4291.6 4394.9 4400.6 4403.5 4408.4 4477.5 4479.5 4483.2 4489.3 4490.9 4730.6 5203.5 5321.2 5343.9 5366.8 6600.1 6603.8 6605.6 6616.7 6682.2 1347.9 1495.9 1519.7 1531.5 1539.7 970.5 981.6 1002.0 1012.5 1016.2 5530.4 6172.0 6303.8 6332.6 6336.7 5739.8 6352.0 6777.3 6864.6 6916.3 4932.5 5891.8 6237.6 6272.9 6279.9 3353.8 3412.5 3556.3 3571.3 3598.7 6785.7 6987.1 7020.1 7052.8 7083.5 6731.6 6785.2 6961.3 6978.6 7031.0 5523.4 6728.2 6777.6 7032.8 7084.9 6674.3 6856.5 6861.3 6872.6 6881.1 6723.9 6783.0 6804.9 6828.7 6835.7 5862.3 6407.3 6542.6 6771.4 7099.5 3890.9 4355.0 4373.8 4395.4 4421.1 5952.3 6800.6 6845.1 6879.3 6880.5 5766.3 6409.6 6533.3 6600.8 6629.6 5659.4 5912.7 6595.1 6602.7 6614.6 5779.9 6265.0 6467.9 6480.9 6483.8 6035.4 6285.2 6480.8 6597.4 6625.3 5006.3 6447.4 6467.6 6477.7 6480.0 03:04:08 INFO - PID 1649 | 03:04:08 INFO - PID 1649 | [#5] yui.html Cycles:180 Average:24955.88 Median:17769.96 stddev:39286.23 (221.1%) stddev-sans-first:39354.68 03:04:08 INFO - PID 1649 | Values: 830.8 838.5 852.4 860.3 869.8 16329.8 16779.4 17475.2 17515.0 19606.8 18239.8 18598.8 18640.6 18681.6 18747.4 972.2 989.8 999.0 999.2 1004.0 999.0 1001.6 1005.4 1005.4 1007.4 1066.0 1073.5 1073.6 1075.2 1078.8 1045.4 1058.9 1068.3 1070.7 1071.7 775.5 784.4 785.8 786.6 793.3 17646.8 18104.0 18219.4 18291.2 18346.8 15556.1 17760.9 17779.0 18118.7 18150.0 14667.6 14717.9 14726.6 14853.2 14893.3 3515.1 3599.9 3607.9 3613.0 3622.4 132729.3 163586.0 163620.0 164400.0 167560.0 13298.8 13948.6 14067.1 14255.8 14275.0 23108.2 23640.9 24437.6 26687.7 26852.0 7892.5 7903.5 7939.0 7950.0 7957.0 3987.3 4038.3 4040.4 4059.5 4063.3 4438.7 4576.2 4584.6 4599.2 4616.5 922.2 961.7 962.8 974.3 978.5 643.4 665.1 677.6 677.6 680.9 11558.4 12433.4 12492.3 12536.1 12722.9 647.0 647.0 650.3 656.6 682.2 5532.6 5667.8 5692.4 5829.3 5891.9 138377.0 138652.0 138984.3 140202.3 143166.3 92871.0 121290.4 121979.4 125971.0 126892.0 85056.0 95636.5 96114.0 96945.5 97075.5 11257.7 11384.6 11500.1 11657.7 11704.5 45617.8 45699.8 45704.8 46506.5 46676.5 59882.1 59967.0 60392.0 60534.4 60865.9 14523.1 14651.6 14664.7 15103.0 15109.8 13417.5 13537.3 13597.0 13652.0 13684.2 14857.9 15108.1 15131.7 15202.5 15285.5 14692.9 15184.8 15296.8 15378.5 15378.9 14386.4 14672.4 14892.3 15002.8 15091.1 10391.9 10781.0 10844.1 10982.9 11132.7 13493.3 14455.8 14527.5 14604.8 14633.1 03:04:08 INFO - PID 1649 | -------- Summary: end -------- 03:04:08 INFO - PID 1649 | 03:04:09 INFO - TEST-INFO | 1649: exit 0 03:04:09 INFO - TEST-OK | dromaeo_css | took 1142456ms 03:04:09 INFO - TEST-START | kraken 03:04:09 INFO - operating with platform_type : mac_ 03:04:09 INFO - Initialising browser for kraken test... 03:04:09 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/tmpb4ypHX/profile 03:04:09 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 03:04:43 INFO - TEST-INFO | 1658: killed by SIGTERM 03:04:43 INFO - Browser initialized. 03:04:43 INFO - Running cycle 1/1 for kraken test... 03:04:43 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/tmpb4ypHX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:04:45 INFO - PID 1662 | 2017-05-29 03:04:45.765 plugin-container[1663:11627] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994f, name = 'com.apple.tsm.portname' 03:04:45 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:04:45 INFO - PID 1662 | 2017-05-29 03:04:45.766 plugin-container[1663:11627] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 03:04:45 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:04:45 INFO - PID 1662 | 2017-05-29 03:04:45.766 plugin-container[1663:11627] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:04:46 INFO - PID 1662 | 2017-05-29 03:04:46.220 plugin-container[1664:11677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 03:04:46 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:04:46 INFO - PID 1662 | 2017-05-29 03:04:46.220 plugin-container[1664:11677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 03:04:46 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:04:46 INFO - PID 1662 | 2017-05-29 03:04:46.220 plugin-container[1664:11677] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:04:55 INFO - PID 1662 | RSS: Main: 262737920 03:04:55 INFO - PID 1662 | 03:04:58 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 03:04:59 INFO - PID 1662 | RSS: Main: 272498688 03:04:59 INFO - PID 1662 | 03:05:01 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 03:05:02 INFO - PID 1662 | RSS: Main: 280100864 03:05:02 INFO - PID 1662 | 03:05:05 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 03:05:06 INFO - PID 1662 | RSS: Main: 282263552 03:05:06 INFO - PID 1662 | 03:05:08 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 03:05:09 INFO - PID 1662 | RSS: Main: 283054080 03:05:09 INFO - PID 1662 | 03:05:11 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:05:11 INFO - PID 1662 | RSS: Main: 284876800 03:05:11 INFO - PID 1662 | 03:05:14 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 03:05:15 INFO - PID 1662 | RSS: Main: 289767424 03:05:15 INFO - PID 1662 | 03:05:18 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 03:05:19 INFO - PID 1662 | RSS: Main: 292614144 03:05:19 INFO - PID 1662 | 03:05:22 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 03:05:22 INFO - PID 1662 | RSS: Main: 291598336 03:05:22 INFO - PID 1662 | 03:05:24 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:05:25 INFO - PID 1662 | RSS: Main: 291667968 03:05:25 INFO - PID 1662 | 03:05:26 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:05:27 INFO - PID 1662 | RSS: Main: 291368960 03:05:27 INFO - PID 1662 | 03:05:29 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:05:30 INFO - PID 1662 | RSS: Main: 291430400 03:05:30 INFO - PID 1662 | 03:05:33 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:05:34 INFO - PID 1662 | RSS: Main: 291328000 03:05:34 INFO - PID 1662 | 03:05:37 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:05:37 INFO - PID 1662 | RSS: Main: 291328000 03:05:37 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 03:05:39 INFO - PID 1662 | RSS: Main: 293163008 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | __start_tp_report 03:05:39 INFO - PID 1662 | _x_x_mozilla_page_load 03:05:39 INFO - PID 1662 | _x_x_mozilla_page_load_details 03:05:39 INFO - PID 1662 | |i|pagename|runs| 03:05:39 INFO - PID 1662 | |0;ai-astar;104;111;116;115;116;117;115;159;114;117 03:05:39 INFO - PID 1662 | |1;audio-beat-detection;183;105;142;188;114;112;121;140;146;110 03:05:39 INFO - PID 1662 | |2;audio-dft;200;207;207;249;292;201;202;203;198;201 03:05:39 INFO - PID 1662 | |3;audio-fft;75;58;60;83;59;61;59;59;96;82 03:05:39 INFO - PID 1662 | |4;audio-oscillator;78;70;85;71;73;68;84;69;68;97 03:05:39 INFO - PID 1662 | |5;imaging-gaussian-blur;89;88;88;88;90;88;113;133;93;85 03:05:39 INFO - PID 1662 | |6;imaging-darkroom;95;98;116;149;116;100;146;114;150;154 03:05:39 INFO - PID 1662 | |7;imaging-desaturate;91;91;93;92;92;102;90;91;91;90 03:05:39 INFO - PID 1662 | |8;json-parse-financial;51;52;52;53;55;54;50;47;44;44 03:05:39 INFO - PID 1662 | |9;json-stringify-tinderbox;58;64;65;88;89;81;55;57;60;57 03:05:39 INFO - PID 1662 | |10;stanford-crypto-aes;88;89;87;86;90;89;90;89;128;88 03:05:39 INFO - PID 1662 | |11;stanford-crypto-ccm;138;136;136;163;152;138;158;142;155;137 03:05:39 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;193;188;186;173;209;228;206;191;237;184 03:05:39 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;68;69;65;68;73;66;70;70;70;66 03:05:39 INFO - PID 1662 | __end_tp_report 03:05:39 INFO - PID 1662 | __start_cc_report 03:05:39 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1209 03:05:39 INFO - PID 1662 | __end_cc_report 03:05:39 INFO - PID 1662 | __startTimestamp1496052339855__endTimestamp 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | ------- Summary: start ------- 03:05:39 INFO - PID 1662 | Number of tests: 14 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:118.40 Median:116.00 stddev:14.79 (12.7%) stddev-sans-first:14.74 03:05:39 INFO - PID 1662 | Values: 104.0 111.0 116.0 115.0 116.0 117.0 115.0 159.0 114.0 117.0 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:136.10 Median:141.00 stddev:29.79 (21.1%) stddev-sans-first:26.32 03:05:39 INFO - PID 1662 | Values: 183.0 105.0 142.0 188.0 114.0 112.0 121.0 140.0 146.0 110.0 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:216.00 Median:205.00 stddev:30.59 (14.9%) stddev-sans-first:31.89 03:05:39 INFO - PID 1662 | Values: 200.0 207.0 207.0 249.0 292.0 201.0 202.0 203.0 198.0 201.0 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:69.20 Median:68.00 stddev:13.73 (20.2%) stddev-sans-first:14.40 03:05:39 INFO - PID 1662 | Values: 75.0 58.0 60.0 83.0 59.0 61.0 59.0 59.0 96.0 82.0 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:75.50 stddev:9.64 (12.8%) stddev-sans-first:10.20 03:05:39 INFO - PID 1662 | Values: 78.0 70.0 85.0 71.0 73.0 68.0 84.0 69.0 68.0 97.0 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:95.50 Median:88.00 stddev:15.37 (17.5%) stddev-sans-first:16.12 03:05:39 INFO - PID 1662 | Values: 89.0 88.0 88.0 88.0 90.0 88.0 113.0 133.0 93.0 85.0 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:123.80 Median:149.50 stddev:23.58 (15.8%) stddev-sans-first:22.59 03:05:39 INFO - PID 1662 | Values: 95.0 98.0 116.0 149.0 116.0 100.0 146.0 114.0 150.0 154.0 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:92.30 Median:91.00 stddev:3.53 (3.9%) stddev-sans-first:3.71 03:05:39 INFO - PID 1662 | Values: 91.0 91.0 93.0 92.0 92.0 102.0 90.0 91.0 91.0 90.0 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:52.00 stddev:3.94 (7.6%) stddev-sans-first:4.17 03:05:39 INFO - PID 1662 | Values: 51.0 52.0 52.0 53.0 55.0 54.0 50.0 47.0 44.0 44.0 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:64.50 stddev:13.36 (20.7%) stddev-sans-first:13.73 03:05:39 INFO - PID 1662 | Values: 58.0 64.0 65.0 88.0 89.0 81.0 55.0 57.0 60.0 57.0 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:92.40 Median:89.00 stddev:12.57 (14.1%) stddev-sans-first:13.23 03:05:39 INFO - PID 1662 | Values: 88.0 89.0 87.0 86.0 90.0 89.0 90.0 89.0 128.0 88.0 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:147.00 stddev:10.39 (7.1%) stddev-sans-first:10.67 03:05:39 INFO - PID 1662 | Values: 138.0 136.0 136.0 163.0 152.0 138.0 158.0 142.0 155.0 137.0 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.50 Median:199.50 stddev:20.34 (10.2%) stddev-sans-first:21.43 03:05:39 INFO - PID 1662 | Values: 193.0 188.0 186.0 173.0 209.0 228.0 206.0 191.0 237.0 184.0 03:05:39 INFO - PID 1662 | 03:05:39 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.50 stddev:2.42 (3.5%) stddev-sans-first:2.55 03:05:39 INFO - PID 1662 | Values: 68.0 69.0 65.0 68.0 73.0 66.0 70.0 70.0 70.0 66.0 03:05:39 INFO - PID 1662 | -------- Summary: end -------- 03:05:39 INFO - PID 1662 | 03:05:40 INFO - TEST-INFO | 1662: exit 0 03:05:40 INFO - TEST-OK | kraken | took 91204ms 03:05:40 INFO - SUITE-END | took 1233s 03:05:41 INFO - Completed test suite (00:20:34) 03:05:41 INFO - javascript benchmark 03:05:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7936.572243805018, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3048.436039332724, 3100.224943764059, 3622.963770362297, 3642.7450078494517, 3658.908527286819, 6089.665068421235, 6150.723217455208, 6171.722272497737, 6234.096056071867, 6294.213223347082, 6145.248604147819, 6167.722452489637, 6182.659953702545, 6201.224846894138, 6216.595921265114, 4488.371627972078, 4497.482789479202, 4543.681942264043, 4776.949071204347, 5283.973580132094, 5219.660722053073, 5287.021900948318, 5426.8371948841595, 5578.525825304854, 5654.236678048473, 5025.597952163818, 5069.960658065088, 5568.443155684439, 5618.101103823389, 5660.2075709400715, 5431.538319242869, 5487.64330318532, 5522.088855338864, 5545.195946587723, 5546.417626149237, 5447.945520544784, 5456.645318054314, 5466.988607107658, 5478.479544443271, 5504.724763761836, 6177.289611694668, 6278.686065696743, 6289.245290565115, 6292.968535157341, 6305.0542418637115, 5551.9722401388135, 5980.073088671263, 6496.837579060514, 6623.172103487064, 6714.764983225613, 6663.86672266552, 6851.143607049119, 6854.485913556453, 6857.417119544794, 6884.552504087268, 6333.778317758903, 6342.714577843962, 6345.49236061114, 6353.904691429632, 6364.872702545923, 6761.628110453972, 6836.931630683729, 6863.553868998499, 6937.167539895144, 6995.755148569878, 6337.588056776295, 6621.470282377399, 6633.303503132198, 6638.17022872141, 6647.966760166169, 6666.266710661824, 6704.932950670528, 6780.762673306411, 6788.151481064867, 6800.489963252776, 6666.83332916681, 6708.53040287185, 6712.194536655535, 6712.295209003082, 6716.731330746716, 6535.509836762262, 6565.638889861008, 6596.10952521413, 6617.172853393326, 6714.563553369016, 5363.061464243741, 5368.731563421813, 5381.1121165007335, 5387.676739395649, 5431.293931788842, 4796.904061918742, 4801.135795556764, 4813.78337974792, 4816.975915120402, 4848.757562121879, 6282.528810339243, 6285.120083188357, 6315.936840631627, 6373.649449280334, 6399.232092148972, 6752.561083529555, 6808.727650894008, 6816.182058153054, 6828.795136145924, 6831.077804496429, 6273.843153921097, 6330.082138090043, 6335.619862808247, 6373.649449280334, 6384.648844313608, 5884.705764711747, 5951.285845698544, 5972.283326000821, 6015.879682406415, 6036.3058248302, 6222.81331560054, 6890.138732658418, 6951.617661028692, 6980.162380514371, 7016.894746578703, 6815.659217039228, 6824.112865327475, 6830.21452532965, 6839.2818754032105, 6844.965775171092, 6945.444364450933, 6966.756163534252, 6979.790606281819, 7019.385541325626, 7028.9297107030325, 5541.556675466034, 6241.937580624317, 6527.281998980014, 6549.7380104795275, 6624.768133115318, 6819.727210911508, 6836.794896153124, 6841.863162736817, 6850.520463567503, 6871.793846184622, 6409.583377080475, 6488.318726533835, 6493.22081350241, 6540.0, 6600.141981542561, 4971.502849715, 5388.757505912165, 6543.083968244545, 6562.639054852029, 6590.472762178918, 5323.920141197807, 5474.507294343528, 6414.839629009312, 6443.6455994920725, 6495.545311828127, 6413.0, 6558.80323590293, 6565.835854103686, 6577.375149360803, 6599.571027883172, 6346.90479642815, 6392.0, 6449.7420103195345, 6457.580257283262, 6479.967600161969], "value": 6079.211907168862, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.6130289643593, 784.3920961255027, 838.1492784823405, 1129.8983091521761, 1250.0811903251115, 39834.00414989627, 39847.80076099619, 40458.39312410309, 40673.0, 40719.0, 40737.0, 40815.0, 40971.79514102425, 41051.0, 41137.38293925586, 7919.485233459835, 8091.838163236732, 8115.959420202891, 8261.17388261175, 8355.456895301786, 7059.39995100417, 8430.0, 8432.325413966899, 8436.325093992466, 8449.36629752771, 8382.0, 8400.537970411626, 8463.915360846375, 8464.365172612048, 8506.617202225914, 8411.915880841174, 8435.40952133351, 8438.0, 8444.071152173256, 8504.27713644341, 8284.420090593661, 8320.417570770049, 8347.165283471664, 8377.49735015901, 8379.539125348103, 38011.0, 38053.80973095144, 38141.23717525634, 38269.23461530782, 38356.041098972746, 3601.4957905892934, 3638.2905333459985, 3652.2695460907926, 3653.488511608377, 3656.7384252432867, 30706.69293307083, 30916.690833091605, 30994.76020959159, 31055.913043043613, 31314.530282045787, 2999.5350720638335, 3087.120170751325, 3093.613298337708, 3108.782385233035, 3120.8127512349333, 252622.0, 256049.0, 284554.0, 310021.0, 311160.0, 48909.51090489049, 48937.0, 49011.50988490141, 49024.754876225394, 49048.26427603589, 42789.0, 50211.0, 50990.49009509932, 51090.0, 51174.74412627913, 12912.483500660057, 13571.050026498247, 14161.0, 14222.786658200135, 14384.56846294592, 11621.825672614917, 11711.004564611932, 11730.472128754227, 11737.943585077213, 11749.588764393206, 12456.065795065406, 12825.294608796607, 13881.889448844067, 13889.0, 13889.236092015037, 6061.727222275008, 6076.787312444043, 6092.238470191226, 6123.4488895999575, 6125.908111378333, 940.374650857186, 972.601233494264, 1004.9748756281151, 1010.9039641234218, 1046.8798385727198, 22851.428714281945, 24619.0, 24718.876405618215, 24874.751252487244, 24990.125345612818, 1015.4668798880647, 1083.4474418046696, 1116.3525155409843, 1125.150511363915, 1129.271619805221, 10792.406417647106, 12619.30593817349, 12705.682357941125, 12747.235165890077, 12858.678533036562, 312640.0, 313446.0, 315079.0, 315092.0, 318115.0, 54619.45380546223, 55700.44299556952, 55704.0, 55833.0, 56166.0, 1510.9574393668333, 1520.2854658310355, 1548.9767653485208, 1559.5165498695442, 1560.1964988031173, 22839.20062797794, 24403.51192976095, 24535.50928981446, 24557.631635525126, 24609.507809843344, 39104.0, 39278.0, 39294.607053929096, 39383.40924886186, 39390.0, 265153.0, 265242.0, 265374.0, 266400.3359966375, 268296.0, 32683.50974735333, 32992.83503582467, 34913.778017769255, 34947.30105397929, 35051.64948350586, 36892.63107368999, 43545.78227108844, 43907.780461098766, 44659.553404465536, 45644.77177614091, 30077.398452031273, 30450.086497405115, 30985.0, 31192.688073118978, 31482.0, 30525.23686907845, 30535.9312424073, 31095.68904310928, 31267.84366078155, 31526.054218373487, 37728.43407348947, 37869.81065094657, 37993.62006380012, 38073.048173795876, 38135.42796858105, 23769.881150594138, 29264.707352926198, 29958.0, 30416.239594010327, 30946.226344341572, 22980.0, 24568.823956768003, 24571.262862114167, 24817.62773558362, 25210.74789252084], "value": 17549.6697780819, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.8784958437259, 412.5874125874126, 429.411705962831, 436.0407104370385, 453.623492501224, 16995.660086798256, 19453.221871125173, 21079.894600526994, 22024.559508809813, 22163.667544986816, 16815.579610509743, 18822.52943676409, 19140.904295478504, 22587.435314117156, 22610.095596176176, 5814.738336774855, 5861.944849927011, 5965.164876917235, 6035.969820150893, 6045.33501314855, 5881.176635271044, 5928.318243402021, 5991.700414979256, 6002.339742628307, 6011.489023433013, 5751.18192085877, 5779.826605201851, 5892.793752218673, 5892.941070589303, 5902.409759024106, 5829.708514574254, 5852.736626851802, 5911.822645320647, 5914.260717410324, 5947.375525569818, 5241.030409374278, 5282.0756367635895, 5293.444188360244, 5323.0, 5324.094903866313, 27208.591871121753, 27451.17646470609, 27768.166954991382, 27834.304142396602, 28155.694070249327, 2127.489402543394, 2225.4658881868395, 2240.215924426454, 2253.5718213539376, 2258.288639078685, 17620.295188192456, 17986.28054877803, 17999.73000404995, 18299.0, 18405.447836564923, 2011.89345859778, 2106.09437941685, 2124.564464284818, 2199.285232299509, 2204.680321353411, 115330.0, 147351.0, 147462.99999999892, 147867.26066369706, 150617.24691376474, 12662.467506498737, 12807.0, 12939.935300323437, 12952.352382380843, 12965.805512917314, 16817.327306907588, 17005.149742512825, 17121.0, 17170.313187472362, 17229.65540689179, 8940.90006321647, 9237.30720195988, 9245.260379169786, 9249.12133347331, 9320.813583728288, 8616.439931404439, 8729.170728780755, 8750.824983500293, 8908.376413651113, 8910.910890891008, 7990.960045199853, 8080.474769139987, 8117.878231826528, 8147.83704325922, 8193.918060819315, 2183.2140429445385, 2199.120351859237, 2200.7689192634543, 2222.277734755545, 2224.443889027743, 924.3806649544821, 956.7608097975506, 961.5576834655997, 977.237754551451, 1053.7102296868422, 21094.89452552758, 21703.78296217049, 21730.13079476835, 21889.139423149212, 22206.00072996719, 1025.5077562769911, 1028.9022542858415, 1067.8184708599556, 1072.3351522056375, 1074.2050882347164, 10832.133429325635, 11118.666440006651, 11198.608048718255, 11262.774744505065, 11270.042046425982, 537647.0, 543826.2808685931, 549323.0, 557382.0, 558127.0, 126459.36770316273, 130572.0, 134205.0, 137718.0, 138474.0, 1041.338749893823, 1219.1039585904352, 1253.6489782860813, 1254.868238834909, 1254.8807863252794, 22113.557728845655, 22384.0, 22546.887265564223, 22612.98241579166, 22850.62896226232, 49727.75136124296, 54164.72917635387, 57338.69959753119, 57513.42486575248, 58113.4188658108, 65022.02466962905, 80008.59995699984, 80525.0, 80914.7862782047, 81716.0, 7777.0, 8302.460340077876, 8882.733517994213, 8941.552922353987, 9159.992400836036, 13254.680430924742, 13638.727225455237, 13657.25935401324, 13984.37070331817, 14024.719505609626, 11444.828327574927, 11515.827262591236, 11674.88325116772, 11730.941345293219, 11828.408579570814, 9552.42685438876, 9878.555465004236, 10718.303310284806, 10967.780644387227, 11187.888121118685, 11599.362035087814, 11608.0, 11802.586909458129, 11929.821052684043, 12032.0, 7778.844423111619, 8842.867356989522, 8987.865182022144, 9001.729948101567, 9296.860547091665, 11703.941480292544, 11834.934855863014, 12021.218620789621, 12033.819492707791, 12114.15200935926], "value": 10429.684615838385, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1186.721120536674, 1406.6342750884774, 1413.8444771075174, 1415.796572913024, 1436.3464623596262, 11695.5906543271, 11846.229995050317, 12103.576374826895, 12151.635450936465, 12424.254544727322, 11904.583339583116, 12187.634370968863, 12257.387130643454, 12265.63203103906, 12324.38378081097, 2542.3644088977708, 2589.2491177558486, 2600.0379859451937, 2600.4149066460086, 2608.621749846271, 2521.5839386501207, 2550.979608156743, 2562.6668533090674, 2570.958761701514, 2577.007851976993, 2491.1530079772874, 2519.962200566993, 2524.924252272435, 2527.886245118965, 2528.6586310847983, 2448.6694296270043, 2482.9379265518414, 2485.403503159238, 2499.850008999466, 2501.524710305036, 2433.9878300608384, 2495.438526331579, 2498.312963934932, 2538.4288535079645, 2540.1998370513415, 11933.582324618597, 11937.761244775056, 12036.81944770829, 12049.277043377338, 12071.034317254598, 6535.738570457223, 6733.393994540465, 6783.796486105425, 6784.3894049535775, 6792.93207067928, 3962.385830196324, 4121.29937910556, 4186.183694179636, 4188.68584856137, 4199.4330765346895, 1257.9182353147107, 1278.6867217531765, 1282.487005197919, 1288.1369482446783, 1304.1914013311712, 15626.296816643278, 15983.680326393407, 16824.07467589282, 16852.0, 16968.74546881798, 5518.889622207534, 5587.05020146576, 5733.254676891977, 5740.339860915965, 5774.105013722879, 11848.526058957545, 11955.462004209832, 11977.161598688008, 11985.820212696817, 11995.160338776202, 6372.681365931685, 6564.7374105036215, 6573.57271777333, 6579.703913323912, 6581.3747693969, 6036.607620504653, 6125.877482450326, 6189.876202475925, 6196.690165491707, 6225.28409232935, 4396.560343965578, 4453.465584129925, 4461.286194208911, 4510.233260345683, 4572.199865023607, 1277.067740549404, 1281.8397700287464, 1290.6837824733, 1306.888914442283, 1315.7500074985721, 778.7430148051129, 780.7267456390218, 798.8935324575505, 801.8837268596021, 803.6383627367708, 6717.899231511531, 7054.6472676365975, 7108.715651373887, 7134.536255143399, 7157.284271572905, 923.3121324613206, 955.1880901233895, 958.434523631061, 975.2734213011352, 979.0845559401982, 4468.597826195658, 4477.283634618446, 4627.768611569475, 4640.744759038218, 4683.2038553446, 13957.511487098105, 14618.0, 14631.634209144644, 15011.32449039774, 15037.0, 12454.750904981849, 14802.889783266299, 15013.849861501463, 15039.548813535612, 15072.773908391382, 7802.297793198526, 8004.879926801103, 8195.754127376189, 8519.57402129891, 8586.871196932176, 6278.277998030285, 6487.448566871869, 7229.855402891808, 7232.45756568249, 7327.560346379234, 13287.867121328663, 13559.254241016839, 13580.93209533946, 13652.590422287347, 13836.723265534834, 13924.930375348464, 13933.72132557323, 14065.437382504586, 14085.436582536993, 14288.928555357157, 7835.804104897423, 9269.72190834276, 9574.138327550554, 9577.23382129414, 9581.616735330508, 10787.190960677824, 10799.784004320027, 10859.34843909368, 10874.0, 10916.781664366827, 8912.0, 9218.26253899673, 9292.39599426035, 9296.814063718823, 9375.296852735935, 8971.685990990283, 8989.730308090768, 9089.454632722058, 9235.76910577241, 9291.76770580741, 8225.136360682041, 8319.251267385722, 8367.707130250412, 8411.873821892554, 8436.789080273042, 6115.938840611537, 6150.569460137747, 6207.906881396873, 6235.0, 6358.554901156875, 6133.509319254538, 6194.318624951162, 6289.0252010938375, 6372.458341041063, 6430.2926678066315], "value": 5464.865328492696, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.69398399328554, 383.63937898375525, 397.64212209011885, 408.92639324921504, 409.8155829876556, 5381.623286369956, 6143.139960405536, 6153.169322141515, 6283.669655082802, 6342.778002769904, 6002.549808764338, 6120.510359171267, 6178.382161783819, 6183.0, 6229.283632382278, 3952.940705889414, 4742.454617718972, 4752.287156926454, 4755.502016864677, 4792.329073929653, 4294.20557196918, 4459.818148190732, 4504.324351347292, 4531.27499600064, 4778.546038126373, 4047.2107938951917, 4424.756638384888, 4431.49037860647, 4453.955460445402, 4457.264551349023, 3300.1089705779427, 3535.487354333607, 4206.64243539298, 4484.45286376203, 4493.977530112361, 3191.840407979592, 3729.4219395993664, 3819.140693344003, 3824.177801772604, 3841.7118716096124, 6007.89723596742, 6008.669523176223, 6009.609375390585, 6015.3383127855905, 6035.275766907999, 4650.023495066006, 4701.976490117561, 5313.442088580682, 5322.973385133049, 5323.920141197885, 6290.402411770874, 6320.873582528324, 6530.869382612321, 6614.081916244331, 6636.93363066368, 4165.520965089023, 4319.460067491564, 4343.457067866517, 4369.80335884886, 4373.475182978063, 5273.815416460444, 5651.745671444794, 6071.726772295257, 6670.866582668319, 7043.506954513186, 4291.592298731615, 4394.912101757947, 4400.613806650895, 4403.471583410012, 4408.4269045024585, 4477.462704475419, 4479.484859241164, 4483.1706134364595, 4489.349044388546, 4490.887727806831, 4730.645201609893, 5203.479652034766, 5321.175217841241, 5343.946560534345, 5366.758495867695, 6600.141981542465, 6603.801885943333, 6605.612821307579, 6616.735330586819, 6682.164729408824, 1347.8652134786444, 1495.9476418325307, 1519.749241375183, 1531.5099168266047, 1539.7305471542436, 970.5058000937663, 981.622075500923, 1001.998001998002, 1012.4583347908833, 1016.1515134862473, 5530.391656917735, 6171.981623032239, 6303.842403939938, 6332.620042797447, 6336.651484168415, 5739.827805165852, 6352.0, 6777.289084366293, 6864.627074585103, 6916.308369163044, 4932.459783211649, 5891.762729826784, 6237.594556353823, 6272.905906411407, 6279.874402511924, 3353.84907679155, 3412.4710669846213, 3556.2752065248333, 3571.339302229044, 3598.6684926577336, 6785.694643741043, 6987.065432518354, 7020.122484689414, 7052.788416347517, 7083.468739844532, 6731.629760363227, 6785.1518560179975, 6961.338672826175, 6978.5812851228075, 7031.0, 5523.404000119937, 6728.192616886005, 6777.559458635074, 7032.753853615099, 7084.858302834017, 6674.332566743286, 6856.5200435969, 6861.348171923659, 6872.62200578973, 6881.13985751781, 6723.932760672331, 6783.0, 6804.863902722017, 6828.692708828214, 6835.658217089225, 5862.267216597926, 6407.295197528361, 6542.574732642363, 6771.356721111487, 7099.538529995534, 3890.9221815562964, 4354.9782251088545, 4373.781310934504, 4395.428594282723, 4421.093675796518, 5952.267946982246, 6800.625965571941, 6845.132500137406, 6879.277675843964, 6880.518363714492, 5766.279215098113, 6409.615423074631, 6533.314002029719, 6600.768973085919, 6629.635370054693, 5659.405762394889, 5912.694049320854, 6595.109660196004, 6602.6986506746625, 6614.570052946543, 5779.8729247797955, 6265.028920517327, 6467.932791089605, 6480.870382592416, 6483.805485835433, 6035.370449978569, 6285.2457705075685, 6480.773172938924, 6597.406233439014, 6625.271220165681, 5006.349174607278, 6447.3552644735155, 6467.611943283418, 6477.708503117276, 6479.967600162157], "value": 4753.550967060032, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.7964548685572, 838.4843321357365, 852.3905407633545, 860.2859626509997, 869.8216865542562, 16329.83670163298, 16779.412720554785, 17475.213615387307, 17515.0, 19606.803931960676, 18239.81760182398, 18598.81401185988, 18640.627187456244, 18681.626367472643, 18747.437576872682, 972.1930797438127, 989.7525618595351, 998.9500524973759, 999.1856636840952, 1003.9548220330066, 998.9900100999006, 1001.5843424978625, 1005.3816902604918, 1005.4419797012655, 1007.4408703169738, 1066.029912779371, 1073.4900922062036, 1073.5598404654093, 1075.2150929821235, 1078.7788503356833, 1045.3518818332607, 1058.899404556566, 1068.326954018963, 1070.6734445993968, 1071.710638127709, 775.4959276470283, 784.4195295481303, 785.7878372839358, 786.6420778545748, 793.2939683681528, 17646.823531764647, 18104.0, 18219.362322318655, 18291.176897039666, 18346.816531834644, 15556.144412057332, 17760.91119544407, 17779.0, 18118.72821907659, 18150.00174990375, 14667.63330916725, 14717.852821471755, 14726.558203253919, 14853.183074930874, 14893.255337233095, 3515.138790996197, 3599.8740044098595, 3607.9278414431565, 3612.981935090334, 3622.3660859349507, 132729.3363533186, 163586.0, 163620.0, 164400.0, 167560.0, 13298.800517992238, 13948.58154255374, 14067.085639433404, 14255.78616320756, 14275.0, 23108.19121330779, 23640.88179559091, 24437.633435498483, 26687.733122668913, 26852.0, 7892.4869883457395, 7903.486273392211, 7939.0, 7950.0, 7957.0, 3987.282289187916, 4038.3134867072667, 4040.4343391925154, 4059.5331536873045, 4063.349864021742, 4438.689291749546, 4576.176288268143, 4584.587387135174, 4599.241125214369, 4616.492185859553, 922.197688011421, 961.7211008807508, 962.8411312133419, 974.3277138774223, 978.5449765858909, 643.3791391307317, 665.1286814273318, 677.5595862689192, 677.617146312332, 680.9421199198124, 11558.422078896021, 12433.37833108341, 12492.312922789333, 12536.059795515373, 12722.872771272354, 646.9547131700735, 646.9583969808698, 650.339904996429, 656.6224420957968, 682.2086379799508, 5532.585056120807, 5667.801626943037, 5692.430756924275, 5829.300483941955, 5891.939450898879, 138377.0, 138652.0, 138984.30507847396, 140202.29898850643, 143166.28416858058, 92871.0, 121290.39354803346, 121979.39010304892, 125971.0, 126892.0, 85056.0, 95636.52181739047, 96114.0, 96945.54581681281, 97075.51462242784, 11257.662270131908, 11384.601538946097, 11500.137489688139, 11657.708557286136, 11704.473298701749, 45617.77191114023, 45699.771501142284, 45704.77147614241, 46506.53493465157, 46676.53323466722, 59882.10176847264, 59967.00329966968, 60392.0, 60534.39465605288, 60865.87011948243, 14523.056001359877, 14651.633709157357, 14664.70670586561, 15103.0, 15109.848901510844, 13417.463301468222, 13537.255450949899, 13597.0, 13651.976101792206, 13684.178949263076, 14857.885658575435, 15108.093514389171, 15131.697366052396, 15202.467913623412, 15285.465008724641, 14692.926535367254, 15184.848151518343, 15296.847031529987, 15378.461753838563, 15378.923475356618, 14386.424543018162, 14672.413103475741, 14892.255387230378, 15002.774958375852, 15091.094534327976, 10391.948040259751, 10781.0, 10844.065949992892, 10982.913246483142, 11132.721681958015, 13493.257870817199, 14455.78316325277, 14527.49153779655, 14604.780928286296, 14633.122012679274], "value": 8645.343545176249, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1551.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 116.0, 115.0, 116.0, 117.0, 115.0, 159.0, 114.0, 117.0], "value": 118.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 105.0, 142.0, 188.0, 114.0, 112.0, 121.0, 140.0, 146.0, 110.0], "value": 136.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 207.0, 207.0, 249.0, 292.0, 201.0, 202.0, 203.0, 198.0, 201.0], "value": 216.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 58.0, 60.0, 83.0, 59.0, 61.0, 59.0, 59.0, 96.0, 82.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 70.0, 85.0, 71.0, 73.0, 68.0, 84.0, 69.0, 68.0, 97.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 88.0, 88.0, 90.0, 88.0, 113.0, 133.0, 93.0, 85.0], "value": 95.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 116.0, 149.0, 116.0, 100.0, 146.0, 114.0, 150.0, 154.0], "value": 123.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 93.0, 92.0, 92.0, 102.0, 90.0, 91.0, 91.0, 90.0], "value": 92.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 53.0, 55.0, 54.0, 50.0, 47.0, 44.0, 44.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 65.0, 88.0, 89.0, 81.0, 55.0, 57.0, 60.0, 57.0], "value": 67.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 87.0, 86.0, 90.0, 89.0, 90.0, 89.0, 128.0, 88.0], "value": 92.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 136.0, 163.0, 152.0, 138.0, 158.0, 142.0, 155.0, 137.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 188.0, 186.0, 173.0, 209.0, 228.0, 206.0, 191.0, 237.0, 184.0], "value": 199.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 65.0, 68.0, 73.0, 66.0, 70.0, 70.0, 70.0, 66.0], "value": 68.5, "unit": "score"}]}]} 03:05:41 INFO - Return code: 0 03:05:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:05:41 INFO - # TBPL SUCCESS # 03:05:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:05:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:05:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:05:41 INFO - 2017-05-29 03:05:41.160 screenresolution[1669:12052] starting screenresolution argv=screenresolution get 03:05:41 INFO - 2017-05-29 03:05:41.163 screenresolution[1669:12052] Display 0: 1600x1200x32@60 03:05:41 INFO - 2017-05-29 03:05:41.169 screenresolution[1670:12055] starting screenresolution argv=screenresolution list 03:05:41 INFO - Available Modes on Display 0 03:05:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:05:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:05:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:05:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:05:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:05:41 INFO - Intel Iris: 03:05:41 INFO - Chipset Model: Intel Iris 03:05:41 INFO - Type: GPU 03:05:41 INFO - Bus: Built-In 03:05:41 INFO - VRAM (Dynamic, Max): 1536 MB 03:05:41 INFO - Vendor: Intel (0x8086) 03:05:41 INFO - Device ID: 0x0a2e 03:05:41 INFO - Revision ID: 0x0009 03:05:41 INFO - Displays: 03:05:41 INFO - Display: 03:05:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:05:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:05:41 INFO - Display Serial Number: 1600x1200 60 03:05:41 INFO - Main Display: Yes 03:05:41 INFO - Mirror: Off 03:05:41 INFO - Online: Yes 03:05:41 INFO - Rotation: Supported 03:05:41 INFO - Return code: 0 03:05:41 INFO - Running post-action listener: _resource_record_post_action 03:05:41 INFO - [mozharness: 2017-05-29 10:05:41.403731Z] Finished run-tests step (success) 03:05:41 INFO - Running post-run listener: _resource_record_post_run 03:05:41 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 33604608; Write bytes: 572216832; Read time: 326; Write time: 2143 03:05:41 INFO - TinderboxPrint: CPU usage
53.4% 03:05:41 INFO - TinderboxPrint: I/O read bytes / time
33,604,608 / 326 03:05:41 INFO - TinderboxPrint: I/O write bytes / time
572,216,832 / 2,143 03:05:41 INFO - TinderboxPrint: CPU idle
2,341.5 (46.6%) 03:05:41 INFO - TinderboxPrint: CPU system
1,016.5 (20.2%) 03:05:41 INFO - TinderboxPrint: CPU user
1,667.8 (33.2%) 03:05:41 INFO - TinderboxPrint: Swap in / out
276,463,616 / 0 03:05:41 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 235168256; Write bytes: 231544320; Read time: 16749; Write time: 574 03:05:41 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:05:41 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 31708160; Write bytes: 321343488; Read time: 305; Write time: 1523 03:05:41 INFO - Running post-run listener: _upload_blobber_files 03:05:41 INFO - Blob upload gear active. 03:05:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:05:41 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 03:05:41 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'] 03:05:41 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 03:05:42 INFO - (blobuploader) - INFO - Open directory for files ... 03:05:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:05:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:05:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:43 INFO - (blobuploader) - INFO - Done attempting. 03:05:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:05:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:05:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:44 INFO - (blobuploader) - INFO - Done attempting. 03:05:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:05:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:05:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:45 INFO - (blobuploader) - INFO - Done attempting. 03:05:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:05:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:05:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:47 INFO - (blobuploader) - INFO - Done attempting. 03:05:47 INFO - (blobuploader) - INFO - Iteration through files over. 03:05:47 INFO - Return code: 0 03:05:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:05:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:05:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c7d9bd029e4eacee9b71f65f01d174ede8e9e3a37f004fdc7c8673c78b3f316e6fb3cca10bd8471f7e7e5640bf548048fa46c67b5827f27990002b959ab217f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b313d52b258f2cbcc3022b1c1b6481eea8c91439f1d2da71c089be87eb5c2f7373b1638af8bb06854b23251430b6ac4893927062080fc59a4cf0ceabe03a23b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56568e7a5877408114f27071b9f4d9e135ddc0f7e2e160478c173cedfbdf1e8ead14e7677a93ff2120f431e6be87eb19156958342e5864628dc3a4ccfe66c0c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a57faca1e0fa026ceb108b33bcff5401da99da96a0520ad8feb940521fab4d5b0338c73b8a7e60c968080e83f3150d479dff6537aa42c4d1db1fdaac1b3c569"} 03:05:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:05:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:05:47 INFO - Contents: 03:05:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c7d9bd029e4eacee9b71f65f01d174ede8e9e3a37f004fdc7c8673c78b3f316e6fb3cca10bd8471f7e7e5640bf548048fa46c67b5827f27990002b959ab217f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b313d52b258f2cbcc3022b1c1b6481eea8c91439f1d2da71c089be87eb5c2f7373b1638af8bb06854b23251430b6ac4893927062080fc59a4cf0ceabe03a23b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56568e7a5877408114f27071b9f4d9e135ddc0f7e2e160478c173cedfbdf1e8ead14e7677a93ff2120f431e6be87eb19156958342e5864628dc3a4ccfe66c0c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a57faca1e0fa026ceb108b33bcff5401da99da96a0520ad8feb940521fab4d5b0338c73b8a7e60c968080e83f3150d479dff6537aa42c4d1db1fdaac1b3c569"} 03:05:47 INFO - Running post-run listener: copy_logs_to_upload_dir 03:05:47 INFO - Copying logs to upload dir... 03:05:47 INFO - mkdir: /builds/slave/test/build/upload/logs 03:05:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.030691 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-05-29 03:05:47.966409) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 03:05:47.970402) ========= 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.ASDIJQcszG/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.1wNGdZmVh3/Listeners TMPDIR=/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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/0c7d9bd029e4eacee9b71f65f01d174ede8e9e3a37f004fdc7c8673c78b3f316e6fb3cca10bd8471f7e7e5640bf548048fa46c67b5827f27990002b959ab217f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b313d52b258f2cbcc3022b1c1b6481eea8c91439f1d2da71c089be87eb5c2f7373b1638af8bb06854b23251430b6ac4893927062080fc59a4cf0ceabe03a23b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56568e7a5877408114f27071b9f4d9e135ddc0f7e2e160478c173cedfbdf1e8ead14e7677a93ff2120f431e6be87eb19156958342e5864628dc3a4ccfe66c0c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a57faca1e0fa026ceb108b33bcff5401da99da96a0520ad8feb940521fab4d5b0338c73b8a7e60c968080e83f3150d479dff6537aa42c4d1db1fdaac1b3c569"} build_url:https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011194 build_url: 'https://queue.taskcluster.net/v1/task/Slbt3NNMROOseFIeVK1-5w/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/0c7d9bd029e4eacee9b71f65f01d174ede8e9e3a37f004fdc7c8673c78b3f316e6fb3cca10bd8471f7e7e5640bf548048fa46c67b5827f27990002b959ab217f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b313d52b258f2cbcc3022b1c1b6481eea8c91439f1d2da71c089be87eb5c2f7373b1638af8bb06854b23251430b6ac4893927062080fc59a4cf0ceabe03a23b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56568e7a5877408114f27071b9f4d9e135ddc0f7e2e160478c173cedfbdf1e8ead14e7677a93ff2120f431e6be87eb19156958342e5864628dc3a4ccfe66c0c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a57faca1e0fa026ceb108b33bcff5401da99da96a0520ad8feb940521fab4d5b0338c73b8a7e60c968080e83f3150d479dff6537aa42c4d1db1fdaac1b3c569"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 03:05:48.005293) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:05:48.005647) ========= 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.ASDIJQcszG/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.1wNGdZmVh3/Listeners TMPDIR=/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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.005000 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:05:48.057746) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 03:05:48.058377) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 03:05:48.059175) ========= ========= Total master_lag: 2.50 =========