builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1462536753.46 results: success (0) buildid: 20160506023024 builduid: 2ec1a804da8d4a5c990c2f52d1a3b266 revision: e65c3c2186710e9ee1dfb3f254bd45019ea34c80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 05:12:33.462870) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 05:12:33.463401) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 05:12:33.463733) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 05:12:33.523414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:12:33.523942) ========= 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=00204705a9daa57ed339a427000001b9-1462535331.938214-342183942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-06 05:12:33-- 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% 222M=0s 2016-05-06 05:12:33 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.146157 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:12:33.687913) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:12:33.688234) ========= 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=00204705a9daa57ed339a427000001b9-1462535331.938214-342183942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017350 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:12:33.738762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:12:33.739119) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e65c3c2186710e9ee1dfb3f254bd45019ea34c80 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev e65c3c2186710e9ee1dfb3f254bd45019ea34c80 --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=00204705a9daa57ed339a427000001b9-1462535331.938214-342183942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-06 05:12:33,790 truncating revision to first 12 chars 2016-05-06 05:12:33,790 Setting DEBUG logging. 2016-05-06 05:12:33,790 attempt 1/10 2016-05-06 05:12:33,790 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e65c3c218671?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 05:12:34,120 unpacking tar archive at: fx-team-e65c3c218671/testing/mozharness/ program finished with exit code 0 elapsedTime=0.636675 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:12:34.391350) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 05:12:34.391773) ========= script_repo_revision: e65c3c2186710e9ee1dfb3f254bd45019ea34c80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 05:12:34.392282) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 05:12:34.392873) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 05:12:34.414078) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-05-06 05:12:34.414451) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=00204705a9daa57ed339a427000001b9-1462535331.938214-342183942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:12:34 INFO - MultiFileLogger online at 20160506 05:12:34 in /builds/slave/test 05:12:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:12:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:12:34 INFO - {'append_to_log': False, 05:12:34 INFO - 'base_work_dir': '/builds/slave/test', 05:12:34 INFO - 'blob_upload_branch': 'Fx-Team', 05:12:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:12:34 INFO - 'branch': 'Fx-Team', 05:12:34 INFO - 'buildbot_json_path': 'buildprops.json', 05:12:34 INFO - 'config_files': ('talos/linux_config.py',), 05:12:34 INFO - 'default_actions': ('clobber', 05:12:34 INFO - 'read-buildbot-config', 05:12:34 INFO - 'download-and-extract', 05:12:34 INFO - 'populate-webroot', 05:12:34 INFO - 'create-virtualenv', 05:12:34 INFO - 'install', 05:12:34 INFO - 'run-tests'), 05:12:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:12:34 INFO - 'download_minidump_stackwalk': True, 05:12:34 INFO - 'download_symbols': 'ondemand', 05:12:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:12:34 INFO - 'tooltool.py': '/tools/tooltool.py', 05:12:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:12:34 INFO - '/tools/misc-python/virtualenv.py')}, 05:12:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:12:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:12:34 INFO - 'installer_path': 'installer.exe', 05:12:34 INFO - 'log_level': 'info', 05:12:34 INFO - 'log_name': 'talos', 05:12:34 INFO - 'log_to_console': True, 05:12:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:12:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:12:34 INFO - 'opt_config_files': (), 05:12:34 INFO - 'pip_index': False, 05:12:34 INFO - 'sps_profile': False, 05:12:34 INFO - 'sps_profile_interval': 0, 05:12:34 INFO - 'suite': 'dromaeojs', 05:12:34 INFO - 'system_bits': '32', 05:12:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:12:34 INFO - 'title': 'talos-linux64-ix-016', 05:12:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:12:34 INFO - 'use_talos_json': True, 05:12:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:12:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:12:34 INFO - 'work_dir': 'build'} 05:12:34 INFO - ##### 05:12:34 INFO - ##### Running clobber step. 05:12:34 INFO - ##### 05:12:34 INFO - Running pre-action listener: _resource_record_pre_action 05:12:34 INFO - Running main action method: clobber 05:12:34 INFO - rmtree: /builds/slave/test/build 05:12:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:12:35 INFO - Running post-action listener: _resource_record_post_action 05:12:35 INFO - ##### 05:12:35 INFO - ##### Running read-buildbot-config step. 05:12:35 INFO - ##### 05:12:35 INFO - Running pre-action listener: _resource_record_pre_action 05:12:35 INFO - Running main action method: read_buildbot_config 05:12:35 INFO - Using buildbot properties: 05:12:35 INFO - { 05:12:35 INFO - "project": "", 05:12:35 INFO - "product": "firefox", 05:12:35 INFO - "script_repo_revision": "production", 05:12:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:12:35 INFO - "repository": "", 05:12:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:12:35 INFO - "buildid": "20160506023024", 05:12:35 INFO - "pgo_build": "True", 05:12:35 INFO - "basedir": "/builds/slave/test", 05:12:35 INFO - "buildnumber": 647, 05:12:35 INFO - "slavename": "talos-linux64-ix-016", 05:12:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:12:35 INFO - "platform": "ubuntu64_hw", 05:12:35 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:12:35 INFO - "branch": "fx-team", 05:12:35 INFO - "repo_path": "integration/fx-team", 05:12:35 INFO - "revision": "e65c3c2186710e9ee1dfb3f254bd45019ea34c80", 05:12:35 INFO - "stage_platform": "linux64-pgo", 05:12:35 INFO - "builduid": "2ec1a804da8d4a5c990c2f52d1a3b266", 05:12:35 INFO - "slavebuilddir": "test" 05:12:35 INFO - } 05:12:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 05:12:35 INFO - Running post-action listener: _resource_record_post_action 05:12:35 INFO - ##### 05:12:35 INFO - ##### Running download-and-extract step. 05:12:35 INFO - ##### 05:12:35 INFO - Running pre-action listener: _resource_record_pre_action 05:12:35 INFO - Running main action method: download_and_extract 05:12:35 INFO - mkdir: /builds/slave/test/build/tests 05:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:12:35 INFO - https://queue.taskcluster.net/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:12:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:12:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:12:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:12:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:12:47 INFO - Downloaded 1361 bytes. 05:12:47 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:12:47 INFO - Using the following test package requirements: 05:12:47 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:12:47 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:12:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:12:47 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:12:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:12:47 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:12:47 INFO - u'jsshell-linux-x86_64.zip'], 05:12:47 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:12:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:12:47 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:12:47 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:12:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:12:47 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:12:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:12:47 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:12:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:12:47 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:12:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:12:47 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:12:47 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')]} 05:12:47 INFO - https://queue.taskcluster.net/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:12:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:12:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:12:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:12:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:12:58 INFO - Downloaded 23894075 bytes. 05:12:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:12:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:12:59 INFO - Return code: 0 05:12:59 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:12:59 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')]} 05:12:59 INFO - https://queue.taskcluster.net/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:12:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:12:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:12:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:12:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:13:00 INFO - Downloaded 23894075 bytes. 05:13:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:13:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:13:02 INFO - Return code: 0 05:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:13:02 INFO - https://queue.taskcluster.net/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:13:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:13:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:13:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:13:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:13:03 INFO - Downloaded 10840152 bytes. 05:13:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:13:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:13:03 INFO - Return code: 0 05:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:13:03 INFO - https://queue.taskcluster.net/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:13:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:13:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:13:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:13:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:13:05 INFO - Downloaded 58411968 bytes. 05:13:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:13:05 INFO - mkdir: /builds/slave/test/properties 05:13:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:13:05 INFO - Writing to file /builds/slave/test/properties/build_url 05:13:05 INFO - Contents: 05:13:05 INFO - build_url:https://queue.taskcluster.net/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:13:06 INFO - Running post-action listener: _resource_record_post_action 05:13:06 INFO - Running post-action listener: set_extra_try_arguments 05:13:06 INFO - ##### 05:13:06 INFO - ##### Running populate-webroot step. 05:13:06 INFO - ##### 05:13:06 INFO - Running pre-action listener: _resource_record_pre_action 05:13:06 INFO - Running main action method: populate_webroot 05:13:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:13:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:13:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:13:06 INFO - '--tppagecycles', 05:13:06 INFO - '7'], 05:13:06 INFO - 'tests': ['tsvgm']}}, 05:13:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:13:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:13:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:13:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:13:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:13:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:13:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['damp', 'tps']}, 05:13:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'tests': ['damp', 'tps']}, 05:13:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['dromaeo_dom']}, 05:13:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:13:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['a11yr', 05:13:06 INFO - 'ts_paint', 05:13:06 INFO - 'tpaint', 05:13:06 INFO - 'sessionrestore', 05:13:06 INFO - 'sessionrestore_no_auto_restore', 05:13:06 INFO - 'tabpaint']}, 05:13:06 INFO - 'other-e10s': {'tests': ['a11yr', 05:13:06 INFO - 'ts_paint', 05:13:06 INFO - 'tpaint', 05:13:06 INFO - 'sessionrestore', 05:13:06 INFO - 'sessionrestore_no_auto_restore', 05:13:06 INFO - 'tabpaint']}, 05:13:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:13:06 INFO - 'ts_paint', 05:13:06 INFO - 'tpaint', 05:13:06 INFO - 'sessionrestore', 05:13:06 INFO - 'sessionrestore_no_auto_restore', 05:13:06 INFO - 'tabpaint']}, 05:13:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:13:06 INFO - 'ts_paint', 05:13:06 INFO - 'tpaint', 05:13:06 INFO - 'sessionrestore', 05:13:06 INFO - 'sessionrestore_no_auto_restore', 05:13:06 INFO - 'tabpaint']}, 05:13:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['a11yr', 05:13:06 INFO - 'ts_paint', 05:13:06 INFO - 'tpaint', 05:13:06 INFO - 'sessionrestore', 05:13:06 INFO - 'sessionrestore_no_auto_restore', 05:13:06 INFO - 'tabpaint']}, 05:13:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['a11yr', 05:13:06 INFO - 'ts_paint', 05:13:06 INFO - 'tpaint', 05:13:06 INFO - 'sessionrestore', 05:13:06 INFO - 'sessionrestore_no_auto_restore', 05:13:06 INFO - 'tabpaint']}, 05:13:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['tsvgx', 05:13:06 INFO - 'tsvgr_opacity', 05:13:06 INFO - 'tart', 05:13:06 INFO - 'tscrollx', 05:13:06 INFO - 'cart']}, 05:13:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:13:06 INFO - 'tsvgr_opacity', 05:13:06 INFO - 'tart', 05:13:06 INFO - 'tscrollx', 05:13:06 INFO - 'cart']}, 05:13:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['tp5o']}, 05:13:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'tests': ['tp5o']}, 05:13:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'talos_options': ['--disable-e10s', 05:13:06 INFO - '--xperf_path', 05:13:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:13:06 INFO - 'tests': ['tp5n']}, 05:13:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'talos_options': ['--xperf_path', 05:13:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:13:06 INFO - 'tests': ['tp5n']}}, 05:13:06 INFO - 'talos.zip': {'path': '', 05:13:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:13:06 INFO - Running post-action listener: _resource_record_post_action 05:13:06 INFO - ##### 05:13:06 INFO - ##### Running create-virtualenv step. 05:13:06 INFO - ##### 05:13:06 INFO - Running pre-action listener: _resource_record_pre_action 05:13:06 INFO - Running main action method: create_virtualenv 05:13:06 INFO - Creating virtualenv /builds/slave/test/build/venv 05:13:06 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 05:13:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:13:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:13:06 INFO - Using real prefix '/usr' 05:13:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:13:06 INFO - Installing distribute.............................................................................................................................................................................................done. 05:13:08 INFO - Installing pip.................done. 05:13:08 INFO - Return code: 0 05:13:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:13:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc134d3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b810>, '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 0x118c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cba0>, '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': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', '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 05:13:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:13:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:13:08 INFO - Using env: {'DISPLAY': ':0', 05:13:08 INFO - 'HOME': '/home/cltbld', 05:13:08 INFO - 'LANG': 'en_US.UTF-8', 05:13:08 INFO - 'LANGUAGE': 'en_US:en', 05:13:08 INFO - 'LOGNAME': 'cltbld', 05:13:08 INFO - 'MAIL': '/var/mail/cltbld', 05:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:08 INFO - 'MOZ_NO_REMOTE': '1', 05:13:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:13:08 INFO - 'NO_EM_RESTART': '1', 05:13:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:08 INFO - 'PWD': '/builds/slave/test', 05:13:08 INFO - 'SHELL': '/bin/bash', 05:13:08 INFO - 'SHLVL': '1', 05:13:08 INFO - 'TERM': 'linux', 05:13:08 INFO - 'TMOUT': '86400', 05:13:08 INFO - 'USER': 'cltbld', 05:13:08 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', 05:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:08 INFO - '_': '/tools/buildbot/bin/python'} 05:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:08 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 05:13:08 INFO - Cleaning up... 05:13:08 INFO - Return code: 0 05:13:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:13:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc134d3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b810>, '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 0x118c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cba0>, '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': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', '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 05:13:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:13:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:13:09 INFO - Using env: {'DISPLAY': ':0', 05:13:09 INFO - 'HOME': '/home/cltbld', 05:13:09 INFO - 'LANG': 'en_US.UTF-8', 05:13:09 INFO - 'LANGUAGE': 'en_US:en', 05:13:09 INFO - 'LOGNAME': 'cltbld', 05:13:09 INFO - 'MAIL': '/var/mail/cltbld', 05:13:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:09 INFO - 'MOZ_NO_REMOTE': '1', 05:13:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:13:09 INFO - 'NO_EM_RESTART': '1', 05:13:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:13:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:09 INFO - 'PWD': '/builds/slave/test', 05:13:09 INFO - 'SHELL': '/bin/bash', 05:13:09 INFO - 'SHLVL': '1', 05:13:09 INFO - 'TERM': 'linux', 05:13:09 INFO - 'TMOUT': '86400', 05:13:09 INFO - 'USER': 'cltbld', 05:13:09 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', 05:13:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:09 INFO - '_': '/tools/buildbot/bin/python'} 05:13:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:09 INFO - Downloading/unpacking psutil>=0.7.1 05:13:09 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 05:13:09 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 05:13:09 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 05:13:09 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 05:13:09 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 05:13:09 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 05:13:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:13:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:13:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:13:11 INFO - Installing collected packages: psutil 05:13:11 INFO - Running setup.py install for psutil 05:13:11 INFO - building 'psutil._psutil_linux' extension 05:13:11 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 05:13:11 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 05:13:11 INFO - building 'psutil._psutil_posix' extension 05:13:11 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 05:13:11 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 05:13:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:13:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:13:11 INFO - Successfully installed psutil 05:13:11 INFO - Cleaning up... 05:13:11 INFO - Return code: 0 05:13:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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', '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 0x7fc134d3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b810>, '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 0x118c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cba0>, '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': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', '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 05:13: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:13: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 mozsystemmonitor==0.0.0 05:13:11 INFO - Using env: {'DISPLAY': ':0', 05:13:11 INFO - 'HOME': '/home/cltbld', 05:13:11 INFO - 'LANG': 'en_US.UTF-8', 05:13:11 INFO - 'LANGUAGE': 'en_US:en', 05:13:11 INFO - 'LOGNAME': 'cltbld', 05:13:11 INFO - 'MAIL': '/var/mail/cltbld', 05:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:11 INFO - 'MOZ_NO_REMOTE': '1', 05:13:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:13:11 INFO - 'NO_EM_RESTART': '1', 05:13:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:11 INFO - 'PWD': '/builds/slave/test', 05:13:11 INFO - 'SHELL': '/bin/bash', 05:13:11 INFO - 'SHLVL': '1', 05:13:11 INFO - 'TERM': 'linux', 05:13:11 INFO - 'TMOUT': '86400', 05:13:11 INFO - 'USER': 'cltbld', 05:13:11 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', 05:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:11 INFO - '_': '/tools/buildbot/bin/python'} 05:13:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:13:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:13:13 INFO - Installing collected packages: mozsystemmonitor 05:13:13 INFO - Running setup.py install for mozsystemmonitor 05:13:13 INFO - Successfully installed mozsystemmonitor 05:13:13 INFO - Cleaning up... 05:13:14 INFO - Return code: 0 05:13:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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', '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 0x7fc134d3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b810>, '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 0x118c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cba0>, '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': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', '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 05:13: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:13: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 blobuploader==1.2.4 05:13:14 INFO - Using env: {'DISPLAY': ':0', 05:13:14 INFO - 'HOME': '/home/cltbld', 05:13:14 INFO - 'LANG': 'en_US.UTF-8', 05:13:14 INFO - 'LANGUAGE': 'en_US:en', 05:13:14 INFO - 'LOGNAME': 'cltbld', 05:13:14 INFO - 'MAIL': '/var/mail/cltbld', 05:13:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:14 INFO - 'MOZ_NO_REMOTE': '1', 05:13:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:13:14 INFO - 'NO_EM_RESTART': '1', 05:13:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:13:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:14 INFO - 'PWD': '/builds/slave/test', 05:13:14 INFO - 'SHELL': '/bin/bash', 05:13:14 INFO - 'SHLVL': '1', 05:13:14 INFO - 'TERM': 'linux', 05:13:14 INFO - 'TMOUT': '86400', 05:13:14 INFO - 'USER': 'cltbld', 05:13:14 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', 05:13:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:14 INFO - '_': '/tools/buildbot/bin/python'} 05:13:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:14 INFO - Downloading/unpacking blobuploader==1.2.4 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:17 INFO - Downloading blobuploader-1.2.4.tar.gz 05:13:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:13:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:13:17 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 05:13:17 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 05:13:17 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 05:13:17 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 05:13:17 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 05:13:17 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 05:13:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:13:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:13:17 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 05:13:17 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 05:13:17 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 05:13:17 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 05:13:17 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 05:13:17 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 05:13:17 INFO - Downloading docopt-0.6.1.tar.gz 05:13:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:13:17 INFO - Installing collected packages: blobuploader, requests, docopt 05:13:17 INFO - Running setup.py install for blobuploader 05:13:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:13:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:13:18 INFO - Running setup.py install for requests 05:13:18 INFO - Running setup.py install for docopt 05:13:18 INFO - Successfully installed blobuploader requests docopt 05:13:18 INFO - Cleaning up... 05:13:18 INFO - Return code: 0 05:13:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:18 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')]} 05:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:18 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')]} 05:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:18 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 0x7fc134d3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b810>, '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 0x118c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cba0>, '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': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', '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 05:13:18 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 05:13:18 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 05:13:18 INFO - Using env: {'DISPLAY': ':0', 05:13:18 INFO - 'HOME': '/home/cltbld', 05:13:18 INFO - 'LANG': 'en_US.UTF-8', 05:13:18 INFO - 'LANGUAGE': 'en_US:en', 05:13:18 INFO - 'LOGNAME': 'cltbld', 05:13:18 INFO - 'MAIL': '/var/mail/cltbld', 05:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:18 INFO - 'MOZ_NO_REMOTE': '1', 05:13:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:13:18 INFO - 'NO_EM_RESTART': '1', 05:13:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:18 INFO - 'PWD': '/builds/slave/test', 05:13:18 INFO - 'SHELL': '/bin/bash', 05:13:18 INFO - 'SHLVL': '1', 05:13:18 INFO - 'TERM': 'linux', 05:13:18 INFO - 'TMOUT': '86400', 05:13:18 INFO - 'USER': 'cltbld', 05:13:18 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', 05:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:18 INFO - '_': '/tools/buildbot/bin/python'} 05:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:13:18 INFO - Running setup.py (path:/tmp/pip-MsiYY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:13:18 INFO - Running setup.py (path:/tmp/pip-j6KYPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:13:18 INFO - Running setup.py (path:/tmp/pip-WvKFdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:13:18 INFO - Running setup.py (path:/tmp/pip-nE9m6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:13:19 INFO - Running setup.py (path:/tmp/pip-NPHW9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:13:19 INFO - Running setup.py (path:/tmp/pip-6z4Qij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:13:19 INFO - Running setup.py (path:/tmp/pip-OlwWbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:13:19 INFO - Running setup.py (path:/tmp/pip-9lVEkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:13:19 INFO - Running setup.py (path:/tmp/pip-A8NeYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:13:19 INFO - Running setup.py (path:/tmp/pip-ZQTtdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:13:19 INFO - Running setup.py (path:/tmp/pip-ynzTbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:13:19 INFO - Running setup.py (path:/tmp/pip-yBfPRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:13:19 INFO - Running setup.py (path:/tmp/pip-oV4ba4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:13:19 INFO - Running setup.py (path:/tmp/pip-nrUskJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:19 INFO - Running setup.py (path:/tmp/pip-_Uvv0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:13:19 INFO - Running setup.py (path:/tmp/pip-ZznksO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:13:19 INFO - Running setup.py (path:/tmp/pip-0Sstw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:13:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:13:19 INFO - Running setup.py install for manifestparser 05:13:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:13:20 INFO - Running setup.py install for mozcrash 05:13:20 INFO - Running setup.py install for mozdebug 05:13:20 INFO - Running setup.py install for mozdevice 05:13:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:13:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:13:20 INFO - Running setup.py install for mozfile 05:13:20 INFO - Running setup.py install for mozhttpd 05:13:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:13:20 INFO - Running setup.py install for mozinfo 05:13:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:13:20 INFO - Running setup.py install for mozInstall 05:13:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:13:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:13:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:13:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:13:20 INFO - Running setup.py install for mozleak 05:13:20 INFO - Running setup.py install for mozlog 05:13:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:13:20 INFO - Running setup.py install for moznetwork 05:13:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:13:21 INFO - Running setup.py install for mozprocess 05:13:21 INFO - Running setup.py install for mozprofile 05:13:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:13:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:13:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:13:21 INFO - Running setup.py install for mozrunner 05:13:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:13:21 INFO - Running setup.py install for mozscreenshot 05:13:21 INFO - Running setup.py install for moztest 05:13:21 INFO - Running setup.py install for mozversion 05:13:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:13:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:13:21 INFO - Cleaning up... 05:13:21 INFO - Return code: 0 05:13:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:21 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')]} 05:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:21 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')]} 05:13:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:21 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 0x7fc134d3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b810>, '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 0x118c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cba0>, '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': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', '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 05:13:21 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 05:13:21 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 05:13:21 INFO - Using env: {'DISPLAY': ':0', 05:13:21 INFO - 'HOME': '/home/cltbld', 05:13:21 INFO - 'LANG': 'en_US.UTF-8', 05:13:21 INFO - 'LANGUAGE': 'en_US:en', 05:13:21 INFO - 'LOGNAME': 'cltbld', 05:13:21 INFO - 'MAIL': '/var/mail/cltbld', 05:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:21 INFO - 'MOZ_NO_REMOTE': '1', 05:13:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:13:21 INFO - 'NO_EM_RESTART': '1', 05:13:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:21 INFO - 'PWD': '/builds/slave/test', 05:13:21 INFO - 'SHELL': '/bin/bash', 05:13:21 INFO - 'SHLVL': '1', 05:13:21 INFO - 'TERM': 'linux', 05:13:21 INFO - 'TMOUT': '86400', 05:13:21 INFO - 'USER': 'cltbld', 05:13:21 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', 05:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:21 INFO - '_': '/tools/buildbot/bin/python'} 05:13:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:13:21 INFO - Running setup.py (path:/tmp/pip-c76z_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:13:22 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)) 05:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:13:22 INFO - Running setup.py (path:/tmp/pip-pHEXSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:13:22 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)) 05:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:13:22 INFO - Running setup.py (path:/tmp/pip-8ihlWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:13:22 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)) 05:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:13:22 INFO - Running setup.py (path:/tmp/pip-6lFJKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:13:22 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)) 05:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:13:22 INFO - Running setup.py (path:/tmp/pip-oijLUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:13:22 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)) 05:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:13:22 INFO - Running setup.py (path:/tmp/pip-JhRjXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:13:22 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)) 05:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:13:22 INFO - Running setup.py (path:/tmp/pip-D_2_b5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:13:22 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)) 05:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:13:22 INFO - Running setup.py (path:/tmp/pip-S123Ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:13:22 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)) 05:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:13:22 INFO - Running setup.py (path:/tmp/pip-CF_o0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:13:22 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)) 05:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:13:22 INFO - Running setup.py (path:/tmp/pip-eDOSU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:13:22 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)) 05:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:13:22 INFO - Running setup.py (path:/tmp/pip-JzQIBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:13:22 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)) 05:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:13:22 INFO - Running setup.py (path:/tmp/pip-nIjQv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:13:22 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)) 05:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:13:22 INFO - Running setup.py (path:/tmp/pip-IODj3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:13:22 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)) 05:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:13:22 INFO - Running setup.py (path:/tmp/pip-w5SALt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:13:22 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)) 05:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:22 INFO - Running setup.py (path:/tmp/pip-Mi4JwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:23 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)) 05:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:13:23 INFO - Running setup.py (path:/tmp/pip-MwDlDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:13:23 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)) 05:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:13:23 INFO - Running setup.py (path:/tmp/pip-28t0sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:13:23 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)) 05:13:23 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)) 05:13:23 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)) 05:13:23 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)) 05:13:23 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)) 05:13:23 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)) 05:13:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:13:23 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 05:13:23 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 05:13:23 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 05:13:23 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 05:13:23 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 05:13:23 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 05:13:25 INFO - Downloading blessings-1.6.tar.gz 05:13:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:13:25 INFO - Installing collected packages: blessings 05:13:25 INFO - Running setup.py install for blessings 05:13:25 INFO - Successfully installed blessings 05:13:25 INFO - Cleaning up... 05:13:25 INFO - Return code: 0 05:13:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:13:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:13:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:13:25 INFO - Reading from file tmpfile_stdout 05:13:25 INFO - Current package versions: 05:13:25 INFO - argparse == 1.2.1 05:13:25 INFO - blessings == 1.6 05:13:25 INFO - blobuploader == 1.2.4 05:13:25 INFO - docopt == 0.6.1 05:13:25 INFO - manifestparser == 1.1 05:13:25 INFO - mozInstall == 1.12 05:13:25 INFO - mozcrash == 0.17 05:13:25 INFO - mozdebug == 0.1 05:13:25 INFO - mozdevice == 0.48 05:13:25 INFO - mozfile == 1.2 05:13:25 INFO - mozhttpd == 0.7 05:13:25 INFO - mozinfo == 0.9 05:13:25 INFO - mozleak == 0.1 05:13:25 INFO - mozlog == 3.1 05:13:25 INFO - moznetwork == 0.27 05:13:25 INFO - mozprocess == 0.22 05:13:25 INFO - mozprofile == 0.28 05:13:25 INFO - mozrunner == 6.11 05:13:25 INFO - mozscreenshot == 0.1 05:13:25 INFO - mozsystemmonitor == 0.0 05:13:25 INFO - moztest == 0.7 05:13:25 INFO - mozversion == 1.4 05:13:25 INFO - psutil == 3.1.1 05:13:25 INFO - requests == 1.2.3 05:13:25 INFO - wsgiref == 0.1.2 05:13:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:25 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')]} 05:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:25 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')]} 05:13:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:25 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 0x7fc134d3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b810>, '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 0x118c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cba0>, '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': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', '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 05:13:25 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 05:13:25 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 05:13:25 INFO - Using env: {'DISPLAY': ':0', 05:13:25 INFO - 'HOME': '/home/cltbld', 05:13:25 INFO - 'LANG': 'en_US.UTF-8', 05:13:25 INFO - 'LANGUAGE': 'en_US:en', 05:13:25 INFO - 'LOGNAME': 'cltbld', 05:13:25 INFO - 'MAIL': '/var/mail/cltbld', 05:13:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:25 INFO - 'MOZ_NO_REMOTE': '1', 05:13:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:13:25 INFO - 'NO_EM_RESTART': '1', 05:13:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:13:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:25 INFO - 'PWD': '/builds/slave/test', 05:13:25 INFO - 'SHELL': '/bin/bash', 05:13:25 INFO - 'SHLVL': '1', 05:13:25 INFO - 'TERM': 'linux', 05:13:25 INFO - 'TMOUT': '86400', 05:13:25 INFO - 'USER': 'cltbld', 05:13:25 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', 05:13:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:25 INFO - '_': '/tools/buildbot/bin/python'} 05:13:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:25 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)) 05:13:25 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)) 05:13:25 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)) 05:13:25 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)) 05:13:25 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)) 05:13:25 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)) 05:13:25 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)) 05:13:25 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)) 05:13:25 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)) 05:13:25 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)) 05:13:25 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)) 05:13:25 INFO - Cleaning up... 05:13:25 INFO - Return code: 0 05:13:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:13:25 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')]} 05:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:25 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')]} 05:13:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7fc134d3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b810>, '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 0x118c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cba0>, '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': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', '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 05:13:25 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 05:13:25 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 05:13:25 INFO - Using env: {'DISPLAY': ':0', 05:13:25 INFO - 'HOME': '/home/cltbld', 05:13:25 INFO - 'LANG': 'en_US.UTF-8', 05:13:25 INFO - 'LANGUAGE': 'en_US:en', 05:13:25 INFO - 'LOGNAME': 'cltbld', 05:13:25 INFO - 'MAIL': '/var/mail/cltbld', 05:13:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:25 INFO - 'MOZ_NO_REMOTE': '1', 05:13:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:13:25 INFO - 'NO_EM_RESTART': '1', 05:13:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:13:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:25 INFO - 'PWD': '/builds/slave/test', 05:13:25 INFO - 'SHELL': '/bin/bash', 05:13:25 INFO - 'SHLVL': '1', 05:13:25 INFO - 'TERM': 'linux', 05:13:25 INFO - 'TMOUT': '86400', 05:13:25 INFO - 'USER': 'cltbld', 05:13:25 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', 05:13:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:25 INFO - '_': '/tools/buildbot/bin/python'} 05:13:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:25 INFO - Downloading/unpacking jsonschema 05:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:25 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 05:13:25 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 05:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:13:27 INFO - Downloading/unpacking functools32 (from jsonschema) 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:28 INFO - Downloading functools32-3.2.3-2.tar.gz 05:13:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:13:28 INFO - warning: no files found matching '*.txt' 05:13:28 INFO - no previously-included directories found matching 'build' 05:13:28 INFO - no previously-included directories found matching 'dist' 05:13:28 INFO - no previously-included directories found matching '.git*' 05:13:28 INFO - Installing collected packages: jsonschema, functools32 05:13:28 INFO - Running setup.py install for functools32 05:13:28 INFO - warning: no files found matching '*.txt' 05:13:28 INFO - no previously-included directories found matching 'build' 05:13:28 INFO - no previously-included directories found matching 'dist' 05:13:28 INFO - no previously-included directories found matching '.git*' 05:13:28 INFO - Successfully installed jsonschema functools32 05:13:28 INFO - Cleaning up... 05:13:28 INFO - Return code: 0 05:13:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:13:28 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')]} 05:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:28 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')]} 05:13:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:28 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 0x7fc134d3cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b810>, '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 0x118c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cba0>, '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': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', '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 05:13:28 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 05:13:28 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 05:13:28 INFO - Using env: {'DISPLAY': ':0', 05:13:28 INFO - 'HOME': '/home/cltbld', 05:13:28 INFO - 'LANG': 'en_US.UTF-8', 05:13:28 INFO - 'LANGUAGE': 'en_US:en', 05:13:28 INFO - 'LOGNAME': 'cltbld', 05:13:28 INFO - 'MAIL': '/var/mail/cltbld', 05:13:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:28 INFO - 'MOZ_NO_REMOTE': '1', 05:13:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:13:28 INFO - 'NO_EM_RESTART': '1', 05:13:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:13:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:28 INFO - 'PWD': '/builds/slave/test', 05:13:28 INFO - 'SHELL': '/bin/bash', 05:13:28 INFO - 'SHLVL': '1', 05:13:28 INFO - 'TERM': 'linux', 05:13:28 INFO - 'TMOUT': '86400', 05:13:28 INFO - 'USER': 'cltbld', 05:13:28 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', 05:13:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:28 INFO - '_': '/tools/buildbot/bin/python'} 05:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:28 INFO - Downloading/unpacking flake8 05:13:28 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 05:13:28 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 05:13:28 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 05:13:28 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 05:13:28 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 05:13:28 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 05:13:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:13:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:13:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:13:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:13:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:13:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:13:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:13:31 INFO - no previously-included directories found matching 'docs/_build' 05:13:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:13:31 INFO - Running setup.py install for pep8 05:13:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:13:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:13:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:13:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:13:31 INFO - no previously-included directories found matching 'docs/_build' 05:13:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:13:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:13:31 INFO - Cleaning up... 05:13:31 INFO - Return code: 0 05:13:31 INFO - Running post-action listener: _resource_record_post_action 05:13:31 INFO - Running post-action listener: _start_resource_monitoring 05:13:31 INFO - Starting resource monitoring. 05:13:31 INFO - ##### 05:13:31 INFO - ##### Running install step. 05:13:31 INFO - ##### 05:13:31 INFO - Running pre-action listener: _resource_record_pre_action 05:13:31 INFO - Running main action method: install 05:13:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:13:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:13:31 INFO - Reading from file tmpfile_stdout 05:13:31 INFO - Detecting whether we're running mozinstall >=1.0... 05:13:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:13:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:13:31 INFO - Reading from file tmpfile_stdout 05:13:31 INFO - Output received: 05:13:31 INFO - Usage: mozinstall [options] installer 05:13:31 INFO - Options: 05:13:31 INFO - -h, --help show this help message and exit 05:13:31 INFO - -d DEST, --destination=DEST 05:13:31 INFO - Directory to install application into. [default: 05:13:31 INFO - "/builds/slave/test"] 05:13:31 INFO - --app=APP Application being installed. [default: firefox] 05:13:31 INFO - mkdir: /builds/slave/test/build/application 05:13:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:13:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:13:48 INFO - Reading from file tmpfile_stdout 05:13:48 INFO - Output received: 05:13:48 INFO - /builds/slave/test/build/application/firefox/firefox 05:13:48 INFO - Running post-action listener: _resource_record_post_action 05:13:48 INFO - ##### 05:13:48 INFO - ##### Running run-tests step. 05:13:48 INFO - ##### 05:13:48 INFO - Running pre-action listener: _resource_record_pre_action 05:13:48 INFO - Running main action method: run_tests 05:13:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:13:48 INFO - Python 2.7.3 05:13:48 INFO - Return code: 0 05:13:48 INFO - grabbing minidump binary from tooltool 05:13: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')]} 05:13: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 0x118b810>, '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 0x118c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cba0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:13: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 05:13: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 05:13:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:13:48 INFO - Return code: 0 05:13:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:13:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:13:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:13:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:13:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:13:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:13:49 INFO - Return code: 0 05:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:13:49 INFO - Using env: {'DISPLAY': ':0', 05:13:49 INFO - 'HOME': '/home/cltbld', 05:13:49 INFO - 'LANG': 'en_US.UTF-8', 05:13:49 INFO - 'LANGUAGE': 'en_US:en', 05:13:49 INFO - 'LOGNAME': 'cltbld', 05:13:49 INFO - 'MAIL': '/var/mail/cltbld', 05:13:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:13:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:49 INFO - 'MOZ_NO_REMOTE': '1', 05:13:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:13:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:13:49 INFO - 'NO_EM_RESTART': '1', 05:13:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:49 INFO - 'PWD': '/builds/slave/test', 05:13:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:13:49 INFO - 'SHELL': '/bin/bash', 05:13:49 INFO - 'SHLVL': '1', 05:13:49 INFO - 'TERM': 'linux', 05:13:49 INFO - 'TMOUT': '86400', 05:13:49 INFO - 'USER': 'cltbld', 05:13:49 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1462535331.938214-342183942', 05:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:49 INFO - '_': '/tools/buildbot/bin/python'} 05:13:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:13:49 INFO - mozversion application_buildid: 20160506023024 05:13:49 INFO - mozversion application_changeset: e65c3c2186710e9ee1dfb3f254bd45019ea34c80 05:13:49 INFO - mozversion application_display_name: Nightly 05:13:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:13:49 INFO - mozversion application_name: Firefox 05:13:49 INFO - mozversion application_remotingname: firefox 05:13:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:13:49 INFO - mozversion application_vendor: Mozilla 05:13:49 INFO - mozversion application_version: 49.0a1 05:13:49 INFO - mozversion platform_buildid: 20160506023024 05:13:49 INFO - mozversion platform_changeset: e65c3c2186710e9ee1dfb3f254bd45019ea34c80 05:13:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:13:49 INFO - mozversion platform_version: 49.0a1 05:13:49 INFO - using testdate: 1462536829 05:13:49 INFO - actual date: 1462536829 05:13:49 INFO - starting webserver on 'localhost:40307' 05:13:49 INFO - SUITE-START | Running 2 tests 05:13:49 INFO - TEST-START | dromaeo_css 05:13:49 INFO - operating with platform_type : linux_ 05:13:49 INFO - Initialising browser for dromaeo_css test... 05:13:49 INFO - TEST-INFO | started process 29922 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmfQ7Ip/profile http://localhost:40307/getInfo.html) 05:13:53 INFO - PROCESS | 29922 | __metrics Screen width/height:1600/1200 05:13:53 INFO - PROCESS | 29922 | colorDepth:24 05:13:53 INFO - PROCESS | 29922 | Browser inner width/height: 1024/697 05:13:53 INFO - PROCESS | 29922 | __metrics 05:13:54 ERROR - PROCESS | 29922 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:13:55 INFO - TEST-INFO | 29922: exit 0 05:13:55 INFO - Browser initialized. 05:13:55 INFO - Running cycle 1/1 for dromaeo_css test... 05:13:55 INFO - TEST-INFO | started process 30004 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmfQ7Ip/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:14:06 INFO - PROCESS | 30004 | RSS: Main: 152027136 05:14:06 INFO - PROCESS | 30004 | 05:14:58 INFO - PROCESS | 30004 | 1462536898206 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] 05:17:06 INFO - PROCESS | 30004 | Cycle 1(1): loaded http://localhost:40307/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40307/tests/dromaeo/cssquery-ext.html) 05:17:07 INFO - PROCESS | 30004 | RSS: Main: 253460480 05:17:07 INFO - PROCESS | 30004 | 05:20:09 INFO - PROCESS | 30004 | Cycle 1(1): loaded http://localhost:40307/tests/dromaeo/cssquery-ext.html (next: http://localhost:40307/tests/dromaeo/cssquery-jquery.html) 05:20:10 INFO - PROCESS | 30004 | RSS: Main: 231673856 05:20:10 INFO - PROCESS | 30004 | 05:23:12 INFO - PROCESS | 30004 | Cycle 1(1): loaded http://localhost:40307/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40307/tests/dromaeo/cssquery-mootools.html) 05:23:12 INFO - PROCESS | 30004 | RSS: Main: 236068864 05:23:12 INFO - PROCESS | 30004 | 05:26:14 INFO - PROCESS | 30004 | Cycle 1(1): loaded http://localhost:40307/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40307/tests/dromaeo/cssquery-prototype.html) 05:26:15 INFO - PROCESS | 30004 | RSS: Main: 228179968 05:26:15 INFO - PROCESS | 30004 | 05:29:17 INFO - PROCESS | 30004 | Cycle 1(1): loaded http://localhost:40307/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40307/tests/dromaeo/cssquery-yui.html) 05:29:18 INFO - PROCESS | 30004 | RSS: Main: 225943552 05:29:18 INFO - PROCESS | 30004 | 05:32:20 INFO - PROCESS | 30004 | Cycle 1(1): loaded http://localhost:40307/tests/dromaeo/cssquery-yui.html (next: http://localhost:40307/tests/dromaeo/cssquery-dojo.html) 05:32:21 INFO - PROCESS | 30004 | RSS: Main: 269357056 05:32:21 INFO - PROCESS | 30004 | 05:32:21 INFO - PROCESS | 30004 | __start_tp_report 05:32:21 INFO - PROCESS | 30004 | _x_x_mozilla_page_load 05:32:21 INFO - PROCESS | 30004 | _x_x_mozilla_page_load_details 05:32:21 INFO - PROCESS | 30004 | |i|pagename|runs| 05:32:21 INFO - PROCESS | 30004 | |0;dojo.html;3187.8724851005954;3189.6012998375204;3214.7428205743545;3220.0339898030584;3250.1549597104745;5314.096603577392;5377.11277639189;5389.137737961922;5446.754896029674;5460.989716902371;5370.3287089113865;5395.298611180541;5421.566274698024;5453.127499600065;5453.15476101205;4918.385201849769;4934.679245849026;4971.651984361096;4985.651004429691;5174.43081261061;5062.0888240116765;5072.960043191156;5108.974455127719;5123.923141152885;5132.589392848563;4834.516548345173;4893.363862697826;4902.632302577321;4933.432655244639;4978.4025916890205;4913.852584422473;4914.967856750087;4982.078315511606;5000.599952003831;5032.446430892599;4867.805287788484;4919.90160196794;4952.529509696609;4957.925631115536;5003.774830132653;5297.49673780994;5318.547923426513;5375.301210842605;5428.75570599324;5458.6724796512335;5832.562557808182;5875.471207591295;5892.528597712173;5893.5579831512805;5903.0555111182;5633.32400111985;5764.279465066867;5778.88442231153;5890.793822216202;5954.07711804671;5472.863178420532;5487.067198576252;5488.862778430492;5495.203195536625;5496.120620700709;5984.1921340618455;5986.431289027535;5987.940120598825;6006.609570377911;6007.519398448114;5492.642978206404;5683.54531637468;5721.599488035798;5730.713464326767;5742.052561327334;5874.823755287348;5875.853103672357;5885.028970219952;5891.675957822275;5891.793787217427;5850.0932355484965;5850.151727999416;5855.209546711225;5862.032764593794;5868.853278668067;5611.2144299798065;5754.74103665336;5760.884782304414;5804.013317735995;5847.06446968483;4425.756583387944;4428.955710442919;4435.246008178618;4440.511543730187;4464.330350447394;3852.8844134676006;3958.5249770027203;3970.0273433009097;3989.401589761559;3995.580464051291;5479.068558345091;5524.1989911462615;5529.695866727288;5588.972055139699;5605.131204663284;5877.089051197071;5881.3530511643685;5886;5886.175935369051;5886.3230728465915;5539.5014448699;5549.306336707911;5561.332640083135;5563.833085007456;5565.41563135873;4820.301056346881;4957.90084198314;4982.750862456863;5020.7740651670765;5057.266696329012;6022.939770602326;6054.303755068136;6054.909176362358;6057.878842423127;6059.757609695651;5704.172894930295;5798.449147330997;5825.300963884191;5828.6502809831545;5834.912476312774;5925.40745925404;5975.16347711312;5983.491403230774;6023.397660233942;6025.548083893638;5735.340435849931;5777.62445441045;5780.248567686174;5780.884382312414;5781.306243250837;5614.5789065821045;5812.9709351452975;5882.117682347682;5882.852928676817;5890.734916928663;5670.74481648335;5749.051406517878;5751.051076572318;5751.424857514216;5805.245318108618;5723.599348045597;5734;5735.483806457439;5739.110437882135;5741.224934633771;5454.672719636835;5518.586106042143;5565.30433695788;5649.2373529574625;5652.152177173457;5726.599138060295;5732.627379220337;5733.397993210653;5745.396733343106;5754.741036653444;5516.310461192351;5565.276514053309;5585.441455854545;5586.497215250647;5591.049521581423 05:32:21 INFO - PROCESS | 30004 | |1;ext.html;611.7981066248138;627.629698477898;630.9620673991282;653.1835205992508;657.0046379734699;32147.67852321476;35171.2965740685;35183.82408087959;43460.56539434605;44729.7763511182;44973.77513112438;45361.31958020632;45422.54577454225;45446.545534544646;45500.0899982001;8746.431481953668;8797.252233560122;8808.075152109033;8810.73567792964;8900.73297801067;8696.521691306976;8818.823623527525;8820.20618144367;8825.426347287406;8837.337199710048;8849;8885.46687198767;8900.37697361185;8902.910970890272;8903.77740556488;8809.38334316598;8858.689945851864;8862.778430539289;8864.689735859247;8866.468011919305;8672.609732561987;8764.824703505894;8766.298696104297;8768.649254029831;8771.956140219321;41259;41565.79217103895;41670.1665966682;41700.1659966799;41715.791421043;3584.4802503636824;3834.6357096075826;3923.4507168996365;4115.855945041939;4305.246581848195;32359.352812943845;32371.676283237102;32726.966648181766;32977.175570610685;33296;4118.341065429547;4121.62905338518;4230.153969206141;4285.742855428684;4305.698601097924;308120;308208;309182;309352.43885366723;309470.99999999773;60838;61083;61215;61293.69353153206;61298.69350653218;37069.81465092711;38036.14728895344;38055.619443805765;39651.206975860325;39715.20569588592;14667.559973200821;14703;14725.631859203606;14791.112533248039;14809.963302568716;11807.350595717146;11813.940930295294;11814.232074915102;11816.172867899337;11828.70428239301;19761.703574446394;20314.390568282975;20342.288019919233;20376;20473;7315;7370.926290737024;7519.8120046998165;7532.698692052257;7638.2743639355285;977.0717818072859;1073.1361254190297;1087.8096333141668;1108.944552772358;1239.4670291774623;32553.186170345456;32593.511097333558;32994.505082423784;33059.66940330614;33292;1114.136544178252;1164.7728692904802;1166.3643314393676;1240.7084335181225;1314.2771475688332;17615;17617;17648.647027059385;17736.20187091584;17837.286508539513;470555;471114;473155;474191;474742;58344;58860;58921.705391473624;58991;58998;1268.1883594499527;1382.0601990646255;1393.2824595333411;1403.0950037226137;1419.0563275421935;31790.205244869063;32994.835025824716;33817.71456070799;33851.66148338583;34213.144671383416;49719;50107.24839127489;50143;50246.2463063047;50255.49744502508;317691;320423;321504;323274;456984;28557.857210713813;29099.563506546994;29484.852575736983;30659.846700766353;30717.692823071484;48142.5185748138;49326.50673493219;50110.749446252536;50404.243936341714;50684.493155067976;41191.588084118775;41451.37822932719;41501.37747933723;42188;43366.783166083966;40551.797241013606;41189.58810411977;41563;41797;42280.788596056824;41713.37429938493;42069.78965105155;42179.57820421756;42640.360394594725;42770;34583.82708086444;36210.27579448344;36429.63570364368;36643.63356366402;36697.26605467929;28231.858840705667;28349.574756378257;28457.430851382444;28558.14325570236;28695.713042869305 05:32:21 INFO - PROCESS | 30004 | |2;jquery.html;406.868904445641;410.91165399439114;410.9198706252281;411.886731148934;427.801072501287;17744.556386090353;19459.221631134755;19497.220111195555;19734.111964961576;20247.898760506214;18543.90728046358;18985.620287594273;18997.335093271704;19486.415407537763;19503.317383891568;5461.972690136554;5551.333839939192;5612.971935140318;5710.628809127415;5712.657240565559;5555.083411237141;5580.609357344987;5584.1902924076185;5607.915881261764;5663.348714897777;5357.571394288467;5507.421720719327;5584.497395234428;5642.915356269638;5659;5457.5633949283965;5481.205225242337;5554.0835762099205;5554.305711786027;5574.665520068809;4962.826301079445;5071.0365030644425;5105.183170692671;5105.412877519096;5106.591472682177;21904.780952190435;22348.88825555878;22363.44091397712;22718.659220111713;23140.42148946273;2431.4772323950438;2442.6336049592705;2500.087468074158;3069.938601227985;3098.148009297439;10347.223958203165;10629.787404251872;10673.092787113104;10862;10891.0198082173;1934.332655233942;1941.6602094633383;1949.4541528372079;1961.1567026178736;2025.422754514956;76799;79509.30304635975;82036.58981705114;83189.58405207995;83597.58201209016;10970.451477426097;10987.121030317556;11262.662120136409;11607.30356178632;12175.208611440405;15637.296321665552;15685.294161762748;15741.527754167164;15759.921200394154;15777.447789327318;8053.919460805434;8080.757577272691;8284.295834854105;8303.377246706521;8329.37529685276;7788.493747906368;7831.608419578998;7915.960420197977;8038.517688938682;8096.230858068474;4759.976200118977;4778.37881075458;4868.245197582357;4916.803327866917;5013.523715247045;1985.444075658789;2013.496625843539;2020.19192323069;2021.080408414168;2046.5599896022427;964.9035096490435;1037.3101887244898;1067.765091679829;1105.690406686129;1109.4341885638382;18877.244910203437;19586.92271925029;19627.509312267022;19657.901710491355;19790.406287811387;1006.6979906028163;1051.8264486359817;1059.6450189186555;1094.9945250273856;1135.8125909224884;10360.689179324632;11085.445727713583;11162.776744465227;11165.776684466266;11167.385793781257;361930;365354;366473.33526664926;430442;440539;59168;68644.6567767158;82544.17455825485;84337.15662843415;85113.14886851177;1076.4025965589174;1117.6367680504031;1123.775244951013;1163.069544364522;1165.9941700291445;13206.405711742802;16492.010479371274;17607.559811004827;17629.20668569943;17848.55378615545;36367.45448818217;36993.6300637001;40174.59825401825;40258.86409634965;40795.59204407918;53261.4673853272;54408;54626;55503.72248138733;56415.71792141177;8435.409521333693;8640.351973601879;8649.697260595849;8731.65073397057;8832.514211718417;9351.814631193125;10932.617358392417;13211.537596184087;13355.866441335462;13483.595492135251;11493.942530287295;11604.825927610924;11628.186026978368;11978.31546219145;12031.879681203076;11662.88337116652;11817;11858.940705296418;11859.653310260699;11902.761944761229;10459.00639439252;11655.125865559947;11799.52801887915;11800.291982481078;12203.75078991519;7632.465727399029;7838.3147623261275;7928.524288542706;8194.754157375288;8988.15742772717;11697.532098715956;11714.670042728185;11751.00116490108;11964.46159922823;12310.81533777012 05:32:21 INFO - PROCESS | 30004 | |3;mootools.html;1373.4780783302344;1543.1281326050785;1570.9553147157142;1754.184304298501;1862.143414029547;8234;8493.023302320235;8530.402871798977;8729.563521823915;8729.956350218241;8462.95768521158;8687.609057592408;8702.651893924236;8709.085546017672;8714.085021072775;2074.4191626344573;2255.3459496745922;2346.9295921122393;2352.494213744045;2375.7861792438675;2379.5597814404305;2384.1178763857397;2393.808495320379;2398.95202095958;2403.0748161957686;2260.3219034289646;2312.8843557822124;2313.548857972696;2314.386687527798;2316.061994892063;1917.9041047947617;2264.1962103453366;2340.0990618611786;2346.7887890089805;2357.104300365867;2247.1236217875044;2257.7742225777456;2274.192661605123;2279.8974046167796;2281.828862835294;8643.481391116553;8693.347998900044;8701;8709.390342676015;8722.912770872274;5077.746112694351;5155.278261043457;5182.481751824825;5198.792048318101;5215.947840521584;3260.152360386293;3307.6361600223954;3343.147497388175;3449.189440481485;3451.3097380523745;1160.3907948327112;1182.2256422043574;1189.6550000499844;1190.053907143814;1217.902567794583;11431.14266430021;11455.083593312514;11461.999999999916;11470.369129697863;11487.827682584768;4504.121696269196;4583.152116858325;4679.064187162581;4684.906301874012;4690.507496712864;9772.51137443125;9783.559739811726;9784;9787.657431989846;9791;4748.335233067373;5265.4997775211295;5279.894402111936;5281.735913204325;5285.101532739443;4768.809247630125;4914.827981020719;4929.408470983505;4986.55121039101;4989.977054703777;4003.719739618257;4010.6991975601945;4027.4985729530717;4073.165001174752;4196.580341965779;1289.7936330187313;1297.9870201298056;1306.7713150198867;1318.4198952460886;1318.940647670857;935.9157675809209;1016.8423894296396;1020.821356262666;1026.384169498295;1027.5119318323736;5824.417558244227;5867.471927526543;5883;5900.409959004065;6058.666773327421;460.4198709625895;461.88683772475287;466.9579737823612;469.39682507977767;470.17249640632735;3807.333716599584;3872.1094148346037;3923.489946306962;3948.0524674078015;3996.720229583901;10340.172786177087;10915.181361397927;10994.120470362352;11086.445677716081;11095.44522773858;10901.727456813644;10908.61819836302;10973.50619222137;10979.725506862393;10987.560497580007;7761.4566980311965;8128.431009829255;8170.42807003504;8215.424920255644;8223.671053157927;5847.239858818326;6102.572819902564;6122.877542449215;6144.907826382697;6241.21984751906;9216;9672.516374181121;9750.024997500193;10039.648612298535;10075.89924100779;9988;10012.749681257736;10159.339642923482;10292.073713365833;10413.479326033517;7315.268473152642;7858.5677787722225;7938.483998540077;8057.919420805952;8250.051244106764;8204.794880128045;8411.53736544496;8431.578421079044;8445.11326310753;8464.195901389357;7507.211742767149;7525;7554.169041405551;7787.182345853604;7801.648925798239;7505.474616776992;7574.810629734301;7656.617169141632;7662;7749.883751743616;6902.930970690229;6915.481338899704;6933.1333583302085;6957.826054348479;6964.965175174092;5180.274761533464;5272.340957380327;5326.280952071421;5390.299261096033;5413.891722165614;5250.973745131403;5263.842084737464;5267.845148836047;5276.129438642581;5389.083855744454 05:32:21 INFO - PROCESS | 30004 | |4;prototype.html;329.6423380632014;335.832083958021;337.9222778760885;341.74881462125336;342.63338228095915;5294.179402192662;5304.681719096847;5313.57491400688;5355.866103347418;5370.7851685932565;4995.428268787816;5118.846434606959;5230.34620672416;5263.973680131604;5273;4143.316352801791;4156.43888075109;4200.004997626118;4208.326667733163;4212.852550160745;4175.624193822556;4176.937345939798;4192.056787222881;4201.39079833424;4225.767582782945;3819.6371344722206;3938.9803050984706;4046.453728746611;4111.5477297497255;4159.2929202035575;4107.178564287125;4108.568600296956;4131.710780245384;4138.2758017347;4144.12973275614;3644.726645501598;3672.1186915140443;3679.797611131387;3689.94465083024;3701.130234394942;4886.560209581119;5067.189249720064;5074.898502029939;5102.642815002914;5122.564582010534;4640.466346370177;4651.302304654295;4690.226112691402;4691.554302341272;4702.835400760957;5477.287952566181;5495.917561236584;5509.3664228613825;5549.445055494459;5607.859803504904;3720.5163328767358;3948.940765888514;3959.8020098994934;3960.1485680578526;3961.445397644332;5645.32256129263;5651.378348381675;5657.547396208293;5672.376038635747;5709.857253568694;4012.6789856811383;4019.2765302245875;4022.336314508132;4057.533383660858;4067.572904845008;3884.2814079395403;4112.362583799516;4127.174565086994;4147.979260103741;4159.292920203573;4637.86086417408;4658.7437690926645;4661.743604101807;4682.204025315705;4683.461401938753;5540.889182216333;5558.861028474321;5560.138178582326;5585.413531579207;5632.464915832989;1329.9069065165345;1365.0990346371348;1370.4518192722792;1381.3438616657008;1394.9163050217019;929.953502324881;940.3182692547848;956.1872408452759;956.6842941829179;958.9136977671903;5257.5815125042145;5305.708186049805;5306.230596563477;5308.018016666929;5311.415744268127;5462;5532.612717109763;5533.78648540587;5558.249636299129;5567.4432556744005;4958.975205123951;5197.818076367309;5234.136367499396;5265.315508983807;5332.226827110125;2886.408286301303;2912.6067980822318;2939.911802645924;2953.4831404504125;2955.586217929492;5736.196932429463;5737.3688894221605;5771.480566749013;5777.7977770777825;5786.161006654096;5460.672359658433;5656.85857853557;5689.630174038674;5689.776698009865;5711.457411545896;5671.262735844314;5694.886102277931;5723.771049157987;5727.3699893011735;5752.252207213036;5271.973640131928;5432.728363581726;5524.171374293888;5612.923218409214;5625.6653488801685;5459.754311055933;5502.614816962774;5512.3660779010925;5521.171824226398;5555.8888822224135;5740.823131257998;5747.942520574741;5748.712564371848;5750.482456578928;5830.4461076197695;3786.9017984784105;3853.7495062820826;3907.199024199975;3926.7447615904875;4065.3495440729343;5635.577331700057;5744.770209191585;5769.769209231584;5773.24947756789;5786.594938354443;5573.916391254215;5588.748506317144;5589.972050139886;5595.496405323541;5617.157426386075;5226.477352264743;5469.933362994337;5519.31008623922;5552.944470555242;5578.135389014709;5455.481729235717;5477.1510415885605;5486.149646804752;5491.560675145899;5495.340559132929;5550.111982082847;5604.887902242014;5618.9604923089355;5633.8309850704545;5638.85902852432;5266.526012658879;5474.370447398441;5478.739889825283;5505.779768809202;5511.173324001432 05:32:21 INFO - PROCESS | 30004 | |5;yui.html;772.2702046565997;813.4305985809933;820.331429884644;864.0495454999501;867.9001914779802;14813.777793333109;15510.457134000313;15510.844891551109;15556;15595.610109747262;14146.080504767184;14159.787603185934;14234.71530569388;14244.14535127893;14247.928760356184;673.3872176319538;807.5598798654718;835.9373047021497;836.7322456813822;838.8573942429801;823.1069289820551;827.6399766101734;829.7344849648115;830.7466222802043;831.0401486283353;717;884.045231150356;886.313107341812;886.7916039730658;887.2724366019867;738.6417587470073;859.256742917375;875.7416562114191;877.6138499060424;880.1110878013188;642.4795915308615;643.298804303307;643.6009674002148;644.5745807766851;645.2160624840841;13404.195748255039;13412.664683382896;13439.932800336033;13457.528986485524;13463.259520726357;13117.934410327982;13215.867841321657;13232;13417.194968301836;13510.662233444144;11162.553497860075;11163;11386.94306528462;11912.34482103484;12073.396330183543;4568.588827005586;4637.211674015425;4647.55848194421;4726.0784147091335;4762.928556071662;52388.738056309856;52768.208476872875;52811.471885281426;53290.733546332405;54026;10860.728481788019;11024.283421577571;11213.831792523119;11293.040091592307;11333.773324533628;18966.051697415074;19286;19360.70958935617;19367.709484357747;19585.216591336186;8302.460340077876;8700.477971321614;8849.51327676984;8888.911110888937;8951.820963580692;4869.14789911764;4932.630052746058;4954.529319714694;4986.80052797884;5027.547520723152;3496.8426420811124;3499.2476617527354;3587.264610754789;3589.635938343413;3594.7483676142942;652.8661624366994;653.5229282623711;664.3423011218858;668.1047396488729;669.2404121322237;773.4276635289957;778.307306497218;793.773774474272;813.8046868751456;825.5872063968015;8218.424710270343;8218.608038284163;8312.127226641236;8477.788055298544;8526.062133165347;700.068908351901;707.9433645308363;718.5078221418345;757.3054200864385;775.0621747685251;4257.446531950888;4334.154839806208;4424.31423129409;4440.800163992628;4531.252343363308;59340;60206.69896650489;60462.39537604656;60919.69540152271;61002.69498652567;48257.5174248253;49299;53664.73167634215;54540.72729636406;55847.16229256564;50943;51051.48948510467;51794.74102629463;51841.48158518367;51995;9218.9539052307;9259.490728009754;9345.719628411158;9450.810983780424;9517.23862091045;33130;33400.498992514644;33767.83116084404;33960.15099622529;34469.310613787085;42803.785981069894;45072.54927450683;45201.32198017098;45924.311135333664;46994.29508557306;11339.829902551302;11848.585299514476;12097.637070887888;12547.560835370718;12833.230006199658;13120.278384688552;13148.276844773247;13229.80155297652;13254.734905302033;13629.522966696119;12881.742365152457;12993;13089.280089594782;13122.60632181036;13367.197968121944;12250.877491224974;12269.141160119063;12454.813177802522;12879.35603219854;12954.67613309637;12206.206596571194;12797.104202705721;12882.420291086735;13026.47894084226;13225.867741322463;10247.231457640557;10253.12848407864;10494.737631559272;10640;10785.029347358775;10173.28786984904;10340.276180667583;10727.249092563447;10759;11430.542778289107 05:32:21 INFO - PROCESS | 30004 | __end_tp_report 05:32:21 INFO - PROCESS | 30004 | __start_cc_report 05:32:21 INFO - PROCESS | 30004 | _x_x_mozilla_cycle_collect,831 05:32:21 INFO - PROCESS | 30004 | __end_cc_report 05:32:21 INFO - PROCESS | 30004 | __startTimestamp1462537941204__endTimestamp 05:32:21 INFO - PROCESS | 30004 | 05:32:21 INFO - PROCESS | 30004 | ------- Summary: start ------- 05:32:21 INFO - PROCESS | 30004 | Number of tests: 6 05:32:21 INFO - PROCESS | 30004 | 05:32:21 INFO - PROCESS | 30004 | [#0] dojo.html Cycles:165 Average:5417.55 Median:5591.05 stddev:611.77 (10.9%) stddev-sans-first:588.11 05:32:21 INFO - PROCESS | 30004 | Values: 3187.9 3189.6 3214.7 3220.0 3250.2 5314.1 5377.1 5389.1 5446.8 5461.0 5370.3 5395.3 5421.6 5453.1 5453.2 4918.4 4934.7 4971.7 4985.7 5174.4 5062.1 5073.0 5109.0 5123.9 5132.6 4834.5 4893.4 4902.6 4933.4 4978.4 4913.9 4915.0 4982.1 5000.6 5032.4 4867.8 4919.9 4952.5 4957.9 5003.8 5297.5 5318.5 5375.3 5428.8 5458.7 5832.6 5875.5 5892.5 5893.6 5903.1 5633.3 5764.3 5778.9 5890.8 5954.1 5472.9 5487.1 5488.9 5495.2 5496.1 5984.2 5986.4 5987.9 6006.6 6007.5 5492.6 5683.5 5721.6 5730.7 5742.1 5874.8 5875.9 5885.0 5891.7 5891.8 5850.1 5850.2 5855.2 5862.0 5868.9 5611.2 5754.7 5760.9 5804.0 5847.1 4425.8 4429.0 4435.2 4440.5 4464.3 3852.9 3958.5 3970.0 3989.4 3995.6 5479.1 5524.2 5529.7 5589.0 5605.1 5877.1 5881.4 5886.0 5886.2 5886.3 5539.5 5549.3 5561.3 5563.8 5565.4 4820.3 4957.9 4982.8 5020.8 5057.3 6022.9 6054.3 6054.9 6057.9 6059.8 5704.2 5798.4 5825.3 5828.7 5834.9 5925.4 5975.2 5983.5 6023.4 6025.5 5735.3 5777.6 5780.2 5780.9 5781.3 5614.6 5813.0 5882.1 5882.9 5890.7 5670.7 5749.1 5751.1 5751.4 5805.2 5723.6 5734.0 5735.5 5739.1 5741.2 5454.7 5518.6 5565.3 5649.2 5652.2 5726.6 5732.6 5733.4 5745.4 5754.7 5516.3 5565.3 5585.4 5586.5 5591.0 05:32:21 INFO - PROCESS | 30004 | 05:32:21 INFO - PROCESS | 30004 | [#1] ext.html Cycles:180 Average:54456.96 Median:41355.19 stddev:101417.68 (245.2%) stddev-sans-first:101621.61 05:32:21 INFO - PROCESS | 30004 | Values: 611.8 627.6 631.0 653.2 657.0 32147.7 35171.3 35183.8 43460.6 44729.8 44973.8 45361.3 45422.5 45446.5 45500.1 8746.4 8797.3 8808.1 8810.7 8900.7 8696.5 8818.8 8820.2 8825.4 8837.3 8849.0 8885.5 8900.4 8902.9 8903.8 8809.4 8858.7 8862.8 8864.7 8866.5 8672.6 8764.8 8766.3 8768.6 8772.0 41259.0 41565.8 41670.2 41700.2 41715.8 3584.5 3834.6 3923.5 4115.9 4305.2 32359.4 32371.7 32727.0 32977.2 33296.0 4118.3 4121.6 4230.2 4285.7 4305.7 308120.0 308208.0 309182.0 309352.4 309471.0 60838.0 61083.0 61215.0 61293.7 61298.7 37069.8 38036.1 38055.6 39651.2 39715.2 14667.6 14703.0 14725.6 14791.1 14810.0 11807.4 11813.9 11814.2 11816.2 11828.7 19761.7 20314.4 20342.3 20376.0 20473.0 7315.0 7370.9 7519.8 7532.7 7638.3 977.1 1073.1 1087.8 1108.9 1239.5 32553.2 32593.5 32994.5 33059.7 33292.0 1114.1 1164.8 1166.4 1240.7 1314.3 17615.0 17617.0 17648.6 17736.2 17837.3 470555.0 471114.0 473155.0 474191.0 474742.0 58344.0 58860.0 58921.7 58991.0 58998.0 1268.2 1382.1 1393.3 1403.1 1419.1 31790.2 32994.8 33817.7 33851.7 34213.1 49719.0 50107.2 50143.0 50246.2 50255.5 317691.0 320423.0 321504.0 323274.0 456984.0 28557.9 29099.6 29484.9 30659.8 30717.7 48142.5 49326.5 50110.7 50404.2 50684.5 41191.6 41451.4 41501.4 42188.0 43366.8 40551.8 41189.6 41563.0 41797.0 42280.8 41713.4 42069.8 42179.6 42640.4 42770.0 34583.8 36210.3 36429.6 36643.6 36697.3 28231.9 28349.6 28457.4 28558.1 28695.7 05:32:21 INFO - PROCESS | 30004 | 05:32:21 INFO - PROCESS | 30004 | [#2] jquery.html Cycles:180 Average:25669.16 Median:22541.05 stddev:65280.61 (289.6%) stddev-sans-first:65436.18 05:32:21 INFO - PROCESS | 30004 | Values: 406.9 410.9 410.9 411.9 427.8 17744.6 19459.2 19497.2 19734.1 20247.9 18543.9 18985.6 18997.3 19486.4 19503.3 5462.0 5551.3 5613.0 5710.6 5712.7 5555.1 5580.6 5584.2 5607.9 5663.3 5357.6 5507.4 5584.5 5642.9 5659.0 5457.6 5481.2 5554.1 5554.3 5574.7 4962.8 5071.0 5105.2 5105.4 5106.6 21904.8 22348.9 22363.4 22718.7 23140.4 2431.5 2442.6 2500.1 3069.9 3098.1 10347.2 10629.8 10673.1 10862.0 10891.0 1934.3 1941.7 1949.5 1961.2 2025.4 76799.0 79509.3 82036.6 83189.6 83597.6 10970.5 10987.1 11262.7 11607.3 12175.2 15637.3 15685.3 15741.5 15759.9 15777.4 8053.9 8080.8 8284.3 8303.4 8329.4 7788.5 7831.6 7916.0 8038.5 8096.2 4760.0 4778.4 4868.2 4916.8 5013.5 1985.4 2013.5 2020.2 2021.1 2046.6 964.9 1037.3 1067.8 1105.7 1109.4 18877.2 19586.9 19627.5 19657.9 19790.4 1006.7 1051.8 1059.6 1095.0 1135.8 10360.7 11085.4 11162.8 11165.8 11167.4 361930.0 365354.0 366473.3 430442.0 440539.0 59168.0 68644.7 82544.2 84337.2 85113.1 1076.4 1117.6 1123.8 1163.1 1166.0 13206.4 16492.0 17607.6 17629.2 17848.6 36367.5 36993.6 40174.6 40258.9 40795.6 53261.5 54408.0 54626.0 55503.7 56415.7 8435.4 8640.4 8649.7 8731.7 8832.5 9351.8 10932.6 13211.5 13355.9 13483.6 11493.9 11604.8 11628.2 11978.3 12031.9 11662.9 11817.0 11858.9 11859.7 11902.8 10459.0 11655.1 11799.5 11800.3 12203.8 7632.5 7838.3 7928.5 8194.8 8988.2 11697.5 11714.7 11751.0 11964.5 12310.8 05:32:21 INFO - PROCESS | 30004 | 05:32:21 INFO - PROCESS | 30004 | [#3] mootools.html Cycles:180 Average:5732.58 Median:4758.57 stddev:3222.03 (67.7%) stddev-sans-first:3214.41 05:32:21 INFO - PROCESS | 30004 | Values: 1373.5 1543.1 1571.0 1754.2 1862.1 8234.0 8493.0 8530.4 8729.6 8730.0 8463.0 8687.6 8702.7 8709.1 8714.1 2074.4 2255.3 2346.9 2352.5 2375.8 2379.6 2384.1 2393.8 2399.0 2403.1 2260.3 2312.9 2313.5 2314.4 2316.1 1917.9 2264.2 2340.1 2346.8 2357.1 2247.1 2257.8 2274.2 2279.9 2281.8 8643.5 8693.3 8701.0 8709.4 8722.9 5077.7 5155.3 5182.5 5198.8 5215.9 3260.2 3307.6 3343.1 3449.2 3451.3 1160.4 1182.2 1189.7 1190.1 1217.9 11431.1 11455.1 11462.0 11470.4 11487.8 4504.1 4583.2 4679.1 4684.9 4690.5 9772.5 9783.6 9784.0 9787.7 9791.0 4748.3 5265.5 5279.9 5281.7 5285.1 4768.8 4914.8 4929.4 4986.6 4990.0 4003.7 4010.7 4027.5 4073.2 4196.6 1289.8 1298.0 1306.8 1318.4 1318.9 935.9 1016.8 1020.8 1026.4 1027.5 5824.4 5867.5 5883.0 5900.4 6058.7 460.4 461.9 467.0 469.4 470.2 3807.3 3872.1 3923.5 3948.1 3996.7 10340.2 10915.2 10994.1 11086.4 11095.4 10901.7 10908.6 10973.5 10979.7 10987.6 7761.5 8128.4 8170.4 8215.4 8223.7 5847.2 6102.6 6122.9 6144.9 6241.2 9216.0 9672.5 9750.0 10039.6 10075.9 9988.0 10012.7 10159.3 10292.1 10413.5 7315.3 7858.6 7938.5 8057.9 8250.1 8204.8 8411.5 8431.6 8445.1 8464.2 7507.2 7525.0 7554.2 7787.2 7801.6 7505.5 7574.8 7656.6 7662.0 7749.9 6902.9 6915.5 6933.1 6957.8 6965.0 5180.3 5272.3 5326.3 5390.3 5413.9 5251.0 5263.8 5267.8 5276.1 5389.1 05:32:21 INFO - PROCESS | 30004 | 05:32:21 INFO - PROCESS | 30004 | [#4] prototype.html Cycles:180 Average:4628.91 Median:5305.97 stddev:1358.79 (25.6%) stddev-sans-first:1323.73 05:32:21 INFO - PROCESS | 30004 | Values: 329.6 335.8 337.9 341.7 342.6 5294.2 5304.7 5313.6 5355.9 5370.8 4995.4 5118.8 5230.3 5264.0 5273.0 4143.3 4156.4 4200.0 4208.3 4212.9 4175.6 4176.9 4192.1 4201.4 4225.8 3819.6 3939.0 4046.5 4111.5 4159.3 4107.2 4108.6 4131.7 4138.3 4144.1 3644.7 3672.1 3679.8 3689.9 3701.1 4886.6 5067.2 5074.9 5102.6 5122.6 4640.5 4651.3 4690.2 4691.6 4702.8 5477.3 5495.9 5509.4 5549.4 5607.9 3720.5 3948.9 3959.8 3960.1 3961.4 5645.3 5651.4 5657.5 5672.4 5709.9 4012.7 4019.3 4022.3 4057.5 4067.6 3884.3 4112.4 4127.2 4148.0 4159.3 4637.9 4658.7 4661.7 4682.2 4683.5 5540.9 5558.9 5560.1 5585.4 5632.5 1329.9 1365.1 1370.5 1381.3 1394.9 930.0 940.3 956.2 956.7 958.9 5257.6 5305.7 5306.2 5308.0 5311.4 5462.0 5532.6 5533.8 5558.2 5567.4 4959.0 5197.8 5234.1 5265.3 5332.2 2886.4 2912.6 2939.9 2953.5 2955.6 5736.2 5737.4 5771.5 5777.8 5786.2 5460.7 5656.9 5689.6 5689.8 5711.5 5671.3 5694.9 5723.8 5727.4 5752.3 5272.0 5432.7 5524.2 5612.9 5625.7 5459.8 5502.6 5512.4 5521.2 5555.9 5740.8 5747.9 5748.7 5750.5 5830.4 3786.9 3853.7 3907.2 3926.7 4065.3 5635.6 5744.8 5769.8 5773.2 5786.6 5573.9 5588.7 5590.0 5595.5 5617.2 5226.5 5469.9 5519.3 5552.9 5578.1 5455.5 5477.2 5486.1 5491.6 5495.3 5550.1 5604.9 5619.0 5633.8 5638.9 5266.5 5474.4 5478.7 5505.8 5511.2 05:32:21 INFO - PROCESS | 30004 | 05:32:21 INFO - PROCESS | 30004 | [#5] yui.html Cycles:180 Average:14740.00 Median:4642.39 stddev:16723.72 (360.2%) stddev-sans-first:16737.73 05:32:21 INFO - PROCESS | 30004 | Values: 772.3 813.4 820.3 864.0 867.9 14813.8 15510.5 15510.8 15556.0 15595.6 14146.1 14159.8 14234.7 14244.1 14247.9 673.4 807.6 835.9 836.7 838.9 823.1 827.6 829.7 830.7 831.0 717.0 884.0 886.3 886.8 887.3 738.6 859.3 875.7 877.6 880.1 642.5 643.3 643.6 644.6 645.2 13404.2 13412.7 13439.9 13457.5 13463.3 13117.9 13215.9 13232.0 13417.2 13510.7 11162.6 11163.0 11386.9 11912.3 12073.4 4568.6 4637.2 4647.6 4726.1 4762.9 52388.7 52768.2 52811.5 53290.7 54026.0 10860.7 11024.3 11213.8 11293.0 11333.8 18966.1 19286.0 19360.7 19367.7 19585.2 8302.5 8700.5 8849.5 8888.9 8951.8 4869.1 4932.6 4954.5 4986.8 5027.5 3496.8 3499.2 3587.3 3589.6 3594.7 652.9 653.5 664.3 668.1 669.2 773.4 778.3 793.8 813.8 825.6 8218.4 8218.6 8312.1 8477.8 8526.1 700.1 707.9 718.5 757.3 775.1 4257.4 4334.2 4424.3 4440.8 4531.3 59340.0 60206.7 60462.4 60919.7 61002.7 48257.5 49299.0 53664.7 54540.7 55847.2 50943.0 51051.5 51794.7 51841.5 51995.0 9219.0 9259.5 9345.7 9450.8 9517.2 33130.0 33400.5 33767.8 33960.2 34469.3 42803.8 45072.5 45201.3 45924.3 46994.3 11339.8 11848.6 12097.6 12547.6 12833.2 13120.3 13148.3 13229.8 13254.7 13629.5 12881.7 12993.0 13089.3 13122.6 13367.2 12250.9 12269.1 12454.8 12879.4 12954.7 12206.2 12797.1 12882.4 13026.5 13225.9 10247.2 10253.1 10494.7 10640.0 10785.0 10173.3 10340.3 10727.2 10759.0 11430.5 05:32:21 INFO - PROCESS | 30004 | -------- Summary: end -------- 05:32:21 INFO - PROCESS | 30004 | 05:32:21 INFO - TEST-INFO | 30004: exit 0 05:32:21 INFO - TEST-OK | dromaeo_css | took 1112411ms 05:32:21 INFO - TEST-START | kraken 05:32:21 INFO - operating with platform_type : linux_ 05:32:21 INFO - Initialising browser for kraken test... 05:32:21 INFO - TEST-INFO | started process 30182 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVpDdVa/profile http://localhost:40307/getInfo.html) 05:32:25 INFO - PROCESS | 30182 | __metrics Screen width/height:1600/1200 05:32:25 INFO - PROCESS | 30182 | colorDepth:24 05:32:25 INFO - PROCESS | 30182 | Browser inner width/height: 1024/697 05:32:25 INFO - PROCESS | 30182 | __metrics 05:32:26 ERROR - PROCESS | 30182 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:32:27 INFO - TEST-INFO | 30182: exit 0 05:32:27 INFO - Browser initialized. 05:32:27 INFO - Running cycle 1/1 for kraken test... 05:32:27 INFO - TEST-INFO | started process 30262 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVpDdVa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:39 INFO - PROCESS | 30262 | RSS: Main: 151814144 05:32:39 INFO - PROCESS | 30262 | 05:32:41 INFO - PROCESS | 30262 | Cycle 1(1): loaded http://localhost:40307/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40307/tests/kraken/driver.html?testName=audio-beat-detection) 05:32:42 INFO - PROCESS | 30262 | RSS: Main: 208322560 05:32:42 INFO - PROCESS | 30262 | 05:32:45 INFO - PROCESS | 30262 | Cycle 1(1): loaded http://localhost:40307/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40307/tests/kraken/driver.html?testName=audio-dft) 05:32:46 INFO - PROCESS | 30262 | RSS: Main: 197869568 05:32:46 INFO - PROCESS | 30262 | 05:32:49 INFO - PROCESS | 30262 | Cycle 1(1): loaded http://localhost:40307/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40307/tests/kraken/driver.html?testName=audio-fft) 05:32:49 INFO - PROCESS | 30262 | RSS: Main: 264941568 05:32:49 INFO - PROCESS | 30262 | 05:32:51 INFO - PROCESS | 30262 | Cycle 1(1): loaded http://localhost:40307/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40307/tests/kraken/driver.html?testName=audio-oscillator) 05:32:52 INFO - PROCESS | 30262 | RSS: Main: 174260224 05:32:52 INFO - PROCESS | 30262 | 05:32:54 INFO - PROCESS | 30262 | Cycle 1(1): loaded http://localhost:40307/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40307/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:32:55 INFO - PROCESS | 30262 | RSS: Main: 177741824 05:32:55 INFO - PROCESS | 30262 | 05:32:58 INFO - PROCESS | 30262 | Cycle 1(1): loaded http://localhost:40307/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40307/tests/kraken/driver.html?testName=imaging-darkroom) 05:32:58 INFO - PROCESS | 30262 | RSS: Main: 213819392 05:32:58 INFO - PROCESS | 30262 | 05:33:02 INFO - PROCESS | 30262 | Cycle 1(1): loaded http://localhost:40307/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40307/tests/kraken/driver.html?testName=imaging-desaturate) 05:33:02 INFO - PROCESS | 30262 | RSS: Main: 214794240 05:33:02 INFO - PROCESS | 30262 | 05:33:05 INFO - PROCESS | 30262 | Cycle 1(1): loaded http://localhost:40307/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40307/tests/kraken/driver.html?testName=json-parse-financial) 05:33:06 INFO - PROCESS | 30262 | RSS: Main: 215478272 05:33:06 INFO - PROCESS | 30262 | 05:33:07 INFO - PROCESS | 30262 | Cycle 1(1): loaded http://localhost:40307/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40307/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:33:08 INFO - PROCESS | 30262 | RSS: Main: 204705792 05:33:08 INFO - PROCESS | 30262 | 05:33:10 INFO - PROCESS | 30262 | Cycle 1(1): loaded http://localhost:40307/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40307/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:33:10 INFO - PROCESS | 30262 | RSS: Main: 207450112 05:33:10 INFO - PROCESS | 30262 | 05:33:12 INFO - PROCESS | 30262 | Cycle 1(1): loaded http://localhost:40307/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40307/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:33:13 INFO - PROCESS | 30262 | RSS: Main: 216907776 05:33:13 INFO - PROCESS | 30262 | 05:33:16 INFO - PROCESS | 30262 | Cycle 1(1): loaded http://localhost:40307/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40307/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:33:16 INFO - PROCESS | 30262 | RSS: Main: 221114368 05:33:16 INFO - PROCESS | 30262 | 05:33:19 INFO - PROCESS | 30262 | Cycle 1(1): loaded http://localhost:40307/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40307/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:33:20 INFO - PROCESS | 30262 | RSS: Main: 217174016 05:33:20 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | Cycle 1(1): loaded http://localhost:40307/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40307/tests/kraken/driver.html?testName=ai-astar) 05:33:21 INFO - PROCESS | 30262 | RSS: Main: 263487488 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | __start_tp_report 05:33:21 INFO - PROCESS | 30262 | _x_x_mozilla_page_load 05:33:21 INFO - PROCESS | 30262 | _x_x_mozilla_page_load_details 05:33:21 INFO - PROCESS | 30262 | |i|pagename|runs| 05:33:21 INFO - PROCESS | 30262 | |0;ai-astar;109;104;110;109;110;108;109;111;110;109 05:33:21 INFO - PROCESS | 30262 | |1;audio-beat-detection;158;126;152;183;127;156;162;127;185;124 05:33:21 INFO - PROCESS | 30262 | |2;audio-dft;160;161;160;159;159;160;161;162;161;160 05:33:21 INFO - PROCESS | 30262 | |3;audio-fft;76;75;75;123;76;74;77;76;130;76 05:33:21 INFO - PROCESS | 30262 | |4;audio-oscillator;93;92;124;92;92;92;123;91;91;128 05:33:21 INFO - PROCESS | 30262 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;117;117;117 05:33:21 INFO - PROCESS | 30262 | |6;imaging-darkroom;148;148;150;148;149;148;149;149;148;146 05:33:21 INFO - PROCESS | 30262 | |7;imaging-desaturate;96;96;96;96;95;94;94;94;96;95 05:33:21 INFO - PROCESS | 30262 | |8;json-parse-financial;58;58;57;56;58;58;58;75;56;55 05:33:21 INFO - PROCESS | 30262 | |9;json-stringify-tinderbox;59;59;59;60;60;59;60;60;61;60 05:33:21 INFO - PROCESS | 30262 | |10;stanford-crypto-aes;80;77;84;78;78;79;85;79;80;83 05:33:21 INFO - PROCESS | 30262 | |11;stanford-crypto-ccm;135;122;121;121;121;122;124;126;227;123 05:33:21 INFO - PROCESS | 30262 | |12;stanford-crypto-pbkdf2;170;173;169;171;170;172;169;167;168;165 05:33:21 INFO - PROCESS | 30262 | |13;stanford-crypto-sha256-iterative;66;65;66;65;66;64;65;63;63;63 05:33:21 INFO - PROCESS | 30262 | __end_tp_report 05:33:21 INFO - PROCESS | 30262 | __start_cc_report 05:33:21 INFO - PROCESS | 30262 | _x_x_mozilla_cycle_collect,1628 05:33:21 INFO - PROCESS | 30262 | __end_cc_report 05:33:21 INFO - PROCESS | 30262 | __startTimestamp1462538001981__endTimestamp 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | ------- Summary: start ------- 05:33:21 INFO - PROCESS | 30262 | Number of tests: 14 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | [#0] ai-astar Cycles:10 Average:108.90 Median:109.50 stddev:1.91 (1.7%) stddev-sans-first:2.03 05:33:21 INFO - PROCESS | 30262 | Values: 109.0 104.0 110.0 109.0 110.0 108.0 109.0 111.0 110.0 109.0 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | [#1] audio-beat-detection Cycles:10 Average:150.00 Median:157.00 stddev:23.27 (14.8%) stddev-sans-first:24.50 05:33:21 INFO - PROCESS | 30262 | Values: 158.0 126.0 152.0 183.0 127.0 156.0 162.0 127.0 185.0 124.0 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 05:33:21 INFO - PROCESS | 30262 | Values: 160.0 161.0 160.0 159.0 159.0 160.0 161.0 162.0 161.0 160.0 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | [#3] audio-fft Cycles:10 Average:85.80 Median:76.00 stddev:21.53 (28.3%) stddev-sans-first:22.54 05:33:21 INFO - PROCESS | 30262 | Values: 76.0 75.0 75.0 123.0 76.0 74.0 77.0 76.0 130.0 76.0 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | [#4] audio-oscillator Cycles:10 Average:101.80 Median:92.00 stddev:16.07 (17.5%) stddev-sans-first:16.72 05:33:21 INFO - PROCESS | 30262 | Values: 93.0 92.0 124.0 92.0 92.0 92.0 123.0 91.0 91.0 128.0 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 05:33:21 INFO - PROCESS | 30262 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.50 stddev:1.06 (0.7%) stddev-sans-first:1.12 05:33:21 INFO - PROCESS | 30262 | Values: 148.0 148.0 150.0 148.0 149.0 148.0 149.0 149.0 148.0 146.0 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:96.00 stddev:0.92 (1.0%) stddev-sans-first:0.93 05:33:21 INFO - PROCESS | 30262 | Values: 96.0 96.0 96.0 96.0 95.0 94.0 94.0 94.0 96.0 95.0 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | [#8] json-parse-financial Cycles:10 Average:58.90 Median:58.00 stddev:5.76 (9.9%) stddev-sans-first:6.10 05:33:21 INFO - PROCESS | 30262 | Values: 58.0 58.0 57.0 56.0 58.0 58.0 58.0 75.0 56.0 55.0 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 05:33:21 INFO - PROCESS | 30262 | Values: 59.0 59.0 59.0 60.0 60.0 59.0 60.0 60.0 61.0 60.0 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:80.00 stddev:2.75 (3.4%) stddev-sans-first:2.92 05:33:21 INFO - PROCESS | 30262 | Values: 80.0 77.0 84.0 78.0 78.0 79.0 85.0 79.0 80.0 83.0 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | [#11] stanford-crypto-ccm Cycles:10 Average:134.20 Median:123.50 stddev:32.88 (26.6%) stddev-sans-first:34.87 05:33:21 INFO - PROCESS | 30262 | Values: 135.0 122.0 121.0 121.0 121.0 122.0 124.0 126.0 227.0 123.0 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.40 Median:170.00 stddev:2.37 (1.4%) stddev-sans-first:2.50 05:33:21 INFO - PROCESS | 30262 | Values: 170.0 173.0 169.0 171.0 170.0 172.0 169.0 167.0 168.0 165.0 05:33:21 INFO - PROCESS | 30262 | 05:33:21 INFO - PROCESS | 30262 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:65.00 stddev:1.26 (1.9%) stddev-sans-first:1.24 05:33:21 INFO - PROCESS | 30262 | Values: 66.0 65.0 66.0 65.0 66.0 64.0 65.0 63.0 63.0 63.0 05:33:21 INFO - PROCESS | 30262 | -------- Summary: end -------- 05:33:21 INFO - PROCESS | 30262 | 05:33:22 INFO - TEST-INFO | 30262: exit 0 05:33:22 INFO - TEST-OK | kraken | took 61080ms 05:33:22 INFO - SUITE-END | took 1173s 05:33:23 INFO - Completed test suite (00:19:33) 05:33:23 INFO - javascript benchmark 05:33:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3187.8724851005954, 3189.6012998375204, 3214.7428205743545, 3220.0339898030584, 3250.1549597104745, 5314.096603577392, 5377.11277639189, 5389.137737961922, 5446.754896029674, 5460.989716902371, 5370.3287089113865, 5395.298611180541, 5421.566274698024, 5453.127499600065, 5453.15476101205, 4918.385201849769, 4934.679245849026, 4971.651984361096, 4985.651004429691, 5174.43081261061, 5062.0888240116765, 5072.960043191156, 5108.974455127719, 5123.923141152885, 5132.589392848563, 4834.516548345173, 4893.363862697826, 4902.632302577321, 4933.432655244639, 4978.4025916890205, 4913.852584422473, 4914.967856750087, 4982.078315511606, 5000.599952003831, 5032.446430892599, 4867.805287788484, 4919.90160196794, 4952.529509696609, 4957.925631115536, 5003.774830132653, 5297.49673780994, 5318.547923426513, 5375.301210842605, 5428.75570599324, 5458.6724796512335, 5832.562557808182, 5875.471207591295, 5892.528597712173, 5893.5579831512805, 5903.0555111182, 5633.32400111985, 5764.279465066867, 5778.88442231153, 5890.793822216202, 5954.07711804671, 5472.863178420532, 5487.067198576252, 5488.862778430492, 5495.203195536625, 5496.120620700709, 5984.1921340618455, 5986.431289027535, 5987.940120598825, 6006.609570377911, 6007.519398448114, 5492.642978206404, 5683.54531637468, 5721.599488035798, 5730.713464326767, 5742.052561327334, 5874.823755287348, 5875.853103672357, 5885.028970219952, 5891.675957822275, 5891.793787217427, 5850.0932355484965, 5850.151727999416, 5855.209546711225, 5862.032764593794, 5868.853278668067, 5611.2144299798065, 5754.74103665336, 5760.884782304414, 5804.013317735995, 5847.06446968483, 4425.756583387944, 4428.955710442919, 4435.246008178618, 4440.511543730187, 4464.330350447394, 3852.8844134676006, 3958.5249770027203, 3970.0273433009097, 3989.401589761559, 3995.580464051291, 5479.068558345091, 5524.1989911462615, 5529.695866727288, 5588.972055139699, 5605.131204663284, 5877.089051197071, 5881.3530511643685, 5886.0, 5886.175935369051, 5886.3230728465915, 5539.5014448699, 5549.306336707911, 5561.332640083135, 5563.833085007456, 5565.41563135873, 4820.301056346881, 4957.90084198314, 4982.750862456863, 5020.7740651670765, 5057.266696329012, 6022.939770602326, 6054.303755068136, 6054.909176362358, 6057.878842423127, 6059.757609695651, 5704.172894930295, 5798.449147330997, 5825.300963884191, 5828.6502809831545, 5834.912476312774, 5925.40745925404, 5975.16347711312, 5983.491403230774, 6023.397660233942, 6025.548083893638, 5735.340435849931, 5777.62445441045, 5780.248567686174, 5780.884382312414, 5781.306243250837, 5614.5789065821045, 5812.9709351452975, 5882.117682347682, 5882.852928676817, 5890.734916928663, 5670.74481648335, 5749.051406517878, 5751.051076572318, 5751.424857514216, 5805.245318108618, 5723.599348045597, 5734.0, 5735.483806457439, 5739.110437882135, 5741.224934633771, 5454.672719636835, 5518.586106042143, 5565.30433695788, 5649.2373529574625, 5652.152177173457, 5726.599138060295, 5732.627379220337, 5733.397993210653, 5745.396733343106, 5754.741036653444, 5516.310461192351, 5565.276514053309, 5585.441455854545, 5586.497215250647, 5591.049521581423], "value": 5376.778930439023, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [611.7981066248138, 627.629698477898, 630.9620673991282, 653.1835205992508, 657.0046379734699, 32147.67852321476, 35171.2965740685, 35183.82408087959, 43460.56539434605, 44729.7763511182, 44973.77513112438, 45361.31958020632, 45422.54577454225, 45446.545534544646, 45500.0899982001, 8746.431481953668, 8797.252233560122, 8808.075152109033, 8810.73567792964, 8900.73297801067, 8696.521691306976, 8818.823623527525, 8820.20618144367, 8825.426347287406, 8837.337199710048, 8849.0, 8885.46687198767, 8900.37697361185, 8902.910970890272, 8903.77740556488, 8809.38334316598, 8858.689945851864, 8862.778430539289, 8864.689735859247, 8866.468011919305, 8672.609732561987, 8764.824703505894, 8766.298696104297, 8768.649254029831, 8771.956140219321, 41259.0, 41565.79217103895, 41670.1665966682, 41700.1659966799, 41715.791421043, 3584.4802503636824, 3834.6357096075826, 3923.4507168996365, 4115.855945041939, 4305.246581848195, 32359.352812943845, 32371.676283237102, 32726.966648181766, 32977.175570610685, 33296.0, 4118.341065429547, 4121.62905338518, 4230.153969206141, 4285.742855428684, 4305.698601097924, 308120.0, 308208.0, 309182.0, 309352.43885366723, 309470.99999999773, 60838.0, 61083.0, 61215.0, 61293.69353153206, 61298.69350653218, 37069.81465092711, 38036.14728895344, 38055.619443805765, 39651.206975860325, 39715.20569588592, 14667.559973200821, 14703.0, 14725.631859203606, 14791.112533248039, 14809.963302568716, 11807.350595717146, 11813.940930295294, 11814.232074915102, 11816.172867899337, 11828.70428239301, 19761.703574446394, 20314.390568282975, 20342.288019919233, 20376.0, 20473.0, 7315.0, 7370.926290737024, 7519.8120046998165, 7532.698692052257, 7638.2743639355285, 977.0717818072859, 1073.1361254190297, 1087.8096333141668, 1108.944552772358, 1239.4670291774623, 32553.186170345456, 32593.511097333558, 32994.505082423784, 33059.66940330614, 33292.0, 1114.136544178252, 1164.7728692904802, 1166.3643314393676, 1240.7084335181225, 1314.2771475688332, 17615.0, 17617.0, 17648.647027059385, 17736.20187091584, 17837.286508539513, 470555.0, 471114.0, 473155.0, 474191.0, 474742.0, 58344.0, 58860.0, 58921.705391473624, 58991.0, 58998.0, 1268.1883594499527, 1382.0601990646255, 1393.2824595333411, 1403.0950037226137, 1419.0563275421935, 31790.205244869063, 32994.835025824716, 33817.71456070799, 33851.66148338583, 34213.144671383416, 49719.0, 50107.24839127489, 50143.0, 50246.2463063047, 50255.49744502508, 317691.0, 320423.0, 321504.0, 323274.0, 456984.0, 28557.857210713813, 29099.563506546994, 29484.852575736983, 30659.846700766353, 30717.692823071484, 48142.5185748138, 49326.50673493219, 50110.749446252536, 50404.243936341714, 50684.493155067976, 41191.588084118775, 41451.37822932719, 41501.37747933723, 42188.0, 43366.783166083966, 40551.797241013606, 41189.58810411977, 41563.0, 41797.0, 42280.788596056824, 41713.37429938493, 42069.78965105155, 42179.57820421756, 42640.360394594725, 42770.0, 34583.82708086444, 36210.27579448344, 36429.63570364368, 36643.63356366402, 36697.26605467929, 28231.858840705667, 28349.574756378257, 28457.430851382444, 28558.14325570236, 28695.713042869305], "value": 19809.210653928905, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.868904445641, 410.91165399439114, 410.9198706252281, 411.886731148934, 427.801072501287, 17744.556386090353, 19459.221631134755, 19497.220111195555, 19734.111964961576, 20247.898760506214, 18543.90728046358, 18985.620287594273, 18997.335093271704, 19486.415407537763, 19503.317383891568, 5461.972690136554, 5551.333839939192, 5612.971935140318, 5710.628809127415, 5712.657240565559, 5555.083411237141, 5580.609357344987, 5584.1902924076185, 5607.915881261764, 5663.348714897777, 5357.571394288467, 5507.421720719327, 5584.497395234428, 5642.915356269638, 5659.0, 5457.5633949283965, 5481.205225242337, 5554.0835762099205, 5554.305711786027, 5574.665520068809, 4962.826301079445, 5071.0365030644425, 5105.183170692671, 5105.412877519096, 5106.591472682177, 21904.780952190435, 22348.88825555878, 22363.44091397712, 22718.659220111713, 23140.42148946273, 2431.4772323950438, 2442.6336049592705, 2500.087468074158, 3069.938601227985, 3098.148009297439, 10347.223958203165, 10629.787404251872, 10673.092787113104, 10862.0, 10891.0198082173, 1934.332655233942, 1941.6602094633383, 1949.4541528372079, 1961.1567026178736, 2025.422754514956, 76799.0, 79509.30304635975, 82036.58981705114, 83189.58405207995, 83597.58201209016, 10970.451477426097, 10987.121030317556, 11262.662120136409, 11607.30356178632, 12175.208611440405, 15637.296321665552, 15685.294161762748, 15741.527754167164, 15759.921200394154, 15777.447789327318, 8053.919460805434, 8080.757577272691, 8284.295834854105, 8303.377246706521, 8329.37529685276, 7788.493747906368, 7831.608419578998, 7915.960420197977, 8038.517688938682, 8096.230858068474, 4759.976200118977, 4778.37881075458, 4868.245197582357, 4916.803327866917, 5013.523715247045, 1985.444075658789, 2013.496625843539, 2020.19192323069, 2021.080408414168, 2046.5599896022427, 964.9035096490435, 1037.3101887244898, 1067.765091679829, 1105.690406686129, 1109.4341885638382, 18877.244910203437, 19586.92271925029, 19627.509312267022, 19657.901710491355, 19790.406287811387, 1006.6979906028163, 1051.8264486359817, 1059.6450189186555, 1094.9945250273856, 1135.8125909224884, 10360.689179324632, 11085.445727713583, 11162.776744465227, 11165.776684466266, 11167.385793781257, 361930.0, 365354.0, 366473.33526664926, 430442.0, 440539.0, 59168.0, 68644.6567767158, 82544.17455825485, 84337.15662843415, 85113.14886851177, 1076.4025965589174, 1117.6367680504031, 1123.775244951013, 1163.069544364522, 1165.9941700291445, 13206.405711742802, 16492.010479371274, 17607.559811004827, 17629.20668569943, 17848.55378615545, 36367.45448818217, 36993.6300637001, 40174.59825401825, 40258.86409634965, 40795.59204407918, 53261.4673853272, 54408.0, 54626.0, 55503.72248138733, 56415.71792141177, 8435.409521333693, 8640.351973601879, 8649.697260595849, 8731.65073397057, 8832.514211718417, 9351.814631193125, 10932.617358392417, 13211.537596184087, 13355.866441335462, 13483.595492135251, 11493.942530287295, 11604.825927610924, 11628.186026978368, 11978.31546219145, 12031.879681203076, 11662.88337116652, 11817.0, 11858.940705296418, 11859.653310260699, 11902.761944761229, 10459.00639439252, 11655.125865559947, 11799.52801887915, 11800.291982481078, 12203.75078991519, 7632.465727399029, 7838.3147623261275, 7928.524288542706, 8194.754157375288, 8988.15742772717, 11697.532098715956, 11714.670042728185, 11751.00116490108, 11964.46159922823, 12310.81533777012], "value": 9154.987096972669, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1373.4780783302344, 1543.1281326050785, 1570.9553147157142, 1754.184304298501, 1862.143414029547, 8234.0, 8493.023302320235, 8530.402871798977, 8729.563521823915, 8729.956350218241, 8462.95768521158, 8687.609057592408, 8702.651893924236, 8709.085546017672, 8714.085021072775, 2074.4191626344573, 2255.3459496745922, 2346.9295921122393, 2352.494213744045, 2375.7861792438675, 2379.5597814404305, 2384.1178763857397, 2393.808495320379, 2398.95202095958, 2403.0748161957686, 2260.3219034289646, 2312.8843557822124, 2313.548857972696, 2314.386687527798, 2316.061994892063, 1917.9041047947617, 2264.1962103453366, 2340.0990618611786, 2346.7887890089805, 2357.104300365867, 2247.1236217875044, 2257.7742225777456, 2274.192661605123, 2279.8974046167796, 2281.828862835294, 8643.481391116553, 8693.347998900044, 8701.0, 8709.390342676015, 8722.912770872274, 5077.746112694351, 5155.278261043457, 5182.481751824825, 5198.792048318101, 5215.947840521584, 3260.152360386293, 3307.6361600223954, 3343.147497388175, 3449.189440481485, 3451.3097380523745, 1160.3907948327112, 1182.2256422043574, 1189.6550000499844, 1190.053907143814, 1217.902567794583, 11431.14266430021, 11455.083593312514, 11461.999999999916, 11470.369129697863, 11487.827682584768, 4504.121696269196, 4583.152116858325, 4679.064187162581, 4684.906301874012, 4690.507496712864, 9772.51137443125, 9783.559739811726, 9784.0, 9787.657431989846, 9791.0, 4748.335233067373, 5265.4997775211295, 5279.894402111936, 5281.735913204325, 5285.101532739443, 4768.809247630125, 4914.827981020719, 4929.408470983505, 4986.55121039101, 4989.977054703777, 4003.719739618257, 4010.6991975601945, 4027.4985729530717, 4073.165001174752, 4196.580341965779, 1289.7936330187313, 1297.9870201298056, 1306.7713150198867, 1318.4198952460886, 1318.940647670857, 935.9157675809209, 1016.8423894296396, 1020.821356262666, 1026.384169498295, 1027.5119318323736, 5824.417558244227, 5867.471927526543, 5883.0, 5900.409959004065, 6058.666773327421, 460.4198709625895, 461.88683772475287, 466.9579737823612, 469.39682507977767, 470.17249640632735, 3807.333716599584, 3872.1094148346037, 3923.489946306962, 3948.0524674078015, 3996.720229583901, 10340.172786177087, 10915.181361397927, 10994.120470362352, 11086.445677716081, 11095.44522773858, 10901.727456813644, 10908.61819836302, 10973.50619222137, 10979.725506862393, 10987.560497580007, 7761.4566980311965, 8128.431009829255, 8170.42807003504, 8215.424920255644, 8223.671053157927, 5847.239858818326, 6102.572819902564, 6122.877542449215, 6144.907826382697, 6241.21984751906, 9216.0, 9672.516374181121, 9750.024997500193, 10039.648612298535, 10075.89924100779, 9988.0, 10012.749681257736, 10159.339642923482, 10292.073713365833, 10413.479326033517, 7315.268473152642, 7858.5677787722225, 7938.483998540077, 8057.919420805952, 8250.051244106764, 8204.794880128045, 8411.53736544496, 8431.578421079044, 8445.11326310753, 8464.195901389357, 7507.211742767149, 7525.0, 7554.169041405551, 7787.182345853604, 7801.648925798239, 7505.474616776992, 7574.810629734301, 7656.617169141632, 7662.0, 7749.883751743616, 6902.930970690229, 6915.481338899704, 6933.1333583302085, 6957.826054348479, 6964.965175174092, 5180.274761533464, 5272.340957380327, 5326.280952071421, 5390.299261096033, 5413.891722165614, 5250.973745131403, 5263.842084737464, 5267.845148836047, 5276.129438642581, 5389.083855744454], "value": 4542.245932362447, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.6423380632014, 335.832083958021, 337.9222778760885, 341.74881462125336, 342.63338228095915, 5294.179402192662, 5304.681719096847, 5313.57491400688, 5355.866103347418, 5370.7851685932565, 4995.428268787816, 5118.846434606959, 5230.34620672416, 5263.973680131604, 5273.0, 4143.316352801791, 4156.43888075109, 4200.004997626118, 4208.326667733163, 4212.852550160745, 4175.624193822556, 4176.937345939798, 4192.056787222881, 4201.39079833424, 4225.767582782945, 3819.6371344722206, 3938.9803050984706, 4046.453728746611, 4111.5477297497255, 4159.2929202035575, 4107.178564287125, 4108.568600296956, 4131.710780245384, 4138.2758017347, 4144.12973275614, 3644.726645501598, 3672.1186915140443, 3679.797611131387, 3689.94465083024, 3701.130234394942, 4886.560209581119, 5067.189249720064, 5074.898502029939, 5102.642815002914, 5122.564582010534, 4640.466346370177, 4651.302304654295, 4690.226112691402, 4691.554302341272, 4702.835400760957, 5477.287952566181, 5495.917561236584, 5509.3664228613825, 5549.445055494459, 5607.859803504904, 3720.5163328767358, 3948.940765888514, 3959.8020098994934, 3960.1485680578526, 3961.445397644332, 5645.32256129263, 5651.378348381675, 5657.547396208293, 5672.376038635747, 5709.857253568694, 4012.6789856811383, 4019.2765302245875, 4022.336314508132, 4057.533383660858, 4067.572904845008, 3884.2814079395403, 4112.362583799516, 4127.174565086994, 4147.979260103741, 4159.292920203573, 4637.86086417408, 4658.7437690926645, 4661.743604101807, 4682.204025315705, 4683.461401938753, 5540.889182216333, 5558.861028474321, 5560.138178582326, 5585.413531579207, 5632.464915832989, 1329.9069065165345, 1365.0990346371348, 1370.4518192722792, 1381.3438616657008, 1394.9163050217019, 929.953502324881, 940.3182692547848, 956.1872408452759, 956.6842941829179, 958.9136977671903, 5257.5815125042145, 5305.708186049805, 5306.230596563477, 5308.018016666929, 5311.415744268127, 5462.0, 5532.612717109763, 5533.78648540587, 5558.249636299129, 5567.4432556744005, 4958.975205123951, 5197.818076367309, 5234.136367499396, 5265.315508983807, 5332.226827110125, 2886.408286301303, 2912.6067980822318, 2939.911802645924, 2953.4831404504125, 2955.586217929492, 5736.196932429463, 5737.3688894221605, 5771.480566749013, 5777.7977770777825, 5786.161006654096, 5460.672359658433, 5656.85857853557, 5689.630174038674, 5689.776698009865, 5711.457411545896, 5671.262735844314, 5694.886102277931, 5723.771049157987, 5727.3699893011735, 5752.252207213036, 5271.973640131928, 5432.728363581726, 5524.171374293888, 5612.923218409214, 5625.6653488801685, 5459.754311055933, 5502.614816962774, 5512.3660779010925, 5521.171824226398, 5555.8888822224135, 5740.823131257998, 5747.942520574741, 5748.712564371848, 5750.482456578928, 5830.4461076197695, 3786.9017984784105, 3853.7495062820826, 3907.199024199975, 3926.7447615904875, 4065.3495440729343, 5635.577331700057, 5744.770209191585, 5769.769209231584, 5773.24947756789, 5786.594938354443, 5573.916391254215, 5588.748506317144, 5589.972050139886, 5595.496405323541, 5617.157426386075, 5226.477352264743, 5469.933362994337, 5519.31008623922, 5552.944470555242, 5578.135389014709, 5455.481729235717, 5477.1510415885605, 5486.149646804752, 5491.560675145899, 5495.340559132929, 5550.111982082847, 5604.887902242014, 5618.9604923089355, 5633.8309850704545, 5638.85902852432, 5266.526012658879, 5474.370447398441, 5478.739889825283, 5505.779768809202, 5511.173324001432], "value": 4197.283312680509, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.2702046565997, 813.4305985809933, 820.331429884644, 864.0495454999501, 867.9001914779802, 14813.777793333109, 15510.457134000313, 15510.844891551109, 15556.0, 15595.610109747262, 14146.080504767184, 14159.787603185934, 14234.71530569388, 14244.14535127893, 14247.928760356184, 673.3872176319538, 807.5598798654718, 835.9373047021497, 836.7322456813822, 838.8573942429801, 823.1069289820551, 827.6399766101734, 829.7344849648115, 830.7466222802043, 831.0401486283353, 717.0, 884.045231150356, 886.313107341812, 886.7916039730658, 887.2724366019867, 738.6417587470073, 859.256742917375, 875.7416562114191, 877.6138499060424, 880.1110878013188, 642.4795915308615, 643.298804303307, 643.6009674002148, 644.5745807766851, 645.2160624840841, 13404.195748255039, 13412.664683382896, 13439.932800336033, 13457.528986485524, 13463.259520726357, 13117.934410327982, 13215.867841321657, 13232.0, 13417.194968301836, 13510.662233444144, 11162.553497860075, 11163.0, 11386.94306528462, 11912.34482103484, 12073.396330183543, 4568.588827005586, 4637.211674015425, 4647.55848194421, 4726.0784147091335, 4762.928556071662, 52388.738056309856, 52768.208476872875, 52811.471885281426, 53290.733546332405, 54026.0, 10860.728481788019, 11024.283421577571, 11213.831792523119, 11293.040091592307, 11333.773324533628, 18966.051697415074, 19286.0, 19360.70958935617, 19367.709484357747, 19585.216591336186, 8302.460340077876, 8700.477971321614, 8849.51327676984, 8888.911110888937, 8951.820963580692, 4869.14789911764, 4932.630052746058, 4954.529319714694, 4986.80052797884, 5027.547520723152, 3496.8426420811124, 3499.2476617527354, 3587.264610754789, 3589.635938343413, 3594.7483676142942, 652.8661624366994, 653.5229282623711, 664.3423011218858, 668.1047396488729, 669.2404121322237, 773.4276635289957, 778.307306497218, 793.773774474272, 813.8046868751456, 825.5872063968015, 8218.424710270343, 8218.608038284163, 8312.127226641236, 8477.788055298544, 8526.062133165347, 700.068908351901, 707.9433645308363, 718.5078221418345, 757.3054200864385, 775.0621747685251, 4257.446531950888, 4334.154839806208, 4424.31423129409, 4440.800163992628, 4531.252343363308, 59340.0, 60206.69896650489, 60462.39537604656, 60919.69540152271, 61002.69498652567, 48257.5174248253, 49299.0, 53664.73167634215, 54540.72729636406, 55847.16229256564, 50943.0, 51051.48948510467, 51794.74102629463, 51841.48158518367, 51995.0, 9218.9539052307, 9259.490728009754, 9345.719628411158, 9450.810983780424, 9517.23862091045, 33130.0, 33400.498992514644, 33767.83116084404, 33960.15099622529, 34469.310613787085, 42803.785981069894, 45072.54927450683, 45201.32198017098, 45924.311135333664, 46994.29508557306, 11339.829902551302, 11848.585299514476, 12097.637070887888, 12547.560835370718, 12833.230006199658, 13120.278384688552, 13148.276844773247, 13229.80155297652, 13254.734905302033, 13629.522966696119, 12881.742365152457, 12993.0, 13089.280089594782, 13122.60632181036, 13367.197968121944, 12250.877491224974, 12269.141160119063, 12454.813177802522, 12879.35603219854, 12954.67613309637, 12206.206596571194, 12797.104202705721, 12882.420291086735, 13026.47894084226, 13225.867741322463, 10247.231457640557, 10253.12848407864, 10494.737631559272, 10640.0, 10785.029347358775, 10173.28786984904, 10340.276180667583, 10727.249092563447, 10759.0, 11430.542778289107], "value": 6845.83699427983, "unit": "score"}], "name": "dromaeo_css", "value": 7092.395876008644, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 104.0, 110.0, 109.0, 110.0, 108.0, 109.0, 111.0, 110.0, 109.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 126.0, 152.0, 183.0, 127.0, 156.0, 162.0, 127.0, 185.0, 124.0], "value": 150.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 159.0, 159.0, 160.0, 161.0, 162.0, 161.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 123.0, 76.0, 74.0, 77.0, 76.0, 130.0, 76.0], "value": 85.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 124.0, 92.0, 92.0, 92.0, 123.0, 91.0, 91.0, 128.0], "value": 101.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 150.0, 148.0, 149.0, 148.0, 149.0, 149.0, 148.0, 146.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 94.0, 94.0, 94.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 56.0, 58.0, 58.0, 58.0, 75.0, 56.0, 55.0], "value": 58.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 60.0, 60.0, 59.0, 60.0, 60.0, 61.0, 60.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 77.0, 84.0, 78.0, 78.0, 79.0, 85.0, 79.0, 80.0, 83.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 122.0, 121.0, 121.0, 121.0, 122.0, 124.0, 126.0, 227.0, 123.0], "value": 134.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 169.0, 171.0, 170.0, 172.0, 169.0, 167.0, 168.0, 165.0], "value": 169.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 65.0, 66.0, 64.0, 65.0, 63.0, 63.0, 63.0], "value": 64.6, "unit": "score"}], "name": "kraken", "value": 1534.3000000000002, "alertThreshold": 2.0}]} 05:33:23 INFO - Return code: 0 05:33:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:33:23 ERROR - # TBPL SUCCESS # 05:33:23 INFO - Running post-action listener: _resource_record_post_action 05:33:23 INFO - Running post-run listener: _resource_record_post_run 05:33:24 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 537432064; Read time: 16; Write time: 388168 05:33:24 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 251023360; Read time: 0; Write time: 209876 05:33:24 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 286408704; Read time: 16; Write time: 178292 05:33:24 INFO - Running post-run listener: _upload_blobber_files 05:33:24 INFO - Blob upload gear active. 05:33:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:33:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:33:24 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:33:24 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:33:24 INFO - (blobuploader) - INFO - Open directory for files ... 05:33:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:33:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:33:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:25 INFO - (blobuploader) - INFO - Done attempting. 05:33:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:33:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:33:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:26 INFO - (blobuploader) - INFO - Done attempting. 05:33:26 INFO - (blobuploader) - INFO - Iteration through files over. 05:33:26 INFO - Return code: 0 05:33:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:33:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:33:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1b39834f62dc8bdb6fb4a147f20030fb0aeb4af14e06f04c530a20e81b77d162f1f05633148a8e06f4b895c684312bee0be963a56a6e76ca7dda71a4789963f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7acb4aa706342f527eb2ef8f20c9dbc29718bcd57714731d825d803df2b478a219bb3a0e11d2c9fef2478078e322a35f784cbbbe01518ce5943b36bb63e979b3"} 05:33:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:33:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:33:26 INFO - Contents: 05:33:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1b39834f62dc8bdb6fb4a147f20030fb0aeb4af14e06f04c530a20e81b77d162f1f05633148a8e06f4b895c684312bee0be963a56a6e76ca7dda71a4789963f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7acb4aa706342f527eb2ef8f20c9dbc29718bcd57714731d825d803df2b478a219bb3a0e11d2c9fef2478078e322a35f784cbbbe01518ce5943b36bb63e979b3"} 05:33:26 INFO - Running post-run listener: copy_logs_to_upload_dir 05:33:26 INFO - Copying logs to upload dir... 05:33:26 INFO - mkdir: /builds/slave/test/build/upload/logs 05:33:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.005597 ========= master_lag: 5.78 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-05-06 05:33:32.198047) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-05-06 05:33:32.205923) ========= 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=00204705a9daa57ed339a427000001b9-1462535331.938214-342183942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1b39834f62dc8bdb6fb4a147f20030fb0aeb4af14e06f04c530a20e81b77d162f1f05633148a8e06f4b895c684312bee0be963a56a6e76ca7dda71a4789963f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7acb4aa706342f527eb2ef8f20c9dbc29718bcd57714731d825d803df2b478a219bb3a0e11d2c9fef2478078e322a35f784cbbbe01518ce5943b36bb63e979b3"} build_url:https://queue.taskcluster.net/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011225 build_url: 'https://queue.taskcluster.net/v1/task/LggrSyw6SUePGUcpp2cObQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1b39834f62dc8bdb6fb4a147f20030fb0aeb4af14e06f04c530a20e81b77d162f1f05633148a8e06f4b895c684312bee0be963a56a6e76ca7dda71a4789963f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7acb4aa706342f527eb2ef8f20c9dbc29718bcd57714731d825d803df2b478a219bb3a0e11d2c9fef2478078e322a35f784cbbbe01518ce5943b36bb63e979b3"}' ========= master_lag: 2.63 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-05-06 05:33:34.850020) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:33:34.850358) ========= 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=00204705a9daa57ed339a427000001b9-1462535331.938214-342183942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004506 ========= master_lag: 0.69 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:33:35.548367) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 05:33:35.548691) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 05:33:35.560793) ========= ========= Total master_lag: 9.17 =========