builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-035 starttime: 1461686788.5 results: success (0) buildid: 20160426084505 builduid: 46315ec6ce654b83806a3efe3d009eb5 revision: 5ffdd1bafd496a5e9ca0d32f9729333d1b467eb4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 09:06:28.502966) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 09:06:28.503400) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 09:06:28.503686) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 09:06:28.526316) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 09:06:28.526583) ========= 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=ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-26 09:06:29-- 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% 221M=0s 2016-04-26 09:06:29 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087650 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 09:06:28.627112) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 09:06:28.627392) ========= 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=ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019543 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 09:06:28.678672) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 09:06:28.679035) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5ffdd1bafd496a5e9ca0d32f9729333d1b467eb4 --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 5ffdd1bafd496a5e9ca0d32f9729333d1b467eb4 --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=ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-26 09:06:29,680 truncating revision to first 12 chars 2016-04-26 09:06:29,680 Setting DEBUG logging. 2016-04-26 09:06:29,680 attempt 1/10 2016-04-26 09:06:29,680 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5ffdd1bafd49?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 09:06:29,953 unpacking tar archive at: fx-team-5ffdd1bafd49/testing/mozharness/ program finished with exit code 0 elapsedTime=0.561542 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 09:06:29.253202) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 09:06:29.253489) ========= script_repo_revision: 5ffdd1bafd496a5e9ca0d32f9729333d1b467eb4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 09:06:29.253881) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 09:06:29.254174) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 09:06:29.263606) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-04-26 09:06:29.263887) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:06:30 INFO - MultiFileLogger online at 20160426 09:06:30 in /builds/slave/test 09:06:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 09:06:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:06:30 INFO - {'append_to_log': False, 09:06:30 INFO - 'base_work_dir': '/builds/slave/test', 09:06:30 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 09:06:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:06:30 INFO - 'branch': 'Fx-Team-Non-PGO', 09:06:30 INFO - 'buildbot_json_path': 'buildprops.json', 09:06:30 INFO - 'config_files': ('talos/linux_config.py',), 09:06:30 INFO - 'default_actions': ('clobber', 09:06:30 INFO - 'read-buildbot-config', 09:06:30 INFO - 'download-and-extract', 09:06:30 INFO - 'populate-webroot', 09:06:30 INFO - 'create-virtualenv', 09:06:30 INFO - 'install', 09:06:30 INFO - 'run-tests'), 09:06:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:06:30 INFO - 'download_minidump_stackwalk': True, 09:06:30 INFO - 'download_symbols': 'ondemand', 09:06:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:06:30 INFO - 'tooltool.py': '/tools/tooltool.py', 09:06:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:06:30 INFO - '/tools/misc-python/virtualenv.py')}, 09:06:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:06:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:06:30 INFO - 'installer_path': 'installer.exe', 09:06:30 INFO - 'log_level': 'info', 09:06:30 INFO - 'log_name': 'talos', 09:06:30 INFO - 'log_to_console': True, 09:06:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:06:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:06:30 INFO - 'opt_config_files': (), 09:06:30 INFO - 'pip_index': False, 09:06:30 INFO - 'sps_profile': False, 09:06:30 INFO - 'sps_profile_interval': 0, 09:06:30 INFO - 'suite': 'dromaeojs', 09:06:30 INFO - 'system_bits': '32', 09:06:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:06:30 INFO - 'title': 'talos-linux64-ix-035', 09:06:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:06:30 INFO - 'use_talos_json': True, 09:06:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:06:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:06:30 INFO - 'work_dir': 'build'} 09:06:30 INFO - ##### 09:06:30 INFO - ##### Running clobber step. 09:06:30 INFO - ##### 09:06:30 INFO - Running pre-action listener: _resource_record_pre_action 09:06:30 INFO - Running main action method: clobber 09:06:30 INFO - rmtree: /builds/slave/test/build 09:06:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:06:30 INFO - Running post-action listener: _resource_record_post_action 09:06:30 INFO - ##### 09:06:30 INFO - ##### Running read-buildbot-config step. 09:06:30 INFO - ##### 09:06:30 INFO - Running pre-action listener: _resource_record_pre_action 09:06:30 INFO - Running main action method: read_buildbot_config 09:06:30 INFO - Using buildbot properties: 09:06:30 INFO - { 09:06:30 INFO - "project": "", 09:06:30 INFO - "product": "firefox", 09:06:30 INFO - "script_repo_revision": "production", 09:06:30 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:06:30 INFO - "repository": "", 09:06:30 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 09:06:30 INFO - "buildid": "20160426084505", 09:06:30 INFO - "pgo_build": "False", 09:06:30 INFO - "basedir": "/builds/slave/test", 09:06:30 INFO - "buildnumber": 1711, 09:06:30 INFO - "slavename": "talos-linux64-ix-035", 09:06:30 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:06:30 INFO - "platform": "ubuntu64_hw", 09:06:30 INFO - "scheduler": "tests-fx-team-linux64-talos", 09:06:30 INFO - "branch": "fx-team", 09:06:30 INFO - "repo_path": "integration/fx-team", 09:06:30 INFO - "revision": "5ffdd1bafd496a5e9ca0d32f9729333d1b467eb4", 09:06:30 INFO - "stage_platform": "linux64", 09:06:30 INFO - "builduid": "46315ec6ce654b83806a3efe3d009eb5", 09:06:30 INFO - "slavebuilddir": "test" 09:06:30 INFO - } 09:06:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 09:06:30 INFO - Running post-action listener: _resource_record_post_action 09:06:30 INFO - ##### 09:06:30 INFO - ##### Running download-and-extract step. 09:06:30 INFO - ##### 09:06:30 INFO - Running pre-action listener: _resource_record_pre_action 09:06:30 INFO - Running main action method: download_and_extract 09:06:30 INFO - mkdir: /builds/slave/test/build/tests 09:06:30 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')]} 09:06:30 INFO - https://queue.taskcluster.net/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:06:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:06:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:06:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/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 09:06:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/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 09:06:40 INFO - Downloaded 1361 bytes. 09:06:40 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:06:40 INFO - Using the following test package requirements: 09:06:40 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:06:40 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:06:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:06:40 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:06:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:06:40 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:06:40 INFO - u'jsshell-linux-x86_64.zip'], 09:06:40 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:06:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:06:40 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:06:40 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:06:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:06:40 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:06:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:06:40 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:06:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:06:40 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:06:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:06:40 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:06:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:06:40 INFO - https://queue.taskcluster.net/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:06:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:06:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:06:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/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 09:06:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/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 09:06:42 INFO - Downloaded 22291517 bytes. 09:06:42 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'] 09:06:42 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 09:06:43 INFO - Return code: 0 09:06:43 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 09:06:43 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')]} 09:06:43 INFO - https://queue.taskcluster.net/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:06:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:06:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:06:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/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 09:06:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/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 09:06:45 INFO - Downloaded 22291517 bytes. 09:06:45 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'] 09:06:45 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 09:06:46 INFO - Return code: 0 09:06:46 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')]} 09:06:46 INFO - https://queue.taskcluster.net/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:06:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:06:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:06:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/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 09:06:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/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 09:06:48 INFO - Downloaded 10840145 bytes. 09:06:48 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'] 09:06:48 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 09:06:48 INFO - Return code: 0 09:06: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')]} 09:06:48 INFO - https://queue.taskcluster.net/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:06:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:06:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:06:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/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 09:06:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/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 09:06:50 INFO - Downloaded 54813220 bytes. 09:06:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:06:50 INFO - mkdir: /builds/slave/test/properties 09:06:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:06:50 INFO - Writing to file /builds/slave/test/properties/build_url 09:06:50 INFO - Contents: 09:06:50 INFO - build_url:https://queue.taskcluster.net/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:06:51 INFO - Running post-action listener: _resource_record_post_action 09:06:51 INFO - Running post-action listener: set_extra_try_arguments 09:06:51 INFO - ##### 09:06:51 INFO - ##### Running populate-webroot step. 09:06:51 INFO - ##### 09:06:51 INFO - Running pre-action listener: _resource_record_pre_action 09:06:51 INFO - Running main action method: populate_webroot 09:06:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:06:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:06:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:06:51 INFO - '--tppagecycles', 09:06:51 INFO - '7'], 09:06:51 INFO - 'tests': ['tsvgm']}}, 09:06:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:06:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:06:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:06:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:06:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:06:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:06:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:51 INFO - 'talos_options': ['--disable-e10s'], 09:06:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:51 INFO - 'talos_options': ['--disable-e10s'], 09:06:51 INFO - 'tests': ['damp', 'tps']}, 09:06:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:51 INFO - 'tests': ['damp', 'tps']}, 09:06:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:06:51 INFO - 'tests': ['dromaeo_dom']}, 09:06:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:06:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:06:51 INFO - 'tests': ['a11yr', 09:06:51 INFO - 'ts_paint', 09:06:51 INFO - 'tpaint', 09:06:51 INFO - 'sessionrestore', 09:06:51 INFO - 'sessionrestore_no_auto_restore', 09:06:51 INFO - 'tabpaint']}, 09:06:51 INFO - 'other-e10s': {'tests': ['a11yr', 09:06:51 INFO - 'ts_paint', 09:06:51 INFO - 'tpaint', 09:06:51 INFO - 'sessionrestore', 09:06:51 INFO - 'sessionrestore_no_auto_restore', 09:06:51 INFO - 'tabpaint']}, 09:06:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:06:51 INFO - 'ts_paint', 09:06:51 INFO - 'tpaint', 09:06:51 INFO - 'sessionrestore', 09:06:51 INFO - 'sessionrestore_no_auto_restore', 09:06:51 INFO - 'tabpaint']}, 09:06:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:06:51 INFO - 'ts_paint', 09:06:51 INFO - 'tpaint', 09:06:51 INFO - 'sessionrestore', 09:06:51 INFO - 'sessionrestore_no_auto_restore', 09:06:51 INFO - 'tabpaint']}, 09:06:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:06:51 INFO - 'tests': ['a11yr', 09:06:51 INFO - 'ts_paint', 09:06:51 INFO - 'tpaint', 09:06:51 INFO - 'sessionrestore', 09:06:51 INFO - 'sessionrestore_no_auto_restore', 09:06:51 INFO - 'tabpaint']}, 09:06:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:06:51 INFO - 'tests': ['a11yr', 09:06:51 INFO - 'ts_paint', 09:06:51 INFO - 'tpaint', 09:06:51 INFO - 'sessionrestore', 09:06:51 INFO - 'sessionrestore_no_auto_restore', 09:06:51 INFO - 'tabpaint']}, 09:06:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:06:51 INFO - 'tests': ['tsvgx', 09:06:51 INFO - 'tsvgr_opacity', 09:06:51 INFO - 'tart', 09:06:51 INFO - 'tscrollx', 09:06:51 INFO - 'cart']}, 09:06:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:06:51 INFO - 'tsvgr_opacity', 09:06:51 INFO - 'tart', 09:06:51 INFO - 'tscrollx', 09:06:51 INFO - 'cart']}, 09:06:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:51 INFO - 'talos_options': ['--disable-e10s'], 09:06:51 INFO - 'tests': ['tp5o']}, 09:06:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:51 INFO - 'tests': ['tp5o']}, 09:06:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:51 INFO - 'talos_options': ['--disable-e10s', 09:06:51 INFO - '--xperf_path', 09:06:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:51 INFO - 'tests': ['tp5n']}, 09:06:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:51 INFO - 'talos_options': ['--xperf_path', 09:06:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:51 INFO - 'tests': ['tp5n']}}, 09:06:51 INFO - 'talos.zip': {'path': '', 09:06:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:06:51 INFO - Running post-action listener: _resource_record_post_action 09:06:51 INFO - ##### 09:06:51 INFO - ##### Running create-virtualenv step. 09:06:51 INFO - ##### 09:06:51 INFO - Running pre-action listener: _resource_record_pre_action 09:06:51 INFO - Running main action method: create_virtualenv 09:06:51 INFO - Creating virtualenv /builds/slave/test/build/venv 09:06:51 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 09:06:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:06:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:06:51 INFO - Using real prefix '/usr' 09:06:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:06:51 INFO - Installing distribute.............................................................................................................................................................................................done. 09:06:53 INFO - Installing pip.................done. 09:06:53 INFO - Return code: 0 09:06:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:06:53 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')]} 09:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:53 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')]} 09:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:53 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 0x7f3837974f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a9410>, '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 0x14aa310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14aa7a0>, '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': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', '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 09:06:53 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 09:06:53 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 09:06:53 INFO - Using env: {'DISPLAY': ':0', 09:06:53 INFO - 'HOME': '/home/cltbld', 09:06:53 INFO - 'LANG': 'en_US.UTF-8', 09:06:53 INFO - 'LANGUAGE': 'en_US:en', 09:06:53 INFO - 'LOGNAME': 'cltbld', 09:06:53 INFO - 'MAIL': '/var/mail/cltbld', 09:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:53 INFO - 'MOZ_NO_REMOTE': '1', 09:06:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:53 INFO - 'NO_EM_RESTART': '1', 09:06:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:53 INFO - 'PWD': '/builds/slave/test', 09:06:53 INFO - 'SHELL': '/bin/bash', 09:06:53 INFO - 'SHLVL': '1', 09:06:53 INFO - 'TERM': 'linux', 09:06:53 INFO - 'TMOUT': '86400', 09:06:53 INFO - 'USER': 'cltbld', 09:06:53 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', 09:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:53 INFO - '_': '/tools/buildbot/bin/python'} 09:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:53 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 09:06:53 INFO - Cleaning up... 09:06:54 INFO - Return code: 0 09:06:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:06:54 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')]} 09:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:54 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')]} 09:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:54 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 0x7f3837974f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a9410>, '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 0x14aa310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14aa7a0>, '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': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', '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 09:06:54 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 09:06:54 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 09:06:54 INFO - Using env: {'DISPLAY': ':0', 09:06:54 INFO - 'HOME': '/home/cltbld', 09:06:54 INFO - 'LANG': 'en_US.UTF-8', 09:06:54 INFO - 'LANGUAGE': 'en_US:en', 09:06:54 INFO - 'LOGNAME': 'cltbld', 09:06:54 INFO - 'MAIL': '/var/mail/cltbld', 09:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:54 INFO - 'MOZ_NO_REMOTE': '1', 09:06:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:54 INFO - 'NO_EM_RESTART': '1', 09:06:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:54 INFO - 'PWD': '/builds/slave/test', 09:06:54 INFO - 'SHELL': '/bin/bash', 09:06:54 INFO - 'SHLVL': '1', 09:06:54 INFO - 'TERM': 'linux', 09:06:54 INFO - 'TMOUT': '86400', 09:06:54 INFO - 'USER': 'cltbld', 09:06:54 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', 09:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:54 INFO - '_': '/tools/buildbot/bin/python'} 09:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:54 INFO - Downloading/unpacking psutil>=0.7.1 09:06:54 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 09:06:54 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 09:06:54 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 09:06:54 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 09:06:54 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 09:06:54 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 09:06:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:06:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:56 INFO - Installing collected packages: psutil 09:06:56 INFO - Running setup.py install for psutil 09:06:56 INFO - building 'psutil._psutil_linux' extension 09:06:56 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 09:06:56 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 09:06:56 INFO - building 'psutil._psutil_posix' extension 09:06:56 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 09:06:56 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 09:06:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:56 INFO - Successfully installed psutil 09:06:56 INFO - Cleaning up... 09:06:56 INFO - Return code: 0 09:06:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:06:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:56 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 0x7f3837974f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a9410>, '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 0x14aa310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14aa7a0>, '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': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', '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 09:06:56 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 09:06:56 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 09:06:56 INFO - Using env: {'DISPLAY': ':0', 09:06:56 INFO - 'HOME': '/home/cltbld', 09:06:56 INFO - 'LANG': 'en_US.UTF-8', 09:06:56 INFO - 'LANGUAGE': 'en_US:en', 09:06:56 INFO - 'LOGNAME': 'cltbld', 09:06:56 INFO - 'MAIL': '/var/mail/cltbld', 09:06:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:56 INFO - 'MOZ_NO_REMOTE': '1', 09:06:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:56 INFO - 'NO_EM_RESTART': '1', 09:06:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:56 INFO - 'PWD': '/builds/slave/test', 09:06:56 INFO - 'SHELL': '/bin/bash', 09:06:56 INFO - 'SHLVL': '1', 09:06:56 INFO - 'TERM': 'linux', 09:06:56 INFO - 'TMOUT': '86400', 09:06:56 INFO - 'USER': 'cltbld', 09:06:56 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', 09:06:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:56 INFO - '_': '/tools/buildbot/bin/python'} 09:06:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:06:56 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 09:06:56 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 09:06:56 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 09:06:56 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 09:06:56 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 09:06:56 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 09:06:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:06:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:06:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:06:58 INFO - Installing collected packages: mozsystemmonitor 09:06:58 INFO - Running setup.py install for mozsystemmonitor 09:06:58 INFO - Successfully installed mozsystemmonitor 09:06:58 INFO - Cleaning up... 09:06:59 INFO - Return code: 0 09:06:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:06: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')]} 09:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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')]} 09:06:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:59 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 0x7f3837974f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a9410>, '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 0x14aa310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14aa7a0>, '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': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', '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 09:06:59 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 09:06:59 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 09:06:59 INFO - Using env: {'DISPLAY': ':0', 09:06:59 INFO - 'HOME': '/home/cltbld', 09:06:59 INFO - 'LANG': 'en_US.UTF-8', 09:06:59 INFO - 'LANGUAGE': 'en_US:en', 09:06:59 INFO - 'LOGNAME': 'cltbld', 09:06:59 INFO - 'MAIL': '/var/mail/cltbld', 09:06:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:59 INFO - 'MOZ_NO_REMOTE': '1', 09:06:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:59 INFO - 'NO_EM_RESTART': '1', 09:06:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:59 INFO - 'PWD': '/builds/slave/test', 09:06:59 INFO - 'SHELL': '/bin/bash', 09:06:59 INFO - 'SHLVL': '1', 09:06:59 INFO - 'TERM': 'linux', 09:06:59 INFO - 'TMOUT': '86400', 09:06:59 INFO - 'USER': 'cltbld', 09:06:59 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', 09:06:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:59 INFO - '_': '/tools/buildbot/bin/python'} 09:06:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:59 INFO - Downloading/unpacking blobuploader==1.2.4 09:06:59 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 09:06:59 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 09:06:59 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 09:06:59 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 09:06:59 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 09:06:59 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 09:07:01 INFO - Downloading blobuploader-1.2.4.tar.gz 09:07:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:07:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:07:01 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 09:07:01 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 09:07:01 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 09:07:01 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 09:07:01 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 09:07:01 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 09:07:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:07:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:07:01 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 09:07:01 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 09:07:01 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 09:07:01 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 09:07:01 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 09:07:01 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 09:07:01 INFO - Downloading docopt-0.6.1.tar.gz 09:07:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:07:01 INFO - Installing collected packages: blobuploader, requests, docopt 09:07:01 INFO - Running setup.py install for blobuploader 09:07:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:07:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:07:01 INFO - Running setup.py install for requests 09:07:02 INFO - Running setup.py install for docopt 09:07:02 INFO - Successfully installed blobuploader requests docopt 09:07:02 INFO - Cleaning up... 09:07:02 INFO - Return code: 0 09:07:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07: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')]} 09:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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')]} 09:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:02 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 0x7f3837974f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a9410>, '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 0x14aa310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14aa7a0>, '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': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', '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 09:07:02 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 09:07:02 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 09:07:02 INFO - Using env: {'DISPLAY': ':0', 09:07:02 INFO - 'HOME': '/home/cltbld', 09:07:02 INFO - 'LANG': 'en_US.UTF-8', 09:07:02 INFO - 'LANGUAGE': 'en_US:en', 09:07:02 INFO - 'LOGNAME': 'cltbld', 09:07:02 INFO - 'MAIL': '/var/mail/cltbld', 09:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:02 INFO - 'MOZ_NO_REMOTE': '1', 09:07:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:02 INFO - 'NO_EM_RESTART': '1', 09:07:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:02 INFO - 'PWD': '/builds/slave/test', 09:07:02 INFO - 'SHELL': '/bin/bash', 09:07:02 INFO - 'SHLVL': '1', 09:07:02 INFO - 'TERM': 'linux', 09:07:02 INFO - 'TMOUT': '86400', 09:07:02 INFO - 'USER': 'cltbld', 09:07:02 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', 09:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:02 INFO - '_': '/tools/buildbot/bin/python'} 09:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:02 INFO - Running setup.py (path:/tmp/pip-BVz8fv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:02 INFO - Running setup.py (path:/tmp/pip-Lfbie_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:02 INFO - Running setup.py (path:/tmp/pip-Y0DTS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:02 INFO - Running setup.py (path:/tmp/pip-uSeTwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:02 INFO - Running setup.py (path:/tmp/pip-zWzXyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:02 INFO - Running setup.py (path:/tmp/pip-ghvq0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:03 INFO - Running setup.py (path:/tmp/pip-do_Tby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:03 INFO - Running setup.py (path:/tmp/pip-jfFCYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:03 INFO - Running setup.py (path:/tmp/pip-S7KmO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:03 INFO - Running setup.py (path:/tmp/pip-peprpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:03 INFO - Running setup.py (path:/tmp/pip-vp9mjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:03 INFO - Running setup.py (path:/tmp/pip-zreBsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:03 INFO - Running setup.py (path:/tmp/pip-EvG8yH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:03 INFO - Running setup.py (path:/tmp/pip-7mRpIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:03 INFO - Running setup.py (path:/tmp/pip-FofaTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:03 INFO - Running setup.py (path:/tmp/pip-k8QKl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:03 INFO - Running setup.py (path:/tmp/pip-R2mGip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:07:03 INFO - Running setup.py install for manifestparser 09:07:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:07:03 INFO - Running setup.py install for mozcrash 09:07:04 INFO - Running setup.py install for mozdebug 09:07:04 INFO - Running setup.py install for mozdevice 09:07:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:07:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:07:04 INFO - Running setup.py install for mozfile 09:07:04 INFO - Running setup.py install for mozhttpd 09:07:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:07:04 INFO - Running setup.py install for mozinfo 09:07:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:07:04 INFO - Running setup.py install for mozInstall 09:07:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:07:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:07:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:07:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:07:04 INFO - Running setup.py install for mozleak 09:07:04 INFO - Running setup.py install for mozlog 09:07:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:07:04 INFO - Running setup.py install for moznetwork 09:07:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:07:04 INFO - Running setup.py install for mozprocess 09:07:05 INFO - Running setup.py install for mozprofile 09:07:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:07:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:07:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:07:05 INFO - Running setup.py install for mozrunner 09:07:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:07:05 INFO - Running setup.py install for mozscreenshot 09:07:05 INFO - Running setup.py install for moztest 09:07:05 INFO - Running setup.py install for mozversion 09:07:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:07:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:07:05 INFO - Cleaning up... 09:07:05 INFO - Return code: 0 09:07:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:05 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 0x7f3837974f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a9410>, '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 0x14aa310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14aa7a0>, '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': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', '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 09:07:05 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 09:07:05 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 09:07:05 INFO - Using env: {'DISPLAY': ':0', 09:07:05 INFO - 'HOME': '/home/cltbld', 09:07:05 INFO - 'LANG': 'en_US.UTF-8', 09:07:05 INFO - 'LANGUAGE': 'en_US:en', 09:07:05 INFO - 'LOGNAME': 'cltbld', 09:07:05 INFO - 'MAIL': '/var/mail/cltbld', 09:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:05 INFO - 'MOZ_NO_REMOTE': '1', 09:07:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:05 INFO - 'NO_EM_RESTART': '1', 09:07:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:05 INFO - 'PWD': '/builds/slave/test', 09:07:05 INFO - 'SHELL': '/bin/bash', 09:07:05 INFO - 'SHLVL': '1', 09:07:05 INFO - 'TERM': 'linux', 09:07:05 INFO - 'TMOUT': '86400', 09:07:05 INFO - 'USER': 'cltbld', 09:07:05 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', 09:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:05 INFO - '_': '/tools/buildbot/bin/python'} 09:07:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:05 INFO - Running setup.py (path:/tmp/pip-_300qf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:05 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)) 09:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:05 INFO - Running setup.py (path:/tmp/pip-WK1zgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:06 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)) 09:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:06 INFO - Running setup.py (path:/tmp/pip-NXuama-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:06 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)) 09:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:06 INFO - Running setup.py (path:/tmp/pip-VWWTPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:06 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)) 09:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:06 INFO - Running setup.py (path:/tmp/pip-4eASDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:06 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)) 09:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:06 INFO - Running setup.py (path:/tmp/pip-FIsurM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:06 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)) 09:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:06 INFO - Running setup.py (path:/tmp/pip-o5AecO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:06 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)) 09:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:06 INFO - Running setup.py (path:/tmp/pip-vyXOYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:06 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)) 09:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:06 INFO - Running setup.py (path:/tmp/pip-PMGZ2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:06 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)) 09:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:06 INFO - Running setup.py (path:/tmp/pip-a54S9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:06 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)) 09:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:06 INFO - Running setup.py (path:/tmp/pip-Ut4DuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:06 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)) 09:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:06 INFO - Running setup.py (path:/tmp/pip-2WmPD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:06 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)) 09:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:06 INFO - Running setup.py (path:/tmp/pip-kZIwEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:06 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)) 09:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:06 INFO - Running setup.py (path:/tmp/pip-WtAEOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:06 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)) 09:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:06 INFO - Running setup.py (path:/tmp/pip-HPEUP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:06 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)) 09:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:06 INFO - Running setup.py (path:/tmp/pip-Rszyx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:07 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)) 09:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:07 INFO - Running setup.py (path:/tmp/pip-I7LtAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:07 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)) 09:07:07 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)) 09:07:07 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)) 09:07:07 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)) 09:07:07 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)) 09:07:07 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)) 09:07:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:08 INFO - Downloading blessings-1.6.tar.gz 09:07:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:07:09 INFO - Installing collected packages: blessings 09:07:09 INFO - Running setup.py install for blessings 09:07:09 INFO - Successfully installed blessings 09:07:09 INFO - Cleaning up... 09:07:09 INFO - Return code: 0 09:07:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:07:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:07:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:07:09 INFO - Reading from file tmpfile_stdout 09:07:09 INFO - Current package versions: 09:07:09 INFO - argparse == 1.2.1 09:07:09 INFO - blessings == 1.6 09:07:09 INFO - blobuploader == 1.2.4 09:07:09 INFO - docopt == 0.6.1 09:07:09 INFO - manifestparser == 1.1 09:07:09 INFO - mozInstall == 1.12 09:07:09 INFO - mozcrash == 0.17 09:07:09 INFO - mozdebug == 0.1 09:07:09 INFO - mozdevice == 0.48 09:07:09 INFO - mozfile == 1.2 09:07:09 INFO - mozhttpd == 0.7 09:07:09 INFO - mozinfo == 0.9 09:07:09 INFO - mozleak == 0.1 09:07:09 INFO - mozlog == 3.1 09:07:09 INFO - moznetwork == 0.27 09:07:09 INFO - mozprocess == 0.22 09:07:09 INFO - mozprofile == 0.28 09:07:09 INFO - mozrunner == 6.11 09:07:09 INFO - mozscreenshot == 0.1 09:07:09 INFO - mozsystemmonitor == 0.0 09:07:09 INFO - moztest == 0.7 09:07:09 INFO - mozversion == 1.4 09:07:09 INFO - psutil == 3.1.1 09:07:09 INFO - requests == 1.2.3 09:07:09 INFO - wsgiref == 0.1.2 09:07:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:09 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')]} 09:07:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:09 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')]} 09:07:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:09 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 0x7f3837974f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a9410>, '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 0x14aa310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14aa7a0>, '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': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', '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 09:07:09 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 09:07:09 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 09:07:09 INFO - Using env: {'DISPLAY': ':0', 09:07:09 INFO - 'HOME': '/home/cltbld', 09:07:09 INFO - 'LANG': 'en_US.UTF-8', 09:07:09 INFO - 'LANGUAGE': 'en_US:en', 09:07:09 INFO - 'LOGNAME': 'cltbld', 09:07:09 INFO - 'MAIL': '/var/mail/cltbld', 09:07:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:09 INFO - 'MOZ_NO_REMOTE': '1', 09:07:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:09 INFO - 'NO_EM_RESTART': '1', 09:07:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:09 INFO - 'PWD': '/builds/slave/test', 09:07:09 INFO - 'SHELL': '/bin/bash', 09:07:09 INFO - 'SHLVL': '1', 09:07:09 INFO - 'TERM': 'linux', 09:07:09 INFO - 'TMOUT': '86400', 09:07:09 INFO - 'USER': 'cltbld', 09:07:09 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', 09:07:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:09 INFO - '_': '/tools/buildbot/bin/python'} 09:07:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:09 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)) 09:07:09 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)) 09:07:09 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)) 09:07:09 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)) 09:07:09 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)) 09:07:09 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)) 09:07:09 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)) 09:07:09 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)) 09:07:09 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)) 09:07:09 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)) 09:07:09 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)) 09:07:09 INFO - Cleaning up... 09:07:09 INFO - Return code: 0 09:07:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:07:09 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')]} 09:07:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:09 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')]} 09:07:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:09 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 0x7f3837974f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a9410>, '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 0x14aa310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14aa7a0>, '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': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', '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 09:07:09 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 09:07:09 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 09:07:09 INFO - Using env: {'DISPLAY': ':0', 09:07:09 INFO - 'HOME': '/home/cltbld', 09:07:09 INFO - 'LANG': 'en_US.UTF-8', 09:07:09 INFO - 'LANGUAGE': 'en_US:en', 09:07:09 INFO - 'LOGNAME': 'cltbld', 09:07:09 INFO - 'MAIL': '/var/mail/cltbld', 09:07:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:09 INFO - 'MOZ_NO_REMOTE': '1', 09:07:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:09 INFO - 'NO_EM_RESTART': '1', 09:07:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:09 INFO - 'PWD': '/builds/slave/test', 09:07:09 INFO - 'SHELL': '/bin/bash', 09:07:09 INFO - 'SHLVL': '1', 09:07:09 INFO - 'TERM': 'linux', 09:07:09 INFO - 'TMOUT': '86400', 09:07:09 INFO - 'USER': 'cltbld', 09:07:09 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', 09:07:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:09 INFO - '_': '/tools/buildbot/bin/python'} 09:07:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:09 INFO - Downloading/unpacking jsonschema 09:07: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 09:07: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 09:07: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 09:07: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 09:07: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 09:07: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 09:07:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:07:11 INFO - Downloading/unpacking functools32 (from jsonschema) 09:07: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 09:07: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 09:07: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 09:07: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 09:07: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 09:07: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 09:07:11 INFO - Downloading functools32-3.2.3-2.tar.gz 09:07:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:07:11 INFO - warning: no files found matching '*.txt' 09:07:11 INFO - no previously-included directories found matching 'build' 09:07:11 INFO - no previously-included directories found matching 'dist' 09:07:11 INFO - no previously-included directories found matching '.git*' 09:07:12 INFO - Installing collected packages: jsonschema, functools32 09:07:12 INFO - Running setup.py install for functools32 09:07:12 INFO - warning: no files found matching '*.txt' 09:07:12 INFO - no previously-included directories found matching 'build' 09:07:12 INFO - no previously-included directories found matching 'dist' 09:07:12 INFO - no previously-included directories found matching '.git*' 09:07:12 INFO - Successfully installed jsonschema functools32 09:07:12 INFO - Cleaning up... 09:07:12 INFO - Return code: 0 09:07:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:07:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:12 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 0x7f3837974f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x134f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a9410>, '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 0x14aa310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14aa7a0>, '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': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', '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 09:07:12 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 09:07:12 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 09:07:12 INFO - Using env: {'DISPLAY': ':0', 09:07:12 INFO - 'HOME': '/home/cltbld', 09:07:12 INFO - 'LANG': 'en_US.UTF-8', 09:07:12 INFO - 'LANGUAGE': 'en_US:en', 09:07:12 INFO - 'LOGNAME': 'cltbld', 09:07:12 INFO - 'MAIL': '/var/mail/cltbld', 09:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:12 INFO - 'MOZ_NO_REMOTE': '1', 09:07:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:12 INFO - 'NO_EM_RESTART': '1', 09:07:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:12 INFO - 'PWD': '/builds/slave/test', 09:07:12 INFO - 'SHELL': '/bin/bash', 09:07:12 INFO - 'SHLVL': '1', 09:07:12 INFO - 'TERM': 'linux', 09:07:12 INFO - 'TMOUT': '86400', 09:07:12 INFO - 'USER': 'cltbld', 09:07:12 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', 09:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:12 INFO - '_': '/tools/buildbot/bin/python'} 09:07:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:12 INFO - Downloading/unpacking flake8 09:07:12 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 09:07:12 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 09:07:12 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 09:07:12 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 09:07:12 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 09:07:12 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 09:07:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:07:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:07: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 09:07: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 09:07: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 09:07: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 09:07: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 09:07: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 09:07:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:07:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:07: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 09:07: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 09:07: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 09:07: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 09:07: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 09:07: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 09:07:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:07: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 09:07: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 09:07: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 09:07: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 09:07: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 09:07: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 09:07:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:07:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:07:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:07:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:07:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:07:14 INFO - no previously-included directories found matching 'docs/_build' 09:07:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:07:15 INFO - Running setup.py install for pep8 09:07:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:07:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:07:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:07:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:07:15 INFO - no previously-included directories found matching 'docs/_build' 09:07:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:07:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:07:15 INFO - Cleaning up... 09:07:15 INFO - Return code: 0 09:07:15 INFO - Running post-action listener: _resource_record_post_action 09:07:15 INFO - Running post-action listener: _start_resource_monitoring 09:07:15 INFO - Starting resource monitoring. 09:07:15 INFO - ##### 09:07:15 INFO - ##### Running install step. 09:07:15 INFO - ##### 09:07:15 INFO - Running pre-action listener: _resource_record_pre_action 09:07:15 INFO - Running main action method: install 09:07:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:07:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:07:15 INFO - Reading from file tmpfile_stdout 09:07:15 INFO - Detecting whether we're running mozinstall >=1.0... 09:07:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:07:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:07:15 INFO - Reading from file tmpfile_stdout 09:07:15 INFO - Output received: 09:07:15 INFO - Usage: mozinstall [options] installer 09:07:15 INFO - Options: 09:07:15 INFO - -h, --help show this help message and exit 09:07:15 INFO - -d DEST, --destination=DEST 09:07:15 INFO - Directory to install application into. [default: 09:07:15 INFO - "/builds/slave/test"] 09:07:15 INFO - --app=APP Application being installed. [default: firefox] 09:07:15 INFO - mkdir: /builds/slave/test/build/application 09:07:15 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'] 09:07:15 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 09:07:31 INFO - Reading from file tmpfile_stdout 09:07:31 INFO - Output received: 09:07:31 INFO - /builds/slave/test/build/application/firefox/firefox 09:07:31 INFO - Running post-action listener: _resource_record_post_action 09:07:31 INFO - ##### 09:07:31 INFO - ##### Running run-tests step. 09:07:31 INFO - ##### 09:07:31 INFO - Running pre-action listener: _resource_record_pre_action 09:07:31 INFO - Running main action method: run_tests 09:07:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:07:31 INFO - Python 2.7.3 09:07:31 INFO - Return code: 0 09:07:31 INFO - grabbing minidump binary from tooltool 09:07:31 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')]} 09:07:31 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 0x14a9410>, '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 0x14aa310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14aa7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:07:31 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 09:07:31 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 09:07:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:07:31 INFO - Return code: 0 09:07:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:07:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:07:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:07:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:07:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:07:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:07:32 INFO - Return code: 0 09:07:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 09:07:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 09:07:32 INFO - Using env: {'DISPLAY': ':0', 09:07:32 INFO - 'HOME': '/home/cltbld', 09:07:32 INFO - 'LANG': 'en_US.UTF-8', 09:07:32 INFO - 'LANGUAGE': 'en_US:en', 09:07:32 INFO - 'LOGNAME': 'cltbld', 09:07:32 INFO - 'MAIL': '/var/mail/cltbld', 09:07:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:07:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:32 INFO - 'MOZ_NO_REMOTE': '1', 09:07:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:07:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:32 INFO - 'NO_EM_RESTART': '1', 09:07:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:32 INFO - 'PWD': '/builds/slave/test', 09:07:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:07:32 INFO - 'SHELL': '/bin/bash', 09:07:32 INFO - 'SHLVL': '1', 09:07:32 INFO - 'TERM': 'linux', 09:07:32 INFO - 'TMOUT': '86400', 09:07:32 INFO - 'USER': 'cltbld', 09:07:32 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727', 09:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:32 INFO - '_': '/tools/buildbot/bin/python'} 09:07:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 09:07:32 INFO - mozversion application_buildid: 20160426084505 09:07:32 INFO - mozversion application_changeset: 5ffdd1bafd496a5e9ca0d32f9729333d1b467eb4 09:07:32 INFO - mozversion application_display_name: Nightly 09:07:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:07:32 INFO - mozversion application_name: Firefox 09:07:32 INFO - mozversion application_remotingname: firefox 09:07:32 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:07:32 INFO - mozversion application_vendor: Mozilla 09:07:32 INFO - mozversion application_version: 49.0a1 09:07:32 INFO - mozversion platform_buildid: 20160426084505 09:07:32 INFO - mozversion platform_changeset: 5ffdd1bafd496a5e9ca0d32f9729333d1b467eb4 09:07:32 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:07:32 INFO - mozversion platform_version: 49.0a1 09:07:32 INFO - using testdate: 1461686852 09:07:32 INFO - actual date: 1461686852 09:07:32 INFO - starting webserver on 'localhost:45122' 09:07:32 INFO - SUITE-START | Running 2 tests 09:07:32 INFO - TEST-START | dromaeo_css 09:07:32 INFO - operating with platform_type : linux_ 09:07:32 INFO - Initialising browser for dromaeo_css test... 09:07:32 INFO - TEST-INFO | started process 7890 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCxn9ic/profile http://localhost:45122/getInfo.html) 09:07:36 INFO - PROCESS | 7890 | __metrics Screen width/height:1600/1200 09:07:36 INFO - PROCESS | 7890 | colorDepth:24 09:07:36 INFO - PROCESS | 7890 | Browser inner width/height: 1024/697 09:07:36 INFO - PROCESS | 7890 | __metrics 09:07:37 ERROR - PROCESS | 7890 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:07:38 INFO - TEST-INFO | 7890: exit 0 09:07:38 INFO - Browser initialized. 09:07:38 INFO - Running cycle 1/1 for dromaeo_css test... 09:07:38 INFO - TEST-INFO | started process 7970 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCxn9ic/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:07:50 INFO - PROCESS | 7970 | RSS: Main: 155742208 09:07:50 INFO - PROCESS | 7970 | 09:08:41 INFO - PROCESS | 7970 | 1461686921496 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] 09:10:52 INFO - PROCESS | 7970 | Cycle 1(1): loaded http://localhost:45122/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45122/tests/dromaeo/cssquery-ext.html) 09:10:53 INFO - PROCESS | 7970 | RSS: Main: 231493632 09:10:53 INFO - PROCESS | 7970 | 09:13:55 INFO - PROCESS | 7970 | Cycle 1(1): loaded http://localhost:45122/tests/dromaeo/cssquery-ext.html (next: http://localhost:45122/tests/dromaeo/cssquery-jquery.html) 09:13:56 INFO - PROCESS | 7970 | RSS: Main: 243486720 09:13:56 INFO - PROCESS | 7970 | 09:16:58 INFO - PROCESS | 7970 | Cycle 1(1): loaded http://localhost:45122/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45122/tests/dromaeo/cssquery-mootools.html) 09:16:58 INFO - PROCESS | 7970 | RSS: Main: 243802112 09:16:58 INFO - PROCESS | 7970 | 09:20:00 INFO - PROCESS | 7970 | Cycle 1(1): loaded http://localhost:45122/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45122/tests/dromaeo/cssquery-prototype.html) 09:20:01 INFO - PROCESS | 7970 | RSS: Main: 237445120 09:20:01 INFO - PROCESS | 7970 | 09:23:04 INFO - PROCESS | 7970 | Cycle 1(1): loaded http://localhost:45122/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45122/tests/dromaeo/cssquery-yui.html) 09:23:05 INFO - PROCESS | 7970 | RSS: Main: 213512192 09:23:05 INFO - PROCESS | 7970 | 09:26:07 INFO - PROCESS | 7970 | Cycle 1(1): loaded http://localhost:45122/tests/dromaeo/cssquery-yui.html (next: http://localhost:45122/tests/dromaeo/cssquery-dojo.html) 09:26:08 INFO - PROCESS | 7970 | RSS: Main: 300572672 09:26:08 INFO - PROCESS | 7970 | 09:26:08 INFO - PROCESS | 7970 | __start_tp_report 09:26:08 INFO - PROCESS | 7970 | _x_x_mozilla_page_load 09:26:08 INFO - PROCESS | 7970 | _x_x_mozilla_page_load_details 09:26:08 INFO - PROCESS | 7970 | |i|pagename|runs| 09:26:08 INFO - PROCESS | 7970 | |0;dojo.html;2552.515022145793;2608.165387076135;2617.397998460355;2626.3040294321995;2648.7086420493747;4408.382826404298;4479.440069991251;4489.057297967427;4506.053728716965;4506.324051392293;4471.396361491198;4501.594856462926;4531.342955271483;4532.773361331937;4547.431571053618;4140.047789008529;4143.523494798107;4171.4994200695955;4187.937180942288;4282.764447955361;4232.15356928614;4240.469941257343;4266.722663026909;4284.507281662618;4290.313549832027;4064.878053658395;4130.793460326971;4152.1695660867645;4194.958050419517;4207.621314081747;4166.062635906927;4208.032152604887;4209.410682504452;4215.957840421618;4245.511766146902;4156.06488540076;4175.164967006581;4181.686373521968;4196.391523229115;4222.641075508585;4246.596573325529;4395.406620106309;4411;4464.330350447426;4472.08322293929;4848.5151484851585;4848.757562121879;4853.3690620219495;4862.367892173995;4866.975665121652;4841.394825646794;4868.561829435368;4869.074875773592;4878.780454879539;4882.926756098661;4415.646748260164;4521.502634710179;4590.173768721629;4606.838760643428;4615.907681846412;4894.045661096124;4917.0903382874285;4930.260460930817;4936.284238785356;4973.204287314013;4735.21868891629;4743.66794324394;4744.7627618618935;4782.95217047832;4787.0904528139545;4669.322948172496;4797.736124513115;4853.296272040605;4874.268859671078;4874.634402419832;4772.379590653193;4773.164696178224;4790.377250957423;4793.568578827852;4835.347228124158;4671.416072990876;4760.047990401933;4766.571008609172;4779.689320194177;4827.927581086286;4110.321796903538;4147.97926010368;4162.105147393325;4187.581241875788;4201.70588058833;3659.7438179327723;3666.7249956253386;3675.687566556819;3696.353138200804;3699.7965111918566;4114.382842573638;4203.97898010508;4215.957840421618;4228.027553662613;4254.829806807755;4792.760361981887;4804.807807687724;4826.517348265215;4855.8057677693205;4862.756862156949;4161;4162.542120366757;4177.081042170717;4196.1607678463815;4221.915561688749;4325.329573916048;4359.912801743947;4391.121775644884;4400.317950717644;4433.756143412079;4543.45478542577;4583.197940360424;4591.724496530219;4605.40129783126;4639.118567472169;4807.879803004953;4818.036392721539;4819.590334821439;4820.758962051813;4873.731944743073;4531.320301954602;4544.409226800495;4563.247064234364;4572.954270457386;4608.49306576283;4769.880752981203;4777.689450185727;4784.641151913551;4795.832145875017;4808.591269742111;4806.903861922812;4822.590079843252;4826.131296366688;4841.298011788201;4852.611791056777;4615.723056616614;4676.953230467788;4685.437747470325;4752.144613969519;4771.260454629538;4647.65142614312;4658.277966915133;4667.626589872734;4680.836170734008;4727.432708075053;4611.331356953156;4612.423447069116;4622.375979242759;4623.959609087929;4662.906741865076;4599.264117741145;4686.859394218179;4740.075685241345;4768.642351823558;4787.712737235777;4613.700109492872;4617.2612382018715;4625.953740462553;4626.005408837116;4682.063587282625 09:26:08 INFO - PROCESS | 7970 | |1;ext.html;575.9488932697825;594.3402822866618;605.1074664869318;613.9938600613993;616.9938300616992;30153.24616884579;30212;30336;30539.541906871415;31747.68252317476;30677.38645227094;31032.37935241294;31262.843685781598;31413;31590.36819263619;7287.198408175096;7475.364594009516;7475.588842613654;7478.102627684685;7481.6633251503545;7350.08123984502;7457.5152615080115;7460.0674915635545;7466.552006879578;7472.887906681377;7537.811554711148;7567.356774674132;7570.2051284615145;7579.734709285176;7621.275978782007;7524.962375188116;7587.620618969085;7588.354989825872;7591.24087591242;7597.544147351176;7444.9627751861435;7529.397648188132;7542.35889949355;7547.132079810837;7550.622468876534;30570.84714576435;30975;31017;31037;31086;3072.846357682107;3173.7143657070974;3222.9033129006166;3339.666033396665;3476.66971637694;24798.628020579705;25170.49659006828;25522.489550209077;25717.485650287075;26270.86864565684;3353.393397571907;3411.795292282471;3417.487376893461;3486.9825650871585;3529.3117842020815;196488;196590.1534430954;196592;196950;197870;40485.39271910924;40621.79689101536;40808;40913;40920.38619420711;36162;41995;42147.57852421498;42149.1570168601;42228.78885605552;12409.875901241052;12817.743645127046;12819.038572106943;12821.935890320488;12830.871691283155;9232.21526170283;9319.720408387759;9329.30030247734;9354.579043942902;9361.157495825408;11330.150238731963;12018.4591693374;12302.261864288172;12311.891929726367;12448.937755311166;5147.459516750762;5154.845354639367;5180.663256888365;5240.973795131;5252.737363131828;783.6630248992874;822.7696245051395;842.4860834890711;868.8349213649386;929.9442033477877;23060.884695576413;23247.18634847772;23546.7645323548;23806.404839878796;23847.761522384902;893.3032234856822;899.7480705402497;913.7989642278689;954.7660823098386;975.11264749078;12109.757804843854;12343.536499007552;12381.566645167375;12497.125201235825;12738.235705857678;261653;265217;267190;268227;268337.21639485046;38892;38972.22055558873;38985.22029559393;39069.21861562753;39082.609173908466;1073.2272763610188;1093.7593729379523;1096.7203363142348;1112.2270022334562;1119.0488085127577;22387.552248955257;23296.53406931818;23376.064957401515;23681;24111;33175.83412083021;33273;33309.50035749417;33332.666673332955;33418.83290583531;203269;203550;204264;205174;205510;19563.51091222674;20155.596888061864;20168.092435840128;21792.78207217971;22143;28736.856315719124;29269.560956585243;30722.692773071984;31052.844735777082;34906.825465873524;29894.25264368408;30320.54519182258;30572.847135765067;30858.382832343676;30920.226994325323;29450.558241626823;29834.701652983193;29867.253318667208;30046.84976575103;30138.84930575333;29110;29621.2594685126;29815.254618634706;29816;29824.25439364033;26795;27399.315017123932;27636;27722.584161237195;27738.583921240795;21828.345149645535;22283.44291392728;22293.77706222982;22340.77659223387;22533.774662253167 09:26:08 INFO - PROCESS | 7970 | |2;jquery.html;344.70011090351414;345.5145520543636;351.5307065068134;354.795992304425;372.07539264926635;14360.138391696506;14681.564915223773;15345;15739;15852.841471585281;14199.290035498236;14561.344739486722;15010.924945375258;15029.023113497615;15039.398424063025;4331.523532411432;4828.87927801806;4953.257011448276;5006.54941055305;5009.899802003958;4785.353977213083;4911.091448082098;4926.605871530287;4929.580985616227;4945.9258111128365;4819.325294458778;4852.9757351213375;4913.385826771653;4915.729634870081;4978.103941290567;4949.653524253304;4956.925646115311;4968.925466118011;4976.676516026447;4985.651004429691;4410.360497727812;4430.490493593245;4431.844885429026;4860.538248866352;5127.0002349541855;11125.332480051142;11199.496022678917;11229.270097443721;11320.094392448584;11323.037541808957;1800.8829426087264;1834.257125864027;1868.8785228960073;2004.4788355027797;2330.52224294021;8599.355048371397;8938.597763100612;8975.281977441855;9345.532723363805;11975.580854670132;1763.1272520102632;1842.4380563927996;1875.681134207188;1914.119505027689;1945.3774792066545;60266;61961.38038619646;62045.689771551304;62999;63407.36592634061;9060.093990600888;9131.315151363542;9272.814543709223;9298.628054877729;9459.763505912408;12515.249085054926;12618.116731828868;12639.494420223273;12644.557440489538;12645.683857903627;6437.613743175424;6507.219133703986;6685.431738302299;6843.452523798084;6854.246032936373;6388.425041746265;6407.455366293917;6490.188726409199;6526.477881769447;6533.477321814338;3979.5025621797276;4057.655099316532;4062.6749860011128;4106.7125301228625;4118.938215926763;1683.924223409925;1722.7674263974454;1731.8527925126252;1745.677049745801;1757.2004737844507;756.6897571995455;768.0514564512822;792.1523969352852;799.684124770724;813.979650508742;14568.927155364156;14835.925820370829;14864.108153510824;15121.16833574142;15299.311530981133;795.3517882925397;808.5189312359137;824.995875020621;835.8913341265596;849.3332733803934;8156.877646835303;8521.616527256288;8563.400561960603;8597.613107410181;8696.565171741388;345219;369015;376665;378802;381981;68753;70238;71388;72394.27605723981;73306.63346683233;931.84624536952;940.8259471997702;975.6341371985505;979.0747743382435;989.9703008909601;14580.43613707165;14679.779803303172;14733.26333683333;14838.851611483748;15160.241987900781;33071.66928330686;33289.500657489676;33698.66301336955;34141.829290853384;35060.47409288914;45363.31955020743;45796;46420.7678961603;46470.7676461629;46828.06343873035;6973;7238.963805180941;7391.113066432063;7423.480356375002;7785.143634200347;9635.855462167932;10607.575696972035;10729.302595331279;10828.566857325619;10901.34591924487;8523.232909038215;9623.663171788954;10186.796264074825;10550.155987520826;10578.576856925636;10130.290879638354;10252.38485690861;10259.692209233735;10315.638952636622;10357.482125893524;10160.796784064129;10464.63373781914;10519.474026298501;10608;10631.415272160106;7147.463940204401;7212.206657267591;7239.457040722073;7421.257874212535;7473.588952607442;8833.293336532934;9740.902590974283;9945.154661853534;9953.552090155816;10232.130268926927 09:26:08 INFO - PROCESS | 7970 | |3;mootools.html;1098.9395583242917;1278.4183196645524;1288.1433846492084;1290.2516540406564;1476.4094362255096;6197.566170368076;6513.348665133491;7047.436205103599;7208.459365547578;7215.458840586951;7146.57120572766;7194.4963852530345;7236.095488063992;7267.781966540999;7306.050213472255;2113.947151321214;2137.2626443876916;2144.399568120921;2144.871307721534;2146.1844499090325;2104.8000439958178;2125.51113243954;2168.8481806273567;2171.2617709978604;2173.3805865328304;2113.7674855765854;2126.33020598511;2146.9785302146934;2150.698902153692;2165.6101901657694;2117.6717608770664;2122.8407869409857;2122.8620139691025;2123.373604786592;2124;2042.9795702042939;2109.799569040954;2116.449723071997;2116.7565729940948;2130.7656157822626;7081.327273908971;7144.642767861586;7146.2496437874315;7166.641667916583;7177;4202.91594168118;4315.287977483711;4317.913641727179;4334.414853994702;4337.0458499130145;2741.6572928383953;2756.503829310723;2800.453911487261;2810.114813833656;2905.985470072636;908.3641450984271;929.1637526226383;947.1948843482984;947.4173383369247;954.8472244440856;9305.069493050709;9334.579943902541;9380.577873995619;9390.62437502499;9614.326997110205;3789.242151569697;3837.9424308635394;3908.921821563553;3924.0974575847144;3930.6265904739003;7704.460687751804;7722.3049925506975;7727.150013498511;7730.729424470116;7731.5747633879555;4276.31578947367;4282.293421585466;4288.742675439484;4291.463567054118;4296.570342965741;3461.549998500179;3809.3904975203836;3864.265789499986;3945.191235796655;3972.1261322509004;3182.283986103155;3222.6777322267585;3251.414745345813;3259.2014956335847;3286.786358886665;952.2286947572488;1014.2342531388754;1017.9949100254452;1026.6817286641165;1030.2118879057527;737.5869513072696;740.4224704730319;741.2106106996032;756.9886451703229;792.908815486215;4845.297431872359;4874.4150701916;4892.535209155125;4938.407391113089;5128.7179205144075;374.62911717400124;375.7407388901649;388.14607862702167;394.4202023026147;412.73378670757194;3239.433099207676;3243.1729908873085;3270.967290327066;3271.934561308808;3311.470164773673;8878.112188781199;9303.906960930304;9335.113164249386;9427.717168484956;9430.952845235866;9129.543522823833;9187.173154416001;9316.767080823034;9330.486823224788;9331.486768227813;6809.387155156159;6843.3840954314355;6896.724131034703;6901.1718593769065;6905.723771049203;4868.123737727102;4987.12725273083;5058.822941197041;5173;5178.689278643294;8524.872126917977;8587.742367728979;8652.524111173945;8668.263197628272;8679.652813887626;8210.63052162642;8421.452605580618;8432.789180270296;8507.276881465144;8513.531755753493;5718.742656580381;6230.626162430269;6345.746170153142;6496.058071579689;6497.285298617054;6952.130983627047;7080.04419403374;7090.113735783028;7098.751543696152;7104.857902841811;6225.252969643671;6516.283208847118;6605.075289459383;6640.933590664032;6655.301193374819;6263.467605253604;6395.7121929514215;6426.742930282923;6491.4806815455595;6501.089847421459;6017.398260174123;6032.939670603413;6094.634321940698;6102.389761023862;6116.602420842631;4320.632746216606;4416.470023597189;4419.602235798793;4500.729956202638;4501.144782491317;4321.222180007503;4467.5085740567865;4563;4596.5863072323655;4701.247800351927 09:26:08 INFO - PROCESS | 7970 | |4;prototype.html;290.3771410324853;291.51462814414003;292.52172697639367;295.52420602829443;298.83116039437704;4603.181743211429;4623.468301145361;4666.2767271073035;4670.602998745111;4705.552972467614;4601.907961840761;4630.865437967695;4631.305304204371;4639.489656137831;4667;3471.2016236265613;3559.501669766235;3599.1182160370745;3696.6488183622514;3724.944125838115;3664.468652045465;3679.337719210541;3695.7597756145897;3716.832742526593;3735.477033215352;3461.2558299965503;3669.761465504747;3679.2825399047197;3683.447482877563;3686.5576130864333;3549.6805287524116;3616.023673608124;3632.110133017401;3635.1093981974323;3655.872044478457;3203.5675183850117;3206.7434605231524;3219.6297425796097;3220.9677903220904;3221.951670724941;4451.799669014869;4463.933041004388;4465.709728867613;4479;4490.865274041751;4059;4134.586541345871;4143.461350024508;4198.265303571893;4210.0948296116485;4814;4836.564709176191;4854.854354369375;4857.319975203474;4874.46380898101;3565.358235517606;3566.964330356689;3569.1434055826676;3585.8744943926836;3586.5158203642436;4925.507449255082;5090.694558326512;5098.776293689525;5102.872428189325;5110.131277682803;3664.2671465706967;3669.944950825686;3681.687056600219;3684.613115622821;3689.741718079709;3701.629837016331;3711.8307733064;3720.6188039654644;3726.795026273581;3732.197577520846;4157.08544120293;4164.8542301019925;4178.1643671265865;4192.790360481964;4194.8112334945;4706.694064885772;4814.350062741555;4857.611391088775;4860.781264843091;4897.2654101885;1328.973420531584;1344.4218985836185;1347.0907137682025;1356.6811799227173;1358.2868993778147;858.9570521474026;912.0560220172223;913.977150571241;914.9496777677158;936.639393833379;4485.6860019798305;4527.864164075083;4552.544745525487;4573.336866154389;4579.816807327737;5322.578226906749;5330.573554115662;5335.786568537293;5347.2781174541715;5355.491228333302;4167.99936030707;4394.1651086293505;4509.88725281864;4510.639148868083;4567.383403240587;2841.076650088688;2866.140157952606;2866.312085099561;2866.8423236721765;2877.136858942309;5052.469490703496;5063.8480845574695;5071.822486212965;5072.416672082684;5089.694618322913;4978.4025916890205;5189.273501709764;5191.195364718475;5460.890782184413;5498.340199176125;4771.284307353924;4813.470518243061;4814.737073575414;4822.493638168012;4823.1800593898415;5049.242613607841;5067.150490071023;5075.416327122428;5086.64393492452;5091.770870310919;4847.95152048475;4952.306677065286;4955.324124972474;4957.504249575014;4968.900621987612;4785.258284965836;4800.3279540863605;4811.663183577116;4820.39745031871;4820.662553621213;3553.129483276614;3584.802835844054;3598.9280214395044;3599;3611.566612006576;4871.585915197106;5022.924656130088;5046.646734728533;5048.864005598858;5080.441151473409;4872.8792377754025;4877.585405240594;4882.90234195307;4893.314935908905;4906.828261010847;4900.632452566;4907.405093344606;4923.719832843558;4928.310036594808;4939.777710002986;4591.793369298322;4728.17256980034;4835.830745924017;4843.152448321459;4851.272309153655;4767.737774422301;4901.872569309007;4917.704937703749;4927.901441971068;4959.454460009324;4763.237881938873;4795.683884503975;4796.208625576741;4807.78364973584;4832.903341933212 09:26:08 INFO - PROCESS | 7970 | |5;yui.html;578.907126614833;640.2285246278238;644.477972841998;648.627039452315;722.7036914864907;12429;12469.501219951202;12471.376431178427;12638.304893230868;12712.427940742666;11162.218644694854;11512.13658975576;11562.942185289061;11573.710657233574;11610.245334053281;489.8628384052453;515.724087613127;696.3002182806273;712.98755766811;735.2977906099688;534.398801348483;536.3911959925493;537.8063896997078;538.2249560632696;538.7898719499399;563.2930672006646;565.366789196101;567.4240645744572;567.6480582039121;568.3691102875825;531.3464438740332;541.4071591607203;549.8790266141442;550.8595308196425;551.2007588995972;412.8101073506191;412.87820093072617;413.21076743420224;414.2191968140078;418.2262813794486;11134.61028863994;11141.777164456747;11162.665120046411;11164.497597608128;11166.665000050012;11372;11703.53185872564;11901.107416943763;12124.454399552042;12206.755864882653;9761.609535618567;9914.355566888129;10033.397996120184;10041.347312424668;10074.244431667654;3254.6094468663673;3307.7188438982716;3313.3870234006786;3323.651016643266;3413.74396920232;42517.78741106306;42524.78737606323;42808.78595607033;42846;42951;9213.257714961135;9288.814223715488;9350.485723285148;9573.377730447499;9659.565319560637;14676.706465870837;15085.924570377078;15117.697646047018;15160.09039457636;15256.694866102616;6409.775657851953;6471.417572418411;6580.078788969548;6781.186257649114;6860.3535151563265;3753.6862098265387;3774.150816066418;3831.65515103642;3909.9609003909595;3993.221321842213;2563.3976015636313;2597.8311409758307;2648.4041090754426;2648.748368904951;2654.8407095574325;390.9941350879739;472.43072098121684;488.59202565857197;490.31356101458243;498.6534358620796;509.9133147364957;573.2891214893585;578.9536837053025;603.435787538644;608.6256951974503;7869.213078692085;8053.436259461899;8078.515289082674;8139.79650508742;8314.70898518561;576.9134629805479;590.5541316306247;593.9910901336483;596.740417918207;601.2994860986988;3827.3110840049058;3853.402722578005;3906.941395879064;3939.4287828265315;3940.3498422760495;54277;54557.45442545603;54675.179872301946;58135.70932145397;58292.708536457045;50765;50824.74587627038;50973;52834.471655282956;53138.46861531335;47326.526734732215;48464.757676212095;48559.75720121377;48932.75533622309;49423.25865112026;7804;7884.684612615431;7886.881696774668;8042.155573664681;8128.512289262663;30802.691973079982;31771.523427149074;31980.840095800304;32010.839945800122;32595.674043259263;39718.404223936086;40232.396514051725;43402.348964764926;43410.78294608507;43824.78087609669;9195.770105747142;9441.575129119343;9548.761280968032;9600.615975360908;9612.279079068958;11009.834852477059;11021.614243501439;11271;11405.429728513707;11683.337411548453;10094.54574544162;10112.544935477774;10140.594376224868;10363.689089327332;10834.891651083388;9825;10151;10363.015513526203;10442.68671939843;10599.62901298451;10490.31812932157;10756.677299681021;11031.117510598971;11110.944445277722;11168.274062186258;8639.870401944101;8738.475691458532;8997;9039.502827344559;9358.230095557208;8450.366222533212;8479.788005299917;9022.909770902206;9076.364654474124;9136.26909847224 09:26:08 INFO - PROCESS | 7970 | __end_tp_report 09:26:08 INFO - PROCESS | 7970 | __start_cc_report 09:26:08 INFO - PROCESS | 7970 | _x_x_mozilla_cycle_collect,1031 09:26:08 INFO - PROCESS | 7970 | __end_cc_report 09:26:08 INFO - PROCESS | 7970 | __startTimestamp1461687968174__endTimestamp 09:26:08 INFO - PROCESS | 7970 | 09:26:08 INFO - PROCESS | 7970 | ------- Summary: start ------- 09:26:08 INFO - PROCESS | 7970 | Number of tests: 6 09:26:08 INFO - PROCESS | 7970 | 09:26:08 INFO - PROCESS | 7970 | [#0] dojo.html Cycles:165 Average:4476.42 Median:4606.84 stddev:441.88 (9.6%) stddev-sans-first:416.66 09:26:08 INFO - PROCESS | 7970 | Values: 2552.5 2608.2 2617.4 2626.3 2648.7 4408.4 4479.4 4489.1 4506.1 4506.3 4471.4 4501.6 4531.3 4532.8 4547.4 4140.0 4143.5 4171.5 4187.9 4282.8 4232.2 4240.5 4266.7 4284.5 4290.3 4064.9 4130.8 4152.2 4195.0 4207.6 4166.1 4208.0 4209.4 4216.0 4245.5 4156.1 4175.2 4181.7 4196.4 4222.6 4246.6 4395.4 4411.0 4464.3 4472.1 4848.5 4848.8 4853.4 4862.4 4867.0 4841.4 4868.6 4869.1 4878.8 4882.9 4415.6 4521.5 4590.2 4606.8 4615.9 4894.0 4917.1 4930.3 4936.3 4973.2 4735.2 4743.7 4744.8 4783.0 4787.1 4669.3 4797.7 4853.3 4874.3 4874.6 4772.4 4773.2 4790.4 4793.6 4835.3 4671.4 4760.0 4766.6 4779.7 4827.9 4110.3 4148.0 4162.1 4187.6 4201.7 3659.7 3666.7 3675.7 3696.4 3699.8 4114.4 4204.0 4216.0 4228.0 4254.8 4792.8 4804.8 4826.5 4855.8 4862.8 4161.0 4162.5 4177.1 4196.2 4221.9 4325.3 4359.9 4391.1 4400.3 4433.8 4543.5 4583.2 4591.7 4605.4 4639.1 4807.9 4818.0 4819.6 4820.8 4873.7 4531.3 4544.4 4563.2 4573.0 4608.5 4769.9 4777.7 4784.6 4795.8 4808.6 4806.9 4822.6 4826.1 4841.3 4852.6 4615.7 4677.0 4685.4 4752.1 4771.3 4647.7 4658.3 4667.6 4680.8 4727.4 4611.3 4612.4 4622.4 4624.0 4662.9 4599.3 4686.9 4740.1 4768.6 4787.7 4613.7 4617.3 4626.0 4626.0 4682.1 09:26:08 INFO - PROCESS | 7970 | 09:26:08 INFO - PROCESS | 7970 | [#1] ext.html Cycles:180 Average:35482.78 Median:30996.00 stddev:58579.95 (189.0%) stddev-sans-first:58685.65 09:26:08 INFO - PROCESS | 7970 | Values: 575.9 594.3 605.1 614.0 617.0 30153.2 30212.0 30336.0 30539.5 31747.7 30677.4 31032.4 31262.8 31413.0 31590.4 7287.2 7475.4 7475.6 7478.1 7481.7 7350.1 7457.5 7460.1 7466.6 7472.9 7537.8 7567.4 7570.2 7579.7 7621.3 7525.0 7587.6 7588.4 7591.2 7597.5 7445.0 7529.4 7542.4 7547.1 7550.6 30570.8 30975.0 31017.0 31037.0 31086.0 3072.8 3173.7 3222.9 3339.7 3476.7 24798.6 25170.5 25522.5 25717.5 26270.9 3353.4 3411.8 3417.5 3487.0 3529.3 196488.0 196590.2 196592.0 196950.0 197870.0 40485.4 40621.8 40808.0 40913.0 40920.4 36162.0 41995.0 42147.6 42149.2 42228.8 12409.9 12817.7 12819.0 12821.9 12830.9 9232.2 9319.7 9329.3 9354.6 9361.2 11330.2 12018.5 12302.3 12311.9 12448.9 5147.5 5154.8 5180.7 5241.0 5252.7 783.7 822.8 842.5 868.8 929.9 23060.9 23247.2 23546.8 23806.4 23847.8 893.3 899.7 913.8 954.8 975.1 12109.8 12343.5 12381.6 12497.1 12738.2 261653.0 265217.0 267190.0 268227.0 268337.2 38892.0 38972.2 38985.2 39069.2 39082.6 1073.2 1093.8 1096.7 1112.2 1119.0 22387.6 23296.5 23376.1 23681.0 24111.0 33175.8 33273.0 33309.5 33332.7 33418.8 203269.0 203550.0 204264.0 205174.0 205510.0 19563.5 20155.6 20168.1 21792.8 22143.0 28736.9 29269.6 30722.7 31052.8 34906.8 29894.3 30320.5 30572.8 30858.4 30920.2 29450.6 29834.7 29867.3 30046.8 30138.8 29110.0 29621.3 29815.3 29816.0 29824.3 26795.0 27399.3 27636.0 27722.6 27738.6 21828.3 22283.4 22293.8 22340.8 22533.8 09:26:08 INFO - PROCESS | 7970 | 09:26:08 INFO - PROCESS | 7970 | [#2] jquery.html Cycles:180 Average:22502.61 Median:46445.77 stddev:61088.82 (131.5%) stddev-sans-first:61237.54 09:26:08 INFO - PROCESS | 7970 | Values: 344.7 345.5 351.5 354.8 372.1 14360.1 14681.6 15345.0 15739.0 15852.8 14199.3 14561.3 15010.9 15029.0 15039.4 4331.5 4828.9 4953.3 5006.5 5009.9 4785.4 4911.1 4926.6 4929.6 4945.9 4819.3 4853.0 4913.4 4915.7 4978.1 4949.7 4956.9 4968.9 4976.7 4985.7 4410.4 4430.5 4431.8 4860.5 5127.0 11125.3 11199.5 11229.3 11320.1 11323.0 1800.9 1834.3 1868.9 2004.5 2330.5 8599.4 8938.6 8975.3 9345.5 11975.6 1763.1 1842.4 1875.7 1914.1 1945.4 60266.0 61961.4 62045.7 62999.0 63407.4 9060.1 9131.3 9272.8 9298.6 9459.8 12515.2 12618.1 12639.5 12644.6 12645.7 6437.6 6507.2 6685.4 6843.5 6854.2 6388.4 6407.5 6490.2 6526.5 6533.5 3979.5 4057.7 4062.7 4106.7 4118.9 1683.9 1722.8 1731.9 1745.7 1757.2 756.7 768.1 792.2 799.7 814.0 14568.9 14835.9 14864.1 15121.2 15299.3 795.4 808.5 825.0 835.9 849.3 8156.9 8521.6 8563.4 8597.6 8696.6 345219.0 369015.0 376665.0 378802.0 381981.0 68753.0 70238.0 71388.0 72394.3 73306.6 931.8 940.8 975.6 979.1 990.0 14580.4 14679.8 14733.3 14838.9 15160.2 33071.7 33289.5 33698.7 34141.8 35060.5 45363.3 45796.0 46420.8 46470.8 46828.1 6973.0 7239.0 7391.1 7423.5 7785.1 9635.9 10607.6 10729.3 10828.6 10901.3 8523.2 9623.7 10186.8 10550.2 10578.6 10130.3 10252.4 10259.7 10315.6 10357.5 10160.8 10464.6 10519.5 10608.0 10631.4 7147.5 7212.2 7239.5 7421.3 7473.6 8833.3 9740.9 9945.2 9953.6 10232.1 09:26:08 INFO - PROCESS | 7970 | 09:26:08 INFO - PROCESS | 7970 | [#3] mootools.html Cycles:180 Average:4796.93 Median:5093.77 stddev:2677.49 (52.6%) stddev-sans-first:2670.57 09:26:08 INFO - PROCESS | 7970 | Values: 1098.9 1278.4 1288.1 1290.3 1476.4 6197.6 6513.3 7047.4 7208.5 7215.5 7146.6 7194.5 7236.1 7267.8 7306.1 2113.9 2137.3 2144.4 2144.9 2146.2 2104.8 2125.5 2168.8 2171.3 2173.4 2113.8 2126.3 2147.0 2150.7 2165.6 2117.7 2122.8 2122.9 2123.4 2124.0 2043.0 2109.8 2116.4 2116.8 2130.8 7081.3 7144.6 7146.2 7166.6 7177.0 4202.9 4315.3 4317.9 4334.4 4337.0 2741.7 2756.5 2800.5 2810.1 2906.0 908.4 929.2 947.2 947.4 954.8 9305.1 9334.6 9380.6 9390.6 9614.3 3789.2 3837.9 3908.9 3924.1 3930.6 7704.5 7722.3 7727.2 7730.7 7731.6 4276.3 4282.3 4288.7 4291.5 4296.6 3461.5 3809.4 3864.3 3945.2 3972.1 3182.3 3222.7 3251.4 3259.2 3286.8 952.2 1014.2 1018.0 1026.7 1030.2 737.6 740.4 741.2 757.0 792.9 4845.3 4874.4 4892.5 4938.4 5128.7 374.6 375.7 388.1 394.4 412.7 3239.4 3243.2 3271.0 3271.9 3311.5 8878.1 9303.9 9335.1 9427.7 9431.0 9129.5 9187.2 9316.8 9330.5 9331.5 6809.4 6843.4 6896.7 6901.2 6905.7 4868.1 4987.1 5058.8 5173.0 5178.7 8524.9 8587.7 8652.5 8668.3 8679.7 8210.6 8421.5 8432.8 8507.3 8513.5 5718.7 6230.6 6345.7 6496.1 6497.3 6952.1 7080.0 7090.1 7098.8 7104.9 6225.3 6516.3 6605.1 6640.9 6655.3 6263.5 6395.7 6426.7 6491.5 6501.1 6017.4 6032.9 6094.6 6102.4 6116.6 4320.6 4416.5 4419.6 4500.7 4501.1 4321.2 4467.5 4563.0 4596.6 4701.2 09:26:08 INFO - PROCESS | 7970 | 09:26:08 INFO - PROCESS | 7970 | [#4] prototype.html Cycles:180 Average:4114.80 Median:4635.40 stddev:1177.42 (25.4%) stddev-sans-first:1145.20 09:26:08 INFO - PROCESS | 7970 | Values: 290.4 291.5 292.5 295.5 298.8 4603.2 4623.5 4666.3 4670.6 4705.6 4601.9 4630.9 4631.3 4639.5 4667.0 3471.2 3559.5 3599.1 3696.6 3724.9 3664.5 3679.3 3695.8 3716.8 3735.5 3461.3 3669.8 3679.3 3683.4 3686.6 3549.7 3616.0 3632.1 3635.1 3655.9 3203.6 3206.7 3219.6 3221.0 3222.0 4451.8 4463.9 4465.7 4479.0 4490.9 4059.0 4134.6 4143.5 4198.3 4210.1 4814.0 4836.6 4854.9 4857.3 4874.5 3565.4 3567.0 3569.1 3585.9 3586.5 4925.5 5090.7 5098.8 5102.9 5110.1 3664.3 3669.9 3681.7 3684.6 3689.7 3701.6 3711.8 3720.6 3726.8 3732.2 4157.1 4164.9 4178.2 4192.8 4194.8 4706.7 4814.4 4857.6 4860.8 4897.3 1329.0 1344.4 1347.1 1356.7 1358.3 859.0 912.1 914.0 914.9 936.6 4485.7 4527.9 4552.5 4573.3 4579.8 5322.6 5330.6 5335.8 5347.3 5355.5 4168.0 4394.2 4509.9 4510.6 4567.4 2841.1 2866.1 2866.3 2866.8 2877.1 5052.5 5063.8 5071.8 5072.4 5089.7 4978.4 5189.3 5191.2 5460.9 5498.3 4771.3 4813.5 4814.7 4822.5 4823.2 5049.2 5067.2 5075.4 5086.6 5091.8 4848.0 4952.3 4955.3 4957.5 4968.9 4785.3 4800.3 4811.7 4820.4 4820.7 3553.1 3584.8 3598.9 3599.0 3611.6 4871.6 5022.9 5046.6 5048.9 5080.4 4872.9 4877.6 4882.9 4893.3 4906.8 4900.6 4907.4 4923.7 4928.3 4939.8 4591.8 4728.2 4835.8 4843.2 4851.3 4767.7 4901.9 4917.7 4927.9 4959.5 4763.2 4795.7 4796.2 4807.8 4832.9 09:26:08 INFO - PROCESS | 7970 | 09:26:08 INFO - PROCESS | 7970 | [#5] yui.html Cycles:180 Average:12938.09 Median:48512.26 stddev:15552.26 (32.1%) stddev-sans-first:15568.19 09:26:08 INFO - PROCESS | 7970 | Values: 578.9 640.2 644.5 648.6 722.7 12429.0 12469.5 12471.4 12638.3 12712.4 11162.2 11512.1 11562.9 11573.7 11610.2 489.9 515.7 696.3 713.0 735.3 534.4 536.4 537.8 538.2 538.8 563.3 565.4 567.4 567.6 568.4 531.3 541.4 549.9 550.9 551.2 412.8 412.9 413.2 414.2 418.2 11134.6 11141.8 11162.7 11164.5 11166.7 11372.0 11703.5 11901.1 12124.5 12206.8 9761.6 9914.4 10033.4 10041.3 10074.2 3254.6 3307.7 3313.4 3323.7 3413.7 42517.8 42524.8 42808.8 42846.0 42951.0 9213.3 9288.8 9350.5 9573.4 9659.6 14676.7 15085.9 15117.7 15160.1 15256.7 6409.8 6471.4 6580.1 6781.2 6860.4 3753.7 3774.2 3831.7 3910.0 3993.2 2563.4 2597.8 2648.4 2648.7 2654.8 391.0 472.4 488.6 490.3 498.7 509.9 573.3 579.0 603.4 608.6 7869.2 8053.4 8078.5 8139.8 8314.7 576.9 590.6 594.0 596.7 601.3 3827.3 3853.4 3906.9 3939.4 3940.3 54277.0 54557.5 54675.2 58135.7 58292.7 50765.0 50824.7 50973.0 52834.5 53138.5 47326.5 48464.8 48559.8 48932.8 49423.3 7804.0 7884.7 7886.9 8042.2 8128.5 30802.7 31771.5 31980.8 32010.8 32595.7 39718.4 40232.4 43402.3 43410.8 43824.8 9195.8 9441.6 9548.8 9600.6 9612.3 11009.8 11021.6 11271.0 11405.4 11683.3 10094.5 10112.5 10140.6 10363.7 10834.9 9825.0 10151.0 10363.0 10442.7 10599.6 10490.3 10756.7 11031.1 11110.9 11168.3 8639.9 8738.5 8997.0 9039.5 9358.2 8450.4 8479.8 9022.9 9076.4 9136.3 09:26:08 INFO - PROCESS | 7970 | -------- Summary: end -------- 09:26:08 INFO - PROCESS | 7970 | 09:26:08 INFO - TEST-INFO | 7970: exit 0 09:26:08 INFO - TEST-OK | dromaeo_css | took 1116424ms 09:26:08 INFO - TEST-START | kraken 09:26:08 INFO - operating with platform_type : linux_ 09:26:08 INFO - Initialising browser for kraken test... 09:26:08 INFO - TEST-INFO | started process 8146 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQxqAfD/profile http://localhost:45122/getInfo.html) 09:26:12 INFO - PROCESS | 8146 | __metrics Screen width/height:1600/1200 09:26:12 INFO - PROCESS | 8146 | colorDepth:24 09:26:12 INFO - PROCESS | 8146 | Browser inner width/height: 1024/697 09:26:12 INFO - PROCESS | 8146 | __metrics 09:26:13 ERROR - PROCESS | 8146 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:26:14 INFO - TEST-INFO | 8146: exit 0 09:26:14 INFO - Browser initialized. 09:26:14 INFO - Running cycle 1/1 for kraken test... 09:26:14 INFO - TEST-INFO | started process 8226 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQxqAfD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:26:26 INFO - PROCESS | 8226 | RSS: Main: 157011968 09:26:26 INFO - PROCESS | 8226 | 09:26:29 INFO - PROCESS | 8226 | Cycle 1(1): loaded http://localhost:45122/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45122/tests/kraken/driver.html?testName=audio-beat-detection) 09:26:30 INFO - PROCESS | 8226 | RSS: Main: 210997248 09:26:30 INFO - PROCESS | 8226 | 09:26:33 INFO - PROCESS | 8226 | Cycle 1(1): loaded http://localhost:45122/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45122/tests/kraken/driver.html?testName=audio-dft) 09:26:33 INFO - PROCESS | 8226 | RSS: Main: 196874240 09:26:33 INFO - PROCESS | 8226 | 09:26:36 INFO - PROCESS | 8226 | Cycle 1(1): loaded http://localhost:45122/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45122/tests/kraken/driver.html?testName=audio-fft) 09:26:37 INFO - PROCESS | 8226 | RSS: Main: 267272192 09:26:37 INFO - PROCESS | 8226 | 09:26:39 INFO - PROCESS | 8226 | Cycle 1(1): loaded http://localhost:45122/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45122/tests/kraken/driver.html?testName=audio-oscillator) 09:26:40 INFO - PROCESS | 8226 | RSS: Main: 174788608 09:26:40 INFO - PROCESS | 8226 | 09:26:42 INFO - PROCESS | 8226 | Cycle 1(1): loaded http://localhost:45122/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45122/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:26:43 INFO - PROCESS | 8226 | RSS: Main: 179412992 09:26:43 INFO - PROCESS | 8226 | 09:26:46 INFO - PROCESS | 8226 | Cycle 1(1): loaded http://localhost:45122/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45122/tests/kraken/driver.html?testName=imaging-darkroom) 09:26:46 INFO - PROCESS | 8226 | RSS: Main: 215773184 09:26:46 INFO - PROCESS | 8226 | 09:26:50 INFO - PROCESS | 8226 | Cycle 1(1): loaded http://localhost:45122/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45122/tests/kraken/driver.html?testName=imaging-desaturate) 09:26:50 INFO - PROCESS | 8226 | RSS: Main: 216854528 09:26:50 INFO - PROCESS | 8226 | 09:26:53 INFO - PROCESS | 8226 | Cycle 1(1): loaded http://localhost:45122/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45122/tests/kraken/driver.html?testName=json-parse-financial) 09:26:54 INFO - PROCESS | 8226 | RSS: Main: 217128960 09:26:54 INFO - PROCESS | 8226 | 09:26:56 INFO - PROCESS | 8226 | Cycle 1(1): loaded http://localhost:45122/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45122/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:26:56 INFO - PROCESS | 8226 | RSS: Main: 203280384 09:26:56 INFO - PROCESS | 8226 | 09:26:58 INFO - PROCESS | 8226 | Cycle 1(1): loaded http://localhost:45122/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45122/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:26:59 INFO - PROCESS | 8226 | RSS: Main: 205783040 09:26:59 INFO - PROCESS | 8226 | 09:27:01 INFO - PROCESS | 8226 | Cycle 1(1): loaded http://localhost:45122/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45122/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:27:02 INFO - PROCESS | 8226 | RSS: Main: 217341952 09:27:02 INFO - PROCESS | 8226 | 09:27:04 INFO - PROCESS | 8226 | Cycle 1(1): loaded http://localhost:45122/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45122/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:27:05 INFO - PROCESS | 8226 | RSS: Main: 225304576 09:27:05 INFO - PROCESS | 8226 | 09:27:08 INFO - PROCESS | 8226 | Cycle 1(1): loaded http://localhost:45122/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45122/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:27:09 INFO - PROCESS | 8226 | RSS: Main: 222072832 09:27:09 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | Cycle 1(1): loaded http://localhost:45122/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45122/tests/kraken/driver.html?testName=ai-astar) 09:27:11 INFO - PROCESS | 8226 | RSS: Main: 284897280 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | __start_tp_report 09:27:11 INFO - PROCESS | 8226 | _x_x_mozilla_page_load 09:27:11 INFO - PROCESS | 8226 | _x_x_mozilla_page_load_details 09:27:11 INFO - PROCESS | 8226 | |i|pagename|runs| 09:27:11 INFO - PROCESS | 8226 | |0;ai-astar;113;114;109;114;114;114;103;112;112;114 09:27:11 INFO - PROCESS | 8226 | |1;audio-beat-detection;166;132;161;187;139;159;166;132;199;142 09:27:11 INFO - PROCESS | 8226 | |2;audio-dft;160;160;160;160;162;161;161;162;161;161 09:27:11 INFO - PROCESS | 8226 | |3;audio-fft;76;75;112;112;74;74;73;114;102;72 09:27:11 INFO - PROCESS | 8226 | |4;audio-oscillator;94;95;150;94;94;94;144;93;95;143 09:27:11 INFO - PROCESS | 8226 | |5;imaging-gaussian-blur;117;116;117;116;116;116;117;117;116;117 09:27:11 INFO - PROCESS | 8226 | |6;imaging-darkroom;146;145;147;145;145;146;146;145;145;146 09:27:11 INFO - PROCESS | 8226 | |7;imaging-desaturate;94;94;95;94;95;95;94;94;95;95 09:27:11 INFO - PROCESS | 8226 | |8;json-parse-financial;65;66;66;65;66;66;67;82;64;62 09:27:11 INFO - PROCESS | 8226 | |9;json-stringify-tinderbox;65;67;66;66;66;64;65;65;68;65 09:27:11 INFO - PROCESS | 8226 | |10;stanford-crypto-aes;79;79;77;85;79;80;79;81;79;81 09:27:11 INFO - PROCESS | 8226 | |11;stanford-crypto-ccm;139;136;135;132;137;134;132;134;167;134 09:27:11 INFO - PROCESS | 8226 | |12;stanford-crypto-pbkdf2;215;221;225;214;219;219;216;215;217;217 09:27:11 INFO - PROCESS | 8226 | |13;stanford-crypto-sha256-iterative;75;75;73;74;74;74;74;73;74;74 09:27:11 INFO - PROCESS | 8226 | __end_tp_report 09:27:11 INFO - PROCESS | 8226 | __start_cc_report 09:27:11 INFO - PROCESS | 8226 | _x_x_mozilla_cycle_collect,1886 09:27:11 INFO - PROCESS | 8226 | __end_cc_report 09:27:11 INFO - PROCESS | 8226 | __startTimestamp1461688031478__endTimestamp 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | ------- Summary: start ------- 09:27:11 INFO - PROCESS | 8226 | Number of tests: 14 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | [#0] ai-astar Cycles:10 Average:111.90 Median:114.00 stddev:3.51 (3.1%) stddev-sans-first:3.70 09:27:11 INFO - PROCESS | 8226 | Values: 113.0 114.0 109.0 114.0 114.0 114.0 103.0 112.0 112.0 114.0 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | [#1] audio-beat-detection Cycles:10 Average:158.30 Median:163.50 stddev:22.68 (13.9%) stddev-sans-first:23.88 09:27:11 INFO - PROCESS | 8226 | Values: 166.0 132.0 161.0 187.0 139.0 159.0 166.0 132.0 199.0 142.0 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 09:27:11 INFO - PROCESS | 8226 | Values: 160.0 160.0 160.0 160.0 162.0 161.0 161.0 162.0 161.0 161.0 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | [#3] audio-fft Cycles:10 Average:88.40 Median:73.50 stddev:18.88 (25.7%) stddev-sans-first:19.49 09:27:11 INFO - PROCESS | 8226 | Values: 76.0 75.0 112.0 112.0 74.0 74.0 73.0 114.0 102.0 72.0 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | [#4] audio-oscillator Cycles:10 Average:109.60 Median:94.00 stddev:24.96 (26.6%) stddev-sans-first:25.83 09:27:11 INFO - PROCESS | 8226 | Values: 94.0 95.0 150.0 94.0 94.0 94.0 144.0 93.0 95.0 143.0 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:27:11 INFO - PROCESS | 8226 | Values: 117.0 116.0 117.0 116.0 116.0 116.0 117.0 117.0 116.0 117.0 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 09:27:11 INFO - PROCESS | 8226 | Values: 146.0 145.0 147.0 145.0 145.0 146.0 146.0 145.0 145.0 146.0 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 09:27:11 INFO - PROCESS | 8226 | Values: 94.0 94.0 95.0 94.0 95.0 95.0 94.0 94.0 95.0 95.0 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | [#8] json-parse-financial Cycles:10 Average:66.90 Median:66.00 stddev:5.49 (8.3%) stddev-sans-first:5.78 09:27:11 INFO - PROCESS | 8226 | Values: 65.0 66.0 66.0 65.0 66.0 66.0 67.0 82.0 64.0 62.0 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:1.16 (1.8%) stddev-sans-first:1.20 09:27:11 INFO - PROCESS | 8226 | Values: 65.0 67.0 66.0 66.0 66.0 64.0 65.0 65.0 68.0 65.0 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:79.50 stddev:2.13 (2.7%) stddev-sans-first:2.24 09:27:11 INFO - PROCESS | 8226 | Values: 79.0 79.0 77.0 85.0 79.0 80.0 79.0 81.0 79.0 81.0 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | [#11] stanford-crypto-ccm Cycles:10 Average:138.00 Median:135.50 stddev:10.41 (7.7%) stddev-sans-first:11.04 09:27:11 INFO - PROCESS | 8226 | Values: 139.0 136.0 135.0 132.0 137.0 134.0 132.0 134.0 167.0 134.0 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:217.80 Median:218.00 stddev:3.33 (1.5%) stddev-sans-first:3.37 09:27:11 INFO - PROCESS | 8226 | Values: 215.0 221.0 225.0 214.0 219.0 219.0 216.0 215.0 217.0 217.0 09:27:11 INFO - PROCESS | 8226 | 09:27:11 INFO - PROCESS | 8226 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:74.00 stddev:0.67 (0.9%) stddev-sans-first:0.60 09:27:11 INFO - PROCESS | 8226 | Values: 75.0 75.0 73.0 74.0 74.0 74.0 74.0 73.0 74.0 74.0 09:27:11 INFO - PROCESS | 8226 | -------- Summary: end -------- 09:27:11 INFO - PROCESS | 8226 | 09:27:12 INFO - TEST-INFO | 8226: exit 0 09:27:12 INFO - TEST-OK | kraken | took 63572ms 09:27:12 INFO - SUITE-END | took 1179s 09:27:12 INFO - Completed test suite (00:19:40) 09:27:12 INFO - javascript benchmark 09:27:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2552.515022145793, 2608.165387076135, 2617.397998460355, 2626.3040294321995, 2648.7086420493747, 4408.382826404298, 4479.440069991251, 4489.057297967427, 4506.053728716965, 4506.324051392293, 4471.396361491198, 4501.594856462926, 4531.342955271483, 4532.773361331937, 4547.431571053618, 4140.047789008529, 4143.523494798107, 4171.4994200695955, 4187.937180942288, 4282.764447955361, 4232.15356928614, 4240.469941257343, 4266.722663026909, 4284.507281662618, 4290.313549832027, 4064.878053658395, 4130.793460326971, 4152.1695660867645, 4194.958050419517, 4207.621314081747, 4166.062635906927, 4208.032152604887, 4209.410682504452, 4215.957840421618, 4245.511766146902, 4156.06488540076, 4175.164967006581, 4181.686373521968, 4196.391523229115, 4222.641075508585, 4246.596573325529, 4395.406620106309, 4411.0, 4464.330350447426, 4472.08322293929, 4848.5151484851585, 4848.757562121879, 4853.3690620219495, 4862.367892173995, 4866.975665121652, 4841.394825646794, 4868.561829435368, 4869.074875773592, 4878.780454879539, 4882.926756098661, 4415.646748260164, 4521.502634710179, 4590.173768721629, 4606.838760643428, 4615.907681846412, 4894.045661096124, 4917.0903382874285, 4930.260460930817, 4936.284238785356, 4973.204287314013, 4735.21868891629, 4743.66794324394, 4744.7627618618935, 4782.95217047832, 4787.0904528139545, 4669.322948172496, 4797.736124513115, 4853.296272040605, 4874.268859671078, 4874.634402419832, 4772.379590653193, 4773.164696178224, 4790.377250957423, 4793.568578827852, 4835.347228124158, 4671.416072990876, 4760.047990401933, 4766.571008609172, 4779.689320194177, 4827.927581086286, 4110.321796903538, 4147.97926010368, 4162.105147393325, 4187.581241875788, 4201.70588058833, 3659.7438179327723, 3666.7249956253386, 3675.687566556819, 3696.353138200804, 3699.7965111918566, 4114.382842573638, 4203.97898010508, 4215.957840421618, 4228.027553662613, 4254.829806807755, 4792.760361981887, 4804.807807687724, 4826.517348265215, 4855.8057677693205, 4862.756862156949, 4161.0, 4162.542120366757, 4177.081042170717, 4196.1607678463815, 4221.915561688749, 4325.329573916048, 4359.912801743947, 4391.121775644884, 4400.317950717644, 4433.756143412079, 4543.45478542577, 4583.197940360424, 4591.724496530219, 4605.40129783126, 4639.118567472169, 4807.879803004953, 4818.036392721539, 4819.590334821439, 4820.758962051813, 4873.731944743073, 4531.320301954602, 4544.409226800495, 4563.247064234364, 4572.954270457386, 4608.49306576283, 4769.880752981203, 4777.689450185727, 4784.641151913551, 4795.832145875017, 4808.591269742111, 4806.903861922812, 4822.590079843252, 4826.131296366688, 4841.298011788201, 4852.611791056777, 4615.723056616614, 4676.953230467788, 4685.437747470325, 4752.144613969519, 4771.260454629538, 4647.65142614312, 4658.277966915133, 4667.626589872734, 4680.836170734008, 4727.432708075053, 4611.331356953156, 4612.423447069116, 4622.375979242759, 4623.959609087929, 4662.906741865076, 4599.264117741145, 4686.859394218179, 4740.075685241345, 4768.642351823558, 4787.712737235777, 4613.700109492872, 4617.2612382018715, 4625.953740462553, 4626.005408837116, 4682.063587282625], "value": 4450.031101062359, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.9488932697825, 594.3402822866618, 605.1074664869318, 613.9938600613993, 616.9938300616992, 30153.24616884579, 30212.0, 30336.0, 30539.541906871415, 31747.68252317476, 30677.38645227094, 31032.37935241294, 31262.843685781598, 31413.0, 31590.36819263619, 7287.198408175096, 7475.364594009516, 7475.588842613654, 7478.102627684685, 7481.6633251503545, 7350.08123984502, 7457.5152615080115, 7460.0674915635545, 7466.552006879578, 7472.887906681377, 7537.811554711148, 7567.356774674132, 7570.2051284615145, 7579.734709285176, 7621.275978782007, 7524.962375188116, 7587.620618969085, 7588.354989825872, 7591.24087591242, 7597.544147351176, 7444.9627751861435, 7529.397648188132, 7542.35889949355, 7547.132079810837, 7550.622468876534, 30570.84714576435, 30975.0, 31017.0, 31037.0, 31086.0, 3072.846357682107, 3173.7143657070974, 3222.9033129006166, 3339.666033396665, 3476.66971637694, 24798.628020579705, 25170.49659006828, 25522.489550209077, 25717.485650287075, 26270.86864565684, 3353.393397571907, 3411.795292282471, 3417.487376893461, 3486.9825650871585, 3529.3117842020815, 196488.0, 196590.1534430954, 196592.0, 196950.0, 197870.0, 40485.39271910924, 40621.79689101536, 40808.0, 40913.0, 40920.38619420711, 36162.0, 41995.0, 42147.57852421498, 42149.1570168601, 42228.78885605552, 12409.875901241052, 12817.743645127046, 12819.038572106943, 12821.935890320488, 12830.871691283155, 9232.21526170283, 9319.720408387759, 9329.30030247734, 9354.579043942902, 9361.157495825408, 11330.150238731963, 12018.4591693374, 12302.261864288172, 12311.891929726367, 12448.937755311166, 5147.459516750762, 5154.845354639367, 5180.663256888365, 5240.973795131, 5252.737363131828, 783.6630248992874, 822.7696245051395, 842.4860834890711, 868.8349213649386, 929.9442033477877, 23060.884695576413, 23247.18634847772, 23546.7645323548, 23806.404839878796, 23847.761522384902, 893.3032234856822, 899.7480705402497, 913.7989642278689, 954.7660823098386, 975.11264749078, 12109.757804843854, 12343.536499007552, 12381.566645167375, 12497.125201235825, 12738.235705857678, 261653.0, 265217.0, 267190.0, 268227.0, 268337.21639485046, 38892.0, 38972.22055558873, 38985.22029559393, 39069.21861562753, 39082.609173908466, 1073.2272763610188, 1093.7593729379523, 1096.7203363142348, 1112.2270022334562, 1119.0488085127577, 22387.552248955257, 23296.53406931818, 23376.064957401515, 23681.0, 24111.0, 33175.83412083021, 33273.0, 33309.50035749417, 33332.666673332955, 33418.83290583531, 203269.0, 203550.0, 204264.0, 205174.0, 205510.0, 19563.51091222674, 20155.596888061864, 20168.092435840128, 21792.78207217971, 22143.0, 28736.856315719124, 29269.560956585243, 30722.692773071984, 31052.844735777082, 34906.825465873524, 29894.25264368408, 30320.54519182258, 30572.847135765067, 30858.382832343676, 30920.226994325323, 29450.558241626823, 29834.701652983193, 29867.253318667208, 30046.84976575103, 30138.84930575333, 29110.0, 29621.2594685126, 29815.254618634706, 29816.0, 29824.25439364033, 26795.0, 27399.315017123932, 27636.0, 27722.584161237195, 27738.583921240795, 21828.345149645535, 22283.44291392728, 22293.77706222982, 22340.77659223387, 22533.774662253167], "value": 14791.237926672777, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.70011090351414, 345.5145520543636, 351.5307065068134, 354.795992304425, 372.07539264926635, 14360.138391696506, 14681.564915223773, 15345.0, 15739.0, 15852.841471585281, 14199.290035498236, 14561.344739486722, 15010.924945375258, 15029.023113497615, 15039.398424063025, 4331.523532411432, 4828.87927801806, 4953.257011448276, 5006.54941055305, 5009.899802003958, 4785.353977213083, 4911.091448082098, 4926.605871530287, 4929.580985616227, 4945.9258111128365, 4819.325294458778, 4852.9757351213375, 4913.385826771653, 4915.729634870081, 4978.103941290567, 4949.653524253304, 4956.925646115311, 4968.925466118011, 4976.676516026447, 4985.651004429691, 4410.360497727812, 4430.490493593245, 4431.844885429026, 4860.538248866352, 5127.0002349541855, 11125.332480051142, 11199.496022678917, 11229.270097443721, 11320.094392448584, 11323.037541808957, 1800.8829426087264, 1834.257125864027, 1868.8785228960073, 2004.4788355027797, 2330.52224294021, 8599.355048371397, 8938.597763100612, 8975.281977441855, 9345.532723363805, 11975.580854670132, 1763.1272520102632, 1842.4380563927996, 1875.681134207188, 1914.119505027689, 1945.3774792066545, 60266.0, 61961.38038619646, 62045.689771551304, 62999.0, 63407.36592634061, 9060.093990600888, 9131.315151363542, 9272.814543709223, 9298.628054877729, 9459.763505912408, 12515.249085054926, 12618.116731828868, 12639.494420223273, 12644.557440489538, 12645.683857903627, 6437.613743175424, 6507.219133703986, 6685.431738302299, 6843.452523798084, 6854.246032936373, 6388.425041746265, 6407.455366293917, 6490.188726409199, 6526.477881769447, 6533.477321814338, 3979.5025621797276, 4057.655099316532, 4062.6749860011128, 4106.7125301228625, 4118.938215926763, 1683.924223409925, 1722.7674263974454, 1731.8527925126252, 1745.677049745801, 1757.2004737844507, 756.6897571995455, 768.0514564512822, 792.1523969352852, 799.684124770724, 813.979650508742, 14568.927155364156, 14835.925820370829, 14864.108153510824, 15121.16833574142, 15299.311530981133, 795.3517882925397, 808.5189312359137, 824.995875020621, 835.8913341265596, 849.3332733803934, 8156.877646835303, 8521.616527256288, 8563.400561960603, 8597.613107410181, 8696.565171741388, 345219.0, 369015.0, 376665.0, 378802.0, 381981.0, 68753.0, 70238.0, 71388.0, 72394.27605723981, 73306.63346683233, 931.84624536952, 940.8259471997702, 975.6341371985505, 979.0747743382435, 989.9703008909601, 14580.43613707165, 14679.779803303172, 14733.26333683333, 14838.851611483748, 15160.241987900781, 33071.66928330686, 33289.500657489676, 33698.66301336955, 34141.829290853384, 35060.47409288914, 45363.31955020743, 45796.0, 46420.7678961603, 46470.7676461629, 46828.06343873035, 6973.0, 7238.963805180941, 7391.113066432063, 7423.480356375002, 7785.143634200347, 9635.855462167932, 10607.575696972035, 10729.302595331279, 10828.566857325619, 10901.34591924487, 8523.232909038215, 9623.663171788954, 10186.796264074825, 10550.155987520826, 10578.576856925636, 10130.290879638354, 10252.38485690861, 10259.692209233735, 10315.638952636622, 10357.482125893524, 10160.796784064129, 10464.63373781914, 10519.474026298501, 10608.0, 10631.415272160106, 7147.463940204401, 7212.206657267591, 7239.457040722073, 7421.257874212535, 7473.588952607442, 8833.293336532934, 9740.902590974283, 9945.154661853534, 9953.552090155816, 10232.130268926927], "value": 7605.618710298218, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1098.9395583242917, 1278.4183196645524, 1288.1433846492084, 1290.2516540406564, 1476.4094362255096, 6197.566170368076, 6513.348665133491, 7047.436205103599, 7208.459365547578, 7215.458840586951, 7146.57120572766, 7194.4963852530345, 7236.095488063992, 7267.781966540999, 7306.050213472255, 2113.947151321214, 2137.2626443876916, 2144.399568120921, 2144.871307721534, 2146.1844499090325, 2104.8000439958178, 2125.51113243954, 2168.8481806273567, 2171.2617709978604, 2173.3805865328304, 2113.7674855765854, 2126.33020598511, 2146.9785302146934, 2150.698902153692, 2165.6101901657694, 2117.6717608770664, 2122.8407869409857, 2122.8620139691025, 2123.373604786592, 2124.0, 2042.9795702042939, 2109.799569040954, 2116.449723071997, 2116.7565729940948, 2130.7656157822626, 7081.327273908971, 7144.642767861586, 7146.2496437874315, 7166.641667916583, 7177.0, 4202.91594168118, 4315.287977483711, 4317.913641727179, 4334.414853994702, 4337.0458499130145, 2741.6572928383953, 2756.503829310723, 2800.453911487261, 2810.114813833656, 2905.985470072636, 908.3641450984271, 929.1637526226383, 947.1948843482984, 947.4173383369247, 954.8472244440856, 9305.069493050709, 9334.579943902541, 9380.577873995619, 9390.62437502499, 9614.326997110205, 3789.242151569697, 3837.9424308635394, 3908.921821563553, 3924.0974575847144, 3930.6265904739003, 7704.460687751804, 7722.3049925506975, 7727.150013498511, 7730.729424470116, 7731.5747633879555, 4276.31578947367, 4282.293421585466, 4288.742675439484, 4291.463567054118, 4296.570342965741, 3461.549998500179, 3809.3904975203836, 3864.265789499986, 3945.191235796655, 3972.1261322509004, 3182.283986103155, 3222.6777322267585, 3251.414745345813, 3259.2014956335847, 3286.786358886665, 952.2286947572488, 1014.2342531388754, 1017.9949100254452, 1026.6817286641165, 1030.2118879057527, 737.5869513072696, 740.4224704730319, 741.2106106996032, 756.9886451703229, 792.908815486215, 4845.297431872359, 4874.4150701916, 4892.535209155125, 4938.407391113089, 5128.7179205144075, 374.62911717400124, 375.7407388901649, 388.14607862702167, 394.4202023026147, 412.73378670757194, 3239.433099207676, 3243.1729908873085, 3270.967290327066, 3271.934561308808, 3311.470164773673, 8878.112188781199, 9303.906960930304, 9335.113164249386, 9427.717168484956, 9430.952845235866, 9129.543522823833, 9187.173154416001, 9316.767080823034, 9330.486823224788, 9331.486768227813, 6809.387155156159, 6843.3840954314355, 6896.724131034703, 6901.1718593769065, 6905.723771049203, 4868.123737727102, 4987.12725273083, 5058.822941197041, 5173.0, 5178.689278643294, 8524.872126917977, 8587.742367728979, 8652.524111173945, 8668.263197628272, 8679.652813887626, 8210.63052162642, 8421.452605580618, 8432.789180270296, 8507.276881465144, 8513.531755753493, 5718.742656580381, 6230.626162430269, 6345.746170153142, 6496.058071579689, 6497.285298617054, 6952.130983627047, 7080.04419403374, 7090.113735783028, 7098.751543696152, 7104.857902841811, 6225.252969643671, 6516.283208847118, 6605.075289459383, 6640.933590664032, 6655.301193374819, 6263.467605253604, 6395.7121929514215, 6426.742930282923, 6491.4806815455595, 6501.089847421459, 6017.398260174123, 6032.939670603413, 6094.634321940698, 6102.389761023862, 6116.602420842631, 4320.632746216606, 4416.470023597189, 4419.602235798793, 4500.729956202638, 4501.144782491317, 4321.222180007503, 4467.5085740567865, 4563.0, 4596.5863072323655, 4701.247800351927], "value": 3805.699916458248, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.3771410324853, 291.51462814414003, 292.52172697639367, 295.52420602829443, 298.83116039437704, 4603.181743211429, 4623.468301145361, 4666.2767271073035, 4670.602998745111, 4705.552972467614, 4601.907961840761, 4630.865437967695, 4631.305304204371, 4639.489656137831, 4667.0, 3471.2016236265613, 3559.501669766235, 3599.1182160370745, 3696.6488183622514, 3724.944125838115, 3664.468652045465, 3679.337719210541, 3695.7597756145897, 3716.832742526593, 3735.477033215352, 3461.2558299965503, 3669.761465504747, 3679.2825399047197, 3683.447482877563, 3686.5576130864333, 3549.6805287524116, 3616.023673608124, 3632.110133017401, 3635.1093981974323, 3655.872044478457, 3203.5675183850117, 3206.7434605231524, 3219.6297425796097, 3220.9677903220904, 3221.951670724941, 4451.799669014869, 4463.933041004388, 4465.709728867613, 4479.0, 4490.865274041751, 4059.0, 4134.586541345871, 4143.461350024508, 4198.265303571893, 4210.0948296116485, 4814.0, 4836.564709176191, 4854.854354369375, 4857.319975203474, 4874.46380898101, 3565.358235517606, 3566.964330356689, 3569.1434055826676, 3585.8744943926836, 3586.5158203642436, 4925.507449255082, 5090.694558326512, 5098.776293689525, 5102.872428189325, 5110.131277682803, 3664.2671465706967, 3669.944950825686, 3681.687056600219, 3684.613115622821, 3689.741718079709, 3701.629837016331, 3711.8307733064, 3720.6188039654644, 3726.795026273581, 3732.197577520846, 4157.08544120293, 4164.8542301019925, 4178.1643671265865, 4192.790360481964, 4194.8112334945, 4706.694064885772, 4814.350062741555, 4857.611391088775, 4860.781264843091, 4897.2654101885, 1328.973420531584, 1344.4218985836185, 1347.0907137682025, 1356.6811799227173, 1358.2868993778147, 858.9570521474026, 912.0560220172223, 913.977150571241, 914.9496777677158, 936.639393833379, 4485.6860019798305, 4527.864164075083, 4552.544745525487, 4573.336866154389, 4579.816807327737, 5322.578226906749, 5330.573554115662, 5335.786568537293, 5347.2781174541715, 5355.491228333302, 4167.99936030707, 4394.1651086293505, 4509.88725281864, 4510.639148868083, 4567.383403240587, 2841.076650088688, 2866.140157952606, 2866.312085099561, 2866.8423236721765, 2877.136858942309, 5052.469490703496, 5063.8480845574695, 5071.822486212965, 5072.416672082684, 5089.694618322913, 4978.4025916890205, 5189.273501709764, 5191.195364718475, 5460.890782184413, 5498.340199176125, 4771.284307353924, 4813.470518243061, 4814.737073575414, 4822.493638168012, 4823.1800593898415, 5049.242613607841, 5067.150490071023, 5075.416327122428, 5086.64393492452, 5091.770870310919, 4847.95152048475, 4952.306677065286, 4955.324124972474, 4957.504249575014, 4968.900621987612, 4785.258284965836, 4800.3279540863605, 4811.663183577116, 4820.39745031871, 4820.662553621213, 3553.129483276614, 3584.802835844054, 3598.9280214395044, 3599.0, 3611.566612006576, 4871.585915197106, 5022.924656130088, 5046.646734728533, 5048.864005598858, 5080.441151473409, 4872.8792377754025, 4877.585405240594, 4882.90234195307, 4893.314935908905, 4906.828261010847, 4900.632452566, 4907.405093344606, 4923.719832843558, 4928.310036594808, 4939.777710002986, 4591.793369298322, 4728.17256980034, 4835.830745924017, 4843.152448321459, 4851.272309153655, 4767.737774422301, 4901.872569309007, 4917.704937703749, 4927.901441971068, 4959.454460009324, 4763.237881938873, 4795.683884503975, 4796.208625576741, 4807.78364973584, 4832.903341933212], "value": 3748.1053421825377, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.907126614833, 640.2285246278238, 644.477972841998, 648.627039452315, 722.7036914864907, 12429.0, 12469.501219951202, 12471.376431178427, 12638.304893230868, 12712.427940742666, 11162.218644694854, 11512.13658975576, 11562.942185289061, 11573.710657233574, 11610.245334053281, 489.8628384052453, 515.724087613127, 696.3002182806273, 712.98755766811, 735.2977906099688, 534.398801348483, 536.3911959925493, 537.8063896997078, 538.2249560632696, 538.7898719499399, 563.2930672006646, 565.366789196101, 567.4240645744572, 567.6480582039121, 568.3691102875825, 531.3464438740332, 541.4071591607203, 549.8790266141442, 550.8595308196425, 551.2007588995972, 412.8101073506191, 412.87820093072617, 413.21076743420224, 414.2191968140078, 418.2262813794486, 11134.61028863994, 11141.777164456747, 11162.665120046411, 11164.497597608128, 11166.665000050012, 11372.0, 11703.53185872564, 11901.107416943763, 12124.454399552042, 12206.755864882653, 9761.609535618567, 9914.355566888129, 10033.397996120184, 10041.347312424668, 10074.244431667654, 3254.6094468663673, 3307.7188438982716, 3313.3870234006786, 3323.651016643266, 3413.74396920232, 42517.78741106306, 42524.78737606323, 42808.78595607033, 42846.0, 42951.0, 9213.257714961135, 9288.814223715488, 9350.485723285148, 9573.377730447499, 9659.565319560637, 14676.706465870837, 15085.924570377078, 15117.697646047018, 15160.09039457636, 15256.694866102616, 6409.775657851953, 6471.417572418411, 6580.078788969548, 6781.186257649114, 6860.3535151563265, 3753.6862098265387, 3774.150816066418, 3831.65515103642, 3909.9609003909595, 3993.221321842213, 2563.3976015636313, 2597.8311409758307, 2648.4041090754426, 2648.748368904951, 2654.8407095574325, 390.9941350879739, 472.43072098121684, 488.59202565857197, 490.31356101458243, 498.6534358620796, 509.9133147364957, 573.2891214893585, 578.9536837053025, 603.435787538644, 608.6256951974503, 7869.213078692085, 8053.436259461899, 8078.515289082674, 8139.79650508742, 8314.70898518561, 576.9134629805479, 590.5541316306247, 593.9910901336483, 596.740417918207, 601.2994860986988, 3827.3110840049058, 3853.402722578005, 3906.941395879064, 3939.4287828265315, 3940.3498422760495, 54277.0, 54557.45442545603, 54675.179872301946, 58135.70932145397, 58292.708536457045, 50765.0, 50824.74587627038, 50973.0, 52834.471655282956, 53138.46861531335, 47326.526734732215, 48464.757676212095, 48559.75720121377, 48932.75533622309, 49423.25865112026, 7804.0, 7884.684612615431, 7886.881696774668, 8042.155573664681, 8128.512289262663, 30802.691973079982, 31771.523427149074, 31980.840095800304, 32010.839945800122, 32595.674043259263, 39718.404223936086, 40232.396514051725, 43402.348964764926, 43410.78294608507, 43824.78087609669, 9195.770105747142, 9441.575129119343, 9548.761280968032, 9600.615975360908, 9612.279079068958, 11009.834852477059, 11021.614243501439, 11271.0, 11405.429728513707, 11683.337411548453, 10094.54574544162, 10112.544935477774, 10140.594376224868, 10363.689089327332, 10834.891651083388, 9825.0, 10151.0, 10363.015513526203, 10442.68671939843, 10599.62901298451, 10490.31812932157, 10756.677299681021, 11031.117510598971, 11110.944445277722, 11168.274062186258, 8639.870401944101, 8738.475691458532, 8997.0, 9039.502827344559, 9358.230095557208, 8450.366222533212, 8479.788005299917, 9022.909770902206, 9076.364654474124, 9136.26909847224], "value": 5526.016862026008, "unit": "score"}], "name": "dromaeo_css", "value": 5834.91385487462, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 109.0, 114.0, 114.0, 114.0, 103.0, 112.0, 112.0, 114.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 132.0, 161.0, 187.0, 139.0, 159.0, 166.0, 132.0, 199.0, 142.0], "value": 158.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 160.0, 162.0, 161.0, 161.0, 162.0, 161.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 112.0, 112.0, 74.0, 74.0, 73.0, 114.0, 102.0, 72.0], "value": 88.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 150.0, 94.0, 94.0, 94.0, 144.0, 93.0, 95.0, 143.0], "value": 109.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 147.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 66.0, 66.0, 67.0, 82.0, 64.0, 62.0], "value": 66.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 66.0, 66.0, 66.0, 64.0, 65.0, 65.0, 68.0, 65.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 77.0, 85.0, 79.0, 80.0, 79.0, 81.0, 79.0, 81.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 135.0, 132.0, 137.0, 134.0, 132.0, 134.0, 167.0, 134.0], "value": 138.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [215.0, 221.0, 225.0, 214.0, 219.0, 219.0, 216.0, 215.0, 217.0, 217.0], "value": 217.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 73.0, 74.0, 74.0, 74.0, 74.0, 73.0, 74.0, 74.0], "value": 74.0, "unit": "score"}], "name": "kraken", "value": 1627.9000000000003, "alertThreshold": 2.0}]} 09:27:12 INFO - Return code: 0 09:27:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:27:12 ERROR - # TBPL SUCCESS # 09:27:12 INFO - Running post-action listener: _resource_record_post_action 09:27:12 INFO - Running post-run listener: _resource_record_post_run 09:27:13 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 518381568; Read time: 12; Write time: 395096 09:27:13 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234708992; Read time: 0; Write time: 224524 09:27:13 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283672576; Read time: 12; Write time: 170572 09:27:13 INFO - Running post-run listener: _upload_blobber_files 09:27:13 INFO - Blob upload gear active. 09:27:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:27:13 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 09:27:13 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:27:13 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:27:14 INFO - (blobuploader) - INFO - Open directory for files ... 09:27:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:27:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:27:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:15 INFO - (blobuploader) - INFO - Done attempting. 09:27:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:27:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:27:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:15 INFO - (blobuploader) - INFO - Done attempting. 09:27:15 INFO - (blobuploader) - INFO - Iteration through files over. 09:27:15 INFO - Return code: 0 09:27:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:27:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:27:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3b72c560976f815b1fcb8524ba7d2eb5473e231aaebf9f45ed61933c73cb0467c1fc8367a6b797f3f5f05a74b5943f3cac850958cd619a8ff6bc8c8f2d255bb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/75c087b02208c44d24ea7b6490a1effd39bdfe81604b876e28b0813c1b939783771446c9ed3dcf97838e884cae1d7c2da560f7295c3882c7613d82a0ee1105c0"} 09:27:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:27:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:27:15 INFO - Contents: 09:27:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3b72c560976f815b1fcb8524ba7d2eb5473e231aaebf9f45ed61933c73cb0467c1fc8367a6b797f3f5f05a74b5943f3cac850958cd619a8ff6bc8c8f2d255bb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/75c087b02208c44d24ea7b6490a1effd39bdfe81604b876e28b0813c1b939783771446c9ed3dcf97838e884cae1d7c2da560f7295c3882c7613d82a0ee1105c0"} 09:27:15 INFO - Running post-run listener: copy_logs_to_upload_dir 09:27:15 INFO - Copying logs to upload dir... 09:27:15 INFO - mkdir: /builds/slave/test/build/upload/logs 09:27:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.570140 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-04-26 09:27:14.852497) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 09:27:14.856070) ========= 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=ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727 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-Non-PGO/sha512/3b72c560976f815b1fcb8524ba7d2eb5473e231aaebf9f45ed61933c73cb0467c1fc8367a6b797f3f5f05a74b5943f3cac850958cd619a8ff6bc8c8f2d255bb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/75c087b02208c44d24ea7b6490a1effd39bdfe81604b876e28b0813c1b939783771446c9ed3dcf97838e884cae1d7c2da560f7295c3882c7613d82a0ee1105c0"} build_url:https://queue.taskcluster.net/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011303 build_url: 'https://queue.taskcluster.net/v1/task/fwAUbEz0QR2xfmWO6vk2Jw/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-Non-PGO/sha512/3b72c560976f815b1fcb8524ba7d2eb5473e231aaebf9f45ed61933c73cb0467c1fc8367a6b797f3f5f05a74b5943f3cac850958cd619a8ff6bc8c8f2d255bb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/75c087b02208c44d24ea7b6490a1effd39bdfe81604b876e28b0813c1b939783771446c9ed3dcf97838e884cae1d7c2da560f7295c3882c7613d82a0ee1105c0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 09:27:14.880631) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 09:27:14.880955) ========= 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=ed14279e38b05397cf4aa391000001a4-1461685285.501594-539273727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 09:27:14.966018) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 09:27:14.966359) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 09:27:14.966765) ========= ========= Total master_lag: 0.17 =========