builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1465288712.53 results: success (0) buildid: 20160606120332 builduid: 394ff36afaaf47b0a5ccdc9e3ac607e3 revision: 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 01:38:32.525628) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 01:38:32.526169) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 01:38:32.526533) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 01:38:32.541368) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:38:32.541692) ========= 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=c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-07 01:38:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-06-07 01:38:32 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082923 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:38:32.638946) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:38:32.639314) ========= 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=c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020503 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:38:32.690191) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-07 01:38:32.690616) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 --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=c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-07 01:38:33,067 truncating revision to first 12 chars 2016-06-07 01:38:33,067 Setting DEBUG logging. 2016-06-07 01:38:33,068 attempt 1/10 2016-06-07 01:38:33,068 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/46d72a56c57d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-07 01:38:34,226 unpacking tar archive at: mozilla-beta-46d72a56c57d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.484457 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-07 01:38:34.189097) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 01:38:34.189496) ========= script_repo_revision: 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 01:38:34.189963) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 01:38:34.190277) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 01:38:34.200924) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 26 secs) (at 2016-06-07 01:38:34.201281) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:38:34 INFO - MultiFileLogger online at 20160607 01:38:34 in /builds/slave/test 01:38:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:38:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:38:34 INFO - {'append_to_log': False, 01:38:34 INFO - 'base_work_dir': '/builds/slave/test', 01:38:34 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:38:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:38:34 INFO - 'branch': 'Mozilla-Beta', 01:38:34 INFO - 'buildbot_json_path': 'buildprops.json', 01:38:34 INFO - 'config_files': ('talos/linux_config.py',), 01:38:34 INFO - 'default_actions': ('clobber', 01:38:34 INFO - 'read-buildbot-config', 01:38:34 INFO - 'download-and-extract', 01:38:34 INFO - 'populate-webroot', 01:38:34 INFO - 'create-virtualenv', 01:38:34 INFO - 'install', 01:38:34 INFO - 'run-tests'), 01:38:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:38:34 INFO - 'download_minidump_stackwalk': True, 01:38:34 INFO - 'download_symbols': 'ondemand', 01:38:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:38:34 INFO - 'tooltool.py': '/tools/tooltool.py', 01:38:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:38:34 INFO - '/tools/misc-python/virtualenv.py')}, 01:38:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:38:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:38:34 INFO - 'installer_path': 'installer.exe', 01:38:34 INFO - 'log_level': 'info', 01:38:34 INFO - 'log_name': 'talos', 01:38:34 INFO - 'log_to_console': True, 01:38:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:38:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:38:34 INFO - 'opt_config_files': (), 01:38:34 INFO - 'pip_index': False, 01:38:34 INFO - 'sps_profile': False, 01:38:34 INFO - 'sps_profile_interval': 0, 01:38:34 INFO - 'suite': 'dromaeojs', 01:38:34 INFO - 'system_bits': '32', 01:38:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:38:34 INFO - 'title': 'talos-linux64-ix-004', 01:38:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:38:34 INFO - 'use_talos_json': True, 01:38:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:38:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:38:34 INFO - 'work_dir': 'build'} 01:38:34 INFO - ##### 01:38:34 INFO - ##### Running clobber step. 01:38:34 INFO - ##### 01:38:34 INFO - Running pre-action listener: _resource_record_pre_action 01:38:34 INFO - Running main action method: clobber 01:38:34 INFO - rmtree: /builds/slave/test/build 01:38:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:38:35 INFO - Running post-action listener: _resource_record_post_action 01:38:35 INFO - ##### 01:38:35 INFO - ##### Running read-buildbot-config step. 01:38:35 INFO - ##### 01:38:35 INFO - Running pre-action listener: _resource_record_pre_action 01:38:35 INFO - Running main action method: read_buildbot_config 01:38:35 INFO - Using buildbot properties: 01:38:35 INFO - { 01:38:35 INFO - "project": "", 01:38:35 INFO - "product": "firefox", 01:38:35 INFO - "script_repo_revision": "production", 01:38:35 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 01:38:35 INFO - "repository": "", 01:38:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 01:38:35 INFO - "buildid": "20160606120332", 01:38:35 INFO - "pgo_build": "True", 01:38:35 INFO - "basedir": "/builds/slave/test", 01:38:35 INFO - "buildnumber": 123, 01:38:35 INFO - "slavename": "talos-linux64-ix-004", 01:38:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:38:35 INFO - "platform": "ubuntu64_hw", 01:38:35 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 01:38:35 INFO - "branch": "mozilla-beta", 01:38:35 INFO - "repo_path": "releases/mozilla-beta", 01:38:35 INFO - "revision": "46d72a56c57dafb4dc1061d4741a3e1181ac3d68", 01:38:35 INFO - "stage_platform": "linux64-pgo", 01:38:35 INFO - "builduid": "394ff36afaaf47b0a5ccdc9e3ac607e3", 01:38:35 INFO - "slavebuilddir": "test" 01:38:35 INFO - } 01:38:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2. 01:38:35 INFO - Running post-action listener: _resource_record_post_action 01:38:35 INFO - ##### 01:38:35 INFO - ##### Running download-and-extract step. 01:38:35 INFO - ##### 01:38:35 INFO - Running pre-action listener: _resource_record_pre_action 01:38:35 INFO - Running main action method: download_and_extract 01:38:35 INFO - mkdir: /builds/slave/test/build/tests 01:38:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:38:35 INFO - https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 01:38:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 01:38:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 01:38:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 01:38:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 01:38:36 INFO - Downloaded 1327 bytes. 01:38:36 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 01:38:36 INFO - Using the following test package requirements: 01:38:36 INFO - {u'common': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 01:38:36 INFO - u'cppunittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:38:36 INFO - u'firefox-48.0.en-US.linux-x86_64.cppunittest.tests.zip'], 01:38:36 INFO - u'gtest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:38:36 INFO - u'firefox-48.0.en-US.linux-x86_64.gtest.tests.zip'], 01:38:36 INFO - u'jittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:38:36 INFO - u'jsshell-linux-x86_64.zip'], 01:38:36 INFO - u'mochitest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:38:36 INFO - u'firefox-48.0.en-US.linux-x86_64.mochitest.tests.zip'], 01:38:36 INFO - u'mozbase': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 01:38:36 INFO - u'reftest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:38:36 INFO - u'firefox-48.0.en-US.linux-x86_64.reftest.tests.zip'], 01:38:36 INFO - u'talos': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:38:36 INFO - u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'], 01:38:36 INFO - u'web-platform': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:38:36 INFO - u'firefox-48.0.en-US.linux-x86_64.web-platform.tests.zip'], 01:38:36 INFO - u'xpcshell': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 01:38:36 INFO - u'firefox-48.0.en-US.linux-x86_64.xpcshell.tests.zip']} 01:38:36 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:38:36 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')]} 01:38:36 INFO - https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:38:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 01:38:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 01:38:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 01:38:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:38:37 INFO - Downloaded 24177984 bytes. 01:38:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:38:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 01:38:38 INFO - Return code: 0 01:38:38 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:38:38 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')]} 01:38:38 INFO - https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:38:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 01:38:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 01:38:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 01:38:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:38:39 INFO - Downloaded 24177984 bytes. 01:38:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:38:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 01:38:41 INFO - Return code: 0 01:38:41 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')]} 01:38:41 INFO - https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:38:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 01:38:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 01:38:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 01:38:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:38:42 INFO - Downloaded 10840145 bytes. 01:38:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:38:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 01:38:42 INFO - Return code: 0 01:38:42 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')]} 01:38:42 INFO - https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:38:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 01:38:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 01:38:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 01:38:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:38:45 INFO - Downloaded 56008216 bytes. 01:38:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 01:38:45 INFO - mkdir: /builds/slave/test/properties 01:38:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:38:45 INFO - Writing to file /builds/slave/test/properties/build_url 01:38:45 INFO - Contents: 01:38:45 INFO - build_url:https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 01:38:46 INFO - Running post-action listener: _resource_record_post_action 01:38:46 INFO - Running post-action listener: set_extra_try_arguments 01:38:46 INFO - ##### 01:38:46 INFO - ##### Running populate-webroot step. 01:38:46 INFO - ##### 01:38:46 INFO - Running pre-action listener: _resource_record_pre_action 01:38:46 INFO - Running main action method: populate_webroot 01:38:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:38:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:38:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:38:46 INFO - '--tppagecycles', 01:38:46 INFO - '7'], 01:38:46 INFO - 'tests': ['tsvgm']}}, 01:38:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:38:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:38:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:38:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:38:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:38:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:38:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:38:46 INFO - 'talos_options': ['--disable-e10s'], 01:38:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:38:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:38:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:38:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:38:46 INFO - 'talos_options': ['--disable-e10s'], 01:38:46 INFO - 'tests': ['damp', 'tps']}, 01:38:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:38:46 INFO - 'tests': ['damp', 'tps']}, 01:38:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:38:46 INFO - 'tests': ['dromaeo_dom']}, 01:38:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:38:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:38:46 INFO - 'tests': ['a11yr', 01:38:46 INFO - 'ts_paint', 01:38:46 INFO - 'tpaint', 01:38:46 INFO - 'sessionrestore', 01:38:46 INFO - 'sessionrestore_no_auto_restore', 01:38:46 INFO - 'tabpaint']}, 01:38:46 INFO - 'other-e10s': {'tests': ['a11yr', 01:38:46 INFO - 'ts_paint', 01:38:46 INFO - 'tpaint', 01:38:46 INFO - 'sessionrestore', 01:38:46 INFO - 'sessionrestore_no_auto_restore', 01:38:46 INFO - 'tabpaint']}, 01:38:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:38:46 INFO - 'ts_paint', 01:38:46 INFO - 'tpaint', 01:38:46 INFO - 'sessionrestore', 01:38:46 INFO - 'sessionrestore_no_auto_restore', 01:38:46 INFO - 'tabpaint']}, 01:38:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:38:46 INFO - 'ts_paint', 01:38:46 INFO - 'tpaint', 01:38:46 INFO - 'sessionrestore', 01:38:46 INFO - 'sessionrestore_no_auto_restore', 01:38:46 INFO - 'tabpaint']}, 01:38:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:38:46 INFO - 'tests': ['a11yr', 01:38:46 INFO - 'ts_paint', 01:38:46 INFO - 'tpaint', 01:38:46 INFO - 'sessionrestore', 01:38:46 INFO - 'sessionrestore_no_auto_restore', 01:38:46 INFO - 'tabpaint']}, 01:38:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:38:46 INFO - 'tests': ['a11yr', 01:38:46 INFO - 'ts_paint', 01:38:46 INFO - 'tpaint', 01:38:46 INFO - 'sessionrestore', 01:38:46 INFO - 'sessionrestore_no_auto_restore', 01:38:46 INFO - 'tabpaint']}, 01:38:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:38:46 INFO - 'tests': ['tsvgx', 01:38:46 INFO - 'tsvgr_opacity', 01:38:46 INFO - 'tart', 01:38:46 INFO - 'tscrollx', 01:38:46 INFO - 'cart']}, 01:38:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:38:46 INFO - 'tsvgr_opacity', 01:38:46 INFO - 'tart', 01:38:46 INFO - 'tscrollx', 01:38:46 INFO - 'cart']}, 01:38:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:38:46 INFO - 'talos_options': ['--disable-e10s'], 01:38:46 INFO - 'tests': ['tp5o']}, 01:38:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:38:46 INFO - 'tests': ['tp5o']}, 01:38:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:38:46 INFO - 'talos_options': ['--disable-e10s', 01:38:46 INFO - '--xperf_path', 01:38:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:38:46 INFO - 'tests': ['tp5n']}, 01:38:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:38:46 INFO - 'talos_options': ['--xperf_path', 01:38:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:38:46 INFO - 'tests': ['tp5n']}}, 01:38:46 INFO - 'talos.zip': {'path': '', 01:38:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:38:46 INFO - Running post-action listener: _resource_record_post_action 01:38:46 INFO - ##### 01:38:46 INFO - ##### Running create-virtualenv step. 01:38:46 INFO - ##### 01:38:46 INFO - Running pre-action listener: _resource_record_pre_action 01:38:46 INFO - Running main action method: create_virtualenv 01:38:46 INFO - Creating virtualenv /builds/slave/test/build/venv 01:38:46 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 01:38:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:38:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:38:46 INFO - Using real prefix '/usr' 01:38:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:38:46 INFO - Installing distribute.............................................................................................................................................................................................done. 01:38:48 INFO - Installing pip.................done. 01:38:48 INFO - Return code: 0 01:38:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:38:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:38:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:48 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 0x7f730a8a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a4a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad9410>, '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 0x2ada310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ada7a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', '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 01:38:48 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 01:38:48 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 01:38:48 INFO - Using env: {'DISPLAY': ':0', 01:38:48 INFO - 'HOME': '/home/cltbld', 01:38:48 INFO - 'LANG': 'en_US.UTF-8', 01:38:48 INFO - 'LANGUAGE': 'en_US:en', 01:38:48 INFO - 'LOGNAME': 'cltbld', 01:38:48 INFO - 'MAIL': '/var/mail/cltbld', 01:38:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:48 INFO - 'MOZ_NO_REMOTE': '1', 01:38:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:38:48 INFO - 'NO_EM_RESTART': '1', 01:38:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:38:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:48 INFO - 'PWD': '/builds/slave/test', 01:38:48 INFO - 'SHELL': '/bin/bash', 01:38:48 INFO - 'SHLVL': '1', 01:38:48 INFO - 'TERM': 'linux', 01:38:48 INFO - 'TMOUT': '86400', 01:38:48 INFO - 'USER': 'cltbld', 01:38:48 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', 01:38:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:48 INFO - '_': '/tools/buildbot/bin/python'} 01:38:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:49 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 01:38:49 INFO - Cleaning up... 01:38:49 INFO - Return code: 0 01:38:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:38: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')]} 01:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38: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')]} 01:38:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:49 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 0x7f730a8a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a4a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad9410>, '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 0x2ada310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ada7a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', '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 01:38:49 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 01:38:49 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 01:38:49 INFO - Using env: {'DISPLAY': ':0', 01:38:49 INFO - 'HOME': '/home/cltbld', 01:38:49 INFO - 'LANG': 'en_US.UTF-8', 01:38:49 INFO - 'LANGUAGE': 'en_US:en', 01:38:49 INFO - 'LOGNAME': 'cltbld', 01:38:49 INFO - 'MAIL': '/var/mail/cltbld', 01:38:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:49 INFO - 'MOZ_NO_REMOTE': '1', 01:38:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:38:49 INFO - 'NO_EM_RESTART': '1', 01:38:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:38:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:49 INFO - 'PWD': '/builds/slave/test', 01:38:49 INFO - 'SHELL': '/bin/bash', 01:38:49 INFO - 'SHLVL': '1', 01:38:49 INFO - 'TERM': 'linux', 01:38:49 INFO - 'TMOUT': '86400', 01:38:49 INFO - 'USER': 'cltbld', 01:38:49 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', 01:38:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:49 INFO - '_': '/tools/buildbot/bin/python'} 01:38:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:49 INFO - Downloading/unpacking psutil>=0.7.1 01:38:49 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 01:38:49 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 01:38:49 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 01:38:49 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 01:38:49 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 01:38:49 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 01:38:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:38:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:38:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:38:51 INFO - Installing collected packages: psutil 01:38:51 INFO - Running setup.py install for psutil 01:38:51 INFO - building 'psutil._psutil_linux' extension 01:38:51 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 01:38:51 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 01:38:51 INFO - building 'psutil._psutil_posix' extension 01:38:51 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 01:38:51 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 01:38:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:38:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:38:51 INFO - Successfully installed psutil 01:38:51 INFO - Cleaning up... 01:38:51 INFO - Return code: 0 01:38:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:38:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:38:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:38:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:51 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 0x7f730a8a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a4a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad9410>, '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 0x2ada310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ada7a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', '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 01:38:51 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 01:38:51 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 01:38:51 INFO - Using env: {'DISPLAY': ':0', 01:38:51 INFO - 'HOME': '/home/cltbld', 01:38:51 INFO - 'LANG': 'en_US.UTF-8', 01:38:51 INFO - 'LANGUAGE': 'en_US:en', 01:38:51 INFO - 'LOGNAME': 'cltbld', 01:38:51 INFO - 'MAIL': '/var/mail/cltbld', 01:38:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:51 INFO - 'MOZ_NO_REMOTE': '1', 01:38:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:38:51 INFO - 'NO_EM_RESTART': '1', 01:38:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:38:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:51 INFO - 'PWD': '/builds/slave/test', 01:38:51 INFO - 'SHELL': '/bin/bash', 01:38:51 INFO - 'SHLVL': '1', 01:38:51 INFO - 'TERM': 'linux', 01:38:51 INFO - 'TMOUT': '86400', 01:38:51 INFO - 'USER': 'cltbld', 01:38:51 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', 01:38:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:51 INFO - '_': '/tools/buildbot/bin/python'} 01:38:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:38:52 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 01:38:52 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 01:38:52 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 01:38:52 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 01:38:52 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 01:38:52 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 01:38:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:38:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:38:53 INFO - Installing collected packages: mozsystemmonitor 01:38:53 INFO - Running setup.py install for mozsystemmonitor 01:38:54 INFO - Successfully installed mozsystemmonitor 01:38:54 INFO - Cleaning up... 01:38:54 INFO - Return code: 0 01:38:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:38:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:38:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:38:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f730a8a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a4a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad9410>, '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 0x2ada310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ada7a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', '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 01:38:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:38:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:38:54 INFO - Using env: {'DISPLAY': ':0', 01:38:54 INFO - 'HOME': '/home/cltbld', 01:38:54 INFO - 'LANG': 'en_US.UTF-8', 01:38:54 INFO - 'LANGUAGE': 'en_US:en', 01:38:54 INFO - 'LOGNAME': 'cltbld', 01:38:54 INFO - 'MAIL': '/var/mail/cltbld', 01:38:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:54 INFO - 'MOZ_NO_REMOTE': '1', 01:38:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:38:54 INFO - 'NO_EM_RESTART': '1', 01:38:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:38:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:54 INFO - 'PWD': '/builds/slave/test', 01:38:54 INFO - 'SHELL': '/bin/bash', 01:38:54 INFO - 'SHLVL': '1', 01:38:54 INFO - 'TERM': 'linux', 01:38:54 INFO - 'TMOUT': '86400', 01:38:54 INFO - 'USER': 'cltbld', 01:38:54 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', 01:38:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:54 INFO - '_': '/tools/buildbot/bin/python'} 01:38:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:54 INFO - Downloading/unpacking blobuploader==1.2.4 01:38:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:56 INFO - Downloading blobuploader-1.2.4.tar.gz 01:38:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:38:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:38:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:56 INFO - Downloading docopt-0.6.1.tar.gz 01:38:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:38:56 INFO - Installing collected packages: blobuploader, requests, docopt 01:38:56 INFO - Running setup.py install for blobuploader 01:38:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:38:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:38:57 INFO - Running setup.py install for requests 01:38:57 INFO - Running setup.py install for docopt 01:38:57 INFO - Successfully installed blobuploader requests docopt 01:38:57 INFO - Cleaning up... 01:38:57 INFO - Return code: 0 01:38:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:38:57 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')]} 01:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:57 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')]} 01:38:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:57 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 0x7f730a8a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a4a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad9410>, '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 0x2ada310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ada7a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', '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 01:38:57 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 01:38:57 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 01:38:57 INFO - Using env: {'DISPLAY': ':0', 01:38:57 INFO - 'HOME': '/home/cltbld', 01:38:57 INFO - 'LANG': 'en_US.UTF-8', 01:38:57 INFO - 'LANGUAGE': 'en_US:en', 01:38:57 INFO - 'LOGNAME': 'cltbld', 01:38:57 INFO - 'MAIL': '/var/mail/cltbld', 01:38:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:57 INFO - 'MOZ_NO_REMOTE': '1', 01:38:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:38:57 INFO - 'NO_EM_RESTART': '1', 01:38:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:38:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:57 INFO - 'PWD': '/builds/slave/test', 01:38:57 INFO - 'SHELL': '/bin/bash', 01:38:57 INFO - 'SHLVL': '1', 01:38:57 INFO - 'TERM': 'linux', 01:38:57 INFO - 'TMOUT': '86400', 01:38:57 INFO - 'USER': 'cltbld', 01:38:57 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', 01:38:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:57 INFO - '_': '/tools/buildbot/bin/python'} 01:38:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:38:57 INFO - Running setup.py (path:/tmp/pip-mI6_Oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:38:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:38:57 INFO - Running setup.py (path:/tmp/pip-mcNr9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:38:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:38:57 INFO - Running setup.py (path:/tmp/pip-bKH6cU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:38:58 INFO - Running setup.py (path:/tmp/pip-9hv72s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:38:58 INFO - Running setup.py (path:/tmp/pip-Po5HvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:38:58 INFO - Running setup.py (path:/tmp/pip-uVwBhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:38:58 INFO - Running setup.py (path:/tmp/pip-kModPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:38:58 INFO - Running setup.py (path:/tmp/pip-Gw38Sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:38:58 INFO - Running setup.py (path:/tmp/pip-bz6PbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:38:58 INFO - Running setup.py (path:/tmp/pip-eKtX6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:38:58 INFO - Running setup.py (path:/tmp/pip-yyv7Gc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:38:58 INFO - Running setup.py (path:/tmp/pip-mFwcUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:38:58 INFO - Running setup.py (path:/tmp/pip-OLmJPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:38:58 INFO - Running setup.py (path:/tmp/pip-XBv7dT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:38:58 INFO - Running setup.py (path:/tmp/pip-6KkbtF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:38:58 INFO - Running setup.py (path:/tmp/pip-CLprkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:38:58 INFO - Running setup.py (path:/tmp/pip-ymw0Hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:38:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:38:59 INFO - Running setup.py install for manifestparser 01:38:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:38:59 INFO - Running setup.py install for mozcrash 01:38:59 INFO - Running setup.py install for mozdebug 01:38:59 INFO - Running setup.py install for mozdevice 01:38:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:38:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:38:59 INFO - Running setup.py install for mozfile 01:38:59 INFO - Running setup.py install for mozhttpd 01:38:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:38:59 INFO - Running setup.py install for mozinfo 01:38:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:38:59 INFO - Running setup.py install for mozInstall 01:38:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:38:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:38:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:38:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:38:59 INFO - Running setup.py install for mozleak 01:38:59 INFO - Running setup.py install for mozlog 01:39:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:39:00 INFO - Running setup.py install for moznetwork 01:39:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:39:00 INFO - Running setup.py install for mozprocess 01:39:00 INFO - Running setup.py install for mozprofile 01:39:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:39:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:39:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:39:00 INFO - Running setup.py install for mozrunner 01:39:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:39:00 INFO - Running setup.py install for mozscreenshot 01:39:00 INFO - Running setup.py install for moztest 01:39:00 INFO - Running setup.py install for mozversion 01:39:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:39:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:39:00 INFO - Cleaning up... 01:39:00 INFO - Return code: 0 01:39:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:39:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:39:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:00 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 0x7f730a8a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a4a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad9410>, '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 0x2ada310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ada7a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', '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 01:39:00 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 01:39:00 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 01:39:00 INFO - Using env: {'DISPLAY': ':0', 01:39:00 INFO - 'HOME': '/home/cltbld', 01:39:00 INFO - 'LANG': 'en_US.UTF-8', 01:39:00 INFO - 'LANGUAGE': 'en_US:en', 01:39:00 INFO - 'LOGNAME': 'cltbld', 01:39:00 INFO - 'MAIL': '/var/mail/cltbld', 01:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:00 INFO - 'MOZ_NO_REMOTE': '1', 01:39:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:00 INFO - 'NO_EM_RESTART': '1', 01:39:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:00 INFO - 'PWD': '/builds/slave/test', 01:39:00 INFO - 'SHELL': '/bin/bash', 01:39:00 INFO - 'SHLVL': '1', 01:39:00 INFO - 'TERM': 'linux', 01:39:00 INFO - 'TMOUT': '86400', 01:39:00 INFO - 'USER': 'cltbld', 01:39:00 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', 01:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:00 INFO - '_': '/tools/buildbot/bin/python'} 01:39:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:39:01 INFO - Running setup.py (path:/tmp/pip-jy3x8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:39:01 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)) 01:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:39:01 INFO - Running setup.py (path:/tmp/pip-uy5MvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:39:01 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)) 01:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:39:01 INFO - Running setup.py (path:/tmp/pip-AJhuAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:39:01 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)) 01:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:39:01 INFO - Running setup.py (path:/tmp/pip-uOPu3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:39:01 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)) 01:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:39:01 INFO - Running setup.py (path:/tmp/pip-lNGIw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:39:01 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)) 01:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:39:01 INFO - Running setup.py (path:/tmp/pip-1nIJtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:39:01 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)) 01:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:39:01 INFO - Running setup.py (path:/tmp/pip-Q5fHZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:39:01 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)) 01:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:39:01 INFO - Running setup.py (path:/tmp/pip-S1qR3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:39:01 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)) 01:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:39:01 INFO - Running setup.py (path:/tmp/pip-lEpkeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:39:01 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)) 01:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:39:01 INFO - Running setup.py (path:/tmp/pip-THiegB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:39:01 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)) 01:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:39:01 INFO - Running setup.py (path:/tmp/pip-0yMdvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:39:01 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)) 01:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:39:01 INFO - Running setup.py (path:/tmp/pip-88ojvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:39:01 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)) 01:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:39:01 INFO - Running setup.py (path:/tmp/pip-HKJ_H7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:39:01 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)) 01:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:39:01 INFO - Running setup.py (path:/tmp/pip-Gkre7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:39:02 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)) 01:39:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:39:02 INFO - Running setup.py (path:/tmp/pip-5rAYIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:39:02 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)) 01:39:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:39:02 INFO - Running setup.py (path:/tmp/pip-0WMTct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:39:02 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)) 01:39:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:39:02 INFO - Running setup.py (path:/tmp/pip-d925r7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:39:02 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)) 01:39:02 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)) 01:39:02 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)) 01:39:02 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)) 01:39:02 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)) 01:39:02 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)) 01:39:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:39:02 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 01:39:02 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 01:39:02 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 01:39:02 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 01:39:02 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 01:39:02 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 01:39:04 INFO - Downloading blessings-1.6.tar.gz 01:39:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:39:04 INFO - Installing collected packages: blessings 01:39:04 INFO - Running setup.py install for blessings 01:39:04 INFO - Successfully installed blessings 01:39:04 INFO - Cleaning up... 01:39:04 INFO - Return code: 0 01:39:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:39:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:39:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:39:04 INFO - Reading from file tmpfile_stdout 01:39:04 INFO - Current package versions: 01:39:04 INFO - argparse == 1.2.1 01:39:04 INFO - blessings == 1.6 01:39:04 INFO - blobuploader == 1.2.4 01:39:04 INFO - docopt == 0.6.1 01:39:04 INFO - manifestparser == 1.1 01:39:04 INFO - mozInstall == 1.12 01:39:04 INFO - mozcrash == 0.17 01:39:04 INFO - mozdebug == 0.1 01:39:04 INFO - mozdevice == 0.48 01:39:04 INFO - mozfile == 1.2 01:39:04 INFO - mozhttpd == 0.7 01:39:04 INFO - mozinfo == 0.9 01:39:04 INFO - mozleak == 0.1 01:39:04 INFO - mozlog == 3.1 01:39:04 INFO - moznetwork == 0.27 01:39:04 INFO - mozprocess == 0.22 01:39:04 INFO - mozprofile == 0.28 01:39:04 INFO - mozrunner == 6.11 01:39:04 INFO - mozscreenshot == 0.1 01:39:04 INFO - mozsystemmonitor == 0.0 01:39:04 INFO - moztest == 0.7 01:39:04 INFO - mozversion == 1.4 01:39:04 INFO - psutil == 3.1.1 01:39:04 INFO - requests == 1.2.3 01:39:04 INFO - wsgiref == 0.1.2 01:39:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:39:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:04 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 0x7f730a8a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a4a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad9410>, '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 0x2ada310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ada7a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', '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 01:39:04 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 01:39:04 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 01:39:04 INFO - Using env: {'DISPLAY': ':0', 01:39:04 INFO - 'HOME': '/home/cltbld', 01:39:04 INFO - 'LANG': 'en_US.UTF-8', 01:39:04 INFO - 'LANGUAGE': 'en_US:en', 01:39:04 INFO - 'LOGNAME': 'cltbld', 01:39:04 INFO - 'MAIL': '/var/mail/cltbld', 01:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:04 INFO - 'MOZ_NO_REMOTE': '1', 01:39:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:04 INFO - 'NO_EM_RESTART': '1', 01:39:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:04 INFO - 'PWD': '/builds/slave/test', 01:39:04 INFO - 'SHELL': '/bin/bash', 01:39:04 INFO - 'SHLVL': '1', 01:39:04 INFO - 'TERM': 'linux', 01:39:04 INFO - 'TMOUT': '86400', 01:39:04 INFO - 'USER': 'cltbld', 01:39:04 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', 01:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:04 INFO - '_': '/tools/buildbot/bin/python'} 01:39:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:04 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)) 01:39:04 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)) 01:39:04 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)) 01:39:04 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)) 01:39:04 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)) 01:39:04 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)) 01:39:04 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)) 01:39:04 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)) 01:39:04 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)) 01:39:04 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)) 01:39:04 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)) 01:39:04 INFO - Cleaning up... 01:39:04 INFO - Return code: 0 01:39:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:39:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:04 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 0x7f730a8a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a4a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad9410>, '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 0x2ada310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ada7a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', '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 01:39:04 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 01:39:04 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 01:39:04 INFO - Using env: {'DISPLAY': ':0', 01:39:04 INFO - 'HOME': '/home/cltbld', 01:39:04 INFO - 'LANG': 'en_US.UTF-8', 01:39:04 INFO - 'LANGUAGE': 'en_US:en', 01:39:04 INFO - 'LOGNAME': 'cltbld', 01:39:04 INFO - 'MAIL': '/var/mail/cltbld', 01:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:04 INFO - 'MOZ_NO_REMOTE': '1', 01:39:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:04 INFO - 'NO_EM_RESTART': '1', 01:39:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:04 INFO - 'PWD': '/builds/slave/test', 01:39:04 INFO - 'SHELL': '/bin/bash', 01:39:04 INFO - 'SHLVL': '1', 01:39:04 INFO - 'TERM': 'linux', 01:39:04 INFO - 'TMOUT': '86400', 01:39:04 INFO - 'USER': 'cltbld', 01:39:04 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', 01:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:04 INFO - '_': '/tools/buildbot/bin/python'} 01:39:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:05 INFO - Downloading/unpacking jsonschema 01:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:39:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:39:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:39:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:39:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:39:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:39:06 INFO - Downloading/unpacking functools32 (from jsonschema) 01:39:06 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 01:39:06 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 01:39:06 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 01:39:06 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 01:39:06 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 01:39:06 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 01:39:07 INFO - Downloading functools32-3.2.3-2.tar.gz 01:39:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:39:07 INFO - warning: no files found matching '*.txt' 01:39:07 INFO - no previously-included directories found matching 'build' 01:39:07 INFO - no previously-included directories found matching 'dist' 01:39:07 INFO - no previously-included directories found matching '.git*' 01:39:07 INFO - Installing collected packages: jsonschema, functools32 01:39:07 INFO - Running setup.py install for functools32 01:39:07 INFO - warning: no files found matching '*.txt' 01:39:07 INFO - no previously-included directories found matching 'build' 01:39:07 INFO - no previously-included directories found matching 'dist' 01:39:07 INFO - no previously-included directories found matching '.git*' 01:39:07 INFO - Successfully installed jsonschema functools32 01:39:07 INFO - Cleaning up... 01:39:07 INFO - Return code: 0 01:39:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:39:07 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')]} 01:39:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:07 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')]} 01:39:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:07 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 0x7f730a8a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a4a5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad9410>, '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 0x2ada310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ada7a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', '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 01:39:07 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 01:39:07 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 01:39:07 INFO - Using env: {'DISPLAY': ':0', 01:39:07 INFO - 'HOME': '/home/cltbld', 01:39:07 INFO - 'LANG': 'en_US.UTF-8', 01:39:07 INFO - 'LANGUAGE': 'en_US:en', 01:39:07 INFO - 'LOGNAME': 'cltbld', 01:39:07 INFO - 'MAIL': '/var/mail/cltbld', 01:39:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:07 INFO - 'MOZ_NO_REMOTE': '1', 01:39:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:07 INFO - 'NO_EM_RESTART': '1', 01:39:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:07 INFO - 'PWD': '/builds/slave/test', 01:39:07 INFO - 'SHELL': '/bin/bash', 01:39:07 INFO - 'SHLVL': '1', 01:39:07 INFO - 'TERM': 'linux', 01:39:07 INFO - 'TMOUT': '86400', 01:39:07 INFO - 'USER': 'cltbld', 01:39:07 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', 01:39:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:07 INFO - '_': '/tools/buildbot/bin/python'} 01:39:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:07 INFO - Downloading/unpacking flake8 01:39:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:39:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:39:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:39:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:39:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:39:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:39:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:39:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:39:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:39:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:39:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:39:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:39:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:39:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:39:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:39:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:39:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:39:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:39:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:39:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:39:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:39:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:39:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:39:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:39:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:39:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:39:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:39:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:39:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:39:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:39:10 INFO - no previously-included directories found matching 'docs/_build' 01:39:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:39:10 INFO - Running setup.py install for pep8 01:39:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:39:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:39:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:39:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:39:10 INFO - no previously-included directories found matching 'docs/_build' 01:39:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:39:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:39:10 INFO - Cleaning up... 01:39:10 INFO - Return code: 0 01:39:10 INFO - Running post-action listener: _resource_record_post_action 01:39:10 INFO - Running post-action listener: _start_resource_monitoring 01:39:10 INFO - Starting resource monitoring. 01:39:10 INFO - ##### 01:39:10 INFO - ##### Running install step. 01:39:10 INFO - ##### 01:39:10 INFO - Running pre-action listener: _resource_record_pre_action 01:39:10 INFO - Running main action method: install 01:39:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:39:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:39:10 INFO - Reading from file tmpfile_stdout 01:39:10 INFO - Detecting whether we're running mozinstall >=1.0... 01:39:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:39:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:39:10 INFO - Reading from file tmpfile_stdout 01:39:10 INFO - Output received: 01:39:10 INFO - Usage: mozinstall [options] installer 01:39:10 INFO - Options: 01:39:10 INFO - -h, --help show this help message and exit 01:39:10 INFO - -d DEST, --destination=DEST 01:39:10 INFO - Directory to install application into. [default: 01:39:10 INFO - "/builds/slave/test"] 01:39:10 INFO - --app=APP Application being installed. [default: firefox] 01:39:10 INFO - mkdir: /builds/slave/test/build/application 01:39:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:39:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 01:39:24 INFO - Reading from file tmpfile_stdout 01:39:24 INFO - Output received: 01:39:24 INFO - /builds/slave/test/build/application/firefox/firefox 01:39:24 INFO - Running post-action listener: _resource_record_post_action 01:39:24 INFO - ##### 01:39:24 INFO - ##### Running run-tests step. 01:39:24 INFO - ##### 01:39:24 INFO - Running pre-action listener: _resource_record_pre_action 01:39:24 INFO - Running main action method: run_tests 01:39:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:39:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:39:24 INFO - Python 2.7.3 01:39:24 INFO - Return code: 0 01:39:24 INFO - grabbing minidump binary from tooltool 01:39:24 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')]} 01:39:24 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 0x2ad9410>, '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 0x2ada310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ada7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:39:24 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 01:39:24 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 01:39:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:39:24 INFO - Return code: 0 01:39:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:39:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:39:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:39:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:39:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:39:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:39:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:39:25 INFO - Return code: 0 01:39:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:39:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:39:25 INFO - Using env: {'DISPLAY': ':0', 01:39:25 INFO - 'HOME': '/home/cltbld', 01:39:25 INFO - 'LANG': 'en_US.UTF-8', 01:39:25 INFO - 'LANGUAGE': 'en_US:en', 01:39:25 INFO - 'LOGNAME': 'cltbld', 01:39:25 INFO - 'MAIL': '/var/mail/cltbld', 01:39:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:39:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:39:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:25 INFO - 'MOZ_NO_REMOTE': '1', 01:39:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:39:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:25 INFO - 'NO_EM_RESTART': '1', 01:39:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:25 INFO - 'PWD': '/builds/slave/test', 01:39:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:39:25 INFO - 'SHELL': '/bin/bash', 01:39:25 INFO - 'SHLVL': '1', 01:39:25 INFO - 'TERM': 'linux', 01:39:25 INFO - 'TMOUT': '86400', 01:39:25 INFO - 'USER': 'cltbld', 01:39:25 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026', 01:39:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:25 INFO - '_': '/tools/buildbot/bin/python'} 01:39:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:39:25 INFO - mozversion application_buildid: 20160606120332 01:39:25 INFO - mozversion application_changeset: 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 01:39:25 INFO - mozversion application_display_name: Firefox 01:39:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:39:25 INFO - mozversion application_name: Firefox 01:39:25 INFO - mozversion application_remotingname: firefox 01:39:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:39:25 INFO - mozversion application_vendor: Mozilla 01:39:25 INFO - mozversion application_version: 48.0 01:39:25 INFO - mozversion platform_buildid: 20160606120332 01:39:25 INFO - mozversion platform_changeset: 46d72a56c57dafb4dc1061d4741a3e1181ac3d68 01:39:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:39:25 INFO - mozversion platform_version: 48.0 01:39:25 INFO - using testdate: 1465288765 01:39:25 INFO - actual date: 1465288765 01:39:25 INFO - starting webserver on 'localhost:41334' 01:39:25 INFO - SUITE-START | Running 2 tests 01:39:25 INFO - TEST-START | dromaeo_css 01:39:25 INFO - operating with platform_type : linux_ 01:39:25 INFO - Initialising browser for dromaeo_css test... 01:39:25 INFO - TEST-INFO | started process 3131 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTMEBXD/profile http://localhost:41334/getInfo.html) 01:39:29 INFO - PROCESS | 3131 | __metrics Screen width/height:1600/1200 01:39:29 INFO - PROCESS | 3131 | colorDepth:24 01:39:29 INFO - PROCESS | 3131 | Browser inner width/height: 1024/697 01:39:29 INFO - PROCESS | 3131 | __metrics 01:39:30 ERROR - PROCESS | 3131 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:39:31 INFO - TEST-INFO | 3131: exit 0 01:39:31 INFO - Browser initialized. 01:39:31 INFO - Running cycle 1/1 for dromaeo_css test... 01:39:31 INFO - TEST-INFO | started process 3219 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTMEBXD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:39:43 INFO - PROCESS | 3219 | RSS: Main: 141545472 01:39:43 INFO - PROCESS | 3219 | 01:40:34 INFO - PROCESS | 3219 | 1465288834980 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] 01:42:41 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:41334/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41334/tests/dromaeo/cssquery-ext.html) 01:42:42 INFO - PROCESS | 3219 | RSS: Main: 274522112 01:42:42 INFO - PROCESS | 3219 | 01:45:45 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:41334/tests/dromaeo/cssquery-ext.html (next: http://localhost:41334/tests/dromaeo/cssquery-jquery.html) 01:45:45 INFO - PROCESS | 3219 | RSS: Main: 219340800 01:45:45 INFO - PROCESS | 3219 | 01:48:47 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:41334/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41334/tests/dromaeo/cssquery-mootools.html) 01:48:48 INFO - PROCESS | 3219 | RSS: Main: 221704192 01:48:48 INFO - PROCESS | 3219 | 01:51:49 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:41334/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41334/tests/dromaeo/cssquery-prototype.html) 01:51:50 INFO - PROCESS | 3219 | RSS: Main: 219435008 01:51:50 INFO - PROCESS | 3219 | 01:54:52 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:41334/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41334/tests/dromaeo/cssquery-yui.html) 01:54:53 INFO - PROCESS | 3219 | RSS: Main: 184635392 01:54:53 INFO - PROCESS | 3219 | 01:57:55 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:41334/tests/dromaeo/cssquery-yui.html (next: http://localhost:41334/tests/dromaeo/cssquery-dojo.html) 01:57:56 INFO - PROCESS | 3219 | RSS: Main: 253550592 01:57:56 INFO - PROCESS | 3219 | 01:57:56 INFO - PROCESS | 3219 | __start_tp_report 01:57:56 INFO - PROCESS | 3219 | _x_x_mozilla_page_load 01:57:56 INFO - PROCESS | 3219 | _x_x_mozilla_page_load_details 01:57:56 INFO - PROCESS | 3219 | |i|pagename|runs| 01:57:56 INFO - PROCESS | 3219 | |0;dojo.html;3270.182454386403;3316.353311104334;3321.534985102086;3341.6992470677633;3371.039253812664;5449.7275136243225;5450.863728406787;5453.5364494018;5456.181572764087;5461.371942226645;5447.1829225616175;5485.204645326436;5485.670859748418;5496.532794712444;5512.751926163322;4952.554270115689;4972.602191824654;5004;5046.84859454217;5064.822731204408;4928.7782049807665;4997.950020499803;5033.54698077173;5038.168702164138;5080.009398167359;5059.241113832918;5085.694858308513;5086.160783470723;5097.05704444677;5101.081805275049;5018.5232402921665;5031.094403007457;5073.365829271341;5084.186530155194;5086.440491545927;4661.487236403993;4910.754462276907;5083.720395378253;5085.084684756743;5090;5386.353637563518;5442.863928401782;5445.754941027623;5458.727063646841;5459.290292262021;5924.733386997553;5926.140709597084;5941.19793827832;5941.257342832146;5945.256842894638;5752.309722833287;5791.391903850119;5851.1223316502865;5883.058710606282;5894.145348924382;5520.558355331564;5552.500274975271;5552.944470555283;5555.805546805883;5555.805546805883;5886.087656413263;5993.61041532299;6026.276846778327;6047.0627052806885;6055.697215139314;5774.307083150049;5836.854078648068;5839.824805255849;5876.118582212703;5879.676617786063;5911.349751527328;5934.673592952429;5938.138969849348;5943.762249510058;5949;5787.65274083548;5865.853353666193;5902.1146827976145;5921.7631294747725;5925.288965324189;5837.3287071986415;5846.853828654318;5848.239728835224;5848.619839710405;5879.118132280192;4464.062546865194;4472.239719247736;4488.3940668010055;4488.618467430305;4490.842820501267;3966.3852102924093;3984.6015398460504;4011.338129208706;4043.292423825819;4090.1410703752545;5530.5299049580435;5543.556515478752;5547.528460080857;5553.666779993213;5586.636868603528;5839.708014599253;5839.883202336015;5841.620294680833;5842.182094506772;5844.736986835603;5452.672839629635;5554.6944918029085;5579.860503487364;5627.971860140673;5640.661560306395;4955.0337684151245;4993.101241776515;4995.300657907896;4999.150144475448;5016.147254966591;5920.496757775637;5936.673482958478;5939.406059394112;5998.280206375176;6003;5674.858128546819;5883.470487656132;5981.671008094422;5994.220751302303;5998.25021872266;5927.288725352985;5928.466438020427;5956.404359564009;5980.34216236205;5989.52083833301;5592.496675299242;5681.573881958952;5688.345840228427;5689.573282003784;5694;5832.3292821326095;5882.911756323744;5885.705714714161;5901.911471327848;5919.260092488439;5657.179708942263;5714.342850572238;5718.628289161191;5720.313562372542;5721.113227449752;5644.294463192101;5654.123610840326;5697.401772813795;5698.943010569841;5732.111522713986;5608.635438696471;5609.158626206102;5617.9719101404235;5677.744501497526;5706.885862282648;5723.971380143073;5765.7117144141785;5769.769209231584;5783.3638299787835;5793.478586927196;5563.638363506359;5685.60200785941;5697.344805347272;5731.885362292647;5826.300843898759 01:57:56 INFO - PROCESS | 3219 | |1;ext.html;577.2639884147711;650.0931200974641;661.8841702702023;664.886969215234;670.708241914767;32398.67601323986;38454.42318365223;40030.39954400679;40115.598844011554;40130;40393.394099088466;40767.796161019156;40783.18433631326;40804.18391632173;40832;8160.143184965582;8686.695965641187;8931.910680893174;8937.195652391283;10387.896121038806;10418.79162416755;10527.63153289635;10549.15606751462;10556.419396933205;10557.155427565776;10561.577536898552;10615.150787936986;10616.681499555025;10619.946900265526;10626.893731062706;10522.053015228628;10574;10576.312539684934;10582.788344233073;10584.629537966212;10373.11828494579;10497.160227181883;10506.264561480699;10767.300125491818;10792.352458852494;40845.79577102125;41187.176256474704;41217;41254.79372603148;41357.586424135974;3618.5115009473648;3665.6151104134215;3734.2344819311975;3885.941710874339;4057.1074363640246;30409.847950760326;30972.225694357596;31601.36797264042;31622.209444763834;31715.52426713578;4499.257622492285;4570.611498022638;4656.068786242765;4670.602998745111;4758.452777930548;316808;321545;322022.9999999977;322332;323628;39844.20311593752;64588;66016;66061.33938660649;66081.33918660849;39420.60579394227;39550.20899581992;39590.60409395927;39646.80176599098;39655;16821.57946051334;16823.074730889675;16939.15304234808;16948.06785626778;16985.15074246283;12737;13643.317834108255;13859.861401385857;13862.65343366424;13882.375293111834;9318.114779096111;9326.72019839406;9329.34694571387;9342.06579342074;9415.340926135243;3666.2850744104644;3683.686886614614;3692.6861216796337;3735.9439608405896;3752.2683076799763;1107.4960892793758;1111.5331560744507;1173.2432580985387;1179.3159967219165;1219.920705154162;33835.154121146676;33860.049627791566;34285.314293713986;34298.82850585731;34494.65505344915;1247.9625611231677;1256.5099611151659;1297.9870201297865;1329.2755448280711;1335.5192130832893;17023;17461.47615571535;17561;17563.23263413516;17734.645307094044;460863;469013;469201;469810;470255;59218.11172832411;59726.402735972086;60170.39829601648;60377.69811150916;60491;1397.6925076483158;1442.35094207607;1489.9255037248095;1498.2583621107622;1513.5156749840166;29960.25099372533;30672.864010851503;33256;33498.66501334956;33800.66199337975;49796.50203498064;49958.25062623991;50205.497945020084;50226.24660630021;50307.748461258925;309192;309481;309685;310196;310750;27397.17808465749;47553;48076.27885581649;48748;48937.51062489329;48095;48141.27788083252;48319.5168048315;49179;49577.25634115419;39243.41134882922;39505.604943950195;39621;40059.799701001306;41214.79392603018;41567;41950.370744438245;42674.57325426706;43911.56088439075;44376.77811610921;45522.54477455316;46057;46575.53424465712;46719.532804671515;46975;38266;38817.417738734504;39475.40786888142;39589;39816;29892.028027827993;30234.24414389658;30494.237644059074;30539.54190687097;30786.230344240677 01:57:56 INFO - PROCESS | 3219 | |2;jquery.html;405.7971014492752;414.264680192658;417.78424783878916;421.7048066353551;426.30725071758394;13688.247146406944;18819.153138108784;19342.419727408163;19381.612367752638;21078.262260820873;16299.59251018725;16541.421050263245;17485.91257043713;20744.792552074476;21035.57928841422;5561.4716601922755;5753.280839895013;5755.568332375067;5788.594798364095;5792.073268277077;5691.117876729114;5772.682502462363;5773.884522309551;5777.1623114648355;5778.4799368056865;5667.631603945751;5683.005474042046;5746.540276777869;5746.597738158331;5749.626274292178;5753.194552762617;5756.683382413966;5796.5362770978645;5798.304203495586;5806.825795226129;5164.2770012198325;5199.272101905736;5201.219817027439;5202.505761952609;5259.15853463444;13080.41138148776;13258.535951241756;13276.402561884739;13308.800367994489;13483.797743033861;2424.4302588891596;2437.4150203951103;2534.5564526208022;2787.888484460619;2952.808067475607;9827;9919.3056486046;10194.9490252549;13220.942324614009;13411.865881341257;1944.7277381166648;2093.413844123648;2116.777738337468;2194.1552502286577;2201.1745595401726;75105.24894751036;76986.6150669243;78847.21152788513;79892.60053699695;82364;10989.175811814146;11248.94375528117;11377.943110284561;11384.146189035855;11397.487113079931;15961;16260.59348516273;16333.673326533402;16353.591160221089;16366.099864507329;8282.751517454486;8488.320934325238;8660.39377243587;8723.214910658071;8985.955070224607;8718.694845680371;8757.781055473664;8803.779905502413;8851.513166775889;8950.955245223862;4973.925391119136;5018.397792264952;5056.82301119459;5076.7207803571155;5083.5678967288195;1810.751397898109;1896.513922385445;1930.7103934409952;1991.75103112111;2031.837453003756;736.8157960509873;1032.509557959963;1057.1701214546545;1117.608836907076;1187.1155988788407;18931.33740319082;19392.4182274532;20050.298239561544;20115.094820733102;20396.184152633727;1055.6041484443333;1078.805814953316;1089.6349722842779;1104.8563686720674;1142.1833389126648;10726.410047447465;11092.667219983414;11093.55625774976;11137.498812553455;11235.932586404278;498247;501088;521813;522918;524111;94775;97068;97097;98659.99999999856;99100.50449747706;1078.5470102556944;1117.8490903727893;1118.8713297970837;1119.3899324867966;1142.3031950510183;17755;18011.639767204844;18044;18056.638867222846;18322.809017413827;26609.46781064329;26617.86691066532;37697.81151094227;39077.80461097676;41232.38151427791;58965.70517147387;60132;60285;60638.39361606327;60730.39269607247;8692.695755648521;8860.822783544421;9009.729708108767;9034.232090272137;12643.241405515699;13884.305784710523;14066.367013484212;14328.49850255236;14616.41534338657;14625.414983400546;12377.752444950871;13035.739285214433;13348.599542013755;13671.453141874612;13716.794248086087;12922.676933076747;13285.402156903156;13334.266615335862;13412.597622071353;13714;13042.673933151747;13440.260785656492;13723.862761372258;13816.378262977989;13859.445622175;13481.797773033608;13557.457701691821;13589.592312230649;13632.727345453233;13646.52237171734;13382.397792099573;13530.93234533821;13538.526151584647;13680.384382702603;13706.246156461091 01:57:56 INFO - PROCESS | 3219 | |3;mootools.html;1382.0532934939574;1488.642725745821;1554.4481708993308;1744.1540852686444;1869.6634605770964;7946.5232086074875;8249.340052795777;8251.46365486243;8253.587320633975;8257.050439199495;8119.878201826978;8283.875741863862;8298.668053277877;8318.66725330986;8326.708565200219;2302.9539409211807;2318.1422873536817;2329.0916542548334;2330.0446816805115;2332.3352844439337;2367.3726462487452;2391.988040059797;2409.421738782697;2417.8791060446997;2421.055788242587;2320.489492311697;2358.1628521874745;2370.893309801063;2408.7952524035477;2410.8915098820594;2334.369720175543;2369.644553316999;2381.1189859752003;2386.5823480890863;2387.057112440576;2336.5910965580956;2388.4148383645943;2400.7719266669637;2401.231605886117;2409.8433601815827;7227.819304517377;8124.309433698143;8508.872366914502;8567.357448191411;8571.742847714579;5160.92258616121;5327.440618735016;5329.467053294639;5330.093884039723;5331.093714068617;3330.350581636582;3354.278830051551;3404.131946353664;3445.793252404864;3448.137965508623;1083.5828206140613;1096.2162054131254;1100.4827730966433;1101.2236373356764;1101.4162493878257;11769.117316201233;11860.406979650983;11870.287782733063;11874.940625296904;11886.821697674543;4645.558671926162;4679.227927391942;4703.318018887242;4713.835015774432;4751.904961900743;10100.444475553768;10115.291929564859;10117.645882394081;10131.138853197413;10143.036411540892;5357.43746906577;5413.729313534308;5414.729263536808;5415.566754659618;5420.13277875538;4887.584555312767;4913.754312284372;4914.508549145128;4920.606351491872;4921.261810728348;3794.2790869734663;3897.707671924617;3927.7446965947124;3929.155231625214;3930.21395720857;1195.2051885496048;1201.591458903977;1204.7710934922336;1209.328822503519;1216.683662247822;1020.285799940045;1044.8641676581997;1048.4705223862054;1052.5105825791045;1069.4438891776233;5772.191893134965;5776.797812076641;5798.971005144947;5977.282726072899;6053.092036194518;966.8936416994125;1021.8671572695501;1089.7057794395469;1109.5062697099713;1144.822552504363;3842.538895332578;3870.8645197417954;3873.593272706325;3876.302265592164;3931.8230679619487;9864.95067524672;10048.447335396624;10074.042965918226;10285.382877027403;10288.022637849543;10140.949295253477;10154.695359139238;10198.235132365102;10223;10248.026437488426;7918.297566023332;7976.162502937224;8212.46618969765;8232.259096681328;8238.711645092273;5492.340919089735;5551.056320425457;5551.195076713773;5616.466435688603;5706.372299047019;9239.168474837297;9329.11373419524;9356.251499879858;9538.189253913495;9567.138957493858;9647.083527064917;9686.70939871805;9689.563969621244;9774.02259774017;9791.265655075755;7161.785146445615;7279.308465695751;7300.087489063867;7396.889046664197;7412.8146796328365;8218.794530136794;8230.83538329242;8259.050209226016;8299.128591497805;8317.459365141247;7282.453815963718;7509.699612015458;7524.849503010018;7538.434617403607;7751.844963100593;7106.964465177641;7202.747903823341;7209.71161153548;7247.891281630885;7278.745243916437;6813.591184528944;6901.068355772108;6902.620355880274;6907.723691052302;6908.3091690830515;3989.182217645318;4835.178019736582;4915.680480768739;4974.676646017997;4997.025580011863;4836.564709176191;4838.056578967067;4915.778789954389;4923.926141107958;4925.9014819704125 01:57:56 INFO - PROCESS | 3219 | |4;prototype.html;345.20086000907895;350.4795378862388;353.1136846515246;353.7382337070568;355.09096712416215;6127.142200091991;6262.686865656722;6280.057991301307;6412.390822871826;6425.48596112311;6285.308616052231;6319.273283572391;6347;6361.809145725624;6441.774537891175;4764.499727528611;4785.1147537705465;4786.138495070886;4789.521047895218;4812.783424745878;4546.818127274905;4681.9063618727605;4800.351952486422;4807.278908163786;4819.277108433728;4741.502142275063;4756.809727610908;4760.214564596838;4769.117713223065;4770.2844573314105;4733.952660473386;4738.5261473852515;4742.928856067162;4746.169420351424;4761.095391875532;4175.144095460423;4176.415301857743;4181.205570941512;4181.832726690929;4194.622483976456;6042.093685947143;6045.939540604581;6053.243344581927;6057.4245446682935;6063.151158837766;5420.430854760272;5589.580781441408;5589.832305030855;5594.440555944414;5612.803551875665;6558.213014438298;6572.901406478906;6686.398224159849;6702.36327548882;6723.991401289797;4678.345031695565;4683.648726345537;4687.718736875764;4688.038952014829;4693.319468677023;6915.204751453597;7005.684744186524;7006.054182685323;7008.929910700878;7011.789646310619;4820.831270905502;4827.517248275144;4827.782749776268;4833.734144622009;4834.516548345207;4731.976340118277;4862.3922009748785;4887.144749668793;4889.828855990023;4909.288153217834;5556.527695145877;5610.298712660918;5628.71856407178;5630.943690563042;5636.577256705764;6723.7646682365885;6761.628110453874;6802.455803535705;6825.590464572141;6831.04365388846;1637.410532208404;1674.698554260245;1676.421634536083;1679.7732306138514;1685.88198826081;1113.5378817790688;1113.7271368514766;1128.2384390536356;1130.1580322659672;1133.3200079951962;6422.197225346832;6485.837854053592;6486.740530378826;6494.253160886558;6495.382938620824;6902.275261097512;6906.447484201251;6922.896156557656;6927.3419025192525;6928.861422771516;6491.058796474485;6491.7078731457195;6495.058216558573;6496;6497.765424569317;3709.2581483703366;3737.794421306854;3743.288775132716;3743.6069212732814;3744.3073031488716;6936.653167341714;7089.22018577956;7111;7114.92885071153;7139.643017849086;7011.158660960616;7013.824654383681;7026.402755765715;7036.6129862857015;7048.859022819515;7079.646017699094;7080.238706904312;7082.858342833115;7092.645367731593;7111.715531378791;6841.315868413119;6873.725050997904;6891.276415976451;6900.171979362509;6905.551139175937;6656.134702488645;6698.799036028927;6806.55757375769;6808.7276508939085;6809.285025072494;6944.131983502062;6946.861062778615;6950.8609827804175;6957.478189135936;6998.545094568837;4828.589569886599;4846.273059041031;4846.80612775485;4858.392700912386;4859.514048595112;6677.833054173684;6855;6865.965670171618;6873.621950792754;6881.724731010904;6767.424768894699;6786.93213067863;6796.864062718817;6801.9319806801295;6807.829804254933;6775.051492790914;6779.220389655253;6787;6789.796306110825;6795.932040679728;6652.301508341748;6661.200655921127;6667.733290668319;6671;6678.298778628174;6633.369829866155;6813.625250611066;6826.761063362758;6866.519343646097;6867.381935625817;6680.331966803281;6680.331966803476;6701.8324541884895;6707.027481015128;6714.764983225564 01:57:56 INFO - PROCESS | 3219 | |5;yui.html;824.2787560884226;832.3881946769125;875.8292133034057;923.593618807725;935.854942483915;15569.143697096655;15707.214639268019;15789.210539473024;15797.131157786318;15806.683866322666;13478.865211347884;13539.661508462294;13584.320783960811;13641.249731264776;13646.317684115804;724.9782506524813;858.6737039924835;868.9913100868973;870.3081050564784;870.4777133719749;739.5008369350694;747.2900744292916;751.1249394455452;857.6397912876575;875.295387213293;893.8480458322101;904.5477261369315;906.1074841281365;907.1110311894347;907.1699395053519;888.3958907942597;898.7618281155499;899.1323372945074;899.6761165980209;900.3022657440471;566.2949627713482;578.9102689083198;663.618419408837;667.2993356975205;673.3132205150768;14818;14820.258987050713;14834;14835.703285934329;14900.925495372454;13544.796828047587;14210.14739115646;14243.501477448239;14263.78604320936;14296.857031429656;11659.358735269558;11727.941360293144;11776.293422394598;11888.940555297255;11968.222065565797;4050.7164498485117;4052.2098190852794;4108.794560271975;4145.502539695226;4164.1879833432495;51288;51363.743181284226;51432;56141.99999999959;56374;11642.825357619642;11875.40622968848;11879.287242765462;12040.578579749841;12051.879481205251;19963.60072798536;20042;20047.699284510745;20054.89972550128;20097.799022009884;8867.822643547093;8869.113088691209;8941.955290223637;9240.8613870792;9296.72109836706;4919.1883339248625;5032.496750325012;5042.319286896295;5390;5393.164059570773;3573.3032058748813;3595.802230877327;3619.8552057917914;3623.5289412376223;3629.201575653352;653.0758976048837;657.2179106862914;662.602438536874;672.9495287853431;682.2393031769541;708.3943228539628;781.5232708048086;796.6932730898529;808.3452403553141;812.3095368936357;9589.472579008081;10129.797404051878;10171.186305095574;10220.131288840532;10228.846567301496;682.9146356705412;764.2930289482207;771.7453240430644;784.4312873166908;788.1173086143556;4380.6057454829215;4427.247367947457;4432.645388368923;4460.487043989982;4522.118186953512;59364.70317648384;59948.100778488355;60089.69955150284;65002.67498662476;65928.34071659319;54632.72683636636;54816.72591637016;54959;55609.1658625121;56206;49612.50387496151;50798.746006269736;50814.237786432495;52047.21929171065;52494.737526312885;9703.854442183514;9966.60133594677;10025.50549291491;10042.246831487522;10144.28989970695;38208.42687359636;38380.80809595934;39271;39388;40022.1995560093;45650.54349456463;51345;51869.22196167136;51870.22194667158;52018.21972670488;11840.82238766402;12048.096392770402;12054.397280136134;12083.033357331216;12230.571929982409;13609.183448993092;13816.861831381557;14067.366968486647;14262.358193881508;14289.857101429268;11757.000654944084;11912.523499060288;13075;13336.799948000593;13523.72952540924;11516.942415288206;12864.485420583072;12891;13197.670058248621;14505.782413263598;13764.17414955106;13885.722285554435;13972.091814032055;14089.577312680636;14347.067440616327;10635.94682026585;10841.837372439262;10847.728806779895;10933.180011499011;11038.17213708991;10746.570137194425;10881.727956801144;10912.181586381212;11155;11397.145214108808 01:57:56 INFO - PROCESS | 3219 | __end_tp_report 01:57:56 INFO - PROCESS | 3219 | __start_cc_report 01:57:56 INFO - PROCESS | 3219 | _x_x_mozilla_cycle_collect,840 01:57:56 INFO - PROCESS | 3219 | __end_cc_report 01:57:56 INFO - PROCESS | 3219 | __startTimestamp1465289876186__endTimestamp 01:57:56 INFO - PROCESS | 3219 | 01:57:56 INFO - PROCESS | 3219 | ------- Summary: start ------- 01:57:56 INFO - PROCESS | 3219 | Number of tests: 6 01:57:56 INFO - PROCESS | 3219 | 01:57:56 INFO - PROCESS | 3219 | [#0] dojo.html Cycles:165 Average:5454.63 Median:5657.18 stddev:594.70 (10.5%) stddev-sans-first:571.30 01:57:56 INFO - PROCESS | 3219 | Values: 3270.2 3316.4 3321.5 3341.7 3371.0 5449.7 5450.9 5453.5 5456.2 5461.4 5447.2 5485.2 5485.7 5496.5 5512.8 4952.6 4972.6 5004.0 5046.8 5064.8 4928.8 4998.0 5033.5 5038.2 5080.0 5059.2 5085.7 5086.2 5097.1 5101.1 5018.5 5031.1 5073.4 5084.2 5086.4 4661.5 4910.8 5083.7 5085.1 5090.0 5386.4 5442.9 5445.8 5458.7 5459.3 5924.7 5926.1 5941.2 5941.3 5945.3 5752.3 5791.4 5851.1 5883.1 5894.1 5520.6 5552.5 5552.9 5555.8 5555.8 5886.1 5993.6 6026.3 6047.1 6055.7 5774.3 5836.9 5839.8 5876.1 5879.7 5911.3 5934.7 5938.1 5943.8 5949.0 5787.7 5865.9 5902.1 5921.8 5925.3 5837.3 5846.9 5848.2 5848.6 5879.1 4464.1 4472.2 4488.4 4488.6 4490.8 3966.4 3984.6 4011.3 4043.3 4090.1 5530.5 5543.6 5547.5 5553.7 5586.6 5839.7 5839.9 5841.6 5842.2 5844.7 5452.7 5554.7 5579.9 5628.0 5640.7 4955.0 4993.1 4995.3 4999.2 5016.1 5920.5 5936.7 5939.4 5998.3 6003.0 5674.9 5883.5 5981.7 5994.2 5998.3 5927.3 5928.5 5956.4 5980.3 5989.5 5592.5 5681.6 5688.3 5689.6 5694.0 5832.3 5882.9 5885.7 5901.9 5919.3 5657.2 5714.3 5718.6 5720.3 5721.1 5644.3 5654.1 5697.4 5698.9 5732.1 5608.6 5609.2 5618.0 5677.7 5706.9 5724.0 5765.7 5769.8 5783.4 5793.5 5563.6 5685.6 5697.3 5731.9 5826.3 01:57:56 INFO - PROCESS | 3219 | 01:57:56 INFO - PROCESS | 3219 | [#1] ext.html Cycles:180 Average:53994.76 Median:21009.13 stddev:98379.06 (468.3%) stddev-sans-first:98573.29 01:57:56 INFO - PROCESS | 3219 | Values: 577.3 650.1 661.9 664.9 670.7 32398.7 38454.4 40030.4 40115.6 40130.0 40393.4 40767.8 40783.2 40804.2 40832.0 8160.1 8686.7 8931.9 8937.2 10387.9 10418.8 10527.6 10549.2 10556.4 10557.2 10561.6 10615.2 10616.7 10619.9 10626.9 10522.1 10574.0 10576.3 10582.8 10584.6 10373.1 10497.2 10506.3 10767.3 10792.4 40845.8 41187.2 41217.0 41254.8 41357.6 3618.5 3665.6 3734.2 3885.9 4057.1 30409.8 30972.2 31601.4 31622.2 31715.5 4499.3 4570.6 4656.1 4670.6 4758.5 316808.0 321545.0 322023.0 322332.0 323628.0 39844.2 64588.0 66016.0 66061.3 66081.3 39420.6 39550.2 39590.6 39646.8 39655.0 16821.6 16823.1 16939.2 16948.1 16985.2 12737.0 13643.3 13859.9 13862.7 13882.4 9318.1 9326.7 9329.3 9342.1 9415.3 3666.3 3683.7 3692.7 3735.9 3752.3 1107.5 1111.5 1173.2 1179.3 1219.9 33835.2 33860.0 34285.3 34298.8 34494.7 1248.0 1256.5 1298.0 1329.3 1335.5 17023.0 17461.5 17561.0 17563.2 17734.6 460863.0 469013.0 469201.0 469810.0 470255.0 59218.1 59726.4 60170.4 60377.7 60491.0 1397.7 1442.4 1489.9 1498.3 1513.5 29960.3 30672.9 33256.0 33498.7 33800.7 49796.5 49958.3 50205.5 50226.2 50307.7 309192.0 309481.0 309685.0 310196.0 310750.0 27397.2 47553.0 48076.3 48748.0 48937.5 48095.0 48141.3 48319.5 49179.0 49577.3 39243.4 39505.6 39621.0 40059.8 41214.8 41567.0 41950.4 42674.6 43911.6 44376.8 45522.5 46057.0 46575.5 46719.5 46975.0 38266.0 38817.4 39475.4 39589.0 39816.0 29892.0 30234.2 30494.2 30539.5 30786.2 01:57:56 INFO - PROCESS | 3219 | 01:57:56 INFO - PROCESS | 3219 | [#2] jquery.html Cycles:180 Average:29837.37 Median:20570.49 stddev:84594.41 (411.2%) stddev-sans-first:84802.86 01:57:56 INFO - PROCESS | 3219 | Values: 405.8 414.3 417.8 421.7 426.3 13688.2 18819.2 19342.4 19381.6 21078.3 16299.6 16541.4 17485.9 20744.8 21035.6 5561.5 5753.3 5755.6 5788.6 5792.1 5691.1 5772.7 5773.9 5777.2 5778.5 5667.6 5683.0 5746.5 5746.6 5749.6 5753.2 5756.7 5796.5 5798.3 5806.8 5164.3 5199.3 5201.2 5202.5 5259.2 13080.4 13258.5 13276.4 13308.8 13483.8 2424.4 2437.4 2534.6 2787.9 2952.8 9827.0 9919.3 10194.9 13220.9 13411.9 1944.7 2093.4 2116.8 2194.2 2201.2 75105.2 76986.6 78847.2 79892.6 82364.0 10989.2 11248.9 11377.9 11384.1 11397.5 15961.0 16260.6 16333.7 16353.6 16366.1 8282.8 8488.3 8660.4 8723.2 8986.0 8718.7 8757.8 8803.8 8851.5 8951.0 4973.9 5018.4 5056.8 5076.7 5083.6 1810.8 1896.5 1930.7 1991.8 2031.8 736.8 1032.5 1057.2 1117.6 1187.1 18931.3 19392.4 20050.3 20115.1 20396.2 1055.6 1078.8 1089.6 1104.9 1142.2 10726.4 11092.7 11093.6 11137.5 11235.9 498247.0 501088.0 521813.0 522918.0 524111.0 94775.0 97068.0 97097.0 98660.0 99100.5 1078.5 1117.8 1118.9 1119.4 1142.3 17755.0 18011.6 18044.0 18056.6 18322.8 26609.5 26617.9 37697.8 39077.8 41232.4 58965.7 60132.0 60285.0 60638.4 60730.4 8692.7 8860.8 9009.7 9034.2 12643.2 13884.3 14066.4 14328.5 14616.4 14625.4 12377.8 13035.7 13348.6 13671.5 13716.8 12922.7 13285.4 13334.3 13412.6 13714.0 13042.7 13440.3 13723.9 13816.4 13859.4 13481.8 13557.5 13589.6 13632.7 13646.5 13382.4 13530.9 13538.5 13680.4 13706.2 01:57:56 INFO - PROCESS | 3219 | 01:57:56 INFO - PROCESS | 3219 | [#3] mootools.html Cycles:180 Average:5606.22 Median:4837.31 stddev:3083.21 (63.7%) stddev-sans-first:3075.51 01:57:56 INFO - PROCESS | 3219 | Values: 1382.1 1488.6 1554.4 1744.2 1869.7 7946.5 8249.3 8251.5 8253.6 8257.1 8119.9 8283.9 8298.7 8318.7 8326.7 2303.0 2318.1 2329.1 2330.0 2332.3 2367.4 2392.0 2409.4 2417.9 2421.1 2320.5 2358.2 2370.9 2408.8 2410.9 2334.4 2369.6 2381.1 2386.6 2387.1 2336.6 2388.4 2400.8 2401.2 2409.8 7227.8 8124.3 8508.9 8567.4 8571.7 5160.9 5327.4 5329.5 5330.1 5331.1 3330.4 3354.3 3404.1 3445.8 3448.1 1083.6 1096.2 1100.5 1101.2 1101.4 11769.1 11860.4 11870.3 11874.9 11886.8 4645.6 4679.2 4703.3 4713.8 4751.9 10100.4 10115.3 10117.6 10131.1 10143.0 5357.4 5413.7 5414.7 5415.6 5420.1 4887.6 4913.8 4914.5 4920.6 4921.3 3794.3 3897.7 3927.7 3929.2 3930.2 1195.2 1201.6 1204.8 1209.3 1216.7 1020.3 1044.9 1048.5 1052.5 1069.4 5772.2 5776.8 5799.0 5977.3 6053.1 966.9 1021.9 1089.7 1109.5 1144.8 3842.5 3870.9 3873.6 3876.3 3931.8 9865.0 10048.4 10074.0 10285.4 10288.0 10140.9 10154.7 10198.2 10223.0 10248.0 7918.3 7976.2 8212.5 8232.3 8238.7 5492.3 5551.1 5551.2 5616.5 5706.4 9239.2 9329.1 9356.3 9538.2 9567.1 9647.1 9686.7 9689.6 9774.0 9791.3 7161.8 7279.3 7300.1 7396.9 7412.8 8218.8 8230.8 8259.1 8299.1 8317.5 7282.5 7509.7 7524.8 7538.4 7751.8 7107.0 7202.7 7209.7 7247.9 7278.7 6813.6 6901.1 6902.6 6907.7 6908.3 3989.2 4835.2 4915.7 4974.7 4997.0 4836.6 4838.1 4915.8 4923.9 4925.9 01:57:56 INFO - PROCESS | 3219 | 01:57:56 INFO - PROCESS | 3219 | [#4] prototype.html Cycles:180 Average:5607.40 Median:6463.81 stddev:1691.13 (26.2%) stddev-sans-first:1649.10 01:57:56 INFO - PROCESS | 3219 | Values: 345.2 350.5 353.1 353.7 355.1 6127.1 6262.7 6280.1 6412.4 6425.5 6285.3 6319.3 6347.0 6361.8 6441.8 4764.5 4785.1 4786.1 4789.5 4812.8 4546.8 4681.9 4800.4 4807.3 4819.3 4741.5 4756.8 4760.2 4769.1 4770.3 4734.0 4738.5 4742.9 4746.2 4761.1 4175.1 4176.4 4181.2 4181.8 4194.6 6042.1 6045.9 6053.2 6057.4 6063.2 5420.4 5589.6 5589.8 5594.4 5612.8 6558.2 6572.9 6686.4 6702.4 6724.0 4678.3 4683.6 4687.7 4688.0 4693.3 6915.2 7005.7 7006.1 7008.9 7011.8 4820.8 4827.5 4827.8 4833.7 4834.5 4732.0 4862.4 4887.1 4889.8 4909.3 5556.5 5610.3 5628.7 5630.9 5636.6 6723.8 6761.6 6802.5 6825.6 6831.0 1637.4 1674.7 1676.4 1679.8 1685.9 1113.5 1113.7 1128.2 1130.2 1133.3 6422.2 6485.8 6486.7 6494.3 6495.4 6902.3 6906.4 6922.9 6927.3 6928.9 6491.1 6491.7 6495.1 6496.0 6497.8 3709.3 3737.8 3743.3 3743.6 3744.3 6936.7 7089.2 7111.0 7114.9 7139.6 7011.2 7013.8 7026.4 7036.6 7048.9 7079.6 7080.2 7082.9 7092.6 7111.7 6841.3 6873.7 6891.3 6900.2 6905.6 6656.1 6698.8 6806.6 6808.7 6809.3 6944.1 6946.9 6950.9 6957.5 6998.5 4828.6 4846.3 4846.8 4858.4 4859.5 6677.8 6855.0 6866.0 6873.6 6881.7 6767.4 6786.9 6796.9 6801.9 6807.8 6775.1 6779.2 6787.0 6789.8 6795.9 6652.3 6661.2 6667.7 6671.0 6678.3 6633.4 6813.6 6826.8 6866.5 6867.4 6680.3 6680.3 6701.8 6707.0 6714.8 01:57:56 INFO - PROCESS | 3219 | 01:57:56 INFO - PROCESS | 3219 | [#5] yui.html Cycles:180 Average:15405.91 Median:4154.85 stddev:17473.08 (420.5%) stddev-sans-first:17487.78 01:57:56 INFO - PROCESS | 3219 | Values: 824.3 832.4 875.8 923.6 935.9 15569.1 15707.2 15789.2 15797.1 15806.7 13478.9 13539.7 13584.3 13641.2 13646.3 725.0 858.7 869.0 870.3 870.5 739.5 747.3 751.1 857.6 875.3 893.8 904.5 906.1 907.1 907.2 888.4 898.8 899.1 899.7 900.3 566.3 578.9 663.6 667.3 673.3 14818.0 14820.3 14834.0 14835.7 14900.9 13544.8 14210.1 14243.5 14263.8 14296.9 11659.4 11727.9 11776.3 11888.9 11968.2 4050.7 4052.2 4108.8 4145.5 4164.2 51288.0 51363.7 51432.0 56142.0 56374.0 11642.8 11875.4 11879.3 12040.6 12051.9 19963.6 20042.0 20047.7 20054.9 20097.8 8867.8 8869.1 8942.0 9240.9 9296.7 4919.2 5032.5 5042.3 5390.0 5393.2 3573.3 3595.8 3619.9 3623.5 3629.2 653.1 657.2 662.6 672.9 682.2 708.4 781.5 796.7 808.3 812.3 9589.5 10129.8 10171.2 10220.1 10228.8 682.9 764.3 771.7 784.4 788.1 4380.6 4427.2 4432.6 4460.5 4522.1 59364.7 59948.1 60089.7 65002.7 65928.3 54632.7 54816.7 54959.0 55609.2 56206.0 49612.5 50798.7 50814.2 52047.2 52494.7 9703.9 9966.6 10025.5 10042.2 10144.3 38208.4 38380.8 39271.0 39388.0 40022.2 45650.5 51345.0 51869.2 51870.2 52018.2 11840.8 12048.1 12054.4 12083.0 12230.6 13609.2 13816.9 14067.4 14262.4 14289.9 11757.0 11912.5 13075.0 13336.8 13523.7 11516.9 12864.5 12891.0 13197.7 14505.8 13764.2 13885.7 13972.1 14089.6 14347.1 10635.9 10841.8 10847.7 10933.2 11038.2 10746.6 10881.7 10912.2 11155.0 11397.1 01:57:56 INFO - PROCESS | 3219 | -------- Summary: end -------- 01:57:56 INFO - PROCESS | 3219 | 01:57:56 INFO - TEST-INFO | 3219: exit 0 01:57:56 INFO - TEST-OK | dromaeo_css | took 1111606ms 01:57:56 INFO - TEST-START | kraken 01:57:56 INFO - operating with platform_type : linux_ 01:57:56 INFO - Initialising browser for kraken test... 01:57:56 INFO - TEST-INFO | started process 3415 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYFk2Qk/profile http://localhost:41334/getInfo.html) 01:58:01 INFO - PROCESS | 3415 | __metrics Screen width/height:1600/1200 01:58:01 INFO - PROCESS | 3415 | colorDepth:24 01:58:01 INFO - PROCESS | 3415 | Browser inner width/height: 1024/697 01:58:01 INFO - PROCESS | 3415 | __metrics 01:58:02 ERROR - PROCESS | 3415 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:58:03 INFO - TEST-INFO | 3415: exit 0 01:58:03 INFO - Browser initialized. 01:58:03 INFO - Running cycle 1/1 for kraken test... 01:58:03 INFO - TEST-INFO | started process 3505 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYFk2Qk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:58:15 INFO - PROCESS | 3505 | RSS: Main: 140959744 01:58:15 INFO - PROCESS | 3505 | 01:58:17 INFO - PROCESS | 3505 | Cycle 1(1): loaded http://localhost:41334/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41334/tests/kraken/driver.html?testName=audio-beat-detection) 01:58:18 INFO - PROCESS | 3505 | RSS: Main: 197099520 01:58:18 INFO - PROCESS | 3505 | 01:58:21 INFO - PROCESS | 3505 | Cycle 1(1): loaded http://localhost:41334/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41334/tests/kraken/driver.html?testName=audio-dft) 01:58:21 INFO - PROCESS | 3505 | RSS: Main: 183808000 01:58:21 INFO - PROCESS | 3505 | 01:58:24 INFO - PROCESS | 3505 | Cycle 1(1): loaded http://localhost:41334/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41334/tests/kraken/driver.html?testName=audio-fft) 01:58:25 INFO - PROCESS | 3505 | RSS: Main: 272175104 01:58:25 INFO - PROCESS | 3505 | 01:58:27 INFO - PROCESS | 3505 | Cycle 1(1): loaded http://localhost:41334/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41334/tests/kraken/driver.html?testName=audio-oscillator) 01:58:27 INFO - PROCESS | 3505 | RSS: Main: 179265536 01:58:27 INFO - PROCESS | 3505 | 01:58:29 INFO - PROCESS | 3505 | Cycle 1(1): loaded http://localhost:41334/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41334/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:58:30 INFO - PROCESS | 3505 | RSS: Main: 177909760 01:58:30 INFO - PROCESS | 3505 | 01:58:33 INFO - PROCESS | 3505 | Cycle 1(1): loaded http://localhost:41334/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41334/tests/kraken/driver.html?testName=imaging-darkroom) 01:58:34 INFO - PROCESS | 3505 | RSS: Main: 212123648 01:58:34 INFO - PROCESS | 3505 | 01:58:37 INFO - PROCESS | 3505 | Cycle 1(1): loaded http://localhost:41334/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41334/tests/kraken/driver.html?testName=imaging-desaturate) 01:58:38 INFO - PROCESS | 3505 | RSS: Main: 212037632 01:58:38 INFO - PROCESS | 3505 | 01:58:40 INFO - PROCESS | 3505 | Cycle 1(1): loaded http://localhost:41334/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41334/tests/kraken/driver.html?testName=json-parse-financial) 01:58:41 INFO - PROCESS | 3505 | RSS: Main: 212107264 01:58:41 INFO - PROCESS | 3505 | 01:58:42 INFO - PROCESS | 3505 | Cycle 1(1): loaded http://localhost:41334/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41334/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:58:43 INFO - PROCESS | 3505 | RSS: Main: 191533056 01:58:43 INFO - PROCESS | 3505 | 01:58:45 INFO - PROCESS | 3505 | Cycle 1(1): loaded http://localhost:41334/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41334/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:58:45 INFO - PROCESS | 3505 | RSS: Main: 193728512 01:58:45 INFO - PROCESS | 3505 | 01:58:47 INFO - PROCESS | 3505 | Cycle 1(1): loaded http://localhost:41334/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41334/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:58:48 INFO - PROCESS | 3505 | RSS: Main: 208601088 01:58:48 INFO - PROCESS | 3505 | 01:58:50 INFO - PROCESS | 3505 | Cycle 1(1): loaded http://localhost:41334/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41334/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:58:51 INFO - PROCESS | 3505 | RSS: Main: 215969792 01:58:51 INFO - PROCESS | 3505 | 01:58:53 INFO - PROCESS | 3505 | Cycle 1(1): loaded http://localhost:41334/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41334/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:58:54 INFO - PROCESS | 3505 | RSS: Main: 212623360 01:58:54 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | Cycle 1(1): loaded http://localhost:41334/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41334/tests/kraken/driver.html?testName=ai-astar) 01:58:56 INFO - PROCESS | 3505 | RSS: Main: 273522688 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | __start_tp_report 01:58:56 INFO - PROCESS | 3505 | _x_x_mozilla_page_load 01:58:56 INFO - PROCESS | 3505 | _x_x_mozilla_page_load_details 01:58:56 INFO - PROCESS | 3505 | |i|pagename|runs| 01:58:56 INFO - PROCESS | 3505 | |0;ai-astar;114;111;110;109;111;111;105;109;109;110 01:58:56 INFO - PROCESS | 3505 | |1;audio-beat-detection;154;136;163;189;128;151;154;128;183;128 01:58:56 INFO - PROCESS | 3505 | |2;audio-dft;163;160;163;161;162;161;160;161;161;161 01:58:56 INFO - PROCESS | 3505 | |3;audio-fft;77;78;77;177;73;75;76;75;165;76 01:58:56 INFO - PROCESS | 3505 | |4;audio-oscillator;93;93;158;93;91;92;152;91;93;155 01:58:56 INFO - PROCESS | 3505 | |5;imaging-gaussian-blur;117;117;116;117;117;117;116;117;117;117 01:58:56 INFO - PROCESS | 3505 | |6;imaging-darkroom;145;146;146;145;145;145;146;145;145;145 01:58:56 INFO - PROCESS | 3505 | |7;imaging-desaturate;95;94;95;95;95;94;94;95;95;94 01:58:56 INFO - PROCESS | 3505 | |8;json-parse-financial;52;53;52;53;54;53;54;67;51;52 01:58:56 INFO - PROCESS | 3505 | |9;json-stringify-tinderbox;59;59;58;58;59;58;58;59;58;59 01:58:56 INFO - PROCESS | 3505 | |10;stanford-crypto-aes;71;74;72;73;74;74;74;80;73;75 01:58:56 INFO - PROCESS | 3505 | |11;stanford-crypto-ccm;119;119;113;120;112;109;116;112;128;130 01:58:56 INFO - PROCESS | 3505 | |12;stanford-crypto-pbkdf2;157;157;165;161;159;159;154;156;160;156 01:58:56 INFO - PROCESS | 3505 | |13;stanford-crypto-sha256-iterative;65;75;64;66;65;65;67;65;64;67 01:58:56 INFO - PROCESS | 3505 | __end_tp_report 01:58:56 INFO - PROCESS | 3505 | __start_cc_report 01:58:56 INFO - PROCESS | 3505 | _x_x_mozilla_cycle_collect,1589 01:58:56 INFO - PROCESS | 3505 | __end_cc_report 01:58:56 INFO - PROCESS | 3505 | __startTimestamp1465289936481__endTimestamp 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | ------- Summary: start ------- 01:58:56 INFO - PROCESS | 3505 | Number of tests: 14 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | [#0] ai-astar Cycles:10 Average:109.90 Median:110.50 stddev:2.28 (2.1%) stddev-sans-first:1.88 01:58:56 INFO - PROCESS | 3505 | Values: 114.0 111.0 110.0 109.0 111.0 111.0 105.0 109.0 109.0 110.0 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | [#1] audio-beat-detection Cycles:10 Average:151.40 Median:154.00 stddev:22.21 (14.4%) stddev-sans-first:23.54 01:58:56 INFO - PROCESS | 3505 | Values: 154.0 136.0 163.0 189.0 128.0 151.0 154.0 128.0 183.0 128.0 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:0.93 01:58:56 INFO - PROCESS | 3505 | Values: 163.0 160.0 163.0 161.0 162.0 161.0 160.0 161.0 161.0 161.0 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | [#3] audio-fft Cycles:10 Average:94.90 Median:76.00 stddev:40.23 (52.9%) stddev-sans-first:42.15 01:58:56 INFO - PROCESS | 3505 | Values: 77.0 78.0 77.0 177.0 73.0 75.0 76.0 75.0 165.0 76.0 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | [#4] audio-oscillator Cycles:10 Average:111.10 Median:92.50 stddev:30.34 (32.8%) stddev-sans-first:31.46 01:58:56 INFO - PROCESS | 3505 | Values: 93.0 93.0 158.0 93.0 91.0 92.0 152.0 91.0 93.0 155.0 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 01:58:56 INFO - PROCESS | 3505 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 01:58:56 INFO - PROCESS | 3505 | Values: 145.0 146.0 146.0 145.0 145.0 145.0 146.0 145.0 145.0 145.0 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 01:58:56 INFO - PROCESS | 3505 | Values: 95.0 94.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 94.0 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:53.00 stddev:4.63 (8.7%) stddev-sans-first:4.85 01:58:56 INFO - PROCESS | 3505 | Values: 52.0 53.0 52.0 53.0 54.0 53.0 54.0 67.0 51.0 52.0 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | [#9] json-stringify-tinderbox Cycles:10 Average:58.50 Median:59.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 01:58:56 INFO - PROCESS | 3505 | Values: 59.0 59.0 58.0 58.0 59.0 58.0 58.0 59.0 58.0 59.0 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:74.00 stddev:2.40 (3.2%) stddev-sans-first:2.29 01:58:56 INFO - PROCESS | 3505 | Values: 71.0 74.0 72.0 73.0 74.0 74.0 74.0 80.0 73.0 75.0 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | [#11] stanford-crypto-ccm Cycles:10 Average:117.80 Median:119.00 stddev:6.92 (5.8%) stddev-sans-first:7.33 01:58:56 INFO - PROCESS | 3505 | Values: 119.0 119.0 113.0 120.0 112.0 109.0 116.0 112.0 128.0 130.0 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.40 Median:159.00 stddev:3.13 (2.0%) stddev-sans-first:3.28 01:58:56 INFO - PROCESS | 3505 | Values: 157.0 157.0 165.0 161.0 159.0 159.0 154.0 156.0 160.0 156.0 01:58:56 INFO - PROCESS | 3505 | 01:58:56 INFO - PROCESS | 3505 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:65.50 stddev:3.23 (4.9%) stddev-sans-first:3.40 01:58:56 INFO - PROCESS | 3505 | Values: 65.0 75.0 64.0 66.0 65.0 65.0 67.0 65.0 64.0 67.0 01:58:56 INFO - PROCESS | 3505 | -------- Summary: end -------- 01:58:56 INFO - PROCESS | 3505 | 01:58:57 INFO - TEST-INFO | 3505: exit 0 01:58:57 INFO - TEST-OK | kraken | took 60583ms 01:58:57 INFO - SUITE-END | took 1172s 01:58:57 INFO - Completed test suite (00:19:32) 01:58:57 INFO - javascript benchmark 01:58:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3270.182454386403, 3316.353311104334, 3321.534985102086, 3341.6992470677633, 3371.039253812664, 5449.7275136243225, 5450.863728406787, 5453.5364494018, 5456.181572764087, 5461.371942226645, 5447.1829225616175, 5485.204645326436, 5485.670859748418, 5496.532794712444, 5512.751926163322, 4952.554270115689, 4972.602191824654, 5004.0, 5046.84859454217, 5064.822731204408, 4928.7782049807665, 4997.950020499803, 5033.54698077173, 5038.168702164138, 5080.009398167359, 5059.241113832918, 5085.694858308513, 5086.160783470723, 5097.05704444677, 5101.081805275049, 5018.5232402921665, 5031.094403007457, 5073.365829271341, 5084.186530155194, 5086.440491545927, 4661.487236403993, 4910.754462276907, 5083.720395378253, 5085.084684756743, 5090.0, 5386.353637563518, 5442.863928401782, 5445.754941027623, 5458.727063646841, 5459.290292262021, 5924.733386997553, 5926.140709597084, 5941.19793827832, 5941.257342832146, 5945.256842894638, 5752.309722833287, 5791.391903850119, 5851.1223316502865, 5883.058710606282, 5894.145348924382, 5520.558355331564, 5552.500274975271, 5552.944470555283, 5555.805546805883, 5555.805546805883, 5886.087656413263, 5993.61041532299, 6026.276846778327, 6047.0627052806885, 6055.697215139314, 5774.307083150049, 5836.854078648068, 5839.824805255849, 5876.118582212703, 5879.676617786063, 5911.349751527328, 5934.673592952429, 5938.138969849348, 5943.762249510058, 5949.0, 5787.65274083548, 5865.853353666193, 5902.1146827976145, 5921.7631294747725, 5925.288965324189, 5837.3287071986415, 5846.853828654318, 5848.239728835224, 5848.619839710405, 5879.118132280192, 4464.062546865194, 4472.239719247736, 4488.3940668010055, 4488.618467430305, 4490.842820501267, 3966.3852102924093, 3984.6015398460504, 4011.338129208706, 4043.292423825819, 4090.1410703752545, 5530.5299049580435, 5543.556515478752, 5547.528460080857, 5553.666779993213, 5586.636868603528, 5839.708014599253, 5839.883202336015, 5841.620294680833, 5842.182094506772, 5844.736986835603, 5452.672839629635, 5554.6944918029085, 5579.860503487364, 5627.971860140673, 5640.661560306395, 4955.0337684151245, 4993.101241776515, 4995.300657907896, 4999.150144475448, 5016.147254966591, 5920.496757775637, 5936.673482958478, 5939.406059394112, 5998.280206375176, 6003.0, 5674.858128546819, 5883.470487656132, 5981.671008094422, 5994.220751302303, 5998.25021872266, 5927.288725352985, 5928.466438020427, 5956.404359564009, 5980.34216236205, 5989.52083833301, 5592.496675299242, 5681.573881958952, 5688.345840228427, 5689.573282003784, 5694.0, 5832.3292821326095, 5882.911756323744, 5885.705714714161, 5901.911471327848, 5919.260092488439, 5657.179708942263, 5714.342850572238, 5718.628289161191, 5720.313562372542, 5721.113227449752, 5644.294463192101, 5654.123610840326, 5697.401772813795, 5698.943010569841, 5732.111522713986, 5608.635438696471, 5609.158626206102, 5617.9719101404235, 5677.744501497526, 5706.885862282648, 5723.971380143073, 5765.7117144141785, 5769.769209231584, 5783.3638299787835, 5793.478586927196, 5563.638363506359, 5685.60200785941, 5697.344805347272, 5731.885362292647, 5826.300843898759], "value": 5416.683175037562, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.2639884147711, 650.0931200974641, 661.8841702702023, 664.886969215234, 670.708241914767, 32398.67601323986, 38454.42318365223, 40030.39954400679, 40115.598844011554, 40130.0, 40393.394099088466, 40767.796161019156, 40783.18433631326, 40804.18391632173, 40832.0, 8160.143184965582, 8686.695965641187, 8931.910680893174, 8937.195652391283, 10387.896121038806, 10418.79162416755, 10527.63153289635, 10549.15606751462, 10556.419396933205, 10557.155427565776, 10561.577536898552, 10615.150787936986, 10616.681499555025, 10619.946900265526, 10626.893731062706, 10522.053015228628, 10574.0, 10576.312539684934, 10582.788344233073, 10584.629537966212, 10373.11828494579, 10497.160227181883, 10506.264561480699, 10767.300125491818, 10792.352458852494, 40845.79577102125, 41187.176256474704, 41217.0, 41254.79372603148, 41357.586424135974, 3618.5115009473648, 3665.6151104134215, 3734.2344819311975, 3885.941710874339, 4057.1074363640246, 30409.847950760326, 30972.225694357596, 31601.36797264042, 31622.209444763834, 31715.52426713578, 4499.257622492285, 4570.611498022638, 4656.068786242765, 4670.602998745111, 4758.452777930548, 316808.0, 321545.0, 322022.9999999977, 322332.0, 323628.0, 39844.20311593752, 64588.0, 66016.0, 66061.33938660649, 66081.33918660849, 39420.60579394227, 39550.20899581992, 39590.60409395927, 39646.80176599098, 39655.0, 16821.57946051334, 16823.074730889675, 16939.15304234808, 16948.06785626778, 16985.15074246283, 12737.0, 13643.317834108255, 13859.861401385857, 13862.65343366424, 13882.375293111834, 9318.114779096111, 9326.72019839406, 9329.34694571387, 9342.06579342074, 9415.340926135243, 3666.2850744104644, 3683.686886614614, 3692.6861216796337, 3735.9439608405896, 3752.2683076799763, 1107.4960892793758, 1111.5331560744507, 1173.2432580985387, 1179.3159967219165, 1219.920705154162, 33835.154121146676, 33860.049627791566, 34285.314293713986, 34298.82850585731, 34494.65505344915, 1247.9625611231677, 1256.5099611151659, 1297.9870201297865, 1329.2755448280711, 1335.5192130832893, 17023.0, 17461.47615571535, 17561.0, 17563.23263413516, 17734.645307094044, 460863.0, 469013.0, 469201.0, 469810.0, 470255.0, 59218.11172832411, 59726.402735972086, 60170.39829601648, 60377.69811150916, 60491.0, 1397.6925076483158, 1442.35094207607, 1489.9255037248095, 1498.2583621107622, 1513.5156749840166, 29960.25099372533, 30672.864010851503, 33256.0, 33498.66501334956, 33800.66199337975, 49796.50203498064, 49958.25062623991, 50205.497945020084, 50226.24660630021, 50307.748461258925, 309192.0, 309481.0, 309685.0, 310196.0, 310750.0, 27397.17808465749, 47553.0, 48076.27885581649, 48748.0, 48937.51062489329, 48095.0, 48141.27788083252, 48319.5168048315, 49179.0, 49577.25634115419, 39243.41134882922, 39505.604943950195, 39621.0, 40059.799701001306, 41214.79392603018, 41567.0, 41950.370744438245, 42674.57325426706, 43911.56088439075, 44376.77811610921, 45522.54477455316, 46057.0, 46575.53424465712, 46719.532804671515, 46975.0, 38266.0, 38817.417738734504, 39475.40786888142, 39589.0, 39816.0, 29892.028027827993, 30234.24414389658, 30494.237644059074, 30539.54190687097, 30786.230344240677], "value": 19914.52019813795, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.7971014492752, 414.264680192658, 417.78424783878916, 421.7048066353551, 426.30725071758394, 13688.247146406944, 18819.153138108784, 19342.419727408163, 19381.612367752638, 21078.262260820873, 16299.59251018725, 16541.421050263245, 17485.91257043713, 20744.792552074476, 21035.57928841422, 5561.4716601922755, 5753.280839895013, 5755.568332375067, 5788.594798364095, 5792.073268277077, 5691.117876729114, 5772.682502462363, 5773.884522309551, 5777.1623114648355, 5778.4799368056865, 5667.631603945751, 5683.005474042046, 5746.540276777869, 5746.597738158331, 5749.626274292178, 5753.194552762617, 5756.683382413966, 5796.5362770978645, 5798.304203495586, 5806.825795226129, 5164.2770012198325, 5199.272101905736, 5201.219817027439, 5202.505761952609, 5259.15853463444, 13080.41138148776, 13258.535951241756, 13276.402561884739, 13308.800367994489, 13483.797743033861, 2424.4302588891596, 2437.4150203951103, 2534.5564526208022, 2787.888484460619, 2952.808067475607, 9827.0, 9919.3056486046, 10194.9490252549, 13220.942324614009, 13411.865881341257, 1944.7277381166648, 2093.413844123648, 2116.777738337468, 2194.1552502286577, 2201.1745595401726, 75105.24894751036, 76986.6150669243, 78847.21152788513, 79892.60053699695, 82364.0, 10989.175811814146, 11248.94375528117, 11377.943110284561, 11384.146189035855, 11397.487113079931, 15961.0, 16260.59348516273, 16333.673326533402, 16353.591160221089, 16366.099864507329, 8282.751517454486, 8488.320934325238, 8660.39377243587, 8723.214910658071, 8985.955070224607, 8718.694845680371, 8757.781055473664, 8803.779905502413, 8851.513166775889, 8950.955245223862, 4973.925391119136, 5018.397792264952, 5056.82301119459, 5076.7207803571155, 5083.5678967288195, 1810.751397898109, 1896.513922385445, 1930.7103934409952, 1991.75103112111, 2031.837453003756, 736.8157960509873, 1032.509557959963, 1057.1701214546545, 1117.608836907076, 1187.1155988788407, 18931.33740319082, 19392.4182274532, 20050.298239561544, 20115.094820733102, 20396.184152633727, 1055.6041484443333, 1078.805814953316, 1089.6349722842779, 1104.8563686720674, 1142.1833389126648, 10726.410047447465, 11092.667219983414, 11093.55625774976, 11137.498812553455, 11235.932586404278, 498247.0, 501088.0, 521813.0, 522918.0, 524111.0, 94775.0, 97068.0, 97097.0, 98659.99999999856, 99100.50449747706, 1078.5470102556944, 1117.8490903727893, 1118.8713297970837, 1119.3899324867966, 1142.3031950510183, 17755.0, 18011.639767204844, 18044.0, 18056.638867222846, 18322.809017413827, 26609.46781064329, 26617.86691066532, 37697.81151094227, 39077.80461097676, 41232.38151427791, 58965.70517147387, 60132.0, 60285.0, 60638.39361606327, 60730.39269607247, 8692.695755648521, 8860.822783544421, 9009.729708108767, 9034.232090272137, 12643.241405515699, 13884.305784710523, 14066.367013484212, 14328.49850255236, 14616.41534338657, 14625.414983400546, 12377.752444950871, 13035.739285214433, 13348.599542013755, 13671.453141874612, 13716.794248086087, 12922.676933076747, 13285.402156903156, 13334.266615335862, 13412.597622071353, 13714.0, 13042.673933151747, 13440.260785656492, 13723.862761372258, 13816.378262977989, 13859.445622175, 13481.797773033608, 13557.457701691821, 13589.592312230649, 13632.727345453233, 13646.52237171734, 13382.397792099573, 13530.93234533821, 13538.526151584647, 13680.384382702603, 13706.246156461091], "value": 9592.37943818828, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1382.0532934939574, 1488.642725745821, 1554.4481708993308, 1744.1540852686444, 1869.6634605770964, 7946.5232086074875, 8249.340052795777, 8251.46365486243, 8253.587320633975, 8257.050439199495, 8119.878201826978, 8283.875741863862, 8298.668053277877, 8318.66725330986, 8326.708565200219, 2302.9539409211807, 2318.1422873536817, 2329.0916542548334, 2330.0446816805115, 2332.3352844439337, 2367.3726462487452, 2391.988040059797, 2409.421738782697, 2417.8791060446997, 2421.055788242587, 2320.489492311697, 2358.1628521874745, 2370.893309801063, 2408.7952524035477, 2410.8915098820594, 2334.369720175543, 2369.644553316999, 2381.1189859752003, 2386.5823480890863, 2387.057112440576, 2336.5910965580956, 2388.4148383645943, 2400.7719266669637, 2401.231605886117, 2409.8433601815827, 7227.819304517377, 8124.309433698143, 8508.872366914502, 8567.357448191411, 8571.742847714579, 5160.92258616121, 5327.440618735016, 5329.467053294639, 5330.093884039723, 5331.093714068617, 3330.350581636582, 3354.278830051551, 3404.131946353664, 3445.793252404864, 3448.137965508623, 1083.5828206140613, 1096.2162054131254, 1100.4827730966433, 1101.2236373356764, 1101.4162493878257, 11769.117316201233, 11860.406979650983, 11870.287782733063, 11874.940625296904, 11886.821697674543, 4645.558671926162, 4679.227927391942, 4703.318018887242, 4713.835015774432, 4751.904961900743, 10100.444475553768, 10115.291929564859, 10117.645882394081, 10131.138853197413, 10143.036411540892, 5357.43746906577, 5413.729313534308, 5414.729263536808, 5415.566754659618, 5420.13277875538, 4887.584555312767, 4913.754312284372, 4914.508549145128, 4920.606351491872, 4921.261810728348, 3794.2790869734663, 3897.707671924617, 3927.7446965947124, 3929.155231625214, 3930.21395720857, 1195.2051885496048, 1201.591458903977, 1204.7710934922336, 1209.328822503519, 1216.683662247822, 1020.285799940045, 1044.8641676581997, 1048.4705223862054, 1052.5105825791045, 1069.4438891776233, 5772.191893134965, 5776.797812076641, 5798.971005144947, 5977.282726072899, 6053.092036194518, 966.8936416994125, 1021.8671572695501, 1089.7057794395469, 1109.5062697099713, 1144.822552504363, 3842.538895332578, 3870.8645197417954, 3873.593272706325, 3876.302265592164, 3931.8230679619487, 9864.95067524672, 10048.447335396624, 10074.042965918226, 10285.382877027403, 10288.022637849543, 10140.949295253477, 10154.695359139238, 10198.235132365102, 10223.0, 10248.026437488426, 7918.297566023332, 7976.162502937224, 8212.46618969765, 8232.259096681328, 8238.711645092273, 5492.340919089735, 5551.056320425457, 5551.195076713773, 5616.466435688603, 5706.372299047019, 9239.168474837297, 9329.11373419524, 9356.251499879858, 9538.189253913495, 9567.138957493858, 9647.083527064917, 9686.70939871805, 9689.563969621244, 9774.02259774017, 9791.265655075755, 7161.785146445615, 7279.308465695751, 7300.087489063867, 7396.889046664197, 7412.8146796328365, 8218.794530136794, 8230.83538329242, 8259.050209226016, 8299.128591497805, 8317.459365141247, 7282.453815963718, 7509.699612015458, 7524.849503010018, 7538.434617403607, 7751.844963100593, 7106.964465177641, 7202.747903823341, 7209.71161153548, 7247.891281630885, 7278.745243916437, 6813.591184528944, 6901.068355772108, 6902.620355880274, 6907.723691052302, 6908.3091690830515, 3989.182217645318, 4835.178019736582, 4915.680480768739, 4974.676646017997, 4997.025580011863, 4836.564709176191, 4838.056578967067, 4915.778789954389, 4923.926141107958, 4925.9014819704125], "value": 4560.159680033341, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.20086000907895, 350.4795378862388, 353.1136846515246, 353.7382337070568, 355.09096712416215, 6127.142200091991, 6262.686865656722, 6280.057991301307, 6412.390822871826, 6425.48596112311, 6285.308616052231, 6319.273283572391, 6347.0, 6361.809145725624, 6441.774537891175, 4764.499727528611, 4785.1147537705465, 4786.138495070886, 4789.521047895218, 4812.783424745878, 4546.818127274905, 4681.9063618727605, 4800.351952486422, 4807.278908163786, 4819.277108433728, 4741.502142275063, 4756.809727610908, 4760.214564596838, 4769.117713223065, 4770.2844573314105, 4733.952660473386, 4738.5261473852515, 4742.928856067162, 4746.169420351424, 4761.095391875532, 4175.144095460423, 4176.415301857743, 4181.205570941512, 4181.832726690929, 4194.622483976456, 6042.093685947143, 6045.939540604581, 6053.243344581927, 6057.4245446682935, 6063.151158837766, 5420.430854760272, 5589.580781441408, 5589.832305030855, 5594.440555944414, 5612.803551875665, 6558.213014438298, 6572.901406478906, 6686.398224159849, 6702.36327548882, 6723.991401289797, 4678.345031695565, 4683.648726345537, 4687.718736875764, 4688.038952014829, 4693.319468677023, 6915.204751453597, 7005.684744186524, 7006.054182685323, 7008.929910700878, 7011.789646310619, 4820.831270905502, 4827.517248275144, 4827.782749776268, 4833.734144622009, 4834.516548345207, 4731.976340118277, 4862.3922009748785, 4887.144749668793, 4889.828855990023, 4909.288153217834, 5556.527695145877, 5610.298712660918, 5628.71856407178, 5630.943690563042, 5636.577256705764, 6723.7646682365885, 6761.628110453874, 6802.455803535705, 6825.590464572141, 6831.04365388846, 1637.410532208404, 1674.698554260245, 1676.421634536083, 1679.7732306138514, 1685.88198826081, 1113.5378817790688, 1113.7271368514766, 1128.2384390536356, 1130.1580322659672, 1133.3200079951962, 6422.197225346832, 6485.837854053592, 6486.740530378826, 6494.253160886558, 6495.382938620824, 6902.275261097512, 6906.447484201251, 6922.896156557656, 6927.3419025192525, 6928.861422771516, 6491.058796474485, 6491.7078731457195, 6495.058216558573, 6496.0, 6497.765424569317, 3709.2581483703366, 3737.794421306854, 3743.288775132716, 3743.6069212732814, 3744.3073031488716, 6936.653167341714, 7089.22018577956, 7111.0, 7114.92885071153, 7139.643017849086, 7011.158660960616, 7013.824654383681, 7026.402755765715, 7036.6129862857015, 7048.859022819515, 7079.646017699094, 7080.238706904312, 7082.858342833115, 7092.645367731593, 7111.715531378791, 6841.315868413119, 6873.725050997904, 6891.276415976451, 6900.171979362509, 6905.551139175937, 6656.134702488645, 6698.799036028927, 6806.55757375769, 6808.7276508939085, 6809.285025072494, 6944.131983502062, 6946.861062778615, 6950.8609827804175, 6957.478189135936, 6998.545094568837, 4828.589569886599, 4846.273059041031, 4846.80612775485, 4858.392700912386, 4859.514048595112, 6677.833054173684, 6855.0, 6865.965670171618, 6873.621950792754, 6881.724731010904, 6767.424768894699, 6786.93213067863, 6796.864062718817, 6801.9319806801295, 6807.829804254933, 6775.051492790914, 6779.220389655253, 6787.0, 6789.796306110825, 6795.932040679728, 6652.301508341748, 6661.200655921127, 6667.733290668319, 6671.0, 6678.298778628174, 6633.369829866155, 6813.625250611066, 6826.761063362758, 6866.519343646097, 6867.381935625817, 6680.331966803281, 6680.331966803476, 6701.8324541884895, 6707.027481015128, 6714.764983225564], "value": 5053.4198288978705, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [824.2787560884226, 832.3881946769125, 875.8292133034057, 923.593618807725, 935.854942483915, 15569.143697096655, 15707.214639268019, 15789.210539473024, 15797.131157786318, 15806.683866322666, 13478.865211347884, 13539.661508462294, 13584.320783960811, 13641.249731264776, 13646.317684115804, 724.9782506524813, 858.6737039924835, 868.9913100868973, 870.3081050564784, 870.4777133719749, 739.5008369350694, 747.2900744292916, 751.1249394455452, 857.6397912876575, 875.295387213293, 893.8480458322101, 904.5477261369315, 906.1074841281365, 907.1110311894347, 907.1699395053519, 888.3958907942597, 898.7618281155499, 899.1323372945074, 899.6761165980209, 900.3022657440471, 566.2949627713482, 578.9102689083198, 663.618419408837, 667.2993356975205, 673.3132205150768, 14818.0, 14820.258987050713, 14834.0, 14835.703285934329, 14900.925495372454, 13544.796828047587, 14210.14739115646, 14243.501477448239, 14263.78604320936, 14296.857031429656, 11659.358735269558, 11727.941360293144, 11776.293422394598, 11888.940555297255, 11968.222065565797, 4050.7164498485117, 4052.2098190852794, 4108.794560271975, 4145.502539695226, 4164.1879833432495, 51288.0, 51363.743181284226, 51432.0, 56141.99999999959, 56374.0, 11642.825357619642, 11875.40622968848, 11879.287242765462, 12040.578579749841, 12051.879481205251, 19963.60072798536, 20042.0, 20047.699284510745, 20054.89972550128, 20097.799022009884, 8867.822643547093, 8869.113088691209, 8941.955290223637, 9240.8613870792, 9296.72109836706, 4919.1883339248625, 5032.496750325012, 5042.319286896295, 5390.0, 5393.164059570773, 3573.3032058748813, 3595.802230877327, 3619.8552057917914, 3623.5289412376223, 3629.201575653352, 653.0758976048837, 657.2179106862914, 662.602438536874, 672.9495287853431, 682.2393031769541, 708.3943228539628, 781.5232708048086, 796.6932730898529, 808.3452403553141, 812.3095368936357, 9589.472579008081, 10129.797404051878, 10171.186305095574, 10220.131288840532, 10228.846567301496, 682.9146356705412, 764.2930289482207, 771.7453240430644, 784.4312873166908, 788.1173086143556, 4380.6057454829215, 4427.247367947457, 4432.645388368923, 4460.487043989982, 4522.118186953512, 59364.70317648384, 59948.100778488355, 60089.69955150284, 65002.67498662476, 65928.34071659319, 54632.72683636636, 54816.72591637016, 54959.0, 55609.1658625121, 56206.0, 49612.50387496151, 50798.746006269736, 50814.237786432495, 52047.21929171065, 52494.737526312885, 9703.854442183514, 9966.60133594677, 10025.50549291491, 10042.246831487522, 10144.28989970695, 38208.42687359636, 38380.80809595934, 39271.0, 39388.0, 40022.1995560093, 45650.54349456463, 51345.0, 51869.22196167136, 51870.22194667158, 52018.21972670488, 11840.82238766402, 12048.096392770402, 12054.397280136134, 12083.033357331216, 12230.571929982409, 13609.183448993092, 13816.861831381557, 14067.366968486647, 14262.358193881508, 14289.857101429268, 11757.000654944084, 11912.523499060288, 13075.0, 13336.799948000593, 13523.72952540924, 11516.942415288206, 12864.485420583072, 12891.0, 13197.670058248621, 14505.782413263598, 13764.17414955106, 13885.722285554435, 13972.091814032055, 14089.577312680636, 14347.067440616327, 10635.94682026585, 10841.837372439262, 10847.728806779895, 10933.180011499011, 11038.17213708991, 10746.570137194425, 10881.727956801144, 10912.181586381212, 11155.0, 11397.145214108808], "value": 7079.8094071531905, "unit": "score"}], "name": "dromaeo_css", "value": 7434.343935430991, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 111.0, 110.0, 109.0, 111.0, 111.0, 105.0, 109.0, 109.0, 110.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 136.0, 163.0, 189.0, 128.0, 151.0, 154.0, 128.0, 183.0, 128.0], "value": 151.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 163.0, 161.0, 162.0, 161.0, 160.0, 161.0, 161.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 77.0, 177.0, 73.0, 75.0, 76.0, 75.0, 165.0, 76.0], "value": 94.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 158.0, 93.0, 91.0, 92.0, 152.0, 91.0, 93.0, 155.0], "value": 111.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 52.0, 53.0, 54.0, 53.0, 54.0, 67.0, 51.0, 52.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 58.0, 59.0, 58.0, 58.0, 59.0, 58.0, 59.0], "value": 58.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 74.0, 72.0, 73.0, 74.0, 74.0, 74.0, 80.0, 73.0, 75.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 119.0, 113.0, 120.0, 112.0, 109.0, 116.0, 112.0, 128.0, 130.0], "value": 117.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 157.0, 165.0, 161.0, 159.0, 159.0, 154.0, 156.0, 160.0, 156.0], "value": 158.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 64.0, 66.0, 65.0, 65.0, 67.0, 65.0, 64.0, 67.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1514.4, "alertThreshold": 2.0}]} 01:58:57 INFO - Return code: 0 01:58:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 01:58:57 ERROR - # TBPL SUCCESS # 01:58:57 INFO - Running post-action listener: _resource_record_post_action 01:58:57 INFO - Running post-run listener: _resource_record_post_run 01:58:58 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 461111296; Read time: 216; Write time: 544704 01:58:58 INFO - install - Wall time: 14s; CPU: 15.0%; Read bytes: 0; Write bytes: 242405376; Read time: 0; Write time: 401672 01:58:58 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8192; Write bytes: 218419200; Read time: 216; Write time: 143028 01:58:58 INFO - Running post-run listener: _upload_blobber_files 01:58:58 INFO - Blob upload gear active. 01:58:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:58:58 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 01:58:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:58:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:58:59 INFO - (blobuploader) - INFO - Open directory for files ... 01:58:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:58:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:58:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:58:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:58:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:58:59 INFO - (blobuploader) - INFO - Done attempting. 01:58:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:58:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:58:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:59:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:59:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:59:00 INFO - (blobuploader) - INFO - Done attempting. 01:59:00 INFO - (blobuploader) - INFO - Iteration through files over. 01:59:00 INFO - Return code: 0 01:59:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:59:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:59:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6aa24b7f944c843120cd7a83ee22fa01af05980c603ec20f9aa2aec78de8f8470cc0d2dc8ab7c3befae1f8e0f6810df06f14a1528a5f40de8553ffb0b7109e26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e66ddcf876b9491414b6b780e9d696d0a87245cbba5295f13c2b2a0b979916178514a4aaec45d2d770f780668cf680161920477f611cfb242937d2f855c67acd"} 01:59:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:59:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:59:00 INFO - Contents: 01:59:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6aa24b7f944c843120cd7a83ee22fa01af05980c603ec20f9aa2aec78de8f8470cc0d2dc8ab7c3befae1f8e0f6810df06f14a1528a5f40de8553ffb0b7109e26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e66ddcf876b9491414b6b780e9d696d0a87245cbba5295f13c2b2a0b979916178514a4aaec45d2d770f780668cf680161920477f611cfb242937d2f855c67acd"} 01:59:00 INFO - Running post-run listener: copy_logs_to_upload_dir 01:59:00 INFO - Copying logs to upload dir... 01:59:00 INFO - mkdir: /builds/slave/test/build/upload/logs 01:59:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1226.308427 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 26 secs) (at 2016-06-07 01:59:00.527278) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 01:59:00.530930) ========= 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=c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6aa24b7f944c843120cd7a83ee22fa01af05980c603ec20f9aa2aec78de8f8470cc0d2dc8ab7c3befae1f8e0f6810df06f14a1528a5f40de8553ffb0b7109e26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e66ddcf876b9491414b6b780e9d696d0a87245cbba5295f13c2b2a0b979916178514a4aaec45d2d770f780668cf680161920477f611cfb242937d2f855c67acd"} build_url:https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011389 build_url: 'https://queue.taskcluster.net/v1/task/DBC1y0KmQwaKqH_GooMS2g/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6aa24b7f944c843120cd7a83ee22fa01af05980c603ec20f9aa2aec78de8f8470cc0d2dc8ab7c3befae1f8e0f6810df06f14a1528a5f40de8553ffb0b7109e26", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e66ddcf876b9491414b6b780e9d696d0a87245cbba5295f13c2b2a0b979916178514a4aaec45d2d770f780668cf680161920477f611cfb242937d2f855c67acd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 01:59:00.557595) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:59:00.557980) ========= 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=c6927cf182745ad25b1d38b9000001fe-1465288712.141648-607105026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004767 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:59:00.641804) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 01:59:00.642196) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 01:59:00.642572) ========= ========= Total master_lag: 0.17 =========