builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-030 starttime: 1462485703.08 results: success (0) buildid: 20160505123841 builduid: 1457772db7ef4c1982b4796b67a4914d revision: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 15:01:43.077767) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 15:01:43.078839) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 15:01:43.079591) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 15:01:43.098935) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 15:01:43.099671) ========= 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=4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-05 15:01:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2016-05-05 15:01:43 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091174 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 15:01:43.206638) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 15:01:43.207005) ========= 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=4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016120 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 15:01:43.256641) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 15:01:43.257035) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b --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 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b --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=4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-05 15:01:43,274 truncating revision to first 12 chars 2016-05-05 15:01:43,274 Setting DEBUG logging. 2016-05-05 15:01:43,274 attempt 1/10 2016-05-05 15:01:43,274 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0123e9f41ced?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-05 15:01:43,528 unpacking tar archive at: mozilla-aurora-0123e9f41ced/testing/mozharness/ program finished with exit code 0 elapsedTime=0.546249 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 15:01:43.816895) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 15:01:43.817266) ========= script_repo_revision: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 15:01:43.817800) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 15:01:43.818125) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 15:01:43.828761) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-05-05 15:01:43.829085) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:01:43 INFO - MultiFileLogger online at 20160505 15:01:43 in /builds/slave/test 15:01:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:01:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:01:43 INFO - {'append_to_log': False, 15:01:43 INFO - 'base_work_dir': '/builds/slave/test', 15:01:43 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:01:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:01:43 INFO - 'branch': 'Mozilla-Aurora', 15:01:43 INFO - 'buildbot_json_path': 'buildprops.json', 15:01:43 INFO - 'config_files': ('talos/linux_config.py',), 15:01:43 INFO - 'default_actions': ('clobber', 15:01:43 INFO - 'read-buildbot-config', 15:01:43 INFO - 'download-and-extract', 15:01:43 INFO - 'populate-webroot', 15:01:43 INFO - 'create-virtualenv', 15:01:43 INFO - 'install', 15:01:43 INFO - 'run-tests'), 15:01:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:01:43 INFO - 'download_minidump_stackwalk': True, 15:01:43 INFO - 'download_symbols': 'ondemand', 15:01:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:01:43 INFO - 'tooltool.py': '/tools/tooltool.py', 15:01:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:01:43 INFO - '/tools/misc-python/virtualenv.py')}, 15:01:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:01:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:01:43 INFO - 'installer_path': 'installer.exe', 15:01:43 INFO - 'log_level': 'info', 15:01:43 INFO - 'log_name': 'talos', 15:01:43 INFO - 'log_to_console': True, 15:01:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:01:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:01:43 INFO - 'opt_config_files': (), 15:01:43 INFO - 'pip_index': False, 15:01:43 INFO - 'sps_profile': False, 15:01:43 INFO - 'sps_profile_interval': 0, 15:01:43 INFO - 'suite': 'dromaeojs-e10s', 15:01:43 INFO - 'system_bits': '32', 15:01:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:01:43 INFO - 'title': 'talos-linux64-ix-030', 15:01:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:01:43 INFO - 'use_talos_json': True, 15:01:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:01:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:01:43 INFO - 'work_dir': 'build'} 15:01:43 INFO - ##### 15:01:43 INFO - ##### Running clobber step. 15:01:43 INFO - ##### 15:01:43 INFO - Running pre-action listener: _resource_record_pre_action 15:01:43 INFO - Running main action method: clobber 15:01:43 INFO - rmtree: /builds/slave/test/build 15:01:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:01:44 INFO - Running post-action listener: _resource_record_post_action 15:01:44 INFO - ##### 15:01:44 INFO - ##### Running read-buildbot-config step. 15:01:44 INFO - ##### 15:01:44 INFO - Running pre-action listener: _resource_record_pre_action 15:01:44 INFO - Running main action method: read_buildbot_config 15:01:44 INFO - Using buildbot properties: 15:01:44 INFO - { 15:01:44 INFO - "project": "", 15:01:44 INFO - "product": "firefox", 15:01:44 INFO - "script_repo_revision": "production", 15:01:44 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 15:01:44 INFO - "repository": "", 15:01:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 15:01:44 INFO - "buildid": "20160505123841", 15:01:44 INFO - "pgo_build": "True", 15:01:44 INFO - "basedir": "/builds/slave/test", 15:01:44 INFO - "buildnumber": 292, 15:01:44 INFO - "slavename": "talos-linux64-ix-030", 15:01:44 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:01:44 INFO - "platform": "ubuntu64_hw", 15:01:44 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 15:01:44 INFO - "branch": "mozilla-aurora", 15:01:44 INFO - "repo_path": "releases/mozilla-aurora", 15:01:44 INFO - "revision": "0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b", 15:01:44 INFO - "stage_platform": "linux64-pgo", 15:01:44 INFO - "builduid": "1457772db7ef4c1982b4796b67a4914d", 15:01:44 INFO - "slavebuilddir": "test" 15:01:44 INFO - } 15:01:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 15:01:44 INFO - Running post-action listener: _resource_record_post_action 15:01:44 INFO - ##### 15:01:44 INFO - ##### Running download-and-extract step. 15:01:44 INFO - ##### 15:01:44 INFO - Running pre-action listener: _resource_record_pre_action 15:01:44 INFO - Running main action method: download_and_extract 15:01:44 INFO - mkdir: /builds/slave/test/build/tests 15:01: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:44 INFO - https://queue.taskcluster.net/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:01:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 15:01:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 15:01:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 15:01:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:01:48 INFO - Downloaded 1361 bytes. 15:01:48 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 15:01:48 INFO - Using the following test package requirements: 15:01:48 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 15:01:48 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:01:48 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 15:01:48 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:01:48 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 15:01:48 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:01:48 INFO - u'jsshell-linux-x86_64.zip'], 15:01:48 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:01:48 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 15:01:48 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 15:01:48 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:01:48 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 15:01:48 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:01:48 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 15:01:48 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:01:48 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 15:01:48 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 15:01:48 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 15:01:48 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:01:48 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:48 INFO - https://queue.taskcluster.net/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:01:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 15:01:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 15:01:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 15:01:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:01:50 INFO - Downloaded 23824026 bytes. 15:01:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:01:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:01:51 INFO - Return code: 0 15:01:51 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:01:51 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:51 INFO - https://queue.taskcluster.net/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:01:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 15:01:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 15:01:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 15:01:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:02:03 INFO - Downloaded 23824026 bytes. 15:02:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:02:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:02:04 INFO - Return code: 0 15:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:04 INFO - https://queue.taskcluster.net/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:02:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 15:02:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 15:02:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 15:02:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:02:05 INFO - Downloaded 10840145 bytes. 15:02:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:02:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:02:05 INFO - Return code: 0 15:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:05 INFO - https://queue.taskcluster.net/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:02:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 15:02:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 15:02:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 15:02:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:02:07 INFO - Downloaded 62687148 bytes. 15:02:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 15:02:07 INFO - mkdir: /builds/slave/test/properties 15:02:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:02:07 INFO - Writing to file /builds/slave/test/properties/build_url 15:02:07 INFO - Contents: 15:02:07 INFO - build_url:https://queue.taskcluster.net/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 15:02:08 INFO - Running post-action listener: _resource_record_post_action 15:02:08 INFO - Running post-action listener: set_extra_try_arguments 15:02:08 INFO - ##### 15:02:08 INFO - ##### Running populate-webroot step. 15:02:08 INFO - ##### 15:02:08 INFO - Running pre-action listener: _resource_record_pre_action 15:02:08 INFO - Running main action method: populate_webroot 15:02:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:02:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:02:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:02:08 INFO - '--tppagecycles', 15:02:08 INFO - '7'], 15:02:08 INFO - 'tests': ['tsvgm']}}, 15:02:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:02:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:02:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:02:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:02:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:02:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:02:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:08 INFO - 'talos_options': ['--disable-e10s'], 15:02:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:02:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:02:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:08 INFO - 'talos_options': ['--disable-e10s'], 15:02:08 INFO - 'tests': ['damp', 'tps']}, 15:02:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:08 INFO - 'tests': ['damp', 'tps']}, 15:02:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:02:08 INFO - 'tests': ['dromaeo_dom']}, 15:02:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:02:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:02:08 INFO - 'tests': ['a11yr', 15:02:08 INFO - 'ts_paint', 15:02:08 INFO - 'tpaint', 15:02:08 INFO - 'sessionrestore', 15:02:08 INFO - 'sessionrestore_no_auto_restore', 15:02:08 INFO - 'tabpaint']}, 15:02:08 INFO - 'other-e10s': {'tests': ['a11yr', 15:02:08 INFO - 'ts_paint', 15:02:08 INFO - 'tpaint', 15:02:08 INFO - 'sessionrestore', 15:02:08 INFO - 'sessionrestore_no_auto_restore', 15:02:08 INFO - 'tabpaint']}, 15:02:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:02:08 INFO - 'ts_paint', 15:02:08 INFO - 'tpaint', 15:02:08 INFO - 'sessionrestore', 15:02:08 INFO - 'sessionrestore_no_auto_restore', 15:02:08 INFO - 'tabpaint']}, 15:02:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:02:08 INFO - 'ts_paint', 15:02:08 INFO - 'tpaint', 15:02:08 INFO - 'sessionrestore', 15:02:08 INFO - 'sessionrestore_no_auto_restore', 15:02:08 INFO - 'tabpaint']}, 15:02:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:02:08 INFO - 'tests': ['a11yr', 15:02:08 INFO - 'ts_paint', 15:02:08 INFO - 'tpaint', 15:02:08 INFO - 'sessionrestore', 15:02:08 INFO - 'sessionrestore_no_auto_restore', 15:02:08 INFO - 'tabpaint']}, 15:02:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:02:08 INFO - 'tests': ['a11yr', 15:02:08 INFO - 'ts_paint', 15:02:08 INFO - 'tpaint', 15:02:08 INFO - 'sessionrestore', 15:02:08 INFO - 'sessionrestore_no_auto_restore', 15:02:08 INFO - 'tabpaint']}, 15:02:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:02:08 INFO - 'tests': ['tsvgx', 15:02:08 INFO - 'tsvgr_opacity', 15:02:08 INFO - 'tart', 15:02:08 INFO - 'tscrollx', 15:02:08 INFO - 'cart']}, 15:02:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:02:08 INFO - 'tsvgr_opacity', 15:02:08 INFO - 'tart', 15:02:08 INFO - 'tscrollx', 15:02:08 INFO - 'cart']}, 15:02:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:08 INFO - 'talos_options': ['--disable-e10s'], 15:02:08 INFO - 'tests': ['tp5o']}, 15:02:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:08 INFO - 'tests': ['tp5o']}, 15:02:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:08 INFO - 'talos_options': ['--disable-e10s', 15:02:08 INFO - '--xperf_path', 15:02:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:02:08 INFO - 'tests': ['tp5n']}, 15:02:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:08 INFO - 'talos_options': ['--xperf_path', 15:02:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:02:08 INFO - 'tests': ['tp5n']}}, 15:02:08 INFO - 'talos.zip': {'path': '', 15:02:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:02:08 INFO - Running post-action listener: _resource_record_post_action 15:02:08 INFO - ##### 15:02:08 INFO - ##### Running create-virtualenv step. 15:02:08 INFO - ##### 15:02:08 INFO - Running pre-action listener: _resource_record_pre_action 15:02:08 INFO - Running main action method: create_virtualenv 15:02:08 INFO - Creating virtualenv /builds/slave/test/build/venv 15:02:08 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 15:02:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:02:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:02:08 INFO - Using real prefix '/usr' 15:02:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:02:08 INFO - Installing distribute.............................................................................................................................................................................................done. 15:02:10 INFO - Installing pip.................done. 15:02:10 INFO - Return code: 0 15:02:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:02:10 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:10 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:10 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 0x7fa2a1f3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d55a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2164410>, '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 0x2165310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21657a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', '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 15:02:10 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 15:02:10 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 15:02:10 INFO - Using env: {'DISPLAY': ':0', 15:02:10 INFO - 'HOME': '/home/cltbld', 15:02:10 INFO - 'LANG': 'en_US.UTF-8', 15:02:10 INFO - 'LANGUAGE': 'en_US:en', 15:02:10 INFO - 'LOGNAME': 'cltbld', 15:02:10 INFO - 'MAIL': '/var/mail/cltbld', 15:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:10 INFO - 'MOZ_NO_REMOTE': '1', 15:02:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:10 INFO - 'NO_EM_RESTART': '1', 15:02:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:10 INFO - 'PWD': '/builds/slave/test', 15:02:10 INFO - 'SHELL': '/bin/bash', 15:02:10 INFO - 'SHLVL': '1', 15:02:10 INFO - 'TERM': 'linux', 15:02:10 INFO - 'TMOUT': '86400', 15:02:10 INFO - 'USER': 'cltbld', 15:02:10 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', 15:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:10 INFO - '_': '/tools/buildbot/bin/python'} 15:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:11 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 15:02:11 INFO - Cleaning up... 15:02:11 INFO - Return code: 0 15:02:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:11 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2a1f3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d55a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2164410>, '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 0x2165310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21657a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', '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 15:02:11 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>=0.7.1'] in /builds/slave/test/build 15:02:11 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>=0.7.1 15:02:11 INFO - Using env: {'DISPLAY': ':0', 15:02:11 INFO - 'HOME': '/home/cltbld', 15:02:11 INFO - 'LANG': 'en_US.UTF-8', 15:02:11 INFO - 'LANGUAGE': 'en_US:en', 15:02:11 INFO - 'LOGNAME': 'cltbld', 15:02:11 INFO - 'MAIL': '/var/mail/cltbld', 15:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:11 INFO - 'MOZ_NO_REMOTE': '1', 15:02:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:11 INFO - 'NO_EM_RESTART': '1', 15:02:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:11 INFO - 'PWD': '/builds/slave/test', 15:02:11 INFO - 'SHELL': '/bin/bash', 15:02:11 INFO - 'SHLVL': '1', 15:02:11 INFO - 'TERM': 'linux', 15:02:11 INFO - 'TMOUT': '86400', 15:02:11 INFO - 'USER': 'cltbld', 15:02:11 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', 15:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:11 INFO - '_': '/tools/buildbot/bin/python'} 15:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:11 INFO - Downloading/unpacking psutil>=0.7.1 15:02: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 15:02: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 15:02: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 15:02: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 15:02: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 15:02: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 15:02:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:02:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:02:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:02:13 INFO - Installing collected packages: psutil 15:02:13 INFO - Running setup.py install for psutil 15:02:13 INFO - building 'psutil._psutil_linux' extension 15:02:13 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 15:02:13 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 15:02:13 INFO - building 'psutil._psutil_posix' extension 15:02:13 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 15:02:13 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 15:02:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:02:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:02:14 INFO - Successfully installed psutil 15:02:14 INFO - Cleaning up... 15:02:14 INFO - Return code: 0 15:02:14 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:02:14 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:14 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:14 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2a1f3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d55a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2164410>, '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 0x2165310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21657a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', '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 15:02:14 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.0.0'] in /builds/slave/test/build 15:02:14 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.0.0 15:02:14 INFO - Using env: {'DISPLAY': ':0', 15:02:14 INFO - 'HOME': '/home/cltbld', 15:02:14 INFO - 'LANG': 'en_US.UTF-8', 15:02:14 INFO - 'LANGUAGE': 'en_US:en', 15:02:14 INFO - 'LOGNAME': 'cltbld', 15:02:14 INFO - 'MAIL': '/var/mail/cltbld', 15:02:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:14 INFO - 'MOZ_NO_REMOTE': '1', 15:02:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:14 INFO - 'NO_EM_RESTART': '1', 15:02:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:14 INFO - 'PWD': '/builds/slave/test', 15:02:14 INFO - 'SHELL': '/bin/bash', 15:02:14 INFO - 'SHLVL': '1', 15:02:14 INFO - 'TERM': 'linux', 15:02:14 INFO - 'TMOUT': '86400', 15:02:14 INFO - 'USER': 'cltbld', 15:02:14 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', 15:02:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:14 INFO - '_': '/tools/buildbot/bin/python'} 15:02:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:14 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:02:14 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 15:02:14 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 15:02:14 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 15:02:14 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 15:02:14 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 15:02:14 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 15:02:16 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:02:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:02:16 INFO - Installing collected packages: mozsystemmonitor 15:02:16 INFO - Running setup.py install for mozsystemmonitor 15:02:16 INFO - Successfully installed mozsystemmonitor 15:02:16 INFO - Cleaning up... 15:02:16 INFO - Return code: 0 15:02:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:02:16 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:16 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:16 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 0x7fa2a1f3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d55a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2164410>, '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 0x2165310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21657a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', '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 15:02:16 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 15:02:16 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 15:02:16 INFO - Using env: {'DISPLAY': ':0', 15:02:16 INFO - 'HOME': '/home/cltbld', 15:02:16 INFO - 'LANG': 'en_US.UTF-8', 15:02:16 INFO - 'LANGUAGE': 'en_US:en', 15:02:16 INFO - 'LOGNAME': 'cltbld', 15:02:16 INFO - 'MAIL': '/var/mail/cltbld', 15:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:16 INFO - 'MOZ_NO_REMOTE': '1', 15:02:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:16 INFO - 'NO_EM_RESTART': '1', 15:02:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:16 INFO - 'PWD': '/builds/slave/test', 15:02:16 INFO - 'SHELL': '/bin/bash', 15:02:16 INFO - 'SHLVL': '1', 15:02:16 INFO - 'TERM': 'linux', 15:02:16 INFO - 'TMOUT': '86400', 15:02:16 INFO - 'USER': 'cltbld', 15:02:16 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', 15:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:16 INFO - '_': '/tools/buildbot/bin/python'} 15:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:16 INFO - Downloading/unpacking blobuploader==1.2.4 15:02: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 15:02: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 15:02: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 15:02: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 15:02: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 15:02: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 15:02:18 INFO - Downloading blobuploader-1.2.4.tar.gz 15:02:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:02:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:02:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:18 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 15:02:18 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 15:02:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:02:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:02:19 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 15:02:19 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 15:02:19 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 15:02:19 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 15:02:19 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 15:02:19 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 15:02:19 INFO - Downloading docopt-0.6.1.tar.gz 15:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:02:19 INFO - Installing collected packages: blobuploader, requests, docopt 15:02:19 INFO - Running setup.py install for blobuploader 15:02:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:02:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:02:19 INFO - Running setup.py install for requests 15:02:19 INFO - Running setup.py install for docopt 15:02:19 INFO - Successfully installed blobuploader requests docopt 15:02:19 INFO - Cleaning up... 15:02:20 INFO - Return code: 0 15:02:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:20 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 0x7fa2a1f3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d55a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2164410>, '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 0x2165310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21657a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', '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 15:02:20 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 15:02:20 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 15:02:20 INFO - Using env: {'DISPLAY': ':0', 15:02:20 INFO - 'HOME': '/home/cltbld', 15:02:20 INFO - 'LANG': 'en_US.UTF-8', 15:02:20 INFO - 'LANGUAGE': 'en_US:en', 15:02:20 INFO - 'LOGNAME': 'cltbld', 15:02:20 INFO - 'MAIL': '/var/mail/cltbld', 15:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:20 INFO - 'MOZ_NO_REMOTE': '1', 15:02:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:20 INFO - 'NO_EM_RESTART': '1', 15:02:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:20 INFO - 'PWD': '/builds/slave/test', 15:02:20 INFO - 'SHELL': '/bin/bash', 15:02:20 INFO - 'SHLVL': '1', 15:02:20 INFO - 'TERM': 'linux', 15:02:20 INFO - 'TMOUT': '86400', 15:02:20 INFO - 'USER': 'cltbld', 15:02:20 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', 15:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:20 INFO - '_': '/tools/buildbot/bin/python'} 15:02:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:02:20 INFO - Running setup.py (path:/tmp/pip-DBQyGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:02:20 INFO - Running setup.py (path:/tmp/pip-OCFwI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:02:20 INFO - Running setup.py (path:/tmp/pip-SdggEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:02:20 INFO - Running setup.py (path:/tmp/pip-uTFBOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:02:20 INFO - Running setup.py (path:/tmp/pip-zGWaEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:02:20 INFO - Running setup.py (path:/tmp/pip-fzGiBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:02:20 INFO - Running setup.py (path:/tmp/pip-xHiFAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:02:20 INFO - Running setup.py (path:/tmp/pip-CeL8rK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:02:20 INFO - Running setup.py (path:/tmp/pip-stL2pd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:02:20 INFO - Running setup.py (path:/tmp/pip-bSloWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:02:20 INFO - Running setup.py (path:/tmp/pip-dDXGYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:02:21 INFO - Running setup.py (path:/tmp/pip-V5X1j_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:02:21 INFO - Running setup.py (path:/tmp/pip-8ZkzWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:02:21 INFO - Running setup.py (path:/tmp/pip-bLrdB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:21 INFO - Running setup.py (path:/tmp/pip-MdL3cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:02:21 INFO - Running setup.py (path:/tmp/pip-irSjgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:02:21 INFO - Running setup.py (path:/tmp/pip-zINpQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:02:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:02:21 INFO - Running setup.py install for manifestparser 15:02:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:02:21 INFO - Running setup.py install for mozcrash 15:02:21 INFO - Running setup.py install for mozdebug 15:02:21 INFO - Running setup.py install for mozdevice 15:02:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:02:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:02:21 INFO - Running setup.py install for mozfile 15:02:21 INFO - Running setup.py install for mozhttpd 15:02:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:02:22 INFO - Running setup.py install for mozinfo 15:02:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:02:22 INFO - Running setup.py install for mozInstall 15:02:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:02:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:02:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:02:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:02:22 INFO - Running setup.py install for mozleak 15:02:22 INFO - Running setup.py install for mozlog 15:02:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:02:22 INFO - Running setup.py install for moznetwork 15:02:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:02:22 INFO - Running setup.py install for mozprocess 15:02:22 INFO - Running setup.py install for mozprofile 15:02:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:02:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:02:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:02:22 INFO - Running setup.py install for mozrunner 15:02:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:02:22 INFO - Running setup.py install for mozscreenshot 15:02:23 INFO - Running setup.py install for moztest 15:02:23 INFO - Running setup.py install for mozversion 15:02:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:02:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:02:23 INFO - Cleaning up... 15:02:23 INFO - Return code: 0 15:02:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02:23 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:23 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:23 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 0x7fa2a1f3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d55a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2164410>, '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 0x2165310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21657a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', '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 15:02:23 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 15:02:23 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 15:02:23 INFO - Using env: {'DISPLAY': ':0', 15:02:23 INFO - 'HOME': '/home/cltbld', 15:02:23 INFO - 'LANG': 'en_US.UTF-8', 15:02:23 INFO - 'LANGUAGE': 'en_US:en', 15:02:23 INFO - 'LOGNAME': 'cltbld', 15:02:23 INFO - 'MAIL': '/var/mail/cltbld', 15:02:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:23 INFO - 'MOZ_NO_REMOTE': '1', 15:02:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:23 INFO - 'NO_EM_RESTART': '1', 15:02:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:23 INFO - 'PWD': '/builds/slave/test', 15:02:23 INFO - 'SHELL': '/bin/bash', 15:02:23 INFO - 'SHLVL': '1', 15:02:23 INFO - 'TERM': 'linux', 15:02:23 INFO - 'TMOUT': '86400', 15:02:23 INFO - 'USER': 'cltbld', 15:02:23 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', 15:02:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:23 INFO - '_': '/tools/buildbot/bin/python'} 15:02:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:02:23 INFO - Running setup.py (path:/tmp/pip-XJgFjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:02:23 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)) 15:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:02:23 INFO - Running setup.py (path:/tmp/pip-gr2lou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:02:23 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)) 15:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:02:23 INFO - Running setup.py (path:/tmp/pip-Ypy32h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:02:23 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)) 15:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:02:23 INFO - Running setup.py (path:/tmp/pip-RajXw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:02:23 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)) 15:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:02:23 INFO - Running setup.py (path:/tmp/pip-qYhkmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:02:23 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)) 15:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:02:23 INFO - Running setup.py (path:/tmp/pip-elGgUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:02:23 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)) 15:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:02:23 INFO - Running setup.py (path:/tmp/pip-imhM71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:02:23 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)) 15:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:02:23 INFO - Running setup.py (path:/tmp/pip-Q524tg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:02:24 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)) 15:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:02:24 INFO - Running setup.py (path:/tmp/pip-MRuV6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:02:24 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)) 15:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:02:24 INFO - Running setup.py (path:/tmp/pip-bKVaKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:02:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 15:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:02:24 INFO - Running setup.py (path:/tmp/pip-BtWVHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:02:24 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)) 15:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:02:24 INFO - Running setup.py (path:/tmp/pip-dM5eIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:02:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 15:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:02:24 INFO - Running setup.py (path:/tmp/pip-Ulu2nf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:02:24 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)) 15:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:02:24 INFO - Running setup.py (path:/tmp/pip-OcRhxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:02:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 15:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:24 INFO - Running setup.py (path:/tmp/pip-pBzjtF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:24 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)) 15:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:02:24 INFO - Running setup.py (path:/tmp/pip-fcMMzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:02:24 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)) 15:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:02:24 INFO - Running setup.py (path:/tmp/pip-4pDMWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:02:24 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)) 15:02:24 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)) 15:02:24 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)) 15:02:24 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)) 15:02:24 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)) 15:02:24 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)) 15:02:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:24 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 15:02:24 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 15:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:26 INFO - Downloading blessings-1.6.tar.gz 15:02:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:02:26 INFO - Installing collected packages: blessings 15:02:26 INFO - Running setup.py install for blessings 15:02:26 INFO - Successfully installed blessings 15:02:26 INFO - Cleaning up... 15:02:26 INFO - Return code: 0 15:02:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:02:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:02:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:02:27 INFO - Reading from file tmpfile_stdout 15:02:27 INFO - Current package versions: 15:02:27 INFO - argparse == 1.2.1 15:02:27 INFO - blessings == 1.6 15:02:27 INFO - blobuploader == 1.2.4 15:02:27 INFO - docopt == 0.6.1 15:02:27 INFO - manifestparser == 1.1 15:02:27 INFO - mozInstall == 1.12 15:02:27 INFO - mozcrash == 0.17 15:02:27 INFO - mozdebug == 0.1 15:02:27 INFO - mozdevice == 0.48 15:02:27 INFO - mozfile == 1.2 15:02:27 INFO - mozhttpd == 0.7 15:02:27 INFO - mozinfo == 0.9 15:02:27 INFO - mozleak == 0.1 15:02:27 INFO - mozlog == 3.1 15:02:27 INFO - moznetwork == 0.27 15:02:27 INFO - mozprocess == 0.22 15:02:27 INFO - mozprofile == 0.28 15:02:27 INFO - mozrunner == 6.11 15:02:27 INFO - mozscreenshot == 0.1 15:02:27 INFO - mozsystemmonitor == 0.0 15:02:27 INFO - moztest == 0.7 15:02:27 INFO - mozversion == 1.4 15:02:27 INFO - psutil == 3.1.1 15:02:27 INFO - requests == 1.2.3 15:02:27 INFO - wsgiref == 0.1.2 15:02:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:27 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 0x7fa2a1f3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d55a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2164410>, '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 0x2165310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21657a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', '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 15:02:27 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 15:02:27 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 15:02:27 INFO - Using env: {'DISPLAY': ':0', 15:02:27 INFO - 'HOME': '/home/cltbld', 15:02:27 INFO - 'LANG': 'en_US.UTF-8', 15:02:27 INFO - 'LANGUAGE': 'en_US:en', 15:02:27 INFO - 'LOGNAME': 'cltbld', 15:02:27 INFO - 'MAIL': '/var/mail/cltbld', 15:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:27 INFO - 'MOZ_NO_REMOTE': '1', 15:02:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:27 INFO - 'NO_EM_RESTART': '1', 15:02:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:27 INFO - 'PWD': '/builds/slave/test', 15:02:27 INFO - 'SHELL': '/bin/bash', 15:02:27 INFO - 'SHLVL': '1', 15:02:27 INFO - 'TERM': 'linux', 15:02:27 INFO - 'TMOUT': '86400', 15:02:27 INFO - 'USER': 'cltbld', 15:02:27 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', 15:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:27 INFO - '_': '/tools/buildbot/bin/python'} 15:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:27 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)) 15:02:27 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)) 15:02:27 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)) 15:02:27 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)) 15:02:27 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)) 15:02:27 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)) 15:02:27 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)) 15:02:27 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)) 15:02:27 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)) 15:02:27 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)) 15:02:27 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)) 15:02:27 INFO - Cleaning up... 15:02:27 INFO - Return code: 0 15:02:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:02:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7fa2a1f3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d55a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2164410>, '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 0x2165310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21657a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', '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 15:02:27 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 15:02:27 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 15:02:27 INFO - Using env: {'DISPLAY': ':0', 15:02:27 INFO - 'HOME': '/home/cltbld', 15:02:27 INFO - 'LANG': 'en_US.UTF-8', 15:02:27 INFO - 'LANGUAGE': 'en_US:en', 15:02:27 INFO - 'LOGNAME': 'cltbld', 15:02:27 INFO - 'MAIL': '/var/mail/cltbld', 15:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:27 INFO - 'MOZ_NO_REMOTE': '1', 15:02:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:27 INFO - 'NO_EM_RESTART': '1', 15:02:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:27 INFO - 'PWD': '/builds/slave/test', 15:02:27 INFO - 'SHELL': '/bin/bash', 15:02:27 INFO - 'SHLVL': '1', 15:02:27 INFO - 'TERM': 'linux', 15:02:27 INFO - 'TMOUT': '86400', 15:02:27 INFO - 'USER': 'cltbld', 15:02:27 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', 15:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:27 INFO - '_': '/tools/buildbot/bin/python'} 15:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:27 INFO - Downloading/unpacking jsonschema 15:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:27 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 15:02:27 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 15:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:02:29 INFO - Downloading/unpacking functools32 (from jsonschema) 15:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:29 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 15:02:29 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 15:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:29 INFO - Downloading functools32-3.2.3-2.tar.gz 15:02:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:02:29 INFO - warning: no files found matching '*.txt' 15:02:29 INFO - no previously-included directories found matching 'build' 15:02:29 INFO - no previously-included directories found matching 'dist' 15:02:29 INFO - no previously-included directories found matching '.git*' 15:02:29 INFO - Installing collected packages: jsonschema, functools32 15:02:29 INFO - Running setup.py install for functools32 15:02:29 INFO - warning: no files found matching '*.txt' 15:02:29 INFO - no previously-included directories found matching 'build' 15:02:29 INFO - no previously-included directories found matching 'dist' 15:02:29 INFO - no previously-included directories found matching '.git*' 15:02:29 INFO - Successfully installed jsonschema functools32 15:02:29 INFO - Cleaning up... 15:02:29 INFO - Return code: 0 15:02:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:02:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2a1f3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d55a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2164410>, '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 0x2165310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21657a0>, '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': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', '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 15:02:29 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', 'flake8'] in /builds/slave/test/build 15:02:29 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 flake8 15:02:29 INFO - Using env: {'DISPLAY': ':0', 15:02:29 INFO - 'HOME': '/home/cltbld', 15:02:29 INFO - 'LANG': 'en_US.UTF-8', 15:02:29 INFO - 'LANGUAGE': 'en_US:en', 15:02:29 INFO - 'LOGNAME': 'cltbld', 15:02:29 INFO - 'MAIL': '/var/mail/cltbld', 15:02:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:29 INFO - 'MOZ_NO_REMOTE': '1', 15:02:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:29 INFO - 'NO_EM_RESTART': '1', 15:02:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:29 INFO - 'PWD': '/builds/slave/test', 15:02:29 INFO - 'SHELL': '/bin/bash', 15:02:29 INFO - 'SHLVL': '1', 15:02:29 INFO - 'TERM': 'linux', 15:02:29 INFO - 'TMOUT': '86400', 15:02:29 INFO - 'USER': 'cltbld', 15:02:29 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', 15:02:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:29 INFO - '_': '/tools/buildbot/bin/python'} 15:02:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:30 INFO - Downloading/unpacking flake8 15:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:30 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 15:02:30 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 15:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:02:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:31 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 15:02:31 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 15:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:02:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:32 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 15:02:32 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 15:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:32 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 15:02:32 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 15:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:02:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:02:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:02:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:02:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:02:32 INFO - no previously-included directories found matching 'docs/_build' 15:02:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:02:32 INFO - Running setup.py install for pep8 15:02:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:02:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:02:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:02:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:02:32 INFO - no previously-included directories found matching 'docs/_build' 15:02:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:02:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:02:32 INFO - Cleaning up... 15:02:32 INFO - Return code: 0 15:02:32 INFO - Running post-action listener: _resource_record_post_action 15:02:32 INFO - Running post-action listener: _start_resource_monitoring 15:02:32 INFO - Starting resource monitoring. 15:02:32 INFO - ##### 15:02:32 INFO - ##### Running install step. 15:02:32 INFO - ##### 15:02:32 INFO - Running pre-action listener: _resource_record_pre_action 15:02:32 INFO - Running main action method: install 15:02:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:02:33 INFO - Reading from file tmpfile_stdout 15:02:33 INFO - Detecting whether we're running mozinstall >=1.0... 15:02:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:02:33 INFO - Reading from file tmpfile_stdout 15:02:33 INFO - Output received: 15:02:33 INFO - Usage: mozinstall [options] installer 15:02:33 INFO - Options: 15:02:33 INFO - -h, --help show this help message and exit 15:02:33 INFO - -d DEST, --destination=DEST 15:02:33 INFO - Directory to install application into. [default: 15:02:33 INFO - "/builds/slave/test"] 15:02:33 INFO - --app=APP Application being installed. [default: firefox] 15:02:33 INFO - mkdir: /builds/slave/test/build/application 15:02:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:02:48 INFO - Reading from file tmpfile_stdout 15:02:48 INFO - Output received: 15:02:48 INFO - /builds/slave/test/build/application/firefox/firefox 15:02:48 INFO - Running post-action listener: _resource_record_post_action 15:02:48 INFO - ##### 15:02:48 INFO - ##### Running run-tests step. 15:02:48 INFO - ##### 15:02:48 INFO - Running pre-action listener: _resource_record_pre_action 15:02:48 INFO - Running main action method: run_tests 15:02:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:02:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:02:48 INFO - Python 2.7.3 15:02:48 INFO - Return code: 0 15:02:48 INFO - grabbing minidump binary from tooltool 15:02:48 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:48 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2164410>, '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 0x2165310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21657a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:02:48 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 15:02:48 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 15:02:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:02:48 INFO - Return code: 0 15:02:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:02:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:02:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:02:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:02:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:02:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:02:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:02:49 INFO - Return code: 0 15:02:49 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:02:49 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:02:49 INFO - Using env: {'DISPLAY': ':0', 15:02:49 INFO - 'HOME': '/home/cltbld', 15:02:49 INFO - 'LANG': 'en_US.UTF-8', 15:02:49 INFO - 'LANGUAGE': 'en_US:en', 15:02:49 INFO - 'LOGNAME': 'cltbld', 15:02:49 INFO - 'MAIL': '/var/mail/cltbld', 15:02:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:02:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:02:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:49 INFO - 'MOZ_NO_REMOTE': '1', 15:02:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:02:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:49 INFO - 'NO_EM_RESTART': '1', 15:02:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:49 INFO - 'PWD': '/builds/slave/test', 15:02:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:02:49 INFO - 'SHELL': '/bin/bash', 15:02:49 INFO - 'SHLVL': '1', 15:02:49 INFO - 'TERM': 'linux', 15:02:49 INFO - 'TMOUT': '86400', 15:02:49 INFO - 'USER': 'cltbld', 15:02:49 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446', 15:02:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:49 INFO - '_': '/tools/buildbot/bin/python'} 15:02:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:02:49 INFO - mozversion application_buildid: 20160505123841 15:02:49 INFO - mozversion application_changeset: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b 15:02:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:02:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:02:49 INFO - mozversion application_name: Firefox 15:02:49 INFO - mozversion application_remotingname: firefox-dev 15:02:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:02:49 INFO - mozversion application_vendor: Mozilla 15:02:49 INFO - mozversion application_version: 48.0a2 15:02:49 INFO - mozversion platform_buildid: 20160505123841 15:02:49 INFO - mozversion platform_changeset: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b 15:02:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:02:49 INFO - mozversion platform_version: 48.0a2 15:02:49 INFO - using testdate: 1462485769 15:02:49 INFO - actual date: 1462485769 15:02:49 INFO - starting webserver on 'localhost:39669' 15:02:49 INFO - SUITE-START | Running 2 tests 15:02:49 INFO - TEST-START | dromaeo_css 15:02:49 INFO - operating with platform_type : linux_ 15:02:49 INFO - Initialising browser for dromaeo_css test... 15:02:49 INFO - TEST-INFO | started process 3840 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV189fs/profile http://localhost:39669/getInfo.html) 15:02:53 INFO - PROCESS | 3840 | 15:02:53 INFO - PROCESS | 3840 | (process:3917): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 15:02:54 INFO - PROCESS | 3840 | __metrics Screen width/height:1600/1200 15:02:54 INFO - PROCESS | 3840 | colorDepth:24 15:02:54 INFO - PROCESS | 3840 | Browser inner width/height: 1024/697 15:02:54 INFO - PROCESS | 3840 | __metrics 15:02:55 ERROR - PROCESS | 3840 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 15:02:56 INFO - TEST-INFO | 3840: exit 0 15:02:56 INFO - Browser initialized. 15:02:56 INFO - Running cycle 1/1 for dromaeo_css test... 15:02:56 INFO - TEST-INFO | started process 3968 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV189fs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:02:57 INFO - PROCESS | 3968 | 15:02:57 INFO - PROCESS | 3968 | (process:4038): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 15:03:07 INFO - PROCESS | 3968 | RSS: Main: 152281088 15:03:07 INFO - PROCESS | 3968 | 15:03:57 INFO - PROCESS | 3968 | 1462485837416 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] 15:06:04 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:39669/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39669/tests/dromaeo/cssquery-ext.html) 15:06:05 INFO - PROCESS | 3968 | RSS: Main: 155246592 15:06:05 INFO - PROCESS | 3968 | 15:09:07 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:39669/tests/dromaeo/cssquery-ext.html (next: http://localhost:39669/tests/dromaeo/cssquery-jquery.html) 15:09:08 INFO - PROCESS | 3968 | RSS: Main: 154157056 15:09:08 INFO - PROCESS | 3968 | 15:12:10 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:39669/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39669/tests/dromaeo/cssquery-mootools.html) 15:12:10 INFO - PROCESS | 3968 | RSS: Main: 153812992 15:12:10 INFO - PROCESS | 3968 | 15:15:12 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:39669/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39669/tests/dromaeo/cssquery-prototype.html) 15:15:13 INFO - PROCESS | 3968 | RSS: Main: 153681920 15:15:13 INFO - PROCESS | 3968 | 15:18:15 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:39669/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39669/tests/dromaeo/cssquery-yui.html) 15:18:16 INFO - PROCESS | 3968 | RSS: Main: 154685440 15:18:16 INFO - PROCESS | 3968 | 15:21:18 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:39669/tests/dromaeo/cssquery-yui.html (next: http://localhost:39669/tests/dromaeo/cssquery-dojo.html) 15:21:18 INFO - PROCESS | 3968 | RSS: Main: 156041216 15:21:18 INFO - PROCESS | 3968 | 15:21:18 INFO - PROCESS | 3968 | __start_tp_report 15:21:18 INFO - PROCESS | 3968 | _x_x_mozilla_page_load 15:21:18 INFO - PROCESS | 3968 | _x_x_mozilla_page_load_details 15:21:18 INFO - PROCESS | 3968 | |i|pagename|runs| 15:21:18 INFO - PROCESS | 3968 | |0;dojo.html;3272.231025708959;3318.3363327334523;3399.269157131216;3445.793252404856;3446.1384653836526;5637.802676906309;5651.604387692863;5659.8585035374135;5665.320161580606;5666.26338575985;5639.661620302784;5665.518430933375;5685.317761868581;5685.374608793039;5692.459216374448;5145.562627176694;5179.404368497614;5219.79120835168;5250.579953603703;5264.42091369949;5068.898622027557;5168.043911876296;5255;5315.495027972356;5316.494932981361;5124.692518448886;5199.948000520003;5214.530692237698;5314.3091398118195;5316.787328506855;5190.92213616796;5197.0385478686185;5198.194279886624;5200.713960732158;5211.687298762086;5014.924776128361;5088.77100530477;5177.352830896138;5195.428502864682;5207.505286997767;5374.193870919355;5504.779808807642;5573.470520300605;5585.357683866367;5588.972055139699;5980.8504787380225;6024.819255422344;6030.366811484775;6030.638161710312;6041.033434650435;5879.029960056586;6056.182415373913;6074.908876366858;6096.268447786294;6100.755969761204;5633.971830140823;5677.120046392815;5687.800926967578;5693.572982026323;5729.88540229193;6205.441510264008;6206.751729930752;6216.036514340284;6218.502519798405;6224.253089629274;5843.503302219359;5934.7922822701;5944.078667806497;5947.137665038632;5966.910496342558;6049.062395328731;6083.361247069082;6084.452399283997;6088.817335479943;6122.54080943931;5979.402059793986;6049.697515124314;6050.788222412194;6066.181065556182;6081.209442772411;5898.203742494794;5932.49573786233;5934.970325148347;6077.1491991121275;6080.695965201722;4548.454185497695;4551.908961820811;4556.476005259371;4572.839950601713;4585.541445855388;4088.509378874554;4101.753894766323;4103.4050062740735;4106.096658735073;4131.752094874257;5800.8259752207505;5871.941280587225;5902.61632993854;5922.674252916045;5958.970205148947;5953.940460595425;5967.16459695643;5994.520438364921;6052.54605904559;6104.481119104837;5765.509931655856;5786.681732504752;5803.419658034163;5812.9709351452975;5831.067029275296;5064.240363945364;5171.612129090333;5183.663061900965;5214.45248248936;5215.869603259949;6186.81439556814;6225.221847269091;6230.221222347207;6239.126522286883;6276.435120839055;5982.670953097621;6033;6050.092486127204;6050.273967123973;6085.786997455068;6176.81469555914;6285.308616052322;6305.054241863757;6308.842278943063;6309.2744334400295;5960.225170727777;5978;5984.581079324405;6000.609960352563;6011.368806275278;5916.112583112567;6053.152558641706;6067.757289708362;6080.604760690541;6089.665068421279;5956.493698035715;5972.223610930551;5973.313068996948;5976.7908123215475;6023.156758053964;5914.083317085859;5953.970230148821;5978.312494063239;5986.610870293417;5986.850328741642;5764.740586673526;5802;5850.824475265749;5889.52883769306;5901;5899.380565040609;5907.320658124198;5911;5946.672932988554;5962.2249107615735;5769.91345129815;5803.593748437569;5806.564507662027;5809.331926828469;5824.796132135355 15:21:18 INFO - PROCESS | 3968 | |1;ext.html;658.1082633032241;671.6708812681782;681.57401623985;719.9064121664182;727.4071631620229;32624.67375326246;38898.41652375217;39843.402348964715;39902.60097399025;39928.80035599822;42335.15329693404;42903;42935.3559696604;43172.35241471373;43190.35214471778;8873.733787986355;9089.954550227272;9096.0449152839;9116.772080698003;9119.224865886408;8996.550172491381;9085.500297483635;9105.635774569042;9115.088491150898;9134.954325228364;8987.685431009915;9026.909730902673;9079.092090790935;9081.545922703872;9132.223760980325;9087.409318394317;9098.226650734696;9127.589258483384;9143.451392916446;9160.541972901328;8900.910990890137;9078.86381704275;9086.363954523185;9087.182153606142;9103.908960910372;43657.90855228613;44102;44364.11271774546;44582;44716;3981.223661386002;3999.6600288975474;4048.271311163989;4101.487314085739;4500.437445319335;31875.20311992219;33905.83047084749;34192.82903585491;34235.657643423496;35039.1240218994;4532.79602417892;4593.793279302439;4617.746023968681;4638.512956139591;4657.347971284023;287313;292038;293239;293365;296357;65254.67372663107;65690.01464978029;65945.34054659391;66014;66201.66899165473;58100;59116;59223.40776592179;59630;69378.30621693718;18241.9054856709;18388.080595970147;18419.171137298854;18461.907690461463;18504.352347667766;13415.329233538285;13496.325183740773;13662.248576328397;13664.248466334247;13681.657958551115;19742.50643733918;19828.405147845588;20002.09990550429;20005.899970500053;20121.899390502953;7656.234376562411;7766.339861111755;7924.762257132295;8055.053024018189;8057.6374063167295;1091.0344345254507;1133.9092872570175;1158.9594364197212;1167.3812879174052;1211.6365090472823;32810.01569952905;32970.67029329676;33276.500852486744;33348.1662958428;33755.66244337525;1159.2754528419737;1211.5577814097792;1217.933013684256;1222.5048855213652;1229.9200551964093;15924.76112858308;16125.677486450206;16151.515454536382;16173.110478923772;16302.755458668129;313701;321022;321722;321765;521409;56388.43611563914;56945.43054569484;57004.71497642485;57190.7140464295;57285.71357143271;1557.7896983907253;1566.248200863569;1574.102761425999;1576.2985471465317;1593.1874743880473;31348.52977205298;31880.6811931887;32356.191095222806;32538.674613253563;32618.836905816268;52243.21635175552;52569.21146182734;53085.734571326895;53093.4690653104;53179.202311964575;486506;487931;489783;490614;492616;31331.84334078315;31520.842395787873;31588;31703.207419814687;31708.524372133976;49251.75374123106;49353;49434.50565494443;50139;51093.23360149527;41941;42591.57408425876;42859;43968;44273.55726442694;42154.578454215065;42941.35587966246;43311.783441083855;43808.12383752371;44146.77926610475;34746.65253347434;38005.239895202496;38035.809820950715;43170.784146080325;44191.33712994243;33712.49431258484;34826.303473930886;35158.29683406369;35530.644693552735;35679;27532.58701119525;28053.158405247876;28205.17944209083;28333.95806782068;28420.57369139423 15:21:18 INFO - PROCESS | 3968 | |2;jquery.html;403.4795114302549;410.22262811971314;421.5636815895548;423.1030215942203;424.9320108782595;18566.90716546417;19112.522186945316;19170.712439313385;19382.61234775304;20220.696689549633;10801.13590912727;10988.890111098886;11215.271007384514;11365.659030229086;11457.656270311883;5006.624503162259;5141.794328226866;5151.613628977823;5164.586833053366;5167.328247327842;4991.126552853254;5731.484166425021;5765.711714414283;5768.336641286242;5770.567207459437;5663.66018038919;5666.121751128581;5699.971500142494;5714.371419143891;5765.855853603652;5464.836054918339;5728.484436400744;5739.999999999979;5741.052726300156;5741.110127930177;4971;5094.6433749637545;5118.25785261135;5136.409312929024;5136.589072874199;22938;23493.41266468335;23583.882080589658;23611.527769444685;24039.27882163538;2377.1561095811085;2390.5935990881594;2421.6609674645565;2668.9457664222523;2758.517259479583;10256.589736410535;10298.227632927561;10427.635032773893;13357.866421335759;14367.85632143686;1931.652302585534;2050.98974505128;2063.473814177375;2069.4929742213103;2164.7943445372666;81283.5935820323;87079;87320;87844;88232;11384.260023098472;11754.23597466162;11882.88117118835;11924.463399146885;12087.456064476944;15911.124888131033;16109.838901611069;16118.435854745028;16159.919200403923;16185;8346.374021948379;8373.371997100241;8374.748757537283;8521.829563408697;8621.52581608005;8250.174982501701;8439.240468357877;8485.915140848636;8532.488050716976;8581.570921453902;4755.785989630475;4854.466008739036;4877.829275975324;4884.413870335582;4946.159152944008;2000.249906285143;2026.9898650506948;2061.948406312791;2075.8443116766002;2091.968620470694;936.541094863512;1067.055655744656;1097.5445190246119;1113.92202545821;1115.486876037032;19425.125869335912;19557.31549395764;19575.314863979693;19720.91534965562;19725.802741972682;1061.596593294543;1099.6151347028476;1122.3939072900705;1141;1141.6632093532428;11010.174236932262;11126.165537584713;11134.496842926263;11246.7188320291;11256.437178141061;496880;497447;527965;531755;535820;103621;107892;109697;110000.4499977495;110979;1111.1000089927024;1145.593314373402;1146.9942650286862;1149.0979581028853;1156.3004382348727;17866.106694665476;17885.46343609694;17944.91027544854;18106.91358518493;18380.816191838447;39772.40341394823;40566.79716601398;40741.38887916743;41216.587834121274;41371.379429307985;55088.44911550833;55451.86248703721;56267;58313;58997.41002589919;8533.957330213309;8704.825903482022;8729.781755456164;8816.206541411304;8865.24645405122;13070.80393794113;13955.162690238618;14089.929550352183;14163.433462661378;14306.856931430553;12366.19619724715;12693.619191424272;12738.108332416641;12741.681457963254;12861.614151575468;8845.557722113997;12131.150819442555;12282.140250182401;12471.688207794878;12902.419391127609;10666.933306669582;12108.273503589813;12467.314297713712;12714.11001229905;13039.152455090389;8908.465492070496;8983.595738191927;9002.36983411181;9002.63989440415;9110.36227464071;12790.424430900446;13002.284874332003;13014.739705205653;13047.869521304665;13068 15:21:18 INFO - PROCESS | 3968 | |3;mootools.html;1596.5290239379383;1615.5395712222016;1834.5413646588354;1869.4111354923207;1963.6759934610782;7615.657295421705;8112;8276.668933242678;8481.618327175269;8507.872381914269;8309;8337.16628337166;8368.163183681629;8392.66429342827;8393.958030209855;2366.4912043910554;2381.154690085021;2391.270662447953;2405.0740464920955;2405.8195635327334;2281.349815302639;2304.066852924568;2368.656544801003;2369.3839601703507;2370.608849539824;2318.918837840676;2321.976780232193;2337.1118974789556;2342.2504798464497;2349.2130136404326;2294.3117064880553;2298;2310.9306720798404;2316.328264803205;2317.687112239843;2245.9663105053437;2273.9090436382526;2275.4311422144465;2280.0651732789643;2282.030137191687;8480.067192608809;8538.530380829052;8589.226969572768;8594.828103437896;8597.140285971416;5110.693358398508;5326.733663316819;5330.680159190422;5333.306670132897;5335.306410166692;3145.9685403145904;3164.9683503165134;3335.1328654549993;3358.8488518016748;3366.175287054663;908.877301564287;914.2411798207472;958.8082383523323;1106.2864452428219;1132.4054871192607;10906.400147991857;10938.617148399848;10988.395638239894;11020.228583999124;11023.724406889813;4211.073563815956;4280.59334363235;4313.137372525507;4336.76147811867;4396.758178300161;8943.46339219636;8971.237444817263;9154;9186.494742789077;9207.539623018822;5072.264521644414;5091.541761241506;5101.336826212643;5106.310648062538;5127.179651255781;4320.135972805451;4746.762661866893;4808.0624278266105;4821.879453013703;4889.3888263967;3761.774293542396;3784.3566593679143;3853.3641949078647;3866.69066474687;3922.2351641429645;1116.1405717597404;1138.6242539961959;1145.707844499664;1145.954161833534;1149.7183190118308;992.7617371830853;1007.5516395204211;1018.7096677446892;1025.825609646362;1031.3502493429091;5643.786585883972;5713.114467257582;5724.656520608777;5761.222234998221;5843.883122337529;420.46600816962285;421.1765997474929;423.33325013104485;423.58911855500116;430.9482862056511;3934.2328245991757;3956.2680904032845;3967.742096763759;3968.8809335719975;3975.6819454443576;9927.354721943051;10424.113950314308;10500.317479363817;10523.684289471328;10525.789484210427;9866;10330.966903309609;10339.379637221791;10380.636677716244;10435.68692939213;7827.530348179127;7882.487638303606;7945.364370850318;8042.235987581287;8057.43597948138;5766.740496677576;5809.622374545641;5844.298684157929;5956.523478121653;6026.126211699367;9462.337636365388;9534.427934323963;9630.711078667651;9764.511774411392;9783.315167938175;9483.336166468556;9499.335046546676;9902.356346837432;9957.352772069793;9973.60105595768;7091.468139889425;7387.852242955003;7460;7675.2324767522805;7720.112187098555;8244;8244.340452763645;8247.381446391424;8289.295409890226;8315.542645154575;7344.632768361667;7412.851742965218;7601.505902116345;7607.277308655669;7650.73222437212;7438.479306448497;7469.402447804271;7484.476086674098;7588.241175882367;7627.275408836152;7003.7548685795755;7269.672864721206;7275.236100209402;7275.272472752682;7338.449616278907;5065.746712664279;5126.53861152498;5159.09715799726;5213.973930130325;5272.051030814337;5126.077306084941;5126.359205099363;5138.922916156186;5140.049090918192;5188.896222075462 15:21:18 INFO - PROCESS | 3968 | |4;prototype.html;323.409777156689;327.7754738004467;332.9400707872582;333.27013839691057;334.09293767421445;5718.7998420055255;5742.9138562921535;5754.539636829054;5758.452946970042;5774.307083150028;5697.4302569743;5697.914531282034;5711.971440142804;5728.598998070137;5735.655860648364;4209.557996410379;4294.377315289281;4440.222960981831;4446.577575130357;4455.064436468346;4390.495093064291;4424.3142312941545;4442.644588432934;4448.488423831269;4465.531119232466;4407.625351845097;4436.936824376664;4453.6659750518675;4460.464744230697;4463.955360446386;4353.238183317922;4395.758233297167;4395.868123956287;4411.1618792429335;4431.889202769924;3829.8276577553797;3876;3877.8836634901;3880.5149356330458;3911.7261791674596;5456.008556876751;5496.395396506381;5537.58468114893;5539.972300138514;5543.029969755276;4753.714777113384;4993.151164302078;5011.724355160465;5026.6983980960895;5045.369328833895;5928.258967629046;5934.287885453773;5953.404659534055;6012.925540688456;6023.066424704134;4180.393842892794;4181.560936101696;4194.706370554062;4203;4203.327467605199;5977.340083662772;6183.907241391383;6213.658248796314;6238.003838771595;6308.810735677891;4224.831006759695;4253.8723838284905;4256.829726810893;4262.765547894834;4265;4296.162248361602;4310.439642846472;4317.251513137185;4321.935170972438;4329.523752387235;4862.902741945141;4948.802047918043;4980.701157930535;4988.176950803149;5005.34930459038;6060.484858787053;6093.75624974996;6094.8171554853425;6124.540659450559;6126.938730612636;1530.9311081001158;1559.8050243719536;1577.4952015354977;1578.084710867694;1581.6915701438108;1006.4464544500496;1020.4285600063988;1062.3838173859144;1096.270979798441;1096.5394534295615;6040.637561746309;6116.510679145657;6178.598391104564;6190.195274614362;6198.923360674225;6533.379328963741;6569.835754106186;6592.1430214072825;6617.636030018299;6637.86724265512;5739.741711622987;5897.557683173779;5986.3714309997695;5986.443524683614;5994.67029313392;3570.8571657133944;3588.2644057968055;3589.8205089745406;3597.1546686528645;3598.4062629665814;6225.595336303126;6521;6526;6563.9343606563325;7003.089598352181;6421.646809425433;6422.678866056772;6431;6476.740930362827;6524.934750652527;6302.968485157545;6487.351264873475;6528.249251336062;6558.770443034471;6586.34136586328;6236.501079913687;6243.625382476885;6258.716963022661;6268;6290.811275661737;6106.297775755667;6270.310265870842;6272.74909003653;6277.466415354746;6299.653519056313;6484.54097827985;6491.7865656500335;6518.380753828379;6520.021996700533;6542.672866356568;4208.179405015992;4273.252180868273;4297.076128632359;4299.097189590221;4303.763293018914;6142.907856382247;6174.197354343906;6202.945499265226;6245.96877015612;6305.4009869062365;6087.756489740361;6097.908531371944;6122.357152498924;6134.202553668173;6150.415710507495;5994.130851026664;6006.279246490449;6133.6319820810895;6143.938560614337;6153.3538978408415;5669.31968163823;5861.9706901465215;5984.451816619314;6015.5488338373925;6034.275886893601;6098.817035488943;6122.234720659918;6125.356837531995;6171.938280617136;6189.566730328834;5657.85855353603;5970.31341395745;5972.34304226526;5978.013627751372;5998.190244316961 15:21:18 INFO - PROCESS | 3968 | |5;yui.html;845.4927043773732;850.6554845287657;906.0079213261479;910.658503061352;926.1525703980856;14998.175100369475;15052.924735376322;15067.321970511339;15273.159976201305;15309.464168754095;12299.385030748472;12380.257184568933;12410.379481025957;12416;12434.564790232344;913.6802119258256;925.453982150531;929.6699671616581;929.7210836748982;930.3115694386139;899.2850683706437;902.5983437370372;926.5552534783308;932.1470854168427;933.6918816790443;945.5886689290181;955.8040601676639;959.4818797849153;961.6297725375711;961.9855702164473;955.3312681123242;956.3544607389983;957.248559880494;957.6887511558771;958.0802429667527;701.4003027411541;701.9087518622597;702.7540360873704;703.4196787650158;704.1198501872659;14883.107013579112;14943.02870313437;14982;15116.09303441786;15304.311305991258;14184.858151418457;14210;14261.714765704732;14362.64093397663;14563;10378.688639340831;10531.631392901289;10931.617393391192;11024.393658348788;11069.169812264112;3869.787161706105;3920.5295364556437;3978.124812541236;4013.9197216055513;4069;56391.43608563944;56797.864042719324;56896.71551642257;57304.42695573033;57355.13967290536;11168.162387820947;11348.319100853974;11387.14596405273;11425.485853136628;11430;18719.812801872078;19032.52418689516;19055.333063342718;19314.227430902607;19705.310314138937;8751.693690720795;8763;8769.166929141722;8819.840393369823;8875.156860098281;4879.682820616648;4897.314375987365;4920.507949205051;5056.544910958031;5120.48795120485;3579.964200358015;3621.1852333224815;3640.581333146722;3645.4167333226555;3655.5247817784048;633.5280216238839;648.2318452633644;660.4023358860239;660.4320284555279;669.2069897171867;690.547691262214;796.7888509544975;813.487502873198;815.4454970619917;815.9918400815916;7603.315701586884;7776.572288524072;7802.024746906637;7914.16901225363;8192.54940978252;733.4609062339283;757.2768006553728;794.9920500795033;801.6392623319413;814.7067055859885;4029.5567487576345;4191.350340697197;4241.921458485382;4249.957500425018;4355.499117601453;58609.41390586125;59597.40402596005;59865;63703;64396.678016609614;57874.7106264466;58626;58841.41158588445;60140.69929650324;60722.089168662504;53826.7308663462;53924.73037634787;54564.72717636466;55266.417602316134;55923.440765591826;9845;10002.24983126283;10070.244731645009;10083.14293285079;10227.693169204937;34819.12952176216;35933;35941.28117437689;36085;36524.26951461009;44404.55595444004;47996.52003479921;48201.517984821105;48621;48880.75559622179;12934.870651293366;12955.157914735511;13087.803682944574;13143.802842957555;13325.466981320638;13758.518451854137;13772;13826.239556824472;14113.717725645634;14120;13434.395452204479;13808.585742427744;14005.299735013004;14112.153270803785;14347.784783228468;13451;13461.394237259543;13556.59330220095;13691.110077844909;14114.435422582981;12970.28663423483;13189.538366157522;13389.531366402129;13493;13985.370658320604;11233.887661123284;11268.436578171222;11432;11442.713932151762;11489.655310340702;11108.833367499656;11161;11190.664280071285;11208.159388046091;11428 15:21:18 INFO - PROCESS | 3968 | __end_tp_report 15:21:18 INFO - PROCESS | 3968 | __start_cc_report 15:21:18 INFO - PROCESS | 3968 | _x_x_mozilla_cycle_collect,334 15:21:18 INFO - PROCESS | 3968 | __end_cc_report 15:21:18 INFO - PROCESS | 3968 | __startTimestamp1462486878419__endTimestamp 15:21:18 INFO - PROCESS | 3968 | 15:21:18 INFO - PROCESS | 3968 | ------- Summary: start ------- 15:21:18 INFO - PROCESS | 3968 | Number of tests: 6 15:21:18 INFO - PROCESS | 3968 | 15:21:18 INFO - PROCESS | 3968 | [#0] dojo.html Cycles:165 Average:5632.74 Median:5889.53 stddev:627.42 (10.7%) stddev-sans-first:601.40 15:21:18 INFO - PROCESS | 3968 | Values: 3272.2 3318.3 3399.3 3445.8 3446.1 5637.8 5651.6 5659.9 5665.3 5666.3 5639.7 5665.5 5685.3 5685.4 5692.5 5145.6 5179.4 5219.8 5250.6 5264.4 5068.9 5168.0 5255.0 5315.5 5316.5 5124.7 5199.9 5214.5 5314.3 5316.8 5190.9 5197.0 5198.2 5200.7 5211.7 5014.9 5088.8 5177.4 5195.4 5207.5 5374.2 5504.8 5573.5 5585.4 5589.0 5980.9 6024.8 6030.4 6030.6 6041.0 5879.0 6056.2 6074.9 6096.3 6100.8 5634.0 5677.1 5687.8 5693.6 5729.9 6205.4 6206.8 6216.0 6218.5 6224.3 5843.5 5934.8 5944.1 5947.1 5966.9 6049.1 6083.4 6084.5 6088.8 6122.5 5979.4 6049.7 6050.8 6066.2 6081.2 5898.2 5932.5 5935.0 6077.1 6080.7 4548.5 4551.9 4556.5 4572.8 4585.5 4088.5 4101.8 4103.4 4106.1 4131.8 5800.8 5871.9 5902.6 5922.7 5959.0 5953.9 5967.2 5994.5 6052.5 6104.5 5765.5 5786.7 5803.4 5813.0 5831.1 5064.2 5171.6 5183.7 5214.5 5215.9 6186.8 6225.2 6230.2 6239.1 6276.4 5982.7 6033.0 6050.1 6050.3 6085.8 6176.8 6285.3 6305.1 6308.8 6309.3 5960.2 5978.0 5984.6 6000.6 6011.4 5916.1 6053.2 6067.8 6080.6 6089.7 5956.5 5972.2 5973.3 5976.8 6023.2 5914.1 5954.0 5978.3 5986.6 5986.9 5764.7 5802.0 5850.8 5889.5 5901.0 5899.4 5907.3 5911.0 5946.7 5962.2 5769.9 5803.6 5806.6 5809.3 5824.8 15:21:18 INFO - PROCESS | 3968 | 15:21:18 INFO - PROCESS | 3968 | [#1] ext.html Cycles:180 Average:55791.34 Median:42881.00 stddev:103615.47 (241.6%) stddev-sans-first:103823.45 15:21:18 INFO - PROCESS | 3968 | Values: 658.1 671.7 681.6 719.9 727.4 32624.7 38898.4 39843.4 39902.6 39928.8 42335.2 42903.0 42935.4 43172.4 43190.4 8873.7 9090.0 9096.0 9116.8 9119.2 8996.6 9085.5 9105.6 9115.1 9135.0 8987.7 9026.9 9079.1 9081.5 9132.2 9087.4 9098.2 9127.6 9143.5 9160.5 8900.9 9078.9 9086.4 9087.2 9103.9 43657.9 44102.0 44364.1 44582.0 44716.0 3981.2 3999.7 4048.3 4101.5 4500.4 31875.2 33905.8 34192.8 34235.7 35039.1 4532.8 4593.8 4617.7 4638.5 4657.3 287313.0 292038.0 293239.0 293365.0 296357.0 65254.7 65690.0 65945.3 66014.0 66201.7 58100.0 59116.0 59223.4 59630.0 69378.3 18241.9 18388.1 18419.2 18461.9 18504.4 13415.3 13496.3 13662.2 13664.2 13681.7 19742.5 19828.4 20002.1 20005.9 20121.9 7656.2 7766.3 7924.8 8055.1 8057.6 1091.0 1133.9 1159.0 1167.4 1211.6 32810.0 32970.7 33276.5 33348.2 33755.7 1159.3 1211.6 1217.9 1222.5 1229.9 15924.8 16125.7 16151.5 16173.1 16302.8 313701.0 321022.0 321722.0 321765.0 521409.0 56388.4 56945.4 57004.7 57190.7 57285.7 1557.8 1566.2 1574.1 1576.3 1593.2 31348.5 31880.7 32356.2 32538.7 32618.8 52243.2 52569.2 53085.7 53093.5 53179.2 486506.0 487931.0 489783.0 490614.0 492616.0 31331.8 31520.8 31588.0 31703.2 31708.5 49251.8 49353.0 49434.5 50139.0 51093.2 41941.0 42591.6 42859.0 43968.0 44273.6 42154.6 42941.4 43311.8 43808.1 44146.8 34746.7 38005.2 38035.8 43170.8 44191.3 33712.5 34826.3 35158.3 35530.6 35679.0 27532.6 28053.2 28205.2 28334.0 28420.6 15:21:18 INFO - PROCESS | 3968 | 15:21:18 INFO - PROCESS | 3968 | [#2] jquery.html Cycles:180 Average:30370.37 Median:2083.91 stddev:85749.13 (4114.8%) stddev-sans-first:85960.15 15:21:18 INFO - PROCESS | 3968 | Values: 403.5 410.2 421.6 423.1 424.9 18566.9 19112.5 19170.7 19382.6 20220.7 10801.1 10988.9 11215.3 11365.7 11457.7 5006.6 5141.8 5151.6 5164.6 5167.3 4991.1 5731.5 5765.7 5768.3 5770.6 5663.7 5666.1 5700.0 5714.4 5765.9 5464.8 5728.5 5740.0 5741.1 5741.1 4971.0 5094.6 5118.3 5136.4 5136.6 22938.0 23493.4 23583.9 23611.5 24039.3 2377.2 2390.6 2421.7 2668.9 2758.5 10256.6 10298.2 10427.6 13357.9 14367.9 1931.7 2051.0 2063.5 2069.5 2164.8 81283.6 87079.0 87320.0 87844.0 88232.0 11384.3 11754.2 11882.9 11924.5 12087.5 15911.1 16109.8 16118.4 16159.9 16185.0 8346.4 8373.4 8374.7 8521.8 8621.5 8250.2 8439.2 8485.9 8532.5 8581.6 4755.8 4854.5 4877.8 4884.4 4946.2 2000.2 2027.0 2061.9 2075.8 2092.0 936.5 1067.1 1097.5 1113.9 1115.5 19425.1 19557.3 19575.3 19720.9 19725.8 1061.6 1099.6 1122.4 1141.0 1141.7 11010.2 11126.2 11134.5 11246.7 11256.4 496880.0 497447.0 527965.0 531755.0 535820.0 103621.0 107892.0 109697.0 110000.4 110979.0 1111.1 1145.6 1147.0 1149.1 1156.3 17866.1 17885.5 17944.9 18106.9 18380.8 39772.4 40566.8 40741.4 41216.6 41371.4 55088.4 55451.9 56267.0 58313.0 58997.4 8534.0 8704.8 8729.8 8816.2 8865.2 13070.8 13955.2 14089.9 14163.4 14306.9 12366.2 12693.6 12738.1 12741.7 12861.6 8845.6 12131.2 12282.1 12471.7 12902.4 10666.9 12108.3 12467.3 12714.1 13039.2 8908.5 8983.6 9002.4 9002.6 9110.4 12790.4 13002.3 13014.7 13047.9 13068.0 15:21:18 INFO - PROCESS | 3968 | 15:21:18 INFO - PROCESS | 3968 | [#3] mootools.html Cycles:180 Average:5572.60 Median:5126.22 stddev:3084.65 (60.2%) stddev-sans-first:3078.83 15:21:18 INFO - PROCESS | 3968 | Values: 1596.5 1615.5 1834.5 1869.4 1963.7 7615.7 8112.0 8276.7 8481.6 8507.9 8309.0 8337.2 8368.2 8392.7 8394.0 2366.5 2381.2 2391.3 2405.1 2405.8 2281.3 2304.1 2368.7 2369.4 2370.6 2318.9 2322.0 2337.1 2342.3 2349.2 2294.3 2298.0 2310.9 2316.3 2317.7 2246.0 2273.9 2275.4 2280.1 2282.0 8480.1 8538.5 8589.2 8594.8 8597.1 5110.7 5326.7 5330.7 5333.3 5335.3 3146.0 3165.0 3335.1 3358.8 3366.2 908.9 914.2 958.8 1106.3 1132.4 10906.4 10938.6 10988.4 11020.2 11023.7 4211.1 4280.6 4313.1 4336.8 4396.8 8943.5 8971.2 9154.0 9186.5 9207.5 5072.3 5091.5 5101.3 5106.3 5127.2 4320.1 4746.8 4808.1 4821.9 4889.4 3761.8 3784.4 3853.4 3866.7 3922.2 1116.1 1138.6 1145.7 1146.0 1149.7 992.8 1007.6 1018.7 1025.8 1031.4 5643.8 5713.1 5724.7 5761.2 5843.9 420.5 421.2 423.3 423.6 430.9 3934.2 3956.3 3967.7 3968.9 3975.7 9927.4 10424.1 10500.3 10523.7 10525.8 9866.0 10331.0 10339.4 10380.6 10435.7 7827.5 7882.5 7945.4 8042.2 8057.4 5766.7 5809.6 5844.3 5956.5 6026.1 9462.3 9534.4 9630.7 9764.5 9783.3 9483.3 9499.3 9902.4 9957.4 9973.6 7091.5 7387.9 7460.0 7675.2 7720.1 8244.0 8244.3 8247.4 8289.3 8315.5 7344.6 7412.9 7601.5 7607.3 7650.7 7438.5 7469.4 7484.5 7588.2 7627.3 7003.8 7269.7 7275.2 7275.3 7338.4 5065.7 5126.5 5159.1 5214.0 5272.1 5126.1 5126.4 5138.9 5140.0 5188.9 15:21:18 INFO - PROCESS | 3968 | 15:21:18 INFO - PROCESS | 3968 | [#4] prototype.html Cycles:180 Average:5106.47 Median:5756.50 stddev:1536.06 (26.7%) stddev-sans-first:1497.83 15:21:18 INFO - PROCESS | 3968 | Values: 323.4 327.8 332.9 333.3 334.1 5718.8 5742.9 5754.5 5758.5 5774.3 5697.4 5697.9 5712.0 5728.6 5735.7 4209.6 4294.4 4440.2 4446.6 4455.1 4390.5 4424.3 4442.6 4448.5 4465.5 4407.6 4436.9 4453.7 4460.5 4464.0 4353.2 4395.8 4395.9 4411.2 4431.9 3829.8 3876.0 3877.9 3880.5 3911.7 5456.0 5496.4 5537.6 5540.0 5543.0 4753.7 4993.2 5011.7 5026.7 5045.4 5928.3 5934.3 5953.4 6012.9 6023.1 4180.4 4181.6 4194.7 4203.0 4203.3 5977.3 6183.9 6213.7 6238.0 6308.8 4224.8 4253.9 4256.8 4262.8 4265.0 4296.2 4310.4 4317.3 4321.9 4329.5 4862.9 4948.8 4980.7 4988.2 5005.3 6060.5 6093.8 6094.8 6124.5 6126.9 1530.9 1559.8 1577.5 1578.1 1581.7 1006.4 1020.4 1062.4 1096.3 1096.5 6040.6 6116.5 6178.6 6190.2 6198.9 6533.4 6569.8 6592.1 6617.6 6637.9 5739.7 5897.6 5986.4 5986.4 5994.7 3570.9 3588.3 3589.8 3597.2 3598.4 6225.6 6521.0 6526.0 6563.9 7003.1 6421.6 6422.7 6431.0 6476.7 6524.9 6303.0 6487.4 6528.2 6558.8 6586.3 6236.5 6243.6 6258.7 6268.0 6290.8 6106.3 6270.3 6272.7 6277.5 6299.7 6484.5 6491.8 6518.4 6520.0 6542.7 4208.2 4273.3 4297.1 4299.1 4303.8 6142.9 6174.2 6202.9 6246.0 6305.4 6087.8 6097.9 6122.4 6134.2 6150.4 5994.1 6006.3 6133.6 6143.9 6153.4 5669.3 5862.0 5984.5 6015.5 6034.3 6098.8 6122.2 6125.4 6171.9 6189.6 5657.9 5970.3 5972.3 5978.0 5998.2 15:21:18 INFO - PROCESS | 3968 | 15:21:18 INFO - PROCESS | 3968 | [#5] yui.html Cycles:180 Average:15429.47 Median:4216.64 stddev:17855.84 (423.5%) stddev-sans-first:17872.35 15:21:18 INFO - PROCESS | 3968 | Values: 845.5 850.7 906.0 910.7 926.2 14998.2 15052.9 15067.3 15273.2 15309.5 12299.4 12380.3 12410.4 12416.0 12434.6 913.7 925.5 929.7 929.7 930.3 899.3 902.6 926.6 932.1 933.7 945.6 955.8 959.5 961.6 962.0 955.3 956.4 957.2 957.7 958.1 701.4 701.9 702.8 703.4 704.1 14883.1 14943.0 14982.0 15116.1 15304.3 14184.9 14210.0 14261.7 14362.6 14563.0 10378.7 10531.6 10931.6 11024.4 11069.2 3869.8 3920.5 3978.1 4013.9 4069.0 56391.4 56797.9 56896.7 57304.4 57355.1 11168.2 11348.3 11387.1 11425.5 11430.0 18719.8 19032.5 19055.3 19314.2 19705.3 8751.7 8763.0 8769.2 8819.8 8875.2 4879.7 4897.3 4920.5 5056.5 5120.5 3580.0 3621.2 3640.6 3645.4 3655.5 633.5 648.2 660.4 660.4 669.2 690.5 796.8 813.5 815.4 816.0 7603.3 7776.6 7802.0 7914.2 8192.5 733.5 757.3 795.0 801.6 814.7 4029.6 4191.4 4241.9 4250.0 4355.5 58609.4 59597.4 59865.0 63703.0 64396.7 57874.7 58626.0 58841.4 60140.7 60722.1 53826.7 53924.7 54564.7 55266.4 55923.4 9845.0 10002.2 10070.2 10083.1 10227.7 34819.1 35933.0 35941.3 36085.0 36524.3 44404.6 47996.5 48201.5 48621.0 48880.8 12934.9 12955.2 13087.8 13143.8 13325.5 13758.5 13772.0 13826.2 14113.7 14120.0 13434.4 13808.6 14005.3 14112.2 14347.8 13451.0 13461.4 13556.6 13691.1 14114.4 12970.3 13189.5 13389.5 13493.0 13985.4 11233.9 11268.4 11432.0 11442.7 11489.7 11108.8 11161.0 11190.7 11208.2 11428.0 15:21:18 INFO - PROCESS | 3968 | -------- Summary: end -------- 15:21:18 INFO - PROCESS | 3968 | 15:21:19 INFO - TEST-INFO | 3968: exit 0 15:21:19 INFO - TEST-OK | dromaeo_css | took 1109206ms 15:21:19 INFO - TEST-START | kraken 15:21:19 INFO - operating with platform_type : linux_ 15:21:19 INFO - Initialising browser for kraken test... 15:21:19 INFO - TEST-INFO | started process 4215 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps_UZSw/profile http://localhost:39669/getInfo.html) 15:21:22 INFO - PROCESS | 4215 | 15:21:22 INFO - PROCESS | 4215 | (process:4292): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 15:21:23 INFO - PROCESS | 4215 | __metrics Screen width/height:1600/1200 15:21:23 INFO - PROCESS | 4215 | colorDepth:24 15:21:23 INFO - PROCESS | 4215 | Browser inner width/height: 1024/697 15:21:23 INFO - PROCESS | 4215 | __metrics 15:21:24 ERROR - PROCESS | 4215 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 15:21:24 INFO - TEST-INFO | 4215: exit 0 15:21:24 INFO - Browser initialized. 15:21:24 INFO - Running cycle 1/1 for kraken test... 15:21:24 INFO - TEST-INFO | started process 4343 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps_UZSw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:21:26 INFO - PROCESS | 4343 | 15:21:26 INFO - PROCESS | 4343 | (process:4415): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 15:21:36 INFO - PROCESS | 4343 | RSS: Main: 152268800 15:21:36 INFO - PROCESS | 4343 | 15:21:39 INFO - PROCESS | 4343 | Cycle 1(1): loaded http://localhost:39669/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39669/tests/kraken/driver.html?testName=audio-beat-detection) 15:21:40 INFO - PROCESS | 4343 | RSS: Main: 157077504 15:21:40 INFO - PROCESS | 4343 | 15:21:42 INFO - PROCESS | 4343 | Cycle 1(1): loaded http://localhost:39669/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39669/tests/kraken/driver.html?testName=audio-dft) 15:21:43 INFO - PROCESS | 4343 | RSS: Main: 157253632 15:21:43 INFO - PROCESS | 4343 | 15:21:46 INFO - PROCESS | 4343 | Cycle 1(1): loaded http://localhost:39669/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39669/tests/kraken/driver.html?testName=audio-fft) 15:21:46 INFO - PROCESS | 4343 | RSS: Main: 157974528 15:21:46 INFO - PROCESS | 4343 | 15:21:48 INFO - PROCESS | 4343 | Cycle 1(1): loaded http://localhost:39669/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39669/tests/kraken/driver.html?testName=audio-oscillator) 15:21:49 INFO - PROCESS | 4343 | RSS: Main: 157917184 15:21:49 INFO - PROCESS | 4343 | 15:21:51 INFO - PROCESS | 4343 | Cycle 1(1): loaded http://localhost:39669/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39669/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:21:52 INFO - PROCESS | 4343 | RSS: Main: 158015488 15:21:52 INFO - PROCESS | 4343 | 15:21:55 INFO - PROCESS | 4343 | Cycle 1(1): loaded http://localhost:39669/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39669/tests/kraken/driver.html?testName=imaging-darkroom) 15:21:56 INFO - PROCESS | 4343 | RSS: Main: 157638656 15:21:56 INFO - PROCESS | 4343 | 15:21:59 INFO - PROCESS | 4343 | Cycle 1(1): loaded http://localhost:39669/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39669/tests/kraken/driver.html?testName=imaging-desaturate) 15:21:59 INFO - PROCESS | 4343 | RSS: Main: 157904896 15:21:59 INFO - PROCESS | 4343 | 15:22:02 INFO - PROCESS | 4343 | Cycle 1(1): loaded http://localhost:39669/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39669/tests/kraken/driver.html?testName=json-parse-financial) 15:22:03 INFO - PROCESS | 4343 | RSS: Main: 157970432 15:22:03 INFO - PROCESS | 4343 | 15:22:04 INFO - PROCESS | 4343 | Cycle 1(1): loaded http://localhost:39669/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39669/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:22:05 INFO - PROCESS | 4343 | RSS: Main: 157876224 15:22:05 INFO - PROCESS | 4343 | 15:22:07 INFO - PROCESS | 4343 | Cycle 1(1): loaded http://localhost:39669/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39669/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:22:07 INFO - PROCESS | 4343 | RSS: Main: 157769728 15:22:07 INFO - PROCESS | 4343 | 15:22:09 INFO - PROCESS | 4343 | Cycle 1(1): loaded http://localhost:39669/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39669/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:22:10 INFO - PROCESS | 4343 | RSS: Main: 157839360 15:22:10 INFO - PROCESS | 4343 | 15:22:13 INFO - PROCESS | 4343 | Cycle 1(1): loaded http://localhost:39669/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39669/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:22:13 INFO - PROCESS | 4343 | RSS: Main: 158400512 15:22:13 INFO - PROCESS | 4343 | 15:22:16 INFO - PROCESS | 4343 | Cycle 1(1): loaded http://localhost:39669/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39669/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:22:17 INFO - PROCESS | 4343 | RSS: Main: 158072832 15:22:17 INFO - PROCESS | 4343 | 15:22:18 INFO - PROCESS | 4343 | Cycle 1(1): loaded http://localhost:39669/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39669/tests/kraken/driver.html?testName=ai-astar) 15:22:19 INFO - PROCESS | 4343 | RSS: Main: 159748096 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | __start_tp_report 15:22:19 INFO - PROCESS | 4343 | _x_x_mozilla_page_load 15:22:19 INFO - PROCESS | 4343 | _x_x_mozilla_page_load_details 15:22:19 INFO - PROCESS | 4343 | |i|pagename|runs| 15:22:19 INFO - PROCESS | 4343 | |0;ai-astar;114;104;112;110;112;122;109;109;110;111 15:22:19 INFO - PROCESS | 4343 | |1;audio-beat-detection;162;126;148;181;131;163;154;134;180;136 15:22:19 INFO - PROCESS | 4343 | |2;audio-dft;162;161;161;162;162;164;162;164;163;160 15:22:19 INFO - PROCESS | 4343 | |3;audio-fft;76;73;77;168;74;76;76;76;161;74 15:22:19 INFO - PROCESS | 4343 | |4;audio-oscillator;93;92;150;92;91;91;148;92;91;140 15:22:19 INFO - PROCESS | 4343 | |5;imaging-gaussian-blur;117;117;117;117;118;116;117;118;117;117 15:22:19 INFO - PROCESS | 4343 | |6;imaging-darkroom;146;147;146;146;146;146;146;146;146;146 15:22:19 INFO - PROCESS | 4343 | |7;imaging-desaturate;95;95;95;96;95;95;96;95;95;95 15:22:19 INFO - PROCESS | 4343 | |8;json-parse-financial;56;61;60;57;58;59;64;85;58;57 15:22:19 INFO - PROCESS | 4343 | |9;json-stringify-tinderbox;58;59;59;59;59;59;58;58;58;59 15:22:19 INFO - PROCESS | 4343 | |10;stanford-crypto-aes;73;74;74;80;74;74;74;74;74;75 15:22:19 INFO - PROCESS | 4343 | |11;stanford-crypto-ccm;121;120;118;130;118;129;117;123;155;132 15:22:19 INFO - PROCESS | 4343 | |12;stanford-crypto-pbkdf2;165;159;169;165;158;160;165;160;160;168 15:22:19 INFO - PROCESS | 4343 | |13;stanford-crypto-sha256-iterative;67;66;73;67;72;66;68;63;66;67 15:22:19 INFO - PROCESS | 4343 | __end_tp_report 15:22:19 INFO - PROCESS | 4343 | __start_cc_report 15:22:19 INFO - PROCESS | 4343 | _x_x_mozilla_cycle_collect,856 15:22:19 INFO - PROCESS | 4343 | __end_cc_report 15:22:19 INFO - PROCESS | 4343 | __startTimestamp1462486939032__endTimestamp 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | ------- Summary: start ------- 15:22:19 INFO - PROCESS | 4343 | Number of tests: 14 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.50 stddev:4.60 (4.1%) stddev-sans-first:4.77 15:22:19 INFO - PROCESS | 4343 | Values: 114.0 104.0 112.0 110.0 112.0 122.0 109.0 109.0 110.0 111.0 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | [#1] audio-beat-detection Cycles:10 Average:151.50 Median:158.00 stddev:19.89 (12.6%) stddev-sans-first:20.73 15:22:19 INFO - PROCESS | 4343 | Values: 162.0 126.0 148.0 181.0 131.0 163.0 154.0 134.0 180.0 136.0 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:1.29 (0.8%) stddev-sans-first:1.36 15:22:19 INFO - PROCESS | 4343 | Values: 162.0 161.0 161.0 162.0 162.0 164.0 162.0 164.0 163.0 160.0 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | [#3] audio-fft Cycles:10 Average:93.10 Median:76.00 stddev:37.69 (49.6%) stddev-sans-first:39.46 15:22:19 INFO - PROCESS | 4343 | Values: 76.0 73.0 77.0 168.0 74.0 76.0 76.0 76.0 161.0 74.0 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | [#4] audio-oscillator Cycles:10 Average:108.00 Median:91.50 stddev:26.35 (28.8%) stddev-sans-first:27.38 15:22:19 INFO - PROCESS | 4343 | Values: 93.0 92.0 150.0 92.0 91.0 91.0 148.0 92.0 91.0 140.0 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 15:22:19 INFO - PROCESS | 4343 | Values: 117.0 117.0 117.0 117.0 118.0 116.0 117.0 118.0 117.0 117.0 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 15:22:19 INFO - PROCESS | 4343 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:22:19 INFO - PROCESS | 4343 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 95.0 95.0 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:59.50 stddev:8.58 (14.4%) stddev-sans-first:8.87 15:22:19 INFO - PROCESS | 4343 | Values: 56.0 61.0 60.0 57.0 58.0 59.0 64.0 85.0 58.0 57.0 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:0.52 (0.9%) stddev-sans-first:0.50 15:22:19 INFO - PROCESS | 4343 | Values: 58.0 59.0 59.0 59.0 59.0 59.0 58.0 58.0 58.0 59.0 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:74.00 stddev:1.96 (2.6%) stddev-sans-first:1.99 15:22:19 INFO - PROCESS | 4343 | Values: 73.0 74.0 74.0 80.0 74.0 74.0 74.0 74.0 74.0 75.0 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:126.00 stddev:11.45 (9.1%) stddev-sans-first:11.98 15:22:19 INFO - PROCESS | 4343 | Values: 121.0 120.0 118.0 130.0 118.0 129.0 117.0 123.0 155.0 132.0 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.90 Median:165.00 stddev:3.96 (2.4%) stddev-sans-first:4.12 15:22:19 INFO - PROCESS | 4343 | Values: 165.0 159.0 169.0 165.0 158.0 160.0 165.0 160.0 160.0 168.0 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - PROCESS | 4343 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.00 stddev:2.95 (4.4%) stddev-sans-first:3.13 15:22:19 INFO - PROCESS | 4343 | Values: 67.0 66.0 73.0 67.0 72.0 66.0 68.0 63.0 66.0 67.0 15:22:19 INFO - PROCESS | 4343 | -------- Summary: end -------- 15:22:19 INFO - PROCESS | 4343 | 15:22:19 INFO - TEST-INFO | 4343: exit 0 15:22:19 INFO - TEST-OK | kraken | took 60857ms 15:22:19 INFO - SUITE-END | took 1170s 15:22:20 INFO - Completed test suite (00:19:30) 15:22:20 INFO - javascript benchmark 15:22:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3272.231025708959, 3318.3363327334523, 3399.269157131216, 3445.793252404856, 3446.1384653836526, 5637.802676906309, 5651.604387692863, 5659.8585035374135, 5665.320161580606, 5666.26338575985, 5639.661620302784, 5665.518430933375, 5685.317761868581, 5685.374608793039, 5692.459216374448, 5145.562627176694, 5179.404368497614, 5219.79120835168, 5250.579953603703, 5264.42091369949, 5068.898622027557, 5168.043911876296, 5255.0, 5315.495027972356, 5316.494932981361, 5124.692518448886, 5199.948000520003, 5214.530692237698, 5314.3091398118195, 5316.787328506855, 5190.92213616796, 5197.0385478686185, 5198.194279886624, 5200.713960732158, 5211.687298762086, 5014.924776128361, 5088.77100530477, 5177.352830896138, 5195.428502864682, 5207.505286997767, 5374.193870919355, 5504.779808807642, 5573.470520300605, 5585.357683866367, 5588.972055139699, 5980.8504787380225, 6024.819255422344, 6030.366811484775, 6030.638161710312, 6041.033434650435, 5879.029960056586, 6056.182415373913, 6074.908876366858, 6096.268447786294, 6100.755969761204, 5633.971830140823, 5677.120046392815, 5687.800926967578, 5693.572982026323, 5729.88540229193, 6205.441510264008, 6206.751729930752, 6216.036514340284, 6218.502519798405, 6224.253089629274, 5843.503302219359, 5934.7922822701, 5944.078667806497, 5947.137665038632, 5966.910496342558, 6049.062395328731, 6083.361247069082, 6084.452399283997, 6088.817335479943, 6122.54080943931, 5979.402059793986, 6049.697515124314, 6050.788222412194, 6066.181065556182, 6081.209442772411, 5898.203742494794, 5932.49573786233, 5934.970325148347, 6077.1491991121275, 6080.695965201722, 4548.454185497695, 4551.908961820811, 4556.476005259371, 4572.839950601713, 4585.541445855388, 4088.509378874554, 4101.753894766323, 4103.4050062740735, 4106.096658735073, 4131.752094874257, 5800.8259752207505, 5871.941280587225, 5902.61632993854, 5922.674252916045, 5958.970205148947, 5953.940460595425, 5967.16459695643, 5994.520438364921, 6052.54605904559, 6104.481119104837, 5765.509931655856, 5786.681732504752, 5803.419658034163, 5812.9709351452975, 5831.067029275296, 5064.240363945364, 5171.612129090333, 5183.663061900965, 5214.45248248936, 5215.869603259949, 6186.81439556814, 6225.221847269091, 6230.221222347207, 6239.126522286883, 6276.435120839055, 5982.670953097621, 6033.0, 6050.092486127204, 6050.273967123973, 6085.786997455068, 6176.81469555914, 6285.308616052322, 6305.054241863757, 6308.842278943063, 6309.2744334400295, 5960.225170727777, 5978.0, 5984.581079324405, 6000.609960352563, 6011.368806275278, 5916.112583112567, 6053.152558641706, 6067.757289708362, 6080.604760690541, 6089.665068421279, 5956.493698035715, 5972.223610930551, 5973.313068996948, 5976.7908123215475, 6023.156758053964, 5914.083317085859, 5953.970230148821, 5978.312494063239, 5986.610870293417, 5986.850328741642, 5764.740586673526, 5802.0, 5850.824475265749, 5889.52883769306, 5901.0, 5899.380565040609, 5907.320658124198, 5911.0, 5946.672932988554, 5962.2249107615735, 5769.91345129815, 5803.593748437569, 5806.564507662027, 5809.331926828469, 5824.796132135355], "value": 5591.449168490442, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.1082633032241, 671.6708812681782, 681.57401623985, 719.9064121664182, 727.4071631620229, 32624.67375326246, 38898.41652375217, 39843.402348964715, 39902.60097399025, 39928.80035599822, 42335.15329693404, 42903.0, 42935.3559696604, 43172.35241471373, 43190.35214471778, 8873.733787986355, 9089.954550227272, 9096.0449152839, 9116.772080698003, 9119.224865886408, 8996.550172491381, 9085.500297483635, 9105.635774569042, 9115.088491150898, 9134.954325228364, 8987.685431009915, 9026.909730902673, 9079.092090790935, 9081.545922703872, 9132.223760980325, 9087.409318394317, 9098.226650734696, 9127.589258483384, 9143.451392916446, 9160.541972901328, 8900.910990890137, 9078.86381704275, 9086.363954523185, 9087.182153606142, 9103.908960910372, 43657.90855228613, 44102.0, 44364.11271774546, 44582.0, 44716.0, 3981.223661386002, 3999.6600288975474, 4048.271311163989, 4101.487314085739, 4500.437445319335, 31875.20311992219, 33905.83047084749, 34192.82903585491, 34235.657643423496, 35039.1240218994, 4532.79602417892, 4593.793279302439, 4617.746023968681, 4638.512956139591, 4657.347971284023, 287313.0, 292038.0, 293239.0, 293365.0, 296357.0, 65254.67372663107, 65690.01464978029, 65945.34054659391, 66014.0, 66201.66899165473, 58100.0, 59116.0, 59223.40776592179, 59630.0, 69378.30621693718, 18241.9054856709, 18388.080595970147, 18419.171137298854, 18461.907690461463, 18504.352347667766, 13415.329233538285, 13496.325183740773, 13662.248576328397, 13664.248466334247, 13681.657958551115, 19742.50643733918, 19828.405147845588, 20002.09990550429, 20005.899970500053, 20121.899390502953, 7656.234376562411, 7766.339861111755, 7924.762257132295, 8055.053024018189, 8057.6374063167295, 1091.0344345254507, 1133.9092872570175, 1158.9594364197212, 1167.3812879174052, 1211.6365090472823, 32810.01569952905, 32970.67029329676, 33276.500852486744, 33348.1662958428, 33755.66244337525, 1159.2754528419737, 1211.5577814097792, 1217.933013684256, 1222.5048855213652, 1229.9200551964093, 15924.76112858308, 16125.677486450206, 16151.515454536382, 16173.110478923772, 16302.755458668129, 313701.0, 321022.0, 321722.0, 321765.0, 521409.0, 56388.43611563914, 56945.43054569484, 57004.71497642485, 57190.7140464295, 57285.71357143271, 1557.7896983907253, 1566.248200863569, 1574.102761425999, 1576.2985471465317, 1593.1874743880473, 31348.52977205298, 31880.6811931887, 32356.191095222806, 32538.674613253563, 32618.836905816268, 52243.21635175552, 52569.21146182734, 53085.734571326895, 53093.4690653104, 53179.202311964575, 486506.0, 487931.0, 489783.0, 490614.0, 492616.0, 31331.84334078315, 31520.842395787873, 31588.0, 31703.207419814687, 31708.524372133976, 49251.75374123106, 49353.0, 49434.50565494443, 50139.0, 51093.23360149527, 41941.0, 42591.57408425876, 42859.0, 43968.0, 44273.55726442694, 42154.578454215065, 42941.35587966246, 43311.783441083855, 43808.12383752371, 44146.77926610475, 34746.65253347434, 38005.239895202496, 38035.809820950715, 43170.784146080325, 44191.33712994243, 33712.49431258484, 34826.303473930886, 35158.29683406369, 35530.644693552735, 35679.0, 27532.58701119525, 28053.158405247876, 28205.17944209083, 28333.95806782068, 28420.57369139423], "value": 20636.215431665183, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.4795114302549, 410.22262811971314, 421.5636815895548, 423.1030215942203, 424.9320108782595, 18566.90716546417, 19112.522186945316, 19170.712439313385, 19382.61234775304, 20220.696689549633, 10801.13590912727, 10988.890111098886, 11215.271007384514, 11365.659030229086, 11457.656270311883, 5006.624503162259, 5141.794328226866, 5151.613628977823, 5164.586833053366, 5167.328247327842, 4991.126552853254, 5731.484166425021, 5765.711714414283, 5768.336641286242, 5770.567207459437, 5663.66018038919, 5666.121751128581, 5699.971500142494, 5714.371419143891, 5765.855853603652, 5464.836054918339, 5728.484436400744, 5739.999999999979, 5741.052726300156, 5741.110127930177, 4971.0, 5094.6433749637545, 5118.25785261135, 5136.409312929024, 5136.589072874199, 22938.0, 23493.41266468335, 23583.882080589658, 23611.527769444685, 24039.27882163538, 2377.1561095811085, 2390.5935990881594, 2421.6609674645565, 2668.9457664222523, 2758.517259479583, 10256.589736410535, 10298.227632927561, 10427.635032773893, 13357.866421335759, 14367.85632143686, 1931.652302585534, 2050.98974505128, 2063.473814177375, 2069.4929742213103, 2164.7943445372666, 81283.5935820323, 87079.0, 87320.0, 87844.0, 88232.0, 11384.260023098472, 11754.23597466162, 11882.88117118835, 11924.463399146885, 12087.456064476944, 15911.124888131033, 16109.838901611069, 16118.435854745028, 16159.919200403923, 16185.0, 8346.374021948379, 8373.371997100241, 8374.748757537283, 8521.829563408697, 8621.52581608005, 8250.174982501701, 8439.240468357877, 8485.915140848636, 8532.488050716976, 8581.570921453902, 4755.785989630475, 4854.466008739036, 4877.829275975324, 4884.413870335582, 4946.159152944008, 2000.249906285143, 2026.9898650506948, 2061.948406312791, 2075.8443116766002, 2091.968620470694, 936.541094863512, 1067.055655744656, 1097.5445190246119, 1113.92202545821, 1115.486876037032, 19425.125869335912, 19557.31549395764, 19575.314863979693, 19720.91534965562, 19725.802741972682, 1061.596593294543, 1099.6151347028476, 1122.3939072900705, 1141.0, 1141.6632093532428, 11010.174236932262, 11126.165537584713, 11134.496842926263, 11246.7188320291, 11256.437178141061, 496880.0, 497447.0, 527965.0, 531755.0, 535820.0, 103621.0, 107892.0, 109697.0, 110000.4499977495, 110979.0, 1111.1000089927024, 1145.593314373402, 1146.9942650286862, 1149.0979581028853, 1156.3004382348727, 17866.106694665476, 17885.46343609694, 17944.91027544854, 18106.91358518493, 18380.816191838447, 39772.40341394823, 40566.79716601398, 40741.38887916743, 41216.587834121274, 41371.379429307985, 55088.44911550833, 55451.86248703721, 56267.0, 58313.0, 58997.41002589919, 8533.957330213309, 8704.825903482022, 8729.781755456164, 8816.206541411304, 8865.24645405122, 13070.80393794113, 13955.162690238618, 14089.929550352183, 14163.433462661378, 14306.856931430553, 12366.19619724715, 12693.619191424272, 12738.108332416641, 12741.681457963254, 12861.614151575468, 8845.557722113997, 12131.150819442555, 12282.140250182401, 12471.688207794878, 12902.419391127609, 10666.933306669582, 12108.273503589813, 12467.314297713712, 12714.11001229905, 13039.152455090389, 8908.465492070496, 8983.595738191927, 9002.36983411181, 9002.63989440415, 9110.36227464071, 12790.424430900446, 13002.284874332003, 13014.739705205653, 13047.869521304665, 13068.0], "value": 9445.795310835807, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1596.5290239379383, 1615.5395712222016, 1834.5413646588354, 1869.4111354923207, 1963.6759934610782, 7615.657295421705, 8112.0, 8276.668933242678, 8481.618327175269, 8507.872381914269, 8309.0, 8337.16628337166, 8368.163183681629, 8392.66429342827, 8393.958030209855, 2366.4912043910554, 2381.154690085021, 2391.270662447953, 2405.0740464920955, 2405.8195635327334, 2281.349815302639, 2304.066852924568, 2368.656544801003, 2369.3839601703507, 2370.608849539824, 2318.918837840676, 2321.976780232193, 2337.1118974789556, 2342.2504798464497, 2349.2130136404326, 2294.3117064880553, 2298.0, 2310.9306720798404, 2316.328264803205, 2317.687112239843, 2245.9663105053437, 2273.9090436382526, 2275.4311422144465, 2280.0651732789643, 2282.030137191687, 8480.067192608809, 8538.530380829052, 8589.226969572768, 8594.828103437896, 8597.140285971416, 5110.693358398508, 5326.733663316819, 5330.680159190422, 5333.306670132897, 5335.306410166692, 3145.9685403145904, 3164.9683503165134, 3335.1328654549993, 3358.8488518016748, 3366.175287054663, 908.877301564287, 914.2411798207472, 958.8082383523323, 1106.2864452428219, 1132.4054871192607, 10906.400147991857, 10938.617148399848, 10988.395638239894, 11020.228583999124, 11023.724406889813, 4211.073563815956, 4280.59334363235, 4313.137372525507, 4336.76147811867, 4396.758178300161, 8943.46339219636, 8971.237444817263, 9154.0, 9186.494742789077, 9207.539623018822, 5072.264521644414, 5091.541761241506, 5101.336826212643, 5106.310648062538, 5127.179651255781, 4320.135972805451, 4746.762661866893, 4808.0624278266105, 4821.879453013703, 4889.3888263967, 3761.774293542396, 3784.3566593679143, 3853.3641949078647, 3866.69066474687, 3922.2351641429645, 1116.1405717597404, 1138.6242539961959, 1145.707844499664, 1145.954161833534, 1149.7183190118308, 992.7617371830853, 1007.5516395204211, 1018.7096677446892, 1025.825609646362, 1031.3502493429091, 5643.786585883972, 5713.114467257582, 5724.656520608777, 5761.222234998221, 5843.883122337529, 420.46600816962285, 421.1765997474929, 423.33325013104485, 423.58911855500116, 430.9482862056511, 3934.2328245991757, 3956.2680904032845, 3967.742096763759, 3968.8809335719975, 3975.6819454443576, 9927.354721943051, 10424.113950314308, 10500.317479363817, 10523.684289471328, 10525.789484210427, 9866.0, 10330.966903309609, 10339.379637221791, 10380.636677716244, 10435.68692939213, 7827.530348179127, 7882.487638303606, 7945.364370850318, 8042.235987581287, 8057.43597948138, 5766.740496677576, 5809.622374545641, 5844.298684157929, 5956.523478121653, 6026.126211699367, 9462.337636365388, 9534.427934323963, 9630.711078667651, 9764.511774411392, 9783.315167938175, 9483.336166468556, 9499.335046546676, 9902.356346837432, 9957.352772069793, 9973.60105595768, 7091.468139889425, 7387.852242955003, 7460.0, 7675.2324767522805, 7720.112187098555, 8244.0, 8244.340452763645, 8247.381446391424, 8289.295409890226, 8315.542645154575, 7344.632768361667, 7412.851742965218, 7601.505902116345, 7607.277308655669, 7650.73222437212, 7438.479306448497, 7469.402447804271, 7484.476086674098, 7588.241175882367, 7627.275408836152, 7003.7548685795755, 7269.672864721206, 7275.236100209402, 7275.272472752682, 7338.449616278907, 5065.746712664279, 5126.53861152498, 5159.09715799726, 5213.973930130325, 5272.051030814337, 5126.077306084941, 5126.359205099363, 5138.922916156186, 5140.049090918192, 5188.896222075462], "value": 4420.907078378942, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.409777156689, 327.7754738004467, 332.9400707872582, 333.27013839691057, 334.09293767421445, 5718.7998420055255, 5742.9138562921535, 5754.539636829054, 5758.452946970042, 5774.307083150028, 5697.4302569743, 5697.914531282034, 5711.971440142804, 5728.598998070137, 5735.655860648364, 4209.557996410379, 4294.377315289281, 4440.222960981831, 4446.577575130357, 4455.064436468346, 4390.495093064291, 4424.3142312941545, 4442.644588432934, 4448.488423831269, 4465.531119232466, 4407.625351845097, 4436.936824376664, 4453.6659750518675, 4460.464744230697, 4463.955360446386, 4353.238183317922, 4395.758233297167, 4395.868123956287, 4411.1618792429335, 4431.889202769924, 3829.8276577553797, 3876.0, 3877.8836634901, 3880.5149356330458, 3911.7261791674596, 5456.008556876751, 5496.395396506381, 5537.58468114893, 5539.972300138514, 5543.029969755276, 4753.714777113384, 4993.151164302078, 5011.724355160465, 5026.6983980960895, 5045.369328833895, 5928.258967629046, 5934.287885453773, 5953.404659534055, 6012.925540688456, 6023.066424704134, 4180.393842892794, 4181.560936101696, 4194.706370554062, 4203.0, 4203.327467605199, 5977.340083662772, 6183.907241391383, 6213.658248796314, 6238.003838771595, 6308.810735677891, 4224.831006759695, 4253.8723838284905, 4256.829726810893, 4262.765547894834, 4265.0, 4296.162248361602, 4310.439642846472, 4317.251513137185, 4321.935170972438, 4329.523752387235, 4862.902741945141, 4948.802047918043, 4980.701157930535, 4988.176950803149, 5005.34930459038, 6060.484858787053, 6093.75624974996, 6094.8171554853425, 6124.540659450559, 6126.938730612636, 1530.9311081001158, 1559.8050243719536, 1577.4952015354977, 1578.084710867694, 1581.6915701438108, 1006.4464544500496, 1020.4285600063988, 1062.3838173859144, 1096.270979798441, 1096.5394534295615, 6040.637561746309, 6116.510679145657, 6178.598391104564, 6190.195274614362, 6198.923360674225, 6533.379328963741, 6569.835754106186, 6592.1430214072825, 6617.636030018299, 6637.86724265512, 5739.741711622987, 5897.557683173779, 5986.3714309997695, 5986.443524683614, 5994.67029313392, 3570.8571657133944, 3588.2644057968055, 3589.8205089745406, 3597.1546686528645, 3598.4062629665814, 6225.595336303126, 6521.0, 6526.0, 6563.9343606563325, 7003.089598352181, 6421.646809425433, 6422.678866056772, 6431.0, 6476.740930362827, 6524.934750652527, 6302.968485157545, 6487.351264873475, 6528.249251336062, 6558.770443034471, 6586.34136586328, 6236.501079913687, 6243.625382476885, 6258.716963022661, 6268.0, 6290.811275661737, 6106.297775755667, 6270.310265870842, 6272.74909003653, 6277.466415354746, 6299.653519056313, 6484.54097827985, 6491.7865656500335, 6518.380753828379, 6520.021996700533, 6542.672866356568, 4208.179405015992, 4273.252180868273, 4297.076128632359, 4299.097189590221, 4303.763293018914, 6142.907856382247, 6174.197354343906, 6202.945499265226, 6245.96877015612, 6305.4009869062365, 6087.756489740361, 6097.908531371944, 6122.357152498924, 6134.202553668173, 6150.415710507495, 5994.130851026664, 6006.279246490449, 6133.6319820810895, 6143.938560614337, 6153.3538978408415, 5669.31968163823, 5861.9706901465215, 5984.451816619314, 6015.5488338373925, 6034.275886893601, 6098.817035488943, 6122.234720659918, 6125.356837531995, 6171.938280617136, 6189.566730328834, 5657.85855353603, 5970.31341395745, 5972.34304226526, 5978.013627751372, 5998.190244316961], "value": 4612.376629828432, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [845.4927043773732, 850.6554845287657, 906.0079213261479, 910.658503061352, 926.1525703980856, 14998.175100369475, 15052.924735376322, 15067.321970511339, 15273.159976201305, 15309.464168754095, 12299.385030748472, 12380.257184568933, 12410.379481025957, 12416.0, 12434.564790232344, 913.6802119258256, 925.453982150531, 929.6699671616581, 929.7210836748982, 930.3115694386139, 899.2850683706437, 902.5983437370372, 926.5552534783308, 932.1470854168427, 933.6918816790443, 945.5886689290181, 955.8040601676639, 959.4818797849153, 961.6297725375711, 961.9855702164473, 955.3312681123242, 956.3544607389983, 957.248559880494, 957.6887511558771, 958.0802429667527, 701.4003027411541, 701.9087518622597, 702.7540360873704, 703.4196787650158, 704.1198501872659, 14883.107013579112, 14943.02870313437, 14982.0, 15116.09303441786, 15304.311305991258, 14184.858151418457, 14210.0, 14261.714765704732, 14362.64093397663, 14563.0, 10378.688639340831, 10531.631392901289, 10931.617393391192, 11024.393658348788, 11069.169812264112, 3869.787161706105, 3920.5295364556437, 3978.124812541236, 4013.9197216055513, 4069.0, 56391.43608563944, 56797.864042719324, 56896.71551642257, 57304.42695573033, 57355.13967290536, 11168.162387820947, 11348.319100853974, 11387.14596405273, 11425.485853136628, 11430.0, 18719.812801872078, 19032.52418689516, 19055.333063342718, 19314.227430902607, 19705.310314138937, 8751.693690720795, 8763.0, 8769.166929141722, 8819.840393369823, 8875.156860098281, 4879.682820616648, 4897.314375987365, 4920.507949205051, 5056.544910958031, 5120.48795120485, 3579.964200358015, 3621.1852333224815, 3640.581333146722, 3645.4167333226555, 3655.5247817784048, 633.5280216238839, 648.2318452633644, 660.4023358860239, 660.4320284555279, 669.2069897171867, 690.547691262214, 796.7888509544975, 813.487502873198, 815.4454970619917, 815.9918400815916, 7603.315701586884, 7776.572288524072, 7802.024746906637, 7914.16901225363, 8192.54940978252, 733.4609062339283, 757.2768006553728, 794.9920500795033, 801.6392623319413, 814.7067055859885, 4029.5567487576345, 4191.350340697197, 4241.921458485382, 4249.957500425018, 4355.499117601453, 58609.41390586125, 59597.40402596005, 59865.0, 63703.0, 64396.678016609614, 57874.7106264466, 58626.0, 58841.41158588445, 60140.69929650324, 60722.089168662504, 53826.7308663462, 53924.73037634787, 54564.72717636466, 55266.417602316134, 55923.440765591826, 9845.0, 10002.24983126283, 10070.244731645009, 10083.14293285079, 10227.693169204937, 34819.12952176216, 35933.0, 35941.28117437689, 36085.0, 36524.26951461009, 44404.55595444004, 47996.52003479921, 48201.517984821105, 48621.0, 48880.75559622179, 12934.870651293366, 12955.157914735511, 13087.803682944574, 13143.802842957555, 13325.466981320638, 13758.518451854137, 13772.0, 13826.239556824472, 14113.717725645634, 14120.0, 13434.395452204479, 13808.585742427744, 14005.299735013004, 14112.153270803785, 14347.784783228468, 13451.0, 13461.394237259543, 13556.59330220095, 13691.110077844909, 14114.435422582981, 12970.28663423483, 13189.538366157522, 13389.531366402129, 13493.0, 13985.370658320604, 11233.887661123284, 11268.436578171222, 11432.0, 11442.713932151762, 11489.655310340702, 11108.833367499656, 11161.0, 11190.664280071285, 11208.159388046091, 11428.0], "value": 7102.675189045343, "unit": "score"}], "name": "dromaeo_css", "value": 7351.610236621078, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 104.0, 112.0, 110.0, 112.0, 122.0, 109.0, 109.0, 110.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 126.0, 148.0, 181.0, 131.0, 163.0, 154.0, 134.0, 180.0, 136.0], "value": 151.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 162.0, 162.0, 164.0, 162.0, 164.0, 163.0, 160.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 77.0, 168.0, 74.0, 76.0, 76.0, 76.0, 161.0, 74.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 150.0, 92.0, 91.0, 91.0, 148.0, 92.0, 91.0, 140.0], "value": 108.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 116.0, 117.0, 118.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 60.0, 57.0, 58.0, 59.0, 64.0, 85.0, 58.0, 57.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 59.0, 59.0, 59.0, 58.0, 58.0, 58.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 80.0, 74.0, 74.0, 74.0, 74.0, 74.0, 75.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 120.0, 118.0, 130.0, 118.0, 129.0, 117.0, 123.0, 155.0, 132.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 159.0, 169.0, 165.0, 158.0, 160.0, 165.0, 160.0, 160.0, 168.0], "value": 162.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 73.0, 67.0, 72.0, 66.0, 68.0, 63.0, 66.0, 67.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1535.8, "alertThreshold": 2.0}]} 15:22:20 INFO - Return code: 0 15:22:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:22:20 ERROR - # TBPL SUCCESS # 15:22:20 INFO - Running post-action listener: _resource_record_post_action 15:22:20 INFO - Running post-run listener: _resource_record_post_run 15:22:21 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 724992; Write bytes: 262070272; Read time: 132; Write time: 144896 15:22:21 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 13344768; Read time: 0; Write time: 4188 15:22:21 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 724992; Write bytes: 248725504; Read time: 132; Write time: 140708 15:22:21 INFO - Running post-run listener: _upload_blobber_files 15:22:21 INFO - Blob upload gear active. 15:22:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:22:21 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:22:21 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'] 15:22:21 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 15:22:21 INFO - (blobuploader) - INFO - Open directory for files ... 15:22:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:22:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:22:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:22 INFO - (blobuploader) - INFO - Done attempting. 15:22:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:22:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:22:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:23 INFO - (blobuploader) - INFO - Done attempting. 15:22:23 INFO - (blobuploader) - INFO - Iteration through files over. 15:22:23 INFO - Return code: 0 15:22:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:22:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:22:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a806079f59ca1fd517d0b660b638567d87c798d34be6b1623040d7c76d34620b760a1bf748e9fc6e87339d7a2032cbb05c88fde2433f152307b38c3e083dcb6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47dcec25420455ecae551620068b4568491f5ee920e221aea470a6b15e3395503e4d51ec104c3ec233c99fc74f0d773800be5e591ac5ccb79d45828d4f2edc37"} 15:22:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:22:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:22:23 INFO - Contents: 15:22:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a806079f59ca1fd517d0b660b638567d87c798d34be6b1623040d7c76d34620b760a1bf748e9fc6e87339d7a2032cbb05c88fde2433f152307b38c3e083dcb6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47dcec25420455ecae551620068b4568491f5ee920e221aea470a6b15e3395503e4d51ec104c3ec233c99fc74f0d773800be5e591ac5ccb79d45828d4f2edc37"} 15:22:23 INFO - Running post-run listener: copy_logs_to_upload_dir 15:22:23 INFO - Copying logs to upload dir... 15:22:23 INFO - mkdir: /builds/slave/test/build/upload/logs 15:22:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.519389 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-05-05 15:22:23.370830) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 15:22:23.373827) ========= 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=4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a806079f59ca1fd517d0b660b638567d87c798d34be6b1623040d7c76d34620b760a1bf748e9fc6e87339d7a2032cbb05c88fde2433f152307b38c3e083dcb6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47dcec25420455ecae551620068b4568491f5ee920e221aea470a6b15e3395503e4d51ec104c3ec233c99fc74f0d773800be5e591ac5ccb79d45828d4f2edc37"} build_url:https://queue.taskcluster.net/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011182 build_url: 'https://queue.taskcluster.net/v1/task/Vymv414BQ3OHq8QOv4zgvQ/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a806079f59ca1fd517d0b660b638567d87c798d34be6b1623040d7c76d34620b760a1bf748e9fc6e87339d7a2032cbb05c88fde2433f152307b38c3e083dcb6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/47dcec25420455ecae551620068b4568491f5ee920e221aea470a6b15e3395503e4d51ec104c3ec233c99fc74f0d773800be5e591ac5ccb79d45828d4f2edc37"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 15:22:23.397715) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 15:22:23.398001) ========= 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=4ac8d263c7293b36a1be1aed000001be-1462485586.538961-1393426446 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004443 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 15:22:23.481078) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 15:22:23.481367) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 15:22:23.481642) ========= ========= Total master_lag: 0.18 =========