builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-024 starttime: 1461140795.05 results: success (0) buildid: 20160420005033 builduid: 0561e628aab0406a8b2e605d200e020c revision: f71acdbd0d45ebdd0c69621dbf0f2f6ac74f9f43 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 01:26:35.052248) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 01:26:35.053406) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 01:26:35.054144) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 01:26:35.073687) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 01:26:35.074395) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-20 01:26:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.9M=0s 2016-04-20 01:26:35 (46.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092957 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 01:26:35.186186) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 01:26:35.186930) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013900 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 01:26:35.240663) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 01:26:35.241498) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f71acdbd0d45ebdd0c69621dbf0f2f6ac74f9f43 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f71acdbd0d45ebdd0c69621dbf0f2f6ac74f9f43 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-20 01:26:35,639 truncating revision to first 12 chars 2016-04-20 01:26:35,639 Setting DEBUG logging. 2016-04-20 01:26:35,639 attempt 1/10 2016-04-20 01:26:35,639 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f71acdbd0d45?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-20 01:26:35,933 unpacking tar archive at: fx-team-f71acdbd0d45/testing/mozharness/ program finished with exit code 0 elapsedTime=0.572681 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 01:26:35.831208) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 01:26:35.831967) ========= script_repo_revision: f71acdbd0d45ebdd0c69621dbf0f2f6ac74f9f43 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 01:26:35.832426) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 01:26:35.832733) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 01:26:35.844034) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-04-20 01:26:35.844723) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:26:36 INFO - MultiFileLogger online at 20160420 01:26:36 in /builds/slave/test 01:26:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 01:26:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:26:36 INFO - {'append_to_log': False, 01:26:36 INFO - 'base_work_dir': '/builds/slave/test', 01:26:36 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 01:26:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:26:36 INFO - 'branch': 'Fx-Team-Non-PGO', 01:26:36 INFO - 'buildbot_json_path': 'buildprops.json', 01:26:36 INFO - 'config_files': ('talos/linux_config.py',), 01:26:36 INFO - 'default_actions': ('clobber', 01:26:36 INFO - 'read-buildbot-config', 01:26:36 INFO - 'download-and-extract', 01:26:36 INFO - 'populate-webroot', 01:26:36 INFO - 'create-virtualenv', 01:26:36 INFO - 'install', 01:26:36 INFO - 'run-tests'), 01:26:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:26:36 INFO - 'download_minidump_stackwalk': True, 01:26:36 INFO - 'download_symbols': 'ondemand', 01:26:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:26:36 INFO - 'tooltool.py': '/tools/tooltool.py', 01:26:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:26:36 INFO - '/tools/misc-python/virtualenv.py')}, 01:26:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:26:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:26:36 INFO - 'installer_path': 'installer.exe', 01:26:36 INFO - 'log_level': 'info', 01:26:36 INFO - 'log_name': 'talos', 01:26:36 INFO - 'log_to_console': True, 01:26:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:26:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:26:36 INFO - 'opt_config_files': (), 01:26:36 INFO - 'pip_index': False, 01:26:36 INFO - 'sps_profile': False, 01:26:36 INFO - 'sps_profile_interval': 0, 01:26:36 INFO - 'suite': 'dromaeojs', 01:26:36 INFO - 'system_bits': '32', 01:26:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:26:36 INFO - 'title': 'talos-linux64-ix-024', 01:26:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:26:36 INFO - 'use_talos_json': True, 01:26:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:26:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:26:36 INFO - 'work_dir': 'build'} 01:26:36 INFO - ##### 01:26:36 INFO - ##### Running clobber step. 01:26:36 INFO - ##### 01:26:36 INFO - Running pre-action listener: _resource_record_pre_action 01:26:36 INFO - Running main action method: clobber 01:26:36 INFO - rmtree: /builds/slave/test/build 01:26:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:26:37 INFO - Running post-action listener: _resource_record_post_action 01:26:37 INFO - ##### 01:26:37 INFO - ##### Running read-buildbot-config step. 01:26:37 INFO - ##### 01:26:37 INFO - Running pre-action listener: _resource_record_pre_action 01:26:37 INFO - Running main action method: read_buildbot_config 01:26:37 INFO - Using buildbot properties: 01:26:37 INFO - { 01:26:37 INFO - "project": "", 01:26:37 INFO - "product": "firefox", 01:26:37 INFO - "script_repo_revision": "production", 01:26:37 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 01:26:37 INFO - "repository": "", 01:26:37 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 01:26:37 INFO - "buildid": "20160420005033", 01:26:37 INFO - "pgo_build": "False", 01:26:37 INFO - "basedir": "/builds/slave/test", 01:26:37 INFO - "buildnumber": 1682, 01:26:37 INFO - "slavename": "talos-linux64-ix-024", 01:26:37 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 01:26:37 INFO - "platform": "ubuntu64_hw", 01:26:37 INFO - "scheduler": "tests-fx-team-linux64-talos", 01:26:37 INFO - "branch": "fx-team", 01:26:37 INFO - "repo_path": "integration/fx-team", 01:26:37 INFO - "revision": "f71acdbd0d45ebdd0c69621dbf0f2f6ac74f9f43", 01:26:37 INFO - "stage_platform": "linux64", 01:26:37 INFO - "builduid": "0561e628aab0406a8b2e605d200e020c", 01:26:37 INFO - "slavebuilddir": "test" 01:26:37 INFO - } 01:26:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 01:26:37 INFO - Running post-action listener: _resource_record_post_action 01:26:37 INFO - ##### 01:26:37 INFO - ##### Running download-and-extract step. 01:26:37 INFO - ##### 01:26:37 INFO - Running pre-action listener: _resource_record_pre_action 01:26:37 INFO - Running main action method: download_and_extract 01:26:37 INFO - mkdir: /builds/slave/test/build/tests 01:26:37 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:26:37 INFO - https://queue.taskcluster.net/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 01:26:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 01:26:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 01:26:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 01:26:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 01:26:38 INFO - Downloaded 1361 bytes. 01:26:38 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 01:26:38 INFO - Using the following test package requirements: 01:26:38 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 01:26:38 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:26:38 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 01:26:38 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'jsshell-linux-x86_64.zip'], 01:26:38 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:26:38 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 01:26:38 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:26:38 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:26:38 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:26:38 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 01:26:38 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:26:38 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:26: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:26:38 INFO - https://queue.taskcluster.net/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:26:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 01:26:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 01:26:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 01:26:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:26:39 INFO - Downloaded 22267191 bytes. 01:26:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:26:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 01:26:40 INFO - Return code: 0 01:26:40 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:40 INFO - https://queue.taskcluster.net/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:26:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 01:26:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 01:26:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 01:26:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:26:41 INFO - Downloaded 22267191 bytes. 01:26:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:26:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 01:26:42 INFO - Return code: 0 01:26: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:26:42 INFO - https://queue.taskcluster.net/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:26:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 01:26:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 01:26:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 01:26:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:26:43 INFO - Downloaded 10845952 bytes. 01:26:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:26:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 01:26:43 INFO - Return code: 0 01:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:43 INFO - https://queue.taskcluster.net/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:26:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 01:26:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 01:26:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 01:26:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:26:45 INFO - Downloaded 54587663 bytes. 01:26:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 01:26:45 INFO - mkdir: /builds/slave/test/properties 01:26:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:26:45 INFO - Writing to file /builds/slave/test/properties/build_url 01:26:45 INFO - Contents: 01:26:45 INFO - build_url:https://queue.taskcluster.net/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 01:26:46 INFO - Running post-action listener: _resource_record_post_action 01:26:46 INFO - Running post-action listener: set_extra_try_arguments 01:26:46 INFO - ##### 01:26:46 INFO - ##### Running populate-webroot step. 01:26:46 INFO - ##### 01:26:46 INFO - Running pre-action listener: _resource_record_pre_action 01:26:46 INFO - Running main action method: populate_webroot 01:26:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:26:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:26:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:26:46 INFO - '--tppagecycles', 01:26:46 INFO - '7'], 01:26:46 INFO - 'tests': ['tsvgm']}}, 01:26:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:26:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:26:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:26:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:26:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:26:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:26:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:46 INFO - 'talos_options': ['--disable-e10s'], 01:26:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:26:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:26:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:46 INFO - 'talos_options': ['--disable-e10s'], 01:26:46 INFO - 'tests': ['damp', 'tps']}, 01:26:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:46 INFO - 'tests': ['damp', 'tps']}, 01:26:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:26:46 INFO - 'tests': ['dromaeo_dom']}, 01:26:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:26:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:26:46 INFO - 'tests': ['a11yr', 01:26:46 INFO - 'ts_paint', 01:26:46 INFO - 'tpaint', 01:26:46 INFO - 'sessionrestore', 01:26:46 INFO - 'sessionrestore_no_auto_restore', 01:26:46 INFO - 'tabpaint']}, 01:26:46 INFO - 'other-e10s': {'tests': ['a11yr', 01:26:46 INFO - 'ts_paint', 01:26:46 INFO - 'tpaint', 01:26:46 INFO - 'sessionrestore', 01:26:46 INFO - 'sessionrestore_no_auto_restore', 01:26:46 INFO - 'tabpaint']}, 01:26:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:26:46 INFO - 'ts_paint', 01:26:46 INFO - 'tpaint', 01:26:46 INFO - 'sessionrestore', 01:26:46 INFO - 'sessionrestore_no_auto_restore', 01:26:46 INFO - 'tabpaint']}, 01:26:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:26:46 INFO - 'ts_paint', 01:26:46 INFO - 'tpaint', 01:26:46 INFO - 'sessionrestore', 01:26:46 INFO - 'sessionrestore_no_auto_restore', 01:26:46 INFO - 'tabpaint']}, 01:26:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:26:46 INFO - 'tests': ['a11yr', 01:26:46 INFO - 'ts_paint', 01:26:46 INFO - 'tpaint', 01:26:46 INFO - 'sessionrestore', 01:26:46 INFO - 'sessionrestore_no_auto_restore', 01:26:46 INFO - 'tabpaint']}, 01:26:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:26:46 INFO - 'tests': ['a11yr', 01:26:46 INFO - 'ts_paint', 01:26:46 INFO - 'tpaint', 01:26:46 INFO - 'sessionrestore', 01:26:46 INFO - 'sessionrestore_no_auto_restore', 01:26:46 INFO - 'tabpaint']}, 01:26:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:26:46 INFO - 'tests': ['tsvgx', 01:26:46 INFO - 'tsvgr_opacity', 01:26:46 INFO - 'tart', 01:26:46 INFO - 'tscrollx', 01:26:46 INFO - 'cart']}, 01:26:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:26:46 INFO - 'tsvgr_opacity', 01:26:46 INFO - 'tart', 01:26:46 INFO - 'tscrollx', 01:26:46 INFO - 'cart']}, 01:26:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:46 INFO - 'talos_options': ['--disable-e10s'], 01:26:46 INFO - 'tests': ['tp5o']}, 01:26:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:46 INFO - 'tests': ['tp5o']}, 01:26:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:46 INFO - 'talos_options': ['--disable-e10s', 01:26:46 INFO - '--xperf_path', 01:26:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:46 INFO - 'tests': ['tp5n']}, 01:26:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:46 INFO - 'talos_options': ['--xperf_path', 01:26:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:46 INFO - 'tests': ['tp5n']}}, 01:26:46 INFO - 'talos.zip': {'path': '', 01:26:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:26:46 INFO - Running post-action listener: _resource_record_post_action 01:26:46 INFO - ##### 01:26:46 INFO - ##### Running create-virtualenv step. 01:26:46 INFO - ##### 01:26:46 INFO - Running pre-action listener: _resource_record_pre_action 01:26:46 INFO - Running main action method: create_virtualenv 01:26:46 INFO - Creating virtualenv /builds/slave/test/build/venv 01:26: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:26:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:26:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:26:46 INFO - Using real prefix '/usr' 01:26:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:26:46 INFO - Installing distribute.............................................................................................................................................................................................done. 01:26:48 INFO - Installing pip.................done. 01:26:48 INFO - Return code: 0 01:26:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:26: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:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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 0x7f22d4c03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b807d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0f640>, '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 0x1c10540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c109d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', '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:26: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:26: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:26:48 INFO - Using env: {'DISPLAY': ':0', 01:26:48 INFO - 'HOME': '/home/cltbld', 01:26:48 INFO - 'LANG': 'en_US.UTF-8', 01:26:48 INFO - 'LANGUAGE': 'en_US:en', 01:26:48 INFO - 'LOGNAME': 'cltbld', 01:26:48 INFO - 'MAIL': '/var/mail/cltbld', 01:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:48 INFO - 'MOZ_NO_REMOTE': '1', 01:26:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:26:48 INFO - 'NO_EM_RESTART': '1', 01:26:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:48 INFO - 'PWD': '/builds/slave/test', 01:26:48 INFO - 'SHELL': '/bin/bash', 01:26:48 INFO - 'SHLVL': '1', 01:26:48 INFO - 'TERM': 'linux', 01:26:48 INFO - 'TMOUT': '86400', 01:26:48 INFO - 'USER': 'cltbld', 01:26:48 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', 01:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:48 INFO - '_': '/tools/buildbot/bin/python'} 01:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:48 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:26:48 INFO - Cleaning up... 01:26:48 INFO - Return code: 0 01:26:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:26: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:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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', '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 0x7f22d4c03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b807d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0f640>, '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 0x1c10540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c109d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', '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:26: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', 'psutil>=0.7.1'] in /builds/slave/test/build 01:26: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 psutil>=0.7.1 01:26:48 INFO - Using env: {'DISPLAY': ':0', 01:26:48 INFO - 'HOME': '/home/cltbld', 01:26:48 INFO - 'LANG': 'en_US.UTF-8', 01:26:48 INFO - 'LANGUAGE': 'en_US:en', 01:26:48 INFO - 'LOGNAME': 'cltbld', 01:26:48 INFO - 'MAIL': '/var/mail/cltbld', 01:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:48 INFO - 'MOZ_NO_REMOTE': '1', 01:26:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:26:48 INFO - 'NO_EM_RESTART': '1', 01:26:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:48 INFO - 'PWD': '/builds/slave/test', 01:26:48 INFO - 'SHELL': '/bin/bash', 01:26:48 INFO - 'SHLVL': '1', 01:26:48 INFO - 'TERM': 'linux', 01:26:48 INFO - 'TMOUT': '86400', 01:26:48 INFO - 'USER': 'cltbld', 01:26:48 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', 01:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:48 INFO - '_': '/tools/buildbot/bin/python'} 01:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:49 INFO - Downloading/unpacking psutil>=0.7.1 01:26: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:26: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:26: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:26: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:26: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:26: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:26:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:26:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:26:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:26:51 INFO - Installing collected packages: psutil 01:26:51 INFO - Running setup.py install for psutil 01:26:51 INFO - building 'psutil._psutil_linux' extension 01:26: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:26: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:26:51 INFO - building 'psutil._psutil_posix' extension 01:26: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:26: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:26:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:26:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:26:51 INFO - Successfully installed psutil 01:26:51 INFO - Cleaning up... 01:26:51 INFO - Return code: 0 01:26:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:26: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:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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 0x7f22d4c03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b807d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0f640>, '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 0x1c10540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c109d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', '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:26: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:26: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:26:51 INFO - Using env: {'DISPLAY': ':0', 01:26:51 INFO - 'HOME': '/home/cltbld', 01:26:51 INFO - 'LANG': 'en_US.UTF-8', 01:26:51 INFO - 'LANGUAGE': 'en_US:en', 01:26:51 INFO - 'LOGNAME': 'cltbld', 01:26:51 INFO - 'MAIL': '/var/mail/cltbld', 01:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:51 INFO - 'MOZ_NO_REMOTE': '1', 01:26:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:26:51 INFO - 'NO_EM_RESTART': '1', 01:26:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:26:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:51 INFO - 'PWD': '/builds/slave/test', 01:26:51 INFO - 'SHELL': '/bin/bash', 01:26:51 INFO - 'SHLVL': '1', 01:26:51 INFO - 'TERM': 'linux', 01:26:51 INFO - 'TMOUT': '86400', 01:26:51 INFO - 'USER': 'cltbld', 01:26:51 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', 01:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:51 INFO - '_': '/tools/buildbot/bin/python'} 01:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:26:51 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:26:51 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:26:51 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:26:51 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:26:51 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:26:51 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:26:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:26: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:26:53 INFO - Installing collected packages: mozsystemmonitor 01:26:53 INFO - Running setup.py install for mozsystemmonitor 01:26:53 INFO - Successfully installed mozsystemmonitor 01:26:53 INFO - Cleaning up... 01:26:53 INFO - Return code: 0 01:26:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f22d4c03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b807d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0f640>, '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 0x1c10540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c109d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', '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:26:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:26:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:26:53 INFO - Using env: {'DISPLAY': ':0', 01:26:53 INFO - 'HOME': '/home/cltbld', 01:26:53 INFO - 'LANG': 'en_US.UTF-8', 01:26:53 INFO - 'LANGUAGE': 'en_US:en', 01:26:53 INFO - 'LOGNAME': 'cltbld', 01:26:53 INFO - 'MAIL': '/var/mail/cltbld', 01:26:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:53 INFO - 'MOZ_NO_REMOTE': '1', 01:26:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:26:53 INFO - 'NO_EM_RESTART': '1', 01:26:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:26:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:53 INFO - 'PWD': '/builds/slave/test', 01:26:53 INFO - 'SHELL': '/bin/bash', 01:26:53 INFO - 'SHLVL': '1', 01:26:53 INFO - 'TERM': 'linux', 01:26:53 INFO - 'TMOUT': '86400', 01:26:53 INFO - 'USER': 'cltbld', 01:26:53 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', 01:26:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:53 INFO - '_': '/tools/buildbot/bin/python'} 01:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:54 INFO - Downloading/unpacking blobuploader==1.2.4 01:26: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:26: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:26: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:26: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:26: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:26: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:26:55 INFO - Downloading blobuploader-1.2.4.tar.gz 01:26:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:26:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:26:55 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:26:55 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:26:55 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:26:55 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:26:55 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:26:55 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:26:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:26:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:26: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:26: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:26: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:26: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:26: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:26: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:26:56 INFO - Downloading docopt-0.6.1.tar.gz 01:26:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:26:56 INFO - Installing collected packages: blobuploader, requests, docopt 01:26:56 INFO - Running setup.py install for blobuploader 01:26:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:26:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:26:56 INFO - Running setup.py install for requests 01:26:57 INFO - Running setup.py install for docopt 01:26:57 INFO - Successfully installed blobuploader requests docopt 01:26:57 INFO - Cleaning up... 01:26:57 INFO - Return code: 0 01:26:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:26: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:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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 0x7f22d4c03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b807d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0f640>, '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 0x1c10540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c109d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', '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:26: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:26: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:26:57 INFO - Using env: {'DISPLAY': ':0', 01:26:57 INFO - 'HOME': '/home/cltbld', 01:26:57 INFO - 'LANG': 'en_US.UTF-8', 01:26:57 INFO - 'LANGUAGE': 'en_US:en', 01:26:57 INFO - 'LOGNAME': 'cltbld', 01:26:57 INFO - 'MAIL': '/var/mail/cltbld', 01:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:57 INFO - 'MOZ_NO_REMOTE': '1', 01:26:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:26:57 INFO - 'NO_EM_RESTART': '1', 01:26:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:57 INFO - 'PWD': '/builds/slave/test', 01:26:57 INFO - 'SHELL': '/bin/bash', 01:26:57 INFO - 'SHLVL': '1', 01:26:57 INFO - 'TERM': 'linux', 01:26:57 INFO - 'TMOUT': '86400', 01:26:57 INFO - 'USER': 'cltbld', 01:26:57 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', 01:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:57 INFO - '_': '/tools/buildbot/bin/python'} 01:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:26:57 INFO - Running setup.py (path:/tmp/pip-JbaUhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:26:57 INFO - Running setup.py (path:/tmp/pip-BV6vr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:26:57 INFO - Running setup.py (path:/tmp/pip-fqyBx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:26:57 INFO - Running setup.py (path:/tmp/pip-ByHEVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:26:57 INFO - Running setup.py (path:/tmp/pip-WkCVSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:26:57 INFO - Running setup.py (path:/tmp/pip-9drJbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:26:57 INFO - Running setup.py (path:/tmp/pip-M_B9zK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:26:57 INFO - Running setup.py (path:/tmp/pip-GU9e8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:26:58 INFO - Running setup.py (path:/tmp/pip-YOkBSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:26:58 INFO - Running setup.py (path:/tmp/pip-HLjwdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:26:58 INFO - Running setup.py (path:/tmp/pip-5jZbWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:26:58 INFO - Running setup.py (path:/tmp/pip-O_NdCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:26:58 INFO - Running setup.py (path:/tmp/pip-dWKBQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:26:58 INFO - Running setup.py (path:/tmp/pip-XaGaAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:26:58 INFO - Running setup.py (path:/tmp/pip-6vJeLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:26:58 INFO - Running setup.py (path:/tmp/pip-VLUn12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:26:58 INFO - Running setup.py (path:/tmp/pip-OqHfaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:26:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:26:58 INFO - Running setup.py install for manifestparser 01:26:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:26:58 INFO - Running setup.py install for mozcrash 01:26:58 INFO - Running setup.py install for mozdebug 01:26:58 INFO - Running setup.py install for mozdevice 01:26:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Running setup.py install for mozfile 01:26:59 INFO - Running setup.py install for mozhttpd 01:26:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Running setup.py install for mozinfo 01:26:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Running setup.py install for mozInstall 01:26:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Running setup.py install for mozleak 01:26:59 INFO - Running setup.py install for mozlog 01:26:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Running setup.py install for moznetwork 01:26:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Running setup.py install for mozprocess 01:26:59 INFO - Running setup.py install for mozprofile 01:27:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:27:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:27:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:27:00 INFO - Running setup.py install for mozrunner 01:27:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:27:00 INFO - Running setup.py install for mozscreenshot 01:27:00 INFO - Running setup.py install for moztest 01:27:00 INFO - Running setup.py install for mozversion 01:27:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:27:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:27:00 INFO - Cleaning up... 01:27:00 INFO - Return code: 0 01:27:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27: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:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27: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:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27: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 0x7f22d4c03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b807d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0f640>, '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 0x1c10540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c109d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', '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:27: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:27: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:27:00 INFO - Using env: {'DISPLAY': ':0', 01:27:00 INFO - 'HOME': '/home/cltbld', 01:27:00 INFO - 'LANG': 'en_US.UTF-8', 01:27:00 INFO - 'LANGUAGE': 'en_US:en', 01:27:00 INFO - 'LOGNAME': 'cltbld', 01:27:00 INFO - 'MAIL': '/var/mail/cltbld', 01:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:00 INFO - 'MOZ_NO_REMOTE': '1', 01:27:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:00 INFO - 'NO_EM_RESTART': '1', 01:27:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:00 INFO - 'PWD': '/builds/slave/test', 01:27:00 INFO - 'SHELL': '/bin/bash', 01:27:00 INFO - 'SHLVL': '1', 01:27:00 INFO - 'TERM': 'linux', 01:27:00 INFO - 'TMOUT': '86400', 01:27:00 INFO - 'USER': 'cltbld', 01:27:00 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', 01:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:00 INFO - '_': '/tools/buildbot/bin/python'} 01:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:27:00 INFO - Running setup.py (path:/tmp/pip-TaGGHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:27:00 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:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:27:00 INFO - Running setup.py (path:/tmp/pip-DI9BzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:27:00 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:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:27:00 INFO - Running setup.py (path:/tmp/pip-xp3m3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:27:00 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:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:27:00 INFO - Running setup.py (path:/tmp/pip-bur5RE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:27:00 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:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:27:00 INFO - Running setup.py (path:/tmp/pip-1hmGU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:27: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:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:27:01 INFO - Running setup.py (path:/tmp/pip-QwP85a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:27: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:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:27:01 INFO - Running setup.py (path:/tmp/pip-TFvYct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:27: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:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:27:01 INFO - Running setup.py (path:/tmp/pip-erHrVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:27: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:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:27:01 INFO - Running setup.py (path:/tmp/pip-SYY5g4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:27: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:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:27:01 INFO - Running setup.py (path:/tmp/pip-d2jRth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:27: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:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:27:01 INFO - Running setup.py (path:/tmp/pip-GqbX4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:27: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:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:27:01 INFO - Running setup.py (path:/tmp/pip-fMJ6Xh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:27: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:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:27:01 INFO - Running setup.py (path:/tmp/pip-P0TN55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:27: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:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:27:01 INFO - Running setup.py (path:/tmp/pip-1d2FPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:27:01 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:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:01 INFO - Running setup.py (path:/tmp/pip-yM_9ho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:01 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:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:27:01 INFO - Running setup.py (path:/tmp/pip-tlnz2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:27:01 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:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:27:01 INFO - Running setup.py (path:/tmp/pip-T0TsP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:27:01 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:27:01 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:27:01 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:27:01 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:27:01 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:27:01 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:27:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:03 INFO - Downloading blessings-1.6.tar.gz 01:27:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:27:03 INFO - Installing collected packages: blessings 01:27:03 INFO - Running setup.py install for blessings 01:27:04 INFO - Successfully installed blessings 01:27:04 INFO - Cleaning up... 01:27:04 INFO - Return code: 0 01:27:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:27:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:04 INFO - Reading from file tmpfile_stdout 01:27:04 INFO - Current package versions: 01:27:04 INFO - argparse == 1.2.1 01:27:04 INFO - blessings == 1.6 01:27:04 INFO - blobuploader == 1.2.4 01:27:04 INFO - docopt == 0.6.1 01:27:04 INFO - manifestparser == 1.1 01:27:04 INFO - mozInstall == 1.12 01:27:04 INFO - mozcrash == 0.17 01:27:04 INFO - mozdebug == 0.1 01:27:04 INFO - mozdevice == 0.48 01:27:04 INFO - mozfile == 1.2 01:27:04 INFO - mozhttpd == 0.7 01:27:04 INFO - mozinfo == 0.9 01:27:04 INFO - mozleak == 0.1 01:27:04 INFO - mozlog == 3.1 01:27:04 INFO - moznetwork == 0.27 01:27:04 INFO - mozprocess == 0.22 01:27:04 INFO - mozprofile == 0.28 01:27:04 INFO - mozrunner == 6.11 01:27:04 INFO - mozscreenshot == 0.1 01:27:04 INFO - mozsystemmonitor == 0.0 01:27:04 INFO - moztest == 0.7 01:27:04 INFO - mozversion == 1.4 01:27:04 INFO - psutil == 3.1.1 01:27:04 INFO - requests == 1.2.3 01:27:04 INFO - wsgiref == 0.1.2 01:27:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27: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:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27: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:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27: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 0x7f22d4c03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b807d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0f640>, '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 0x1c10540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c109d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', '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:27: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:27: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:27:04 INFO - Using env: {'DISPLAY': ':0', 01:27:04 INFO - 'HOME': '/home/cltbld', 01:27:04 INFO - 'LANG': 'en_US.UTF-8', 01:27:04 INFO - 'LANGUAGE': 'en_US:en', 01:27:04 INFO - 'LOGNAME': 'cltbld', 01:27:04 INFO - 'MAIL': '/var/mail/cltbld', 01:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:04 INFO - 'MOZ_NO_REMOTE': '1', 01:27:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:04 INFO - 'NO_EM_RESTART': '1', 01:27:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:04 INFO - 'PWD': '/builds/slave/test', 01:27:04 INFO - 'SHELL': '/bin/bash', 01:27:04 INFO - 'SHLVL': '1', 01:27:04 INFO - 'TERM': 'linux', 01:27:04 INFO - 'TMOUT': '86400', 01:27:04 INFO - 'USER': 'cltbld', 01:27:04 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', 01:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:04 INFO - '_': '/tools/buildbot/bin/python'} 01:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27: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:27: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:27: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:27: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:27: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:27: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:27: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:27: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:27: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:27: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:27: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:27:04 INFO - Cleaning up... 01:27:04 INFO - Return code: 0 01:27:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:27: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:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27: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:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27: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 0x7f22d4c03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b807d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0f640>, '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 0x1c10540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c109d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', '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:27: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:27: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:27:04 INFO - Using env: {'DISPLAY': ':0', 01:27:04 INFO - 'HOME': '/home/cltbld', 01:27:04 INFO - 'LANG': 'en_US.UTF-8', 01:27:04 INFO - 'LANGUAGE': 'en_US:en', 01:27:04 INFO - 'LOGNAME': 'cltbld', 01:27:04 INFO - 'MAIL': '/var/mail/cltbld', 01:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:04 INFO - 'MOZ_NO_REMOTE': '1', 01:27:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:04 INFO - 'NO_EM_RESTART': '1', 01:27:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:04 INFO - 'PWD': '/builds/slave/test', 01:27:04 INFO - 'SHELL': '/bin/bash', 01:27:04 INFO - 'SHLVL': '1', 01:27:04 INFO - 'TERM': 'linux', 01:27:04 INFO - 'TMOUT': '86400', 01:27:04 INFO - 'USER': 'cltbld', 01:27:04 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', 01:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:04 INFO - '_': '/tools/buildbot/bin/python'} 01:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:04 INFO - Downloading/unpacking jsonschema 01:27:04 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:27:04 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:27:04 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:27:04 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:27:04 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:27:04 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:27:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:27:06 INFO - Downloading/unpacking functools32 (from jsonschema) 01:27: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:27: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:27: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:27: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:27: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:27: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:27:06 INFO - Downloading functools32-3.2.3-2.tar.gz 01:27:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:27:06 INFO - warning: no files found matching '*.txt' 01:27:06 INFO - no previously-included directories found matching 'build' 01:27:06 INFO - no previously-included directories found matching 'dist' 01:27:06 INFO - no previously-included directories found matching '.git*' 01:27:06 INFO - Installing collected packages: jsonschema, functools32 01:27:06 INFO - Running setup.py install for functools32 01:27:07 INFO - warning: no files found matching '*.txt' 01:27:07 INFO - no previously-included directories found matching 'build' 01:27:07 INFO - no previously-included directories found matching 'dist' 01:27:07 INFO - no previously-included directories found matching '.git*' 01:27:07 INFO - Successfully installed jsonschema functools32 01:27:07 INFO - Cleaning up... 01:27:07 INFO - Return code: 0 01:27:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:27: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:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27: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:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27: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 0x7f22d4c03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b807d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0f640>, '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 0x1c10540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c109d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', '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:27: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:27: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:27:07 INFO - Using env: {'DISPLAY': ':0', 01:27:07 INFO - 'HOME': '/home/cltbld', 01:27:07 INFO - 'LANG': 'en_US.UTF-8', 01:27:07 INFO - 'LANGUAGE': 'en_US:en', 01:27:07 INFO - 'LOGNAME': 'cltbld', 01:27:07 INFO - 'MAIL': '/var/mail/cltbld', 01:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:07 INFO - 'MOZ_NO_REMOTE': '1', 01:27:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:07 INFO - 'NO_EM_RESTART': '1', 01:27:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:07 INFO - 'PWD': '/builds/slave/test', 01:27:07 INFO - 'SHELL': '/bin/bash', 01:27:07 INFO - 'SHLVL': '1', 01:27:07 INFO - 'TERM': 'linux', 01:27:07 INFO - 'TMOUT': '86400', 01:27:07 INFO - 'USER': 'cltbld', 01:27:07 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', 01:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:07 INFO - '_': '/tools/buildbot/bin/python'} 01:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:07 INFO - Downloading/unpacking flake8 01:27: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:27: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:27: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:27: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:27: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:27: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:27:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:27:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:27: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:27: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:27: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:27: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:27: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:27: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:27:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:27:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:27: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:27: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:27: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:27: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:27: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:27: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:27:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:27: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:27: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:27: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:27: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:27: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:27: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:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:27:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:27:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:27:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:27:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:27:09 INFO - no previously-included directories found matching 'docs/_build' 01:27:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:27:10 INFO - Running setup.py install for pep8 01:27:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:27:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:27:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:27:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:27:10 INFO - no previously-included directories found matching 'docs/_build' 01:27:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:27:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:27:10 INFO - Cleaning up... 01:27:10 INFO - Return code: 0 01:27:10 INFO - Running post-action listener: _resource_record_post_action 01:27:10 INFO - Running post-action listener: _start_resource_monitoring 01:27:10 INFO - Starting resource monitoring. 01:27:10 INFO - ##### 01:27:10 INFO - ##### Running install step. 01:27:10 INFO - ##### 01:27:10 INFO - Running pre-action listener: _resource_record_pre_action 01:27:10 INFO - Running main action method: install 01:27:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:10 INFO - Reading from file tmpfile_stdout 01:27:10 INFO - Detecting whether we're running mozinstall >=1.0... 01:27:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:27:10 INFO - Reading from file tmpfile_stdout 01:27:10 INFO - Output received: 01:27:10 INFO - Usage: mozinstall [options] installer 01:27:10 INFO - Options: 01:27:10 INFO - -h, --help show this help message and exit 01:27:10 INFO - -d DEST, --destination=DEST 01:27:10 INFO - Directory to install application into. [default: 01:27:10 INFO - "/builds/slave/test"] 01:27:10 INFO - --app=APP Application being installed. [default: firefox] 01:27:10 INFO - mkdir: /builds/slave/test/build/application 01:27:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 01:27:26 INFO - Reading from file tmpfile_stdout 01:27:26 INFO - Output received: 01:27:26 INFO - /builds/slave/test/build/application/firefox/firefox 01:27:26 INFO - Running post-action listener: _resource_record_post_action 01:27:26 INFO - ##### 01:27:26 INFO - ##### Running run-tests step. 01:27:26 INFO - ##### 01:27:26 INFO - Running pre-action listener: _resource_record_pre_action 01:27:26 INFO - Running main action method: run_tests 01:27:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:27:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:27:26 INFO - Python 2.7.3 01:27:26 INFO - Return code: 0 01:27:26 INFO - grabbing minidump binary from tooltool 01:27:26 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:27:26 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 0x1c0f640>, '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 0x1c10540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c109d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:27:26 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:27:26 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:27:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:27:26 INFO - Return code: 0 01:27:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:27:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:27:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:27:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:27:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:27:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:27:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:27:27 INFO - Return code: 0 01:27:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:27:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:27:27 INFO - Using env: {'DISPLAY': ':0', 01:27:27 INFO - 'HOME': '/home/cltbld', 01:27:27 INFO - 'LANG': 'en_US.UTF-8', 01:27:27 INFO - 'LANGUAGE': 'en_US:en', 01:27:27 INFO - 'LOGNAME': 'cltbld', 01:27:27 INFO - 'MAIL': '/var/mail/cltbld', 01:27:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:27:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:27:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:27 INFO - 'MOZ_NO_REMOTE': '1', 01:27:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:27:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:27 INFO - 'NO_EM_RESTART': '1', 01:27:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:27 INFO - 'PWD': '/builds/slave/test', 01:27:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:27:27 INFO - 'SHELL': '/bin/bash', 01:27:27 INFO - 'SHLVL': '1', 01:27:27 INFO - 'TERM': 'linux', 01:27:27 INFO - 'TMOUT': '86400', 01:27:27 INFO - 'USER': 'cltbld', 01:27:27 INFO - 'XDG_SESSION_COOKIE': '7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736', 01:27:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:27 INFO - '_': '/tools/buildbot/bin/python'} 01:27:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:27:27 INFO - mozversion application_buildid: 20160420005033 01:27:27 INFO - mozversion application_changeset: f71acdbd0d45ebdd0c69621dbf0f2f6ac74f9f43 01:27:27 INFO - mozversion application_display_name: Nightly 01:27:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:27:27 INFO - mozversion application_name: Firefox 01:27:27 INFO - mozversion application_remotingname: firefox 01:27:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:27:27 INFO - mozversion application_vendor: Mozilla 01:27:27 INFO - mozversion application_version: 48.0a1 01:27:27 INFO - mozversion platform_buildid: 20160420005033 01:27:27 INFO - mozversion platform_changeset: f71acdbd0d45ebdd0c69621dbf0f2f6ac74f9f43 01:27:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:27:27 INFO - mozversion platform_version: 48.0a1 01:27:27 INFO - using testdate: 1461140847 01:27:27 INFO - actual date: 1461140847 01:27:27 INFO - starting webserver on 'localhost:37398' 01:27:27 INFO - SUITE-START | Running 2 tests 01:27:27 INFO - TEST-START | dromaeo_css 01:27:27 INFO - operating with platform_type : linux_ 01:27:27 INFO - Initialising browser for dromaeo_css test... 01:27:27 INFO - TEST-INFO | started process 5517 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVLfgsU/profile http://localhost:37398/getInfo.html) 01:27:31 INFO - PROCESS | 5517 | __metrics Screen width/height:1600/1200 01:27:31 INFO - PROCESS | 5517 | colorDepth:24 01:27:31 INFO - PROCESS | 5517 | Browser inner width/height: 1024/697 01:27:31 INFO - PROCESS | 5517 | __metrics 01:27:32 ERROR - PROCESS | 5517 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:27:33 INFO - TEST-INFO | 5517: exit 0 01:27:33 INFO - Browser initialized. 01:27:33 INFO - Running cycle 1/1 for dromaeo_css test... 01:27:33 INFO - TEST-INFO | started process 5600 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVLfgsU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:27:45 INFO - PROCESS | 5600 | RSS: Main: 154918912 01:27:45 INFO - PROCESS | 5600 | 01:28:37 INFO - PROCESS | 5600 | 1461140917077 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:30:48 INFO - PROCESS | 5600 | Cycle 1(1): loaded http://localhost:37398/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37398/tests/dromaeo/cssquery-ext.html) 01:30:49 INFO - PROCESS | 5600 | RSS: Main: 261066752 01:30:49 INFO - PROCESS | 5600 | 01:33:52 INFO - PROCESS | 5600 | Cycle 1(1): loaded http://localhost:37398/tests/dromaeo/cssquery-ext.html (next: http://localhost:37398/tests/dromaeo/cssquery-jquery.html) 01:33:52 INFO - PROCESS | 5600 | RSS: Main: 232566784 01:33:52 INFO - PROCESS | 5600 | 01:36:54 INFO - PROCESS | 5600 | Cycle 1(1): loaded http://localhost:37398/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37398/tests/dromaeo/cssquery-mootools.html) 01:36:55 INFO - PROCESS | 5600 | RSS: Main: 238718976 01:36:55 INFO - PROCESS | 5600 | 01:39:57 INFO - PROCESS | 5600 | Cycle 1(1): loaded http://localhost:37398/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37398/tests/dromaeo/cssquery-prototype.html) 01:39:58 INFO - PROCESS | 5600 | RSS: Main: 232169472 01:39:58 INFO - PROCESS | 5600 | 01:43:01 INFO - PROCESS | 5600 | Cycle 1(1): loaded http://localhost:37398/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37398/tests/dromaeo/cssquery-yui.html) 01:43:01 INFO - PROCESS | 5600 | RSS: Main: 217563136 01:43:01 INFO - PROCESS | 5600 | 01:46:04 INFO - PROCESS | 5600 | Cycle 1(1): loaded http://localhost:37398/tests/dromaeo/cssquery-yui.html (next: http://localhost:37398/tests/dromaeo/cssquery-dojo.html) 01:46:04 INFO - PROCESS | 5600 | RSS: Main: 270991360 01:46:04 INFO - PROCESS | 5600 | 01:46:04 INFO - PROCESS | 5600 | __start_tp_report 01:46:04 INFO - PROCESS | 5600 | _x_x_mozilla_page_load 01:46:04 INFO - PROCESS | 5600 | _x_x_mozilla_page_load_details 01:46:04 INFO - PROCESS | 5600 | |i|pagename|runs| 01:46:04 INFO - PROCESS | 5600 | |0;dojo.html;2567.525007873543;2643.207037888634;2650.5626571615685;2663.6404085448457;2678.9598156027664;4474;4522.186006518826;4546.909061818762;4548.977255113724;4562.04197118605;4467.240569103252;4599.77001149942;4668.463126740426;4681.508441613632;4690.835820746274;4215.346621273708;4233.661307095425;4266.040140968273;4282.507511636155;4340.08858139791;4280.764557949312;4286.271333873249;4297.183535128315;4297.957020429787;4324.07032488015;4087.9182416351805;4270.572942705736;4302.032042790378;4331.566843315674;4356.80394382252;4199.895002624929;4204.453421055274;4221.6200541951375;4238.131183107486;4277.165952639237;4218.261804184255;4233.25917964355;4233.576642335773;4274.551172126913;4281.850135245282;4358.825646974117;4496.437945256843;4496.977515112436;4510.526394728572;4541.772911354419;4874.805007799684;4919.631027672939;4924.4583095859425;4966.230234313651;4973.726445045521;4907.533784290486;4912.557869791736;4922.212446008622;4931.753412329369;4960.603151747851;4437.42313499243;4603.861884143447;4607.539246075433;4607.884802879955;4647.326137710013;4987.925181122286;5031.295618613397;5058.494150584912;5066.113430149709;5112.335396398445;4827.058723548875;4828.830990915301;4832.44426890912;4834.613230941516;4884.902301953941;4739.241721324572;4880.829170978998;4914.017196560702;4955.207166853358;4974.452810190876;4774.045190961821;4791;4793.424789025339;4839.879003024953;4852.951470485321;4771.6898401603785;4776.402949631296;4777.187878060738;4802.039592081597;4820.855374338776;4043.8584649537124;4107.096438783463;4131.628153466202;4151.7924103794685;4198.265303571863;3660.633936606318;3670.082479380155;3682.3371793077504;3700.500432441586;3704.129529560551;4237.216115018731;4251;4256.659467242675;4258.169656916872;4298.914021719611;4715.080559290974;4851.781669824866;4914.606831453475;4959.801607935714;4965.081459929925;4250.36244563318;4258.361245813091;4265.1043280910735;4283.764392958417;4325.632321252666;4465.553444655573;4493.28107502798;4498.775061246924;4542.704724192917;4545.159145558082;4540.341650460665;4703.506131856243;4717.3159891816185;4741.881452963704;4764.190087685032;4861.343718598086;4862.246351815475;4865.683730557503;4867.634927380532;4897.608191344612;4484.798184081789;4619.653525985632;4628.305754136906;4635.096156249499;4672.7897244624755;4856.320115183948;4900.779464924016;4909.582685471775;4948.307236986752;4954.504549545017;4900.852974410774;4939.259111133359;4939.876503087452;4942.28336891156;4988.925166122439;4619.260918253063;4761.3810204674555;4798.47216806158;4809.062232864558;4857.975710121426;4705.717656940595;4708.788104535372;4729.266963620645;4769.785359658755;4778.546038126373;4665.6034237090225;4676.064787042673;4684.765761711833;4688.437387513521;4730.0539892022425;4631.073785243032;4781.665283430127;4782.521747825329;4827.8551643450755;4828.37231159947;4745.881352966204;4763.428388593391;4781;4783.95216047835;4836.105320515716 01:46:04 INFO - PROCESS | 5600 | |1;ext.html;567.4864247855692;607.2591438445097;609.9847503812401;627.570114471587;636.1253276744476;26812.59781103285;33575.32849343012;34365.48451773222;34419.827900860524;34477.31045379091;37496.25007499848;38324.80837595815;38347.80826095873;38358;38375.616243837554;7319.451041171907;7602.923970760291;7810.297073263405;9494.952525237399;9767.36512126713;9595.568199431042;9728.902710972905;9734.367266127714;9738.415695058284;9739.415635061883;9679.032096790299;9763.804723905518;9767.951160244189;9781.462019588955;9781.902180978206;7515.32362087412;9825.312228144032;10170.237232207613;10195.79608407835;10199.18406527476;9604.711858644181;9653.17947974416;9701.417914925127;9726.465044422624;9758.804823903482;33902.32195356104;34567.65432345695;34767.130821729406;34819.65180348215;34983;3058.8623511942014;3121.5317702344605;3285.737141028712;3729.813509324523;3841.519810023747;28608.856955715295;28696.426071478454;28703.71296287031;28719.85640071786;28944.276393090342;3326.7172290355347;3366.3099064691924;3393.7285017198565;3399.2521645238007;3403.5235067090625;226492;226498;226835.8658206715;227290;227641;52164.739176303876;52358;52557.737211313695;52583.73708131435;52650;55892.72053639706;56061;60468;62610.68694656497;62740.68629656914;12591.496340146476;13239.801402978963;13461.461541538425;13559.322033898266;13582.59252222435;9329.253659707207;9414.340996130206;9421;9551.570179341947;9685.6610018649;17610.735838962424;17753.7336939946;17771.489423399154;17786.644267114843;17794.110294485225;6692.799216023527;6876.724931002704;6948.131483564554;6957.304269573002;7023.508354415142;846.8052347960004;868.9826203475895;893.7710647859194;951.900050494702;974.6734843827255;27471.17586472369;27879.721202787714;28077.22285508709;28338.433231335257;28548.714512855022;991.5240684471494;1014.6144465103214;1051.558345494894;1070.5717712914895;1072.855164552791;14523.927380363242;15094.924525377302;15113.54659360221;15115.470958516398;15322.540323790305;335798;342390;343188;344950.78016808373;345101;47675;49167.754161228964;49184.75407623011;49251.26123108156;49296.75351623219;1106.8450416941635;1134.7787181499693;1223.9449224784905;1246.4204145072586;1247.6943148928572;24666.63000054965;25372.238832835046;25570.488590228462;26053.609195862457;26164;38594.2281154381;39256.60743392644;39280;39321.41017884677;39389;221172;225956;226129;226270;227119.86440067695;23397.298081057597;24232.030718771053;24245.51508969846;24258.272251832474;24587.50824983526;35490.46764298486;35607.465888012215;35618.287634246655;36003;36415.27169456543;30064.64062710851;30675.693243067282;30750.384992300475;31340.686593134687;31657.525137123423;30989.380212395175;31032.22419439532;31158.68841311558;31180.220494487818;31399.372012559164;31608.209794755316;33697.494537582446;34125.31749365049;34137.146571335914;36187.81906090453;27131.1860644181;27468;28449.715502845535;28880.85559572189;29039.56440653434;23931.52136957286;24237.757622424255;24452.388690282885;24575;24787.25638230856 01:46:04 INFO - PROCESS | 5600 | |2;jquery.html;376.99246015079683;380.501542978698;385.3026022898552;386.86653104678885;388.11703374822275;16100.114493702842;16446.259918303676;17392.565185870328;17407;17432.738508922346;11974.7605047899;15057.17185554794;15826.683466330695;15826.76259856103;15840.128792916385;4537.841175558848;4679.087577922306;4727.574518293353;4727.645426593002;4734.739589322586;3434.931301373971;5119.206522988924;5417.943501017303;5446.455354464541;5453.945460545384;5429.131338985763;5434.402215756264;5441.319835020622;5445.156000819879;5466.125419932811;5071.797128114871;5384.165454354581;5394.05604019297;5394.3796463406425;5397.892042159155;3349.949750753741;4832.202686556714;5172.19830926207;5176.197689358156;5199.766010529535;11674.94162529182;11721.648350549412;11739.823902641467;11804.881951180465;12504.49982000719;1849.8982555959417;1925.1336898395778;1935.2936178294963;1986.4437957371958;2432.1852179519706;9225.769355766093;9483.66807161746;9553.187979021792;9604.231661467065;10072.748181295454;1626.8617167540774;1752.2465339903836;1789.7225929980873;1798.739182818484;1838.3290099113726;63655.36344636541;66845.33154668439;67176.32823671798;67320;67492.6625366875;9670.419774813534;9742.756431089138;9850.950745246228;9922.36281013639;10071.34536255141;13504.189748615114;13565.59303220905;13601.659958501117;13613.387397567134;13630.522931697344;7074.398676112588;7142.750003749844;7152.24901385347;7224.422046236394;7292.124945006527;6829.829254268683;6845.726170953206;6872.862542749217;6939.965300173568;6959.721611135599;4179.895502612399;4207.221663992172;4236.661067114624;4238.6397156242065;4299.914001720011;1705;1723.448496481114;1761.8414342709218;1764.5765016396228;1771.477414162825;832.134580036767;877.7322916510398;911.4485736129678;955.4983633592419;956.5886668732372;15671.608209794846;16657.167141643113;16865.241064152142;16909;16943.15284235783;861.8534849075672;879.9428037177563;890.1499068389672;899.8965119011267;958.2477754962321;9078.273738100936;9192.49441280736;9315.161635452841;9367.859482107773;9486.335956483117;380228;388407;389768;391880;392422;76782;77101.61449192718;77716.90546702176;78237;78798.6060069696;933.8926023507383;939.4973689076448;941.6892425499568;963.0850691842777;964.1370972979145;14571.00039332675;14603.634909127357;14732.55802325932;14855.92572037133;14966.476173333882;33536.155511447105;33899.4915076279;34062.65937340595;34417;34534.48198277078;43901.341479878465;46367;46462.76768616135;47162.76418617885;48524.75737621431;7417.777466676009;7731.38148948094;7845.254700803414;7851.528908265523;7864.331531819859;11559.133065019989;11589.594364197214;11611.6516504505;11785.646430607096;12112.454939527566;10032.899671003488;10366.637167699095;10484.947575262075;10533.473326333806;10806.243562950833;10661.466926653791;10705.518251678537;10798.56805727762;10813.945930270298;10837.049331120474;10695.786084278427;10734;10737;10921.453927303763;11113.666590002313;7794.1118199470475;7854.253845884632;7927.60361981887;8015.038195416354;8070.636821342936;10356.378617282988;10535.15718742514;10597.099246564128;10694.091002264584;10742.516586753458 01:46:04 INFO - PROCESS | 5600 | |3;mootools.html;1125.1561329003248;1422.2319947228498;1460.934257958392;1573.858352748253;1750.9387171448993;7290.635468226587;7489.700411983521;7509.962450187755;7521.774346769592;7524.774256772299;7332.386874887529;7479.5138316009425;7488.887666685004;7495.625218739068;7498.512596681226;1820.8270214329618;1882.7270045843345;1886.528367908023;2137.8931053447304;2153.149505945151;2225.054351900436;2302.4128847143957;2302.5855346037706;2303.34354708908;2303.7350704668925;2189.912403503866;2211.9447013824624;2211.966820497694;2214.258223495131;2240.977590224093;1853.0271607406085;1893.479293194369;2187.7921597448217;2189.3650841256017;2191.3425972208274;1888.688366419539;2164.426426996847;2220.211824802188;2239.742429620598;2240.8095311898346;7662.770116896502;7708.884366734504;7729.265719756614;7729.536227826348;7733.149353571102;4503.954960450386;4670.95329046712;4686.742229177394;4696.131215725068;4700.741459219742;2926.0636596289196;2960.6151200344034;2998.7301142897027;3063.0657649416917;3071.6314042314843;931.1014870649856;936.3398803843274;945.3760518058117;960.3949511807587;961.4135377419768;10757.354558726502;10808.081313088396;10838.945805271002;10885.782284354347;10907.890921090768;4282.8715138545895;4305.483341998917;4462.888427789267;4481.417415735934;4482.394876691605;9135.360524763335;9231.907680923105;9255.722328330161;9282.396644218104;9286.349955503185;4687.179743544866;4692.225782745877;4715.363425937523;4715.764211789466;4718.103560323596;3721.6650501455;3828.3300422426523;4216.5572614875;4237.63980061692;4279.358096285582;3739.6634302912325;3757.323681737259;3839.040239940015;3856.1902000580194;3876.6898648108086;1216.391804097951;1218.4638758946035;1219.432963671897;1234.734532075608;1252.7306629074792;689.9482538809609;780.594091072639;884.9955750221336;908.4367692030983;916.6699988004314;5242.423333433319;5381.112116500812;5408;5506.421825708323;5593.804216852391;852.2202185000655;859.9613017414231;876.6624849432886;913.9040400757825;949.2738055387634;3772.9245415091546;3778.792166430818;3780.7920564368674;3815.122521819997;3869.342211823997;9485.715428537154;9858.507074646239;9868.80262394748;9880.258980576486;9960.950195248977;9649.855252171223;9731.805363892683;9808.068233517804;9843.704688859345;9856.802863942681;7391.482596218213;7403.888941665879;7527.736529221451;7558.811029724191;7570.697172113164;5196.506331898464;5322.920156197582;5403.270558474555;5491.75287112073;5525.530329922001;8879.955600222216;9140.223081038186;9143.954280228556;9230.307726920373;9277.86083208766;9288.767780805534;9295.907040929775;9296.814063718823;9302.720918372459;9419.199368053793;5773.6824474654295;6434.774782882577;6557.803265902031;6626.304238055128;6635.668216589248;7571.962140189264;7623.4663573549315;7629.2752188542;7673.846523069619;7687.500312479671;6523.151990241238;6620.966895165493;6678.198616166092;6783.253842077269;6804.693788779616;6785;6785.830354240986;6804.387605115563;6871.03805467245;6901.896471553031;6277.843053923689;6482.124913136666;6499.122618446449;6550.213974323112;6568.277489476058;4920.975395123001;4930.334404855442;4937.325142623812;4970.552650261349;5034.018366418513;4731.030138821602;4784.186688262934;4845.5881250094135;4933.605311574994;4982.128127577732 01:46:04 INFO - PROCESS | 5600 | |4;prototype.html;308.5433558333668;320.60725611126367;320.9582754241948;325.09623247372315;327.11515350975606;4822.855314340567;4979.7342209294775;4999.275105109761;5066.240063990399;5078.822241221554;4791.377120974277;4967;4968.975155124219;4974.999999999991;4984.426790919046;3956.188981258835;3960.980195099035;3961.4850069490926;3962.445257663929;3963.7225394222414;3759.9624003760023;3809.3714537101346;3929.626685464876;3940.7044471664594;3960.2079584083153;3886.7084968627323;3891.5135608048995;3895.6883449323986;3898.7465814722095;3902.7853468059247;3862.594427585091;3874.5737968823405;3885.689144868432;3909.7849618270984;3915.6671682906704;3479.4780782882517;3485.5643044619424;3488.110531814371;3492.301539692072;3494.1614012637037;4581.381513495702;4822.517748225185;4827.710337379768;4847.175980083359;4850.75746212688;4435.711678740871;4436.489803672586;4441.911161776747;4442.311441726538;4442.73343599385;5232.096186616426;5247.449017853147;5256.500632439912;5258.184981327862;5263.6578622389425;3624.855005799765;3701.9634502339386;3706.036430528055;3716.7955762433057;3733.010752150682;5382.650127741685;5394.973025134888;5426.430224826416;5474.835754927319;5478.315210598676;3761.567419746709;3795.544534655859;3795.582485926546;3799.088218827517;3803.581606023335;3750.849966001384;3824.827882745283;3855.3060449118866;3855.51806024247;3856.382978723391;4368.191884501377;4383.715058521186;4386.122775444924;4390.385346051556;4397.73613583186;5207.453217412193;5212.332053742842;5228;5235.47645235481;5251.76367063483;1245.483124503321;1281.8718128187106;1328.926909019994;1334.8586958150836;1338.6987927716382;891.6121487153109;904.3353135445442;905.9456432614064;916.1571354354011;928.4986107501874;4573.262160379101;4573.679842410999;4591.678582499259;4592.954070459253;4596.47140578831;5318;5327.440618735054;5332.253484512172;5333.5199832015305;5341.545968592635;4451.59935605797;4550.977245113753;4561.24739417999;4581.74800385982;4591.9540804591525;2852.1871266688895;2852.229897927548;2860.75683566895;2862.670792858806;2869.6412948381453;5054.342935418372;5059.797608095635;5067.543921047123;5071.087204303187;5074.314967479417;5292.862034662566;5442.102053161184;5451.509364157245;5469.863253418617;5628.774849006076;5162.070827251056;5164.535191832678;5173.301604283373;5205.765740541685;5206.8958620828125;5464.5081942623765;5471.753771080391;5476.753546090516;5489.204065410573;5496.367917689517;5252.44849290819;5342.973285133549;5352.277442545206;5364.3830959440165;5373.650712703662;5126.256692779601;5159.2003239497935;5174.430812610534;5174.6119041071315;5188.558972487381;3779.527559055118;3823.560290566509;3827.9425808612336;3827.9425808612336;3838.9424158637084;5140.614453915897;5249.868753281198;5400.729963501888;5425.810096646598;5443.101888188405;5104.132297509358;5249.475052494873;5268.78924843002;5278.97360513195;5281;5250.212468129811;5256.4781596329785;5256.579473642176;5277.021117081094;5285.418603953639;4922.310876477368;5187.714675692873;5188.922166167435;5195.298634684269;5198.8960220796125;5308.25684404176;5316.255724198538;5319.920201196907;5345.9465405345445;5346.465353464622;5195.3246078009615;5201.843944681666;5213.400458947118;5219.8434046978655;5228.294180285613 01:46:04 INFO - PROCESS | 5600 | |5;yui.html;637.0953246390126;655.3806652713185;673.4477728262824;684.7432212920158;685.2222727204622;11355.716107097327;11367.943160284198;11527.596534121307;11567.710807229823;11568;10870.510827012784;11323.660290191307;11549.191556591042;11598.942005289962;11607.767844643102;645.4287955159671;781.1914668318292;814.568278812229;821.7699044267615;824.7072289337289;818.7216346442209;822.6668199379232;822.9218224268685;822.9670813167467;823.2590668398459;862.0388267082221;862.4911302331623;862.4911302331623;863.6588547523725;864.0495454999514;686.4885660183146;834.7328854766478;858.3347905373323;860.4450129666361;865.9826803463927;626.8558231606711;627.4165026525329;628.136312570216;628.6008384675719;629.4460874430472;12277;12503;12516.123871329011;12518.186317889307;12540.74918501634;12339.259644421363;12423.44094515749;12586.18189817659;12629.052821038367;12759;9370.344075914689;9376.765580860532;9480.810383792354;9629.90370096297;9766.07222313879;2866.928326791826;2912.5339945608603;2913.6649285332246;2936;2960.6595241547284;43902.560974390486;43953;43954.34068488975;44378.33432498515;45336.54663453356;9629.181519570915;9669.468179250069;9712.22302215821;9804.607815687436;9821.165200958;18327.908360458114;18412.447626571226;18475.538111547317;18880.81119188818;18915.62168756617;5762.855928601734;5887.087501437199;6110.938890611126;6189.752409903554;6226.439620434092;3276.0663210984753;3298.1249163572766;3300.6534313896695;3313.287643156733;3318.8672453101603;2641.616965539986;2729.672439307296;2731.426400455887;2731.726827317252;2734.6718393792476;433.9587739164774;500.19968051118644;510.64765311934644;513.2455290722631;515.6673945305257;558.5699011760921;575.9683217423081;587.090010483757;587.632729544035;597.8505373656586;7285.708571657075;7399.556026638419;7479.70081196746;7692.538447693044;7883.408744344197;477.28645674716523;483.4995779368337;528.6009063157292;532.5074306266688;535.8044313825426;3268.379007988522;3575.982120089383;3584.7490675652452;3686.723495737777;3725.795081270502;50867.138220191766;51048.48951510512;51655.7417212919;51749.48250517446;52021;46646.30030549477;46893;47381.28928066013;47718;48010.27984580234;43679.78160109179;44033;44357.33463998043;44625.330620040724;45241.54758452373;6793.626350550767;6940.444764418935;7100.183478899786;7144.142702875688;7156.284371563011;28791.136265912057;28877.422451550432;29295.267618309714;29496.852515737282;29574.556381654722;35847.28305433929;37326.440103399014;39366.606333936295;39497;39684.00789980274;9270.44377337362;9314.813703726024;9461.38500997433;9596.856047159159;9712.70861874145;10086.24891815822;10177.084062434416;10196.449468416085;10242.897571024492;10312.329698569569;9625.71122866315;9656.32405731592;9799.461029643438;9834.459104749307;9847.113759761656;8839.867401988846;8865.202131808168;8894.86657700148;8930.508822014852;9296.163345298955;8785.912140878509;8808.647654093764;8922.910770892207;8927.955360223157;9091.272698184262;7694.269044440769;8310.667573297;8809.07504711996;8865.335099867665;8870.979777628432;7670.424718146273;7898.2891539761695;8003.6798528058225;8042.83914321722;8190.549519776469 01:46:04 INFO - PROCESS | 5600 | __end_tp_report 01:46:04 INFO - PROCESS | 5600 | __start_cc_report 01:46:04 INFO - PROCESS | 5600 | _x_x_mozilla_cycle_collect,1082 01:46:04 INFO - PROCESS | 5600 | __end_cc_report 01:46:04 INFO - PROCESS | 5600 | __startTimestamp1461141964690__endTimestamp 01:46:04 INFO - PROCESS | 5600 | 01:46:04 INFO - PROCESS | 5600 | ------- Summary: start ------- 01:46:04 INFO - PROCESS | 5600 | Number of tests: 6 01:46:04 INFO - PROCESS | 5600 | 01:46:04 INFO - PROCESS | 5600 | [#0] dojo.html Cycles:165 Average:4543.19 Median:4676.06 stddev:456.68 (9.8%) stddev-sans-first:430.98 01:46:04 INFO - PROCESS | 5600 | Values: 2567.5 2643.2 2650.6 2663.6 2679.0 4474.0 4522.2 4546.9 4549.0 4562.0 4467.2 4599.8 4668.5 4681.5 4690.8 4215.3 4233.7 4266.0 4282.5 4340.1 4280.8 4286.3 4297.2 4298.0 4324.1 4087.9 4270.6 4302.0 4331.6 4356.8 4199.9 4204.5 4221.6 4238.1 4277.2 4218.3 4233.3 4233.6 4274.6 4281.9 4358.8 4496.4 4497.0 4510.5 4541.8 4874.8 4919.6 4924.5 4966.2 4973.7 4907.5 4912.6 4922.2 4931.8 4960.6 4437.4 4603.9 4607.5 4607.9 4647.3 4987.9 5031.3 5058.5 5066.1 5112.3 4827.1 4828.8 4832.4 4834.6 4884.9 4739.2 4880.8 4914.0 4955.2 4974.5 4774.0 4791.0 4793.4 4839.9 4853.0 4771.7 4776.4 4777.2 4802.0 4820.9 4043.9 4107.1 4131.6 4151.8 4198.3 3660.6 3670.1 3682.3 3700.5 3704.1 4237.2 4251.0 4256.7 4258.2 4298.9 4715.1 4851.8 4914.6 4959.8 4965.1 4250.4 4258.4 4265.1 4283.8 4325.6 4465.6 4493.3 4498.8 4542.7 4545.2 4540.3 4703.5 4717.3 4741.9 4764.2 4861.3 4862.2 4865.7 4867.6 4897.6 4484.8 4619.7 4628.3 4635.1 4672.8 4856.3 4900.8 4909.6 4948.3 4954.5 4900.9 4939.3 4939.9 4942.3 4988.9 4619.3 4761.4 4798.5 4809.1 4858.0 4705.7 4708.8 4729.3 4769.8 4778.5 4665.6 4676.1 4684.8 4688.4 4730.1 4631.1 4781.7 4782.5 4827.9 4828.4 4745.9 4763.4 4781.0 4784.0 4836.1 01:46:04 INFO - PROCESS | 5600 | 01:46:04 INFO - PROCESS | 5600 | [#1] ext.html Cycles:180 Average:41978.38 Median:18764.42 stddev:70892.85 (377.8%) stddev-sans-first:71023.54 01:46:04 INFO - PROCESS | 5600 | Values: 567.5 607.3 610.0 627.6 636.1 26812.6 33575.3 34365.5 34419.8 34477.3 37496.3 38324.8 38347.8 38358.0 38375.6 7319.5 7602.9 7810.3 9495.0 9767.4 9595.6 9728.9 9734.4 9738.4 9739.4 9679.0 9763.8 9768.0 9781.5 9781.9 7515.3 9825.3 10170.2 10195.8 10199.2 9604.7 9653.2 9701.4 9726.5 9758.8 33902.3 34567.7 34767.1 34819.7 34983.0 3058.9 3121.5 3285.7 3729.8 3841.5 28608.9 28696.4 28703.7 28719.9 28944.3 3326.7 3366.3 3393.7 3399.3 3403.5 226492.0 226498.0 226835.9 227290.0 227641.0 52164.7 52358.0 52557.7 52583.7 52650.0 55892.7 56061.0 60468.0 62610.7 62740.7 12591.5 13239.8 13461.5 13559.3 13582.6 9329.3 9414.3 9421.0 9551.6 9685.7 17610.7 17753.7 17771.5 17786.6 17794.1 6692.8 6876.7 6948.1 6957.3 7023.5 846.8 869.0 893.8 951.9 974.7 27471.2 27879.7 28077.2 28338.4 28548.7 991.5 1014.6 1051.6 1070.6 1072.9 14523.9 15094.9 15113.5 15115.5 15322.5 335798.0 342390.0 343188.0 344950.8 345101.0 47675.0 49167.8 49184.8 49251.3 49296.8 1106.8 1134.8 1223.9 1246.4 1247.7 24666.6 25372.2 25570.5 26053.6 26164.0 38594.2 39256.6 39280.0 39321.4 39389.0 221172.0 225956.0 226129.0 226270.0 227119.9 23397.3 24232.0 24245.5 24258.3 24587.5 35490.5 35607.5 35618.3 36003.0 36415.3 30064.6 30675.7 30750.4 31340.7 31657.5 30989.4 31032.2 31158.7 31180.2 31399.4 31608.2 33697.5 34125.3 34137.1 36187.8 27131.2 27468.0 28449.7 28880.9 29039.6 23931.5 24237.8 24452.4 24575.0 24787.3 01:46:04 INFO - PROCESS | 5600 | 01:46:04 INFO - PROCESS | 5600 | [#2] jquery.html Cycles:180 Average:23692.70 Median:4221.94 stddev:64111.23 (1518.5%) stddev-sans-first:64267.18 01:46:04 INFO - PROCESS | 5600 | Values: 377.0 380.5 385.3 386.9 388.1 16100.1 16446.3 17392.6 17407.0 17432.7 11974.8 15057.2 15826.7 15826.8 15840.1 4537.8 4679.1 4727.6 4727.6 4734.7 3434.9 5119.2 5417.9 5446.5 5453.9 5429.1 5434.4 5441.3 5445.2 5466.1 5071.8 5384.2 5394.1 5394.4 5397.9 3349.9 4832.2 5172.2 5176.2 5199.8 11674.9 11721.6 11739.8 11804.9 12504.5 1849.9 1925.1 1935.3 1986.4 2432.2 9225.8 9483.7 9553.2 9604.2 10072.7 1626.9 1752.2 1789.7 1798.7 1838.3 63655.4 66845.3 67176.3 67320.0 67492.7 9670.4 9742.8 9851.0 9922.4 10071.3 13504.2 13565.6 13601.7 13613.4 13630.5 7074.4 7142.8 7152.2 7224.4 7292.1 6829.8 6845.7 6872.9 6940.0 6959.7 4179.9 4207.2 4236.7 4238.6 4299.9 1705.0 1723.4 1761.8 1764.6 1771.5 832.1 877.7 911.4 955.5 956.6 15671.6 16657.2 16865.2 16909.0 16943.2 861.9 879.9 890.1 899.9 958.2 9078.3 9192.5 9315.2 9367.9 9486.3 380228.0 388407.0 389768.0 391880.0 392422.0 76782.0 77101.6 77716.9 78237.0 78798.6 933.9 939.5 941.7 963.1 964.1 14571.0 14603.6 14732.6 14855.9 14966.5 33536.2 33899.5 34062.7 34417.0 34534.5 43901.3 46367.0 46462.8 47162.8 48524.8 7417.8 7731.4 7845.3 7851.5 7864.3 11559.1 11589.6 11611.7 11785.6 12112.5 10032.9 10366.6 10484.9 10533.5 10806.2 10661.5 10705.5 10798.6 10813.9 10837.0 10695.8 10734.0 10737.0 10921.5 11113.7 7794.1 7854.3 7927.6 8015.0 8070.6 10356.4 10535.2 10597.1 10694.1 10742.5 01:46:04 INFO - PROCESS | 5600 | 01:46:04 INFO - PROCESS | 5600 | [#3] mootools.html Cycles:180 Average:5205.78 Median:5449.88 stddev:2907.80 (53.4%) stddev-sans-first:2899.78 01:46:04 INFO - PROCESS | 5600 | Values: 1125.2 1422.2 1460.9 1573.9 1750.9 7290.6 7489.7 7510.0 7521.8 7524.8 7332.4 7479.5 7488.9 7495.6 7498.5 1820.8 1882.7 1886.5 2137.9 2153.1 2225.1 2302.4 2302.6 2303.3 2303.7 2189.9 2211.9 2212.0 2214.3 2241.0 1853.0 1893.5 2187.8 2189.4 2191.3 1888.7 2164.4 2220.2 2239.7 2240.8 7662.8 7708.9 7729.3 7729.5 7733.1 4504.0 4671.0 4686.7 4696.1 4700.7 2926.1 2960.6 2998.7 3063.1 3071.6 931.1 936.3 945.4 960.4 961.4 10757.4 10808.1 10838.9 10885.8 10907.9 4282.9 4305.5 4462.9 4481.4 4482.4 9135.4 9231.9 9255.7 9282.4 9286.3 4687.2 4692.2 4715.4 4715.8 4718.1 3721.7 3828.3 4216.6 4237.6 4279.4 3739.7 3757.3 3839.0 3856.2 3876.7 1216.4 1218.5 1219.4 1234.7 1252.7 689.9 780.6 885.0 908.4 916.7 5242.4 5381.1 5408.0 5506.4 5593.8 852.2 860.0 876.7 913.9 949.3 3772.9 3778.8 3780.8 3815.1 3869.3 9485.7 9858.5 9868.8 9880.3 9961.0 9649.9 9731.8 9808.1 9843.7 9856.8 7391.5 7403.9 7527.7 7558.8 7570.7 5196.5 5322.9 5403.3 5491.8 5525.5 8880.0 9140.2 9144.0 9230.3 9277.9 9288.8 9295.9 9296.8 9302.7 9419.2 5773.7 6434.8 6557.8 6626.3 6635.7 7572.0 7623.5 7629.3 7673.8 7687.5 6523.2 6621.0 6678.2 6783.3 6804.7 6785.0 6785.8 6804.4 6871.0 6901.9 6277.8 6482.1 6499.1 6550.2 6568.3 4921.0 4930.3 4937.3 4970.6 5034.0 4731.0 4784.2 4845.6 4933.6 4982.1 01:46:04 INFO - PROCESS | 5600 | 01:46:04 INFO - PROCESS | 5600 | [#4] prototype.html Cycles:180 Average:4354.27 Median:4977.37 stddev:1267.21 (25.5%) stddev-sans-first:1233.85 01:46:04 INFO - PROCESS | 5600 | Values: 308.5 320.6 321.0 325.1 327.1 4822.9 4979.7 4999.3 5066.2 5078.8 4791.4 4967.0 4969.0 4975.0 4984.4 3956.2 3961.0 3961.5 3962.4 3963.7 3760.0 3809.4 3929.6 3940.7 3960.2 3886.7 3891.5 3895.7 3898.7 3902.8 3862.6 3874.6 3885.7 3909.8 3915.7 3479.5 3485.6 3488.1 3492.3 3494.2 4581.4 4822.5 4827.7 4847.2 4850.8 4435.7 4436.5 4441.9 4442.3 4442.7 5232.1 5247.4 5256.5 5258.2 5263.7 3624.9 3702.0 3706.0 3716.8 3733.0 5382.7 5395.0 5426.4 5474.8 5478.3 3761.6 3795.5 3795.6 3799.1 3803.6 3750.8 3824.8 3855.3 3855.5 3856.4 4368.2 4383.7 4386.1 4390.4 4397.7 5207.5 5212.3 5228.0 5235.5 5251.8 1245.5 1281.9 1328.9 1334.9 1338.7 891.6 904.3 905.9 916.2 928.5 4573.3 4573.7 4591.7 4593.0 4596.5 5318.0 5327.4 5332.3 5333.5 5341.5 4451.6 4551.0 4561.2 4581.7 4592.0 2852.2 2852.2 2860.8 2862.7 2869.6 5054.3 5059.8 5067.5 5071.1 5074.3 5292.9 5442.1 5451.5 5469.9 5628.8 5162.1 5164.5 5173.3 5205.8 5206.9 5464.5 5471.8 5476.8 5489.2 5496.4 5252.4 5343.0 5352.3 5364.4 5373.7 5126.3 5159.2 5174.4 5174.6 5188.6 3779.5 3823.6 3827.9 3827.9 3838.9 5140.6 5249.9 5400.7 5425.8 5443.1 5104.1 5249.5 5268.8 5279.0 5281.0 5250.2 5256.5 5256.6 5277.0 5285.4 4922.3 5187.7 5188.9 5195.3 5198.9 5308.3 5316.3 5319.9 5345.9 5346.5 5195.3 5201.8 5213.4 5219.8 5228.3 01:46:04 INFO - PROCESS | 5600 | 01:46:04 INFO - PROCESS | 5600 | [#5] yui.html Cycles:180 Average:12328.66 Median:567.27 stddev:14526.77 (2560.8%) stddev-sans-first:14540.99 01:46:04 INFO - PROCESS | 5600 | Values: 637.1 655.4 673.4 684.7 685.2 11355.7 11367.9 11527.6 11567.7 11568.0 10870.5 11323.7 11549.2 11598.9 11607.8 645.4 781.2 814.6 821.8 824.7 818.7 822.7 822.9 823.0 823.3 862.0 862.5 862.5 863.7 864.0 686.5 834.7 858.3 860.4 866.0 626.9 627.4 628.1 628.6 629.4 12277.0 12503.0 12516.1 12518.2 12540.7 12339.3 12423.4 12586.2 12629.1 12759.0 9370.3 9376.8 9480.8 9629.9 9766.1 2866.9 2912.5 2913.7 2936.0 2960.7 43902.6 43953.0 43954.3 44378.3 45336.5 9629.2 9669.5 9712.2 9804.6 9821.2 18327.9 18412.4 18475.5 18880.8 18915.6 5762.9 5887.1 6110.9 6189.8 6226.4 3276.1 3298.1 3300.7 3313.3 3318.9 2641.6 2729.7 2731.4 2731.7 2734.7 434.0 500.2 510.6 513.2 515.7 558.6 576.0 587.1 587.6 597.9 7285.7 7399.6 7479.7 7692.5 7883.4 477.3 483.5 528.6 532.5 535.8 3268.4 3576.0 3584.7 3686.7 3725.8 50867.1 51048.5 51655.7 51749.5 52021.0 46646.3 46893.0 47381.3 47718.0 48010.3 43679.8 44033.0 44357.3 44625.3 45241.5 6793.6 6940.4 7100.2 7144.1 7156.3 28791.1 28877.4 29295.3 29496.9 29574.6 35847.3 37326.4 39366.6 39497.0 39684.0 9270.4 9314.8 9461.4 9596.9 9712.7 10086.2 10177.1 10196.4 10242.9 10312.3 9625.7 9656.3 9799.5 9834.5 9847.1 8839.9 8865.2 8894.9 8930.5 9296.2 8785.9 8808.6 8922.9 8928.0 9091.3 7694.3 8310.7 8809.1 8865.3 8871.0 7670.4 7898.3 8003.7 8042.8 8190.5 01:46:04 INFO - PROCESS | 5600 | -------- Summary: end -------- 01:46:04 INFO - PROCESS | 5600 | 01:46:05 INFO - TEST-INFO | 5600: exit 0 01:46:05 INFO - TEST-OK | dromaeo_css | took 1117722ms 01:46:05 INFO - TEST-START | kraken 01:46:05 INFO - operating with platform_type : linux_ 01:46:05 INFO - Initialising browser for kraken test... 01:46:05 INFO - TEST-INFO | started process 5772 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwXG0mT/profile http://localhost:37398/getInfo.html) 01:46:09 INFO - PROCESS | 5772 | __metrics Screen width/height:1600/1200 01:46:09 INFO - PROCESS | 5772 | colorDepth:24 01:46:09 INFO - PROCESS | 5772 | Browser inner width/height: 1024/697 01:46:09 INFO - PROCESS | 5772 | __metrics 01:46:10 ERROR - PROCESS | 5772 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:46:10 INFO - TEST-INFO | 5772: exit 0 01:46:10 INFO - Browser initialized. 01:46:10 INFO - Running cycle 1/1 for kraken test... 01:46:10 INFO - TEST-INFO | started process 5853 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwXG0mT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:46:22 INFO - PROCESS | 5853 | RSS: Main: 154533888 01:46:22 INFO - PROCESS | 5853 | 01:46:25 INFO - PROCESS | 5853 | Cycle 1(1): loaded http://localhost:37398/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37398/tests/kraken/driver.html?testName=audio-beat-detection) 01:46:26 INFO - PROCESS | 5853 | RSS: Main: 209248256 01:46:26 INFO - PROCESS | 5853 | 01:46:29 INFO - PROCESS | 5853 | Cycle 1(1): loaded http://localhost:37398/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37398/tests/kraken/driver.html?testName=audio-dft) 01:46:29 INFO - PROCESS | 5853 | RSS: Main: 195407872 01:46:29 INFO - PROCESS | 5853 | 01:46:33 INFO - PROCESS | 5853 | Cycle 1(1): loaded http://localhost:37398/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37398/tests/kraken/driver.html?testName=audio-fft) 01:46:33 INFO - PROCESS | 5853 | RSS: Main: 265547776 01:46:33 INFO - PROCESS | 5853 | 01:46:35 INFO - PROCESS | 5853 | Cycle 1(1): loaded http://localhost:37398/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37398/tests/kraken/driver.html?testName=audio-oscillator) 01:46:36 INFO - PROCESS | 5853 | RSS: Main: 174641152 01:46:36 INFO - PROCESS | 5853 | 01:46:38 INFO - PROCESS | 5853 | Cycle 1(1): loaded http://localhost:37398/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37398/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:46:39 INFO - PROCESS | 5853 | RSS: Main: 178102272 01:46:39 INFO - PROCESS | 5853 | 01:46:42 INFO - PROCESS | 5853 | Cycle 1(1): loaded http://localhost:37398/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37398/tests/kraken/driver.html?testName=imaging-darkroom) 01:46:43 INFO - PROCESS | 5853 | RSS: Main: 214683648 01:46:43 INFO - PROCESS | 5853 | 01:46:46 INFO - PROCESS | 5853 | Cycle 1(1): loaded http://localhost:37398/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37398/tests/kraken/driver.html?testName=imaging-desaturate) 01:46:47 INFO - PROCESS | 5853 | RSS: Main: 215912448 01:46:47 INFO - PROCESS | 5853 | 01:46:50 INFO - PROCESS | 5853 | Cycle 1(1): loaded http://localhost:37398/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37398/tests/kraken/driver.html?testName=json-parse-financial) 01:46:50 INFO - PROCESS | 5853 | RSS: Main: 216256512 01:46:50 INFO - PROCESS | 5853 | 01:46:52 INFO - PROCESS | 5853 | Cycle 1(1): loaded http://localhost:37398/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37398/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:46:52 INFO - PROCESS | 5853 | RSS: Main: 202125312 01:46:52 INFO - PROCESS | 5853 | 01:46:54 INFO - PROCESS | 5853 | Cycle 1(1): loaded http://localhost:37398/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37398/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:46:55 INFO - PROCESS | 5853 | RSS: Main: 205172736 01:46:55 INFO - PROCESS | 5853 | 01:46:57 INFO - PROCESS | 5853 | Cycle 1(1): loaded http://localhost:37398/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37398/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:46:58 INFO - PROCESS | 5853 | RSS: Main: 217968640 01:46:58 INFO - PROCESS | 5853 | 01:47:00 INFO - PROCESS | 5853 | Cycle 1(1): loaded http://localhost:37398/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37398/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:47:01 INFO - PROCESS | 5853 | RSS: Main: 223916032 01:47:01 INFO - PROCESS | 5853 | 01:47:04 INFO - PROCESS | 5853 | Cycle 1(1): loaded http://localhost:37398/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37398/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:47:05 INFO - PROCESS | 5853 | RSS: Main: 217767936 01:47:05 INFO - PROCESS | 5853 | 01:47:06 INFO - PROCESS | 5853 | Cycle 1(1): loaded http://localhost:37398/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37398/tests/kraken/driver.html?testName=ai-astar) 01:47:07 INFO - PROCESS | 5853 | RSS: Main: 281186304 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | __start_tp_report 01:47:07 INFO - PROCESS | 5853 | _x_x_mozilla_page_load 01:47:07 INFO - PROCESS | 5853 | _x_x_mozilla_page_load_details 01:47:07 INFO - PROCESS | 5853 | |i|pagename|runs| 01:47:07 INFO - PROCESS | 5853 | |0;ai-astar;113;113;108;111;111;112;100;111;111;112 01:47:07 INFO - PROCESS | 5853 | |1;audio-beat-detection;158;132;158;188;132;154;157;141;193;133 01:47:07 INFO - PROCESS | 5853 | |2;audio-dft;161;162;162;164;166;164;162;161;161;159 01:47:07 INFO - PROCESS | 5853 | |3;audio-fft;73;74;108;121;77;74;74;132;99;75 01:47:07 INFO - PROCESS | 5853 | |4;audio-oscillator;95;95;141;94;93;94;153;94;94;149 01:47:07 INFO - PROCESS | 5853 | |5;imaging-gaussian-blur;117;116;117;117;117;116;117;117;117;117 01:47:07 INFO - PROCESS | 5853 | |6;imaging-darkroom;146;147;147;145;145;147;146;145;146;146 01:47:07 INFO - PROCESS | 5853 | |7;imaging-desaturate;95;94;95;95;95;98;95;94;95;94 01:47:07 INFO - PROCESS | 5853 | |8;json-parse-financial;66;64;65;64;65;65;66;83;63;65 01:47:07 INFO - PROCESS | 5853 | |9;json-stringify-tinderbox;64;65;64;64;65;64;65;64;65;65 01:47:07 INFO - PROCESS | 5853 | |10;stanford-crypto-aes;78;86;77;78;88;80;80;79;79;79 01:47:07 INFO - PROCESS | 5853 | |11;stanford-crypto-ccm;143;132;130;130;132;129;132;136;176;142 01:47:07 INFO - PROCESS | 5853 | |12;stanford-crypto-pbkdf2;175;175;172;177;172;172;182;175;174;173 01:47:07 INFO - PROCESS | 5853 | |13;stanford-crypto-sha256-iterative;75;73;72;74;72;73;72;75;74;72 01:47:07 INFO - PROCESS | 5853 | __end_tp_report 01:47:07 INFO - PROCESS | 5853 | __start_cc_report 01:47:07 INFO - PROCESS | 5853 | _x_x_mozilla_cycle_collect,1869 01:47:07 INFO - PROCESS | 5853 | __end_cc_report 01:47:07 INFO - PROCESS | 5853 | __startTimestamp1461142027223__endTimestamp 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | ------- Summary: start ------- 01:47:07 INFO - PROCESS | 5853 | Number of tests: 14 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | [#0] ai-astar Cycles:10 Average:110.20 Median:111.50 stddev:3.85 (3.5%) stddev-sans-first:3.95 01:47:07 INFO - PROCESS | 5853 | Values: 113.0 113.0 108.0 111.0 111.0 112.0 100.0 111.0 111.0 112.0 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | [#1] audio-beat-detection Cycles:10 Average:154.60 Median:157.50 stddev:21.84 (13.9%) stddev-sans-first:23.13 01:47:07 INFO - PROCESS | 5853 | Values: 158.0 132.0 158.0 188.0 132.0 154.0 157.0 141.0 193.0 133.0 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.00 stddev:1.99 (1.2%) stddev-sans-first:2.06 01:47:07 INFO - PROCESS | 5853 | Values: 161.0 162.0 162.0 164.0 166.0 164.0 162.0 161.0 161.0 159.0 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | [#3] audio-fft Cycles:10 Average:90.70 Median:74.00 stddev:22.55 (30.5%) stddev-sans-first:22.99 01:47:07 INFO - PROCESS | 5853 | Values: 73.0 74.0 108.0 121.0 77.0 74.0 74.0 132.0 99.0 75.0 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | [#4] audio-oscillator Cycles:10 Average:110.20 Median:94.00 stddev:26.02 (27.7%) stddev-sans-first:27.01 01:47:07 INFO - PROCESS | 5853 | Values: 95.0 95.0 141.0 94.0 93.0 94.0 153.0 94.0 94.0 149.0 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 01:47:07 INFO - PROCESS | 5853 | Values: 117.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 01:47:07 INFO - PROCESS | 5853 | Values: 146.0 147.0 147.0 145.0 145.0 147.0 146.0 145.0 146.0 146.0 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:1.15 (1.2%) stddev-sans-first:1.22 01:47:07 INFO - PROCESS | 5853 | Values: 95.0 94.0 95.0 95.0 95.0 98.0 95.0 94.0 95.0 94.0 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | [#8] json-parse-financial Cycles:10 Average:66.60 Median:65.00 stddev:5.83 (9.0%) stddev-sans-first:6.18 01:47:07 INFO - PROCESS | 5853 | Values: 66.0 64.0 65.0 64.0 65.0 65.0 66.0 83.0 63.0 65.0 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:65.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 01:47:07 INFO - PROCESS | 5853 | Values: 64.0 65.0 64.0 64.0 65.0 64.0 65.0 64.0 65.0 65.0 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:79.50 stddev:3.63 (4.6%) stddev-sans-first:3.74 01:47:07 INFO - PROCESS | 5853 | Values: 78.0 86.0 77.0 78.0 88.0 80.0 80.0 79.0 79.0 79.0 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | [#11] stanford-crypto-ccm Cycles:10 Average:138.20 Median:134.00 stddev:14.16 (10.6%) stddev-sans-first:14.92 01:47:07 INFO - PROCESS | 5853 | Values: 143.0 132.0 130.0 130.0 132.0 129.0 132.0 136.0 176.0 142.0 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:175.00 stddev:3.06 (1.7%) stddev-sans-first:3.24 01:47:07 INFO - PROCESS | 5853 | Values: 175.0 175.0 172.0 177.0 172.0 172.0 182.0 175.0 174.0 173.0 01:47:07 INFO - PROCESS | 5853 | 01:47:07 INFO - PROCESS | 5853 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:73.50 stddev:1.23 (1.7%) stddev-sans-first:1.12 01:47:07 INFO - PROCESS | 5853 | Values: 75.0 73.0 72.0 74.0 72.0 73.0 72.0 75.0 74.0 72.0 01:47:07 INFO - PROCESS | 5853 | -------- Summary: end -------- 01:47:07 INFO - PROCESS | 5853 | 01:47:08 INFO - TEST-INFO | 5853: exit 0 01:47:08 INFO - TEST-OK | kraken | took 62795ms 01:47:08 INFO - SUITE-END | took 1180s 01:47:08 INFO - Completed test suite (00:19:40) 01:47:08 INFO - javascript benchmark 01:47:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2567.525007873543, 2643.207037888634, 2650.5626571615685, 2663.6404085448457, 2678.9598156027664, 4474.0, 4522.186006518826, 4546.909061818762, 4548.977255113724, 4562.04197118605, 4467.240569103252, 4599.77001149942, 4668.463126740426, 4681.508441613632, 4690.835820746274, 4215.346621273708, 4233.661307095425, 4266.040140968273, 4282.507511636155, 4340.08858139791, 4280.764557949312, 4286.271333873249, 4297.183535128315, 4297.957020429787, 4324.07032488015, 4087.9182416351805, 4270.572942705736, 4302.032042790378, 4331.566843315674, 4356.80394382252, 4199.895002624929, 4204.453421055274, 4221.6200541951375, 4238.131183107486, 4277.165952639237, 4218.261804184255, 4233.25917964355, 4233.576642335773, 4274.551172126913, 4281.850135245282, 4358.825646974117, 4496.437945256843, 4496.977515112436, 4510.526394728572, 4541.772911354419, 4874.805007799684, 4919.631027672939, 4924.4583095859425, 4966.230234313651, 4973.726445045521, 4907.533784290486, 4912.557869791736, 4922.212446008622, 4931.753412329369, 4960.603151747851, 4437.42313499243, 4603.861884143447, 4607.539246075433, 4607.884802879955, 4647.326137710013, 4987.925181122286, 5031.295618613397, 5058.494150584912, 5066.113430149709, 5112.335396398445, 4827.058723548875, 4828.830990915301, 4832.44426890912, 4834.613230941516, 4884.902301953941, 4739.241721324572, 4880.829170978998, 4914.017196560702, 4955.207166853358, 4974.452810190876, 4774.045190961821, 4791.0, 4793.424789025339, 4839.879003024953, 4852.951470485321, 4771.6898401603785, 4776.402949631296, 4777.187878060738, 4802.039592081597, 4820.855374338776, 4043.8584649537124, 4107.096438783463, 4131.628153466202, 4151.7924103794685, 4198.265303571863, 3660.633936606318, 3670.082479380155, 3682.3371793077504, 3700.500432441586, 3704.129529560551, 4237.216115018731, 4251.0, 4256.659467242675, 4258.169656916872, 4298.914021719611, 4715.080559290974, 4851.781669824866, 4914.606831453475, 4959.801607935714, 4965.081459929925, 4250.36244563318, 4258.361245813091, 4265.1043280910735, 4283.764392958417, 4325.632321252666, 4465.553444655573, 4493.28107502798, 4498.775061246924, 4542.704724192917, 4545.159145558082, 4540.341650460665, 4703.506131856243, 4717.3159891816185, 4741.881452963704, 4764.190087685032, 4861.343718598086, 4862.246351815475, 4865.683730557503, 4867.634927380532, 4897.608191344612, 4484.798184081789, 4619.653525985632, 4628.305754136906, 4635.096156249499, 4672.7897244624755, 4856.320115183948, 4900.779464924016, 4909.582685471775, 4948.307236986752, 4954.504549545017, 4900.852974410774, 4939.259111133359, 4939.876503087452, 4942.28336891156, 4988.925166122439, 4619.260918253063, 4761.3810204674555, 4798.47216806158, 4809.062232864558, 4857.975710121426, 4705.717656940595, 4708.788104535372, 4729.266963620645, 4769.785359658755, 4778.546038126373, 4665.6034237090225, 4676.064787042673, 4684.765761711833, 4688.437387513521, 4730.0539892022425, 4631.073785243032, 4781.665283430127, 4782.521747825329, 4827.8551643450755, 4828.37231159947, 4745.881352966204, 4763.428388593391, 4781.0, 4783.95216047835, 4836.105320515716], "value": 4515.561923151446, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.4864247855692, 607.2591438445097, 609.9847503812401, 627.570114471587, 636.1253276744476, 26812.59781103285, 33575.32849343012, 34365.48451773222, 34419.827900860524, 34477.31045379091, 37496.25007499848, 38324.80837595815, 38347.80826095873, 38358.0, 38375.616243837554, 7319.451041171907, 7602.923970760291, 7810.297073263405, 9494.952525237399, 9767.36512126713, 9595.568199431042, 9728.902710972905, 9734.367266127714, 9738.415695058284, 9739.415635061883, 9679.032096790299, 9763.804723905518, 9767.951160244189, 9781.462019588955, 9781.902180978206, 7515.32362087412, 9825.312228144032, 10170.237232207613, 10195.79608407835, 10199.18406527476, 9604.711858644181, 9653.17947974416, 9701.417914925127, 9726.465044422624, 9758.804823903482, 33902.32195356104, 34567.65432345695, 34767.130821729406, 34819.65180348215, 34983.0, 3058.8623511942014, 3121.5317702344605, 3285.737141028712, 3729.813509324523, 3841.519810023747, 28608.856955715295, 28696.426071478454, 28703.71296287031, 28719.85640071786, 28944.276393090342, 3326.7172290355347, 3366.3099064691924, 3393.7285017198565, 3399.2521645238007, 3403.5235067090625, 226492.0, 226498.0, 226835.8658206715, 227290.0, 227641.0, 52164.739176303876, 52358.0, 52557.737211313695, 52583.73708131435, 52650.0, 55892.72053639706, 56061.0, 60468.0, 62610.68694656497, 62740.68629656914, 12591.496340146476, 13239.801402978963, 13461.461541538425, 13559.322033898266, 13582.59252222435, 9329.253659707207, 9414.340996130206, 9421.0, 9551.570179341947, 9685.6610018649, 17610.735838962424, 17753.7336939946, 17771.489423399154, 17786.644267114843, 17794.110294485225, 6692.799216023527, 6876.724931002704, 6948.131483564554, 6957.304269573002, 7023.508354415142, 846.8052347960004, 868.9826203475895, 893.7710647859194, 951.900050494702, 974.6734843827255, 27471.17586472369, 27879.721202787714, 28077.22285508709, 28338.433231335257, 28548.714512855022, 991.5240684471494, 1014.6144465103214, 1051.558345494894, 1070.5717712914895, 1072.855164552791, 14523.927380363242, 15094.924525377302, 15113.54659360221, 15115.470958516398, 15322.540323790305, 335798.0, 342390.0, 343188.0, 344950.78016808373, 345101.0, 47675.0, 49167.754161228964, 49184.75407623011, 49251.26123108156, 49296.75351623219, 1106.8450416941635, 1134.7787181499693, 1223.9449224784905, 1246.4204145072586, 1247.6943148928572, 24666.63000054965, 25372.238832835046, 25570.488590228462, 26053.609195862457, 26164.0, 38594.2281154381, 39256.60743392644, 39280.0, 39321.41017884677, 39389.0, 221172.0, 225956.0, 226129.0, 226270.0, 227119.86440067695, 23397.298081057597, 24232.030718771053, 24245.51508969846, 24258.272251832474, 24587.50824983526, 35490.46764298486, 35607.465888012215, 35618.287634246655, 36003.0, 36415.27169456543, 30064.64062710851, 30675.693243067282, 30750.384992300475, 31340.686593134687, 31657.525137123423, 30989.380212395175, 31032.22419439532, 31158.68841311558, 31180.220494487818, 31399.372012559164, 31608.209794755316, 33697.494537582446, 34125.31749365049, 34137.146571335914, 36187.81906090453, 27131.1860644181, 27468.0, 28449.715502845535, 28880.85559572189, 29039.56440653434, 23931.52136957286, 24237.757622424255, 24452.388690282885, 24575.0, 24787.25638230856], "value": 17104.42333711831, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.99246015079683, 380.501542978698, 385.3026022898552, 386.86653104678885, 388.11703374822275, 16100.114493702842, 16446.259918303676, 17392.565185870328, 17407.0, 17432.738508922346, 11974.7605047899, 15057.17185554794, 15826.683466330695, 15826.76259856103, 15840.128792916385, 4537.841175558848, 4679.087577922306, 4727.574518293353, 4727.645426593002, 4734.739589322586, 3434.931301373971, 5119.206522988924, 5417.943501017303, 5446.455354464541, 5453.945460545384, 5429.131338985763, 5434.402215756264, 5441.319835020622, 5445.156000819879, 5466.125419932811, 5071.797128114871, 5384.165454354581, 5394.05604019297, 5394.3796463406425, 5397.892042159155, 3349.949750753741, 4832.202686556714, 5172.19830926207, 5176.197689358156, 5199.766010529535, 11674.94162529182, 11721.648350549412, 11739.823902641467, 11804.881951180465, 12504.49982000719, 1849.8982555959417, 1925.1336898395778, 1935.2936178294963, 1986.4437957371958, 2432.1852179519706, 9225.769355766093, 9483.66807161746, 9553.187979021792, 9604.231661467065, 10072.748181295454, 1626.8617167540774, 1752.2465339903836, 1789.7225929980873, 1798.739182818484, 1838.3290099113726, 63655.36344636541, 66845.33154668439, 67176.32823671798, 67320.0, 67492.6625366875, 9670.419774813534, 9742.756431089138, 9850.950745246228, 9922.36281013639, 10071.34536255141, 13504.189748615114, 13565.59303220905, 13601.659958501117, 13613.387397567134, 13630.522931697344, 7074.398676112588, 7142.750003749844, 7152.24901385347, 7224.422046236394, 7292.124945006527, 6829.829254268683, 6845.726170953206, 6872.862542749217, 6939.965300173568, 6959.721611135599, 4179.895502612399, 4207.221663992172, 4236.661067114624, 4238.6397156242065, 4299.914001720011, 1705.0, 1723.448496481114, 1761.8414342709218, 1764.5765016396228, 1771.477414162825, 832.134580036767, 877.7322916510398, 911.4485736129678, 955.4983633592419, 956.5886668732372, 15671.608209794846, 16657.167141643113, 16865.241064152142, 16909.0, 16943.15284235783, 861.8534849075672, 879.9428037177563, 890.1499068389672, 899.8965119011267, 958.2477754962321, 9078.273738100936, 9192.49441280736, 9315.161635452841, 9367.859482107773, 9486.335956483117, 380228.0, 388407.0, 389768.0, 391880.0, 392422.0, 76782.0, 77101.61449192718, 77716.90546702176, 78237.0, 78798.6060069696, 933.8926023507383, 939.4973689076448, 941.6892425499568, 963.0850691842777, 964.1370972979145, 14571.00039332675, 14603.634909127357, 14732.55802325932, 14855.92572037133, 14966.476173333882, 33536.155511447105, 33899.4915076279, 34062.65937340595, 34417.0, 34534.48198277078, 43901.341479878465, 46367.0, 46462.76768616135, 47162.76418617885, 48524.75737621431, 7417.777466676009, 7731.38148948094, 7845.254700803414, 7851.528908265523, 7864.331531819859, 11559.133065019989, 11589.594364197214, 11611.6516504505, 11785.646430607096, 12112.454939527566, 10032.899671003488, 10366.637167699095, 10484.947575262075, 10533.473326333806, 10806.243562950833, 10661.466926653791, 10705.518251678537, 10798.56805727762, 10813.945930270298, 10837.049331120474, 10695.786084278427, 10734.0, 10737.0, 10921.453927303763, 11113.666590002313, 7794.1118199470475, 7854.253845884632, 7927.60361981887, 8015.038195416354, 8070.636821342936, 10356.378617282988, 10535.15718742514, 10597.099246564128, 10694.091002264584, 10742.516586753458], "value": 7998.882255870826, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1125.1561329003248, 1422.2319947228498, 1460.934257958392, 1573.858352748253, 1750.9387171448993, 7290.635468226587, 7489.700411983521, 7509.962450187755, 7521.774346769592, 7524.774256772299, 7332.386874887529, 7479.5138316009425, 7488.887666685004, 7495.625218739068, 7498.512596681226, 1820.8270214329618, 1882.7270045843345, 1886.528367908023, 2137.8931053447304, 2153.149505945151, 2225.054351900436, 2302.4128847143957, 2302.5855346037706, 2303.34354708908, 2303.7350704668925, 2189.912403503866, 2211.9447013824624, 2211.966820497694, 2214.258223495131, 2240.977590224093, 1853.0271607406085, 1893.479293194369, 2187.7921597448217, 2189.3650841256017, 2191.3425972208274, 1888.688366419539, 2164.426426996847, 2220.211824802188, 2239.742429620598, 2240.8095311898346, 7662.770116896502, 7708.884366734504, 7729.265719756614, 7729.536227826348, 7733.149353571102, 4503.954960450386, 4670.95329046712, 4686.742229177394, 4696.131215725068, 4700.741459219742, 2926.0636596289196, 2960.6151200344034, 2998.7301142897027, 3063.0657649416917, 3071.6314042314843, 931.1014870649856, 936.3398803843274, 945.3760518058117, 960.3949511807587, 961.4135377419768, 10757.354558726502, 10808.081313088396, 10838.945805271002, 10885.782284354347, 10907.890921090768, 4282.8715138545895, 4305.483341998917, 4462.888427789267, 4481.417415735934, 4482.394876691605, 9135.360524763335, 9231.907680923105, 9255.722328330161, 9282.396644218104, 9286.349955503185, 4687.179743544866, 4692.225782745877, 4715.363425937523, 4715.764211789466, 4718.103560323596, 3721.6650501455, 3828.3300422426523, 4216.5572614875, 4237.63980061692, 4279.358096285582, 3739.6634302912325, 3757.323681737259, 3839.040239940015, 3856.1902000580194, 3876.6898648108086, 1216.391804097951, 1218.4638758946035, 1219.432963671897, 1234.734532075608, 1252.7306629074792, 689.9482538809609, 780.594091072639, 884.9955750221336, 908.4367692030983, 916.6699988004314, 5242.423333433319, 5381.112116500812, 5408.0, 5506.421825708323, 5593.804216852391, 852.2202185000655, 859.9613017414231, 876.6624849432886, 913.9040400757825, 949.2738055387634, 3772.9245415091546, 3778.792166430818, 3780.7920564368674, 3815.122521819997, 3869.342211823997, 9485.715428537154, 9858.507074646239, 9868.80262394748, 9880.258980576486, 9960.950195248977, 9649.855252171223, 9731.805363892683, 9808.068233517804, 9843.704688859345, 9856.802863942681, 7391.482596218213, 7403.888941665879, 7527.736529221451, 7558.811029724191, 7570.697172113164, 5196.506331898464, 5322.920156197582, 5403.270558474555, 5491.75287112073, 5525.530329922001, 8879.955600222216, 9140.223081038186, 9143.954280228556, 9230.307726920373, 9277.86083208766, 9288.767780805534, 9295.907040929775, 9296.814063718823, 9302.720918372459, 9419.199368053793, 5773.6824474654295, 6434.774782882577, 6557.803265902031, 6626.304238055128, 6635.668216589248, 7571.962140189264, 7623.4663573549315, 7629.2752188542, 7673.846523069619, 7687.500312479671, 6523.151990241238, 6620.966895165493, 6678.198616166092, 6783.253842077269, 6804.693788779616, 6785.0, 6785.830354240986, 6804.387605115563, 6871.03805467245, 6901.896471553031, 6277.843053923689, 6482.124913136666, 6499.122618446449, 6550.213974323112, 6568.277489476058, 4920.975395123001, 4930.334404855442, 4937.325142623812, 4970.552650261349, 5034.018366418513, 4731.030138821602, 4784.186688262934, 4845.5881250094135, 4933.605311574994, 4982.128127577732], "value": 4193.246462298551, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.5433558333668, 320.60725611126367, 320.9582754241948, 325.09623247372315, 327.11515350975606, 4822.855314340567, 4979.7342209294775, 4999.275105109761, 5066.240063990399, 5078.822241221554, 4791.377120974277, 4967.0, 4968.975155124219, 4974.999999999991, 4984.426790919046, 3956.188981258835, 3960.980195099035, 3961.4850069490926, 3962.445257663929, 3963.7225394222414, 3759.9624003760023, 3809.3714537101346, 3929.626685464876, 3940.7044471664594, 3960.2079584083153, 3886.7084968627323, 3891.5135608048995, 3895.6883449323986, 3898.7465814722095, 3902.7853468059247, 3862.594427585091, 3874.5737968823405, 3885.689144868432, 3909.7849618270984, 3915.6671682906704, 3479.4780782882517, 3485.5643044619424, 3488.110531814371, 3492.301539692072, 3494.1614012637037, 4581.381513495702, 4822.517748225185, 4827.710337379768, 4847.175980083359, 4850.75746212688, 4435.711678740871, 4436.489803672586, 4441.911161776747, 4442.311441726538, 4442.73343599385, 5232.096186616426, 5247.449017853147, 5256.500632439912, 5258.184981327862, 5263.6578622389425, 3624.855005799765, 3701.9634502339386, 3706.036430528055, 3716.7955762433057, 3733.010752150682, 5382.650127741685, 5394.973025134888, 5426.430224826416, 5474.835754927319, 5478.315210598676, 3761.567419746709, 3795.544534655859, 3795.582485926546, 3799.088218827517, 3803.581606023335, 3750.849966001384, 3824.827882745283, 3855.3060449118866, 3855.51806024247, 3856.382978723391, 4368.191884501377, 4383.715058521186, 4386.122775444924, 4390.385346051556, 4397.73613583186, 5207.453217412193, 5212.332053742842, 5228.0, 5235.47645235481, 5251.76367063483, 1245.483124503321, 1281.8718128187106, 1328.926909019994, 1334.8586958150836, 1338.6987927716382, 891.6121487153109, 904.3353135445442, 905.9456432614064, 916.1571354354011, 928.4986107501874, 4573.262160379101, 4573.679842410999, 4591.678582499259, 4592.954070459253, 4596.47140578831, 5318.0, 5327.440618735054, 5332.253484512172, 5333.5199832015305, 5341.545968592635, 4451.59935605797, 4550.977245113753, 4561.24739417999, 4581.74800385982, 4591.9540804591525, 2852.1871266688895, 2852.229897927548, 2860.75683566895, 2862.670792858806, 2869.6412948381453, 5054.342935418372, 5059.797608095635, 5067.543921047123, 5071.087204303187, 5074.314967479417, 5292.862034662566, 5442.102053161184, 5451.509364157245, 5469.863253418617, 5628.774849006076, 5162.070827251056, 5164.535191832678, 5173.301604283373, 5205.765740541685, 5206.8958620828125, 5464.5081942623765, 5471.753771080391, 5476.753546090516, 5489.204065410573, 5496.367917689517, 5252.44849290819, 5342.973285133549, 5352.277442545206, 5364.3830959440165, 5373.650712703662, 5126.256692779601, 5159.2003239497935, 5174.430812610534, 5174.6119041071315, 5188.558972487381, 3779.527559055118, 3823.560290566509, 3827.9425808612336, 3827.9425808612336, 3838.9424158637084, 5140.614453915897, 5249.868753281198, 5400.729963501888, 5425.810096646598, 5443.101888188405, 5104.132297509358, 5249.475052494873, 5268.78924843002, 5278.97360513195, 5281.0, 5250.212468129811, 5256.4781596329785, 5256.579473642176, 5277.021117081094, 5285.418603953639, 4922.310876477368, 5187.714675692873, 5188.922166167435, 5195.298634684269, 5198.8960220796125, 5308.25684404176, 5316.255724198538, 5319.920201196907, 5345.9465405345445, 5346.465353464622, 5195.3246078009615, 5201.843944681666, 5213.400458947118, 5219.8434046978655, 5228.294180285613], "value": 3954.7979383926418, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.0953246390126, 655.3806652713185, 673.4477728262824, 684.7432212920158, 685.2222727204622, 11355.716107097327, 11367.943160284198, 11527.596534121307, 11567.710807229823, 11568.0, 10870.510827012784, 11323.660290191307, 11549.191556591042, 11598.942005289962, 11607.767844643102, 645.4287955159671, 781.1914668318292, 814.568278812229, 821.7699044267615, 824.7072289337289, 818.7216346442209, 822.6668199379232, 822.9218224268685, 822.9670813167467, 823.2590668398459, 862.0388267082221, 862.4911302331623, 862.4911302331623, 863.6588547523725, 864.0495454999514, 686.4885660183146, 834.7328854766478, 858.3347905373323, 860.4450129666361, 865.9826803463927, 626.8558231606711, 627.4165026525329, 628.136312570216, 628.6008384675719, 629.4460874430472, 12277.0, 12503.0, 12516.123871329011, 12518.186317889307, 12540.74918501634, 12339.259644421363, 12423.44094515749, 12586.18189817659, 12629.052821038367, 12759.0, 9370.344075914689, 9376.765580860532, 9480.810383792354, 9629.90370096297, 9766.07222313879, 2866.928326791826, 2912.5339945608603, 2913.6649285332246, 2936.0, 2960.6595241547284, 43902.560974390486, 43953.0, 43954.34068488975, 44378.33432498515, 45336.54663453356, 9629.181519570915, 9669.468179250069, 9712.22302215821, 9804.607815687436, 9821.165200958, 18327.908360458114, 18412.447626571226, 18475.538111547317, 18880.81119188818, 18915.62168756617, 5762.855928601734, 5887.087501437199, 6110.938890611126, 6189.752409903554, 6226.439620434092, 3276.0663210984753, 3298.1249163572766, 3300.6534313896695, 3313.287643156733, 3318.8672453101603, 2641.616965539986, 2729.672439307296, 2731.426400455887, 2731.726827317252, 2734.6718393792476, 433.9587739164774, 500.19968051118644, 510.64765311934644, 513.2455290722631, 515.6673945305257, 558.5699011760921, 575.9683217423081, 587.090010483757, 587.632729544035, 597.8505373656586, 7285.708571657075, 7399.556026638419, 7479.70081196746, 7692.538447693044, 7883.408744344197, 477.28645674716523, 483.4995779368337, 528.6009063157292, 532.5074306266688, 535.8044313825426, 3268.379007988522, 3575.982120089383, 3584.7490675652452, 3686.723495737777, 3725.795081270502, 50867.138220191766, 51048.48951510512, 51655.7417212919, 51749.48250517446, 52021.0, 46646.30030549477, 46893.0, 47381.28928066013, 47718.0, 48010.27984580234, 43679.78160109179, 44033.0, 44357.33463998043, 44625.330620040724, 45241.54758452373, 6793.626350550767, 6940.444764418935, 7100.183478899786, 7144.142702875688, 7156.284371563011, 28791.136265912057, 28877.422451550432, 29295.267618309714, 29496.852515737282, 29574.556381654722, 35847.28305433929, 37326.440103399014, 39366.606333936295, 39497.0, 39684.00789980274, 9270.44377337362, 9314.813703726024, 9461.38500997433, 9596.856047159159, 9712.70861874145, 10086.24891815822, 10177.084062434416, 10196.449468416085, 10242.897571024492, 10312.329698569569, 9625.71122866315, 9656.32405731592, 9799.461029643438, 9834.459104749307, 9847.113759761656, 8839.867401988846, 8865.202131808168, 8894.86657700148, 8930.508822014852, 9296.163345298955, 8785.912140878509, 8808.647654093764, 8922.910770892207, 8927.955360223157, 9091.272698184262, 7694.269044440769, 8310.667573297, 8809.07504711996, 8865.335099867665, 8870.979777628432, 7670.424718146273, 7898.2891539761695, 8003.6798528058225, 8042.83914321722, 8190.549519776469], "value": 5591.16162462247, "unit": "score"}], "name": "dromaeo_css", "value": 6208.8654742477775, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 108.0, 111.0, 111.0, 112.0, 100.0, 111.0, 111.0, 112.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 132.0, 158.0, 188.0, 132.0, 154.0, 157.0, 141.0, 193.0, 133.0], "value": 154.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 164.0, 166.0, 164.0, 162.0, 161.0, 161.0, 159.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 108.0, 121.0, 77.0, 74.0, 74.0, 132.0, 99.0, 75.0], "value": 90.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 141.0, 94.0, 93.0, 94.0, 153.0, 94.0, 94.0, 149.0], "value": 110.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 145.0, 145.0, 147.0, 146.0, 145.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 98.0, 95.0, 94.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 64.0, 65.0, 65.0, 66.0, 83.0, 63.0, 65.0], "value": 66.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 64.0, 65.0, 64.0, 65.0, 64.0, 65.0, 65.0], "value": 64.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 86.0, 77.0, 78.0, 88.0, 80.0, 80.0, 79.0, 79.0, 79.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 132.0, 130.0, 130.0, 132.0, 129.0, 132.0, 136.0, 176.0, 142.0], "value": 138.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 175.0, 172.0, 177.0, 172.0, 172.0, 182.0, 175.0, 174.0, 173.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 72.0, 74.0, 72.0, 73.0, 72.0, 75.0, 74.0, 72.0], "value": 73.2, "unit": "score"}], "name": "kraken", "value": 1583.3000000000002, "alertThreshold": 2.0}]} 01:47:08 INFO - Return code: 0 01:47:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 01:47:08 ERROR - # TBPL SUCCESS # 01:47:08 INFO - Running post-action listener: _resource_record_post_action 01:47:08 INFO - Running post-run listener: _resource_record_post_run 01:47:09 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 12288; Write bytes: 517275648; Read time: 240; Write time: 432908 01:47:09 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 234512384; Read time: 188; Write time: 261672 01:47:09 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 8192; Write bytes: 282697728; Read time: 52; Write time: 171236 01:47:09 INFO - Running post-run listener: _upload_blobber_files 01:47:09 INFO - Blob upload gear active. 01:47:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:47:09 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:47:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:47:10 INFO - (blobuploader) - INFO - Open directory for files ... 01:47:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:47:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:47:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:11 INFO - (blobuploader) - INFO - Done attempting. 01:47:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:47:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:47:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:11 INFO - (blobuploader) - INFO - Done attempting. 01:47:11 INFO - (blobuploader) - INFO - Iteration through files over. 01:47:11 INFO - Return code: 0 01:47:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:47:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:47:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ae53b57d3bfeeb245a1f7edc1d69e8d7e21c044e6c618d54c59cbf2f359e5f551e2c77d9fd8201da4e5a8e4fc8d43f03c4a53bb229bf41baee6716b39caa2e1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c9b7fde12f077d3395eebbf86b1e73e42e2cad6b997c705dee6ddd0bad27d424a09965b4a3c738ba964f8faf7c70fa615d10b7398cd5aeaafa76e45a43d6dd6f"} 01:47:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:47:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:47:11 INFO - Contents: 01:47:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ae53b57d3bfeeb245a1f7edc1d69e8d7e21c044e6c618d54c59cbf2f359e5f551e2c77d9fd8201da4e5a8e4fc8d43f03c4a53bb229bf41baee6716b39caa2e1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c9b7fde12f077d3395eebbf86b1e73e42e2cad6b997c705dee6ddd0bad27d424a09965b4a3c738ba964f8faf7c70fa615d10b7398cd5aeaafa76e45a43d6dd6f"} 01:47:11 INFO - Running post-run listener: copy_logs_to_upload_dir 01:47:11 INFO - Copying logs to upload dir... 01:47:11 INFO - mkdir: /builds/slave/test/build/upload/logs 01:47:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.588383 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-04-20 01:47:11.459802) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-20 01:47:11.463502) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ae53b57d3bfeeb245a1f7edc1d69e8d7e21c044e6c618d54c59cbf2f359e5f551e2c77d9fd8201da4e5a8e4fc8d43f03c4a53bb229bf41baee6716b39caa2e1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c9b7fde12f077d3395eebbf86b1e73e42e2cad6b997c705dee6ddd0bad27d424a09965b4a3c738ba964f8faf7c70fa615d10b7398cd5aeaafa76e45a43d6dd6f"} build_url:https://queue.taskcluster.net/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/X5cPoHiFSpmCZuUGv2FY2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ae53b57d3bfeeb245a1f7edc1d69e8d7e21c044e6c618d54c59cbf2f359e5f551e2c77d9fd8201da4e5a8e4fc8d43f03c4a53bb229bf41baee6716b39caa2e1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c9b7fde12f077d3395eebbf86b1e73e42e2cad6b997c705dee6ddd0bad27d424a09965b4a3c738ba964f8faf7c70fa615d10b7398cd5aeaafa76e45a43d6dd6f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-20 01:47:11.492937) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 01:47:11.493718) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7cf709767345e0a0c5a90e72000001d4-1461140794.692066-1608736 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004388 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 01:47:11.578742) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 01:47:11.579498) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 01:47:11.580175) ========= ========= Total master_lag: 0.20 =========