builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-024 starttime: 1461564400.83 results: success (0) buildid: 20160424203035 builduid: b9c18d5e79154e3582c0ed4d36867f73 revision: 3cfe3ba1ed21c24a62d442f5645afb0fb6ed5e59 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:40.832211) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:40.832685) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:40.832967) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:40.849385) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:40.849665) ========= 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=7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-24 23:06:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.3M=0s 2016-04-24 23:06:41 (69.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.070739 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:40.939890) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:40.940184) ========= 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=7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019987 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:40.998087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-24 23:06:40.998401) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3cfe3ba1ed21c24a62d442f5645afb0fb6ed5e59 --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 3cfe3ba1ed21c24a62d442f5645afb0fb6ed5e59 --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=7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-24 23:06:41,875 truncating revision to first 12 chars 2016-04-24 23:06:41,875 Setting DEBUG logging. 2016-04-24 23:06:41,875 attempt 1/10 2016-04-24 23:06:41,875 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3cfe3ba1ed21?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-24 23:06:43,012 unpacking tar archive at: fx-team-3cfe3ba1ed21/testing/mozharness/ program finished with exit code 0 elapsedTime=1.424508 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-24 23:06:42.435538) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:42.435846) ========= script_repo_revision: 3cfe3ba1ed21c24a62d442f5645afb0fb6ed5e59 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:42.436236) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:42.436492) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:42.444765) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-04-24 23:06:42.445052) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:06:43 INFO - MultiFileLogger online at 20160424 23:06:43 in /builds/slave/test 23:06:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:06:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:06:43 INFO - {'append_to_log': False, 23:06:43 INFO - 'base_work_dir': '/builds/slave/test', 23:06:43 INFO - 'blob_upload_branch': 'Fx-Team', 23:06:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:06:43 INFO - 'branch': 'Fx-Team', 23:06:43 INFO - 'buildbot_json_path': 'buildprops.json', 23:06:43 INFO - 'config_files': ('talos/linux_config.py',), 23:06:43 INFO - 'default_actions': ('clobber', 23:06:43 INFO - 'read-buildbot-config', 23:06:43 INFO - 'download-and-extract', 23:06:43 INFO - 'populate-webroot', 23:06:43 INFO - 'create-virtualenv', 23:06:43 INFO - 'install', 23:06:43 INFO - 'run-tests'), 23:06:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:06:43 INFO - 'download_minidump_stackwalk': True, 23:06:43 INFO - 'download_symbols': 'ondemand', 23:06:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:06:43 INFO - 'tooltool.py': '/tools/tooltool.py', 23:06:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:06:43 INFO - '/tools/misc-python/virtualenv.py')}, 23:06:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:06:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:06:43 INFO - 'installer_path': 'installer.exe', 23:06:43 INFO - 'log_level': 'info', 23:06:43 INFO - 'log_name': 'talos', 23:06:43 INFO - 'log_to_console': True, 23:06:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:06:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:06:43 INFO - 'opt_config_files': (), 23:06:43 INFO - 'pip_index': False, 23:06:43 INFO - 'sps_profile': False, 23:06:43 INFO - 'sps_profile_interval': 0, 23:06:43 INFO - 'suite': 'dromaeojs-e10s', 23:06:43 INFO - 'system_bits': '32', 23:06:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:06:43 INFO - 'title': 'talos-linux64-ix-024', 23:06:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:06:43 INFO - 'use_talos_json': True, 23:06:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:06:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:06:43 INFO - 'work_dir': 'build'} 23:06:43 INFO - ##### 23:06:43 INFO - ##### Running clobber step. 23:06:43 INFO - ##### 23:06:43 INFO - Running pre-action listener: _resource_record_pre_action 23:06:43 INFO - Running main action method: clobber 23:06:43 INFO - rmtree: /builds/slave/test/build 23:06:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:06:43 INFO - Running post-action listener: _resource_record_post_action 23:06:43 INFO - ##### 23:06:43 INFO - ##### Running read-buildbot-config step. 23:06:43 INFO - ##### 23:06:43 INFO - Running pre-action listener: _resource_record_pre_action 23:06:43 INFO - Running main action method: read_buildbot_config 23:06:43 INFO - Using buildbot properties: 23:06:43 INFO - { 23:06:43 INFO - "project": "", 23:06:43 INFO - "product": "firefox", 23:06:43 INFO - "script_repo_revision": "production", 23:06:43 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 23:06:43 INFO - "repository": "", 23:06:43 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 23:06:43 INFO - "buildid": "20160424203035", 23:06:43 INFO - "pgo_build": "True", 23:06:43 INFO - "basedir": "/builds/slave/test", 23:06:43 INFO - "buildnumber": 689, 23:06:43 INFO - "slavename": "talos-linux64-ix-024", 23:06:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:06:43 INFO - "platform": "ubuntu64_hw", 23:06:43 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:06:43 INFO - "branch": "fx-team", 23:06:43 INFO - "repo_path": "integration/fx-team", 23:06:43 INFO - "revision": "3cfe3ba1ed21c24a62d442f5645afb0fb6ed5e59", 23:06:43 INFO - "stage_platform": "linux64-pgo", 23:06:43 INFO - "builduid": "b9c18d5e79154e3582c0ed4d36867f73", 23:06:43 INFO - "slavebuilddir": "test" 23:06:43 INFO - } 23:06:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 23:06:43 INFO - Running post-action listener: _resource_record_post_action 23:06:43 INFO - ##### 23:06:43 INFO - ##### Running download-and-extract step. 23:06:43 INFO - ##### 23:06:43 INFO - Running pre-action listener: _resource_record_pre_action 23:06:43 INFO - Running main action method: download_and_extract 23:06:43 INFO - mkdir: /builds/slave/test/build/tests 23: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')]} 23:06:43 INFO - https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:06:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 23:06:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 23:06:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 23:06:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:06:49 INFO - Downloaded 1361 bytes. 23:06:49 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 23:06:49 INFO - Using the following test package requirements: 23:06:49 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 23:06:49 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:49 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:06:49 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:49 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:06:49 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:49 INFO - u'jsshell-linux-x86_64.zip'], 23:06:49 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:49 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:06:49 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 23:06:49 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:49 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:06:49 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:49 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:06:49 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:49 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:06:49 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:49 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:06:49 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:06:49 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')]} 23:06:49 INFO - https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:06:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 23:06:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 23:06:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 23:06:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:06:55 INFO - Downloaded 23857707 bytes. 23:06:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:06:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:06:56 INFO - Return code: 0 23:06:56 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23: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')]} 23:06:56 INFO - https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:06:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 23:06:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 23:06:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 23:06:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:07:02 INFO - Downloaded 23857707 bytes. 23:07:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:07:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:07:03 INFO - Return code: 0 23:07:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:03 INFO - https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:07:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 23:07:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 23:07:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 23:07:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:07:05 INFO - Downloaded 10840145 bytes. 23:07:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:07:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 23:07:05 INFO - Return code: 0 23: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')]} 23:07:05 INFO - https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:07:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 23:07:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 23:07:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 23:07:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:07:08 INFO - Downloaded 58331334 bytes. 23:07:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 23:07:08 INFO - mkdir: /builds/slave/test/properties 23:07:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:07:08 INFO - Writing to file /builds/slave/test/properties/build_url 23:07:08 INFO - Contents: 23:07:08 INFO - build_url:https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 23:07:08 INFO - Running post-action listener: _resource_record_post_action 23:07:08 INFO - Running post-action listener: set_extra_try_arguments 23:07:08 INFO - ##### 23:07:08 INFO - ##### Running populate-webroot step. 23:07:08 INFO - ##### 23:07:08 INFO - Running pre-action listener: _resource_record_pre_action 23:07:08 INFO - Running main action method: populate_webroot 23:07:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:07:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:07:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:07:08 INFO - '--tppagecycles', 23:07:08 INFO - '7'], 23:07:08 INFO - 'tests': ['tsvgm']}}, 23:07:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:07:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:07:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:07:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:07:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:07:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:07:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:08 INFO - 'talos_options': ['--disable-e10s'], 23:07:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:07:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:07:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:08 INFO - 'talos_options': ['--disable-e10s'], 23:07:08 INFO - 'tests': ['damp', 'tps']}, 23:07:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:08 INFO - 'tests': ['damp', 'tps']}, 23:07:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:07:09 INFO - 'tests': ['dromaeo_dom']}, 23:07:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:07:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:07:09 INFO - 'tests': ['a11yr', 23:07:09 INFO - 'ts_paint', 23:07:09 INFO - 'tpaint', 23:07:09 INFO - 'sessionrestore', 23:07:09 INFO - 'sessionrestore_no_auto_restore', 23:07:09 INFO - 'tabpaint']}, 23:07:09 INFO - 'other-e10s': {'tests': ['a11yr', 23:07:09 INFO - 'ts_paint', 23:07:09 INFO - 'tpaint', 23:07:09 INFO - 'sessionrestore', 23:07:09 INFO - 'sessionrestore_no_auto_restore', 23:07:09 INFO - 'tabpaint']}, 23:07:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:07:09 INFO - 'ts_paint', 23:07:09 INFO - 'tpaint', 23:07:09 INFO - 'sessionrestore', 23:07:09 INFO - 'sessionrestore_no_auto_restore', 23:07:09 INFO - 'tabpaint']}, 23:07:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:07:09 INFO - 'ts_paint', 23:07:09 INFO - 'tpaint', 23:07:09 INFO - 'sessionrestore', 23:07:09 INFO - 'sessionrestore_no_auto_restore', 23:07:09 INFO - 'tabpaint']}, 23:07:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:07:09 INFO - 'tests': ['a11yr', 23:07:09 INFO - 'ts_paint', 23:07:09 INFO - 'tpaint', 23:07:09 INFO - 'sessionrestore', 23:07:09 INFO - 'sessionrestore_no_auto_restore', 23:07:09 INFO - 'tabpaint']}, 23:07:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:07:09 INFO - 'tests': ['a11yr', 23:07:09 INFO - 'ts_paint', 23:07:09 INFO - 'tpaint', 23:07:09 INFO - 'sessionrestore', 23:07:09 INFO - 'sessionrestore_no_auto_restore', 23:07:09 INFO - 'tabpaint']}, 23:07:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:07:09 INFO - 'tests': ['tsvgx', 23:07:09 INFO - 'tsvgr_opacity', 23:07:09 INFO - 'tart', 23:07:09 INFO - 'tscrollx', 23:07:09 INFO - 'cart']}, 23:07:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:07:09 INFO - 'tsvgr_opacity', 23:07:09 INFO - 'tart', 23:07:09 INFO - 'tscrollx', 23:07:09 INFO - 'cart']}, 23:07:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:09 INFO - 'talos_options': ['--disable-e10s'], 23:07:09 INFO - 'tests': ['tp5o']}, 23:07:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:09 INFO - 'tests': ['tp5o']}, 23:07:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:09 INFO - 'talos_options': ['--disable-e10s', 23:07:09 INFO - '--xperf_path', 23:07:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:07:09 INFO - 'tests': ['tp5n']}, 23:07:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:09 INFO - 'talos_options': ['--xperf_path', 23:07:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:07:09 INFO - 'tests': ['tp5n']}}, 23:07:09 INFO - 'talos.zip': {'path': '', 23:07:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:07:09 INFO - Running post-action listener: _resource_record_post_action 23:07:09 INFO - ##### 23:07:09 INFO - ##### Running create-virtualenv step. 23:07:09 INFO - ##### 23:07:09 INFO - Running pre-action listener: _resource_record_pre_action 23:07:09 INFO - Running main action method: create_virtualenv 23:07:09 INFO - Creating virtualenv /builds/slave/test/build/venv 23:07:09 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 23:07:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:07:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:07:09 INFO - Using real prefix '/usr' 23:07:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:07:09 INFO - Installing distribute.............................................................................................................................................................................................done. 23:07:11 INFO - Installing pip.................done. 23:07:11 INFO - Return code: 0 23:07:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:07:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb4f539f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbd7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c640>, '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 0x2c4d540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4d9d0>, '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': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', '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 23:07:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:07:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:07:11 INFO - Using env: {'DISPLAY': ':0', 23:07:11 INFO - 'HOME': '/home/cltbld', 23:07:11 INFO - 'LANG': 'en_US.UTF-8', 23:07:11 INFO - 'LANGUAGE': 'en_US:en', 23:07:11 INFO - 'LOGNAME': 'cltbld', 23:07:11 INFO - 'MAIL': '/var/mail/cltbld', 23:07:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:11 INFO - 'MOZ_NO_REMOTE': '1', 23:07:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:11 INFO - 'NO_EM_RESTART': '1', 23:07:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:11 INFO - 'PWD': '/builds/slave/test', 23:07:11 INFO - 'SHELL': '/bin/bash', 23:07:11 INFO - 'SHLVL': '1', 23:07:11 INFO - 'TERM': 'linux', 23:07:11 INFO - 'TMOUT': '86400', 23:07:11 INFO - 'USER': 'cltbld', 23:07:11 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', 23:07:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:11 INFO - '_': '/tools/buildbot/bin/python'} 23:07:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:07:11 INFO - Cleaning up... 23:07:11 INFO - Return code: 0 23:07:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 23:07:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb4f539f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbd7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c640>, '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 0x2c4d540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4d9d0>, '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': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', '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 23:07:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 23:07:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 23:07:11 INFO - Using env: {'DISPLAY': ':0', 23:07:11 INFO - 'HOME': '/home/cltbld', 23:07:11 INFO - 'LANG': 'en_US.UTF-8', 23:07:11 INFO - 'LANGUAGE': 'en_US:en', 23:07:11 INFO - 'LOGNAME': 'cltbld', 23:07:11 INFO - 'MAIL': '/var/mail/cltbld', 23:07:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:11 INFO - 'MOZ_NO_REMOTE': '1', 23:07:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:11 INFO - 'NO_EM_RESTART': '1', 23:07:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:11 INFO - 'PWD': '/builds/slave/test', 23:07:11 INFO - 'SHELL': '/bin/bash', 23:07:11 INFO - 'SHLVL': '1', 23:07:11 INFO - 'TERM': 'linux', 23:07:11 INFO - 'TMOUT': '86400', 23:07:11 INFO - 'USER': 'cltbld', 23:07:11 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', 23:07:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:11 INFO - '_': '/tools/buildbot/bin/python'} 23:07:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:11 INFO - Downloading/unpacking psutil>=0.7.1 23: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 23: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 23: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 23: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 23: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 23: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 23:07:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:07:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:07:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:07:13 INFO - Installing collected packages: psutil 23:07:13 INFO - Running setup.py install for psutil 23:07:14 INFO - building 'psutil._psutil_linux' extension 23:07:14 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 23:07:14 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 23:07:14 INFO - building 'psutil._psutil_posix' extension 23:07:14 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 23:07:14 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 23:07:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:07:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:07:14 INFO - Successfully installed psutil 23:07:14 INFO - Cleaning up... 23:07:14 INFO - Return code: 0 23:07:14 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 23:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb4f539f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbd7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c640>, '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 0x2c4d540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4d9d0>, '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': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', '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 23:07:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 23:07:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 23:07:14 INFO - Using env: {'DISPLAY': ':0', 23:07:14 INFO - 'HOME': '/home/cltbld', 23:07:14 INFO - 'LANG': 'en_US.UTF-8', 23:07:14 INFO - 'LANGUAGE': 'en_US:en', 23:07:14 INFO - 'LOGNAME': 'cltbld', 23:07:14 INFO - 'MAIL': '/var/mail/cltbld', 23:07:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:14 INFO - 'MOZ_NO_REMOTE': '1', 23:07:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:14 INFO - 'NO_EM_RESTART': '1', 23:07:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:14 INFO - 'PWD': '/builds/slave/test', 23:07:14 INFO - 'SHELL': '/bin/bash', 23:07:14 INFO - 'SHLVL': '1', 23:07:14 INFO - 'TERM': 'linux', 23:07:14 INFO - 'TMOUT': '86400', 23:07:14 INFO - 'USER': 'cltbld', 23:07:14 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', 23:07:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:14 INFO - '_': '/tools/buildbot/bin/python'} 23:07:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:14 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 23: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 23: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 23: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 23: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 23: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 23: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 23:07:16 INFO - Downloading mozsystemmonitor-0.0.tar.gz 23:07:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:07:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 23:07:16 INFO - Installing collected packages: mozsystemmonitor 23:07:16 INFO - Running setup.py install for mozsystemmonitor 23:07:16 INFO - Successfully installed mozsystemmonitor 23:07:16 INFO - Cleaning up... 23:07:16 INFO - Return code: 0 23:07:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb4f539f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbd7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c640>, '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 0x2c4d540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4d9d0>, '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': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', '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 23:07:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:07:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:07:16 INFO - Using env: {'DISPLAY': ':0', 23:07:16 INFO - 'HOME': '/home/cltbld', 23:07:16 INFO - 'LANG': 'en_US.UTF-8', 23:07:16 INFO - 'LANGUAGE': 'en_US:en', 23:07:16 INFO - 'LOGNAME': 'cltbld', 23:07:16 INFO - 'MAIL': '/var/mail/cltbld', 23:07:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:16 INFO - 'MOZ_NO_REMOTE': '1', 23:07:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:16 INFO - 'NO_EM_RESTART': '1', 23:07:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:16 INFO - 'PWD': '/builds/slave/test', 23:07:16 INFO - 'SHELL': '/bin/bash', 23:07:16 INFO - 'SHLVL': '1', 23:07:16 INFO - 'TERM': 'linux', 23:07:16 INFO - 'TMOUT': '86400', 23:07:16 INFO - 'USER': 'cltbld', 23:07:16 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', 23:07:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:16 INFO - '_': '/tools/buildbot/bin/python'} 23:07:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:16 INFO - Downloading/unpacking blobuploader==1.2.4 23:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:18 INFO - Downloading blobuploader-1.2.4.tar.gz 23:07:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:07:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:07:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:19 INFO - Downloading docopt-0.6.1.tar.gz 23:07:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:07:19 INFO - Installing collected packages: blobuploader, requests, docopt 23:07:19 INFO - Running setup.py install for blobuploader 23:07:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:07:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:07:19 INFO - Running setup.py install for requests 23:07:20 INFO - Running setup.py install for docopt 23:07:20 INFO - Successfully installed blobuploader requests docopt 23:07:20 INFO - Cleaning up... 23:07:20 INFO - Return code: 0 23:07:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb4f539f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbd7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c640>, '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 0x2c4d540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4d9d0>, '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': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', '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 23:07:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:07:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:07:20 INFO - Using env: {'DISPLAY': ':0', 23:07:20 INFO - 'HOME': '/home/cltbld', 23:07:20 INFO - 'LANG': 'en_US.UTF-8', 23:07:20 INFO - 'LANGUAGE': 'en_US:en', 23:07:20 INFO - 'LOGNAME': 'cltbld', 23:07:20 INFO - 'MAIL': '/var/mail/cltbld', 23:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:20 INFO - 'MOZ_NO_REMOTE': '1', 23:07:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:20 INFO - 'NO_EM_RESTART': '1', 23:07:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:20 INFO - 'PWD': '/builds/slave/test', 23:07:20 INFO - 'SHELL': '/bin/bash', 23:07:20 INFO - 'SHLVL': '1', 23:07:20 INFO - 'TERM': 'linux', 23:07:20 INFO - 'TMOUT': '86400', 23:07:20 INFO - 'USER': 'cltbld', 23:07:20 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', 23:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:20 INFO - '_': '/tools/buildbot/bin/python'} 23:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:07:20 INFO - Running setup.py (path:/tmp/pip-Eongzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:07:20 INFO - Running setup.py (path:/tmp/pip-bmf2QE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:07:20 INFO - Running setup.py (path:/tmp/pip-goPa1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:07:20 INFO - Running setup.py (path:/tmp/pip-j0Mt_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:07:20 INFO - Running setup.py (path:/tmp/pip-vUlsG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:07:20 INFO - Running setup.py (path:/tmp/pip-BFiBIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:07:20 INFO - Running setup.py (path:/tmp/pip-EIxZgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:07:20 INFO - Running setup.py (path:/tmp/pip-IcgFvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:07:21 INFO - Running setup.py (path:/tmp/pip-OK9DlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:07:21 INFO - Running setup.py (path:/tmp/pip-Je1PDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:07:21 INFO - Running setup.py (path:/tmp/pip-kWFNsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:07:21 INFO - Running setup.py (path:/tmp/pip-kd6JyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:07:21 INFO - Running setup.py (path:/tmp/pip-DInbmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:07:21 INFO - Running setup.py (path:/tmp/pip-YN7kTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:21 INFO - Running setup.py (path:/tmp/pip-DjI0ZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:07:21 INFO - Running setup.py (path:/tmp/pip-vmglty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:07:21 INFO - Running setup.py (path:/tmp/pip-26EDeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:07:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:07:21 INFO - Running setup.py install for manifestparser 23:07:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:07:21 INFO - Running setup.py install for mozcrash 23:07:21 INFO - Running setup.py install for mozdebug 23:07:21 INFO - Running setup.py install for mozdevice 23:07:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:07:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:07:22 INFO - Running setup.py install for mozfile 23:07:22 INFO - Running setup.py install for mozhttpd 23:07:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:07:22 INFO - Running setup.py install for mozinfo 23:07:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:07:22 INFO - Running setup.py install for mozInstall 23:07:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:07:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:07:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:07:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:07:22 INFO - Running setup.py install for mozleak 23:07:22 INFO - Running setup.py install for mozlog 23:07:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:07:22 INFO - Running setup.py install for moznetwork 23:07:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:07:22 INFO - Running setup.py install for mozprocess 23:07:22 INFO - Running setup.py install for mozprofile 23:07:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:07:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:07:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:07:23 INFO - Running setup.py install for mozrunner 23:07:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:07:23 INFO - Running setup.py install for mozscreenshot 23:07:23 INFO - Running setup.py install for moztest 23:07:23 INFO - Running setup.py install for mozversion 23:07:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:07:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:07:23 INFO - Cleaning up... 23:07:23 INFO - Return code: 0 23:07:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb4f539f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbd7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c640>, '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 0x2c4d540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4d9d0>, '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': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', '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 23:07:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:07:23 INFO - Using env: {'DISPLAY': ':0', 23:07:23 INFO - 'HOME': '/home/cltbld', 23:07:23 INFO - 'LANG': 'en_US.UTF-8', 23:07:23 INFO - 'LANGUAGE': 'en_US:en', 23:07:23 INFO - 'LOGNAME': 'cltbld', 23:07:23 INFO - 'MAIL': '/var/mail/cltbld', 23:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:23 INFO - 'MOZ_NO_REMOTE': '1', 23:07:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:23 INFO - 'NO_EM_RESTART': '1', 23:07:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:23 INFO - 'PWD': '/builds/slave/test', 23:07:23 INFO - 'SHELL': '/bin/bash', 23:07:23 INFO - 'SHLVL': '1', 23:07:23 INFO - 'TERM': 'linux', 23:07:23 INFO - 'TMOUT': '86400', 23:07:23 INFO - 'USER': 'cltbld', 23:07:23 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', 23:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:23 INFO - '_': '/tools/buildbot/bin/python'} 23:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:07:23 INFO - Running setup.py (path:/tmp/pip-Htu8FF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:07:23 INFO - Running setup.py (path:/tmp/pip-1Gc5Kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:07:23 INFO - Running setup.py (path:/tmp/pip-lb_oJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:07:23 INFO - Running setup.py (path:/tmp/pip-RrElq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:07:23 INFO - Running setup.py (path:/tmp/pip-t0wgme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:07:24 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)) 23:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:07:24 INFO - Running setup.py (path:/tmp/pip-NUb7cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:07:24 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)) 23:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:07:24 INFO - Running setup.py (path:/tmp/pip-889Oqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:07:24 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)) 23:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:07:24 INFO - Running setup.py (path:/tmp/pip-inTUmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:07:24 INFO - Running setup.py (path:/tmp/pip-_YMmJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:07:24 INFO - Running setup.py (path:/tmp/pip-gRBe5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:07:24 INFO - Running setup.py (path:/tmp/pip-uA82eF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:07:24 INFO - Running setup.py (path:/tmp/pip-5AX0cr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:07:24 INFO - Running setup.py (path:/tmp/pip-RcbmE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:07:24 INFO - Running setup.py (path:/tmp/pip-10uiDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:24 INFO - Running setup.py (path:/tmp/pip-wym8h0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:07:24 INFO - Running setup.py (path:/tmp/pip-soI75b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:07:24 INFO - Running setup.py (path:/tmp/pip-59yJnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:07:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:26 INFO - Downloading blessings-1.6.tar.gz 23:07:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:07:26 INFO - Installing collected packages: blessings 23:07:26 INFO - Running setup.py install for blessings 23:07:26 INFO - Successfully installed blessings 23:07:26 INFO - Cleaning up... 23:07:27 INFO - Return code: 0 23:07:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:07:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:07:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:07:27 INFO - Reading from file tmpfile_stdout 23:07:27 INFO - Current package versions: 23:07:27 INFO - argparse == 1.2.1 23:07:27 INFO - blessings == 1.6 23:07:27 INFO - blobuploader == 1.2.4 23:07:27 INFO - docopt == 0.6.1 23:07:27 INFO - manifestparser == 1.1 23:07:27 INFO - mozInstall == 1.12 23:07:27 INFO - mozcrash == 0.17 23:07:27 INFO - mozdebug == 0.1 23:07:27 INFO - mozdevice == 0.48 23:07:27 INFO - mozfile == 1.2 23:07:27 INFO - mozhttpd == 0.7 23:07:27 INFO - mozinfo == 0.9 23:07:27 INFO - mozleak == 0.1 23:07:27 INFO - mozlog == 3.1 23:07:27 INFO - moznetwork == 0.27 23:07:27 INFO - mozprocess == 0.22 23:07:27 INFO - mozprofile == 0.28 23:07:27 INFO - mozrunner == 6.11 23:07:27 INFO - mozscreenshot == 0.1 23:07:27 INFO - mozsystemmonitor == 0.0 23:07:27 INFO - moztest == 0.7 23:07:27 INFO - mozversion == 1.4 23:07:27 INFO - psutil == 3.1.1 23:07:27 INFO - requests == 1.2.3 23:07:27 INFO - wsgiref == 0.1.2 23:07:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb4f539f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbd7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c640>, '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 0x2c4d540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4d9d0>, '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': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', '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 23:07:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:07:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:07:27 INFO - Using env: {'DISPLAY': ':0', 23:07:27 INFO - 'HOME': '/home/cltbld', 23:07:27 INFO - 'LANG': 'en_US.UTF-8', 23:07:27 INFO - 'LANGUAGE': 'en_US:en', 23:07:27 INFO - 'LOGNAME': 'cltbld', 23:07:27 INFO - 'MAIL': '/var/mail/cltbld', 23:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:27 INFO - 'MOZ_NO_REMOTE': '1', 23:07:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:27 INFO - 'NO_EM_RESTART': '1', 23:07:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:27 INFO - 'PWD': '/builds/slave/test', 23:07:27 INFO - 'SHELL': '/bin/bash', 23:07:27 INFO - 'SHLVL': '1', 23:07:27 INFO - 'TERM': 'linux', 23:07:27 INFO - 'TMOUT': '86400', 23:07:27 INFO - 'USER': 'cltbld', 23:07:27 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', 23:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:27 INFO - '_': '/tools/buildbot/bin/python'} 23:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:07:27 INFO - Cleaning up... 23:07:27 INFO - Return code: 0 23:07:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb4f539f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbd7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c640>, '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 0x2c4d540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4d9d0>, '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': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', '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 23:07:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:07:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:07:27 INFO - Using env: {'DISPLAY': ':0', 23:07:27 INFO - 'HOME': '/home/cltbld', 23:07:27 INFO - 'LANG': 'en_US.UTF-8', 23:07:27 INFO - 'LANGUAGE': 'en_US:en', 23:07:27 INFO - 'LOGNAME': 'cltbld', 23:07:27 INFO - 'MAIL': '/var/mail/cltbld', 23:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:27 INFO - 'MOZ_NO_REMOTE': '1', 23:07:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:27 INFO - 'NO_EM_RESTART': '1', 23:07:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:27 INFO - 'PWD': '/builds/slave/test', 23:07:27 INFO - 'SHELL': '/bin/bash', 23:07:27 INFO - 'SHLVL': '1', 23:07:27 INFO - 'TERM': 'linux', 23:07:27 INFO - 'TMOUT': '86400', 23:07:27 INFO - 'USER': 'cltbld', 23:07:27 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', 23:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:27 INFO - '_': '/tools/buildbot/bin/python'} 23:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:27 INFO - Downloading/unpacking jsonschema 23:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:07:29 INFO - Downloading/unpacking functools32 (from jsonschema) 23:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:29 INFO - Downloading functools32-3.2.3-2.tar.gz 23:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:07:29 INFO - warning: no files found matching '*.txt' 23:07:29 INFO - no previously-included directories found matching 'build' 23:07:29 INFO - no previously-included directories found matching 'dist' 23:07:29 INFO - no previously-included directories found matching '.git*' 23:07:29 INFO - Installing collected packages: jsonschema, functools32 23:07:29 INFO - Running setup.py install for functools32 23:07:29 INFO - warning: no files found matching '*.txt' 23:07:29 INFO - no previously-included directories found matching 'build' 23:07:29 INFO - no previously-included directories found matching 'dist' 23:07:29 INFO - no previously-included directories found matching '.git*' 23:07:29 INFO - Successfully installed jsonschema functools32 23:07:29 INFO - Cleaning up... 23:07:29 INFO - Return code: 0 23:07:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:07:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb4f539f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbd7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4c640>, '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 0x2c4d540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4d9d0>, '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': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', '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 23:07:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 23:07:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:07:29 INFO - Using env: {'DISPLAY': ':0', 23:07:29 INFO - 'HOME': '/home/cltbld', 23:07:29 INFO - 'LANG': 'en_US.UTF-8', 23:07:29 INFO - 'LANGUAGE': 'en_US:en', 23:07:29 INFO - 'LOGNAME': 'cltbld', 23:07:29 INFO - 'MAIL': '/var/mail/cltbld', 23:07:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:29 INFO - 'MOZ_NO_REMOTE': '1', 23:07:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:29 INFO - 'NO_EM_RESTART': '1', 23:07:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:29 INFO - 'PWD': '/builds/slave/test', 23:07:29 INFO - 'SHELL': '/bin/bash', 23:07:29 INFO - 'SHLVL': '1', 23:07:29 INFO - 'TERM': 'linux', 23:07:29 INFO - 'TMOUT': '86400', 23:07:29 INFO - 'USER': 'cltbld', 23:07:29 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', 23:07:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:29 INFO - '_': '/tools/buildbot/bin/python'} 23:07:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:30 INFO - Downloading/unpacking flake8 23:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:07:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:07:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:07:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:07:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:07:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:07:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:07:32 INFO - no previously-included directories found matching 'docs/_build' 23:07:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:07:32 INFO - Running setup.py install for pep8 23:07:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:07:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:07:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:07:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:07:32 INFO - no previously-included directories found matching 'docs/_build' 23:07:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:07:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:07:32 INFO - Cleaning up... 23:07:32 INFO - Return code: 0 23:07:32 INFO - Running post-action listener: _resource_record_post_action 23:07:32 INFO - Running post-action listener: _start_resource_monitoring 23:07:33 INFO - Starting resource monitoring. 23:07:33 INFO - ##### 23:07:33 INFO - ##### Running install step. 23:07:33 INFO - ##### 23:07:33 INFO - Running pre-action listener: _resource_record_pre_action 23:07:33 INFO - Running main action method: install 23:07:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:07:33 INFO - Reading from file tmpfile_stdout 23:07:33 INFO - Detecting whether we're running mozinstall >=1.0... 23:07:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:07:33 INFO - Reading from file tmpfile_stdout 23:07:33 INFO - Output received: 23:07:33 INFO - Usage: mozinstall [options] installer 23:07:33 INFO - Options: 23:07:33 INFO - -h, --help show this help message and exit 23:07:33 INFO - -d DEST, --destination=DEST 23:07:33 INFO - Directory to install application into. [default: 23:07:33 INFO - "/builds/slave/test"] 23:07:33 INFO - --app=APP Application being installed. [default: firefox] 23:07:33 INFO - mkdir: /builds/slave/test/build/application 23:07:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:07:49 INFO - Reading from file tmpfile_stdout 23:07:49 INFO - Output received: 23:07:49 INFO - /builds/slave/test/build/application/firefox/firefox 23:07:49 INFO - Running post-action listener: _resource_record_post_action 23:07:49 INFO - ##### 23:07:49 INFO - ##### Running run-tests step. 23:07:49 INFO - ##### 23:07:49 INFO - Running pre-action listener: _resource_record_pre_action 23:07:49 INFO - Running main action method: run_tests 23:07:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:07:49 INFO - Python 2.7.3 23:07:49 INFO - Return code: 0 23:07:49 INFO - grabbing minidump binary from tooltool 23:07:49 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')]} 23:07:49 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 0x2c4c640>, '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 0x2c4d540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4d9d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:07:49 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 23:07:49 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 23:07:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:07:49 INFO - Return code: 0 23:07:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:07:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:07:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:07:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:07:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:07:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:07:50 INFO - Return code: 0 23:07:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 23:07:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 23:07:50 INFO - Using env: {'DISPLAY': ':0', 23:07:50 INFO - 'HOME': '/home/cltbld', 23:07:50 INFO - 'LANG': 'en_US.UTF-8', 23:07:50 INFO - 'LANGUAGE': 'en_US:en', 23:07:50 INFO - 'LOGNAME': 'cltbld', 23:07:50 INFO - 'MAIL': '/var/mail/cltbld', 23:07:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:07:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:50 INFO - 'MOZ_NO_REMOTE': '1', 23:07:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:07:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:50 INFO - 'NO_EM_RESTART': '1', 23:07:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:50 INFO - 'PWD': '/builds/slave/test', 23:07:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:07:50 INFO - 'SHELL': '/bin/bash', 23:07:50 INFO - 'SHLVL': '1', 23:07:50 INFO - 'TERM': 'linux', 23:07:50 INFO - 'TMOUT': '86400', 23:07:50 INFO - 'USER': 'cltbld', 23:07:50 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502', 23:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:50 INFO - '_': '/tools/buildbot/bin/python'} 23:07:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 23:07:51 INFO - mozversion application_buildid: 20160424203035 23:07:51 INFO - mozversion application_changeset: 3cfe3ba1ed21c24a62d442f5645afb0fb6ed5e59 23:07:51 INFO - mozversion application_display_name: Nightly 23:07:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:07:51 INFO - mozversion application_name: Firefox 23:07:51 INFO - mozversion application_remotingname: firefox 23:07:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:07:51 INFO - mozversion application_vendor: Mozilla 23:07:51 INFO - mozversion application_version: 48.0a1 23:07:51 INFO - mozversion platform_buildid: 20160424203035 23:07:51 INFO - mozversion platform_changeset: 3cfe3ba1ed21c24a62d442f5645afb0fb6ed5e59 23:07:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:07:51 INFO - mozversion platform_version: 48.0a1 23:07:51 INFO - using testdate: 1461564471 23:07:51 INFO - actual date: 1461564471 23:07:51 INFO - starting webserver on 'localhost:55998' 23:07:51 INFO - SUITE-START | Running 2 tests 23:07:51 INFO - TEST-START | dromaeo_css 23:07:51 INFO - operating with platform_type : linux_ 23:07:51 INFO - Initialising browser for dromaeo_css test... 23:07:51 INFO - TEST-INFO | started process 28540 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyKXfAz/profile http://localhost:55998/getInfo.html) 23:07:54 INFO - PROCESS | 28540 | 23:07:54 INFO - PROCESS | 28540 | (process:28609): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:07:55 INFO - PROCESS | 28540 | __metrics Screen width/height:1600/1200 23:07:55 INFO - PROCESS | 28540 | colorDepth:24 23:07:55 INFO - PROCESS | 28540 | Browser inner width/height: 1024/697 23:07:55 INFO - PROCESS | 28540 | __metrics 23:07:56 ERROR - PROCESS | 28540 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 23:07:57 INFO - TEST-INFO | 28540: exit 0 23:07:57 INFO - Browser initialized. 23:07:57 INFO - Running cycle 1/1 for dromaeo_css test... 23:07:57 INFO - TEST-INFO | started process 28660 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyKXfAz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:07:58 INFO - PROCESS | 28660 | 23:07:58 INFO - PROCESS | 28660 | (process:28721): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:08:08 INFO - PROCESS | 28660 | RSS: Main: 164790272 23:08:08 INFO - PROCESS | 28660 | 23:08:58 INFO - PROCESS | 28660 | 1461564538561 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] 23:11:06 INFO - PROCESS | 28660 | Cycle 1(1): loaded http://localhost:55998/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55998/tests/dromaeo/cssquery-ext.html) 23:11:07 INFO - PROCESS | 28660 | RSS: Main: 170184704 23:11:07 INFO - PROCESS | 28660 | 23:14:09 INFO - PROCESS | 28660 | Cycle 1(1): loaded http://localhost:55998/tests/dromaeo/cssquery-ext.html (next: http://localhost:55998/tests/dromaeo/cssquery-jquery.html) 23:14:09 INFO - PROCESS | 28660 | RSS: Main: 166756352 23:14:09 INFO - PROCESS | 28660 | 23:17:11 INFO - PROCESS | 28660 | Cycle 1(1): loaded http://localhost:55998/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55998/tests/dromaeo/cssquery-mootools.html) 23:17:11 INFO - PROCESS | 28660 | RSS: Main: 167432192 23:17:11 INFO - PROCESS | 28660 | 23:20:13 INFO - PROCESS | 28660 | Cycle 1(1): loaded http://localhost:55998/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55998/tests/dromaeo/cssquery-prototype.html) 23:20:14 INFO - PROCESS | 28660 | RSS: Main: 165183488 23:20:14 INFO - PROCESS | 28660 | 23:23:16 INFO - PROCESS | 28660 | Cycle 1(1): loaded http://localhost:55998/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55998/tests/dromaeo/cssquery-yui.html) 23:23:17 INFO - PROCESS | 28660 | RSS: Main: 166047744 23:23:17 INFO - PROCESS | 28660 | 23:26:19 INFO - PROCESS | 28660 | Cycle 1(1): loaded http://localhost:55998/tests/dromaeo/cssquery-yui.html (next: http://localhost:55998/tests/dromaeo/cssquery-dojo.html) 23:26:19 INFO - PROCESS | 28660 | RSS: Main: 168144896 23:26:19 INFO - PROCESS | 28660 | 23:26:19 INFO - PROCESS | 28660 | __start_tp_report 23:26:19 INFO - PROCESS | 28660 | _x_x_mozilla_page_load 23:26:19 INFO - PROCESS | 28660 | _x_x_mozilla_page_load_details 23:26:19 INFO - PROCESS | 28660 | |i|pagename|runs| 23:26:19 INFO - PROCESS | 28660 | |0;dojo.html;3131.545925840753;3167.1923659466834;3175.444297247981;3177.38041081989;3195.1852277669204;5300.867478313044;5319.654222475537;5320.042392369377;5328.653637513559;5397.352317721869;5279.41926388097;5311.203319502077;5376.892462150755;5387.568994480433;5504.614676972614;4886.9755651221785;4901.926471102936;4939.580135688453;4953.108440480712;5006.04885071837;757.7479913510796;4792.976035119819;4827.61379089672;4887.169181239198;4910.361652985125;4871.902561948759;4892.094962431962;4898.167311557026;4900.068986892479;4923.433805112422;4909.484504127051;4915.9754201229125;4929.901401971976;4930.309756634074;4976.129177393977;4785.569298763093;4833.419989601235;4900.411950565919;4942.975285123552;4973.055119527278;5339.733013349317;5371.248025276464;5380.757865896005;5418.756155972991;5461.781528738846;5654.151877218409;5769.624974376652;5780.219670344533;5792.247007888947;5823.213866128103;5714.628549144334;5727.284089488814;5742.368339482654;5763.106718458645;5790.7683692652245;5426.5116139547235;5449.782008719647;5462.6995515246645;5479.04116779562;5485.945140548583;5715.571332150105;5940.34656187819;5961.582689211714;5964.552658550626;6015.54883383748;5631.690257035821;5644.520215781705;5651.802186923438;5654.85862853432;5677.943220567824;5687.544996400278;5708.743106560215;5714.399988001283;5724.484796368347;5729.9713501432225;5568.192612071227;5682.488576028095;5685.431456854364;5725.312962444534;5823.475887170175;5740.311162660424;5743.195952566644;5746.88506229873;5769.711514424262;5777.653340799566;4224.8732538023905;4310.784460777011;4355.107203023373;4371.956280437218;4401.955980440219;3805.0867791729784;3900.7854567998465;3929.6856251500385;3938.0351813805228;3954.0114971257185;5373.838784836461;5518.887235763421;5570.805021824298;5572.136318870582;5619.971900140555;5735.1970724098655;5774.162746401748;5797.536197104221;5814.9709251454315;5831.941680583224;5443.047466693313;5465.863353416196;5507.614466987273;5524.502794748492;5550.611457198038;4922.729249891218;4928.186849169919;4945.703257804544;4946.826861059845;5003.049420610072;5971.253593300838;5971.9701401493585;5983.222181116514;5987.550933679904;5996.580239383201;5657.20799088128;5778.248827652546;5875.911861321998;5885.793997210077;5899.852503687443;5913.46778789911;5946.524278057832;5947.732352044255;5951.226340575698;5961;5581.967336042845;5677.602567820213;5682.232898558807;5682.3465301489205;5688.260526131577;5784.94215057844;5791.942080579309;5813.854653633693;5820.70896455184;5876.676782776988;5639.943600563942;5686.8578285541535;5687.886242275049;5693.658380497018;5702.201691763239;5516.420775818481;5617.43825617435;5656.858578535405;5691.8861622768145;5700.828975130753;5495.945040549544;5504.0643090675485;5511.53151982086;5519.034169020486;5553.250311207935;5563.248961390161;5567;5602.9353924891875;5626.746796394254;5640.971795140998;5426.782928682808;5508.862278443071;5540.030494663337;5606.103023516218;5678.20505129274 23:26:19 INFO - PROCESS | 28660 | |1;ext.html;575.4619430832172;637.1366797988728;651.0592194279267;659.9109120268762;685.4413652872911;36178.63821361786;37491.62508374915;37512.249755004916;37543.81228093859;37593.436098458544;41751.16497670045;42591.57408425915;42744.786276068655;42806.7859660702;42893.57106428935;8707.08575599564;8862.24670902974;8866.024737278894;8869.37914345996;8874.378793484459;8692.13078692132;8782.12178782123;8786.16531429513;8789.25291350236;8794.912050879506;8731.039585645574;8768.60541275641;8777.517236551987;8797.912020879805;8805.427647202943;8685.0880657531;8700.347473939448;8787.121287871225;8793.824123517557;8820.073892241351;8592.183742544448;8776.385653004292;8779.956100219459;8784.253338466238;8795.164459376349;31182.688173118433;38757.22485550302;39156.412653810214;39476.407853882214;39675.80162099171;3351.2962277921424;3397.3205358928312;3518.2084031093054;3573.749837511375;3585.0858031202138;27229.86385068062;27508.312292192855;28429.431411371657;28737.42525149506;28867.711322886713;4093.488313960755;4098.886507293137;4124.216398884202;4193.245215861144;4193.51774545928;234507;234858;236404.99999999828;237567;239104;31788.841055795037;32003.199920002186;32141;32883.83558082194;33791.66208337934;31488.685113148575;31545.211369715482;31681;31719.365612688078;31813.52279715806;14368.856311436752;14517.346719397652;14567.198804065878;14577.125372477685;14592.927035364755;11793.882061179278;11796.94101529487;11815.409229538489;11819.763604727857;11833.053355731521;7553.433492487976;7574.659140338698;7579.431542634325;7808.726694565663;7913.841723165505;2849.829010259391;2869.2826793301674;2886.9133925982255;2938.1479370982174;2974.256435891027;1024.257413375312;1036.429963520061;1080.935143891369;1108.2242430298822;1125.4935279124427;31270.215010222728;32390;32616.836915815744;32696.346073078406;33609.83195084009;1078.369154044877;1163.4299193395175;1186.7982442984542;1218.5734992752466;1236.5301185549433;15941.840581594268;16677.749833752503;16766.41317553904;16915.154242287837;17037.829621703626;443848;448723.2946846845;450891;454103;454207;57004;58573.414265857646;58791.41208587945;58857;59023.40976590265;1325.337331334352;1326.3368315842079;1335.6527302901122;1415.8938079643863;1475.5204558518524;29690;30917.83575149718;31787.20531986719;32197.195070123435;32367.51448728318;51070;59573.404265956786;61740.69129654323;61763.38236617756;61819.381806181365;417891;418884;421664;423337;424813;43034.35448468213;43043.3543496854;43879.122417552106;43882.34176487419;44368.78496542823;48694.75652621714;48801.75599121982;48890.75554622347;49453.75273123755;49818.75090624524;41383;42142;42289.365659514515;42981.57018429776;43123.784381077894;40943;42146.009983759985;42547;42802.57197427986;42919.7854010728;37081.443778343884;40604.3909341366;41361.1727765449;41788.79105604452;42795.358069629605;34547;35315.57278759289;35545.46681799827;35828.462573060904;36629.81685091664;27020.68255942246;28169;28368.71631283661;28577.571336430385;28625.713742862306 23:26:19 INFO - PROCESS | 28660 | |2;jquery.html;370.47022757456836;370.81088644791157;375.64313901793304;382.76077451592755;386.97291189616703;17740.492058175056;18508.167132479037;19469.610607787836;19574.314898978522;19620.60758784827;11424.314541127518;11933;12090.153689241735;12094.697632559168;12102.213356131846;4433.467983841927;5081.872953176182;5200.245964335169;5221.112410890139;5268.789248430058;5190.351206099238;5223.37319521656;5248.343957005374;5251.763670634811;5285.920711189336;5366.195070739382;5650.51970582501;5656.519195868356;5657.632253903504;5681.744321505522;5471.552921659369;5587.525060369873;5625;5648.604597678143;6176.81469555914;5480.095784195603;5500.28496295479;5523.420040895729;5548.667079975174;5558.110702287655;12929.48282068716;13440;21756.782432175634;22657.433564160863;22690.43273918165;2186.835987300959;2221.911123555056;2253.290213582716;2315.9421014474606;2561.961570576424;9449.574769135406;9796.90203097967;9910.653127140515;9969.302148849583;10319.79360412795;1939.7963213862624;2025.4126303372152;2036.358547057672;2053.0761157479046;2092.299079808266;76286.23713762847;77426.6128669353;77461;77495.99999999943;79133.60433197855;10247.84628230577;10385.688429347132;10408.739781505372;10444.321119127384;10516.26386152962;14288;14296.857031429552;14350.928245358706;14395.136291822524;14401.063930844462;7607.125180604191;7872;7949.495908788713;7985.961824962717;8003.239692229229;7685.5004424712215;7790.727324543614;7792.805179870548;7847.176046515148;7865.292123708894;4385.495667998157;4409.625181859577;4458.977705111453;4488.730676159441;4501.819927202875;1872.9344472943383;1883.2579863626365;1926.9036548172535;1931.9517012074812;1951.7657881054365;873.9781505462287;966.2415004221792;1002.1181360402799;1033.6898930320874;1040.2770074798093;18297.359592414203;18542.81457185411;18619.813801862078;18720.719189212174;18872.33946811855;985.9556319965619;1037.1443559063803;1043.0299821166236;1049.1134990932649;1073.7369344510646;9891.604335826487;10307.196240657855;10437.739056523647;10557.788844223072;10587.947060264649;502863;506717;512980;522851;526714;90987;95487;95907.52046239724;95942.52028739951;96161;995.144176008632;1025.5026312238558;1025.789713108811;1030.062642994871;1033.9483025848829;16302.836971630131;16395.83604163991;16461.423850165185;16477.91761041187;16640.75038874442;36520.45219321659;36922.44616330811;37482.43776343302;37837.43243851399;38027.42958855675;48896.51103489062;48904.51095488999;52982.4701752993;54254.728726356116;54607.358374538606;7946.40401969843;7976.7208147714555;8070.314023308085;8176.550289734122;8180.550069746221;8706.085860984505;11870.82193767077;13018.80471792905;13091.279979601213;13168.209907405204;11200.159988001096;11304.49129789145;11361.602343917923;11379;11383.089352851586;11322.207445478738;11345.773084538097;11396.202265841312;11413.429328533373;11553.24903881245;11495.8275625864;11655.358955257543;11681.182317238052;11694.88305116938;11771.823422648496;7838.176991415819;7869.921300786918;7870.763877083467;7997.7600671979935;8481.194286542768;10458.790824183627;11508.942455288005;11605.30368177912;11729;11872.109591780823 23:26:19 INFO - PROCESS | 28660 | |3;mootools.html;1489.8733607643346;1495.8288428531512;1617.9433719819808;1750.0899532243243;1768.3368736723721;7529.247075292468;8099.433039687225;8166.101728809834;8452.154784521545;8461.619227134779;8191.590420478967;8213.137620549845;8306.46008009479;8308.750737477885;8327.084020757711;2285.5086156476395;2314.3519814451897;2317.559663663907;2319.4575607221177;2320.8491448055906;2247.1573160064977;2254.368776742515;2269.693591365161;2272.534130503243;2286.4626812699003;2138.3000514590935;2149.570085982802;2156.6657168138963;2267.954640907181;2315.224399826052;2211.635080211763;2222.411061068818;2228.064213030515;2229.9219527316545;2234.4637287051155;2206.6359050756764;2215.6344203206613;2218.134927378324;2220.6335954567476;2229.498362868358;8142.063662678749;8151.796205094861;8184.713535026305;8218.054923683734;8219.136990616018;4987.925181122286;5107.284980102751;5162.664426812245;5173.611979101544;5196.922046169273;3001.3397052648384;3163.6678148794504;3225.048610659861;3259.5110733389943;3272.2310257089566;1050.558765318568;1057.5082586597193;1058.4125810175349;1059.046857827961;1075.704181350127;11282.153838462149;11322.830157547643;11337.829932551018;11358.375289359083;11405.37270450125;4345.804438800262;4459.643228541709;4586.403767510203;4608.262677971508;4706.788194531255;9680.322377433513;9695.757606059906;9728;9750.561224744763;9768.755781105529;5116.104681680691;5122.820701275437;5150.768215430315;5173.094708426011;5185.170372740344;4102;4583.954160458419;4709.411323584552;4714.504976977437;4733.715977041388;3903.7072219583647;4042.130941847517;4069.9796501017304;4107.54816970133;4150.543440221573;1265.2029221590524;1280.257450678604;1285.9421326040351;1290.393515047926;1295.8963282937343;986.6497393425374;997.1125698128672;997.5808663989795;1002.5137808163036;1003.8795344558699;5729.656220626775;5766.221560089418;5938.317093534212;6038.486728628028;6052.334243233241;918.9540522973824;958.6644674364056;979.1872745621259;988.8220120378257;1024.7643042100358;4049.3318602430268;4068.023674318142;4083.9795801020805;4135.545090040093;4226.049138943713;9986.750331241778;10292.794144117075;10371.94814025925;10378.688639340831;10438.582456701799;10471.214658900613;10494.42280674555;10529.789404211873;10559.208059395576;10560;7563.886541701879;7852.411069169835;7868.488548244346;7878.369730421668;8061.677532898736;5833.125031245348;6069.908951365821;6135.079738039151;6170.444659980623;6180.876382472416;9636.566354513923;9681.757956051155;9696.127348538665;9704.514774261117;9869.555869986012;9480.857787133336;9717.80564388694;9972.950135249277;10109.292349535755;10146.289759716748;6907.551009184387;7365.373943214887;7491.625418729151;7580.9620951894885;7660.157382687788;8101.392395570238;8124.146964568635;8130.39022073335;8149.062857771432;8178.059523154675;7235.927640723526;7436.479446438913;7507.474476786572;7565;7606.277403646644;7292.124945006421;7321.377682897013;7350.558966462029;7371.6314184289495;7407.370373518312;6994.475414344046;6998.720051197895;7043.929560704328;7051.858962820817;7056.682449289691;4965.503449655005;4966.602671786176;5058.190689489664;5101.0818052750865;5202.687838729688;5109.31024311728;5379.892402152013;5387.81142660005;5393.217983392307;5405.945940540544 23:26:19 INFO - PROCESS | 28660 | |4;prototype.html;292.1789770744209;305.4791580355494;316.95245713143026;317.43496576094543;317.61727118821824;5913.793017244398;6065.787697430591;6066.454019138277;6176.104464852599;6177.752889884399;6159.076138579216;6178.907316390247;6190.876182476348;6193.132961385399;6198.473129783963;4559;4565.794539245733;4602.907941841161;4608.976955115237;4614.953850461503;4300.6774491913075;4561.726296422209;4563.315502674593;4564.863054108366;4577.29051996941;4478.596926276635;4507.594316511513;4508.413906192191;4512.796924138422;4515.938754392731;4495.483019452739;4507.143642707908;4512.871782054486;4526.411566496367;4546.227141385956;3676.2096149327754;3855.9421608675893;3881.592432794544;3907.2966865964113;3922.5685174631053;5842.211301474289;5852.76588936442;5868.325142608612;5898.823035308905;5903.144044113623;5186.559142472895;5187.559057480119;5212.556932660728;5214.3053507610175;5215.791368345262;5921.822345329647;6317.873642527124;6344.49244060474;6353.364663533656;6360.109584658151;4270.416398079179;4272.9359059614135;4278.336857787017;4289.571042895716;4294.935575966363;6311.810645680637;6629.93370066298;6660.70026848793;6670;6683.228944329757;4347.630496393625;4355.194289056535;4377.715448495837;4378.452693413324;4386.166628340605;4404.823807047682;4408.184485870124;4433.955660443355;4444.533324000933;4445.888527868033;5099.6685215460875;5176.559992400613;5178.275041494194;5184.818531351385;5195.454477279907;6142.416470435302;6370.140031095742;6390.137331460193;6393.200849893763;6397;1513.3643869574805;1520.0803513874168;1533.8312785593575;1535.332130523226;1538.730722123624;968.9680490277832;969.6945462179391;978.7406337320616;984.6602921991902;1003.4531180506641;6159.476444502178;6161.599496032743;6167.352427995085;6174.969125154345;6183.598066125687;6567.737290508326;6573.4223786291905;6579.80260592183;6580.967095164589;6644.4020038196795;5915.40845915405;6176.474999624992;6226.315105338409;6239.344868788802;6247.344028876993;3500.2474467989505;3540.3714291425717;3544.1848374873407;3556.968479141072;3574.1064733816547;6516.6415847128865;6806.18325800907;6808.8978665320055;6822.863542729117;6853.588784672936;6681.498887583451;6714.597124172566;6754.527183097136;6761;6765.357291057342;6600.405963463215;6736.865262694817;6777.457803375718;6825.385715285549;6843.281455447106;6529.967350163219;6548.8035358939305;6563.343665633399;6583.604983700993;6606.405423511907;6268.341824108403;6326.398992095744;6439.259485159079;6444.9355506444335;6518.869622607616;6826.658667066726;6842.863142737217;6845.349691779472;6849.383555480031;6877.793666190022;4314;4314.784260787011;4323;4329.6536277098385;4331.068820203672;6334.714937827901;6388.584741991755;6545.247296560766;6642.966785166043;6684.799456016327;6417.71120299597;6426.807195784134;6443.83890402725;6453.322401147811;6458.22501299847;6216.720247588779;6389.712462939272;6397.584157029778;6398.968005159944;6402.615843049246;6260.248770147611;6280.623162610258;6309.085182648582;6339.922213223856;6350.555461117862;6420.967895160681;6451.2903580607035;6467.773627923;6468.870622587616;6517.608943463408;6049.848753781191;6290.811275661737;6338.619682819046;6346.428821406095;6356.618602883842 23:26:19 INFO - PROCESS | 28660 | |5;yui.html;825.7564018614509;831.1812864328635;836.3267569606467;842.9873551896719;848.5163456829606;13158.407871645775;13161.144525605841;13290.667733306673;13424.664383390407;13561.25413102279;10708.089812365939;12030.69923251917;12036.157468977175;12044.217125886811;12054.095942804302;782.2216894190288;801.0027515742909;808.4664121679692;810.6757297081156;812.1107387410776;803.1727320859495;805.730080423059;806.379088102161;806.4113197365926;809.4981111710748;843.7131375332385;848.8472075026493;850.0437008365589;850.4174640371334;851.0255757158051;840.8444437779;844.0883845446933;845.8646616541355;849.2059923971107;850.1498501498502;612.494691879197;615.8306465721918;617.197643064015;618.3352895637208;619.6498978077371;13232.801507977389;13264.535741249107;13294.601161965156;13324.533641322507;13341.66645833862;13358.198508089448;13462.259575723332;13489.325533723373;13582.117162384513;13629.59111226655;10778.19163562736;11167.497462614201;11221.270617409842;11258.155638327076;11800.114991375682;3835.3479908415356;3879.12719638082;3913.3934240192816;3935.7048221383225;3988.660963818079;57216.71391643057;57759;58227.708861455845;58414.415855841326;58539.70730146365;10124.489592670141;10181.18550515957;10377.9481102594;10429.37423754562;10458.947705261426;17293.913530432266;17294.82705172932;17392.913035434744;17399.73900391495;17487.300507979537;7535.1334596521;7552.962235188789;7666.923330766733;7722.575258360844;7887;4315.093830295673;4349.325854492558;4361.694681372337;4370.2352088384405;4423.668224883082;3046.299351149248;3092.4433601951414;3093.706097920694;3096.551000105017;3111.626604807392;580.663215335113;592.3070008090544;596.2308621877816;597.9641221526722;604.7339170764938;672.6939242644585;681.8261343357412;719.0652152202221;727.5052963984541;727.788941207044;8193.344532437392;8502.74491765248;8620.965484141943;8684.739457816275;8926.821463570823;572.9054705973551;673.4840418946671;682.3926705232348;704.527966262595;737.0344848248811;4067.7152599317765;4097.672186225095;4201.747895126302;4230.365445183185;4231.449911511545;59332;61456.385436145065;61604;64389;65245.67377163084;51901;52719.736401318514;52996.205056923405;53275.467245327825;54334.45665543373;47622.28566571504;49148.75425622849;50195.74902125466;50957.490425095275;51202.74398627983;8359.623816928375;8403.831923361377;8406.411551191359;8899.421537600036;8948.73153805387;28120.43759124847;32644.51033234456;33459.665403346626;33888.66111338855;33908.321833562695;42982;47218;47362;47797;48192.27711584259;10674.89325106739;10919.181061420266;11046.723831904266;11103.278286911325;11251.381174035507;11794.646160615195;12024.75950480968;12075.456604452997;12205.20666156697;12211.206271592673;11631.823838391834;11688.532458701557;11754.177207595729;11980.64058078259;11988.160828742253;11760.117991150526;11785.705357366134;11799.174057816213;11836.349000805038;11911.583094591648;10658.786824263316;11430.199886008233;11467.885321147016;11504.424778761197;11630.069594432256;9646.758831029281;9815.36200146988;9823.705288841345;9905.702828915144;9937.701868943943;9329.942316210774;9700.563474643517;9713;9825.4104753715;10052.798944021224 23:26:19 INFO - PROCESS | 28660 | __end_tp_report 23:26:19 INFO - PROCESS | 28660 | __start_cc_report 23:26:19 INFO - PROCESS | 28660 | _x_x_mozilla_cycle_collect,332 23:26:19 INFO - PROCESS | 28660 | __end_cc_report 23:26:19 INFO - PROCESS | 28660 | __startTimestamp1461565579905__endTimestamp 23:26:19 INFO - PROCESS | 28660 | 23:26:19 INFO - PROCESS | 28660 | ------- Summary: start ------- 23:26:19 INFO - PROCESS | 28660 | Number of tests: 6 23:26:19 INFO - PROCESS | 28660 | 23:26:19 INFO - PROCESS | 28660 | [#0] dojo.html Cycles:165 Average:5329.98 Median:5568.19 stddev:705.46 (12.7%) stddev-sans-first:686.22 23:26:19 INFO - PROCESS | 28660 | Values: 3131.5 3167.2 3175.4 3177.4 3195.2 5300.9 5319.7 5320.0 5328.7 5397.4 5279.4 5311.2 5376.9 5387.6 5504.6 4887.0 4901.9 4939.6 4953.1 5006.0 757.7 4793.0 4827.6 4887.2 4910.4 4871.9 4892.1 4898.2 4900.1 4923.4 4909.5 4916.0 4929.9 4930.3 4976.1 4785.6 4833.4 4900.4 4943.0 4973.1 5339.7 5371.2 5380.8 5418.8 5461.8 5654.2 5769.6 5780.2 5792.2 5823.2 5714.6 5727.3 5742.4 5763.1 5790.8 5426.5 5449.8 5462.7 5479.0 5485.9 5715.6 5940.3 5961.6 5964.6 6015.5 5631.7 5644.5 5651.8 5654.9 5677.9 5687.5 5708.7 5714.4 5724.5 5730.0 5568.2 5682.5 5685.4 5725.3 5823.5 5740.3 5743.2 5746.9 5769.7 5777.7 4224.9 4310.8 4355.1 4372.0 4402.0 3805.1 3900.8 3929.7 3938.0 3954.0 5373.8 5518.9 5570.8 5572.1 5620.0 5735.2 5774.2 5797.5 5815.0 5831.9 5443.0 5465.9 5507.6 5524.5 5550.6 4922.7 4928.2 4945.7 4946.8 5003.0 5971.3 5972.0 5983.2 5987.6 5996.6 5657.2 5778.2 5875.9 5885.8 5899.9 5913.5 5946.5 5947.7 5951.2 5961.0 5582.0 5677.6 5682.2 5682.3 5688.3 5784.9 5791.9 5813.9 5820.7 5876.7 5639.9 5686.9 5687.9 5693.7 5702.2 5516.4 5617.4 5656.9 5691.9 5700.8 5495.9 5504.1 5511.5 5519.0 5553.3 5563.2 5567.0 5602.9 5626.7 5641.0 5426.8 5508.9 5540.0 5606.1 5678.2 23:26:19 INFO - PROCESS | 28660 | 23:26:19 INFO - PROCESS | 28660 | [#1] ext.html Cycles:180 Average:52521.20 Median:40140.10 stddev:101135.64 (252.0%) stddev-sans-first:101344.15 23:26:19 INFO - PROCESS | 28660 | Values: 575.5 637.1 651.1 659.9 685.4 36178.6 37491.6 37512.2 37543.8 37593.4 41751.2 42591.6 42744.8 42806.8 42893.6 8707.1 8862.2 8866.0 8869.4 8874.4 8692.1 8782.1 8786.2 8789.3 8794.9 8731.0 8768.6 8777.5 8797.9 8805.4 8685.1 8700.3 8787.1 8793.8 8820.1 8592.2 8776.4 8780.0 8784.3 8795.2 31182.7 38757.2 39156.4 39476.4 39675.8 3351.3 3397.3 3518.2 3573.7 3585.1 27229.9 27508.3 28429.4 28737.4 28867.7 4093.5 4098.9 4124.2 4193.2 4193.5 234507.0 234858.0 236405.0 237567.0 239104.0 31788.8 32003.2 32141.0 32883.8 33791.7 31488.7 31545.2 31681.0 31719.4 31813.5 14368.9 14517.3 14567.2 14577.1 14592.9 11793.9 11796.9 11815.4 11819.8 11833.1 7553.4 7574.7 7579.4 7808.7 7913.8 2849.8 2869.3 2886.9 2938.1 2974.3 1024.3 1036.4 1080.9 1108.2 1125.5 31270.2 32390.0 32616.8 32696.3 33609.8 1078.4 1163.4 1186.8 1218.6 1236.5 15941.8 16677.7 16766.4 16915.2 17037.8 443848.0 448723.3 450891.0 454103.0 454207.0 57004.0 58573.4 58791.4 58857.0 59023.4 1325.3 1326.3 1335.7 1415.9 1475.5 29690.0 30917.8 31787.2 32197.2 32367.5 51070.0 59573.4 61740.7 61763.4 61819.4 417891.0 418884.0 421664.0 423337.0 424813.0 43034.4 43043.4 43879.1 43882.3 44368.8 48694.8 48801.8 48890.8 49453.8 49818.8 41383.0 42142.0 42289.4 42981.6 43123.8 40943.0 42146.0 42547.0 42802.6 42919.8 37081.4 40604.4 41361.2 41788.8 42795.4 34547.0 35315.6 35545.5 35828.5 36629.8 27020.7 28169.0 28368.7 28577.6 28625.7 23:26:19 INFO - PROCESS | 28660 | 23:26:19 INFO - PROCESS | 28660 | [#2] jquery.html Cycles:180 Average:28795.06 Median:36721.45 stddev:84745.55 (230.8%) stddev-sans-first:84956.40 23:26:19 INFO - PROCESS | 28660 | Values: 370.5 370.8 375.6 382.8 387.0 17740.5 18508.2 19469.6 19574.3 19620.6 11424.3 11933.0 12090.2 12094.7 12102.2 4433.5 5081.9 5200.2 5221.1 5268.8 5190.4 5223.4 5248.3 5251.8 5285.9 5366.2 5650.5 5656.5 5657.6 5681.7 5471.6 5587.5 5625.0 5648.6 6176.8 5480.1 5500.3 5523.4 5548.7 5558.1 12929.5 13440.0 21756.8 22657.4 22690.4 2186.8 2221.9 2253.3 2315.9 2562.0 9449.6 9796.9 9910.7 9969.3 10319.8 1939.8 2025.4 2036.4 2053.1 2092.3 76286.2 77426.6 77461.0 77496.0 79133.6 10247.8 10385.7 10408.7 10444.3 10516.3 14288.0 14296.9 14350.9 14395.1 14401.1 7607.1 7872.0 7949.5 7986.0 8003.2 7685.5 7790.7 7792.8 7847.2 7865.3 4385.5 4409.6 4459.0 4488.7 4501.8 1872.9 1883.3 1926.9 1932.0 1951.8 874.0 966.2 1002.1 1033.7 1040.3 18297.4 18542.8 18619.8 18720.7 18872.3 986.0 1037.1 1043.0 1049.1 1073.7 9891.6 10307.2 10437.7 10557.8 10587.9 502863.0 506717.0 512980.0 522851.0 526714.0 90987.0 95487.0 95907.5 95942.5 96161.0 995.1 1025.5 1025.8 1030.1 1033.9 16302.8 16395.8 16461.4 16477.9 16640.8 36520.5 36922.4 37482.4 37837.4 38027.4 48896.5 48904.5 52982.5 54254.7 54607.4 7946.4 7976.7 8070.3 8176.6 8180.6 8706.1 11870.8 13018.8 13091.3 13168.2 11200.2 11304.5 11361.6 11379.0 11383.1 11322.2 11345.8 11396.2 11413.4 11553.2 11495.8 11655.4 11681.2 11694.9 11771.8 7838.2 7869.9 7870.8 7997.8 8481.2 10458.8 11508.9 11605.3 11729.0 11872.1 23:26:19 INFO - PROCESS | 28660 | 23:26:19 INFO - PROCESS | 28660 | [#3] mootools.html Cycles:180 Average:5599.75 Median:5112.71 stddev:3096.61 (60.6%) stddev-sans-first:3089.90 23:26:19 INFO - PROCESS | 28660 | Values: 1489.9 1495.8 1617.9 1750.1 1768.3 7529.2 8099.4 8166.1 8452.2 8461.6 8191.6 8213.1 8306.5 8308.8 8327.1 2285.5 2314.4 2317.6 2319.5 2320.8 2247.2 2254.4 2269.7 2272.5 2286.5 2138.3 2149.6 2156.7 2268.0 2315.2 2211.6 2222.4 2228.1 2229.9 2234.5 2206.6 2215.6 2218.1 2220.6 2229.5 8142.1 8151.8 8184.7 8218.1 8219.1 4987.9 5107.3 5162.7 5173.6 5196.9 3001.3 3163.7 3225.0 3259.5 3272.2 1050.6 1057.5 1058.4 1059.0 1075.7 11282.2 11322.8 11337.8 11358.4 11405.4 4345.8 4459.6 4586.4 4608.3 4706.8 9680.3 9695.8 9728.0 9750.6 9768.8 5116.1 5122.8 5150.8 5173.1 5185.2 4102.0 4584.0 4709.4 4714.5 4733.7 3903.7 4042.1 4070.0 4107.5 4150.5 1265.2 1280.3 1285.9 1290.4 1295.9 986.6 997.1 997.6 1002.5 1003.9 5729.7 5766.2 5938.3 6038.5 6052.3 919.0 958.7 979.2 988.8 1024.8 4049.3 4068.0 4084.0 4135.5 4226.0 9986.8 10292.8 10371.9 10378.7 10438.6 10471.2 10494.4 10529.8 10559.2 10560.0 7563.9 7852.4 7868.5 7878.4 8061.7 5833.1 6069.9 6135.1 6170.4 6180.9 9636.6 9681.8 9696.1 9704.5 9869.6 9480.9 9717.8 9973.0 10109.3 10146.3 6907.6 7365.4 7491.6 7581.0 7660.2 8101.4 8124.1 8130.4 8149.1 8178.1 7235.9 7436.5 7507.5 7565.0 7606.3 7292.1 7321.4 7350.6 7371.6 7407.4 6994.5 6998.7 7043.9 7051.9 7056.7 4965.5 4966.6 5058.2 5101.1 5202.7 5109.3 5379.9 5387.8 5393.2 5405.9 23:26:19 INFO - PROCESS | 28660 | 23:26:19 INFO - PROCESS | 28660 | [#4] prototype.html Cycles:180 Average:5299.79 Median:6178.33 stddev:1641.65 (26.6%) stddev-sans-first:1602.65 23:26:19 INFO - PROCESS | 28660 | Values: 292.2 305.5 317.0 317.4 317.6 5913.8 6065.8 6066.5 6176.1 6177.8 6159.1 6178.9 6190.9 6193.1 6198.5 4559.0 4565.8 4602.9 4609.0 4615.0 4300.7 4561.7 4563.3 4564.9 4577.3 4478.6 4507.6 4508.4 4512.8 4515.9 4495.5 4507.1 4512.9 4526.4 4546.2 3676.2 3855.9 3881.6 3907.3 3922.6 5842.2 5852.8 5868.3 5898.8 5903.1 5186.6 5187.6 5212.6 5214.3 5215.8 5921.8 6317.9 6344.5 6353.4 6360.1 4270.4 4272.9 4278.3 4289.6 4294.9 6311.8 6629.9 6660.7 6670.0 6683.2 4347.6 4355.2 4377.7 4378.5 4386.2 4404.8 4408.2 4434.0 4444.5 4445.9 5099.7 5176.6 5178.3 5184.8 5195.5 6142.4 6370.1 6390.1 6393.2 6397.0 1513.4 1520.1 1533.8 1535.3 1538.7 969.0 969.7 978.7 984.7 1003.5 6159.5 6161.6 6167.4 6175.0 6183.6 6567.7 6573.4 6579.8 6581.0 6644.4 5915.4 6176.5 6226.3 6239.3 6247.3 3500.2 3540.4 3544.2 3557.0 3574.1 6516.6 6806.2 6808.9 6822.9 6853.6 6681.5 6714.6 6754.5 6761.0 6765.4 6600.4 6736.9 6777.5 6825.4 6843.3 6530.0 6548.8 6563.3 6583.6 6606.4 6268.3 6326.4 6439.3 6444.9 6518.9 6826.7 6842.9 6845.3 6849.4 6877.8 4314.0 4314.8 4323.0 4329.7 4331.1 6334.7 6388.6 6545.2 6643.0 6684.8 6417.7 6426.8 6443.8 6453.3 6458.2 6216.7 6389.7 6397.6 6399.0 6402.6 6260.2 6280.6 6309.1 6339.9 6350.6 6421.0 6451.3 6467.8 6468.9 6517.6 6049.8 6290.8 6338.6 6346.4 6356.6 23:26:19 INFO - PROCESS | 28660 | 23:26:19 INFO - PROCESS | 28660 | [#5] yui.html Cycles:180 Average:14387.36 Median:47994.64 stddev:17290.60 (36.0%) stddev-sans-first:17309.12 23:26:19 INFO - PROCESS | 28660 | Values: 825.8 831.2 836.3 843.0 848.5 13158.4 13161.1 13290.7 13424.7 13561.3 10708.1 12030.7 12036.2 12044.2 12054.1 782.2 801.0 808.5 810.7 812.1 803.2 805.7 806.4 806.4 809.5 843.7 848.8 850.0 850.4 851.0 840.8 844.1 845.9 849.2 850.1 612.5 615.8 617.2 618.3 619.6 13232.8 13264.5 13294.6 13324.5 13341.7 13358.2 13462.3 13489.3 13582.1 13629.6 10778.2 11167.5 11221.3 11258.2 11800.1 3835.3 3879.1 3913.4 3935.7 3988.7 57216.7 57759.0 58227.7 58414.4 58539.7 10124.5 10181.2 10377.9 10429.4 10458.9 17293.9 17294.8 17392.9 17399.7 17487.3 7535.1 7553.0 7666.9 7722.6 7887.0 4315.1 4349.3 4361.7 4370.2 4423.7 3046.3 3092.4 3093.7 3096.6 3111.6 580.7 592.3 596.2 598.0 604.7 672.7 681.8 719.1 727.5 727.8 8193.3 8502.7 8621.0 8684.7 8926.8 572.9 673.5 682.4 704.5 737.0 4067.7 4097.7 4201.7 4230.4 4231.4 59332.0 61456.4 61604.0 64389.0 65245.7 51901.0 52719.7 52996.2 53275.5 54334.5 47622.3 49148.8 50195.7 50957.5 51202.7 8359.6 8403.8 8406.4 8899.4 8948.7 28120.4 32644.5 33459.7 33888.7 33908.3 42982.0 47218.0 47362.0 47797.0 48192.3 10674.9 10919.2 11046.7 11103.3 11251.4 11794.6 12024.8 12075.5 12205.2 12211.2 11631.8 11688.5 11754.2 11980.6 11988.2 11760.1 11785.7 11799.2 11836.3 11911.6 10658.8 11430.2 11467.9 11504.4 11630.1 9646.8 9815.4 9823.7 9905.7 9937.7 9329.9 9700.6 9713.0 9825.4 10052.8 23:26:19 INFO - PROCESS | 28660 | -------- Summary: end -------- 23:26:19 INFO - PROCESS | 28660 | 23:26:20 INFO - TEST-INFO | 28660: exit 0 23:26:20 INFO - TEST-OK | dromaeo_css | took 1109574ms 23:26:20 INFO - TEST-START | kraken 23:26:20 INFO - operating with platform_type : linux_ 23:26:20 INFO - Initialising browser for kraken test... 23:26:20 INFO - TEST-INFO | started process 28895 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyV1ABe/profile http://localhost:55998/getInfo.html) 23:26:24 INFO - PROCESS | 28895 | 23:26:24 INFO - PROCESS | 28895 | (process:28964): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:26:25 INFO - PROCESS | 28895 | __metrics Screen width/height:1600/1200 23:26:25 INFO - PROCESS | 28895 | colorDepth:24 23:26:25 INFO - PROCESS | 28895 | Browser inner width/height: 1024/697 23:26:25 INFO - PROCESS | 28895 | __metrics 23:26:25 ERROR - PROCESS | 28895 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 23:26:26 INFO - TEST-INFO | 28895: exit 0 23:26:26 INFO - Browser initialized. 23:26:26 INFO - Running cycle 1/1 for kraken test... 23:26:26 INFO - TEST-INFO | started process 29015 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyV1ABe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:26:28 INFO - PROCESS | 29015 | 23:26:28 INFO - PROCESS | 29015 | (process:29076): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:26:38 INFO - PROCESS | 29015 | RSS: Main: 164540416 23:26:38 INFO - PROCESS | 29015 | 23:26:40 INFO - PROCESS | 29015 | Cycle 1(1): loaded http://localhost:55998/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55998/tests/kraken/driver.html?testName=audio-beat-detection) 23:26:41 INFO - PROCESS | 29015 | RSS: Main: 169123840 23:26:41 INFO - PROCESS | 29015 | 23:26:44 INFO - PROCESS | 29015 | Cycle 1(1): loaded http://localhost:55998/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55998/tests/kraken/driver.html?testName=audio-dft) 23:26:44 INFO - PROCESS | 29015 | RSS: Main: 172449792 23:26:44 INFO - PROCESS | 29015 | 23:26:47 INFO - PROCESS | 29015 | Cycle 1(1): loaded http://localhost:55998/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55998/tests/kraken/driver.html?testName=audio-fft) 23:26:48 INFO - PROCESS | 29015 | RSS: Main: 162951168 23:26:48 INFO - PROCESS | 29015 | 23:26:50 INFO - PROCESS | 29015 | Cycle 1(1): loaded http://localhost:55998/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55998/tests/kraken/driver.html?testName=audio-oscillator) 23:26:51 INFO - PROCESS | 29015 | RSS: Main: 162979840 23:26:51 INFO - PROCESS | 29015 | 23:26:53 INFO - PROCESS | 29015 | Cycle 1(1): loaded http://localhost:55998/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55998/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:26:54 INFO - PROCESS | 29015 | RSS: Main: 163848192 23:26:54 INFO - PROCESS | 29015 | 23:26:57 INFO - PROCESS | 29015 | Cycle 1(1): loaded http://localhost:55998/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55998/tests/kraken/driver.html?testName=imaging-darkroom) 23:26:57 INFO - PROCESS | 29015 | RSS: Main: 164409344 23:26:57 INFO - PROCESS | 29015 | 23:27:01 INFO - PROCESS | 29015 | Cycle 1(1): loaded http://localhost:55998/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55998/tests/kraken/driver.html?testName=imaging-desaturate) 23:27:01 INFO - PROCESS | 29015 | RSS: Main: 164573184 23:27:01 INFO - PROCESS | 29015 | 23:27:04 INFO - PROCESS | 29015 | Cycle 1(1): loaded http://localhost:55998/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55998/tests/kraken/driver.html?testName=json-parse-financial) 23:27:05 INFO - PROCESS | 29015 | RSS: Main: 167489536 23:27:05 INFO - PROCESS | 29015 | 23:27:06 INFO - PROCESS | 29015 | Cycle 1(1): loaded http://localhost:55998/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55998/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:27:07 INFO - PROCESS | 29015 | RSS: Main: 167297024 23:27:07 INFO - PROCESS | 29015 | 23:27:09 INFO - PROCESS | 29015 | Cycle 1(1): loaded http://localhost:55998/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55998/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:27:09 INFO - PROCESS | 29015 | RSS: Main: 167264256 23:27:09 INFO - PROCESS | 29015 | 23:27:11 INFO - PROCESS | 29015 | Cycle 1(1): loaded http://localhost:55998/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55998/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:27:12 INFO - PROCESS | 29015 | RSS: Main: 167632896 23:27:12 INFO - PROCESS | 29015 | 23:27:15 INFO - PROCESS | 29015 | Cycle 1(1): loaded http://localhost:55998/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55998/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:27:15 INFO - PROCESS | 29015 | RSS: Main: 168173568 23:27:15 INFO - PROCESS | 29015 | 23:27:18 INFO - PROCESS | 29015 | Cycle 1(1): loaded http://localhost:55998/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55998/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:27:19 INFO - PROCESS | 29015 | RSS: Main: 168103936 23:27:19 INFO - PROCESS | 29015 | 23:27:20 INFO - PROCESS | 29015 | Cycle 1(1): loaded http://localhost:55998/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55998/tests/kraken/driver.html?testName=ai-astar) 23:27:21 INFO - PROCESS | 29015 | RSS: Main: 169607168 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | __start_tp_report 23:27:21 INFO - PROCESS | 29015 | _x_x_mozilla_page_load 23:27:21 INFO - PROCESS | 29015 | _x_x_mozilla_page_load_details 23:27:21 INFO - PROCESS | 29015 | |i|pagename|runs| 23:27:21 INFO - PROCESS | 29015 | |0;ai-astar;113;109;114;112;112;124;110;113;111;113 23:27:21 INFO - PROCESS | 29015 | |1;audio-beat-detection;156;139;159;186;127;158;152;136;187;129 23:27:21 INFO - PROCESS | 29015 | |2;audio-dft;163;164;161;163;161;164;167;165;165;162 23:27:21 INFO - PROCESS | 29015 | |3;audio-fft;75;73;77;157;73;78;76;76;171;77 23:27:21 INFO - PROCESS | 29015 | |4;audio-oscillator;93;92;142;94;93;94;154;93;92;146 23:27:21 INFO - PROCESS | 29015 | |5;imaging-gaussian-blur;118;118;117;118;117;119;118;117;117;117 23:27:21 INFO - PROCESS | 29015 | |6;imaging-darkroom;148;148;147;148;159;148;148;148;147;147 23:27:21 INFO - PROCESS | 29015 | |7;imaging-desaturate;98;96;95;95;95;96;96;96;95;95 23:27:21 INFO - PROCESS | 29015 | |8;json-parse-financial;58;58;58;59;58;58;58;79;55;55 23:27:21 INFO - PROCESS | 29015 | |9;json-stringify-tinderbox;61;61;61;61;61;61;61;61;61;60 23:27:21 INFO - PROCESS | 29015 | |10;stanford-crypto-aes;77;77;78;79;87;78;79;78;79;79 23:27:21 INFO - PROCESS | 29015 | |11;stanford-crypto-ccm;140;134;125;124;134;147;141;125;191;138 23:27:21 INFO - PROCESS | 29015 | |12;stanford-crypto-pbkdf2;168;177;172;164;168;166;169;169;172;167 23:27:21 INFO - PROCESS | 29015 | |13;stanford-crypto-sha256-iterative;68;69;70;71;69;68;70;68;70;68 23:27:21 INFO - PROCESS | 29015 | __end_tp_report 23:27:21 INFO - PROCESS | 29015 | __start_cc_report 23:27:21 INFO - PROCESS | 29015 | _x_x_mozilla_cycle_collect,837 23:27:21 INFO - PROCESS | 29015 | __end_cc_report 23:27:21 INFO - PROCESS | 29015 | __startTimestamp1461565641224__endTimestamp 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | ------- Summary: start ------- 23:27:21 INFO - PROCESS | 29015 | Number of tests: 14 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | [#0] ai-astar Cycles:10 Average:113.10 Median:113.00 stddev:4.12 (3.6%) stddev-sans-first:4.37 23:27:21 INFO - PROCESS | 29015 | Values: 113.0 109.0 114.0 112.0 112.0 124.0 110.0 113.0 111.0 113.0 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | [#1] audio-beat-detection Cycles:10 Average:152.90 Median:157.00 stddev:21.22 (13.5%) stddev-sans-first:22.48 23:27:21 INFO - PROCESS | 29015 | Values: 156.0 139.0 159.0 186.0 127.0 158.0 152.0 136.0 187.0 129.0 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | [#2] audio-dft Cycles:10 Average:163.50 Median:164.00 stddev:1.90 (1.2%) stddev-sans-first:2.01 23:27:21 INFO - PROCESS | 29015 | Values: 163.0 164.0 161.0 163.0 161.0 164.0 167.0 165.0 165.0 162.0 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | [#3] audio-fft Cycles:10 Average:93.30 Median:76.00 stddev:37.44 (49.3%) stddev-sans-first:39.12 23:27:21 INFO - PROCESS | 29015 | Values: 75.0 73.0 77.0 157.0 73.0 78.0 76.0 76.0 171.0 77.0 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | [#4] audio-oscillator Cycles:10 Average:109.30 Median:93.00 stddev:26.41 (28.4%) stddev-sans-first:27.35 23:27:21 INFO - PROCESS | 29015 | Values: 93.0 92.0 142.0 94.0 93.0 94.0 154.0 93.0 92.0 146.0 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 23:27:21 INFO - PROCESS | 29015 | Values: 118.0 118.0 117.0 118.0 117.0 119.0 118.0 117.0 117.0 117.0 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:148.00 stddev:3.61 (2.4%) stddev-sans-first:3.82 23:27:21 INFO - PROCESS | 29015 | Values: 148.0 148.0 147.0 148.0 159.0 148.0 148.0 148.0 147.0 147.0 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.95 (1.0%) stddev-sans-first:0.53 23:27:21 INFO - PROCESS | 29015 | Values: 98.0 96.0 95.0 95.0 95.0 96.0 96.0 96.0 95.0 95.0 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | [#8] json-parse-financial Cycles:10 Average:59.60 Median:58.00 stddev:6.95 (12.0%) stddev-sans-first:7.34 23:27:21 INFO - PROCESS | 29015 | Values: 58.0 58.0 58.0 59.0 58.0 58.0 58.0 79.0 55.0 55.0 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | [#9] json-stringify-tinderbox Cycles:10 Average:60.90 Median:61.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 23:27:21 INFO - PROCESS | 29015 | Values: 61.0 61.0 61.0 61.0 61.0 61.0 61.0 61.0 61.0 60.0 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | [#10] stanford-crypto-aes Cycles:10 Average:79.10 Median:79.00 stddev:2.88 (3.7%) stddev-sans-first:2.96 23:27:21 INFO - PROCESS | 29015 | Values: 77.0 77.0 78.0 79.0 87.0 78.0 79.0 78.0 79.0 79.0 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | [#11] stanford-crypto-ccm Cycles:10 Average:139.90 Median:139.00 stddev:19.53 (14.1%) stddev-sans-first:20.71 23:27:21 INFO - PROCESS | 29015 | Values: 140.0 134.0 125.0 124.0 134.0 147.0 141.0 125.0 191.0 138.0 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.20 Median:169.00 stddev:3.68 (2.2%) stddev-sans-first:3.87 23:27:21 INFO - PROCESS | 29015 | Values: 168.0 177.0 172.0 164.0 168.0 166.0 169.0 169.0 172.0 167.0 23:27:21 INFO - PROCESS | 29015 | 23:27:21 INFO - PROCESS | 29015 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.50 stddev:1.10 (1.6%) stddev-sans-first:1.09 23:27:21 INFO - PROCESS | 29015 | Values: 68.0 69.0 70.0 71.0 69.0 68.0 70.0 68.0 70.0 68.0 23:27:21 INFO - PROCESS | 29015 | -------- Summary: end -------- 23:27:21 INFO - PROCESS | 29015 | 23:27:22 INFO - TEST-INFO | 29015: exit 0 23:27:22 INFO - TEST-OK | kraken | took 61567ms 23:27:22 INFO - SUITE-END | took 1171s 23:27:22 INFO - Completed test suite (00:19:31) 23:27:22 INFO - javascript benchmark 23:27:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3131.545925840753, 3167.1923659466834, 3175.444297247981, 3177.38041081989, 3195.1852277669204, 5300.867478313044, 5319.654222475537, 5320.042392369377, 5328.653637513559, 5397.352317721869, 5279.41926388097, 5311.203319502077, 5376.892462150755, 5387.568994480433, 5504.614676972614, 4886.9755651221785, 4901.926471102936, 4939.580135688453, 4953.108440480712, 5006.04885071837, 757.7479913510796, 4792.976035119819, 4827.61379089672, 4887.169181239198, 4910.361652985125, 4871.902561948759, 4892.094962431962, 4898.167311557026, 4900.068986892479, 4923.433805112422, 4909.484504127051, 4915.9754201229125, 4929.901401971976, 4930.309756634074, 4976.129177393977, 4785.569298763093, 4833.419989601235, 4900.411950565919, 4942.975285123552, 4973.055119527278, 5339.733013349317, 5371.248025276464, 5380.757865896005, 5418.756155972991, 5461.781528738846, 5654.151877218409, 5769.624974376652, 5780.219670344533, 5792.247007888947, 5823.213866128103, 5714.628549144334, 5727.284089488814, 5742.368339482654, 5763.106718458645, 5790.7683692652245, 5426.5116139547235, 5449.782008719647, 5462.6995515246645, 5479.04116779562, 5485.945140548583, 5715.571332150105, 5940.34656187819, 5961.582689211714, 5964.552658550626, 6015.54883383748, 5631.690257035821, 5644.520215781705, 5651.802186923438, 5654.85862853432, 5677.943220567824, 5687.544996400278, 5708.743106560215, 5714.399988001283, 5724.484796368347, 5729.9713501432225, 5568.192612071227, 5682.488576028095, 5685.431456854364, 5725.312962444534, 5823.475887170175, 5740.311162660424, 5743.195952566644, 5746.88506229873, 5769.711514424262, 5777.653340799566, 4224.8732538023905, 4310.784460777011, 4355.107203023373, 4371.956280437218, 4401.955980440219, 3805.0867791729784, 3900.7854567998465, 3929.6856251500385, 3938.0351813805228, 3954.0114971257185, 5373.838784836461, 5518.887235763421, 5570.805021824298, 5572.136318870582, 5619.971900140555, 5735.1970724098655, 5774.162746401748, 5797.536197104221, 5814.9709251454315, 5831.941680583224, 5443.047466693313, 5465.863353416196, 5507.614466987273, 5524.502794748492, 5550.611457198038, 4922.729249891218, 4928.186849169919, 4945.703257804544, 4946.826861059845, 5003.049420610072, 5971.253593300838, 5971.9701401493585, 5983.222181116514, 5987.550933679904, 5996.580239383201, 5657.20799088128, 5778.248827652546, 5875.911861321998, 5885.793997210077, 5899.852503687443, 5913.46778789911, 5946.524278057832, 5947.732352044255, 5951.226340575698, 5961.0, 5581.967336042845, 5677.602567820213, 5682.232898558807, 5682.3465301489205, 5688.260526131577, 5784.94215057844, 5791.942080579309, 5813.854653633693, 5820.70896455184, 5876.676782776988, 5639.943600563942, 5686.8578285541535, 5687.886242275049, 5693.658380497018, 5702.201691763239, 5516.420775818481, 5617.43825617435, 5656.858578535405, 5691.8861622768145, 5700.828975130753, 5495.945040549544, 5504.0643090675485, 5511.53151982086, 5519.034169020486, 5553.250311207935, 5563.248961390161, 5567.0, 5602.9353924891875, 5626.746796394254, 5640.971795140998, 5426.782928682808, 5508.862278443071, 5540.030494663337, 5606.103023516218, 5678.20505129274], "value": 5284.183685174577, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.4619430832172, 637.1366797988728, 651.0592194279267, 659.9109120268762, 685.4413652872911, 36178.63821361786, 37491.62508374915, 37512.249755004916, 37543.81228093859, 37593.436098458544, 41751.16497670045, 42591.57408425915, 42744.786276068655, 42806.7859660702, 42893.57106428935, 8707.08575599564, 8862.24670902974, 8866.024737278894, 8869.37914345996, 8874.378793484459, 8692.13078692132, 8782.12178782123, 8786.16531429513, 8789.25291350236, 8794.912050879506, 8731.039585645574, 8768.60541275641, 8777.517236551987, 8797.912020879805, 8805.427647202943, 8685.0880657531, 8700.347473939448, 8787.121287871225, 8793.824123517557, 8820.073892241351, 8592.183742544448, 8776.385653004292, 8779.956100219459, 8784.253338466238, 8795.164459376349, 31182.688173118433, 38757.22485550302, 39156.412653810214, 39476.407853882214, 39675.80162099171, 3351.2962277921424, 3397.3205358928312, 3518.2084031093054, 3573.749837511375, 3585.0858031202138, 27229.86385068062, 27508.312292192855, 28429.431411371657, 28737.42525149506, 28867.711322886713, 4093.488313960755, 4098.886507293137, 4124.216398884202, 4193.245215861144, 4193.51774545928, 234507.0, 234858.0, 236404.99999999828, 237567.0, 239104.0, 31788.841055795037, 32003.199920002186, 32141.0, 32883.83558082194, 33791.66208337934, 31488.685113148575, 31545.211369715482, 31681.0, 31719.365612688078, 31813.52279715806, 14368.856311436752, 14517.346719397652, 14567.198804065878, 14577.125372477685, 14592.927035364755, 11793.882061179278, 11796.94101529487, 11815.409229538489, 11819.763604727857, 11833.053355731521, 7553.433492487976, 7574.659140338698, 7579.431542634325, 7808.726694565663, 7913.841723165505, 2849.829010259391, 2869.2826793301674, 2886.9133925982255, 2938.1479370982174, 2974.256435891027, 1024.257413375312, 1036.429963520061, 1080.935143891369, 1108.2242430298822, 1125.4935279124427, 31270.215010222728, 32390.0, 32616.836915815744, 32696.346073078406, 33609.83195084009, 1078.369154044877, 1163.4299193395175, 1186.7982442984542, 1218.5734992752466, 1236.5301185549433, 15941.840581594268, 16677.749833752503, 16766.41317553904, 16915.154242287837, 17037.829621703626, 443848.0, 448723.2946846845, 450891.0, 454103.0, 454207.0, 57004.0, 58573.414265857646, 58791.41208587945, 58857.0, 59023.40976590265, 1325.337331334352, 1326.3368315842079, 1335.6527302901122, 1415.8938079643863, 1475.5204558518524, 29690.0, 30917.83575149718, 31787.20531986719, 32197.195070123435, 32367.51448728318, 51070.0, 59573.404265956786, 61740.69129654323, 61763.38236617756, 61819.381806181365, 417891.0, 418884.0, 421664.0, 423337.0, 424813.0, 43034.35448468213, 43043.3543496854, 43879.122417552106, 43882.34176487419, 44368.78496542823, 48694.75652621714, 48801.75599121982, 48890.75554622347, 49453.75273123755, 49818.75090624524, 41383.0, 42142.0, 42289.365659514515, 42981.57018429776, 43123.784381077894, 40943.0, 42146.009983759985, 42547.0, 42802.57197427986, 42919.7854010728, 37081.443778343884, 40604.3909341366, 41361.1727765449, 41788.79105604452, 42795.358069629605, 34547.0, 35315.57278759289, 35545.46681799827, 35828.462573060904, 36629.81685091664, 27020.68255942246, 28169.0, 28368.71631283661, 28577.571336430385, 28625.713742862306], "value": 18201.58550969177, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.47022757456836, 370.81088644791157, 375.64313901793304, 382.76077451592755, 386.97291189616703, 17740.492058175056, 18508.167132479037, 19469.610607787836, 19574.314898978522, 19620.60758784827, 11424.314541127518, 11933.0, 12090.153689241735, 12094.697632559168, 12102.213356131846, 4433.467983841927, 5081.872953176182, 5200.245964335169, 5221.112410890139, 5268.789248430058, 5190.351206099238, 5223.37319521656, 5248.343957005374, 5251.763670634811, 5285.920711189336, 5366.195070739382, 5650.51970582501, 5656.519195868356, 5657.632253903504, 5681.744321505522, 5471.552921659369, 5587.525060369873, 5625.0, 5648.604597678143, 6176.81469555914, 5480.095784195603, 5500.28496295479, 5523.420040895729, 5548.667079975174, 5558.110702287655, 12929.48282068716, 13440.0, 21756.782432175634, 22657.433564160863, 22690.43273918165, 2186.835987300959, 2221.911123555056, 2253.290213582716, 2315.9421014474606, 2561.961570576424, 9449.574769135406, 9796.90203097967, 9910.653127140515, 9969.302148849583, 10319.79360412795, 1939.7963213862624, 2025.4126303372152, 2036.358547057672, 2053.0761157479046, 2092.299079808266, 76286.23713762847, 77426.6128669353, 77461.0, 77495.99999999943, 79133.60433197855, 10247.84628230577, 10385.688429347132, 10408.739781505372, 10444.321119127384, 10516.26386152962, 14288.0, 14296.857031429552, 14350.928245358706, 14395.136291822524, 14401.063930844462, 7607.125180604191, 7872.0, 7949.495908788713, 7985.961824962717, 8003.239692229229, 7685.5004424712215, 7790.727324543614, 7792.805179870548, 7847.176046515148, 7865.292123708894, 4385.495667998157, 4409.625181859577, 4458.977705111453, 4488.730676159441, 4501.819927202875, 1872.9344472943383, 1883.2579863626365, 1926.9036548172535, 1931.9517012074812, 1951.7657881054365, 873.9781505462287, 966.2415004221792, 1002.1181360402799, 1033.6898930320874, 1040.2770074798093, 18297.359592414203, 18542.81457185411, 18619.813801862078, 18720.719189212174, 18872.33946811855, 985.9556319965619, 1037.1443559063803, 1043.0299821166236, 1049.1134990932649, 1073.7369344510646, 9891.604335826487, 10307.196240657855, 10437.739056523647, 10557.788844223072, 10587.947060264649, 502863.0, 506717.0, 512980.0, 522851.0, 526714.0, 90987.0, 95487.0, 95907.52046239724, 95942.52028739951, 96161.0, 995.144176008632, 1025.5026312238558, 1025.789713108811, 1030.062642994871, 1033.9483025848829, 16302.836971630131, 16395.83604163991, 16461.423850165185, 16477.91761041187, 16640.75038874442, 36520.45219321659, 36922.44616330811, 37482.43776343302, 37837.43243851399, 38027.42958855675, 48896.51103489062, 48904.51095488999, 52982.4701752993, 54254.728726356116, 54607.358374538606, 7946.40401969843, 7976.7208147714555, 8070.314023308085, 8176.550289734122, 8180.550069746221, 8706.085860984505, 11870.82193767077, 13018.80471792905, 13091.279979601213, 13168.209907405204, 11200.159988001096, 11304.49129789145, 11361.602343917923, 11379.0, 11383.089352851586, 11322.207445478738, 11345.773084538097, 11396.202265841312, 11413.429328533373, 11553.24903881245, 11495.8275625864, 11655.358955257543, 11681.182317238052, 11694.88305116938, 11771.823422648496, 7838.176991415819, 7869.921300786918, 7870.763877083467, 7997.7600671979935, 8481.194286542768, 10458.790824183627, 11508.942455288005, 11605.30368177912, 11729.0, 11872.109591780823], "value": 8767.158124406915, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1489.8733607643346, 1495.8288428531512, 1617.9433719819808, 1750.0899532243243, 1768.3368736723721, 7529.247075292468, 8099.433039687225, 8166.101728809834, 8452.154784521545, 8461.619227134779, 8191.590420478967, 8213.137620549845, 8306.46008009479, 8308.750737477885, 8327.084020757711, 2285.5086156476395, 2314.3519814451897, 2317.559663663907, 2319.4575607221177, 2320.8491448055906, 2247.1573160064977, 2254.368776742515, 2269.693591365161, 2272.534130503243, 2286.4626812699003, 2138.3000514590935, 2149.570085982802, 2156.6657168138963, 2267.954640907181, 2315.224399826052, 2211.635080211763, 2222.411061068818, 2228.064213030515, 2229.9219527316545, 2234.4637287051155, 2206.6359050756764, 2215.6344203206613, 2218.134927378324, 2220.6335954567476, 2229.498362868358, 8142.063662678749, 8151.796205094861, 8184.713535026305, 8218.054923683734, 8219.136990616018, 4987.925181122286, 5107.284980102751, 5162.664426812245, 5173.611979101544, 5196.922046169273, 3001.3397052648384, 3163.6678148794504, 3225.048610659861, 3259.5110733389943, 3272.2310257089566, 1050.558765318568, 1057.5082586597193, 1058.4125810175349, 1059.046857827961, 1075.704181350127, 11282.153838462149, 11322.830157547643, 11337.829932551018, 11358.375289359083, 11405.37270450125, 4345.804438800262, 4459.643228541709, 4586.403767510203, 4608.262677971508, 4706.788194531255, 9680.322377433513, 9695.757606059906, 9728.0, 9750.561224744763, 9768.755781105529, 5116.104681680691, 5122.820701275437, 5150.768215430315, 5173.094708426011, 5185.170372740344, 4102.0, 4583.954160458419, 4709.411323584552, 4714.504976977437, 4733.715977041388, 3903.7072219583647, 4042.130941847517, 4069.9796501017304, 4107.54816970133, 4150.543440221573, 1265.2029221590524, 1280.257450678604, 1285.9421326040351, 1290.393515047926, 1295.8963282937343, 986.6497393425374, 997.1125698128672, 997.5808663989795, 1002.5137808163036, 1003.8795344558699, 5729.656220626775, 5766.221560089418, 5938.317093534212, 6038.486728628028, 6052.334243233241, 918.9540522973824, 958.6644674364056, 979.1872745621259, 988.8220120378257, 1024.7643042100358, 4049.3318602430268, 4068.023674318142, 4083.9795801020805, 4135.545090040093, 4226.049138943713, 9986.750331241778, 10292.794144117075, 10371.94814025925, 10378.688639340831, 10438.582456701799, 10471.214658900613, 10494.42280674555, 10529.789404211873, 10559.208059395576, 10560.0, 7563.886541701879, 7852.411069169835, 7868.488548244346, 7878.369730421668, 8061.677532898736, 5833.125031245348, 6069.908951365821, 6135.079738039151, 6170.444659980623, 6180.876382472416, 9636.566354513923, 9681.757956051155, 9696.127348538665, 9704.514774261117, 9869.555869986012, 9480.857787133336, 9717.80564388694, 9972.950135249277, 10109.292349535755, 10146.289759716748, 6907.551009184387, 7365.373943214887, 7491.625418729151, 7580.9620951894885, 7660.157382687788, 8101.392395570238, 8124.146964568635, 8130.39022073335, 8149.062857771432, 8178.059523154675, 7235.927640723526, 7436.479446438913, 7507.474476786572, 7565.0, 7606.277403646644, 7292.124945006421, 7321.377682897013, 7350.558966462029, 7371.6314184289495, 7407.370373518312, 6994.475414344046, 6998.720051197895, 7043.929560704328, 7051.858962820817, 7056.682449289691, 4965.503449655005, 4966.602671786176, 5058.190689489664, 5101.0818052750865, 5202.687838729688, 5109.31024311728, 5379.892402152013, 5387.81142660005, 5393.217983392307, 5405.945940540544], "value": 4524.564892964136, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.1789770744209, 305.4791580355494, 316.95245713143026, 317.43496576094543, 317.61727118821824, 5913.793017244398, 6065.787697430591, 6066.454019138277, 6176.104464852599, 6177.752889884399, 6159.076138579216, 6178.907316390247, 6190.876182476348, 6193.132961385399, 6198.473129783963, 4559.0, 4565.794539245733, 4602.907941841161, 4608.976955115237, 4614.953850461503, 4300.6774491913075, 4561.726296422209, 4563.315502674593, 4564.863054108366, 4577.29051996941, 4478.596926276635, 4507.594316511513, 4508.413906192191, 4512.796924138422, 4515.938754392731, 4495.483019452739, 4507.143642707908, 4512.871782054486, 4526.411566496367, 4546.227141385956, 3676.2096149327754, 3855.9421608675893, 3881.592432794544, 3907.2966865964113, 3922.5685174631053, 5842.211301474289, 5852.76588936442, 5868.325142608612, 5898.823035308905, 5903.144044113623, 5186.559142472895, 5187.559057480119, 5212.556932660728, 5214.3053507610175, 5215.791368345262, 5921.822345329647, 6317.873642527124, 6344.49244060474, 6353.364663533656, 6360.109584658151, 4270.416398079179, 4272.9359059614135, 4278.336857787017, 4289.571042895716, 4294.935575966363, 6311.810645680637, 6629.93370066298, 6660.70026848793, 6670.0, 6683.228944329757, 4347.630496393625, 4355.194289056535, 4377.715448495837, 4378.452693413324, 4386.166628340605, 4404.823807047682, 4408.184485870124, 4433.955660443355, 4444.533324000933, 4445.888527868033, 5099.6685215460875, 5176.559992400613, 5178.275041494194, 5184.818531351385, 5195.454477279907, 6142.416470435302, 6370.140031095742, 6390.137331460193, 6393.200849893763, 6397.0, 1513.3643869574805, 1520.0803513874168, 1533.8312785593575, 1535.332130523226, 1538.730722123624, 968.9680490277832, 969.6945462179391, 978.7406337320616, 984.6602921991902, 1003.4531180506641, 6159.476444502178, 6161.599496032743, 6167.352427995085, 6174.969125154345, 6183.598066125687, 6567.737290508326, 6573.4223786291905, 6579.80260592183, 6580.967095164589, 6644.4020038196795, 5915.40845915405, 6176.474999624992, 6226.315105338409, 6239.344868788802, 6247.344028876993, 3500.2474467989505, 3540.3714291425717, 3544.1848374873407, 3556.968479141072, 3574.1064733816547, 6516.6415847128865, 6806.18325800907, 6808.8978665320055, 6822.863542729117, 6853.588784672936, 6681.498887583451, 6714.597124172566, 6754.527183097136, 6761.0, 6765.357291057342, 6600.405963463215, 6736.865262694817, 6777.457803375718, 6825.385715285549, 6843.281455447106, 6529.967350163219, 6548.8035358939305, 6563.343665633399, 6583.604983700993, 6606.405423511907, 6268.341824108403, 6326.398992095744, 6439.259485159079, 6444.9355506444335, 6518.869622607616, 6826.658667066726, 6842.863142737217, 6845.349691779472, 6849.383555480031, 6877.793666190022, 4314.0, 4314.784260787011, 4323.0, 4329.6536277098385, 4331.068820203672, 6334.714937827901, 6388.584741991755, 6545.247296560766, 6642.966785166043, 6684.799456016327, 6417.71120299597, 6426.807195784134, 6443.83890402725, 6453.322401147811, 6458.22501299847, 6216.720247588779, 6389.712462939272, 6397.584157029778, 6398.968005159944, 6402.615843049246, 6260.248770147611, 6280.623162610258, 6309.085182648582, 6339.922213223856, 6350.555461117862, 6420.967895160681, 6451.2903580607035, 6467.773627923, 6468.870622587616, 6517.608943463408, 6049.848753781191, 6290.811275661737, 6338.619682819046, 6346.428821406095, 6356.618602883842], "value": 4747.863413779966, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [825.7564018614509, 831.1812864328635, 836.3267569606467, 842.9873551896719, 848.5163456829606, 13158.407871645775, 13161.144525605841, 13290.667733306673, 13424.664383390407, 13561.25413102279, 10708.089812365939, 12030.69923251917, 12036.157468977175, 12044.217125886811, 12054.095942804302, 782.2216894190288, 801.0027515742909, 808.4664121679692, 810.6757297081156, 812.1107387410776, 803.1727320859495, 805.730080423059, 806.379088102161, 806.4113197365926, 809.4981111710748, 843.7131375332385, 848.8472075026493, 850.0437008365589, 850.4174640371334, 851.0255757158051, 840.8444437779, 844.0883845446933, 845.8646616541355, 849.2059923971107, 850.1498501498502, 612.494691879197, 615.8306465721918, 617.197643064015, 618.3352895637208, 619.6498978077371, 13232.801507977389, 13264.535741249107, 13294.601161965156, 13324.533641322507, 13341.66645833862, 13358.198508089448, 13462.259575723332, 13489.325533723373, 13582.117162384513, 13629.59111226655, 10778.19163562736, 11167.497462614201, 11221.270617409842, 11258.155638327076, 11800.114991375682, 3835.3479908415356, 3879.12719638082, 3913.3934240192816, 3935.7048221383225, 3988.660963818079, 57216.71391643057, 57759.0, 58227.708861455845, 58414.415855841326, 58539.70730146365, 10124.489592670141, 10181.18550515957, 10377.9481102594, 10429.37423754562, 10458.947705261426, 17293.913530432266, 17294.82705172932, 17392.913035434744, 17399.73900391495, 17487.300507979537, 7535.1334596521, 7552.962235188789, 7666.923330766733, 7722.575258360844, 7887.0, 4315.093830295673, 4349.325854492558, 4361.694681372337, 4370.2352088384405, 4423.668224883082, 3046.299351149248, 3092.4433601951414, 3093.706097920694, 3096.551000105017, 3111.626604807392, 580.663215335113, 592.3070008090544, 596.2308621877816, 597.9641221526722, 604.7339170764938, 672.6939242644585, 681.8261343357412, 719.0652152202221, 727.5052963984541, 727.788941207044, 8193.344532437392, 8502.74491765248, 8620.965484141943, 8684.739457816275, 8926.821463570823, 572.9054705973551, 673.4840418946671, 682.3926705232348, 704.527966262595, 737.0344848248811, 4067.7152599317765, 4097.672186225095, 4201.747895126302, 4230.365445183185, 4231.449911511545, 59332.0, 61456.385436145065, 61604.0, 64389.0, 65245.67377163084, 51901.0, 52719.736401318514, 52996.205056923405, 53275.467245327825, 54334.45665543373, 47622.28566571504, 49148.75425622849, 50195.74902125466, 50957.490425095275, 51202.74398627983, 8359.623816928375, 8403.831923361377, 8406.411551191359, 8899.421537600036, 8948.73153805387, 28120.43759124847, 32644.51033234456, 33459.665403346626, 33888.66111338855, 33908.321833562695, 42982.0, 47218.0, 47362.0, 47797.0, 48192.27711584259, 10674.89325106739, 10919.181061420266, 11046.723831904266, 11103.278286911325, 11251.381174035507, 11794.646160615195, 12024.75950480968, 12075.456604452997, 12205.20666156697, 12211.206271592673, 11631.823838391834, 11688.532458701557, 11754.177207595729, 11980.64058078259, 11988.160828742253, 11760.117991150526, 11785.705357366134, 11799.174057816213, 11836.349000805038, 11911.583094591648, 10658.786824263316, 11430.199886008233, 11467.885321147016, 11504.424778761197, 11630.069594432256, 9646.758831029281, 9815.36200146988, 9823.705288841345, 9905.702828915144, 9937.701868943943, 9329.942316210774, 9700.563474643517, 9713.0, 9825.4104753715, 10052.798944021224], "value": 6451.436042527385, "unit": "score"}], "name": "dromaeo_css", "value": 6992.285958717962, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 109.0, 114.0, 112.0, 112.0, 124.0, 110.0, 113.0, 111.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 139.0, 159.0, 186.0, 127.0, 158.0, 152.0, 136.0, 187.0, 129.0], "value": 152.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 161.0, 163.0, 161.0, 164.0, 167.0, 165.0, 165.0, 162.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 77.0, 157.0, 73.0, 78.0, 76.0, 76.0, 171.0, 77.0], "value": 93.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 142.0, 94.0, 93.0, 94.0, 154.0, 93.0, 92.0, 146.0], "value": 109.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 117.0, 119.0, 118.0, 117.0, 117.0, 117.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 148.0, 159.0, 148.0, 148.0, 148.0, 147.0, 147.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 59.0, 58.0, 58.0, 58.0, 79.0, 55.0, 55.0], "value": 59.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 60.0], "value": 60.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 78.0, 79.0, 87.0, 78.0, 79.0, 78.0, 79.0, 79.0], "value": 79.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 134.0, 125.0, 124.0, 134.0, 147.0, 141.0, 125.0, 191.0, 138.0], "value": 139.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 177.0, 172.0, 164.0, 168.0, 166.0, 169.0, 169.0, 172.0, 167.0], "value": 169.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 70.0, 71.0, 69.0, 68.0, 70.0, 68.0, 70.0, 68.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1572.0, "alertThreshold": 2.0}]} 23:27:22 INFO - Return code: 0 23:27:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:27:22 ERROR - # TBPL SUCCESS # 23:27:22 INFO - Running post-action listener: _resource_record_post_action 23:27:22 INFO - Running post-run listener: _resource_record_post_run 23:27:23 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 0; Write bytes: 537362432; Read time: 0; Write time: 375556 23:27:23 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 244744192; Read time: 0; Write time: 228936 23:27:23 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 292618240; Read time: 0; Write time: 146620 23:27:23 INFO - Running post-run listener: _upload_blobber_files 23:27:23 INFO - Blob upload gear active. 23:27:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:27:23 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 23:27:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:27:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:27:24 INFO - (blobuploader) - INFO - Open directory for files ... 23:27:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:27:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:27:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:27:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:27:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:27:24 INFO - (blobuploader) - INFO - Done attempting. 23:27:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:27:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:27:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:27:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:27:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:27:25 INFO - (blobuploader) - INFO - Done attempting. 23:27:25 INFO - (blobuploader) - INFO - Iteration through files over. 23:27:25 INFO - Return code: 0 23:27:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:27:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:27:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/011cfec0876f6c68d3b09bc9262f35c05e5efcfcfe02832cc2bb7150cd8519d5bd87e324634c6f6d3cfd1c6696bd227360b5633fed90f8c0d6da77898189f952", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f1f8f75d5513534d97515861a554873000917b67bde6f320c88c10d5f75395f91f51eeaadb42de2b0c706dd41de8230076ed171c020d36616747234fe12e9d69"} 23:27:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:27:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:27:25 INFO - Contents: 23:27:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/011cfec0876f6c68d3b09bc9262f35c05e5efcfcfe02832cc2bb7150cd8519d5bd87e324634c6f6d3cfd1c6696bd227360b5633fed90f8c0d6da77898189f952", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f1f8f75d5513534d97515861a554873000917b67bde6f320c88c10d5f75395f91f51eeaadb42de2b0c706dd41de8230076ed171c020d36616747234fe12e9d69"} 23:27:25 INFO - Running post-run listener: copy_logs_to_upload_dir 23:27:25 INFO - Copying logs to upload dir... 23:27:25 INFO - mkdir: /builds/slave/test/build/upload/logs 23:27:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.322114 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-04-24 23:27:24.782849) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-24 23:27:24.786212) ========= 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=7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/011cfec0876f6c68d3b09bc9262f35c05e5efcfcfe02832cc2bb7150cd8519d5bd87e324634c6f6d3cfd1c6696bd227360b5633fed90f8c0d6da77898189f952", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f1f8f75d5513534d97515861a554873000917b67bde6f320c88c10d5f75395f91f51eeaadb42de2b0c706dd41de8230076ed171c020d36616747234fe12e9d69"} build_url:https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011260 build_url: 'https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/011cfec0876f6c68d3b09bc9262f35c05e5efcfcfe02832cc2bb7150cd8519d5bd87e324634c6f6d3cfd1c6696bd227360b5633fed90f8c0d6da77898189f952", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f1f8f75d5513534d97515861a554873000917b67bde6f320c88c10d5f75395f91f51eeaadb42de2b0c706dd41de8230076ed171c020d36616747234fe12e9d69"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-24 23:27:24.810699) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:27:24.811040) ========= 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=7cf709767345e0a0c5a90e72000001d4-1461559532.253755-18104502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:27:24.897031) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-24 23:27:24.897410) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-24 23:27:24.897800) ========= ========= Total master_lag: 0.18 =========