builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1474334561.47 results: success (0) buildid: 20160919151817 builduid: 7d7fb7dd4d4449d5912b21bc77e84330 revision: c429089a8e66cff46a0fba433acee27f08057034 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 18:22:41.472542) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 18:22:41.473084) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 18:22:41.473422) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 18:22:41.488304) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:22:41.488660) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 18:22:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.0M=0s 2016-09-19 18:22:42 (43.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093048 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:22:41.596308) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:22:41.596679) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016316 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:22:41.647035) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:22:41.647432) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c429089a8e66cff46a0fba433acee27f08057034 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c429089a8e66cff46a0fba433acee27f08057034 --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=d6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 18:22:42,244 truncating revision to first 12 chars 2016-09-19 18:22:42,244 Setting DEBUG logging. 2016-09-19 18:22:42,244 attempt 1/10 2016-09-19 18:22:42,244 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c429089a8e66?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 18:22:42,829 unpacking tar archive at: mozilla-aurora-c429089a8e66/testing/mozharness/ program finished with exit code 0 elapsedTime=0.939304 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:22:42.601247) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 18:22:42.601617) ========= script_repo_revision: c429089a8e66cff46a0fba433acee27f08057034 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 18:22:42.602089) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 18:22:42.602397) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 18:22:42.612045) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-09-19 18:22:42.612351) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=d6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:22:43 INFO - MultiFileLogger online at 20160919 18:22:43 in /builds/slave/test 18:22:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:22:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:22:43 INFO - {'append_to_log': False, 18:22:43 INFO - 'base_work_dir': '/builds/slave/test', 18:22:43 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:22:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:22:43 INFO - 'branch': 'Mozilla-Aurora', 18:22:43 INFO - 'buildbot_json_path': 'buildprops.json', 18:22:43 INFO - 'config_files': ('talos/linux_config.py',), 18:22:43 INFO - 'default_actions': ('clobber', 18:22:43 INFO - 'read-buildbot-config', 18:22:43 INFO - 'download-and-extract', 18:22:43 INFO - 'populate-webroot', 18:22:43 INFO - 'create-virtualenv', 18:22:43 INFO - 'install', 18:22:43 INFO - 'run-tests'), 18:22:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:22:43 INFO - 'download_minidump_stackwalk': True, 18:22:43 INFO - 'download_symbols': 'ondemand', 18:22:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:22:43 INFO - 'tooltool.py': '/tools/tooltool.py', 18:22:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:22:43 INFO - '/tools/misc-python/virtualenv.py')}, 18:22:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:22:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:22:43 INFO - 'installer_path': 'installer.exe', 18:22:43 INFO - 'log_level': 'info', 18:22:43 INFO - 'log_name': 'talos', 18:22:43 INFO - 'log_to_console': True, 18:22:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:22:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:22:43 INFO - 'opt_config_files': (), 18:22:43 INFO - 'pip_index': False, 18:22:43 INFO - 'sps_profile': False, 18:22:43 INFO - 'sps_profile_interval': 0, 18:22:43 INFO - 'suite': 'dromaeojs', 18:22:43 INFO - 'system_bits': '32', 18:22:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:22:43 INFO - 'title': 'talos-linux64-ix-034', 18:22:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:22:43 INFO - 'use_talos_json': True, 18:22:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:22:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:22:43 INFO - 'work_dir': 'build'} 18:22:43 INFO - [mozharness: 2016-09-20 01:22:43.300348Z] Running clobber step. 18:22:43 INFO - Running pre-action listener: _resource_record_pre_action 18:22:43 INFO - Running main action method: clobber 18:22:43 INFO - rmtree: /builds/slave/test/build 18:22:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:22:44 INFO - Running post-action listener: _resource_record_post_action 18:22:44 INFO - [mozharness: 2016-09-20 01:22:44.284704Z] Finished clobber step (success) 18:22:44 INFO - [mozharness: 2016-09-20 01:22:44.284805Z] Running read-buildbot-config step. 18:22:44 INFO - Running pre-action listener: _resource_record_pre_action 18:22:44 INFO - Running main action method: read_buildbot_config 18:22:44 INFO - Using buildbot properties: 18:22:44 INFO - { 18:22:44 INFO - "project": "", 18:22:44 INFO - "product": "firefox", 18:22:44 INFO - "script_repo_revision": "production", 18:22:44 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 18:22:44 INFO - "repository": "", 18:22:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 18:22:44 INFO - "buildid": "20160919151817", 18:22:44 INFO - "pgo_build": "True", 18:22:44 INFO - "basedir": "/builds/slave/test", 18:22:44 INFO - "buildnumber": 762, 18:22:44 INFO - "slavename": "talos-linux64-ix-034", 18:22:44 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:22:44 INFO - "platform": "ubuntu64_hw", 18:22:44 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 18:22:44 INFO - "branch": "mozilla-aurora", 18:22:44 INFO - "repo_path": "releases/mozilla-aurora", 18:22:44 INFO - "revision": "c429089a8e66cff46a0fba433acee27f08057034", 18:22:44 INFO - "stage_platform": "linux64-pgo", 18:22:44 INFO - "builduid": "7d7fb7dd4d4449d5912b21bc77e84330", 18:22:44 INFO - "slavebuilddir": "test" 18:22:44 INFO - } 18:22:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 18:22:44 INFO - Running post-action listener: _resource_record_post_action 18:22:44 INFO - [mozharness: 2016-09-20 01:22:44.287639Z] Finished read-buildbot-config step (success) 18:22:44 INFO - [mozharness: 2016-09-20 01:22:44.287729Z] Running download-and-extract step. 18:22:44 INFO - Running pre-action listener: _resource_record_pre_action 18:22:44 INFO - Running main action method: download_and_extract 18:22:44 INFO - mkdir: /builds/slave/test/build/tests 18:22:44 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')]} 18:22:44 INFO - trying https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 18:22:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 18:22:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:22:44 INFO - Downloaded 1361 bytes. 18:22:44 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 18:22:44 INFO - Using the following test package requirements: 18:22:44 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 18:22:44 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:22:44 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 18:22:44 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:22:44 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 18:22:44 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:22:44 INFO - u'jsshell-linux-x86_64.zip'], 18:22:44 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:22:44 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 18:22:44 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 18:22:44 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:22:44 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 18:22:44 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:22:44 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 18:22:44 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:22:44 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 18:22:44 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 18:22:44 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 18:22:44 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:22:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 18:22:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:22:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 18:22:45 INFO - Expected file size: 21893574 18:22:49 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:22:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 18:22:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:22:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 18:22:50 INFO - Expected file size: 21893574 18:22:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 18:22:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:22:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 18:22:55 INFO - Expected file size: 14414059 18:22:56 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')]} 18:22:56 INFO - trying https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 18:22:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 18:22:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:22:57 INFO - Downloaded 63918632 bytes. 18:22:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 18:22:57 INFO - mkdir: /builds/slave/test/properties 18:22:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:22:57 INFO - Writing to file /builds/slave/test/properties/build_url 18:22:57 INFO - Contents: 18:22:57 INFO - build_url:https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 18:22:57 INFO - Running post-action listener: _resource_record_post_action 18:22:57 INFO - Running post-action listener: set_extra_try_arguments 18:22:57 INFO - [mozharness: 2016-09-20 01:22:57.841201Z] Finished download-and-extract step (success) 18:22:57 INFO - [mozharness: 2016-09-20 01:22:57.841430Z] Running populate-webroot step. 18:22:57 INFO - Running pre-action listener: _resource_record_pre_action 18:22:57 INFO - Running main action method: populate_webroot 18:22:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:22:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:22:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:22:57 INFO - '--tppagecycles', 18:22:57 INFO - '7'], 18:22:57 INFO - 'tests': ['tsvgm']}}, 18:22:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:22:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:22:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:22:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:22:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:22:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:22:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:57 INFO - 'talos_options': ['--disable-e10s'], 18:22:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:57 INFO - 'talos_options': ['--disable-e10s'], 18:22:57 INFO - 'tests': ['damp', 'tps']}, 18:22:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:57 INFO - 'tests': ['damp', 'tps']}, 18:22:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:22:57 INFO - 'tests': ['dromaeo_dom']}, 18:22:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:22:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:22:57 INFO - 'tests': ['basic_compositor_video']}, 18:22:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:22:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:22:57 INFO - 'tests': ['a11yr', 18:22:57 INFO - 'ts_paint', 18:22:57 INFO - 'tpaint', 18:22:57 INFO - 'sessionrestore', 18:22:57 INFO - 'sessionrestore_no_auto_restore', 18:22:57 INFO - 'tabpaint']}, 18:22:57 INFO - 'other-e10s': {'tests': ['a11yr', 18:22:57 INFO - 'ts_paint', 18:22:57 INFO - 'tpaint', 18:22:57 INFO - 'sessionrestore', 18:22:57 INFO - 'sessionrestore_no_auto_restore', 18:22:57 INFO - 'tabpaint']}, 18:22:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:22:57 INFO - 'ts_paint', 18:22:57 INFO - 'tpaint', 18:22:57 INFO - 'sessionrestore', 18:22:57 INFO - 'sessionrestore_no_auto_restore', 18:22:57 INFO - 'tabpaint']}, 18:22:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:22:57 INFO - 'ts_paint', 18:22:57 INFO - 'tpaint', 18:22:57 INFO - 'sessionrestore', 18:22:57 INFO - 'sessionrestore_no_auto_restore', 18:22:57 INFO - 'tabpaint']}, 18:22:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:22:57 INFO - 'tests': ['a11yr', 18:22:57 INFO - 'ts_paint', 18:22:57 INFO - 'tpaint', 18:22:57 INFO - 'sessionrestore', 18:22:57 INFO - 'sessionrestore_no_auto_restore', 18:22:57 INFO - 'tabpaint']}, 18:22:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:22:57 INFO - 'tests': ['a11yr', 18:22:57 INFO - 'ts_paint', 18:22:57 INFO - 'tpaint', 18:22:57 INFO - 'sessionrestore', 18:22:57 INFO - 'sessionrestore_no_auto_restore', 18:22:57 INFO - 'tabpaint']}, 18:22:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:22:57 INFO - 'tests': ['tsvgx', 18:22:57 INFO - 'tsvgr_opacity', 18:22:57 INFO - 'tart', 18:22:57 INFO - 'tscrollx', 18:22:57 INFO - 'cart']}, 18:22:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:22:57 INFO - 'tsvgr_opacity', 18:22:57 INFO - 'tart', 18:22:57 INFO - 'tscrollx', 18:22:57 INFO - 'cart']}, 18:22:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:57 INFO - 'talos_options': ['--disable-e10s'], 18:22:57 INFO - 'tests': ['tp5o']}, 18:22:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:57 INFO - 'tests': ['tp5o']}, 18:22:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:57 INFO - 'talos_options': ['--disable-e10s', 18:22:57 INFO - '--xperf_path', 18:22:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:57 INFO - 'tests': ['tp5n']}, 18:22:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:57 INFO - 'talos_options': ['--xperf_path', 18:22:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:57 INFO - 'tests': ['tp5n']}}, 18:22:57 INFO - 'talos.zip': {'path': '', 18:22:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:22:57 INFO - Running post-action listener: _resource_record_post_action 18:22:57 INFO - [mozharness: 2016-09-20 01:22:57.862145Z] Finished populate-webroot step (success) 18:22:57 INFO - [mozharness: 2016-09-20 01:22:57.862234Z] Running create-virtualenv step. 18:22:57 INFO - Running pre-action listener: _resource_record_pre_action 18:22:57 INFO - Running main action method: create_virtualenv 18:22:57 INFO - Creating virtualenv /builds/slave/test/build/venv 18:22:57 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 18:22:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:22:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:22:57 INFO - Using real prefix '/usr' 18:22:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:22:58 INFO - Installing distribute.............................................................................................................................................................................................done. 18:23:00 INFO - Installing pip.................done. 18:23:00 INFO - Return code: 0 18:23:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:23: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')]} 18:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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')]} 18:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f828679ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd95ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd92f60>, '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 0xd93d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd941d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', '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 18:23: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', 'pip>=1.5'] in /builds/slave/test/build 18:23: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 pip>=1.5 18:23:00 INFO - Using env: {'DISPLAY': ':0', 18:23:00 INFO - 'HOME': '/home/cltbld', 18:23:00 INFO - 'LANG': 'en_US.UTF-8', 18:23:00 INFO - 'LANGUAGE': 'en_US:en', 18:23:00 INFO - 'LOGNAME': 'cltbld', 18:23:00 INFO - 'MAIL': '/var/mail/cltbld', 18:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:00 INFO - 'MOZ_NO_REMOTE': '1', 18:23:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:00 INFO - 'NO_EM_RESTART': '1', 18:23:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:00 INFO - 'PWD': '/builds/slave/test', 18:23:00 INFO - 'SHELL': '/bin/bash', 18:23:00 INFO - 'SHLVL': '1', 18:23:00 INFO - 'TERM': 'linux', 18:23:00 INFO - 'TMOUT': '86400', 18:23:00 INFO - 'USER': 'cltbld', 18:23:00 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', 18:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:00 INFO - '_': '/tools/buildbot/bin/python'} 18:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:00 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 18:23:00 INFO - Cleaning up... 18:23:00 INFO - Return code: 0 18:23:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:23: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')]} 18:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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')]} 18:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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', '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 0x7f828679ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd95ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd92f60>, '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 0xd93d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd941d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', '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 18:23: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', 'psutil>=3.1.1'] in /builds/slave/test/build 18:23: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 psutil>=3.1.1 18:23:00 INFO - Using env: {'DISPLAY': ':0', 18:23:00 INFO - 'HOME': '/home/cltbld', 18:23:00 INFO - 'LANG': 'en_US.UTF-8', 18:23:00 INFO - 'LANGUAGE': 'en_US:en', 18:23:00 INFO - 'LOGNAME': 'cltbld', 18:23:00 INFO - 'MAIL': '/var/mail/cltbld', 18:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:00 INFO - 'MOZ_NO_REMOTE': '1', 18:23:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:00 INFO - 'NO_EM_RESTART': '1', 18:23:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:00 INFO - 'PWD': '/builds/slave/test', 18:23:00 INFO - 'SHELL': '/bin/bash', 18:23:00 INFO - 'SHLVL': '1', 18:23:00 INFO - 'TERM': 'linux', 18:23:00 INFO - 'TMOUT': '86400', 18:23:00 INFO - 'USER': 'cltbld', 18:23:00 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', 18:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:00 INFO - '_': '/tools/buildbot/bin/python'} 18:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:00 INFO - Downloading/unpacking psutil>=3.1.1 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:23:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:23:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:23:02 INFO - Installing collected packages: psutil 18:23:02 INFO - Running setup.py install for psutil 18:23:02 INFO - building 'psutil._psutil_linux' extension 18:23:02 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 18:23:03 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 18:23:03 INFO - building 'psutil._psutil_posix' extension 18:23:03 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 18:23:03 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 18:23:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:23:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:23:03 INFO - Successfully installed psutil 18:23:03 INFO - Cleaning up... 18:23:03 INFO - Return code: 0 18:23:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:23:03 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')]} 18:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:03 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')]} 18:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:03 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 0x7f828679ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd95ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd92f60>, '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 0xd93d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd941d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', '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 18:23:03 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 18:23:03 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 18:23:03 INFO - Using env: {'DISPLAY': ':0', 18:23:03 INFO - 'HOME': '/home/cltbld', 18:23:03 INFO - 'LANG': 'en_US.UTF-8', 18:23:03 INFO - 'LANGUAGE': 'en_US:en', 18:23:03 INFO - 'LOGNAME': 'cltbld', 18:23:03 INFO - 'MAIL': '/var/mail/cltbld', 18:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:03 INFO - 'MOZ_NO_REMOTE': '1', 18:23:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:03 INFO - 'NO_EM_RESTART': '1', 18:23:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:03 INFO - 'PWD': '/builds/slave/test', 18:23:03 INFO - 'SHELL': '/bin/bash', 18:23:03 INFO - 'SHLVL': '1', 18:23:03 INFO - 'TERM': 'linux', 18:23:03 INFO - 'TMOUT': '86400', 18:23:03 INFO - 'USER': 'cltbld', 18:23:03 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', 18:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:03 INFO - '_': '/tools/buildbot/bin/python'} 18:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:23:03 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 18:23:03 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 18:23:03 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 18:23:03 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 18:23:03 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 18:23:03 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 18:23:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:23:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:23:05 INFO - Installing collected packages: mozsystemmonitor 18:23:05 INFO - Running setup.py install for mozsystemmonitor 18:23:05 INFO - Successfully installed mozsystemmonitor 18:23:05 INFO - Cleaning up... 18:23:05 INFO - Return code: 0 18:23:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:23: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')]} 18:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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')]} 18:23:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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', '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 0x7f828679ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd95ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd92f60>, '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 0xd93d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd941d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', '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 18:23: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:23: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 jsonschema==2.5.1 18:23:05 INFO - Using env: {'DISPLAY': ':0', 18:23:05 INFO - 'HOME': '/home/cltbld', 18:23:05 INFO - 'LANG': 'en_US.UTF-8', 18:23:05 INFO - 'LANGUAGE': 'en_US:en', 18:23:05 INFO - 'LOGNAME': 'cltbld', 18:23:05 INFO - 'MAIL': '/var/mail/cltbld', 18:23:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:05 INFO - 'MOZ_NO_REMOTE': '1', 18:23:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:05 INFO - 'NO_EM_RESTART': '1', 18:23:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:05 INFO - 'PWD': '/builds/slave/test', 18:23:05 INFO - 'SHELL': '/bin/bash', 18:23:05 INFO - 'SHLVL': '1', 18:23:05 INFO - 'TERM': 'linux', 18:23:05 INFO - 'TMOUT': '86400', 18:23:05 INFO - 'USER': 'cltbld', 18:23:05 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', 18:23:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:05 INFO - '_': '/tools/buildbot/bin/python'} 18:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:05 INFO - Downloading/unpacking jsonschema==2.5.1 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:23:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23:08 INFO - Downloading functools32-3.2.3-2.tar.gz 18:23:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:23:08 INFO - warning: no files found matching '*.txt' 18:23:08 INFO - no previously-included directories found matching 'build' 18:23:08 INFO - no previously-included directories found matching 'dist' 18:23:08 INFO - no previously-included directories found matching '.git*' 18:23:08 INFO - Installing collected packages: jsonschema, functools32 18:23:08 INFO - Running setup.py install for functools32 18:23:08 INFO - warning: no files found matching '*.txt' 18:23:08 INFO - no previously-included directories found matching 'build' 18:23:08 INFO - no previously-included directories found matching 'dist' 18:23:08 INFO - no previously-included directories found matching '.git*' 18:23:08 INFO - Successfully installed jsonschema functools32 18:23:08 INFO - Cleaning up... 18:23:08 INFO - Return code: 0 18:23:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:23: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')]} 18:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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')]} 18:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:08 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 0x7f828679ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd95ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd92f60>, '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 0xd93d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd941d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', '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 18:23:08 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 18:23:08 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 18:23:08 INFO - Using env: {'DISPLAY': ':0', 18:23:08 INFO - 'HOME': '/home/cltbld', 18:23:08 INFO - 'LANG': 'en_US.UTF-8', 18:23:08 INFO - 'LANGUAGE': 'en_US:en', 18:23:08 INFO - 'LOGNAME': 'cltbld', 18:23:08 INFO - 'MAIL': '/var/mail/cltbld', 18:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:08 INFO - 'MOZ_NO_REMOTE': '1', 18:23:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:08 INFO - 'NO_EM_RESTART': '1', 18:23:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:08 INFO - 'PWD': '/builds/slave/test', 18:23:08 INFO - 'SHELL': '/bin/bash', 18:23:08 INFO - 'SHLVL': '1', 18:23:08 INFO - 'TERM': 'linux', 18:23:08 INFO - 'TMOUT': '86400', 18:23:08 INFO - 'USER': 'cltbld', 18:23:08 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', 18:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:08 INFO - '_': '/tools/buildbot/bin/python'} 18:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:23:08 INFO - Cleaning up... 18:23:08 INFO - Return code: 0 18:23:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:23: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')]} 18:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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')]} 18:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:08 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 0x7f828679ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd95ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd92f60>, '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 0xd93d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd941d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', '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 18:23:08 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 18:23:08 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 18:23:08 INFO - Using env: {'DISPLAY': ':0', 18:23:08 INFO - 'HOME': '/home/cltbld', 18:23:08 INFO - 'LANG': 'en_US.UTF-8', 18:23:08 INFO - 'LANGUAGE': 'en_US:en', 18:23:08 INFO - 'LOGNAME': 'cltbld', 18:23:08 INFO - 'MAIL': '/var/mail/cltbld', 18:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:08 INFO - 'MOZ_NO_REMOTE': '1', 18:23:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:08 INFO - 'NO_EM_RESTART': '1', 18:23:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:08 INFO - 'PWD': '/builds/slave/test', 18:23:08 INFO - 'SHELL': '/bin/bash', 18:23:08 INFO - 'SHLVL': '1', 18:23:08 INFO - 'TERM': 'linux', 18:23:08 INFO - 'TMOUT': '86400', 18:23:08 INFO - 'USER': 'cltbld', 18:23:08 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', 18:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:08 INFO - '_': '/tools/buildbot/bin/python'} 18:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:08 INFO - Downloading/unpacking blobuploader==1.2.4 18:23:08 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 18:23:08 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 18:23:08 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 18:23:08 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 18:23:08 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 18:23:08 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 18:23:10 INFO - Downloading blobuploader-1.2.4.tar.gz 18:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:23:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:23:10 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 18:23:10 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 18:23:10 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 18:23:10 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 18:23:10 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 18:23:10 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 18:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:23:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:23:11 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 18:23:11 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 18:23:11 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 18:23:11 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 18:23:11 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 18:23:11 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 18:23:11 INFO - Downloading docopt-0.6.1.tar.gz 18:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:23:11 INFO - Installing collected packages: blobuploader, requests, docopt 18:23:11 INFO - Running setup.py install for blobuploader 18:23:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:23:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:23:11 INFO - Running setup.py install for requests 18:23:11 INFO - Running setup.py install for docopt 18:23:12 INFO - Successfully installed blobuploader requests docopt 18:23:12 INFO - Cleaning up... 18:23:12 INFO - Return code: 0 18:23:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23:12 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')]} 18:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:12 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')]} 18:23:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:12 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 0x7f828679ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd95ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd92f60>, '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 0xd93d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd941d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', '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 18:23:12 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 18:23:12 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 18:23:12 INFO - Using env: {'DISPLAY': ':0', 18:23:12 INFO - 'HOME': '/home/cltbld', 18:23:12 INFO - 'LANG': 'en_US.UTF-8', 18:23:12 INFO - 'LANGUAGE': 'en_US:en', 18:23:12 INFO - 'LOGNAME': 'cltbld', 18:23:12 INFO - 'MAIL': '/var/mail/cltbld', 18:23:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:12 INFO - 'MOZ_NO_REMOTE': '1', 18:23:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:12 INFO - 'NO_EM_RESTART': '1', 18:23:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:12 INFO - 'PWD': '/builds/slave/test', 18:23:12 INFO - 'SHELL': '/bin/bash', 18:23:12 INFO - 'SHLVL': '1', 18:23:12 INFO - 'TERM': 'linux', 18:23:12 INFO - 'TMOUT': '86400', 18:23:12 INFO - 'USER': 'cltbld', 18:23:12 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', 18:23:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:12 INFO - '_': '/tools/buildbot/bin/python'} 18:23:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:23:12 INFO - Running setup.py (path:/tmp/pip-WmSww7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:23:12 INFO - Running setup.py (path:/tmp/pip-s0gkbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:23:12 INFO - Running setup.py (path:/tmp/pip-WRBlG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:23:12 INFO - Running setup.py (path:/tmp/pip-UgTsdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:23:12 INFO - Running setup.py (path:/tmp/pip-1SN2uW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:23:12 INFO - Running setup.py (path:/tmp/pip-2Ddl5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:23:12 INFO - Running setup.py (path:/tmp/pip-IXzYUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:23:12 INFO - Running setup.py (path:/tmp/pip-UM5jT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:23:12 INFO - Running setup.py (path:/tmp/pip-nIMi19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:23:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:23:12 INFO - Running setup.py (path:/tmp/pip-K2Y_YT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:23:13 INFO - Running setup.py (path:/tmp/pip-wUCMoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:23:13 INFO - Running setup.py (path:/tmp/pip-7AFkjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:23:13 INFO - Running setup.py (path:/tmp/pip-3Z7o76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:23:13 INFO - Running setup.py (path:/tmp/pip-Boy_W8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:13 INFO - Running setup.py (path:/tmp/pip-pftkbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:23:13 INFO - Running setup.py (path:/tmp/pip-Hahljt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:23:13 INFO - Running setup.py (path:/tmp/pip-QJDF2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:23:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:23:13 INFO - Running setup.py install for manifestparser 18:23:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:23:13 INFO - Running setup.py install for mozcrash 18:23:13 INFO - Running setup.py install for mozdebug 18:23:13 INFO - Running setup.py install for mozdevice 18:23:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:23:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:23:13 INFO - Running setup.py install for mozfile 18:23:14 INFO - Running setup.py install for mozhttpd 18:23:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:23:14 INFO - Running setup.py install for mozinfo 18:23:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:23:14 INFO - Running setup.py install for mozInstall 18:23:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:23:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:23:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:23:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:23:14 INFO - Running setup.py install for mozleak 18:23:14 INFO - Running setup.py install for mozlog 18:23:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:23:14 INFO - Running setup.py install for moznetwork 18:23:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:23:14 INFO - Running setup.py install for mozprocess 18:23:14 INFO - Running setup.py install for mozprofile 18:23:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:23:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:23:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:23:14 INFO - Running setup.py install for mozrunner 18:23:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:23:15 INFO - Running setup.py install for mozscreenshot 18:23:15 INFO - Running setup.py install for moztest 18:23:15 INFO - Running setup.py install for mozversion 18:23:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:23:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:23:15 INFO - Cleaning up... 18:23:15 INFO - Return code: 0 18:23:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23: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')]} 18:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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')]} 18:23:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:15 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 0x7f828679ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd95ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd92f60>, '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 0xd93d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd941d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', '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 18:23:15 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 18:23:15 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 18:23:15 INFO - Using env: {'DISPLAY': ':0', 18:23:15 INFO - 'HOME': '/home/cltbld', 18:23:15 INFO - 'LANG': 'en_US.UTF-8', 18:23:15 INFO - 'LANGUAGE': 'en_US:en', 18:23:15 INFO - 'LOGNAME': 'cltbld', 18:23:15 INFO - 'MAIL': '/var/mail/cltbld', 18:23:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:15 INFO - 'MOZ_NO_REMOTE': '1', 18:23:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:15 INFO - 'NO_EM_RESTART': '1', 18:23:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:15 INFO - 'PWD': '/builds/slave/test', 18:23:15 INFO - 'SHELL': '/bin/bash', 18:23:15 INFO - 'SHLVL': '1', 18:23:15 INFO - 'TERM': 'linux', 18:23:15 INFO - 'TMOUT': '86400', 18:23:15 INFO - 'USER': 'cltbld', 18:23:15 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', 18:23:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:15 INFO - '_': '/tools/buildbot/bin/python'} 18:23:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:23:15 INFO - Running setup.py (path:/tmp/pip-K7Okfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:23:15 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)) 18:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:23:15 INFO - Running setup.py (path:/tmp/pip-VWXrsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:23:15 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)) 18:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:23:15 INFO - Running setup.py (path:/tmp/pip-W39Ihp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:23:15 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)) 18:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:23:15 INFO - Running setup.py (path:/tmp/pip-IVSC_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:23:15 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)) 18:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:23:15 INFO - Running setup.py (path:/tmp/pip-gq39kY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:23:15 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)) 18:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:23:15 INFO - Running setup.py (path:/tmp/pip-5_BQCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:23:15 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)) 18:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:23:15 INFO - Running setup.py (path:/tmp/pip-bxQJiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:23:16 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)) 18:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:23:16 INFO - Running setup.py (path:/tmp/pip-1TJiay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:23:16 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)) 18:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:23:16 INFO - Running setup.py (path:/tmp/pip-rbk9C7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:23:16 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)) 18:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:23:16 INFO - Running setup.py (path:/tmp/pip-LhI9A3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:23:16 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)) 18:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:23:16 INFO - Running setup.py (path:/tmp/pip-OikxaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:23:16 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)) 18:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:23:16 INFO - Running setup.py (path:/tmp/pip-OGzith-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:23:16 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)) 18:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:23:16 INFO - Running setup.py (path:/tmp/pip-BApwXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:23:16 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)) 18:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:23:16 INFO - Running setup.py (path:/tmp/pip-eAL1kZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:23:16 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)) 18:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:16 INFO - Running setup.py (path:/tmp/pip-2tNLGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:16 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)) 18:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:23:16 INFO - Running setup.py (path:/tmp/pip-eGhCeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:23:16 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)) 18:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:23:16 INFO - Running setup.py (path:/tmp/pip-nlXCPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:23:16 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)) 18:23:16 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)) 18:23:16 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)) 18:23:16 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)) 18:23:16 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)) 18:23:16 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)) 18:23:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:16 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 18:23:16 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 18:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:18 INFO - Downloading blessings-1.6.tar.gz 18:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:23:18 INFO - Installing collected packages: blessings 18:23:18 INFO - Running setup.py install for blessings 18:23:18 INFO - Successfully installed blessings 18:23:18 INFO - Cleaning up... 18:23:18 INFO - Return code: 0 18:23:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:23:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:23:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:23:19 INFO - Reading from file tmpfile_stdout 18:23:19 INFO - Current package versions: 18:23:19 INFO - argparse == 1.2.1 18:23:19 INFO - blessings == 1.6 18:23:19 INFO - blobuploader == 1.2.4 18:23:19 INFO - docopt == 0.6.1 18:23:19 INFO - functools32 == 3.2.3-2 18:23:19 INFO - jsonschema == 2.5.1 18:23:19 INFO - manifestparser == 1.1 18:23:19 INFO - mozInstall == 1.12 18:23:19 INFO - mozcrash == 0.17 18:23:19 INFO - mozdebug == 0.1 18:23:19 INFO - mozdevice == 0.48 18:23:19 INFO - mozfile == 1.2 18:23:19 INFO - mozhttpd == 0.7 18:23:19 INFO - mozinfo == 0.9 18:23:19 INFO - mozleak == 0.1 18:23:19 INFO - mozlog == 3.4 18:23:19 INFO - moznetwork == 0.27 18:23:19 INFO - mozprocess == 0.23 18:23:19 INFO - mozprofile == 0.28 18:23:19 INFO - mozrunner == 6.12 18:23:19 INFO - mozscreenshot == 0.1 18:23:19 INFO - mozsystemmonitor == 0.3 18:23:19 INFO - moztest == 0.7 18:23:19 INFO - mozversion == 1.4 18:23:19 INFO - psutil == 3.1.1 18:23:19 INFO - requests == 1.2.3 18:23:19 INFO - wsgiref == 0.1.2 18:23:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23:19 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')]} 18:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:19 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')]} 18:23:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:19 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 0x7f828679ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd95ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd92f60>, '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 0xd93d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd941d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', '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 18:23:19 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 18:23:19 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 18:23:19 INFO - Using env: {'DISPLAY': ':0', 18:23:19 INFO - 'HOME': '/home/cltbld', 18:23:19 INFO - 'LANG': 'en_US.UTF-8', 18:23:19 INFO - 'LANGUAGE': 'en_US:en', 18:23:19 INFO - 'LOGNAME': 'cltbld', 18:23:19 INFO - 'MAIL': '/var/mail/cltbld', 18:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:19 INFO - 'MOZ_NO_REMOTE': '1', 18:23:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:19 INFO - 'NO_EM_RESTART': '1', 18:23:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:19 INFO - 'PWD': '/builds/slave/test', 18:23:19 INFO - 'SHELL': '/bin/bash', 18:23:19 INFO - 'SHLVL': '1', 18:23:19 INFO - 'TERM': 'linux', 18:23:19 INFO - 'TMOUT': '86400', 18:23:19 INFO - 'USER': 'cltbld', 18:23:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', 18:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:19 INFO - '_': '/tools/buildbot/bin/python'} 18:23:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:19 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)) 18:23:19 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)) 18:23:19 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)) 18:23:19 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)) 18:23:19 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)) 18:23:19 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)) 18:23:19 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)) 18:23:19 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)) 18:23:19 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)) 18:23:19 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)) 18:23:19 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)) 18:23:19 INFO - Cleaning up... 18:23:19 INFO - Return code: 0 18:23:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:23:19 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')]} 18:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:19 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')]} 18:23:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7f828679ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd95ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd92f60>, '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 0xd93d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd941d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', '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 18:23:19 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 18:23:19 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 18:23:19 INFO - Using env: {'DISPLAY': ':0', 18:23:19 INFO - 'HOME': '/home/cltbld', 18:23:19 INFO - 'LANG': 'en_US.UTF-8', 18:23:19 INFO - 'LANGUAGE': 'en_US:en', 18:23:19 INFO - 'LOGNAME': 'cltbld', 18:23:19 INFO - 'MAIL': '/var/mail/cltbld', 18:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:19 INFO - 'MOZ_NO_REMOTE': '1', 18:23:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:19 INFO - 'NO_EM_RESTART': '1', 18:23:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:19 INFO - 'PWD': '/builds/slave/test', 18:23:19 INFO - 'SHELL': '/bin/bash', 18:23:19 INFO - 'SHLVL': '1', 18:23:19 INFO - 'TERM': 'linux', 18:23:19 INFO - 'TMOUT': '86400', 18:23:19 INFO - 'USER': 'cltbld', 18:23:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', 18:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:19 INFO - '_': '/tools/buildbot/bin/python'} 18:23:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:23:19 INFO - Cleaning up... 18:23:19 INFO - Return code: 0 18:23:19 INFO - Running post-action listener: _resource_record_post_action 18:23:19 INFO - Running post-action listener: _start_resource_monitoring 18:23:19 INFO - Starting resource monitoring. 18:23:19 INFO - [mozharness: 2016-09-20 01:23:19.649348Z] Finished create-virtualenv step (success) 18:23:19 INFO - [mozharness: 2016-09-20 01:23:19.649845Z] Running install step. 18:23:19 INFO - Running pre-action listener: _resource_record_pre_action 18:23:19 INFO - Running main action method: install 18:23:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:23:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:23:19 INFO - Reading from file tmpfile_stdout 18:23:19 INFO - Detecting whether we're running mozinstall >=1.0... 18:23:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:23:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:23:19 INFO - Reading from file tmpfile_stdout 18:23:19 INFO - Output received: 18:23:19 INFO - Usage: mozinstall [options] installer 18:23:19 INFO - Options: 18:23:19 INFO - -h, --help show this help message and exit 18:23:19 INFO - -d DEST, --destination=DEST 18:23:19 INFO - Directory to install application into. [default: 18:23:19 INFO - "/builds/slave/test"] 18:23:19 INFO - --app=APP Application being installed. [default: firefox] 18:23:19 INFO - mkdir: /builds/slave/test/build/application 18:23:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:23:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:23:35 INFO - Reading from file tmpfile_stdout 18:23:35 INFO - Output received: 18:23:35 INFO - /builds/slave/test/build/application/firefox/firefox 18:23:35 INFO - Running post-action listener: _resource_record_post_action 18:23:35 INFO - [mozharness: 2016-09-20 01:23:35.883233Z] Finished install step (success) 18:23:35 INFO - [mozharness: 2016-09-20 01:23:35.883477Z] Running run-tests step. 18:23:35 INFO - Running pre-action listener: _resource_record_pre_action 18:23:35 INFO - Running main action method: run_tests 18:23:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:23:35 INFO - Python 2.7.3 18:23:35 INFO - Return code: 0 18:23:35 INFO - grabbing minidump binary from tooltool 18:23:35 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')]} 18:23:35 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 0xd92f60>, '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 0xd93d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd941d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:23:35 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 18:23:35 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 18:23:35 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 18:23:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:23:35 INFO - Return code: 0 18:23:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:23:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:23:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:23:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:23:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:23:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 18:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 18:23:35 INFO - Using env: {'DISPLAY': ':0', 18:23:35 INFO - 'HOME': '/home/cltbld', 18:23:35 INFO - 'LANG': 'en_US.UTF-8', 18:23:35 INFO - 'LANGUAGE': 'en_US:en', 18:23:35 INFO - 'LOGNAME': 'cltbld', 18:23:35 INFO - 'MAIL': '/var/mail/cltbld', 18:23:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:23:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:35 INFO - 'MOZ_NO_REMOTE': '1', 18:23:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:23:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:35 INFO - 'NO_EM_RESTART': '1', 18:23:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:35 INFO - 'PWD': '/builds/slave/test', 18:23:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:23:35 INFO - 'SHELL': '/bin/bash', 18:23:35 INFO - 'SHLVL': '1', 18:23:35 INFO - 'TERM': 'linux', 18:23:35 INFO - 'TMOUT': '86400', 18:23:35 INFO - 'USER': 'cltbld', 18:23:35 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003', 18:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:35 INFO - '_': '/tools/buildbot/bin/python'} 18:23:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 18:23:36 INFO - mozversion application_buildid: 20160919151817 18:23:36 INFO - mozversion application_changeset: c429089a8e66cff46a0fba433acee27f08057034 18:23:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:23:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:23:36 INFO - mozversion application_name: Firefox 18:23:36 INFO - mozversion application_remotingname: firefox-dev 18:23:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:23:36 INFO - mozversion application_vendor: Mozilla 18:23:36 INFO - mozversion application_version: 51.0a2 18:23:36 INFO - mozversion platform_buildid: 20160919151817 18:23:36 INFO - mozversion platform_changeset: c429089a8e66cff46a0fba433acee27f08057034 18:23:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:23:36 INFO - mozversion platform_version: 51.0a2 18:23:36 INFO - using testdate: 1474334616 18:23:36 INFO - actual date: 1474334616 18:23:36 INFO - starting webserver on 'localhost:44685' 18:23:36 INFO - SUITE-START | Running 2 tests 18:23:36 INFO - TEST-START | dromaeo_css 18:23:36 INFO - operating with platform_type : linux_ 18:23:36 INFO - Initialising browser for dromaeo_css test... 18:23:36 INFO - TEST-INFO | started process 29642 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqMuL8_/profile http://localhost:44685/getInfo.html) 18:23:40 INFO - PROCESS | 29642 | __metrics Screen width/height:1600/1200 18:23:40 INFO - PROCESS | 29642 | colorDepth:24 18:23:40 INFO - PROCESS | 29642 | Browser inner width/height: 1024/697 18:23:40 INFO - PROCESS | 29642 | __metrics 18:23:40 INFO - PROCESS | 29642 | 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" 18:23:40 INFO - PROCESS | 29642 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:23:41 INFO - PROCESS | 29642 | console.error: PushService: 18:23:41 INFO - PROCESS | 29642 | stateChangeProcessEnqueue: Error transitioning state 18:23:41 INFO - PROCESS | 29642 | UnknownError 18:23:41 INFO - TEST-INFO | 29642: exit 0 18:23:41 INFO - Browser initialized. 18:23:41 INFO - Running cycle 1/1 for dromaeo_css test... 18:23:41 INFO - TEST-INFO | started process 29734 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqMuL8_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:23:53 INFO - PROCESS | 29734 | RSS: Main: 144388096 18:23:53 INFO - PROCESS | 29734 | 18:24:20 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01742 seconds. 18:24:23 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.03372 seconds. 18:24:25 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.10014 seconds. 18:24:44 INFO - PROCESS | 29734 | 1474334684849 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] 18:24:51 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01646 seconds. 18:24:57 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.16711 seconds. 18:25:12 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.06651 seconds. 18:25:24 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.03362 seconds. 18:25:26 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01639 seconds. 18:25:28 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01644 seconds. 18:25:53 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.04974 seconds. 18:25:58 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.03375 seconds. 18:26:10 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.03358 seconds. 18:26:14 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00653 seconds. 18:26:16 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00043 seconds. 18:26:37 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 18:26:44 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.78399 seconds. 18:26:49 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.03325 seconds. 18:26:53 INFO - PROCESS | 29734 | Cycle 1(1): loaded http://localhost:44685/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44685/tests/dromaeo/cssquery-ext.html) 18:26:55 INFO - PROCESS | 29734 | RSS: Main: 225181696 18:26:55 INFO - PROCESS | 29734 | 18:26:58 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01847 seconds. 18:27:50 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01682 seconds. 18:27:56 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00072 seconds. 18:28:05 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01651 seconds. 18:29:00 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00076 seconds. 18:29:15 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.0169 seconds. 18:29:19 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00023 seconds. 18:29:25 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 18:29:27 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00027 seconds. 18:29:37 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 18:29:47 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01658 seconds. 18:29:57 INFO - PROCESS | 29734 | Cycle 1(1): loaded http://localhost:44685/tests/dromaeo/cssquery-ext.html (next: http://localhost:44685/tests/dromaeo/cssquery-jquery.html) 18:29:58 INFO - PROCESS | 29734 | RSS: Main: 229982208 18:29:58 INFO - PROCESS | 29734 | 18:30:01 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01684 seconds. 18:31:01 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00027 seconds. 18:31:03 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 18:31:25 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00076 seconds. 18:31:27 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00027 seconds. 18:32:23 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 18:32:29 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00026 seconds. 18:32:37 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 18:32:54 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 18:32:56 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 18:33:00 INFO - PROCESS | 29734 | Cycle 1(1): loaded http://localhost:44685/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44685/tests/dromaeo/cssquery-mootools.html) 18:33:00 INFO - PROCESS | 29734 | RSS: Main: 201564160 18:33:00 INFO - PROCESS | 29734 | 18:33:04 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01603 seconds. 18:33:15 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 18:33:45 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 18:33:47 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 18:34:11 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 18:34:13 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 18:34:25 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01646 seconds. 18:34:33 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00038 seconds. 18:34:48 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00046 seconds. 18:35:10 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.0004 seconds. 18:35:42 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00035 seconds. 18:35:44 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01645 seconds. 18:36:02 INFO - PROCESS | 29734 | Cycle 1(1): loaded http://localhost:44685/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44685/tests/dromaeo/cssquery-prototype.html) 18:36:03 INFO - PROCESS | 29734 | RSS: Main: 173740032 18:36:03 INFO - PROCESS | 29734 | 18:36:13 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 18:36:19 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01652 seconds. 18:36:21 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 18:36:26 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00028 seconds. 18:36:30 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00057 seconds. 18:36:34 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 18:36:37 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00016 seconds. 18:36:40 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00017 seconds. 18:36:54 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.05016 seconds. 18:37:00 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00066 seconds. 18:37:12 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 18:37:26 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01635 seconds. 18:37:34 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00031 seconds. 18:37:49 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00037 seconds. 18:37:53 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01726 seconds. 18:37:56 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00074 seconds. 18:37:57 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00044 seconds. 18:38:05 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01716 seconds. 18:38:39 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 18:38:48 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 18:39:05 INFO - PROCESS | 29734 | Cycle 1(1): loaded http://localhost:44685/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44685/tests/dromaeo/cssquery-yui.html) 18:39:06 INFO - PROCESS | 29734 | RSS: Main: 174555136 18:39:06 INFO - PROCESS | 29734 | 18:39:14 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 18:39:47 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00067 seconds. 18:39:55 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00028 seconds. 18:40:21 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 18:40:43 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 18:41:08 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00068 seconds. 18:41:30 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 18:41:43 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.03346 seconds. 18:41:44 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 18:41:58 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.00048 seconds. 18:42:07 INFO - PROCESS | 29734 | [GFX1-]: Refresh driver waiting for the compositor for 1.01716 seconds. 18:42:09 INFO - PROCESS | 29734 | Cycle 1(1): loaded http://localhost:44685/tests/dromaeo/cssquery-yui.html (next: http://localhost:44685/tests/dromaeo/cssquery-dojo.html) 18:42:09 INFO - PROCESS | 29734 | RSS: Main: 265011200 18:42:09 INFO - PROCESS | 29734 | 18:42:09 INFO - PROCESS | 29734 | __start_tp_report 18:42:09 INFO - PROCESS | 29734 | _x_x_mozilla_page_load 18:42:09 INFO - PROCESS | 29734 | _x_x_mozilla_page_load_details 18:42:09 INFO - PROCESS | 29734 | |i|pagename|runs| 18:42:09 INFO - PROCESS | 29734 | |0;dojo.html;3106.223444138965;3146.323540438805;3151.4012337655845;3162.320101178246;3193.233623930256;5549.77800887964;5645.379008309083;5650.660960342378;5666.065099258617;5696.401877802833;5643.68959707216;5701.74342154603;5702.230198923144;5711.485966263035;5745.683987380692;5130.563902068319;5159.690418574897;5173.042987047389;5190.844274671747;5237.083510385686;5033.6728112672745;5087.7710503027265;5248.0815857225025;5248.790048398059;5267.552258058051;5097.847064588068;5145.6398052136365;5219.00838840621;5219.582433405338;5222.9477705223035;5120.0527902338;5155.458676838915;5195.506426889439;5202.843914682566;5215.139501982168;5129.410117836422;5214.843554693365;5223.71269580173;5224.4775522447835;5259.631825772198;5484.341878974548;5520.199571062214;5675.801346952837;5713.514351280146;5790.942090579082;5974.910376344358;6022.156898034279;6023.247094113236;6032.698365081728;6052;6062.090686397031;6149.5080393568405;6161.537884658668;6164.476019538344;6170.568060235785;5485.149801780731;5523.337199536081;5719.313682358102;5723.51350135239;5728.169415434739;6136.641621527207;6212.534059945522;6216.471599914058;6299.118123462719;6323.683815809191;6053.576249662481;6065.484433823086;6068.21113255274;6069.0592958091565;6070.726817293233;5854.736536855852;5896.911546326808;6138.171346868205;6147.907781383283;6157.599756015934;5987.281526216795;6051.425114614105;6053.576249662569;6126.479249263773;6130.601510901777;6041.093835924558;6090.147379366892;6100.542459315569;6103.1455596216565;6124.142620033199;4624.468186158568;4654.488006319302;4669.859904202879;4670.229412147026;4697;4014.1770936957855;4049.635532802003;4050.736702114353;4053.43251944728;4080.5511393746665;5797.47822695951;5800.158976948319;5804;5804.593678442468;5831.533477321804;6100.481459076028;6118.847028824315;6122.142899994004;6130.570860039755;6130.63216207029;5607.43925607436;5648.604597678123;5784.1902133701315;5799.594028417971;5842.298924129132;5287.339082614673;5292.92060619091;5296.761645725952;5304.177852432879;5331.466853314715;6136.7852125175405;6145.784897528654;6194.442500174916;6203.689815509207;6205.162303089115;5989.850253743692;5995.850103747442;6048.455638992512;6123.969380153071;6125.081237814364;6179.0422484514975;6244.718987645476;6255.249370075438;6273;6274.592151510137;5957;5960.215263307918;5960.791372301948;5963.135345374983;5963.671998040149;5867.149263356704;5886.646801191942;6052.969735151296;6058.636481811105;6065.211522502046;5810.302763668218;5835.387284335084;5859.5019423349495;5909.881802363842;5913.201717768046;5901.852453688693;5905.763769449174;5909.054551271776;5912.793052243151;5913.46778789911;5682.829515114553;5837.591368604157;5838.766449341979;5841.737121829613;5844.620099693507;5878;5915.881682366415;5921.023031199804;5929.407059294036;6058.485028772604;5798.33319168301;5845.09401042839;5847.678377689268;5849.093390524476;5851.21008663842 18:42:09 INFO - PROCESS | 29734 | |1;ext.html;590.5541316306188;616.3805375597524;617.5461036138438;633.4172561243655;638.9041643753436;35315.117122071926;48578.75710621446;49206.75396623021;49238.261426078636;49253;50028;50510.747446262714;50565.74717126409;50578.241326380135;50591.241131382965;8714.564271786418;9377.437353758796;9573;9588.328816982814;9589.856152157723;8726.432781869189;11177.776444471107;11388.202825802196;11414.600488982887;11750.353730544817;11575.652730418058;11627.186096973215;11646.359450230233;11646.767064658701;11657.300561966267;11431.085513158927;11473.770524589545;11511.597094101666;11512.884871151266;11516.30902145874;11313.83029254562;11349.829752553718;11367.374794386305;11387.715307117389;11388;47549.76225118852;48063.279050813915;48117.75941120307;48178;48184.27723584149;3737.3272810894027;3794.5067141271734;3926.725129240954;4036.495438070241;4042.151148258869;36020.81989590061;36150.276994459964;36151.4577281341;36160.457593136125;37516.624833751586;4101.610347017028;4140.8136633851545;4146.626803587661;4160.7087503874745;4180.937285940683;379643.1017844921;379932;381162;381253;382456;40071.1985760289;41051;69766.302336977;70365.64817175882;70667.64666176637;40363.79818100949;40458.595414046074;40478.392824107665;40550.79724601358;40611;17951.551211219823;18067.728984064986;18139.818601814077;18147;18153;13757.862421375658;13964.930175349262;14888.106713597219;15226.238688065552;15282.235888205767;9710.514474276259;9719.514024298756;9761;9766.511674416251;9803.558839852223;3566.2154326048226;3613.3495970725;3619.2942376236906;3620.855165793339;3711.0907827582414;1035.6064695415694;1093.1145771924766;1093.704699731068;1134.982975255372;1147.65570328901;32863.671363286536;35661;35718;35961.1009724759;35992.64007359893;1155.5204590095184;1173.3839734139644;1210.4794938176492;1269.2194300505123;1278.4055414232241;16942;18143.818561814478;18550.814491855177;18575.25698972053;18632.25470981173;634780;651047;651618;651986;652314;66858.66570667212;67660.6616966912;67781.66109169423;67821.32178678249;67906.66046669833;1389.3747813483972;1426.3652674559721;1468.4786900650543;1508.0951429142647;1532.26451303375;32596.83701581477;36464.08839779027;36758;37014.629853702194;37212.44181337336;57366.13950790825;67881.66059169672;68942;69019.30980690129;69151;546160;554084;554627;556021;556761;49842;50896;51155.744221278655;51458;54774.45225547853;53980;54271.72864135812;54453.45546544484;54539.72730136324;54840.72579637076;45479.77260113811;45903.54096458993;46314.76842615765;46667.29999050085;47494.52505475039;44687.10625787531;45949.31076033929;46064.7696761514;46136.53863461322;47396.7630161847;46364;46401;47086.293705595126;47150.05699886052;47689.28466073081;39542.60457395389;41503.377449338885;41625.583744163385;41931;42112.57887421209;30720.84639576788;31134.844325779133;31419.565852040876;31563.36873262476;31617.8419107903 18:42:09 INFO - PROCESS | 29734 | |2;jquery.html;397.14613580801256;415.56573380817025;426.8207352911777;427.1116078556603;444.08296866969704;15598.688026239468;19324.130414131363;20577.27979520717;20592.897035514838;20952.26667066653;19841;20710.792892071076;20711.275105371315;20804.68792968103;20968.37094887152;5856.47291743743;5883.499902508282;6092.756289748405;6095.817125486243;6099.725512351933;5892.734826932777;5940.3762604926505;6004.939950600482;6005.099235114724;6022.187004754368;5784.595078344518;5925.674087925162;5995.550333724967;6000.849978750544;6013.218281623383;5778.913316300259;5779.393163717812;5810.912836307417;5818.650880947135;5983.940160598382;5201.5838732901275;5205.427402985669;5328.360596728378;5601.943980560183;5639.351474580435;15268.541943741706;15355.4625588105;15623.687526249525;23495.295141145794;24026.75973240263;2363.645453182019;2553.9361515962064;2757.9310517237027;3050.8932187373334;3152.243461569207;10437.582496700123;10873.130149588093;11324.03745681618;14465;14607.342669579788;1791.3013924569427;1862.850971922243;1894.1760334254643;1923.1345894347598;1951.5706544560173;83455.582722086;85527.57236213778;85953.57023214844;86343.56828215881;87552;10615.25693201484;11715.882841171479;11721.238119522204;12065;12081.939590301992;15449.073055616698;15672.216389180496;16504.33982640681;16570.917145414194;17064.57338566546;8913.688020919237;8931.106889311146;9119.407238529475;9142.680006199884;9237.861432078524;8717.041125476193;8758.605862736193;8773.254273386707;9019.819603608023;9054.909450905538;5112.744362781846;5180.637355385087;5199.090159222121;5212;5356.651817631841;2023.8583299168918;2071.4096482502414;2125.224293132995;2149.623815832223;2236.7204099487562;1000.5497526113278;1005.9265425858713;1046.4767616191905;1064.8136576099153;1097.3470784882982;20270.69593956092;20436;20561.48596285105;20692.896535517528;20753.273635422687;1010.8584798128268;1034.0900007992918;1057.6404022632341;1087.8748943871556;1095.4522738630685;10447.738806529745;11421.885781142248;11434.942825285987;11482.770344593062;11605.245659032136;429986;430343;433885;446503;487806.0487278081;94567;95294.52352738191;96891;96986;97361.51319243359;1044.9059584637419;1063.6011495689118;1091.3233795047122;1109.9223054386277;1114.5486078138206;17586.03276819787;18513.35203267879;18626.906865466128;18695.439136825917;19136.23455061782;43879.90300242392;44110.77944610256;44676.55323446724;44907.55092449165;45277.773611131735;55599;56932.43067569271;57833;57956;59800.40199597948;8653.870191947;8969.865452018355;9123.406978546373;9215;9276.304277179373;11569.942150289195;11968.46141923633;13399.933000334935;13789.793153102511;14303.21332326732;12353.93823030915;12454.25274483534;12479.500819967463;12502.43739031764;12772.361381931052;12053;12075.69810754703;12124.669500815933;12468.251904885374;12480.812787808372;12108.031357491203;12128.393580321124;12373.257604543756;12575.434105465092;12749.426275817425;8931.240844528287;8992.46045237288;9006.54967251648;9129.178373946377;9408.012158723235;12084.39578021113;12198.146129771187;12643.87356126427;12724.554640587536;12727.554535591209 18:42:09 INFO - PROCESS | 29734 | |3;mootools.html;1431.026901706839;1529.4876216467487;1562.523430353742;1774.6007148391614;1883.76452943382;8174.305184059354;9192.862107068391;9489.098535639103;9492.003339649333;9725.12473877351;9411.764705882357;9444.433333999965;9483.099105584959;9534.23726101912;9542.856857147131;2512.3216731482535;2538.4796116796015;2540.4030052937546;2542.1102614085107;2543.186180422266;2460.1635443949053;2507.661465702134;2531.898724051036;2533.366658335416;2536.644869718241;2475.517274131545;2497.4880149569294;2508.147229941819;2536.2898388451263;2538.6065159900245;2435.6468312094735;2456.803455723547;2463.5072985402903;2472.4807790364043;2473.0231558534456;2401.7598240176017;2441.4506735984437;2448.033026954343;2450.0567281596536;2450.3506570758764;9291.396059256127;9337.813243735156;9452.149306562374;9494.857577136348;9500.524973751284;5637.8590535236535;5869.85325366865;5878.441548052928;5881.764729410818;5882.264716910387;3512.4731290306404;3519.982400087983;3565.447355659877;3567.250877315767;3567.554055743032;1142.737170450801;1146.656003199037;1152.8443660105863;1153.4809335798925;1173.724174818917;13153.21080735159;13217.405216765172;13264.602061938158;13278.269695166762;13279.86720132796;4788.7366194859615;4799;4844.903101937942;4945.777440015136;4980.12847751642;10469.790604187874;10471.371717696962;10482;10494.42280674555;10499.212559057949;5695.886082278331;5715;5726.455986681259;5728.427157284238;5731.340895796953;4609.746463944515;4635.188841952645;5315.654482458628;5333.7066461345;5351.09031464652;4030.133521292936;4039.1921615676983;4099.733517321364;4131.8967025823995;4142.171565686875;1219.3781171602548;1236.1532350340046;1236.3262022197923;1242.931638759877;1251.8059700746398;828.846663367279;967.4243824924159;1060.9098226650822;1081.210716177195;1103.7351035751362;6436.967815160894;6606.966965165239;6678.365555272241;6744.359285867835;6830.726770929206;469.7017393954795;471.67690132259514;474.1749356120326;493.65690844862473;494.2067980890691;4472.217361961643;4537.6143027843;4557.475890272594;4628.213203755369;4741.928871066937;11609.245399049034;12156.452959616838;12180.390980450942;12184.512619495299;12190.695232619255;11197.272177308449;11496.94251528737;11770.411479425995;11771.176017678852;12042.759144817055;8566.100559441169;8650.697225597074;8757.737267882101;8850.114988501098;8856.690015849543;6434.58175218628;6541.70562324687;6575.605463672195;6618.8345291368105;6710.194776626836;10416.114630256514;10652.57389704403;10679.839802403125;10682.946585267024;10980.615678451215;11047;11211.88788112141;11290.548378064785;11293.830592540955;11348.773024539629;7693.576853273124;7907.48601340911;8199.098099208963;8272.545010024507;8542.145785421408;9117.817643646957;9425.245980321695;9440.197583205505;9441.905580944103;9442.291828112782;8244.587770611566;8297.792555185928;8344.624491897757;8389.412741108064;8486.490810551386;7770.144773923818;7916;8209.343252539902;8213.301869340934;8476.110008449024;7230.891536626849;7351.8162045949275;7437.330640242404;7444.553326800409;7519.135299440634;5559.72201389937;5562.082256427806;5702.173184888251;5722.484976352148;5726.141078838207;5361.329833770778;5567.777288908398;5624.043912534797;5785.913211301918;5923.378045305181 18:42:09 INFO - PROCESS | 29734 | |4;prototype.html;355.9483874838148;358.446200620042;361.95656521217455;365.8189196347808;367.21782603055505;5534;5536.640118392312;5557.499825015753;5565.972170139149;5574.916376254349;5478.890422191554;5511.944880551193;5516.724163791814;5525.944740552603;5538.252335934648;4180.5610410906875;4328.852853993694;4362.19299429605;4371.519132895379;4377.774223217504;4344.761038142901;4354.216241076605;4363.454568178978;4364.890877728067;4387.648988080946;4314.590113939171;4326.875012496742;4341.39220509129;4372.27857403528;4377.277749171383;4123.546409894924;4305.5694430557005;4316.935245971313;4323.48118225815;4382.145481631083;3776.2258736958856;3781.054736315921;3802.9429558556644;3804.144067584799;3821.522309711286;5349.197620356938;5419.729013549346;5424.620276580641;5427.701476418795;5444.40111587725;4852.592748103046;4888.291197776302;4912.115819152551;4935.827246046407;4965.702057876539;5645.294338207724;5665.583604098975;5667.886642267173;5694.914576281359;5710.200571919934;4143.7306575072525;4151.377293405983;4171.581662234855;4173.144505376391;4192.937105943413;5874.530037597025;5963.522918166536;5984.324389171039;5999.400059994009;6050.727717252734;4298.161858437575;4298.871033868926;4301.440812694393;4313.245182093184;4321.077120681267;4388.4733831940375;4390.978045109754;4392.450943632046;4396.516383197845;4409.184300904343;4708.8351907683755;4926.630502712239;4937.395346512398;4998.925016124761;4998.975005124951;5758.3090029196765;5760.567957403127;5760.588655779361;5766.135079738073;5773.268019594105;1495.274791726012;1507.366905899525;1510.6903084867577;1515.8408367121515;1515.9317830697646;970.9757256068514;984.242133557157;1001.33911618333;1044.8693913260843;1060.3266925502207;5613.887722245532;5616.9047035827625;5631.943680563224;5633.183188437653;5653.265075540154;5853.975554277984;5862.505061209365;5879.735411906475;5896.705164741831;5920.644761314335;5396.97457483077;5632.8028519002455;5654.717264136777;5696.686682232434;5733.913991290134;3123.8754048542505;3150.7239567975007;3163.5922014703697;3171.3340198558562;3174.2857856981996;5861.296644402699;6111.419415155553;6124.448799608057;6162.784302549389;6175.3515880832765;5741.311042674905;6079.574429789872;6099.268087829489;6109.25886122453;6128.78549250783;5934.228550288522;6004.399560044048;6092.055731361646;6107.267127944675;6156.661383623765;5986.922353976326;5993.362920315048;5996.340402555803;6009.549283803644;6011.639301641915;5515.393306736176;5669.412564481951;5857.941420585739;5858.648481091148;5915.497182739343;6076.817695469143;6116.235470566179;6123.897698414328;6143.938560614515;6173.5736666150715;4119.691023173334;4269.530351661377;4271.209826182167;4286.61420472159;4296.82812687489;5837.416258374129;5852.122181672783;5856.297244330707;5939.4951429129005;5941.554383421347;5716.914246286392;5755.424457554211;5790;5805.796797112081;5821;5601.719914004365;5823.330317013598;5834.912476312774;5844.824655260349;5845;5774.718986000422;5775.9133612995;5782.306123265235;5785.652860828363;5794.7682092717505;5658.971705141613;5866.442687944638;5889.38161493037;5894;5906.692252338704;5721.682718498848;5736.512396446349;5750.769969201185;5763.423657634203;5770.422957704196 18:42:09 INFO - PROCESS | 29734 | |5;yui.html;831.4346244553705;854.4189950833432;855.858783300755;893.6470094312747;918.4948278446852;16310.999999999985;17383.913080434613;17408.65182696347;17411.825881741195;17466.951982881004;15998.760018599702;16064.116473593947;16072.357105715757;16116.435924742636;16125.193740312967;726.9890951635716;748.2741740511694;860.5912191708951;877.9648814047431;881.568031664483;875.7810547363159;878.8769572259889;878.9560521973908;879.0462348352014;879.2658130461073;928.2805825485235;930.186087173723;930.2465003347277;930.2743859789375;930.5300823084333;756.9243075692442;773.8336257704592;898.1961144775422;923.2936803345447;930.6230976454547;691.4675699711195;693.4071368979501;693.4348505967671;693.7710555516668;694.3091623834317;16407.17964101802;16437.178141092896;16443.342266309333;16465.176741162893;16466.341346346133;14827.517248275193;14997.625059373493;15056.623584410478;15124.319405626773;15171.317290721834;13741.106828056145;13936.094153880067;13967;14029.368678409495;14484;4595.862124136248;4621.792019359137;4672.602828759559;4698.600618947394;4710.78801453932;77711.611441943;79346;79415.60292198502;79555;84657.57671211666;11486.138539609563;11596.478158482889;11710.414479276002;12037.819432708517;12159.635210943687;23066.884665576566;23107.653385199235;23281.37030407889;23346.64980025301;23372.181973630843;8892.466452012899;8907.331950103768;8915.42049766763;8936.910630893737;9204.171624553823;4953.380827396575;4977.800887964513;5006.849794506171;5071.163258062378;5120.48795120485;3577.2130131371055;3667.284879448482;3706.29580379727;3706.9814650926573;3713.4801127842125;554.9694766787866;607.9270487541523;619.9535034872313;632.8164832198703;637.7640273098983;702.4836744992426;731.8572878288683;744.8510297940326;745.7576287706518;758.4539131825077;9334.953325233331;10102.614829691338;10177.694669159937;10231.7442063949;10699.893001070046;656.7916422767405;730.204077555467;738.1916801102784;790.5691398188002;823.1768231768232;4377.255866502702;4401.163778882003;4474.149911516802;4518.774061296987;4593.6219134260255;131737;132564;133878;134693;138036;102224.48887755662;104616;106214.46892765487;106253.46873265585;110248.4487577557;72611.63694181496;100812.49593752131;100905.49547252363;103575;104145;8368.24685778283;8413.621387037476;8534.530600817016;8535.487870727777;8583.957080214559;47115.29327060165;59290;60257.39742602518;60516.69741651264;61866.690666546376;49265.26102108399;70223.64888175526;73304;75517;77268;13238.89567798105;14534.92732536373;14572;14583.197924114276;14588.562343129723;13183.868161318263;13841;14739.705205896036;15008.47470338533;15303.693926121638;10740.946295268786;13700.657483562592;14069.437222510986;14285.57143285703;15253.313600888208;13695.520656777364;13837.169769813843;13989.160650361011;14143.929280353945;14488.927555362156;14093.436262549383;14096.647583810487;14264.144151350953;14302.427902883768;14444;11812.586559470377;12015.158938874194;12026.819597705866;12397.256164630151;12503.499860005499;11767.823482647938;12263.26420414778;12263.448144833683;12375.257484550955;12475.126741128033 18:42:09 INFO - PROCESS | 29734 | __end_tp_report 18:42:09 INFO - PROCESS | 29734 | __start_cc_report 18:42:09 INFO - PROCESS | 29734 | _x_x_mozilla_cycle_collect,1064 18:42:09 INFO - PROCESS | 29734 | __end_cc_report 18:42:09 INFO - PROCESS | 29734 | __startTimestamp1474335729312__endTimestamp 18:42:09 INFO - PROCESS | 29734 | 18:42:09 INFO - PROCESS | 29734 | ------- Summary: start ------- 18:42:09 INFO - PROCESS | 29734 | Number of tests: 6 18:42:09 INFO - PROCESS | 29734 | 18:42:09 INFO - PROCESS | 29734 | [#0] dojo.html Cycles:165 Average:5631.12 Median:5844.62 stddev:653.72 (11.2%) stddev-sans-first:624.99 18:42:09 INFO - PROCESS | 29734 | Values: 3106.2 3146.3 3151.4 3162.3 3193.2 5549.8 5645.4 5650.7 5666.1 5696.4 5643.7 5701.7 5702.2 5711.5 5745.7 5130.6 5159.7 5173.0 5190.8 5237.1 5033.7 5087.8 5248.1 5248.8 5267.6 5097.8 5145.6 5219.0 5219.6 5222.9 5120.1 5155.5 5195.5 5202.8 5215.1 5129.4 5214.8 5223.7 5224.5 5259.6 5484.3 5520.2 5675.8 5713.5 5790.9 5974.9 6022.2 6023.2 6032.7 6052.0 6062.1 6149.5 6161.5 6164.5 6170.6 5485.1 5523.3 5719.3 5723.5 5728.2 6136.6 6212.5 6216.5 6299.1 6323.7 6053.6 6065.5 6068.2 6069.1 6070.7 5854.7 5896.9 6138.2 6147.9 6157.6 5987.3 6051.4 6053.6 6126.5 6130.6 6041.1 6090.1 6100.5 6103.1 6124.1 4624.5 4654.5 4669.9 4670.2 4697.0 4014.2 4049.6 4050.7 4053.4 4080.6 5797.5 5800.2 5804.0 5804.6 5831.5 6100.5 6118.8 6122.1 6130.6 6130.6 5607.4 5648.6 5784.2 5799.6 5842.3 5287.3 5292.9 5296.8 5304.2 5331.5 6136.8 6145.8 6194.4 6203.7 6205.2 5989.9 5995.9 6048.5 6124.0 6125.1 6179.0 6244.7 6255.2 6273.0 6274.6 5957.0 5960.2 5960.8 5963.1 5963.7 5867.1 5886.6 6053.0 6058.6 6065.2 5810.3 5835.4 5859.5 5909.9 5913.2 5901.9 5905.8 5909.1 5912.8 5913.5 5682.8 5837.6 5838.8 5841.7 5844.6 5878.0 5915.9 5921.0 5929.4 6058.5 5798.3 5845.1 5847.7 5849.1 5851.2 18:42:09 INFO - PROCESS | 29734 | 18:42:09 INFO - PROCESS | 29734 | [#1] ext.html Cycles:180 Average:69934.94 Median:40580.90 stddev:143519.28 (353.7%) stddev-sans-first:143827.45 18:42:09 INFO - PROCESS | 29734 | Values: 590.6 616.4 617.5 633.4 638.9 35315.1 48578.8 49206.8 49238.3 49253.0 50028.0 50510.7 50565.7 50578.2 50591.2 8714.6 9377.4 9573.0 9588.3 9589.9 8726.4 11177.8 11388.2 11414.6 11750.4 11575.7 11627.2 11646.4 11646.8 11657.3 11431.1 11473.8 11511.6 11512.9 11516.3 11313.8 11349.8 11367.4 11387.7 11388.0 47549.8 48063.3 48117.8 48178.0 48184.3 3737.3 3794.5 3926.7 4036.5 4042.2 36020.8 36150.3 36151.5 36160.5 37516.6 4101.6 4140.8 4146.6 4160.7 4180.9 379643.1 379932.0 381162.0 381253.0 382456.0 40071.2 41051.0 69766.3 70365.6 70667.6 40363.8 40458.6 40478.4 40550.8 40611.0 17951.6 18067.7 18139.8 18147.0 18153.0 13757.9 13964.9 14888.1 15226.2 15282.2 9710.5 9719.5 9761.0 9766.5 9803.6 3566.2 3613.3 3619.3 3620.9 3711.1 1035.6 1093.1 1093.7 1135.0 1147.7 32863.7 35661.0 35718.0 35961.1 35992.6 1155.5 1173.4 1210.5 1269.2 1278.4 16942.0 18143.8 18550.8 18575.3 18632.3 634780.0 651047.0 651618.0 651986.0 652314.0 66858.7 67660.7 67781.7 67821.3 67906.7 1389.4 1426.4 1468.5 1508.1 1532.3 32596.8 36464.1 36758.0 37014.6 37212.4 57366.1 67881.7 68942.0 69019.3 69151.0 546160.0 554084.0 554627.0 556021.0 556761.0 49842.0 50896.0 51155.7 51458.0 54774.5 53980.0 54271.7 54453.5 54539.7 54840.7 45479.8 45903.5 46314.8 46667.3 47494.5 44687.1 45949.3 46064.8 46136.5 47396.8 46364.0 46401.0 47086.3 47150.1 47689.3 39542.6 41503.4 41625.6 41931.0 42112.6 30720.8 31134.8 31419.6 31563.4 31617.8 18:42:09 INFO - PROCESS | 29734 | 18:42:09 INFO - PROCESS | 29734 | [#2] jquery.html Cycles:180 Average:28407.58 Median:2137.42 stddev:73984.18 (3461.4%) stddev-sans-first:74161.83 18:42:09 INFO - PROCESS | 29734 | Values: 397.1 415.6 426.8 427.1 444.1 15598.7 19324.1 20577.3 20592.9 20952.3 19841.0 20710.8 20711.3 20804.7 20968.4 5856.5 5883.5 6092.8 6095.8 6099.7 5892.7 5940.4 6004.9 6005.1 6022.2 5784.6 5925.7 5995.6 6000.8 6013.2 5778.9 5779.4 5810.9 5818.7 5983.9 5201.6 5205.4 5328.4 5601.9 5639.4 15268.5 15355.5 15623.7 23495.3 24026.8 2363.6 2553.9 2757.9 3050.9 3152.2 10437.6 10873.1 11324.0 14465.0 14607.3 1791.3 1862.9 1894.2 1923.1 1951.6 83455.6 85527.6 85953.6 86343.6 87552.0 10615.3 11715.9 11721.2 12065.0 12081.9 15449.1 15672.2 16504.3 16570.9 17064.6 8913.7 8931.1 9119.4 9142.7 9237.9 8717.0 8758.6 8773.3 9019.8 9054.9 5112.7 5180.6 5199.1 5212.0 5356.7 2023.9 2071.4 2125.2 2149.6 2236.7 1000.5 1005.9 1046.5 1064.8 1097.3 20270.7 20436.0 20561.5 20692.9 20753.3 1010.9 1034.1 1057.6 1087.9 1095.5 10447.7 11421.9 11434.9 11482.8 11605.2 429986.0 430343.0 433885.0 446503.0 487806.0 94567.0 95294.5 96891.0 96986.0 97361.5 1044.9 1063.6 1091.3 1109.9 1114.5 17586.0 18513.4 18626.9 18695.4 19136.2 43879.9 44110.8 44676.6 44907.6 45277.8 55599.0 56932.4 57833.0 57956.0 59800.4 8653.9 8969.9 9123.4 9215.0 9276.3 11569.9 11968.5 13399.9 13789.8 14303.2 12353.9 12454.3 12479.5 12502.4 12772.4 12053.0 12075.7 12124.7 12468.3 12480.8 12108.0 12128.4 12373.3 12575.4 12749.4 8931.2 8992.5 9006.5 9129.2 9408.0 12084.4 12198.1 12643.9 12724.6 12727.6 18:42:09 INFO - PROCESS | 29734 | 18:42:09 INFO - PROCESS | 29734 | [#3] mootools.html Cycles:180 Average:6216.29 Median:472.93 stddev:3570.71 (755.0%) stddev-sans-first:3562.62 18:42:09 INFO - PROCESS | 29734 | Values: 1431.0 1529.5 1562.5 1774.6 1883.8 8174.3 9192.9 9489.1 9492.0 9725.1 9411.8 9444.4 9483.1 9534.2 9542.9 2512.3 2538.5 2540.4 2542.1 2543.2 2460.2 2507.7 2531.9 2533.4 2536.6 2475.5 2497.5 2508.1 2536.3 2538.6 2435.6 2456.8 2463.5 2472.5 2473.0 2401.8 2441.5 2448.0 2450.1 2450.4 9291.4 9337.8 9452.1 9494.9 9500.5 5637.9 5869.9 5878.4 5881.8 5882.3 3512.5 3520.0 3565.4 3567.3 3567.6 1142.7 1146.7 1152.8 1153.5 1173.7 13153.2 13217.4 13264.6 13278.3 13279.9 4788.7 4799.0 4844.9 4945.8 4980.1 10469.8 10471.4 10482.0 10494.4 10499.2 5695.9 5715.0 5726.5 5728.4 5731.3 4609.7 4635.2 5315.7 5333.7 5351.1 4030.1 4039.2 4099.7 4131.9 4142.2 1219.4 1236.2 1236.3 1242.9 1251.8 828.8 967.4 1060.9 1081.2 1103.7 6437.0 6607.0 6678.4 6744.4 6830.7 469.7 471.7 474.2 493.7 494.2 4472.2 4537.6 4557.5 4628.2 4741.9 11609.2 12156.5 12180.4 12184.5 12190.7 11197.3 11496.9 11770.4 11771.2 12042.8 8566.1 8650.7 8757.7 8850.1 8856.7 6434.6 6541.7 6575.6 6618.8 6710.2 10416.1 10652.6 10679.8 10682.9 10980.6 11047.0 11211.9 11290.5 11293.8 11348.8 7693.6 7907.5 8199.1 8272.5 8542.1 9117.8 9425.2 9440.2 9441.9 9442.3 8244.6 8297.8 8344.6 8389.4 8486.5 7770.1 7916.0 8209.3 8213.3 8476.1 7230.9 7351.8 7437.3 7444.6 7519.1 5559.7 5562.1 5702.2 5722.5 5726.1 5361.3 5567.8 5624.0 5785.9 5923.4 18:42:09 INFO - PROCESS | 29734 | 18:42:09 INFO - PROCESS | 29734 | [#4] prototype.html Cycles:180 Average:4889.74 Median:5607.80 stddev:1422.81 (25.4%) stddev-sans-first:1385.51 18:42:09 INFO - PROCESS | 29734 | Values: 355.9 358.4 362.0 365.8 367.2 5534.0 5536.6 5557.5 5566.0 5574.9 5478.9 5511.9 5516.7 5525.9 5538.3 4180.6 4328.9 4362.2 4371.5 4377.8 4344.8 4354.2 4363.5 4364.9 4387.6 4314.6 4326.9 4341.4 4372.3 4377.3 4123.5 4305.6 4316.9 4323.5 4382.1 3776.2 3781.1 3802.9 3804.1 3821.5 5349.2 5419.7 5424.6 5427.7 5444.4 4852.6 4888.3 4912.1 4935.8 4965.7 5645.3 5665.6 5667.9 5694.9 5710.2 4143.7 4151.4 4171.6 4173.1 4192.9 5874.5 5963.5 5984.3 5999.4 6050.7 4298.2 4298.9 4301.4 4313.2 4321.1 4388.5 4391.0 4392.5 4396.5 4409.2 4708.8 4926.6 4937.4 4998.9 4999.0 5758.3 5760.6 5760.6 5766.1 5773.3 1495.3 1507.4 1510.7 1515.8 1515.9 971.0 984.2 1001.3 1044.9 1060.3 5613.9 5616.9 5631.9 5633.2 5653.3 5854.0 5862.5 5879.7 5896.7 5920.6 5397.0 5632.8 5654.7 5696.7 5733.9 3123.9 3150.7 3163.6 3171.3 3174.3 5861.3 6111.4 6124.4 6162.8 6175.4 5741.3 6079.6 6099.3 6109.3 6128.8 5934.2 6004.4 6092.1 6107.3 6156.7 5986.9 5993.4 5996.3 6009.5 6011.6 5515.4 5669.4 5857.9 5858.6 5915.5 6076.8 6116.2 6123.9 6143.9 6173.6 4119.7 4269.5 4271.2 4286.6 4296.8 5837.4 5852.1 5856.3 5939.5 5941.6 5716.9 5755.4 5790.0 5805.8 5821.0 5601.7 5823.3 5834.9 5844.8 5845.0 5774.7 5775.9 5782.3 5785.7 5794.8 5659.0 5866.4 5889.4 5894.0 5906.7 5721.7 5736.5 5750.8 5763.4 5770.4 18:42:09 INFO - PROCESS | 29734 | 18:42:09 INFO - PROCESS | 29734 | [#5] yui.html Cycles:180 Average:22358.46 Median:3706.64 stddev:32972.45 (889.6%) stddev-sans-first:33025.33 18:42:09 INFO - PROCESS | 29734 | Values: 831.4 854.4 855.9 893.6 918.5 16311.0 17383.9 17408.7 17411.8 17467.0 15998.8 16064.1 16072.4 16116.4 16125.2 727.0 748.3 860.6 878.0 881.6 875.8 878.9 879.0 879.0 879.3 928.3 930.2 930.2 930.3 930.5 756.9 773.8 898.2 923.3 930.6 691.5 693.4 693.4 693.8 694.3 16407.2 16437.2 16443.3 16465.2 16466.3 14827.5 14997.6 15056.6 15124.3 15171.3 13741.1 13936.1 13967.0 14029.4 14484.0 4595.9 4621.8 4672.6 4698.6 4710.8 77711.6 79346.0 79415.6 79555.0 84657.6 11486.1 11596.5 11710.4 12037.8 12159.6 23066.9 23107.7 23281.4 23346.6 23372.2 8892.5 8907.3 8915.4 8936.9 9204.2 4953.4 4977.8 5006.8 5071.2 5120.5 3577.2 3667.3 3706.3 3707.0 3713.5 555.0 607.9 620.0 632.8 637.8 702.5 731.9 744.9 745.8 758.5 9335.0 10102.6 10177.7 10231.7 10699.9 656.8 730.2 738.2 790.6 823.2 4377.3 4401.2 4474.1 4518.8 4593.6 131737.0 132564.0 133878.0 134693.0 138036.0 102224.5 104616.0 106214.5 106253.5 110248.4 72611.6 100812.5 100905.5 103575.0 104145.0 8368.2 8413.6 8534.5 8535.5 8584.0 47115.3 59290.0 60257.4 60516.7 61866.7 49265.3 70223.6 73304.0 75517.0 77268.0 13238.9 14534.9 14572.0 14583.2 14588.6 13183.9 13841.0 14739.7 15008.5 15303.7 10740.9 13700.7 14069.4 14285.6 15253.3 13695.5 13837.2 13989.2 14143.9 14488.9 14093.4 14096.6 14264.1 14302.4 14444.0 11812.6 12015.2 12026.8 12397.3 12503.5 11767.8 12263.3 12263.4 12375.3 12475.1 18:42:09 INFO - PROCESS | 29734 | -------- Summary: end -------- 18:42:09 INFO - PROCESS | 29734 | 18:42:10 INFO - TEST-INFO | 29734: exit 0 18:42:10 INFO - TEST-OK | dromaeo_css | took 1113937ms 18:42:10 INFO - TEST-START | kraken 18:42:10 INFO - operating with platform_type : linux_ 18:42:10 INFO - Initialising browser for kraken test... 18:42:10 INFO - TEST-INFO | started process 30055 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPf2XfI/profile http://localhost:44685/getInfo.html) 18:42:13 INFO - PROCESS | 30055 | __metrics Screen width/height:1600/1200 18:42:13 INFO - PROCESS | 30055 | colorDepth:24 18:42:13 INFO - PROCESS | 30055 | Browser inner width/height: 1024/697 18:42:13 INFO - PROCESS | 30055 | __metrics 18:42:14 INFO - PROCESS | 30055 | 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" 18:42:14 INFO - PROCESS | 30055 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:42:15 INFO - PROCESS | 30055 | console.error: PushService: 18:42:15 INFO - PROCESS | 30055 | stateChangeProcessEnqueue: Error transitioning state 18:42:15 INFO - PROCESS | 30055 | UnknownError 18:42:15 INFO - TEST-INFO | 30055: exit 0 18:42:15 INFO - Browser initialized. 18:42:15 INFO - Running cycle 1/1 for kraken test... 18:42:15 INFO - TEST-INFO | started process 30146 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPf2XfI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:42:27 INFO - PROCESS | 30146 | RSS: Main: 144904192 18:42:27 INFO - PROCESS | 30146 | 18:42:30 INFO - PROCESS | 30146 | Cycle 1(1): loaded http://localhost:44685/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44685/tests/kraken/driver.html?testName=audio-beat-detection) 18:42:30 INFO - PROCESS | 30146 | RSS: Main: 186626048 18:42:30 INFO - PROCESS | 30146 | 18:42:33 INFO - PROCESS | 30146 | Cycle 1(1): loaded http://localhost:44685/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44685/tests/kraken/driver.html?testName=audio-dft) 18:42:34 INFO - PROCESS | 30146 | RSS: Main: 176283648 18:42:34 INFO - PROCESS | 30146 | 18:42:36 INFO - PROCESS | 30146 | Cycle 1(1): loaded http://localhost:44685/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44685/tests/kraken/driver.html?testName=audio-fft) 18:42:37 INFO - PROCESS | 30146 | RSS: Main: 266956800 18:42:37 INFO - PROCESS | 30146 | 18:42:39 INFO - PROCESS | 30146 | Cycle 1(1): loaded http://localhost:44685/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44685/tests/kraken/driver.html?testName=audio-oscillator) 18:42:40 INFO - PROCESS | 30146 | RSS: Main: 173326336 18:42:40 INFO - PROCESS | 30146 | 18:42:42 INFO - PROCESS | 30146 | Cycle 1(1): loaded http://localhost:44685/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44685/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:42:42 INFO - PROCESS | 30146 | RSS: Main: 174923776 18:42:42 INFO - PROCESS | 30146 | 18:42:45 INFO - PROCESS | 30146 | Cycle 1(1): loaded http://localhost:44685/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44685/tests/kraken/driver.html?testName=imaging-darkroom) 18:42:46 INFO - PROCESS | 30146 | RSS: Main: 210608128 18:42:46 INFO - PROCESS | 30146 | 18:42:49 INFO - PROCESS | 30146 | Cycle 1(1): loaded http://localhost:44685/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44685/tests/kraken/driver.html?testName=imaging-desaturate) 18:42:50 INFO - PROCESS | 30146 | RSS: Main: 209743872 18:42:50 INFO - PROCESS | 30146 | 18:42:53 INFO - PROCESS | 30146 | Cycle 1(1): loaded http://localhost:44685/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44685/tests/kraken/driver.html?testName=json-parse-financial) 18:42:53 INFO - PROCESS | 30146 | RSS: Main: 209543168 18:42:53 INFO - PROCESS | 30146 | 18:42:55 INFO - PROCESS | 30146 | Cycle 1(1): loaded http://localhost:44685/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44685/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:42:55 INFO - PROCESS | 30146 | RSS: Main: 171290624 18:42:55 INFO - PROCESS | 30146 | 18:42:57 INFO - PROCESS | 30146 | Cycle 1(1): loaded http://localhost:44685/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44685/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:42:57 INFO - PROCESS | 30146 | RSS: Main: 179163136 18:42:57 INFO - PROCESS | 30146 | 18:42:59 INFO - PROCESS | 30146 | Cycle 1(1): loaded http://localhost:44685/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44685/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:43:00 INFO - PROCESS | 30146 | RSS: Main: 183005184 18:43:00 INFO - PROCESS | 30146 | 18:43:03 INFO - PROCESS | 30146 | Cycle 1(1): loaded http://localhost:44685/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44685/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:43:03 INFO - PROCESS | 30146 | RSS: Main: 183971840 18:43:03 INFO - PROCESS | 30146 | 18:43:06 INFO - PROCESS | 30146 | Cycle 1(1): loaded http://localhost:44685/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44685/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:43:06 INFO - PROCESS | 30146 | RSS: Main: 182562816 18:43:06 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | Cycle 1(1): loaded http://localhost:44685/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44685/tests/kraken/driver.html?testName=ai-astar) 18:43:08 INFO - PROCESS | 30146 | RSS: Main: 234315776 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | __start_tp_report 18:43:08 INFO - PROCESS | 30146 | _x_x_mozilla_page_load 18:43:08 INFO - PROCESS | 30146 | _x_x_mozilla_page_load_details 18:43:08 INFO - PROCESS | 30146 | |i|pagename|runs| 18:43:08 INFO - PROCESS | 30146 | |0;ai-astar;108;111;110;111;111;111;111;111;110;110 18:43:08 INFO - PROCESS | 30146 | |1;audio-beat-detection;141;116;116;167;118;116;117;122;151;119 18:43:08 INFO - PROCESS | 30146 | |2;audio-dft;162;161;173;161;169;162;161;160;160;162 18:43:08 INFO - PROCESS | 30146 | |3;audio-fft;74;75;75;107;77;75;76;75;96;75 18:43:08 INFO - PROCESS | 30146 | |4;audio-oscillator;81;81;117;82;82;81;99;81;81;110 18:43:08 INFO - PROCESS | 30146 | |5;imaging-gaussian-blur;117;117;116;117;116;117;117;116;118;118 18:43:08 INFO - PROCESS | 30146 | |6;imaging-darkroom;146;146;147;145;146;146;145;147;146;146 18:43:08 INFO - PROCESS | 30146 | |7;imaging-desaturate;95;95;96;96;96;95;95;95;95;95 18:43:08 INFO - PROCESS | 30146 | |8;json-parse-financial;50;49;47;47;49;47;48;49;48;49 18:43:08 INFO - PROCESS | 30146 | |9;json-stringify-tinderbox;59;60;59;59;59;59;60;67;60;59 18:43:08 INFO - PROCESS | 30146 | |10;stanford-crypto-aes;81;75;73;76;72;72;78;72;73;72 18:43:08 INFO - PROCESS | 30146 | |11;stanford-crypto-ccm;127;118;120;125;128;120;123;133;154;128 18:43:08 INFO - PROCESS | 30146 | |12;stanford-crypto-pbkdf2;153;153;151;153;153;154;153;154;148;151 18:43:08 INFO - PROCESS | 30146 | |13;stanford-crypto-sha256-iterative;63;62;62;61;61;63;62;62;61;61 18:43:08 INFO - PROCESS | 30146 | __end_tp_report 18:43:08 INFO - PROCESS | 30146 | __start_cc_report 18:43:08 INFO - PROCESS | 30146 | _x_x_mozilla_cycle_collect,1585 18:43:08 INFO - PROCESS | 30146 | __end_cc_report 18:43:08 INFO - PROCESS | 30146 | __startTimestamp1474335788810__endTimestamp 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | ------- Summary: start ------- 18:43:08 INFO - PROCESS | 30146 | Number of tests: 14 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:0.97 (0.9%) stddev-sans-first:0.50 18:43:08 INFO - PROCESS | 30146 | Values: 108.0 111.0 110.0 111.0 111.0 111.0 111.0 111.0 110.0 110.0 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:120.50 stddev:18.22 (15.1%) stddev-sans-first:18.74 18:43:08 INFO - PROCESS | 30146 | Values: 141.0 116.0 116.0 167.0 118.0 116.0 117.0 122.0 151.0 119.0 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | [#2] audio-dft Cycles:10 Average:163.10 Median:162.00 stddev:4.33 (2.7%) stddev-sans-first:4.58 18:43:08 INFO - PROCESS | 30146 | Values: 162.0 161.0 173.0 161.0 169.0 162.0 161.0 160.0 160.0 162.0 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | [#3] audio-fft Cycles:10 Average:80.50 Median:75.00 stddev:11.39 (15.2%) stddev-sans-first:11.84 18:43:08 INFO - PROCESS | 30146 | Values: 74.0 75.0 75.0 107.0 77.0 75.0 76.0 75.0 96.0 75.0 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | [#4] audio-oscillator Cycles:10 Average:89.50 Median:81.00 stddev:13.91 (17.2%) stddev-sans-first:14.41 18:43:08 INFO - PROCESS | 30146 | Values: 81.0 81.0 117.0 82.0 82.0 81.0 99.0 81.0 81.0 110.0 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 18:43:08 INFO - PROCESS | 30146 | Values: 117.0 117.0 116.0 117.0 116.0 117.0 117.0 116.0 118.0 118.0 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 18:43:08 INFO - PROCESS | 30146 | Values: 146.0 146.0 147.0 145.0 146.0 146.0 145.0 147.0 146.0 146.0 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 18:43:08 INFO - PROCESS | 30146 | Values: 95.0 95.0 96.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | [#8] json-parse-financial Cycles:10 Average:48.30 Median:49.00 stddev:1.06 (2.2%) stddev-sans-first:0.93 18:43:08 INFO - PROCESS | 30146 | Values: 50.0 49.0 47.0 47.0 49.0 47.0 48.0 49.0 48.0 49.0 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:59.50 stddev:2.47 (4.2%) stddev-sans-first:2.59 18:43:08 INFO - PROCESS | 30146 | Values: 59.0 60.0 59.0 59.0 59.0 59.0 60.0 67.0 60.0 59.0 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | [#10] stanford-crypto-aes Cycles:10 Average:74.40 Median:74.00 stddev:3.10 (4.2%) stddev-sans-first:2.18 18:43:08 INFO - PROCESS | 30146 | Values: 81.0 75.0 73.0 76.0 72.0 72.0 78.0 72.0 73.0 72.0 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | [#11] stanford-crypto-ccm Cycles:10 Average:127.60 Median:127.50 stddev:10.34 (8.1%) stddev-sans-first:10.97 18:43:08 INFO - PROCESS | 30146 | Values: 127.0 118.0 120.0 125.0 128.0 120.0 123.0 133.0 154.0 128.0 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.30 Median:153.00 stddev:1.83 (1.2%) stddev-sans-first:1.92 18:43:08 INFO - PROCESS | 30146 | Values: 153.0 153.0 151.0 153.0 153.0 154.0 153.0 154.0 148.0 151.0 18:43:08 INFO - PROCESS | 30146 | 18:43:08 INFO - PROCESS | 30146 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:0.79 (1.3%) stddev-sans-first:0.71 18:43:08 INFO - PROCESS | 30146 | Values: 63.0 62.0 62.0 61.0 61.0 63.0 62.0 62.0 61.0 61.0 18:43:08 INFO - PROCESS | 30146 | -------- Summary: end -------- 18:43:08 INFO - PROCESS | 30146 | 18:43:09 INFO - TEST-INFO | 30146: exit 0 18:43:09 INFO - TEST-OK | kraken | took 59708ms 18:43:09 INFO - SUITE-END | took 1173s 18:43:09 INFO - Completed test suite (00:19:33) 18:43:09 INFO - javascript benchmark 18:43:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3106.223444138965, 3146.323540438805, 3151.4012337655845, 3162.320101178246, 3193.233623930256, 5549.77800887964, 5645.379008309083, 5650.660960342378, 5666.065099258617, 5696.401877802833, 5643.68959707216, 5701.74342154603, 5702.230198923144, 5711.485966263035, 5745.683987380692, 5130.563902068319, 5159.690418574897, 5173.042987047389, 5190.844274671747, 5237.083510385686, 5033.6728112672745, 5087.7710503027265, 5248.0815857225025, 5248.790048398059, 5267.552258058051, 5097.847064588068, 5145.6398052136365, 5219.00838840621, 5219.582433405338, 5222.9477705223035, 5120.0527902338, 5155.458676838915, 5195.506426889439, 5202.843914682566, 5215.139501982168, 5129.410117836422, 5214.843554693365, 5223.71269580173, 5224.4775522447835, 5259.631825772198, 5484.341878974548, 5520.199571062214, 5675.801346952837, 5713.514351280146, 5790.942090579082, 5974.910376344358, 6022.156898034279, 6023.247094113236, 6032.698365081728, 6052.0, 6062.090686397031, 6149.5080393568405, 6161.537884658668, 6164.476019538344, 6170.568060235785, 5485.149801780731, 5523.337199536081, 5719.313682358102, 5723.51350135239, 5728.169415434739, 6136.641621527207, 6212.534059945522, 6216.471599914058, 6299.118123462719, 6323.683815809191, 6053.576249662481, 6065.484433823086, 6068.21113255274, 6069.0592958091565, 6070.726817293233, 5854.736536855852, 5896.911546326808, 6138.171346868205, 6147.907781383283, 6157.599756015934, 5987.281526216795, 6051.425114614105, 6053.576249662569, 6126.479249263773, 6130.601510901777, 6041.093835924558, 6090.147379366892, 6100.542459315569, 6103.1455596216565, 6124.142620033199, 4624.468186158568, 4654.488006319302, 4669.859904202879, 4670.229412147026, 4697.0, 4014.1770936957855, 4049.635532802003, 4050.736702114353, 4053.43251944728, 4080.5511393746665, 5797.47822695951, 5800.158976948319, 5804.0, 5804.593678442468, 5831.533477321804, 6100.481459076028, 6118.847028824315, 6122.142899994004, 6130.570860039755, 6130.63216207029, 5607.43925607436, 5648.604597678123, 5784.1902133701315, 5799.594028417971, 5842.298924129132, 5287.339082614673, 5292.92060619091, 5296.761645725952, 5304.177852432879, 5331.466853314715, 6136.7852125175405, 6145.784897528654, 6194.442500174916, 6203.689815509207, 6205.162303089115, 5989.850253743692, 5995.850103747442, 6048.455638992512, 6123.969380153071, 6125.081237814364, 6179.0422484514975, 6244.718987645476, 6255.249370075438, 6273.0, 6274.592151510137, 5957.0, 5960.215263307918, 5960.791372301948, 5963.135345374983, 5963.671998040149, 5867.149263356704, 5886.646801191942, 6052.969735151296, 6058.636481811105, 6065.211522502046, 5810.302763668218, 5835.387284335084, 5859.5019423349495, 5909.881802363842, 5913.201717768046, 5901.852453688693, 5905.763769449174, 5909.054551271776, 5912.793052243151, 5913.46778789911, 5682.829515114553, 5837.591368604157, 5838.766449341979, 5841.737121829613, 5844.620099693507, 5878.0, 5915.881682366415, 5921.023031199804, 5929.407059294036, 6058.485028772604, 5798.33319168301, 5845.09401042839, 5847.678377689268, 5849.093390524476, 5851.21008663842], "value": 5584.888467786347, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.5541316306188, 616.3805375597524, 617.5461036138438, 633.4172561243655, 638.9041643753436, 35315.117122071926, 48578.75710621446, 49206.75396623021, 49238.261426078636, 49253.0, 50028.0, 50510.747446262714, 50565.74717126409, 50578.241326380135, 50591.241131382965, 8714.564271786418, 9377.437353758796, 9573.0, 9588.328816982814, 9589.856152157723, 8726.432781869189, 11177.776444471107, 11388.202825802196, 11414.600488982887, 11750.353730544817, 11575.652730418058, 11627.186096973215, 11646.359450230233, 11646.767064658701, 11657.300561966267, 11431.085513158927, 11473.770524589545, 11511.597094101666, 11512.884871151266, 11516.30902145874, 11313.83029254562, 11349.829752553718, 11367.374794386305, 11387.715307117389, 11388.0, 47549.76225118852, 48063.279050813915, 48117.75941120307, 48178.0, 48184.27723584149, 3737.3272810894027, 3794.5067141271734, 3926.725129240954, 4036.495438070241, 4042.151148258869, 36020.81989590061, 36150.276994459964, 36151.4577281341, 36160.457593136125, 37516.624833751586, 4101.610347017028, 4140.8136633851545, 4146.626803587661, 4160.7087503874745, 4180.937285940683, 379643.1017844921, 379932.0, 381162.0, 381253.0, 382456.0, 40071.1985760289, 41051.0, 69766.302336977, 70365.64817175882, 70667.64666176637, 40363.79818100949, 40458.595414046074, 40478.392824107665, 40550.79724601358, 40611.0, 17951.551211219823, 18067.728984064986, 18139.818601814077, 18147.0, 18153.0, 13757.862421375658, 13964.930175349262, 14888.106713597219, 15226.238688065552, 15282.235888205767, 9710.514474276259, 9719.514024298756, 9761.0, 9766.511674416251, 9803.558839852223, 3566.2154326048226, 3613.3495970725, 3619.2942376236906, 3620.855165793339, 3711.0907827582414, 1035.6064695415694, 1093.1145771924766, 1093.704699731068, 1134.982975255372, 1147.65570328901, 32863.671363286536, 35661.0, 35718.0, 35961.1009724759, 35992.64007359893, 1155.5204590095184, 1173.3839734139644, 1210.4794938176492, 1269.2194300505123, 1278.4055414232241, 16942.0, 18143.818561814478, 18550.814491855177, 18575.25698972053, 18632.25470981173, 634780.0, 651047.0, 651618.0, 651986.0, 652314.0, 66858.66570667212, 67660.6616966912, 67781.66109169423, 67821.32178678249, 67906.66046669833, 1389.3747813483972, 1426.3652674559721, 1468.4786900650543, 1508.0951429142647, 1532.26451303375, 32596.83701581477, 36464.08839779027, 36758.0, 37014.629853702194, 37212.44181337336, 57366.13950790825, 67881.66059169672, 68942.0, 69019.30980690129, 69151.0, 546160.0, 554084.0, 554627.0, 556021.0, 556761.0, 49842.0, 50896.0, 51155.744221278655, 51458.0, 54774.45225547853, 53980.0, 54271.72864135812, 54453.45546544484, 54539.72730136324, 54840.72579637076, 45479.77260113811, 45903.54096458993, 46314.76842615765, 46667.29999050085, 47494.52505475039, 44687.10625787531, 45949.31076033929, 46064.7696761514, 46136.53863461322, 47396.7630161847, 46364.0, 46401.0, 47086.293705595126, 47150.05699886052, 47689.28466073081, 39542.60457395389, 41503.377449338885, 41625.583744163385, 41931.0, 42112.57887421209, 30720.84639576788, 31134.844325779133, 31419.565852040876, 31563.36873262476, 31617.8419107903], "value": 21758.90426724279, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.14613580801256, 415.56573380817025, 426.8207352911777, 427.1116078556603, 444.08296866969704, 15598.688026239468, 19324.130414131363, 20577.27979520717, 20592.897035514838, 20952.26667066653, 19841.0, 20710.792892071076, 20711.275105371315, 20804.68792968103, 20968.37094887152, 5856.47291743743, 5883.499902508282, 6092.756289748405, 6095.817125486243, 6099.725512351933, 5892.734826932777, 5940.3762604926505, 6004.939950600482, 6005.099235114724, 6022.187004754368, 5784.595078344518, 5925.674087925162, 5995.550333724967, 6000.849978750544, 6013.218281623383, 5778.913316300259, 5779.393163717812, 5810.912836307417, 5818.650880947135, 5983.940160598382, 5201.5838732901275, 5205.427402985669, 5328.360596728378, 5601.943980560183, 5639.351474580435, 15268.541943741706, 15355.4625588105, 15623.687526249525, 23495.295141145794, 24026.75973240263, 2363.645453182019, 2553.9361515962064, 2757.9310517237027, 3050.8932187373334, 3152.243461569207, 10437.582496700123, 10873.130149588093, 11324.03745681618, 14465.0, 14607.342669579788, 1791.3013924569427, 1862.850971922243, 1894.1760334254643, 1923.1345894347598, 1951.5706544560173, 83455.582722086, 85527.57236213778, 85953.57023214844, 86343.56828215881, 87552.0, 10615.25693201484, 11715.882841171479, 11721.238119522204, 12065.0, 12081.939590301992, 15449.073055616698, 15672.216389180496, 16504.33982640681, 16570.917145414194, 17064.57338566546, 8913.688020919237, 8931.106889311146, 9119.407238529475, 9142.680006199884, 9237.861432078524, 8717.041125476193, 8758.605862736193, 8773.254273386707, 9019.819603608023, 9054.909450905538, 5112.744362781846, 5180.637355385087, 5199.090159222121, 5212.0, 5356.651817631841, 2023.8583299168918, 2071.4096482502414, 2125.224293132995, 2149.623815832223, 2236.7204099487562, 1000.5497526113278, 1005.9265425858713, 1046.4767616191905, 1064.8136576099153, 1097.3470784882982, 20270.69593956092, 20436.0, 20561.48596285105, 20692.896535517528, 20753.273635422687, 1010.8584798128268, 1034.0900007992918, 1057.6404022632341, 1087.8748943871556, 1095.4522738630685, 10447.738806529745, 11421.885781142248, 11434.942825285987, 11482.770344593062, 11605.245659032136, 429986.0, 430343.0, 433885.0, 446503.0, 487806.0487278081, 94567.0, 95294.52352738191, 96891.0, 96986.0, 97361.51319243359, 1044.9059584637419, 1063.6011495689118, 1091.3233795047122, 1109.9223054386277, 1114.5486078138206, 17586.03276819787, 18513.35203267879, 18626.906865466128, 18695.439136825917, 19136.23455061782, 43879.90300242392, 44110.77944610256, 44676.55323446724, 44907.55092449165, 45277.773611131735, 55599.0, 56932.43067569271, 57833.0, 57956.0, 59800.40199597948, 8653.870191947, 8969.865452018355, 9123.406978546373, 9215.0, 9276.304277179373, 11569.942150289195, 11968.46141923633, 13399.933000334935, 13789.793153102511, 14303.21332326732, 12353.93823030915, 12454.25274483534, 12479.500819967463, 12502.43739031764, 12772.361381931052, 12053.0, 12075.69810754703, 12124.669500815933, 12468.251904885374, 12480.812787808372, 12108.031357491203, 12128.393580321124, 12373.257604543756, 12575.434105465092, 12749.426275817425, 8931.240844528287, 8992.46045237288, 9006.54967251648, 9129.178373946377, 9408.012158723235, 12084.39578021113, 12198.146129771187, 12643.87356126427, 12724.554640587536, 12727.554535591209], "value": 9657.10180268319, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1431.026901706839, 1529.4876216467487, 1562.523430353742, 1774.6007148391614, 1883.76452943382, 8174.305184059354, 9192.862107068391, 9489.098535639103, 9492.003339649333, 9725.12473877351, 9411.764705882357, 9444.433333999965, 9483.099105584959, 9534.23726101912, 9542.856857147131, 2512.3216731482535, 2538.4796116796015, 2540.4030052937546, 2542.1102614085107, 2543.186180422266, 2460.1635443949053, 2507.661465702134, 2531.898724051036, 2533.366658335416, 2536.644869718241, 2475.517274131545, 2497.4880149569294, 2508.147229941819, 2536.2898388451263, 2538.6065159900245, 2435.6468312094735, 2456.803455723547, 2463.5072985402903, 2472.4807790364043, 2473.0231558534456, 2401.7598240176017, 2441.4506735984437, 2448.033026954343, 2450.0567281596536, 2450.3506570758764, 9291.396059256127, 9337.813243735156, 9452.149306562374, 9494.857577136348, 9500.524973751284, 5637.8590535236535, 5869.85325366865, 5878.441548052928, 5881.764729410818, 5882.264716910387, 3512.4731290306404, 3519.982400087983, 3565.447355659877, 3567.250877315767, 3567.554055743032, 1142.737170450801, 1146.656003199037, 1152.8443660105863, 1153.4809335798925, 1173.724174818917, 13153.21080735159, 13217.405216765172, 13264.602061938158, 13278.269695166762, 13279.86720132796, 4788.7366194859615, 4799.0, 4844.903101937942, 4945.777440015136, 4980.12847751642, 10469.790604187874, 10471.371717696962, 10482.0, 10494.42280674555, 10499.212559057949, 5695.886082278331, 5715.0, 5726.455986681259, 5728.427157284238, 5731.340895796953, 4609.746463944515, 4635.188841952645, 5315.654482458628, 5333.7066461345, 5351.09031464652, 4030.133521292936, 4039.1921615676983, 4099.733517321364, 4131.8967025823995, 4142.171565686875, 1219.3781171602548, 1236.1532350340046, 1236.3262022197923, 1242.931638759877, 1251.8059700746398, 828.846663367279, 967.4243824924159, 1060.9098226650822, 1081.210716177195, 1103.7351035751362, 6436.967815160894, 6606.966965165239, 6678.365555272241, 6744.359285867835, 6830.726770929206, 469.7017393954795, 471.67690132259514, 474.1749356120326, 493.65690844862473, 494.2067980890691, 4472.217361961643, 4537.6143027843, 4557.475890272594, 4628.213203755369, 4741.928871066937, 11609.245399049034, 12156.452959616838, 12180.390980450942, 12184.512619495299, 12190.695232619255, 11197.272177308449, 11496.94251528737, 11770.411479425995, 11771.176017678852, 12042.759144817055, 8566.100559441169, 8650.697225597074, 8757.737267882101, 8850.114988501098, 8856.690015849543, 6434.58175218628, 6541.70562324687, 6575.605463672195, 6618.8345291368105, 6710.194776626836, 10416.114630256514, 10652.57389704403, 10679.839802403125, 10682.946585267024, 10980.615678451215, 11047.0, 11211.88788112141, 11290.548378064785, 11293.830592540955, 11348.773024539629, 7693.576853273124, 7907.48601340911, 8199.098099208963, 8272.545010024507, 8542.145785421408, 9117.817643646957, 9425.245980321695, 9440.197583205505, 9441.905580944103, 9442.291828112782, 8244.587770611566, 8297.792555185928, 8344.624491897757, 8389.412741108064, 8486.490810551386, 7770.144773923818, 7916.0, 8209.343252539902, 8213.301869340934, 8476.110008449024, 7230.891536626849, 7351.8162045949275, 7437.330640242404, 7444.553326800409, 7519.135299440634, 5559.72201389937, 5562.082256427806, 5702.173184888251, 5722.484976352148, 5726.141078838207, 5361.329833770778, 5567.777288908398, 5624.043912534797, 5785.913211301918, 5923.378045305181], "value": 4863.171590872287, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.9483874838148, 358.446200620042, 361.95656521217455, 365.8189196347808, 367.21782603055505, 5534.0, 5536.640118392312, 5557.499825015753, 5565.972170139149, 5574.916376254349, 5478.890422191554, 5511.944880551193, 5516.724163791814, 5525.944740552603, 5538.252335934648, 4180.5610410906875, 4328.852853993694, 4362.19299429605, 4371.519132895379, 4377.774223217504, 4344.761038142901, 4354.216241076605, 4363.454568178978, 4364.890877728067, 4387.648988080946, 4314.590113939171, 4326.875012496742, 4341.39220509129, 4372.27857403528, 4377.277749171383, 4123.546409894924, 4305.5694430557005, 4316.935245971313, 4323.48118225815, 4382.145481631083, 3776.2258736958856, 3781.054736315921, 3802.9429558556644, 3804.144067584799, 3821.522309711286, 5349.197620356938, 5419.729013549346, 5424.620276580641, 5427.701476418795, 5444.40111587725, 4852.592748103046, 4888.291197776302, 4912.115819152551, 4935.827246046407, 4965.702057876539, 5645.294338207724, 5665.583604098975, 5667.886642267173, 5694.914576281359, 5710.200571919934, 4143.7306575072525, 4151.377293405983, 4171.581662234855, 4173.144505376391, 4192.937105943413, 5874.530037597025, 5963.522918166536, 5984.324389171039, 5999.400059994009, 6050.727717252734, 4298.161858437575, 4298.871033868926, 4301.440812694393, 4313.245182093184, 4321.077120681267, 4388.4733831940375, 4390.978045109754, 4392.450943632046, 4396.516383197845, 4409.184300904343, 4708.8351907683755, 4926.630502712239, 4937.395346512398, 4998.925016124761, 4998.975005124951, 5758.3090029196765, 5760.567957403127, 5760.588655779361, 5766.135079738073, 5773.268019594105, 1495.274791726012, 1507.366905899525, 1510.6903084867577, 1515.8408367121515, 1515.9317830697646, 970.9757256068514, 984.242133557157, 1001.33911618333, 1044.8693913260843, 1060.3266925502207, 5613.887722245532, 5616.9047035827625, 5631.943680563224, 5633.183188437653, 5653.265075540154, 5853.975554277984, 5862.505061209365, 5879.735411906475, 5896.705164741831, 5920.644761314335, 5396.97457483077, 5632.8028519002455, 5654.717264136777, 5696.686682232434, 5733.913991290134, 3123.8754048542505, 3150.7239567975007, 3163.5922014703697, 3171.3340198558562, 3174.2857856981996, 5861.296644402699, 6111.419415155553, 6124.448799608057, 6162.784302549389, 6175.3515880832765, 5741.311042674905, 6079.574429789872, 6099.268087829489, 6109.25886122453, 6128.78549250783, 5934.228550288522, 6004.399560044048, 6092.055731361646, 6107.267127944675, 6156.661383623765, 5986.922353976326, 5993.362920315048, 5996.340402555803, 6009.549283803644, 6011.639301641915, 5515.393306736176, 5669.412564481951, 5857.941420585739, 5858.648481091148, 5915.497182739343, 6076.817695469143, 6116.235470566179, 6123.897698414328, 6143.938560614515, 6173.5736666150715, 4119.691023173334, 4269.530351661377, 4271.209826182167, 4286.61420472159, 4296.82812687489, 5837.416258374129, 5852.122181672783, 5856.297244330707, 5939.4951429129005, 5941.554383421347, 5716.914246286392, 5755.424457554211, 5790.0, 5805.796797112081, 5821.0, 5601.719914004365, 5823.330317013598, 5834.912476312774, 5844.824655260349, 5845.0, 5774.718986000422, 5775.9133612995, 5782.306123265235, 5785.652860828363, 5794.7682092717505, 5658.971705141613, 5866.442687944638, 5889.38161493037, 5894.0, 5906.692252338704, 5721.682718498848, 5736.512396446349, 5750.769969201185, 5763.423657634203, 5770.422957704196], "value": 4442.080540138289, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [831.4346244553705, 854.4189950833432, 855.858783300755, 893.6470094312747, 918.4948278446852, 16310.999999999985, 17383.913080434613, 17408.65182696347, 17411.825881741195, 17466.951982881004, 15998.760018599702, 16064.116473593947, 16072.357105715757, 16116.435924742636, 16125.193740312967, 726.9890951635716, 748.2741740511694, 860.5912191708951, 877.9648814047431, 881.568031664483, 875.7810547363159, 878.8769572259889, 878.9560521973908, 879.0462348352014, 879.2658130461073, 928.2805825485235, 930.186087173723, 930.2465003347277, 930.2743859789375, 930.5300823084333, 756.9243075692442, 773.8336257704592, 898.1961144775422, 923.2936803345447, 930.6230976454547, 691.4675699711195, 693.4071368979501, 693.4348505967671, 693.7710555516668, 694.3091623834317, 16407.17964101802, 16437.178141092896, 16443.342266309333, 16465.176741162893, 16466.341346346133, 14827.517248275193, 14997.625059373493, 15056.623584410478, 15124.319405626773, 15171.317290721834, 13741.106828056145, 13936.094153880067, 13967.0, 14029.368678409495, 14484.0, 4595.862124136248, 4621.792019359137, 4672.602828759559, 4698.600618947394, 4710.78801453932, 77711.611441943, 79346.0, 79415.60292198502, 79555.0, 84657.57671211666, 11486.138539609563, 11596.478158482889, 11710.414479276002, 12037.819432708517, 12159.635210943687, 23066.884665576566, 23107.653385199235, 23281.37030407889, 23346.64980025301, 23372.181973630843, 8892.466452012899, 8907.331950103768, 8915.42049766763, 8936.910630893737, 9204.171624553823, 4953.380827396575, 4977.800887964513, 5006.849794506171, 5071.163258062378, 5120.48795120485, 3577.2130131371055, 3667.284879448482, 3706.29580379727, 3706.9814650926573, 3713.4801127842125, 554.9694766787866, 607.9270487541523, 619.9535034872313, 632.8164832198703, 637.7640273098983, 702.4836744992426, 731.8572878288683, 744.8510297940326, 745.7576287706518, 758.4539131825077, 9334.953325233331, 10102.614829691338, 10177.694669159937, 10231.7442063949, 10699.893001070046, 656.7916422767405, 730.204077555467, 738.1916801102784, 790.5691398188002, 823.1768231768232, 4377.255866502702, 4401.163778882003, 4474.149911516802, 4518.774061296987, 4593.6219134260255, 131737.0, 132564.0, 133878.0, 134693.0, 138036.0, 102224.48887755662, 104616.0, 106214.46892765487, 106253.46873265585, 110248.4487577557, 72611.63694181496, 100812.49593752131, 100905.49547252363, 103575.0, 104145.0, 8368.24685778283, 8413.621387037476, 8534.530600817016, 8535.487870727777, 8583.957080214559, 47115.29327060165, 59290.0, 60257.39742602518, 60516.69741651264, 61866.690666546376, 49265.26102108399, 70223.64888175526, 73304.0, 75517.0, 77268.0, 13238.89567798105, 14534.92732536373, 14572.0, 14583.197924114276, 14588.562343129723, 13183.868161318263, 13841.0, 14739.705205896036, 15008.47470338533, 15303.693926121638, 10740.946295268786, 13700.657483562592, 14069.437222510986, 14285.57143285703, 15253.313600888208, 13695.520656777364, 13837.169769813843, 13989.160650361011, 14143.929280353945, 14488.927555362156, 14093.436262549383, 14096.647583810487, 14264.144151350953, 14302.427902883768, 14444.0, 11812.586559470377, 12015.158938874194, 12026.819597705866, 12397.256164630151, 12503.499860005499, 11767.823482647938, 12263.26420414778, 12263.448144833683, 12375.257484550955, 12475.126741128033], "value": 8006.693353193167, "unit": "score"}], "name": "dromaeo_css", "value": 7666.261124368784, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 111.0, 110.0, 111.0, 111.0, 111.0, 111.0, 111.0, 110.0, 110.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 116.0, 116.0, 167.0, 118.0, 116.0, 117.0, 122.0, 151.0, 119.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 173.0, 161.0, 169.0, 162.0, 161.0, 160.0, 160.0, 162.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 107.0, 77.0, 75.0, 76.0, 75.0, 96.0, 75.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 117.0, 82.0, 82.0, 81.0, 99.0, 81.0, 81.0, 110.0], "value": 89.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 118.0, 118.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 145.0, 146.0, 146.0, 145.0, 147.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 47.0, 47.0, 49.0, 47.0, 48.0, 49.0, 48.0, 49.0], "value": 48.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 60.0, 67.0, 60.0, 59.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 75.0, 73.0, 76.0, 72.0, 72.0, 78.0, 72.0, 73.0, 72.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 118.0, 120.0, 125.0, 128.0, 120.0, 123.0, 133.0, 154.0, 128.0], "value": 127.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 151.0, 153.0, 153.0, 154.0, 153.0, 154.0, 148.0, 151.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 61.0, 61.0, 63.0, 62.0, 62.0, 61.0, 61.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1454.4999999999998, "alertThreshold": 2.0}]} 18:43:10 INFO - Return code: 0 18:43:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:43:10 INFO - # TBPL SUCCESS # 18:43:10 INFO - Running post-action listener: _resource_record_post_action 18:43:10 INFO - [mozharness: 2016-09-20 01:43:10.130078Z] Finished run-tests step (success) 18:43:10 INFO - Running post-run listener: _resource_record_post_run 18:43:10 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 355004416; Read time: 12; Write time: 291164 18:43:10 INFO - TinderboxPrint: CPU usage
12.9% 18:43:10 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 18:43:10 INFO - TinderboxPrint: I/O write bytes / time
355,004,416 / 291,164 18:43:10 INFO - TinderboxPrint: CPU idle
8,267.4 (87.0%) 18:43:10 INFO - TinderboxPrint: CPU user
1,183.5 (12.5%) 18:43:10 INFO - TinderboxPrint: Swap in / out
0 / 0 18:43:10 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107917312; Read time: 0; Write time: 110652 18:43:10 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 246624256; Read time: 0; Write time: 180508 18:43:10 INFO - Running post-run listener: _upload_blobber_files 18:43:10 INFO - Blob upload gear active. 18:43:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:43:10 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 18:43:10 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:43:10 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:43:11 INFO - (blobuploader) - INFO - Open directory for files ... 18:43:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:43:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:43:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:11 INFO - (blobuploader) - INFO - Done attempting. 18:43:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:43:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:43:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:12 INFO - (blobuploader) - INFO - Done attempting. 18:43:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:43:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:43:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:16 INFO - (blobuploader) - INFO - Done attempting. 18:43:16 INFO - (blobuploader) - INFO - Iteration through files over. 18:43:16 INFO - Return code: 0 18:43:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:43:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:43:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c122a744a2ed8bacb8618c3d22e34863720ddc89e3073e3da83601a45470f4a20ccac580d1b86e68b0d986b486df4da02929c7c0d57782567d965eb41ffe67dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83fae3272f16ac574d9fad9910a624c1abf739ca33c05c9571bc2659921dab2c571ec063c887218ab981cf7d81692cc8f7f0895576ced707c8bca0570ba85162", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e9ee8efb122009b108d9934ba209402ce49eab25bfeeefc9503f4ba2603ae88ff5f15e09c7d923ae698e600910e06297b7d75c7bd888501a41c215dc265a1eab"} 18:43:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:43:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:43:16 INFO - Contents: 18:43:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c122a744a2ed8bacb8618c3d22e34863720ddc89e3073e3da83601a45470f4a20ccac580d1b86e68b0d986b486df4da02929c7c0d57782567d965eb41ffe67dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83fae3272f16ac574d9fad9910a624c1abf739ca33c05c9571bc2659921dab2c571ec063c887218ab981cf7d81692cc8f7f0895576ced707c8bca0570ba85162", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e9ee8efb122009b108d9934ba209402ce49eab25bfeeefc9503f4ba2603ae88ff5f15e09c7d923ae698e600910e06297b7d75c7bd888501a41c215dc265a1eab"} 18:43:16 INFO - Running post-run listener: copy_logs_to_upload_dir 18:43:16 INFO - Copying logs to upload dir... 18:43:16 INFO - mkdir: /builds/slave/test/build/upload/logs 18:43:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.307866 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-09-19 18:43:15.932199) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 18:43:15.936162) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c122a744a2ed8bacb8618c3d22e34863720ddc89e3073e3da83601a45470f4a20ccac580d1b86e68b0d986b486df4da02929c7c0d57782567d965eb41ffe67dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83fae3272f16ac574d9fad9910a624c1abf739ca33c05c9571bc2659921dab2c571ec063c887218ab981cf7d81692cc8f7f0895576ced707c8bca0570ba85162", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e9ee8efb122009b108d9934ba209402ce49eab25bfeeefc9503f4ba2603ae88ff5f15e09c7d923ae698e600910e06297b7d75c7bd888501a41c215dc265a1eab"} build_url:https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011293 build_url: 'https://queue.taskcluster.net/v1/task/Hx9jQF1XT5WHkA9evC7iTQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c122a744a2ed8bacb8618c3d22e34863720ddc89e3073e3da83601a45470f4a20ccac580d1b86e68b0d986b486df4da02929c7c0d57782567d965eb41ffe67dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83fae3272f16ac574d9fad9910a624c1abf739ca33c05c9571bc2659921dab2c571ec063c887218ab981cf7d81692cc8f7f0895576ced707c8bca0570ba85162", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e9ee8efb122009b108d9934ba209402ce49eab25bfeeefc9503f4ba2603ae88ff5f15e09c7d923ae698e600910e06297b7d75c7bd888501a41c215dc265a1eab"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 18:43:15.962003) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:43:15.962333) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474334561.305927-1430172003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004539 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:43:16.044618) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 18:43:16.044975) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 18:43:16.045286) ========= ========= Total master_lag: 0.17 =========