builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-055 starttime: 1474285538.11 results: success (0) buildid: 20160919030422 builduid: e5d452446b6f47b19983d4cdb144636c revision: f0f15b7c6aa77a0c5750918aa0a1cb3dc82185bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 04:45:38.107277) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 04:45:38.107795) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 04:45:38.108130) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 04:45:38.120861) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 04:45:38.121591) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 04:45:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.7M=0s 2016-09-19 04:45:38 (40.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116978 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 04:45:38.255428) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 04:45:38.255991) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016590 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 04:45:38.306315) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 04:45:38.306724) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f0f15b7c6aa77a0c5750918aa0a1cb3dc82185bc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f0f15b7c6aa77a0c5750918aa0a1cb3dc82185bc --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 04:45:38,850 truncating revision to first 12 chars 2016-09-19 04:45:38,850 Setting DEBUG logging. 2016-09-19 04:45:38,850 attempt 1/10 2016-09-19 04:45:38,850 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f0f15b7c6aa7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 04:45:39,948 unpacking tar archive at: mozilla-central-f0f15b7c6aa7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.435007 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 04:45:39.759063) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 04:45:39.759808) ========= script_repo_revision: f0f15b7c6aa77a0c5750918aa0a1cb3dc82185bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 04:45:39.760637) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 04:45:39.760970) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 04:45:39.772011) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-09-19 04:45:39.772695) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_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', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:45:40 INFO - MultiFileLogger online at 20160919 04:45:40 in /builds/slave/test 04:45:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:45:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:45:40 INFO - {'append_to_log': False, 04:45:40 INFO - 'base_work_dir': '/builds/slave/test', 04:45:40 INFO - 'blob_upload_branch': 'Firefox', 04:45:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:45:40 INFO - 'branch': 'Firefox', 04:45:40 INFO - 'buildbot_json_path': 'buildprops.json', 04:45:40 INFO - 'config_files': ('talos/linux_config.py',), 04:45:40 INFO - 'default_actions': ('clobber', 04:45:40 INFO - 'read-buildbot-config', 04:45:40 INFO - 'download-and-extract', 04:45:40 INFO - 'populate-webroot', 04:45:40 INFO - 'create-virtualenv', 04:45:40 INFO - 'install', 04:45:40 INFO - 'run-tests'), 04:45:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:45:40 INFO - 'download_minidump_stackwalk': True, 04:45:40 INFO - 'download_symbols': 'ondemand', 04:45:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:45:40 INFO - 'tooltool.py': '/tools/tooltool.py', 04:45:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:45:40 INFO - '/tools/misc-python/virtualenv.py')}, 04:45:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:45:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:45:40 INFO - 'installer_path': 'installer.exe', 04:45:40 INFO - 'log_level': 'info', 04:45:40 INFO - 'log_name': 'talos', 04:45:40 INFO - 'log_to_console': True, 04:45:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:45:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:45:40 INFO - 'opt_config_files': (), 04:45:40 INFO - 'pip_index': False, 04:45:40 INFO - 'sps_profile': False, 04:45:40 INFO - 'sps_profile_interval': 0, 04:45:40 INFO - 'suite': 'dromaeojs', 04:45:40 INFO - 'system_bits': '32', 04:45:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:45:40 INFO - 'title': 'talos-linux64-ix-055', 04:45:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:45:40 INFO - 'use_talos_json': True, 04:45:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:45:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:45:40 INFO - 'work_dir': 'build'} 04:45:40 INFO - [mozharness: 2016-09-19 11:45:40.414630Z] Running clobber step. 04:45:40 INFO - Running pre-action listener: _resource_record_pre_action 04:45:40 INFO - Running main action method: clobber 04:45:40 INFO - rmtree: /builds/slave/test/build 04:45:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:45:40 INFO - Running post-action listener: _resource_record_post_action 04:45:40 INFO - [mozharness: 2016-09-19 11:45:40.872903Z] Finished clobber step (success) 04:45:40 INFO - [mozharness: 2016-09-19 11:45:40.873006Z] Running read-buildbot-config step. 04:45:40 INFO - Running pre-action listener: _resource_record_pre_action 04:45:40 INFO - Running main action method: read_buildbot_config 04:45:40 INFO - Using buildbot properties: 04:45:40 INFO - { 04:45:40 INFO - "project": "", 04:45:40 INFO - "product": "firefox", 04:45:40 INFO - "script_repo_revision": "production", 04:45:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 04:45:40 INFO - "repository": "", 04:45:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 04:45:40 INFO - "buildid": "20160919030422", 04:45:40 INFO - "stage_platform": "linux64-pgo", 04:45:40 INFO - "basedir": "/builds/slave/test", 04:45:40 INFO - "nightly_build": "True", 04:45:40 INFO - "buildnumber": 459, 04:45:40 INFO - "slavename": "talos-linux64-ix-055", 04:45:40 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:45:40 INFO - "platform": "ubuntu64_hw", 04:45:40 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 04:45:40 INFO - "branch": "mozilla-central", 04:45:40 INFO - "repo_path": "mozilla-central", 04:45:40 INFO - "revision": "f0f15b7c6aa77a0c5750918aa0a1cb3dc82185bc", 04:45:40 INFO - "pgo_build": "True", 04:45:40 INFO - "builduid": "e5d452446b6f47b19983d4cdb144636c", 04:45:40 INFO - "slavebuilddir": "test" 04:45:40 INFO - } 04:45:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 04:45:40 INFO - Running post-action listener: _resource_record_post_action 04:45:40 INFO - [mozharness: 2016-09-19 11:45:40.875856Z] Finished read-buildbot-config step (success) 04:45:40 INFO - [mozharness: 2016-09-19 11:45:40.875947Z] Running download-and-extract step. 04:45:40 INFO - Running pre-action listener: _resource_record_pre_action 04:45:40 INFO - Running main action method: download_and_extract 04:45:40 INFO - mkdir: /builds/slave/test/build/tests 04:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:45:40 INFO - trying https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 04:45:40 INFO - Downloading https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 04:45:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:45:41 INFO - Downloaded 1361 bytes. 04:45:41 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 04:45:41 INFO - Using the following test package requirements: 04:45:41 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 04:45:41 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:45:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:45:41 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:45:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:45:41 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:45:41 INFO - u'jsshell-linux-x86_64.zip'], 04:45:41 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:45:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:45:41 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 04:45:41 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:45:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:45:41 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:45:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:45:41 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:45:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:45:41 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:45:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:45:41 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:45:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 04:45:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:45:41 INFO - Fetch https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:45:41 INFO - Expected file size: 21936403 04:45:46 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:45:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 04:45:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:45:46 INFO - Fetch https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:45:46 INFO - Expected file size: 21936403 04:45:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 04:45:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:45:51 INFO - Fetch https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 04:45:52 INFO - Expected file size: 14414048 04:45:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:45:52 INFO - trying https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:45:52 INFO - Downloading https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:45:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:45:53 INFO - Downloaded 59579053 bytes. 04:45:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:45:53 INFO - mkdir: /builds/slave/test/properties 04:45:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:45:53 INFO - Writing to file /builds/slave/test/properties/build_url 04:45:53 INFO - Contents: 04:45:53 INFO - build_url:https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:45:54 INFO - Running post-action listener: _resource_record_post_action 04:45:54 INFO - Running post-action listener: set_extra_try_arguments 04:45:54 INFO - [mozharness: 2016-09-19 11:45:54.478727Z] Finished download-and-extract step (success) 04:45:54 INFO - [mozharness: 2016-09-19 11:45:54.478957Z] Running populate-webroot step. 04:45:54 INFO - Running pre-action listener: _resource_record_pre_action 04:45:54 INFO - Running main action method: populate_webroot 04:45:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:45:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:45:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:45:54 INFO - '--tppagecycles', 04:45:54 INFO - '7'], 04:45:54 INFO - 'tests': ['tsvgm']}}, 04:45:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:45:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:45:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:45:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:45:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:45:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:45:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:54 INFO - 'talos_options': ['--disable-e10s'], 04:45:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:54 INFO - 'talos_options': ['--disable-e10s'], 04:45:54 INFO - 'tests': ['damp', 'tps']}, 04:45:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:54 INFO - 'tests': ['damp', 'tps']}, 04:45:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:45:54 INFO - 'tests': ['dromaeo_dom']}, 04:45:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:45:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:45:54 INFO - 'tests': ['basic_compositor_video']}, 04:45:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:45:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:45:54 INFO - 'tests': ['a11yr', 04:45:54 INFO - 'ts_paint', 04:45:54 INFO - 'tpaint', 04:45:54 INFO - 'sessionrestore', 04:45:54 INFO - 'sessionrestore_no_auto_restore', 04:45:54 INFO - 'tabpaint']}, 04:45:54 INFO - 'other-e10s': {'tests': ['a11yr', 04:45:54 INFO - 'ts_paint', 04:45:54 INFO - 'tpaint', 04:45:54 INFO - 'sessionrestore', 04:45:54 INFO - 'sessionrestore_no_auto_restore', 04:45:54 INFO - 'tabpaint']}, 04:45:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:45:54 INFO - 'ts_paint', 04:45:54 INFO - 'tpaint', 04:45:54 INFO - 'sessionrestore', 04:45:54 INFO - 'sessionrestore_no_auto_restore', 04:45:54 INFO - 'tabpaint']}, 04:45:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:45:54 INFO - 'ts_paint', 04:45:54 INFO - 'tpaint', 04:45:54 INFO - 'sessionrestore', 04:45:54 INFO - 'sessionrestore_no_auto_restore', 04:45:54 INFO - 'tabpaint']}, 04:45:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:45:54 INFO - 'tests': ['a11yr', 04:45:54 INFO - 'ts_paint', 04:45:54 INFO - 'tpaint', 04:45:54 INFO - 'sessionrestore', 04:45:54 INFO - 'sessionrestore_no_auto_restore', 04:45:54 INFO - 'tabpaint']}, 04:45:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:45:54 INFO - 'tests': ['a11yr', 04:45:54 INFO - 'ts_paint', 04:45:54 INFO - 'tpaint', 04:45:54 INFO - 'sessionrestore', 04:45:54 INFO - 'sessionrestore_no_auto_restore', 04:45:54 INFO - 'tabpaint']}, 04:45:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:45:54 INFO - 'tests': ['tsvgx', 04:45:54 INFO - 'tsvgr_opacity', 04:45:54 INFO - 'tart', 04:45:54 INFO - 'tscrollx', 04:45:54 INFO - 'cart']}, 04:45:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:45:54 INFO - 'tsvgr_opacity', 04:45:54 INFO - 'tart', 04:45:54 INFO - 'tscrollx', 04:45:54 INFO - 'cart']}, 04:45:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:54 INFO - 'talos_options': ['--disable-e10s'], 04:45:54 INFO - 'tests': ['tp5o']}, 04:45:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:54 INFO - 'tests': ['tp5o']}, 04:45:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:54 INFO - 'talos_options': ['--disable-e10s', 04:45:54 INFO - '--xperf_path', 04:45:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:54 INFO - 'tests': ['tp5n']}, 04:45:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:54 INFO - 'talos_options': ['--xperf_path', 04:45:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:54 INFO - 'tests': ['tp5n']}}, 04:45:54 INFO - 'talos.zip': {'path': '', 04:45:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:45:54 INFO - Running post-action listener: _resource_record_post_action 04:45:54 INFO - [mozharness: 2016-09-19 11:45:54.499087Z] Finished populate-webroot step (success) 04:45:54 INFO - [mozharness: 2016-09-19 11:45:54.499178Z] Running create-virtualenv step. 04:45:54 INFO - Running pre-action listener: _resource_record_pre_action 04:45:54 INFO - Running main action method: create_virtualenv 04:45:54 INFO - Creating virtualenv /builds/slave/test/build/venv 04:45:54 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 04:45:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:45:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:45:54 INFO - Using real prefix '/usr' 04:45:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:45:55 INFO - Installing distribute.............................................................................................................................................................................................done. 04:45:57 INFO - Installing pip.................done. 04:45:57 INFO - Return code: 0 04:45:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:45:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:57 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f93e4bbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c930>, '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 0x280d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:45:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:45:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:45:57 INFO - Using env: {'DISPLAY': ':0', 04:45:57 INFO - 'HOME': '/home/cltbld', 04:45:57 INFO - 'LANG': 'en_US.UTF-8', 04:45:57 INFO - 'LANGUAGE': 'en_US:en', 04:45:57 INFO - 'LOGNAME': 'cltbld', 04:45:57 INFO - 'MAIL': '/var/mail/cltbld', 04:45:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:57 INFO - 'MOZ_NO_REMOTE': '1', 04:45:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:57 INFO - 'NO_EM_RESTART': '1', 04:45:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:57 INFO - 'PWD': '/builds/slave/test', 04:45:57 INFO - 'SHELL': '/bin/bash', 04:45:57 INFO - 'SHLVL': '1', 04:45:57 INFO - 'TERM': 'linux', 04:45:57 INFO - 'TMOUT': '86400', 04:45:57 INFO - 'USER': 'cltbld', 04:45:57 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 04:45:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:57 INFO - '_': '/tools/buildbot/bin/python'} 04:45:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:57 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 04:45:57 INFO - Cleaning up... 04:45:57 INFO - Return code: 0 04:45:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:45:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:57 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f93e4bbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c930>, '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 0x280d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:45:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:45:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:45:57 INFO - Using env: {'DISPLAY': ':0', 04:45:57 INFO - 'HOME': '/home/cltbld', 04:45:57 INFO - 'LANG': 'en_US.UTF-8', 04:45:57 INFO - 'LANGUAGE': 'en_US:en', 04:45:57 INFO - 'LOGNAME': 'cltbld', 04:45:57 INFO - 'MAIL': '/var/mail/cltbld', 04:45:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:57 INFO - 'MOZ_NO_REMOTE': '1', 04:45:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:57 INFO - 'NO_EM_RESTART': '1', 04:45:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:57 INFO - 'PWD': '/builds/slave/test', 04:45:57 INFO - 'SHELL': '/bin/bash', 04:45:57 INFO - 'SHLVL': '1', 04:45:57 INFO - 'TERM': 'linux', 04:45:57 INFO - 'TMOUT': '86400', 04:45:57 INFO - 'USER': 'cltbld', 04:45:57 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 04:45:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:57 INFO - '_': '/tools/buildbot/bin/python'} 04:45:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:57 INFO - Downloading/unpacking psutil>=3.1.1 04:45:57 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 04:45:57 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 04:45:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:57 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 04:45:57 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 04:45:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:45:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:59 INFO - Installing collected packages: psutil 04:45:59 INFO - Running setup.py install for psutil 04:45:59 INFO - building 'psutil._psutil_linux' extension 04:45:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:45:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:45:59 INFO - building 'psutil._psutil_posix' extension 04:45:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:45:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:45:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:46:00 INFO - Successfully installed psutil 04:46:00 INFO - Cleaning up... 04:46:00 INFO - Return code: 0 04:46:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:46:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f93e4bbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c930>, '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 0x280d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:46:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:46:00 INFO - Using env: {'DISPLAY': ':0', 04:46:00 INFO - 'HOME': '/home/cltbld', 04:46:00 INFO - 'LANG': 'en_US.UTF-8', 04:46:00 INFO - 'LANGUAGE': 'en_US:en', 04:46:00 INFO - 'LOGNAME': 'cltbld', 04:46:00 INFO - 'MAIL': '/var/mail/cltbld', 04:46:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:00 INFO - 'MOZ_NO_REMOTE': '1', 04:46:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:00 INFO - 'NO_EM_RESTART': '1', 04:46:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:00 INFO - 'PWD': '/builds/slave/test', 04:46:00 INFO - 'SHELL': '/bin/bash', 04:46:00 INFO - 'SHLVL': '1', 04:46:00 INFO - 'TERM': 'linux', 04:46:00 INFO - 'TMOUT': '86400', 04:46:00 INFO - 'USER': 'cltbld', 04:46:00 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 04:46:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:00 INFO - '_': '/tools/buildbot/bin/python'} 04:46:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:46:00 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 04:46:00 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 04:46:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:00 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 04:46:00 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 04:46:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:46:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:46:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:46:02 INFO - Installing collected packages: mozsystemmonitor 04:46:02 INFO - Running setup.py install for mozsystemmonitor 04:46:02 INFO - Successfully installed mozsystemmonitor 04:46:02 INFO - Cleaning up... 04:46:02 INFO - Return code: 0 04:46:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:02 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f93e4bbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c930>, '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 0x280d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:46:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:46:02 INFO - Using env: {'DISPLAY': ':0', 04:46:02 INFO - 'HOME': '/home/cltbld', 04:46:02 INFO - 'LANG': 'en_US.UTF-8', 04:46:02 INFO - 'LANGUAGE': 'en_US:en', 04:46:02 INFO - 'LOGNAME': 'cltbld', 04:46:02 INFO - 'MAIL': '/var/mail/cltbld', 04:46:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:02 INFO - 'MOZ_NO_REMOTE': '1', 04:46:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:02 INFO - 'NO_EM_RESTART': '1', 04:46:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:02 INFO - 'PWD': '/builds/slave/test', 04:46:02 INFO - 'SHELL': '/bin/bash', 04:46:02 INFO - 'SHLVL': '1', 04:46:02 INFO - 'TERM': 'linux', 04:46:02 INFO - 'TMOUT': '86400', 04:46:02 INFO - 'USER': 'cltbld', 04:46:02 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 04:46:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:02 INFO - '_': '/tools/buildbot/bin/python'} 04:46:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:02 INFO - Downloading/unpacking jsonschema==2.5.1 04:46:02 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 04:46:02 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 04:46:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:02 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 04:46:02 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 04:46:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:46:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:46:04 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 04:46:04 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 04:46:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:04 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 04:46:04 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 04:46:04 INFO - Downloading functools32-3.2.3-2.tar.gz 04:46:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:46:04 INFO - warning: no files found matching '*.txt' 04:46:04 INFO - no previously-included directories found matching 'build' 04:46:04 INFO - no previously-included directories found matching 'dist' 04:46:04 INFO - no previously-included directories found matching '.git*' 04:46:04 INFO - Installing collected packages: jsonschema, functools32 04:46:04 INFO - Running setup.py install for functools32 04:46:04 INFO - warning: no files found matching '*.txt' 04:46:04 INFO - no previously-included directories found matching 'build' 04:46:04 INFO - no previously-included directories found matching 'dist' 04:46:04 INFO - no previously-included directories found matching '.git*' 04:46:04 INFO - Successfully installed jsonschema functools32 04:46:04 INFO - Cleaning up... 04:46:04 INFO - Return code: 0 04:46:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:04 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f93e4bbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c930>, '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 0x280d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:46:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:46:04 INFO - Using env: {'DISPLAY': ':0', 04:46:04 INFO - 'HOME': '/home/cltbld', 04:46:04 INFO - 'LANG': 'en_US.UTF-8', 04:46:04 INFO - 'LANGUAGE': 'en_US:en', 04:46:04 INFO - 'LOGNAME': 'cltbld', 04:46:04 INFO - 'MAIL': '/var/mail/cltbld', 04:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:04 INFO - 'MOZ_NO_REMOTE': '1', 04:46:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:04 INFO - 'NO_EM_RESTART': '1', 04:46:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:04 INFO - 'PWD': '/builds/slave/test', 04:46:04 INFO - 'SHELL': '/bin/bash', 04:46:04 INFO - 'SHLVL': '1', 04:46:04 INFO - 'TERM': 'linux', 04:46:04 INFO - 'TMOUT': '86400', 04:46:04 INFO - 'USER': 'cltbld', 04:46:04 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 04:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:04 INFO - '_': '/tools/buildbot/bin/python'} 04:46:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:46:05 INFO - Cleaning up... 04:46:05 INFO - Return code: 0 04:46:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f93e4bbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c930>, '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 0x280d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:46:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:46:05 INFO - Using env: {'DISPLAY': ':0', 04:46:05 INFO - 'HOME': '/home/cltbld', 04:46:05 INFO - 'LANG': 'en_US.UTF-8', 04:46:05 INFO - 'LANGUAGE': 'en_US:en', 04:46:05 INFO - 'LOGNAME': 'cltbld', 04:46:05 INFO - 'MAIL': '/var/mail/cltbld', 04:46:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:05 INFO - 'MOZ_NO_REMOTE': '1', 04:46:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:05 INFO - 'NO_EM_RESTART': '1', 04:46:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:05 INFO - 'PWD': '/builds/slave/test', 04:46:05 INFO - 'SHELL': '/bin/bash', 04:46:05 INFO - 'SHLVL': '1', 04:46:05 INFO - 'TERM': 'linux', 04:46:05 INFO - 'TMOUT': '86400', 04:46:05 INFO - 'USER': 'cltbld', 04:46:05 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 04:46:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:05 INFO - '_': '/tools/buildbot/bin/python'} 04:46:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:05 INFO - Downloading/unpacking blobuploader==1.2.4 04:46:05 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 04:46:05 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 04:46:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:05 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 04:46:05 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 04:46:07 INFO - Downloading blobuploader-1.2.4.tar.gz 04:46:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:46:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:46:07 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 04:46:07 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 04:46:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:07 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 04:46:07 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 04:46:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:46:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:46:07 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 04:46:07 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 04:46:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:07 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 04:46:07 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 04:46:07 INFO - Downloading docopt-0.6.1.tar.gz 04:46:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:46:08 INFO - Installing collected packages: blobuploader, requests, docopt 04:46:08 INFO - Running setup.py install for blobuploader 04:46:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:46:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:46:08 INFO - Running setup.py install for requests 04:46:08 INFO - Running setup.py install for docopt 04:46:08 INFO - Successfully installed blobuploader requests docopt 04:46:08 INFO - Cleaning up... 04:46:08 INFO - Return code: 0 04:46:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:08 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f93e4bbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c930>, '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 0x280d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46:08 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:46:08 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:46:08 INFO - Using env: {'DISPLAY': ':0', 04:46:08 INFO - 'HOME': '/home/cltbld', 04:46:08 INFO - 'LANG': 'en_US.UTF-8', 04:46:08 INFO - 'LANGUAGE': 'en_US:en', 04:46:08 INFO - 'LOGNAME': 'cltbld', 04:46:08 INFO - 'MAIL': '/var/mail/cltbld', 04:46:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:08 INFO - 'MOZ_NO_REMOTE': '1', 04:46:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:08 INFO - 'NO_EM_RESTART': '1', 04:46:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:08 INFO - 'PWD': '/builds/slave/test', 04:46:08 INFO - 'SHELL': '/bin/bash', 04:46:08 INFO - 'SHLVL': '1', 04:46:08 INFO - 'TERM': 'linux', 04:46:08 INFO - 'TMOUT': '86400', 04:46:08 INFO - 'USER': 'cltbld', 04:46:08 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 04:46:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:08 INFO - '_': '/tools/buildbot/bin/python'} 04:46:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:46:08 INFO - Running setup.py (path:/tmp/pip-fUcg0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:46:08 INFO - Running setup.py (path:/tmp/pip-qxNA3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:46:08 INFO - Running setup.py (path:/tmp/pip-tPelcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:46:09 INFO - Running setup.py (path:/tmp/pip-n85VQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:46:09 INFO - Running setup.py (path:/tmp/pip-uQgJvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:46:09 INFO - Running setup.py (path:/tmp/pip-7YMZSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:46:09 INFO - Running setup.py (path:/tmp/pip-Ic0vDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:46:09 INFO - Running setup.py (path:/tmp/pip-PH06M3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:46:09 INFO - Running setup.py (path:/tmp/pip-qxGqWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:46:09 INFO - Running setup.py (path:/tmp/pip-rpCbFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:46:09 INFO - Running setup.py (path:/tmp/pip-BbhJrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:46:09 INFO - Running setup.py (path:/tmp/pip-jUp2Lz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:46:09 INFO - Running setup.py (path:/tmp/pip-ecV0hK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:46:09 INFO - Running setup.py (path:/tmp/pip-lO1V4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:09 INFO - Running setup.py (path:/tmp/pip-cKOSB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:46:09 INFO - Running setup.py (path:/tmp/pip-HpNCWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:46:09 INFO - Running setup.py (path:/tmp/pip-nlIqKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:46:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:46:10 INFO - Running setup.py install for manifestparser 04:46:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:46:10 INFO - Running setup.py install for mozcrash 04:46:10 INFO - Running setup.py install for mozdebug 04:46:10 INFO - Running setup.py install for mozdevice 04:46:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:46:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:46:10 INFO - Running setup.py install for mozfile 04:46:10 INFO - Running setup.py install for mozhttpd 04:46:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:46:10 INFO - Running setup.py install for mozinfo 04:46:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:46:10 INFO - Running setup.py install for mozInstall 04:46:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:46:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:46:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:46:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:46:10 INFO - Running setup.py install for mozleak 04:46:10 INFO - Running setup.py install for mozlog 04:46:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:46:11 INFO - Running setup.py install for moznetwork 04:46:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:46:11 INFO - Running setup.py install for mozprocess 04:46:11 INFO - Running setup.py install for mozprofile 04:46:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:46:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:46:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:46:11 INFO - Running setup.py install for mozrunner 04:46:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:46:11 INFO - Running setup.py install for mozscreenshot 04:46:11 INFO - Running setup.py install for moztest 04:46:11 INFO - Running setup.py install for mozversion 04:46:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:46:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:46:11 INFO - Cleaning up... 04:46:11 INFO - Return code: 0 04:46:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f93e4bbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c930>, '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 0x280d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:46:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:46:11 INFO - Using env: {'DISPLAY': ':0', 04:46:11 INFO - 'HOME': '/home/cltbld', 04:46:11 INFO - 'LANG': 'en_US.UTF-8', 04:46:11 INFO - 'LANGUAGE': 'en_US:en', 04:46:11 INFO - 'LOGNAME': 'cltbld', 04:46:11 INFO - 'MAIL': '/var/mail/cltbld', 04:46:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:11 INFO - 'MOZ_NO_REMOTE': '1', 04:46:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:11 INFO - 'NO_EM_RESTART': '1', 04:46:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:11 INFO - 'PWD': '/builds/slave/test', 04:46:11 INFO - 'SHELL': '/bin/bash', 04:46:11 INFO - 'SHLVL': '1', 04:46:11 INFO - 'TERM': 'linux', 04:46:11 INFO - 'TMOUT': '86400', 04:46:11 INFO - 'USER': 'cltbld', 04:46:11 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 04:46:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:11 INFO - '_': '/tools/buildbot/bin/python'} 04:46:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:46:12 INFO - Running setup.py (path:/tmp/pip-EMMH88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:46:12 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)) 04:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:46:12 INFO - Running setup.py (path:/tmp/pip-k6gSDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 04:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:46:12 INFO - Running setup.py (path:/tmp/pip-7qcdfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:46:12 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)) 04:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:46:12 INFO - Running setup.py (path:/tmp/pip-AXqwXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 04:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:46:12 INFO - Running setup.py (path:/tmp/pip-i_YSN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:46:12 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)) 04:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:46:12 INFO - Running setup.py (path:/tmp/pip-y73BWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:46:12 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)) 04:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:46:12 INFO - Running setup.py (path:/tmp/pip-iZBtM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:46:12 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)) 04:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:46:12 INFO - Running setup.py (path:/tmp/pip-MXjNJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:46:12 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)) 04:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:46:12 INFO - Running setup.py (path:/tmp/pip-rRzaI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:46:12 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)) 04:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:46:12 INFO - Running setup.py (path:/tmp/pip-lgbLXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:46:12 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)) 04:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:46:12 INFO - Running setup.py (path:/tmp/pip-y4KkZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:46:12 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)) 04:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:46:12 INFO - Running setup.py (path:/tmp/pip-_YPc2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 04:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:46:12 INFO - Running setup.py (path:/tmp/pip-0b09dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:46:13 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)) 04:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:46:13 INFO - Running setup.py (path:/tmp/pip-kEDzA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 04:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:13 INFO - Running setup.py (path:/tmp/pip-j4mE98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:13 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)) 04:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:46:13 INFO - Running setup.py (path:/tmp/pip-BC_YTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 04:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:46:13 INFO - Running setup.py (path:/tmp/pip-IzF8V_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:46:13 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)) 04:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:46:13 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)) 04:46:13 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:46:13 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:46:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:46:13 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 04:46:13 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 04:46:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:13 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 04:46:13 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 04:46:15 INFO - Downloading blessings-1.6.tar.gz 04:46:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:46:15 INFO - Installing collected packages: blessings 04:46:15 INFO - Running setup.py install for blessings 04:46:15 INFO - Successfully installed blessings 04:46:15 INFO - Cleaning up... 04:46:15 INFO - Return code: 0 04:46:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:46:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:46:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:46:15 INFO - Reading from file tmpfile_stdout 04:46:15 INFO - Current package versions: 04:46:15 INFO - argparse == 1.2.1 04:46:15 INFO - blessings == 1.6 04:46:15 INFO - blobuploader == 1.2.4 04:46:15 INFO - docopt == 0.6.1 04:46:15 INFO - functools32 == 3.2.3-2 04:46:15 INFO - jsonschema == 2.5.1 04:46:15 INFO - manifestparser == 1.1 04:46:15 INFO - mozInstall == 1.12 04:46:15 INFO - mozcrash == 0.17 04:46:15 INFO - mozdebug == 0.1 04:46:15 INFO - mozdevice == 0.48 04:46:15 INFO - mozfile == 1.2 04:46:15 INFO - mozhttpd == 0.7 04:46:15 INFO - mozinfo == 0.9 04:46:15 INFO - mozleak == 0.1 04:46:15 INFO - mozlog == 3.4 04:46:15 INFO - moznetwork == 0.27 04:46:15 INFO - mozprocess == 0.23 04:46:15 INFO - mozprofile == 0.28 04:46:15 INFO - mozrunner == 6.12 04:46:15 INFO - mozscreenshot == 0.1 04:46:15 INFO - mozsystemmonitor == 0.3 04:46:15 INFO - moztest == 0.7 04:46:15 INFO - mozversion == 1.4 04:46:15 INFO - psutil == 3.1.1 04:46:15 INFO - requests == 1.2.3 04:46:15 INFO - wsgiref == 0.1.2 04:46:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:15 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f93e4bbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c930>, '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 0x280d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46:15 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 04:46:15 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:46:15 INFO - Using env: {'DISPLAY': ':0', 04:46:15 INFO - 'HOME': '/home/cltbld', 04:46:15 INFO - 'LANG': 'en_US.UTF-8', 04:46:15 INFO - 'LANGUAGE': 'en_US:en', 04:46:15 INFO - 'LOGNAME': 'cltbld', 04:46:15 INFO - 'MAIL': '/var/mail/cltbld', 04:46:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:15 INFO - 'MOZ_NO_REMOTE': '1', 04:46:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:15 INFO - 'NO_EM_RESTART': '1', 04:46:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:15 INFO - 'PWD': '/builds/slave/test', 04:46:15 INFO - 'SHELL': '/bin/bash', 04:46:15 INFO - 'SHLVL': '1', 04:46:15 INFO - 'TERM': 'linux', 04:46:15 INFO - 'TMOUT': '86400', 04:46:15 INFO - 'USER': 'cltbld', 04:46:15 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 04:46:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:15 INFO - '_': '/tools/buildbot/bin/python'} 04:46:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:15 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)) 04:46:15 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)) 04:46:15 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)) 04:46:15 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)) 04:46:15 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)) 04:46:15 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)) 04:46:15 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)) 04:46:15 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)) 04:46:15 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)) 04:46:15 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)) 04:46:15 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)) 04:46:15 INFO - Cleaning up... 04:46:15 INFO - Return code: 0 04:46:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:46:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f93e4bbff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c930>, '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 0x280d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280dcc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:46:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:46:15 INFO - Using env: {'DISPLAY': ':0', 04:46:15 INFO - 'HOME': '/home/cltbld', 04:46:15 INFO - 'LANG': 'en_US.UTF-8', 04:46:15 INFO - 'LANGUAGE': 'en_US:en', 04:46:15 INFO - 'LOGNAME': 'cltbld', 04:46:15 INFO - 'MAIL': '/var/mail/cltbld', 04:46:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:15 INFO - 'MOZ_NO_REMOTE': '1', 04:46:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:15 INFO - 'NO_EM_RESTART': '1', 04:46:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:15 INFO - 'PWD': '/builds/slave/test', 04:46:15 INFO - 'SHELL': '/bin/bash', 04:46:15 INFO - 'SHLVL': '1', 04:46:15 INFO - 'TERM': 'linux', 04:46:15 INFO - 'TMOUT': '86400', 04:46:15 INFO - 'USER': 'cltbld', 04:46:15 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 04:46:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:15 INFO - '_': '/tools/buildbot/bin/python'} 04:46:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:46:16 INFO - Cleaning up... 04:46:16 INFO - Return code: 0 04:46:16 INFO - Running post-action listener: _resource_record_post_action 04:46:16 INFO - Running post-action listener: _start_resource_monitoring 04:46:16 INFO - Starting resource monitoring. 04:46:16 INFO - [mozharness: 2016-09-19 11:46:16.128261Z] Finished create-virtualenv step (success) 04:46:16 INFO - [mozharness: 2016-09-19 11:46:16.128774Z] Running install step. 04:46:16 INFO - Running pre-action listener: _resource_record_pre_action 04:46:16 INFO - Running main action method: install 04:46:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:46:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:46:16 INFO - Reading from file tmpfile_stdout 04:46:16 INFO - Detecting whether we're running mozinstall >=1.0... 04:46:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:46:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:46:16 INFO - Reading from file tmpfile_stdout 04:46:16 INFO - Output received: 04:46:16 INFO - Usage: mozinstall [options] installer 04:46:16 INFO - Options: 04:46:16 INFO - -h, --help show this help message and exit 04:46:16 INFO - -d DEST, --destination=DEST 04:46:16 INFO - Directory to install application into. [default: 04:46:16 INFO - "/builds/slave/test"] 04:46:16 INFO - --app=APP Application being installed. [default: firefox] 04:46:16 INFO - mkdir: /builds/slave/test/build/application 04:46:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:46:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:46:33 INFO - Reading from file tmpfile_stdout 04:46:33 INFO - Output received: 04:46:33 INFO - /builds/slave/test/build/application/firefox/firefox 04:46:33 INFO - Running post-action listener: _resource_record_post_action 04:46:33 INFO - [mozharness: 2016-09-19 11:46:33.381641Z] Finished install step (success) 04:46:33 INFO - [mozharness: 2016-09-19 11:46:33.381889Z] Running run-tests step. 04:46:33 INFO - Running pre-action listener: _resource_record_pre_action 04:46:33 INFO - Running main action method: run_tests 04:46:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:46:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:46:33 INFO - Python 2.7.3 04:46:33 INFO - Return code: 0 04:46:33 INFO - grabbing minidump binary from tooltool 04:46:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 04:46:33 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/linux64/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 0x280c930>, '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 0x280d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280dcc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:46:33 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:46:33 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/linux64/releng.manifest -o -c /builds/tooltool_cache 04:46:33 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:46:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:46:33 INFO - Return code: 0 04:46:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:46:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:46:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:46:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:46:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:46:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:46:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:46:33 INFO - Using env: {'DISPLAY': ':0', 04:46:33 INFO - 'HOME': '/home/cltbld', 04:46:33 INFO - 'LANG': 'en_US.UTF-8', 04:46:33 INFO - 'LANGUAGE': 'en_US:en', 04:46:33 INFO - 'LOGNAME': 'cltbld', 04:46:33 INFO - 'MAIL': '/var/mail/cltbld', 04:46:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:46:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:46:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:33 INFO - 'MOZ_NO_REMOTE': '1', 04:46:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:46:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:33 INFO - 'NO_EM_RESTART': '1', 04:46:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:33 INFO - 'PWD': '/builds/slave/test', 04:46:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:46:33 INFO - 'SHELL': '/bin/bash', 04:46:33 INFO - 'SHLVL': '1', 04:46:33 INFO - 'TERM': 'linux', 04:46:33 INFO - 'TMOUT': '86400', 04:46:33 INFO - 'USER': 'cltbld', 04:46:33 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339', 04:46:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:33 INFO - '_': '/tools/buildbot/bin/python'} 04:46:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:46:33 INFO - mozversion application_buildid: 20160919030422 04:46:33 INFO - mozversion application_changeset: f0f15b7c6aa77a0c5750918aa0a1cb3dc82185bc 04:46:33 INFO - mozversion application_display_name: Nightly 04:46:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:46:33 INFO - mozversion application_name: Firefox 04:46:33 INFO - mozversion application_remotingname: firefox 04:46:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:46:33 INFO - mozversion application_vendor: Mozilla 04:46:33 INFO - mozversion application_version: 51.0a1 04:46:33 INFO - mozversion platform_buildid: 20160919030422 04:46:33 INFO - mozversion platform_changeset: f0f15b7c6aa77a0c5750918aa0a1cb3dc82185bc 04:46:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:46:33 INFO - mozversion platform_version: 51.0a1 04:46:33 INFO - using testdate: 1474285593 04:46:33 INFO - actual date: 1474285593 04:46:33 INFO - starting webserver on 'localhost:52624' 04:46:33 INFO - SUITE-START | Running 2 tests 04:46:33 INFO - TEST-START | dromaeo_css 04:46:33 INFO - operating with platform_type : linux_ 04:46:33 INFO - Initialising browser for dromaeo_css test... 04:46:33 INFO - TEST-INFO | started process 10872 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3F2EIU/profile http://localhost:52624/getInfo.html) 04:46:38 INFO - PROCESS | 10872 | __metrics Screen width/height:1600/1200 04:46:38 INFO - PROCESS | 10872 | colorDepth:24 04:46:38 INFO - PROCESS | 10872 | Browser inner width/height: 1024/697 04:46:38 INFO - PROCESS | 10872 | __metrics 04:46:38 INFO - PROCESS | 10872 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:46:38 INFO - PROCESS | 10872 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:46:39 INFO - PROCESS | 10872 | console.error: PushService: 04:46:39 INFO - PROCESS | 10872 | stateChangeProcessEnqueue: Error transitioning state 04:46:39 INFO - PROCESS | 10872 | UnknownError 04:46:39 INFO - TEST-INFO | 10872: exit 0 04:46:39 INFO - Browser initialized. 04:46:39 INFO - Running cycle 1/1 for dromaeo_css test... 04:46:39 INFO - TEST-INFO | started process 10957 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3F2EIU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:46:51 INFO - PROCESS | 10957 | RSS: Main: 172507136 04:46:51 INFO - PROCESS | 10957 | 04:47:00 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:47:01 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 2.01672 seconds. 04:47:26 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.05001 seconds. 04:47:43 INFO - PROCESS | 10957 | 1474285663012 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:47:51 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:47:53 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:48:22 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 04:48:39 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 04:48:45 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 04:48:50 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:48:57 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 04:49:00 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:49:07 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:49:14 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 04:49:19 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 04:49:24 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:49:30 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:49:47 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 04:49:48 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01663 seconds. 04:49:52 INFO - PROCESS | 10957 | Cycle 1(1): loaded http://localhost:52624/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52624/tests/dromaeo/cssquery-ext.html) 04:49:53 INFO - PROCESS | 10957 | RSS: Main: 315109376 04:49:53 INFO - PROCESS | 10957 | 04:50:01 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 04:50:28 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:51:05 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:51:09 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:51:12 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:51:19 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:51:36 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:51:51 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:51:56 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:51:57 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:52:03 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 04:52:09 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:52:19 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:52:21 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:52:30 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:52:33 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:52:44 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:52:54 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 04:52:56 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:52:56 INFO - PROCESS | 10957 | Cycle 1(1): loaded http://localhost:52624/tests/dromaeo/cssquery-ext.html (next: http://localhost:52624/tests/dromaeo/cssquery-jquery.html) 04:52:56 INFO - PROCESS | 10957 | RSS: Main: 274116608 04:52:56 INFO - PROCESS | 10957 | 04:53:03 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 04:53:07 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 04:53:23 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:54:00 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:54:03 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:54:12 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:54:14 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:54:22 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:54:30 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:54:32 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:54:38 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:54:48 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:55:06 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:55:28 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:55:34 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:55:40 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:55:54 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 04:55:57 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:55:59 INFO - PROCESS | 10957 | Cycle 1(1): loaded http://localhost:52624/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52624/tests/dromaeo/cssquery-mootools.html) 04:55:59 INFO - PROCESS | 10957 | RSS: Main: 247693312 04:55:59 INFO - PROCESS | 10957 | 04:56:03 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:56:04 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:56:06 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 04:56:14 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:56:20 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:56:22 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:56:24 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:56:30 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:56:34 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:56:38 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:56:45 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:56:46 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:56:48 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:56:54 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:56:56 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:57:08 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:57:18 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:57:32 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:57:55 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:58:19 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:58:25 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:58:27 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:58:32 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:58:40 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:58:43 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:58:45 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:58:53 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:59:01 INFO - PROCESS | 10957 | Cycle 1(1): loaded http://localhost:52624/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52624/tests/dromaeo/cssquery-prototype.html) 04:59:02 INFO - PROCESS | 10957 | RSS: Main: 248008704 04:59:02 INFO - PROCESS | 10957 | 04:59:09 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:59:10 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:59:17 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:59:33 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:59:41 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:59:48 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:59:51 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:59:53 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 05:00:21 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:00:23 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:00:36 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:00:46 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:00:52 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:00:56 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:01:14 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:01:41 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 05:01:42 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:01:52 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:02:04 INFO - PROCESS | 10957 | Cycle 1(1): loaded http://localhost:52624/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52624/tests/dromaeo/cssquery-yui.html) 05:02:05 INFO - PROCESS | 10957 | RSS: Main: 216756224 05:02:05 INFO - PROCESS | 10957 | 05:02:10 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 05:02:32 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:02:41 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 05:02:48 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:03:18 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:03:29 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:03:41 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:03:55 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:03:59 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:04:17 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:04:31 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:04:37 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 05:04:45 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:04:49 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:05:00 INFO - PROCESS | 10957 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:05:08 INFO - PROCESS | 10957 | Cycle 1(1): loaded http://localhost:52624/tests/dromaeo/cssquery-yui.html (next: http://localhost:52624/tests/dromaeo/cssquery-dojo.html) 05:05:08 INFO - PROCESS | 10957 | RSS: Main: 307052544 05:05:08 INFO - PROCESS | 10957 | 05:05:08 INFO - PROCESS | 10957 | __start_tp_report 05:05:08 INFO - PROCESS | 10957 | _x_x_mozilla_page_load 05:05:08 INFO - PROCESS | 10957 | _x_x_mozilla_page_load_details 05:05:08 INFO - PROCESS | 10957 | |i|pagename|runs| 05:05:08 INFO - PROCESS | 10957 | |0;dojo.html;2969.62879640045;3014.8944786932457;3015.185899807052;3048.344605909732;3066.677998810125;5156.819511317105;5222.033923724104;5279.208118782185;5310.176922576997;5353.866153346173;5211.895762084756;5270.789168433268;5293.126634105368;5350.143976963686;5376.086065368887;4725.763711814413;4796.928046079312;4813.7833797479025;4829.879253018667;4952.158133117361;4916.680415772981;4920.360353154085;4952.628552858532;4967.329410529586;4998.900021999558;4775.044991001796;4806.663533552653;4820.349252850855;4846.418429788413;4917.459079501252;4890.8043678252825;4960.404751429815;4977.054359671687;4985.1774457214515;4985.576226020757;4864.318995340655;4866.537678920497;4867.196912509432;4876.634252431082;4884.340614017134;5189.117849965515;5361.329833770778;5371.865703357408;5373.247745315659;5374.408815030343;5570.944290557083;5644.604877658566;5698.059820129673;5699.344575373802;5763.769449222068;5719.542436605062;5725.341585717654;5729.427057294279;5730.283714535683;5739.081746920473;5320.095583750772;5323.973380133113;5335.466453354711;5350.38470575881;5376.4085950545405;5594.916076258859;5609.971950140223;5668.461496157858;5740.6268592541355;5784.566157538202;5526.944730552724;5625.831225063254;5627.184058311604;5635.042042852725;5645.322561292589;5641.576881733887;5648.858778530487;5650.576206784508;5650.689212093374;5654.491095801315;5372.382176049726;5611.354694210218;5616.466435688603;5619.466150715675;5621.353544342453;5467.972660136754;5528.668279903219;5572.832815015556;5574.665520068809;5653.689047102366;4339.956600433955;4348.152110338454;4349.238883195429;4350.412694286231;4403.713758605681;3859.9232564579715;3864.30442520349;3871.2257548490416;3912.921741565153;3923.372260438316;5236.92144617831;5291.232771248147;5331.813386531453;5399.83800485986;5401.297831281908;5668.914966275509;5690.601657883991;5702.429757024264;5705.115707065412;5761.971190144106;5341.092014357569;5369.3556773187465;5378.2470454136455;5382.219578161144;5391.137418013099;4727.2436410174205;4823.758812059453;4880.585150262267;4916.950830491721;4954.430240522386;5894.145348924382;5900.203472531153;5907.8227653169615;5953.583249172603;5981.162637230791;5684.9431505684415;5714.485696287354;5720.3421606515785;5725.742341594555;5733.483986441241;5700.458456446631;5759.222504961776;5836.883262334814;5870.677112758839;5882.411758824083;5630.352509461464;5635.746391412315;5649.350324712711;5708.771649133988;5714.342850572238;5683.65898046102;5744.569157313134;5746.741396637245;5755.107958266475;5756.884862302647;5416.458354164552;5446.645968012067;5537.446255374431;5598.160275958476;5605.775768969195;5431.619786614899;5486.369067557282;5500.614956953135;5504.752286147213;5564.4991950724625;5489.670619762827;5495.175723641486;5496.203050557727;5501.284832971848;5505.587080969023;5435.130379139318;5496.890062198654;5525.889482210253;5599.244102046172;5599.300087489064;5354.303940487712;5361.678299302054;5364;5412.458754124556;5437.265969094122 05:05:08 INFO - PROCESS | 10957 | |1;ext.html;545.845644259556;595.6932179927338;601.9006863867462;604.6462819250738;612.6599737145883;32593;33422.33155336892;43755.99999999996;44723.77638111813;45210.09579808402;46167.30749038767;46723;46799.29801052978;46801;46846;9115.680951166727;9257.21313688334;9265.119813617695;9270.119338662817;9272.07279272074;9066.818663626724;9103.317251206154;9155.54222288883;9165.083491650848;9168.404053736485;9171.54142292886;9217.354785165042;9218.078192180796;9221.815563688722;9223.769405764842;9143.222826059793;9171.954140229323;9182.908170918272;9191.632334706605;9206.171444569954;9085;9149.862752058725;9157.908420915772;9158.954205228998;9164.95417522908;43756.56243437557;44066;44069;44093.33859992103;44108.558914411085;3380.7802492837886;3381.644927282649;3425.1265927188656;3484.1289677580603;3517.3668739626855;32658;33193.336133277444;33666.83166584176;36512.26975460476;36898.07754806124;4238.300680387732;4299.591538803808;4417.425734654506;4427.468703755538;4445.599896009375;365620;369257.1537142324;369336;369620;370753;62489;63197.68401158057;63555.8688685234;63572.68213658902;63668.68165659142;68242.65878670575;68588;68650.65674671595;68665.65667171632;68692.07003089666;15114.319855606524;15423.922880385526;15441.845581544041;15529.301181446863;15873.444429445144;11441.713957151138;11472.94263528677;11488.310701357945;11496.367699776425;11574.18980671345;19883.801161988486;20017.699734503993;20019.595688948408;20057.29799457012;20152.294669686784;6452.773793041487;7433.368163706145;7461.552306861606;7536.171021187665;7674.582506510431;915.7573243090586;969.9369540979814;986.6102889358663;1026.3892983674702;1064.5582083435288;32536.349273014406;32724.509132363033;32826;32901.02797035578;33184.1703957403;1060.926502855393;1087.8259478483405;1126.8591426071741;1135.7217481717032;1170.7658468306372;16262.51212463628;17209.1395430228;17268.481945541655;17327.65344693099;17345.13274336278;597154;600937;602015;602371;605109;58528.41471585315;58839.70580147072;58995.11507327393;59129.40870591325;59193;1229.1273196030918;1284.2037936479442;1315.0203098691536;1334.3928512526777;1350.1359130156707;29626.259343516584;33670.831645841616;33728.49407258844;33804.154896127795;33841.49237761386;51719.34525892688;52303.21545176749;52317.7384113077;52325;52350.21474677959;405187;405476;405914;406342;407793;29001;30043.699563004964;30099.398012039197;31498;43922.560774391844;47484.28773568468;47747.28379074386;47932;48189.75905120452;48851.755741222485;39636.60363396444;40536.1892762149;41224.38163427491;41432.3785143217;42760.35859462173;41437.79281103696;41581.79209104056;42495.78752106219;43033.784831075645;43813.78093109514;40880.38679419752;41654.37518437165;42003.57996419997;42061.1587768249;42119;34166.658333416344;36412.635873641986;36903.44644830276;37181;37662.62337376591;27997.720022799513;28159;28182.577261341507;28199.295017623903;28235.57646635261 05:05:08 INFO - PROCESS | 10957 | |2;jquery.html;372.80427775417917;382.90810205550656;392.5250446959179;394.9052227465408;419.75024860208185;13959.930200348997;15741.291641876114;16999.74500382495;19699.212031518757;20159.39521814348;17894.731579026324;18100.185491652875;18591.628167436644;18758.718619220723;18877.52806179846;5600.327960644719;5786.91507023629;5851.210086638313;5863;5866.384029676887;5599.132134519135;5767.913481297784;5800.8259752207505;5802.506786923117;5803.709814509279;5517.917231241535;5767.9423205768035;5774.538036957033;5787.739551720183;5794.507466865301;5683.2895888014;5712.171735098408;5746.82759517211;5750.05124154514;5753.280839895013;4980.850574482735;5145.485451454862;5153.587712982952;5157.8452646420665;5158.4067832199025;23063.423414414607;23142.19002334927;23202.76797232023;23234.65148022781;23540;2288.965665515002;2390.617501199817;2529.9494010119693;2733.961094783989;2773.6671599408;14330.707086866156;14682.926585367004;14698.485553005594;14784.556463306119;15076.849231507655;1898.2312163573774;1924.422673198035;1944.0279860069966;1983.6627773278578;1995.0623207092642;78202.60898695527;84446.57776711139;85541.57229213876;86037.13962860355;90208;10951.288166269167;11167.38579378142;11213.3832639204;11372.701916444139;11476.770464590662;15894.046357218604;15935.601609959613;15943.920280398755;15944.362225511082;15954.441594544136;7889;7935.444518883623;8035.55804430762;8053.234942680436;8297.128801475757;7404.777856664308;7510.324070833652;7789.6631010696565;7790.104138024087;7806.174974262571;4700.271457924027;4782.784774685148;4863.076015557032;4917.655764096479;4981.775820088104;1831.4505648305455;1871.8006342547453;1906.6663333916508;1910.3695780392436;1932.6327997680396;854.2952064546773;889.7553172877381;948.8339540580371;962.932594718377;1027.8920713324994;18678.34625788091;19454.319098831475;19531.90234048849;19720.802791971895;19851.928718245472;926.2682480840061;929.9814003719888;968.2641192693603;982.2436723722836;986.0386123529502;9516.762080948032;10485;10826.512806923707;10859.076978456762;10862.674119776419;429509.1309933826;429627;430677;433296;440353;90308.54845725861;91075;91810;92398;92705;940.3464592108419;973.1290495007071;994.5231331472758;996.1372233922611;996.7607774134301;16508.75236871472;16612.916935415247;16971.830281697024;17462.21420036076;17812.55468613295;41069.58930410658;42520.14959700851;42521.78739106285;42680;42877.57122428861;56580.434195657515;57736.13395799151;57880.4211957875;58738.7063064699;59212;8207.425480216567;8425.95787021061;8457.746267611981;8589.570521474026;8960.1935825776;10321.48392580383;12620.747585048432;12762.17045892014;12770.553030644254;12805.8079128815;10992.835107473555;11048.613298534512;11130.443477825915;11212.719682007688;11554.075673945896;10447.791044178923;10463.162946964378;10603.734906627396;11000.724981875197;11037.944810275898;11078.446077696244;11120.443977800916;11863.881361186623;12139.514419423478;12160.635180944586;7663.846723065619;7964.482308649919;8025.357971362394;8444.451110677786;8591.226789588967;8751.737447876574;10677.14582833356;11052.392118433563;11127;11533.077353811843 05:05:08 INFO - PROCESS | 10957 | |3;mootools.html;1327.2766342343423;1488.888333374997;1516.332813562033;1642.1378776142524;1799.9010054447008;7666.195049519797;8341.165883411655;8702.825943481126;8726.47641141532;8937.50843703596;8715.95642021789;8808.823823523526;8810.471371717702;8814.118588141182;8818.559072046404;2404.89177986991;2431.8540887546806;2433.5741245282006;2435.5494233566847;2436.2812970173754;2342.367560758594;2350.8471949323325;2400.063975049732;2409.734929157791;2416.9395765105837;2376.6197408414655;2377.5363804058215;2386.9641955370685;2393.74865639108;2412.8069754419694;2303.4817166137486;2307.976920230801;2320.1995311617466;2320.9651855222187;2355.658429527709;2310.202979971924;2336.19401306549;2336.299110266913;2341.8126549876138;2342.133410638075;8503.532305723184;8608.569571521399;8739.21347078766;8770.912290877073;8780.956095219546;5111.361079865017;5245.580353571705;5262.342207224097;5281.471852814688;5297.708626025567;3227.3706627207703;3317.4857897026;3379.8310084495924;3391.576052993376;3436.9484457733156;1091.8635170603675;1132.116948779953;1134.5348407153028;1160.8548931383577;1162.3839365136491;12230.816537751942;12240.632781016584;12314.692132696755;12316.076294277875;12330.383480825923;4406.427164468599;4542.31865220213;4691.085238378483;4701.200795864711;4704.811807527729;9665.323427360157;9687.467189304656;9688.820015496456;9695.321327507148;9697.854532182022;5260.342457192851;5267.762950667229;5282.049231138432;5292.364916210079;5298.364196296392;4347.043650396908;4478.731276123443;4750.809967601257;4869.074875773592;4952.90094198114;3673.8897833065053;3723.3856413691437;3755.9248815024093;3836.175222327213;3836.462895194675;1102.2559772153763;1119.0320372877402;1140.2303445174473;1142.731458107344;1145.0839328537138;908.8318661047595;929.9023602521773;940.0646356874953;943.3302355327656;961.8316794560924;5636.670292711679;5657.264555607745;5933.910991335133;6028.125921741323;6131.908021379683;463.77275135183515;465.4159030416873;470.32273526122276;476.3925994357222;483.19789150010814;3829.5021647185686;4000;4022.1955608878343;4058.1680755445063;4112.835486580563;10598;10774.24580279373;10946.562137514411;11020.614278500214;11028.28316159447;10721.142308615292;10843.240973131806;10970.890291096986;10972.945135274273;10974.451277436096;8156.714514991947;8185.877211841827;8235.50586964784;8247.587620619064;8292.958535207285;5542.168674698827;5615.915761263503;5628.380878103323;5679.062954612443;5827.766889324549;9959.502024898871;10085.69742907714;10108.747281318027;10130.8480372793;10247.282690211614;10063.949680251551;10290.228232882713;10440.895591043993;10543.89456105429;10609.469526523797;6706.93293067063;6896.827579310357;7233.529820561646;7504.924950750423;7572.810679733051;8397.496150230761;8775.692850750193;8780.824383512167;8789.560521973748;8789.780255493664;7699.422543309162;7778.261065198797;7823.921760782319;8026.35789136856;8031.839363212586;7473.364763994904;7608.657610407656;7615.847683046197;7650.808729781577;7651.196624354585;7166.605836679033;7266;7284.125904891447;7284.49008569395;7287.526310789782;4935.605151587936;5024.221245706921;5032.194848824171;5165.612579056661;5177.4822517747925;5145.07388670043;5163.251328557413;5192.273081768631;5229.01297506945;5236.659617124875 05:05:08 INFO - PROCESS | 10957 | |4;prototype.html;329.98515066822006;331.52591793734956;341.936741702785;342.1105126670657;344.05213636431625;5015;5133.589312854972;5188.48115188481;5269.394019687742;5278.36659600848;4983.800647974077;5086.720230387336;5128.128218202905;5151.562117220032;5175.844724658266;4116.938245926313;4128.772917489537;4129.091599848036;4140.15126898985;4167.270727622669;3774.169682669808;3863.4784304118866;4141.917161656765;4159.00367823445;4171.874843754692;4088.795560221977;4095.5494895561615;4102.528209255929;4102.646126778156;4110.712250142491;4101.138760860223;4109.684900831705;4111.602055301182;4117.794110294488;4125.608067233608;3686.262747450494;3696.6137698363113;3703.962960370389;3705.6850167735774;3710.7031437485207;4875.463698993108;4972.279019542182;4996.350474438336;5004.574611158056;5013.6490445668815;4669.836555720567;4670.859874203746;4672.322513235562;4687.367205427257;4701.976490117528;5311.681299122065;5322.77576157486;5346.839594812123;5364.1149210380245;5409.891802163935;3696.482492451059;3899.2981263372867;3908.882733517999;3914.628110329514;3930.4081846852073;5594.832155035356;5629.127485239795;5650.915236271459;5659.132486279518;5690.374058853523;3992.2215168041953;4017.979910100431;4033.4554835097474;4070.5115386153852;4078.775667338325;3964.960350396459;4040.696947728932;4061.5329237138108;4072.124493233969;4129.483814523184;4611.492735799059;4613.907721845544;4615.14619795339;4618.445786505641;4634.374359461497;5383.138697808332;5390.838274851761;5394.406615272317;5397.568194544427;5434.538064264502;1357.7915655066997;1385.8129152564477;1394.5607133752835;1407.0853944935873;1412.4773833681604;868.2489646455897;888.4314039015036;901.7430032440723;902.3773596218588;905.8233644439402;4654.488006319302;4659.670164917541;4674.555917187861;4693.850006748368;4700.929486057712;4876.073546026315;4903.877403064952;4909.582685471703;4922;4932.928170168538;4619.514950930104;4620.53794620542;4656.4645065817185;4668.836590719308;4688.929666055012;2716.891324347004;2927.956080658792;2935.955960660549;2960.2913334299305;2960.585518027478;5660.330202590246;5673.546116310686;5709.914351284734;5716.485516303553;5729.28383952006;5462.262594549765;5645.068563686946;5655.915161272585;5660.179274005246;5719.571032172604;5694.174344720075;5694.430556944355;5695.515881150149;5700.600957932905;5712.971435142797;5544.805931792368;5545.390007099135;5545.7781688732;5551.361593416808;5595.524380427546;5228.555572776357;5432.701201433959;5441.0684207105905;5468.808591699271;5475.260839786578;5682.460166284196;5689.687067211177;5699.805574853628;5706.771729130788;5722.423485321674;3809.1619843634357;4000.9799951001223;4014.598540145962;4025.2955732747237;4033.6773058155864;5160.380754309507;5528.640638358494;5529.640573362719;5536.7785288588;5542;5376.73116344189;5385.892282154413;5392.735051278551;5393.298871146726;5396.730163491731;5228.869278268074;5325.600579956596;5381.246625472359;5410.8647283816645;5413.215083812904;5287.603429742708;5329.973350133379;5357.464253574767;5363.436839131991;5364.785408583769;5403.540699040468;5419.187121931584;5422.15956526739;5439.3200849893765;5440.156775699773;5066.721330326867;5215.2698622192165;5241.689577605599;5289.68261904287;5312.910853274992 05:05:08 INFO - PROCESS | 10957 | |5;yui.html;721.8159369360809;735.805011671907;761.8209720715631;812.9268365847073;827.1728271728272;14369.640758981017;15228.23858807058;15249.39002439904;15251.237438128092;15331.693366132671;13988.860111398883;14004.64988375291;14042.157470551774;14046.08700434471;14050.64873378166;652.438902543812;670.2058061197472;787.3386355461412;791.0783936713736;805.9758207253763;793.9245771651713;806.4878801960767;807.8990126234221;808.2564041082219;808.6765293882436;870.8040690844573;872.402404353017;873.2795443758893;873.4628203654738;873.5588527793487;692.5463821197098;710.2861624067779;818.6848063495597;864.2178828160525;867.7353407210799;620.2494981072907;625.7309356976543;625.8748250349948;626.2516293029831;626.5551458464496;14554;14575.489857855031;14592.489262875748;14592.92703536486;14601.415943362359;14309.084198661434;14333;14410.92794536031;14565.854341456661;14932.925335373253;13208.207507549578;13310.134841235289;13455.259960702157;13809.447622095104;14176.645583860485;4577.771111444447;4598.103369842882;4620.145273124449;4809;4824.662273640846;79591;79893.20106798974;80226;84173.57913210517;84955;11552.537898484135;11624.709382265342;11729.706757330965;11796.52813887435;11880.524779008916;22367.10531578751;22413;22441.32676019722;22519.211827585958;22567.887160564092;7816.452848300678;7847.332976696931;7980.840383192303;8117.228863258099;8274.627641756135;4557.270836666117;4637.095766325601;4727.267273572602;4775.570198682135;4893.73084480357;3419.8461069251944;3523.383407903658;3524.418470952264;3526.84129214186;3776.9433458498142;643.8486955565438;648.2836465705421;649.1139594453537;654.5287393076977;659.5877576514679;660.5970358081566;721.7798571435762;757.556829254892;760.323312252085;767.4129291092319;8992.280617550581;9405.294602904809;9677.806443871224;9755.707328780149;9859.457729824928;648.9351064893473;672.825065482978;689.5793565924782;700.7232143303366;729.8176953335525;4558.521355257716;4725.456572494207;4874.4150701916;4880.170371036932;4915;123051.3847430775;128252;128985.35507322592;130009.34995324964;132507;96708.51645741727;111790.44104779424;114458;115224;117619;78576.6071169652;80668;97273;100972.49513752385;102196;9348.672796452092;9406.153446189875;9455.479948602619;9523;9572.808543828945;49390.25914611355;51252.48747512477;52272.47727522676;52867.47132528779;53631.731841340545;73089.63455182903;74602.62698686472;77154;78480.60759696165;81330;12854.228746275254;13247.337633118113;13267.8523496607;13405.66485837862;13971.860281397056;14963.476278330207;15410.383584656489;15437.305321260796;15744.92127539355;16365.754513682543;13499.86500134986;13763.655908602364;13768.793468098187;13930.58208253754;13966.30168491551;12078.39608019578;12517.186382885084;12566.120371574269;12587.370631468208;12741.936290318488;13071.856495476017;13526.526571569948;13722.65693357674;13776;14254.714905702036;9922;10150.796984060426;10196.039307627017;10532.684019479428;10547.894521054692;11284.266522676;11738.185089845663;11744.29534227949;11956.820647690118;11970.521179152736 05:05:08 INFO - PROCESS | 10957 | __end_tp_report 05:05:08 INFO - PROCESS | 10957 | __start_cc_report 05:05:08 INFO - PROCESS | 10957 | _x_x_mozilla_cycle_collect,1036 05:05:08 INFO - PROCESS | 10957 | __end_cc_report 05:05:08 INFO - PROCESS | 10957 | __startTimestamp1474286708789__endTimestamp 05:05:08 INFO - PROCESS | 10957 | 05:05:08 INFO - PROCESS | 10957 | ------- Summary: start ------- 05:05:08 INFO - PROCESS | 10957 | Number of tests: 6 05:05:08 INFO - PROCESS | 10957 | 05:05:08 INFO - PROCESS | 10957 | [#0] dojo.html Cycles:165 Average:5272.20 Median:5435.13 stddev:592.90 (10.9%) stddev-sans-first:566.54 05:05:08 INFO - PROCESS | 10957 | Values: 2969.6 3014.9 3015.2 3048.3 3066.7 5156.8 5222.0 5279.2 5310.2 5353.9 5211.9 5270.8 5293.1 5350.1 5376.1 4725.8 4796.9 4813.8 4829.9 4952.2 4916.7 4920.4 4952.6 4967.3 4998.9 4775.0 4806.7 4820.3 4846.4 4917.5 4890.8 4960.4 4977.1 4985.2 4985.6 4864.3 4866.5 4867.2 4876.6 4884.3 5189.1 5361.3 5371.9 5373.2 5374.4 5570.9 5644.6 5698.1 5699.3 5763.8 5719.5 5725.3 5729.4 5730.3 5739.1 5320.1 5324.0 5335.5 5350.4 5376.4 5594.9 5610.0 5668.5 5740.6 5784.6 5526.9 5625.8 5627.2 5635.0 5645.3 5641.6 5648.9 5650.6 5650.7 5654.5 5372.4 5611.4 5616.5 5619.5 5621.4 5468.0 5528.7 5572.8 5574.7 5653.7 4340.0 4348.2 4349.2 4350.4 4403.7 3859.9 3864.3 3871.2 3912.9 3923.4 5236.9 5291.2 5331.8 5399.8 5401.3 5668.9 5690.6 5702.4 5705.1 5762.0 5341.1 5369.4 5378.2 5382.2 5391.1 4727.2 4823.8 4880.6 4917.0 4954.4 5894.1 5900.2 5907.8 5953.6 5981.2 5684.9 5714.5 5720.3 5725.7 5733.5 5700.5 5759.2 5836.9 5870.7 5882.4 5630.4 5635.7 5649.4 5708.8 5714.3 5683.7 5744.6 5746.7 5755.1 5756.9 5416.5 5446.6 5537.4 5598.2 5605.8 5431.6 5486.4 5500.6 5504.8 5564.5 5489.7 5495.2 5496.2 5501.3 5505.6 5435.1 5496.9 5525.9 5599.2 5599.3 5354.3 5361.7 5364.0 5412.5 5437.3 05:05:08 INFO - PROCESS | 10957 | 05:05:08 INFO - PROCESS | 10957 | [#1] ext.html Cycles:180 Average:62515.87 Median:42628.07 stddev:124909.37 (293.0%) stddev-sans-first:125173.12 05:05:08 INFO - PROCESS | 10957 | Values: 545.8 595.7 601.9 604.6 612.7 32593.0 33422.3 43756.0 44723.8 45210.1 46167.3 46723.0 46799.3 46801.0 46846.0 9115.7 9257.2 9265.1 9270.1 9272.1 9066.8 9103.3 9155.5 9165.1 9168.4 9171.5 9217.4 9218.1 9221.8 9223.8 9143.2 9172.0 9182.9 9191.6 9206.2 9085.0 9149.9 9157.9 9159.0 9165.0 43756.6 44066.0 44069.0 44093.3 44108.6 3380.8 3381.6 3425.1 3484.1 3517.4 32658.0 33193.3 33666.8 36512.3 36898.1 4238.3 4299.6 4417.4 4427.5 4445.6 365620.0 369257.2 369336.0 369620.0 370753.0 62489.0 63197.7 63555.9 63572.7 63668.7 68242.7 68588.0 68650.7 68665.7 68692.1 15114.3 15423.9 15441.8 15529.3 15873.4 11441.7 11472.9 11488.3 11496.4 11574.2 19883.8 20017.7 20019.6 20057.3 20152.3 6452.8 7433.4 7461.6 7536.2 7674.6 915.8 969.9 986.6 1026.4 1064.6 32536.3 32724.5 32826.0 32901.0 33184.2 1060.9 1087.8 1126.9 1135.7 1170.8 16262.5 17209.1 17268.5 17327.7 17345.1 597154.0 600937.0 602015.0 602371.0 605109.0 58528.4 58839.7 58995.1 59129.4 59193.0 1229.1 1284.2 1315.0 1334.4 1350.1 29626.3 33670.8 33728.5 33804.2 33841.5 51719.3 52303.2 52317.7 52325.0 52350.2 405187.0 405476.0 405914.0 406342.0 407793.0 29001.0 30043.7 30099.4 31498.0 43922.6 47484.3 47747.3 47932.0 48189.8 48851.8 39636.6 40536.2 41224.4 41432.4 42760.4 41437.8 41581.8 42495.8 43033.8 43813.8 40880.4 41654.4 42003.6 42061.2 42119.0 34166.7 36412.6 36903.4 37181.0 37662.6 27997.7 28159.0 28182.6 28199.3 28235.6 05:05:08 INFO - PROCESS | 10957 | 05:05:08 INFO - PROCESS | 10957 | [#2] jquery.html Cycles:180 Average:27471.15 Median:42520.97 stddev:71792.94 (168.8%) stddev-sans-first:71965.51 05:05:08 INFO - PROCESS | 10957 | Values: 372.8 382.9 392.5 394.9 419.8 13959.9 15741.3 16999.7 19699.2 20159.4 17894.7 18100.2 18591.6 18758.7 18877.5 5600.3 5786.9 5851.2 5863.0 5866.4 5599.1 5767.9 5800.8 5802.5 5803.7 5517.9 5767.9 5774.5 5787.7 5794.5 5683.3 5712.2 5746.8 5750.1 5753.3 4980.9 5145.5 5153.6 5157.8 5158.4 23063.4 23142.2 23202.8 23234.7 23540.0 2289.0 2390.6 2529.9 2734.0 2773.7 14330.7 14682.9 14698.5 14784.6 15076.8 1898.2 1924.4 1944.0 1983.7 1995.1 78202.6 84446.6 85541.6 86037.1 90208.0 10951.3 11167.4 11213.4 11372.7 11476.8 15894.0 15935.6 15943.9 15944.4 15954.4 7889.0 7935.4 8035.6 8053.2 8297.1 7404.8 7510.3 7789.7 7790.1 7806.2 4700.3 4782.8 4863.1 4917.7 4981.8 1831.5 1871.8 1906.7 1910.4 1932.6 854.3 889.8 948.8 962.9 1027.9 18678.3 19454.3 19531.9 19720.8 19851.9 926.3 930.0 968.3 982.2 986.0 9516.8 10485.0 10826.5 10859.1 10862.7 429509.1 429627.0 430677.0 433296.0 440353.0 90308.5 91075.0 91810.0 92398.0 92705.0 940.3 973.1 994.5 996.1 996.8 16508.8 16612.9 16971.8 17462.2 17812.6 41069.6 42520.1 42521.8 42680.0 42877.6 56580.4 57736.1 57880.4 58738.7 59212.0 8207.4 8426.0 8457.7 8589.6 8960.2 10321.5 12620.7 12762.2 12770.6 12805.8 10992.8 11048.6 11130.4 11212.7 11554.1 10447.8 10463.2 10603.7 11000.7 11037.9 11078.4 11120.4 11863.9 12139.5 12160.6 7663.8 7964.5 8025.4 8444.5 8591.2 8751.7 10677.1 11052.4 11127.0 11533.1 05:05:08 INFO - PROCESS | 10957 | 05:05:08 INFO - PROCESS | 10957 | [#3] mootools.html Cycles:180 Average:5745.67 Median:479.80 stddev:3297.66 (687.3%) stddev-sans-first:3290.19 05:05:08 INFO - PROCESS | 10957 | Values: 1327.3 1488.9 1516.3 1642.1 1799.9 7666.2 8341.2 8702.8 8726.5 8937.5 8716.0 8808.8 8810.5 8814.1 8818.6 2404.9 2431.9 2433.6 2435.5 2436.3 2342.4 2350.8 2400.1 2409.7 2416.9 2376.6 2377.5 2387.0 2393.7 2412.8 2303.5 2308.0 2320.2 2321.0 2355.7 2310.2 2336.2 2336.3 2341.8 2342.1 8503.5 8608.6 8739.2 8770.9 8781.0 5111.4 5245.6 5262.3 5281.5 5297.7 3227.4 3317.5 3379.8 3391.6 3436.9 1091.9 1132.1 1134.5 1160.9 1162.4 12230.8 12240.6 12314.7 12316.1 12330.4 4406.4 4542.3 4691.1 4701.2 4704.8 9665.3 9687.5 9688.8 9695.3 9697.9 5260.3 5267.8 5282.0 5292.4 5298.4 4347.0 4478.7 4750.8 4869.1 4952.9 3673.9 3723.4 3755.9 3836.2 3836.5 1102.3 1119.0 1140.2 1142.7 1145.1 908.8 929.9 940.1 943.3 961.8 5636.7 5657.3 5933.9 6028.1 6131.9 463.8 465.4 470.3 476.4 483.2 3829.5 4000.0 4022.2 4058.2 4112.8 10598.0 10774.2 10946.6 11020.6 11028.3 10721.1 10843.2 10970.9 10972.9 10974.5 8156.7 8185.9 8235.5 8247.6 8293.0 5542.2 5615.9 5628.4 5679.1 5827.8 9959.5 10085.7 10108.7 10130.8 10247.3 10063.9 10290.2 10440.9 10543.9 10609.5 6706.9 6896.8 7233.5 7504.9 7572.8 8397.5 8775.7 8780.8 8789.6 8789.8 7699.4 7778.3 7823.9 8026.4 8031.8 7473.4 7608.7 7615.8 7650.8 7651.2 7166.6 7266.0 7284.1 7284.5 7287.5 4935.6 5024.2 5032.2 5165.6 5177.5 5145.1 5163.3 5192.3 5229.0 5236.7 05:05:08 INFO - PROCESS | 10957 | 05:05:08 INFO - PROCESS | 10957 | [#4] prototype.html Cycles:180 Average:4516.78 Median:5014.32 stddev:1308.35 (26.1%) stddev-sans-first:1273.72 05:05:08 INFO - PROCESS | 10957 | Values: 330.0 331.5 341.9 342.1 344.1 5015.0 5133.6 5188.5 5269.4 5278.4 4983.8 5086.7 5128.1 5151.6 5175.8 4116.9 4128.8 4129.1 4140.2 4167.3 3774.2 3863.5 4141.9 4159.0 4171.9 4088.8 4095.5 4102.5 4102.6 4110.7 4101.1 4109.7 4111.6 4117.8 4125.6 3686.3 3696.6 3704.0 3705.7 3710.7 4875.5 4972.3 4996.4 5004.6 5013.6 4669.8 4670.9 4672.3 4687.4 4702.0 5311.7 5322.8 5346.8 5364.1 5409.9 3696.5 3899.3 3908.9 3914.6 3930.4 5594.8 5629.1 5650.9 5659.1 5690.4 3992.2 4018.0 4033.5 4070.5 4078.8 3965.0 4040.7 4061.5 4072.1 4129.5 4611.5 4613.9 4615.1 4618.4 4634.4 5383.1 5390.8 5394.4 5397.6 5434.5 1357.8 1385.8 1394.6 1407.1 1412.5 868.2 888.4 901.7 902.4 905.8 4654.5 4659.7 4674.6 4693.9 4700.9 4876.1 4903.9 4909.6 4922.0 4932.9 4619.5 4620.5 4656.5 4668.8 4688.9 2716.9 2928.0 2936.0 2960.3 2960.6 5660.3 5673.5 5709.9 5716.5 5729.3 5462.3 5645.1 5655.9 5660.2 5719.6 5694.2 5694.4 5695.5 5700.6 5713.0 5544.8 5545.4 5545.8 5551.4 5595.5 5228.6 5432.7 5441.1 5468.8 5475.3 5682.5 5689.7 5699.8 5706.8 5722.4 3809.2 4001.0 4014.6 4025.3 4033.7 5160.4 5528.6 5529.6 5536.8 5542.0 5376.7 5385.9 5392.7 5393.3 5396.7 5228.9 5325.6 5381.2 5410.9 5413.2 5287.6 5330.0 5357.5 5363.4 5364.8 5403.5 5419.2 5422.2 5439.3 5440.2 5066.7 5215.3 5241.7 5289.7 5312.9 05:05:08 INFO - PROCESS | 10957 | 05:05:08 INFO - PROCESS | 10957 | [#5] yui.html Cycles:180 Average:21959.70 Median:4681.28 stddev:32861.88 (702.0%) stddev-sans-first:32915.38 05:05:08 INFO - PROCESS | 10957 | Values: 721.8 735.8 761.8 812.9 827.2 14369.6 15228.2 15249.4 15251.2 15331.7 13988.9 14004.6 14042.2 14046.1 14050.6 652.4 670.2 787.3 791.1 806.0 793.9 806.5 807.9 808.3 808.7 870.8 872.4 873.3 873.5 873.6 692.5 710.3 818.7 864.2 867.7 620.2 625.7 625.9 626.3 626.6 14554.0 14575.5 14592.5 14592.9 14601.4 14309.1 14333.0 14410.9 14565.9 14932.9 13208.2 13310.1 13455.3 13809.4 14176.6 4577.8 4598.1 4620.1 4809.0 4824.7 79591.0 79893.2 80226.0 84173.6 84955.0 11552.5 11624.7 11729.7 11796.5 11880.5 22367.1 22413.0 22441.3 22519.2 22567.9 7816.5 7847.3 7980.8 8117.2 8274.6 4557.3 4637.1 4727.3 4775.6 4893.7 3419.8 3523.4 3524.4 3526.8 3776.9 643.8 648.3 649.1 654.5 659.6 660.6 721.8 757.6 760.3 767.4 8992.3 9405.3 9677.8 9755.7 9859.5 648.9 672.8 689.6 700.7 729.8 4558.5 4725.5 4874.4 4880.2 4915.0 123051.4 128252.0 128985.4 130009.3 132507.0 96708.5 111790.4 114458.0 115224.0 117619.0 78576.6 80668.0 97273.0 100972.5 102196.0 9348.7 9406.2 9455.5 9523.0 9572.8 49390.3 51252.5 52272.5 52867.5 53631.7 73089.6 74602.6 77154.0 78480.6 81330.0 12854.2 13247.3 13267.9 13405.7 13971.9 14963.5 15410.4 15437.3 15744.9 16365.8 13499.9 13763.7 13768.8 13930.6 13966.3 12078.4 12517.2 12566.1 12587.4 12741.9 13071.9 13526.5 13722.7 13776.0 14254.7 9922.0 10150.8 10196.0 10532.7 10547.9 11284.3 11738.2 11744.3 11956.8 11970.5 05:05:08 INFO - PROCESS | 10957 | -------- Summary: end -------- 05:05:08 INFO - PROCESS | 10957 | 05:05:09 INFO - TEST-INFO | 10957: exit 0 05:05:09 INFO - TEST-OK | dromaeo_css | took 1115992ms 05:05:09 INFO - TEST-START | kraken 05:05:09 INFO - operating with platform_type : linux_ 05:05:09 INFO - Initialising browser for kraken test... 05:05:09 INFO - TEST-INFO | started process 11259 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmlH0q7/profile http://localhost:52624/getInfo.html) 05:05:13 INFO - PROCESS | 11259 | __metrics Screen width/height:1600/1200 05:05:13 INFO - PROCESS | 11259 | colorDepth:24 05:05:13 INFO - PROCESS | 11259 | Browser inner width/height: 1024/697 05:05:13 INFO - PROCESS | 11259 | __metrics 05:05:14 INFO - PROCESS | 11259 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:05:14 INFO - PROCESS | 11259 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:05:15 INFO - PROCESS | 11259 | console.error: PushService: 05:05:15 INFO - PROCESS | 11259 | stateChangeProcessEnqueue: Error transitioning state 05:05:15 INFO - PROCESS | 11259 | UnknownError 05:05:15 INFO - TEST-INFO | 11259: exit 0 05:05:15 INFO - Browser initialized. 05:05:15 INFO - Running cycle 1/1 for kraken test... 05:05:15 INFO - TEST-INFO | started process 11339 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmlH0q7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:05:27 INFO - PROCESS | 11339 | RSS: Main: 172924928 05:05:27 INFO - PROCESS | 11339 | 05:05:30 INFO - PROCESS | 11339 | Cycle 1(1): loaded http://localhost:52624/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52624/tests/kraken/driver.html?testName=audio-beat-detection) 05:05:31 INFO - PROCESS | 11339 | RSS: Main: 232816640 05:05:31 INFO - PROCESS | 11339 | 05:05:33 INFO - PROCESS | 11339 | Cycle 1(1): loaded http://localhost:52624/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52624/tests/kraken/driver.html?testName=audio-dft) 05:05:34 INFO - PROCESS | 11339 | RSS: Main: 217059328 05:05:34 INFO - PROCESS | 11339 | 05:05:37 INFO - PROCESS | 11339 | Cycle 1(1): loaded http://localhost:52624/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52624/tests/kraken/driver.html?testName=audio-fft) 05:05:38 INFO - PROCESS | 11339 | RSS: Main: 298024960 05:05:38 INFO - PROCESS | 11339 | 05:05:40 INFO - PROCESS | 11339 | Cycle 1(1): loaded http://localhost:52624/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52624/tests/kraken/driver.html?testName=audio-oscillator) 05:05:40 INFO - PROCESS | 11339 | RSS: Main: 209104896 05:05:40 INFO - PROCESS | 11339 | 05:05:42 INFO - PROCESS | 11339 | Cycle 1(1): loaded http://localhost:52624/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52624/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:05:43 INFO - PROCESS | 11339 | RSS: Main: 212860928 05:05:43 INFO - PROCESS | 11339 | 05:05:46 INFO - PROCESS | 11339 | Cycle 1(1): loaded http://localhost:52624/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52624/tests/kraken/driver.html?testName=imaging-darkroom) 05:05:47 INFO - PROCESS | 11339 | RSS: Main: 249200640 05:05:47 INFO - PROCESS | 11339 | 05:05:50 INFO - PROCESS | 11339 | Cycle 1(1): loaded http://localhost:52624/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52624/tests/kraken/driver.html?testName=imaging-desaturate) 05:05:51 INFO - PROCESS | 11339 | RSS: Main: 248143872 05:05:51 INFO - PROCESS | 11339 | 05:05:53 INFO - PROCESS | 11339 | Cycle 1(1): loaded http://localhost:52624/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52624/tests/kraken/driver.html?testName=json-parse-financial) 05:05:54 INFO - PROCESS | 11339 | RSS: Main: 248041472 05:05:54 INFO - PROCESS | 11339 | 05:05:56 INFO - PROCESS | 11339 | Cycle 1(1): loaded http://localhost:52624/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52624/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:05:56 INFO - PROCESS | 11339 | RSS: Main: 209899520 05:05:56 INFO - PROCESS | 11339 | 05:05:58 INFO - PROCESS | 11339 | Cycle 1(1): loaded http://localhost:52624/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52624/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:05:58 INFO - PROCESS | 11339 | RSS: Main: 216518656 05:05:58 INFO - PROCESS | 11339 | 05:06:00 INFO - PROCESS | 11339 | Cycle 1(1): loaded http://localhost:52624/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52624/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:06:01 INFO - PROCESS | 11339 | RSS: Main: 221999104 05:06:01 INFO - PROCESS | 11339 | 05:06:04 INFO - PROCESS | 11339 | Cycle 1(1): loaded http://localhost:52624/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52624/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:06:04 INFO - PROCESS | 11339 | RSS: Main: 223457280 05:06:04 INFO - PROCESS | 11339 | 05:06:07 INFO - PROCESS | 11339 | Cycle 1(1): loaded http://localhost:52624/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52624/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:06:08 INFO - PROCESS | 11339 | RSS: Main: 221417472 05:06:08 INFO - PROCESS | 11339 | 05:06:09 INFO - PROCESS | 11339 | Cycle 1(1): loaded http://localhost:52624/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52624/tests/kraken/driver.html?testName=ai-astar) 05:06:10 INFO - PROCESS | 11339 | RSS: Main: 272252928 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | __start_tp_report 05:06:10 INFO - PROCESS | 11339 | _x_x_mozilla_page_load 05:06:10 INFO - PROCESS | 11339 | _x_x_mozilla_page_load_details 05:06:10 INFO - PROCESS | 11339 | |i|pagename|runs| 05:06:10 INFO - PROCESS | 11339 | |0;ai-astar;110;113;111;111;110;111;112;110;111;111 05:06:10 INFO - PROCESS | 11339 | |1;audio-beat-detection;153;124;118;170;119;126;117;123;165;119 05:06:10 INFO - PROCESS | 11339 | |2;audio-dft;161;162;162;161;161;162;163;163;162;162 05:06:10 INFO - PROCESS | 11339 | |3;audio-fft;77;75;76;115;77;77;75;75;108;74 05:06:10 INFO - PROCESS | 11339 | |4;audio-oscillator;82;83;116;82;81;82;113;83;83;116 05:06:10 INFO - PROCESS | 11339 | |5;imaging-gaussian-blur;117;116;116;117;116;117;117;116;117;117 05:06:10 INFO - PROCESS | 11339 | |6;imaging-darkroom;145;146;145;147;145;147;145;146;146;145 05:06:10 INFO - PROCESS | 11339 | |7;imaging-desaturate;96;96;96;96;96;96;95;95;96;96 05:06:10 INFO - PROCESS | 11339 | |8;json-parse-financial;50;51;50;51;55;51;51;51;51;50 05:06:10 INFO - PROCESS | 11339 | |9;json-stringify-tinderbox;61;63;62;63;62;61;62;63;62;62 05:06:10 INFO - PROCESS | 11339 | |10;stanford-crypto-aes;85;78;79;79;78;77;85;77;79;78 05:06:10 INFO - PROCESS | 11339 | |11;stanford-crypto-ccm;137;125;134;124;127;123;135;127;163;137 05:06:10 INFO - PROCESS | 11339 | |12;stanford-crypto-pbkdf2;169;169;167;168;166;169;169;171;166;169 05:06:10 INFO - PROCESS | 11339 | |13;stanford-crypto-sha256-iterative;66;65;74;66;66;65;65;67;65;64 05:06:10 INFO - PROCESS | 11339 | __end_tp_report 05:06:10 INFO - PROCESS | 11339 | __start_cc_report 05:06:10 INFO - PROCESS | 11339 | _x_x_mozilla_cycle_collect,1572 05:06:10 INFO - PROCESS | 11339 | __end_cc_report 05:06:10 INFO - PROCESS | 11339 | __startTimestamp1474286770173__endTimestamp 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | ------- Summary: start ------- 05:06:10 INFO - PROCESS | 11339 | Number of tests: 14 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.00 stddev:0.94 (0.8%) stddev-sans-first:0.93 05:06:10 INFO - PROCESS | 11339 | Values: 110.0 113.0 111.0 111.0 110.0 111.0 112.0 110.0 111.0 111.0 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | [#1] audio-beat-detection Cycles:10 Average:133.40 Median:125.00 stddev:20.80 (16.6%) stddev-sans-first:20.82 05:06:10 INFO - PROCESS | 11339 | Values: 153.0 124.0 118.0 170.0 119.0 126.0 117.0 123.0 165.0 119.0 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 05:06:10 INFO - PROCESS | 11339 | Values: 161.0 162.0 162.0 161.0 161.0 162.0 163.0 163.0 162.0 162.0 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | [#3] audio-fft Cycles:10 Average:82.90 Median:75.50 stddev:15.20 (20.1%) stddev-sans-first:15.97 05:06:10 INFO - PROCESS | 11339 | Values: 77.0 75.0 76.0 115.0 77.0 77.0 75.0 75.0 108.0 74.0 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | [#4] audio-oscillator Cycles:10 Average:92.10 Median:82.00 stddev:15.84 (19.3%) stddev-sans-first:16.37 05:06:10 INFO - PROCESS | 11339 | Values: 82.0 83.0 116.0 82.0 81.0 82.0 113.0 83.0 83.0 116.0 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 05:06:10 INFO - PROCESS | 11339 | Values: 117.0 116.0 116.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.83 05:06:10 INFO - PROCESS | 11339 | Values: 145.0 146.0 145.0 147.0 145.0 147.0 145.0 146.0 146.0 145.0 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 05:06:10 INFO - PROCESS | 11339 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 95.0 95.0 96.0 96.0 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.00 stddev:1.45 (2.8%) stddev-sans-first:1.48 05:06:10 INFO - PROCESS | 11339 | Values: 50.0 51.0 50.0 51.0 55.0 51.0 51.0 51.0 51.0 50.0 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 05:06:10 INFO - PROCESS | 11339 | Values: 61.0 63.0 62.0 63.0 62.0 61.0 62.0 63.0 62.0 62.0 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:79.00 stddev:2.99 (3.8%) stddev-sans-first:2.42 05:06:10 INFO - PROCESS | 11339 | Values: 85.0 78.0 79.0 79.0 78.0 77.0 85.0 77.0 79.0 78.0 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | [#11] stanford-crypto-ccm Cycles:10 Average:133.20 Median:134.50 stddev:11.80 (8.8%) stddev-sans-first:12.44 05:06:10 INFO - PROCESS | 11339 | Values: 137.0 125.0 134.0 124.0 127.0 123.0 135.0 127.0 163.0 137.0 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.30 Median:169.00 stddev:1.57 (0.9%) stddev-sans-first:1.64 05:06:10 INFO - PROCESS | 11339 | Values: 169.0 169.0 167.0 168.0 166.0 169.0 169.0 171.0 166.0 169.0 05:06:10 INFO - PROCESS | 11339 | 05:06:10 INFO - PROCESS | 11339 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:2.83 (4.3%) stddev-sans-first:3.00 05:06:10 INFO - PROCESS | 11339 | Values: 66.0 65.0 74.0 66.0 66.0 65.0 65.0 67.0 65.0 64.0 05:06:10 INFO - PROCESS | 11339 | -------- Summary: end -------- 05:06:10 INFO - PROCESS | 11339 | 05:06:11 INFO - TEST-INFO | 11339: exit 0 05:06:11 INFO - TEST-OK | kraken | took 61679ms 05:06:11 INFO - SUITE-END | took 1177s 05:06:11 INFO - Completed test suite (00:19:38) 05:06:11 INFO - javascript benchmark 05:06:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2969.62879640045, 3014.8944786932457, 3015.185899807052, 3048.344605909732, 3066.677998810125, 5156.819511317105, 5222.033923724104, 5279.208118782185, 5310.176922576997, 5353.866153346173, 5211.895762084756, 5270.789168433268, 5293.126634105368, 5350.143976963686, 5376.086065368887, 4725.763711814413, 4796.928046079312, 4813.7833797479025, 4829.879253018667, 4952.158133117361, 4916.680415772981, 4920.360353154085, 4952.628552858532, 4967.329410529586, 4998.900021999558, 4775.044991001796, 4806.663533552653, 4820.349252850855, 4846.418429788413, 4917.459079501252, 4890.8043678252825, 4960.404751429815, 4977.054359671687, 4985.1774457214515, 4985.576226020757, 4864.318995340655, 4866.537678920497, 4867.196912509432, 4876.634252431082, 4884.340614017134, 5189.117849965515, 5361.329833770778, 5371.865703357408, 5373.247745315659, 5374.408815030343, 5570.944290557083, 5644.604877658566, 5698.059820129673, 5699.344575373802, 5763.769449222068, 5719.542436605062, 5725.341585717654, 5729.427057294279, 5730.283714535683, 5739.081746920473, 5320.095583750772, 5323.973380133113, 5335.466453354711, 5350.38470575881, 5376.4085950545405, 5594.916076258859, 5609.971950140223, 5668.461496157858, 5740.6268592541355, 5784.566157538202, 5526.944730552724, 5625.831225063254, 5627.184058311604, 5635.042042852725, 5645.322561292589, 5641.576881733887, 5648.858778530487, 5650.576206784508, 5650.689212093374, 5654.491095801315, 5372.382176049726, 5611.354694210218, 5616.466435688603, 5619.466150715675, 5621.353544342453, 5467.972660136754, 5528.668279903219, 5572.832815015556, 5574.665520068809, 5653.689047102366, 4339.956600433955, 4348.152110338454, 4349.238883195429, 4350.412694286231, 4403.713758605681, 3859.9232564579715, 3864.30442520349, 3871.2257548490416, 3912.921741565153, 3923.372260438316, 5236.92144617831, 5291.232771248147, 5331.813386531453, 5399.83800485986, 5401.297831281908, 5668.914966275509, 5690.601657883991, 5702.429757024264, 5705.115707065412, 5761.971190144106, 5341.092014357569, 5369.3556773187465, 5378.2470454136455, 5382.219578161144, 5391.137418013099, 4727.2436410174205, 4823.758812059453, 4880.585150262267, 4916.950830491721, 4954.430240522386, 5894.145348924382, 5900.203472531153, 5907.8227653169615, 5953.583249172603, 5981.162637230791, 5684.9431505684415, 5714.485696287354, 5720.3421606515785, 5725.742341594555, 5733.483986441241, 5700.458456446631, 5759.222504961776, 5836.883262334814, 5870.677112758839, 5882.411758824083, 5630.352509461464, 5635.746391412315, 5649.350324712711, 5708.771649133988, 5714.342850572238, 5683.65898046102, 5744.569157313134, 5746.741396637245, 5755.107958266475, 5756.884862302647, 5416.458354164552, 5446.645968012067, 5537.446255374431, 5598.160275958476, 5605.775768969195, 5431.619786614899, 5486.369067557282, 5500.614956953135, 5504.752286147213, 5564.4991950724625, 5489.670619762827, 5495.175723641486, 5496.203050557727, 5501.284832971848, 5505.587080969023, 5435.130379139318, 5496.890062198654, 5525.889482210253, 5599.244102046172, 5599.300087489064, 5354.303940487712, 5361.678299302054, 5364.0, 5412.458754124556, 5437.265969094122], "value": 5232.042747238742, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.845644259556, 595.6932179927338, 601.9006863867462, 604.6462819250738, 612.6599737145883, 32593.0, 33422.33155336892, 43755.99999999996, 44723.77638111813, 45210.09579808402, 46167.30749038767, 46723.0, 46799.29801052978, 46801.0, 46846.0, 9115.680951166727, 9257.21313688334, 9265.119813617695, 9270.119338662817, 9272.07279272074, 9066.818663626724, 9103.317251206154, 9155.54222288883, 9165.083491650848, 9168.404053736485, 9171.54142292886, 9217.354785165042, 9218.078192180796, 9221.815563688722, 9223.769405764842, 9143.222826059793, 9171.954140229323, 9182.908170918272, 9191.632334706605, 9206.171444569954, 9085.0, 9149.862752058725, 9157.908420915772, 9158.954205228998, 9164.95417522908, 43756.56243437557, 44066.0, 44069.0, 44093.33859992103, 44108.558914411085, 3380.7802492837886, 3381.644927282649, 3425.1265927188656, 3484.1289677580603, 3517.3668739626855, 32658.0, 33193.336133277444, 33666.83166584176, 36512.26975460476, 36898.07754806124, 4238.300680387732, 4299.591538803808, 4417.425734654506, 4427.468703755538, 4445.599896009375, 365620.0, 369257.1537142324, 369336.0, 369620.0, 370753.0, 62489.0, 63197.68401158057, 63555.8688685234, 63572.68213658902, 63668.68165659142, 68242.65878670575, 68588.0, 68650.65674671595, 68665.65667171632, 68692.07003089666, 15114.319855606524, 15423.922880385526, 15441.845581544041, 15529.301181446863, 15873.444429445144, 11441.713957151138, 11472.94263528677, 11488.310701357945, 11496.367699776425, 11574.18980671345, 19883.801161988486, 20017.699734503993, 20019.595688948408, 20057.29799457012, 20152.294669686784, 6452.773793041487, 7433.368163706145, 7461.552306861606, 7536.171021187665, 7674.582506510431, 915.7573243090586, 969.9369540979814, 986.6102889358663, 1026.3892983674702, 1064.5582083435288, 32536.349273014406, 32724.509132363033, 32826.0, 32901.02797035578, 33184.1703957403, 1060.926502855393, 1087.8259478483405, 1126.8591426071741, 1135.7217481717032, 1170.7658468306372, 16262.51212463628, 17209.1395430228, 17268.481945541655, 17327.65344693099, 17345.13274336278, 597154.0, 600937.0, 602015.0, 602371.0, 605109.0, 58528.41471585315, 58839.70580147072, 58995.11507327393, 59129.40870591325, 59193.0, 1229.1273196030918, 1284.2037936479442, 1315.0203098691536, 1334.3928512526777, 1350.1359130156707, 29626.259343516584, 33670.831645841616, 33728.49407258844, 33804.154896127795, 33841.49237761386, 51719.34525892688, 52303.21545176749, 52317.7384113077, 52325.0, 52350.21474677959, 405187.0, 405476.0, 405914.0, 406342.0, 407793.0, 29001.0, 30043.699563004964, 30099.398012039197, 31498.0, 43922.560774391844, 47484.28773568468, 47747.28379074386, 47932.0, 48189.75905120452, 48851.755741222485, 39636.60363396444, 40536.1892762149, 41224.38163427491, 41432.3785143217, 42760.35859462173, 41437.79281103696, 41581.79209104056, 42495.78752106219, 43033.784831075645, 43813.78093109514, 40880.38679419752, 41654.37518437165, 42003.57996419997, 42061.1587768249, 42119.0, 34166.658333416344, 36412.635873641986, 36903.44644830276, 37181.0, 37662.62337376591, 27997.720022799513, 28159.0, 28182.577261341507, 28199.295017623903, 28235.57646635261], "value": 20487.027255207817, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.80427775417917, 382.90810205550656, 392.5250446959179, 394.9052227465408, 419.75024860208185, 13959.930200348997, 15741.291641876114, 16999.74500382495, 19699.212031518757, 20159.39521814348, 17894.731579026324, 18100.185491652875, 18591.628167436644, 18758.718619220723, 18877.52806179846, 5600.327960644719, 5786.91507023629, 5851.210086638313, 5863.0, 5866.384029676887, 5599.132134519135, 5767.913481297784, 5800.8259752207505, 5802.506786923117, 5803.709814509279, 5517.917231241535, 5767.9423205768035, 5774.538036957033, 5787.739551720183, 5794.507466865301, 5683.2895888014, 5712.171735098408, 5746.82759517211, 5750.05124154514, 5753.280839895013, 4980.850574482735, 5145.485451454862, 5153.587712982952, 5157.8452646420665, 5158.4067832199025, 23063.423414414607, 23142.19002334927, 23202.76797232023, 23234.65148022781, 23540.0, 2288.965665515002, 2390.617501199817, 2529.9494010119693, 2733.961094783989, 2773.6671599408, 14330.707086866156, 14682.926585367004, 14698.485553005594, 14784.556463306119, 15076.849231507655, 1898.2312163573774, 1924.422673198035, 1944.0279860069966, 1983.6627773278578, 1995.0623207092642, 78202.60898695527, 84446.57776711139, 85541.57229213876, 86037.13962860355, 90208.0, 10951.288166269167, 11167.38579378142, 11213.3832639204, 11372.701916444139, 11476.770464590662, 15894.046357218604, 15935.601609959613, 15943.920280398755, 15944.362225511082, 15954.441594544136, 7889.0, 7935.444518883623, 8035.55804430762, 8053.234942680436, 8297.128801475757, 7404.777856664308, 7510.324070833652, 7789.6631010696565, 7790.104138024087, 7806.174974262571, 4700.271457924027, 4782.784774685148, 4863.076015557032, 4917.655764096479, 4981.775820088104, 1831.4505648305455, 1871.8006342547453, 1906.6663333916508, 1910.3695780392436, 1932.6327997680396, 854.2952064546773, 889.7553172877381, 948.8339540580371, 962.932594718377, 1027.8920713324994, 18678.34625788091, 19454.319098831475, 19531.90234048849, 19720.802791971895, 19851.928718245472, 926.2682480840061, 929.9814003719888, 968.2641192693603, 982.2436723722836, 986.0386123529502, 9516.762080948032, 10485.0, 10826.512806923707, 10859.076978456762, 10862.674119776419, 429509.1309933826, 429627.0, 430677.0, 433296.0, 440353.0, 90308.54845725861, 91075.0, 91810.0, 92398.0, 92705.0, 940.3464592108419, 973.1290495007071, 994.5231331472758, 996.1372233922611, 996.7607774134301, 16508.75236871472, 16612.916935415247, 16971.830281697024, 17462.21420036076, 17812.55468613295, 41069.58930410658, 42520.14959700851, 42521.78739106285, 42680.0, 42877.57122428861, 56580.434195657515, 57736.13395799151, 57880.4211957875, 58738.7063064699, 59212.0, 8207.425480216567, 8425.95787021061, 8457.746267611981, 8589.570521474026, 8960.1935825776, 10321.48392580383, 12620.747585048432, 12762.17045892014, 12770.553030644254, 12805.8079128815, 10992.835107473555, 11048.613298534512, 11130.443477825915, 11212.719682007688, 11554.075673945896, 10447.791044178923, 10463.162946964378, 10603.734906627396, 11000.724981875197, 11037.944810275898, 11078.446077696244, 11120.443977800916, 11863.881361186623, 12139.514419423478, 12160.635180944586, 7663.846723065619, 7964.482308649919, 8025.357971362394, 8444.451110677786, 8591.226789588967, 8751.737447876574, 10677.14582833356, 11052.392118433563, 11127.0, 11533.077353811843], "value": 9137.495634103772, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.2766342343423, 1488.888333374997, 1516.332813562033, 1642.1378776142524, 1799.9010054447008, 7666.195049519797, 8341.165883411655, 8702.825943481126, 8726.47641141532, 8937.50843703596, 8715.95642021789, 8808.823823523526, 8810.471371717702, 8814.118588141182, 8818.559072046404, 2404.89177986991, 2431.8540887546806, 2433.5741245282006, 2435.5494233566847, 2436.2812970173754, 2342.367560758594, 2350.8471949323325, 2400.063975049732, 2409.734929157791, 2416.9395765105837, 2376.6197408414655, 2377.5363804058215, 2386.9641955370685, 2393.74865639108, 2412.8069754419694, 2303.4817166137486, 2307.976920230801, 2320.1995311617466, 2320.9651855222187, 2355.658429527709, 2310.202979971924, 2336.19401306549, 2336.299110266913, 2341.8126549876138, 2342.133410638075, 8503.532305723184, 8608.569571521399, 8739.21347078766, 8770.912290877073, 8780.956095219546, 5111.361079865017, 5245.580353571705, 5262.342207224097, 5281.471852814688, 5297.708626025567, 3227.3706627207703, 3317.4857897026, 3379.8310084495924, 3391.576052993376, 3436.9484457733156, 1091.8635170603675, 1132.116948779953, 1134.5348407153028, 1160.8548931383577, 1162.3839365136491, 12230.816537751942, 12240.632781016584, 12314.692132696755, 12316.076294277875, 12330.383480825923, 4406.427164468599, 4542.31865220213, 4691.085238378483, 4701.200795864711, 4704.811807527729, 9665.323427360157, 9687.467189304656, 9688.820015496456, 9695.321327507148, 9697.854532182022, 5260.342457192851, 5267.762950667229, 5282.049231138432, 5292.364916210079, 5298.364196296392, 4347.043650396908, 4478.731276123443, 4750.809967601257, 4869.074875773592, 4952.90094198114, 3673.8897833065053, 3723.3856413691437, 3755.9248815024093, 3836.175222327213, 3836.462895194675, 1102.2559772153763, 1119.0320372877402, 1140.2303445174473, 1142.731458107344, 1145.0839328537138, 908.8318661047595, 929.9023602521773, 940.0646356874953, 943.3302355327656, 961.8316794560924, 5636.670292711679, 5657.264555607745, 5933.910991335133, 6028.125921741323, 6131.908021379683, 463.77275135183515, 465.4159030416873, 470.32273526122276, 476.3925994357222, 483.19789150010814, 3829.5021647185686, 4000.0, 4022.1955608878343, 4058.1680755445063, 4112.835486580563, 10598.0, 10774.24580279373, 10946.562137514411, 11020.614278500214, 11028.28316159447, 10721.142308615292, 10843.240973131806, 10970.890291096986, 10972.945135274273, 10974.451277436096, 8156.714514991947, 8185.877211841827, 8235.50586964784, 8247.587620619064, 8292.958535207285, 5542.168674698827, 5615.915761263503, 5628.380878103323, 5679.062954612443, 5827.766889324549, 9959.502024898871, 10085.69742907714, 10108.747281318027, 10130.8480372793, 10247.282690211614, 10063.949680251551, 10290.228232882713, 10440.895591043993, 10543.89456105429, 10609.469526523797, 6706.93293067063, 6896.827579310357, 7233.529820561646, 7504.924950750423, 7572.810679733051, 8397.496150230761, 8775.692850750193, 8780.824383512167, 8789.560521973748, 8789.780255493664, 7699.422543309162, 7778.261065198797, 7823.921760782319, 8026.35789136856, 8031.839363212586, 7473.364763994904, 7608.657610407656, 7615.847683046197, 7650.808729781577, 7651.196624354585, 7166.605836679033, 7266.0, 7284.125904891447, 7284.49008569395, 7287.526310789782, 4935.605151587936, 5024.221245706921, 5032.194848824171, 5165.612579056661, 5177.4822517747925, 5145.07388670043, 5163.251328557413, 5192.273081768631, 5229.01297506945, 5236.659617124875], "value": 4516.720021060242, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.98515066822006, 331.52591793734956, 341.936741702785, 342.1105126670657, 344.05213636431625, 5015.0, 5133.589312854972, 5188.48115188481, 5269.394019687742, 5278.36659600848, 4983.800647974077, 5086.720230387336, 5128.128218202905, 5151.562117220032, 5175.844724658266, 4116.938245926313, 4128.772917489537, 4129.091599848036, 4140.15126898985, 4167.270727622669, 3774.169682669808, 3863.4784304118866, 4141.917161656765, 4159.00367823445, 4171.874843754692, 4088.795560221977, 4095.5494895561615, 4102.528209255929, 4102.646126778156, 4110.712250142491, 4101.138760860223, 4109.684900831705, 4111.602055301182, 4117.794110294488, 4125.608067233608, 3686.262747450494, 3696.6137698363113, 3703.962960370389, 3705.6850167735774, 3710.7031437485207, 4875.463698993108, 4972.279019542182, 4996.350474438336, 5004.574611158056, 5013.6490445668815, 4669.836555720567, 4670.859874203746, 4672.322513235562, 4687.367205427257, 4701.976490117528, 5311.681299122065, 5322.77576157486, 5346.839594812123, 5364.1149210380245, 5409.891802163935, 3696.482492451059, 3899.2981263372867, 3908.882733517999, 3914.628110329514, 3930.4081846852073, 5594.832155035356, 5629.127485239795, 5650.915236271459, 5659.132486279518, 5690.374058853523, 3992.2215168041953, 4017.979910100431, 4033.4554835097474, 4070.5115386153852, 4078.775667338325, 3964.960350396459, 4040.696947728932, 4061.5329237138108, 4072.124493233969, 4129.483814523184, 4611.492735799059, 4613.907721845544, 4615.14619795339, 4618.445786505641, 4634.374359461497, 5383.138697808332, 5390.838274851761, 5394.406615272317, 5397.568194544427, 5434.538064264502, 1357.7915655066997, 1385.8129152564477, 1394.5607133752835, 1407.0853944935873, 1412.4773833681604, 868.2489646455897, 888.4314039015036, 901.7430032440723, 902.3773596218588, 905.8233644439402, 4654.488006319302, 4659.670164917541, 4674.555917187861, 4693.850006748368, 4700.929486057712, 4876.073546026315, 4903.877403064952, 4909.582685471703, 4922.0, 4932.928170168538, 4619.514950930104, 4620.53794620542, 4656.4645065817185, 4668.836590719308, 4688.929666055012, 2716.891324347004, 2927.956080658792, 2935.955960660549, 2960.2913334299305, 2960.585518027478, 5660.330202590246, 5673.546116310686, 5709.914351284734, 5716.485516303553, 5729.28383952006, 5462.262594549765, 5645.068563686946, 5655.915161272585, 5660.179274005246, 5719.571032172604, 5694.174344720075, 5694.430556944355, 5695.515881150149, 5700.600957932905, 5712.971435142797, 5544.805931792368, 5545.390007099135, 5545.7781688732, 5551.361593416808, 5595.524380427546, 5228.555572776357, 5432.701201433959, 5441.0684207105905, 5468.808591699271, 5475.260839786578, 5682.460166284196, 5689.687067211177, 5699.805574853628, 5706.771729130788, 5722.423485321674, 3809.1619843634357, 4000.9799951001223, 4014.598540145962, 4025.2955732747237, 4033.6773058155864, 5160.380754309507, 5528.640638358494, 5529.640573362719, 5536.7785288588, 5542.0, 5376.73116344189, 5385.892282154413, 5392.735051278551, 5393.298871146726, 5396.730163491731, 5228.869278268074, 5325.600579956596, 5381.246625472359, 5410.8647283816645, 5413.215083812904, 5287.603429742708, 5329.973350133379, 5357.464253574767, 5363.436839131991, 5364.785408583769, 5403.540699040468, 5419.187121931584, 5422.15956526739, 5439.3200849893765, 5440.156775699773, 5066.721330326867, 5215.2698622192165, 5241.689577605599, 5289.68261904287, 5312.910853274992], "value": 4103.143579995586, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.8159369360809, 735.805011671907, 761.8209720715631, 812.9268365847073, 827.1728271728272, 14369.640758981017, 15228.23858807058, 15249.39002439904, 15251.237438128092, 15331.693366132671, 13988.860111398883, 14004.64988375291, 14042.157470551774, 14046.08700434471, 14050.64873378166, 652.438902543812, 670.2058061197472, 787.3386355461412, 791.0783936713736, 805.9758207253763, 793.9245771651713, 806.4878801960767, 807.8990126234221, 808.2564041082219, 808.6765293882436, 870.8040690844573, 872.402404353017, 873.2795443758893, 873.4628203654738, 873.5588527793487, 692.5463821197098, 710.2861624067779, 818.6848063495597, 864.2178828160525, 867.7353407210799, 620.2494981072907, 625.7309356976543, 625.8748250349948, 626.2516293029831, 626.5551458464496, 14554.0, 14575.489857855031, 14592.489262875748, 14592.92703536486, 14601.415943362359, 14309.084198661434, 14333.0, 14410.92794536031, 14565.854341456661, 14932.925335373253, 13208.207507549578, 13310.134841235289, 13455.259960702157, 13809.447622095104, 14176.645583860485, 4577.771111444447, 4598.103369842882, 4620.145273124449, 4809.0, 4824.662273640846, 79591.0, 79893.20106798974, 80226.0, 84173.57913210517, 84955.0, 11552.537898484135, 11624.709382265342, 11729.706757330965, 11796.52813887435, 11880.524779008916, 22367.10531578751, 22413.0, 22441.32676019722, 22519.211827585958, 22567.887160564092, 7816.452848300678, 7847.332976696931, 7980.840383192303, 8117.228863258099, 8274.627641756135, 4557.270836666117, 4637.095766325601, 4727.267273572602, 4775.570198682135, 4893.73084480357, 3419.8461069251944, 3523.383407903658, 3524.418470952264, 3526.84129214186, 3776.9433458498142, 643.8486955565438, 648.2836465705421, 649.1139594453537, 654.5287393076977, 659.5877576514679, 660.5970358081566, 721.7798571435762, 757.556829254892, 760.323312252085, 767.4129291092319, 8992.280617550581, 9405.294602904809, 9677.806443871224, 9755.707328780149, 9859.457729824928, 648.9351064893473, 672.825065482978, 689.5793565924782, 700.7232143303366, 729.8176953335525, 4558.521355257716, 4725.456572494207, 4874.4150701916, 4880.170371036932, 4915.0, 123051.3847430775, 128252.0, 128985.35507322592, 130009.34995324964, 132507.0, 96708.51645741727, 111790.44104779424, 114458.0, 115224.0, 117619.0, 78576.6071169652, 80668.0, 97273.0, 100972.49513752385, 102196.0, 9348.672796452092, 9406.153446189875, 9455.479948602619, 9523.0, 9572.808543828945, 49390.25914611355, 51252.48747512477, 52272.47727522676, 52867.47132528779, 53631.731841340545, 73089.63455182903, 74602.62698686472, 77154.0, 78480.60759696165, 81330.0, 12854.228746275254, 13247.337633118113, 13267.8523496607, 13405.66485837862, 13971.860281397056, 14963.476278330207, 15410.383584656489, 15437.305321260796, 15744.92127539355, 16365.754513682543, 13499.86500134986, 13763.655908602364, 13768.793468098187, 13930.58208253754, 13966.30168491551, 12078.39608019578, 12517.186382885084, 12566.120371574269, 12587.370631468208, 12741.936290318488, 13071.856495476017, 13526.526571569948, 13722.65693357674, 13776.0, 14254.714905702036, 9922.0, 10150.796984060426, 10196.039307627017, 10532.684019479428, 10547.894521054692, 11284.266522676, 11738.185089845663, 11744.29534227949, 11956.820647690118, 11970.521179152736], "value": 7674.692840754688, "unit": "score"}], "name": "dromaeo_css", "value": 7200.087642834372, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 111.0, 111.0, 110.0, 111.0, 112.0, 110.0, 111.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 124.0, 118.0, 170.0, 119.0, 126.0, 117.0, 123.0, 165.0, 119.0], "value": 133.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 161.0, 161.0, 162.0, 163.0, 163.0, 162.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 76.0, 115.0, 77.0, 77.0, 75.0, 75.0, 108.0, 74.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 116.0, 82.0, 81.0, 82.0, 113.0, 83.0, 83.0, 116.0], "value": 92.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 147.0, 145.0, 147.0, 145.0, 146.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 50.0, 51.0, 55.0, 51.0, 51.0, 51.0, 51.0, 50.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 62.0, 63.0, 62.0, 61.0, 62.0, 63.0, 62.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 78.0, 79.0, 79.0, 78.0, 77.0, 85.0, 77.0, 79.0, 78.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 125.0, 134.0, 124.0, 127.0, 123.0, 135.0, 127.0, 163.0, 137.0], "value": 133.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 167.0, 168.0, 166.0, 169.0, 169.0, 171.0, 166.0, 169.0], "value": 168.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 74.0, 66.0, 66.0, 65.0, 65.0, 67.0, 65.0, 64.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1499.9, "alertThreshold": 2.0}]} 05:06:11 INFO - Return code: 0 05:06:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:06:11 INFO - # TBPL SUCCESS # 05:06:11 INFO - Running post-action listener: _resource_record_post_action 05:06:11 INFO - [mozharness: 2016-09-19 12:06:11.969188Z] Finished run-tests step (success) 05:06:11 INFO - Running post-run listener: _resource_record_post_run 05:06:12 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 397557760; Read time: 8; Write time: 288736 05:06:12 INFO - TinderboxPrint: CPU usage
13.1% 05:06:12 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 05:06:12 INFO - TinderboxPrint: I/O write bytes / time
397,557,760 / 288,736 05:06:12 INFO - TinderboxPrint: CPU idle
8,283.1 (86.9%) 05:06:12 INFO - TinderboxPrint: CPU user
1,197.4 (12.6%) 05:06:12 INFO - TinderboxPrint: Swap in / out
0 / 0 05:06:12 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106872832; Read time: 0; Write time: 97000 05:06:12 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 290684928; Read time: 0; Write time: 191736 05:06:12 INFO - Running post-run listener: _upload_blobber_files 05:06:12 INFO - Blob upload gear active. 05:06:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:06:12 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 05:06:12 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'] 05:06:12 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 05:06:13 INFO - (blobuploader) - INFO - Open directory for files ... 05:06:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:06:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:06:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:14 INFO - (blobuploader) - INFO - Done attempting. 05:06:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:06:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:06:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:14 INFO - (blobuploader) - INFO - Done attempting. 05:06:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:06:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:06:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:18 INFO - (blobuploader) - INFO - Done attempting. 05:06:18 INFO - (blobuploader) - INFO - Iteration through files over. 05:06:18 INFO - Return code: 0 05:06:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:06:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:06:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6090f52ba904690c1869370b19b9a44fcb7dcb2c4ec30c831b52d9e8976daee0df919e4b662a7616fb7c90210e8baf67f1834a47572bc141525ce0aadfa5525b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d82004560239fb1a52d31d4d5b650a1f515e9acc3a1a54fee8c94e49a6c377075263514e9289d2643e9008313d025e056a39ba3825c31bc6a07bae88256cbe9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2af5558f359cc3586fdd0f1f26a4f594fd19cef70e302c173609fb629b0a57af0a9df4b5e3951250b9af2ca9ed75307a13c44322cf7f03a4438bbc985601199f"} 05:06:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:06:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:06:18 INFO - Contents: 05:06:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6090f52ba904690c1869370b19b9a44fcb7dcb2c4ec30c831b52d9e8976daee0df919e4b662a7616fb7c90210e8baf67f1834a47572bc141525ce0aadfa5525b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d82004560239fb1a52d31d4d5b650a1f515e9acc3a1a54fee8c94e49a6c377075263514e9289d2643e9008313d025e056a39ba3825c31bc6a07bae88256cbe9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2af5558f359cc3586fdd0f1f26a4f594fd19cef70e302c173609fb629b0a57af0a9df4b5e3951250b9af2ca9ed75307a13c44322cf7f03a4438bbc985601199f"} 05:06:18 INFO - Running post-run listener: copy_logs_to_upload_dir 05:06:18 INFO - Copying logs to upload dir... 05:06:18 INFO - mkdir: /builds/slave/test/build/upload/logs 05:06:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.206424 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-09-19 05:06:17.995805) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 05:06:18.005499) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6090f52ba904690c1869370b19b9a44fcb7dcb2c4ec30c831b52d9e8976daee0df919e4b662a7616fb7c90210e8baf67f1834a47572bc141525ce0aadfa5525b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d82004560239fb1a52d31d4d5b650a1f515e9acc3a1a54fee8c94e49a6c377075263514e9289d2643e9008313d025e056a39ba3825c31bc6a07bae88256cbe9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2af5558f359cc3586fdd0f1f26a4f594fd19cef70e302c173609fb629b0a57af0a9df4b5e3951250b9af2ca9ed75307a13c44322cf7f03a4438bbc985601199f"} build_url:https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011431 build_url: 'https://queue.taskcluster.net/v1/task/ZxdHHuCUT3267DCggSb59Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6090f52ba904690c1869370b19b9a44fcb7dcb2c4ec30c831b52d9e8976daee0df919e4b662a7616fb7c90210e8baf67f1834a47572bc141525ce0aadfa5525b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d82004560239fb1a52d31d4d5b650a1f515e9acc3a1a54fee8c94e49a6c377075263514e9289d2643e9008313d025e056a39ba3825c31bc6a07bae88256cbe9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2af5558f359cc3586fdd0f1f26a4f594fd19cef70e302c173609fb629b0a57af0a9df4b5e3951250b9af2ca9ed75307a13c44322cf7f03a4438bbc985601199f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 05:06:18.034820) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 05:06:18.035240) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1474285537.876539-843474339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004533 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 05:06:18.123327) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 05:06:18.124006) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 05:06:18.124581) ========= ========= Total master_lag: 0.19 =========