builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1473245352.85 results: success (0) buildid: 20160907004009 builduid: 4e2b6e876f594fbe91551200ebdb17c0 revision: 3a0fd1bb116a3b79b6f33462a04144818b550ee9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 03:49:12.851888) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 03:49:12.852306) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 03:49:12.852601) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 03:49:12.889578) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 03:49:12.889889) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-07 03:49:12-- 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% 54.2K=0.2s 2016-09-07 03:49:13 (54.2 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.323738 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 03:49:13.228709) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 03:49:13.229103) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020711 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 03:49:13.278934) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 03:49:13.279303) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3a0fd1bb116a3b79b6f33462a04144818b550ee9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3a0fd1bb116a3b79b6f33462a04144818b550ee9 --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=edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-07 03:49:13,422 truncating revision to first 12 chars 2016-09-07 03:49:13,422 Setting DEBUG logging. 2016-09-07 03:49:13,422 attempt 1/10 2016-09-07 03:49:13,422 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3a0fd1bb116a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 03:49:13,782 unpacking tar archive at: mozilla-aurora-3a0fd1bb116a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.704272 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 03:49:13.996889) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 03:49:13.997213) ========= script_repo_revision: 3a0fd1bb116a3b79b6f33462a04144818b550ee9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 03:49:13.997607) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 03:49:13.997877) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 03:49:14.006320) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-07 03:49:14.006664) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:49:14 INFO - MultiFileLogger online at 20160907 03:49:14 in /builds/slave/test 03:49:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:49:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:49:14 INFO - {'append_to_log': False, 03:49:14 INFO - 'base_work_dir': '/builds/slave/test', 03:49:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:49:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:49:14 INFO - 'branch': 'Mozilla-Aurora', 03:49:14 INFO - 'buildbot_json_path': 'buildprops.json', 03:49:14 INFO - 'config_files': ('talos/linux_config.py',), 03:49:14 INFO - 'default_actions': ('clobber', 03:49:14 INFO - 'read-buildbot-config', 03:49:14 INFO - 'download-and-extract', 03:49:14 INFO - 'populate-webroot', 03:49:14 INFO - 'create-virtualenv', 03:49:14 INFO - 'install', 03:49:14 INFO - 'run-tests'), 03:49:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:49:14 INFO - 'download_minidump_stackwalk': True, 03:49:14 INFO - 'download_symbols': 'ondemand', 03:49:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:49:14 INFO - 'tooltool.py': '/tools/tooltool.py', 03:49:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:49:14 INFO - '/tools/misc-python/virtualenv.py')}, 03:49:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:49:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:49:14 INFO - 'installer_path': 'installer.exe', 03:49:14 INFO - 'log_level': 'info', 03:49:14 INFO - 'log_name': 'talos', 03:49:14 INFO - 'log_to_console': True, 03:49:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:49:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:49:14 INFO - 'opt_config_files': (), 03:49:14 INFO - 'pip_index': False, 03:49:14 INFO - 'sps_profile': False, 03:49:14 INFO - 'sps_profile_interval': 0, 03:49:14 INFO - 'suite': 'dromaeojs', 03:49:14 INFO - 'system_bits': '32', 03:49:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:49:14 INFO - 'title': 'talos-linux64-ix-033', 03:49:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:49:14 INFO - 'use_talos_json': True, 03:49:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:49:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:49:14 INFO - 'work_dir': 'build'} 03:49:14 INFO - [mozharness: 2016-09-07 10:49:14.234456Z] Running clobber step. 03:49:14 INFO - Running pre-action listener: _resource_record_pre_action 03:49:14 INFO - Running main action method: clobber 03:49:14 INFO - rmtree: /builds/slave/test/build 03:49:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:49:14 INFO - Running post-action listener: _resource_record_post_action 03:49:14 INFO - [mozharness: 2016-09-07 10:49:14.682438Z] Finished clobber step (success) 03:49:14 INFO - [mozharness: 2016-09-07 10:49:14.682540Z] Running read-buildbot-config step. 03:49:14 INFO - Running pre-action listener: _resource_record_pre_action 03:49:14 INFO - Running main action method: read_buildbot_config 03:49:14 INFO - Using buildbot properties: 03:49:14 INFO - { 03:49:14 INFO - "project": "", 03:49:14 INFO - "product": "firefox", 03:49:14 INFO - "script_repo_revision": "production", 03:49:14 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:49:14 INFO - "repository": "", 03:49:14 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:49:14 INFO - "buildid": "20160907004009", 03:49:14 INFO - "stage_platform": "linux64-pgo", 03:49:14 INFO - "basedir": "/builds/slave/test", 03:49:14 INFO - "nightly_build": "True", 03:49:14 INFO - "buildnumber": 507, 03:49:14 INFO - "slavename": "talos-linux64-ix-033", 03:49:14 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:49:14 INFO - "platform": "ubuntu64_hw", 03:49:14 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:49:14 INFO - "branch": "mozilla-aurora", 03:49:14 INFO - "repo_path": "releases/mozilla-aurora", 03:49:14 INFO - "revision": "3a0fd1bb116a3b79b6f33462a04144818b550ee9", 03:49:14 INFO - "pgo_build": "True", 03:49:14 INFO - "builduid": "4e2b6e876f594fbe91551200ebdb17c0", 03:49:14 INFO - "slavebuilddir": "test" 03:49:14 INFO - } 03:49:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:49:14 INFO - Running post-action listener: _resource_record_post_action 03:49:14 INFO - [mozharness: 2016-09-07 10:49:14.685091Z] Finished read-buildbot-config step (success) 03:49:14 INFO - [mozharness: 2016-09-07 10:49:14.685181Z] Running download-and-extract step. 03:49:14 INFO - Running pre-action listener: _resource_record_pre_action 03:49:14 INFO - Running main action method: download_and_extract 03:49:14 INFO - mkdir: /builds/slave/test/build/tests 03:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:14 INFO - https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:49:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:49:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:49:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:49:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:49:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:49:14 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:49:14 INFO - retry: Failed, sleeping 30 seconds before retrying 03:49:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 03:49:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:49:44 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:49:44 INFO - retry: Failed, sleeping 60 seconds before retrying 03:50:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 03:50:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:50:44 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:50:44 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 03:50:44 INFO - Caught exception: HTTP Error 404: Not Found 03:50:44 INFO - Caught exception: HTTP Error 404: Not Found 03:50:44 INFO - Caught exception: HTTP Error 404: Not Found 03:50:44 INFO - trying https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:50:44 INFO - Downloading https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:50:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:50:45 INFO - Downloaded 1361 bytes. 03:50:45 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:50:45 INFO - Using the following test package requirements: 03:50:45 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:50:45 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:45 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:50:45 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:45 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:50:45 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:45 INFO - u'jsshell-linux-x86_64.zip'], 03:50:45 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:45 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:50:45 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:50:45 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:45 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:50:45 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:45 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:50:45 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:45 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:50:45 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:45 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:50:45 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:50:45 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')]} 03:50:45 INFO - https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:50:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:50:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:50:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:50:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:50:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:50:45 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:50:45 INFO - retry: Failed, sleeping 30 seconds before retrying 03:51:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:51:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:51:15 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:51:15 INFO - retry: Failed, sleeping 60 seconds before retrying 03:52:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:52:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:52:15 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:52:15 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:52:15 INFO - Caught exception: HTTP Error 404: Not Found 03:52:15 INFO - Caught exception: HTTP Error 404: Not Found 03:52:15 INFO - Caught exception: HTTP Error 404: Not Found 03:52:15 INFO - trying https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:52:15 INFO - Downloading https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:52:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:52:16 INFO - Downloaded 21768581 bytes. 03:52:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:52:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:52:17 INFO - Return code: 0 03:52:17 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:52:17 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')]} 03:52:17 INFO - https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:52:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:52:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:52:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:52:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:52:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:52:17 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:52:17 INFO - retry: Failed, sleeping 30 seconds before retrying 03:52:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:52:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:52:47 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:52:47 INFO - retry: Failed, sleeping 60 seconds before retrying 03:53:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:53:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:53:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:53:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:53:47 INFO - Caught exception: HTTP Error 404: Not Found 03:53:47 INFO - Caught exception: HTTP Error 404: Not Found 03:53:47 INFO - Caught exception: HTTP Error 404: Not Found 03:53:47 INFO - trying https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:53:47 INFO - Downloading https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:53:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:53:48 INFO - Downloaded 21768581 bytes. 03:53:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:53:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:53:49 INFO - Return code: 0 03:53:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:53:49 INFO - https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:53:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:53:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:53:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:53:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:53:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:53:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:53:49 INFO - retry: Failed, sleeping 30 seconds before retrying 03:54:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 03:54:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:54:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:54:19 INFO - retry: Failed, sleeping 60 seconds before retrying 03:55:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 03:55:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:55:19 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:55:19 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 03:55:19 INFO - Caught exception: HTTP Error 404: Not Found 03:55:19 INFO - Caught exception: HTTP Error 404: Not Found 03:55:19 INFO - Caught exception: HTTP Error 404: Not Found 03:55:19 INFO - trying https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:55:19 INFO - Downloading https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:55:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:55:20 INFO - Downloaded 14413740 bytes. 03:55:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:55:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:55:20 INFO - Return code: 0 03:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:55:20 INFO - https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:55:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:55:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:55:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:55:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:55:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:55:20 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:55:20 INFO - retry: Failed, sleeping 30 seconds before retrying 03:55:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 03:55:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:55:50 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:55:50 INFO - retry: Failed, sleeping 60 seconds before retrying 03:56:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 03:56:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:56:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:56:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 03:56:51 INFO - Caught exception: HTTP Error 404: Not Found 03:56:51 INFO - Caught exception: HTTP Error 404: Not Found 03:56:51 INFO - Caught exception: HTTP Error 404: Not Found 03:56:51 INFO - trying https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:56:51 INFO - Downloading https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:56:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:56:52 INFO - Downloaded 63871392 bytes. 03:56:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:56:52 INFO - mkdir: /builds/slave/test/properties 03:56:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:56:52 INFO - Writing to file /builds/slave/test/properties/build_url 03:56:52 INFO - Contents: 03:56:52 INFO - build_url:https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:56:52 INFO - Running post-action listener: _resource_record_post_action 03:56:52 INFO - Running post-action listener: set_extra_try_arguments 03:56:52 INFO - [mozharness: 2016-09-07 10:56:52.808858Z] Finished download-and-extract step (success) 03:56:52 INFO - [mozharness: 2016-09-07 10:56:52.809094Z] Running populate-webroot step. 03:56:52 INFO - Running pre-action listener: _resource_record_pre_action 03:56:52 INFO - Running main action method: populate_webroot 03:56:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:56:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:56:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:56:52 INFO - '--tppagecycles', 03:56:52 INFO - '7'], 03:56:52 INFO - 'tests': ['tsvgm']}}, 03:56:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:56:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:56:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:56:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:56:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:56:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:56:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:52 INFO - 'talos_options': ['--disable-e10s'], 03:56:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:56:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:56:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:52 INFO - 'talos_options': ['--disable-e10s'], 03:56:52 INFO - 'tests': ['damp', 'tps']}, 03:56:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:52 INFO - 'tests': ['damp', 'tps']}, 03:56:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:56:52 INFO - 'tests': ['dromaeo_dom']}, 03:56:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:56:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:56:52 INFO - 'tests': ['basic_compositor_video']}, 03:56:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:56:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:56:52 INFO - 'tests': ['a11yr', 03:56:52 INFO - 'ts_paint', 03:56:52 INFO - 'tpaint', 03:56:52 INFO - 'sessionrestore', 03:56:52 INFO - 'sessionrestore_no_auto_restore', 03:56:52 INFO - 'tabpaint']}, 03:56:52 INFO - 'other-e10s': {'tests': ['a11yr', 03:56:52 INFO - 'ts_paint', 03:56:52 INFO - 'tpaint', 03:56:52 INFO - 'sessionrestore', 03:56:52 INFO - 'sessionrestore_no_auto_restore', 03:56:52 INFO - 'tabpaint']}, 03:56:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:56:52 INFO - 'ts_paint', 03:56:52 INFO - 'tpaint', 03:56:52 INFO - 'sessionrestore', 03:56:52 INFO - 'sessionrestore_no_auto_restore', 03:56:52 INFO - 'tabpaint']}, 03:56:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:56:52 INFO - 'ts_paint', 03:56:52 INFO - 'tpaint', 03:56:52 INFO - 'sessionrestore', 03:56:52 INFO - 'sessionrestore_no_auto_restore', 03:56:52 INFO - 'tabpaint']}, 03:56:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:56:52 INFO - 'tests': ['a11yr', 03:56:52 INFO - 'ts_paint', 03:56:52 INFO - 'tpaint', 03:56:52 INFO - 'sessionrestore', 03:56:52 INFO - 'sessionrestore_no_auto_restore', 03:56:52 INFO - 'tabpaint']}, 03:56:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:56:52 INFO - 'tests': ['a11yr', 03:56:52 INFO - 'ts_paint', 03:56:52 INFO - 'tpaint', 03:56:52 INFO - 'sessionrestore', 03:56:52 INFO - 'sessionrestore_no_auto_restore', 03:56:52 INFO - 'tabpaint']}, 03:56:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:56:52 INFO - 'tests': ['tsvgx', 03:56:52 INFO - 'tsvgr_opacity', 03:56:52 INFO - 'tart', 03:56:52 INFO - 'tscrollx', 03:56:52 INFO - 'cart']}, 03:56:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:56:52 INFO - 'tsvgr_opacity', 03:56:52 INFO - 'tart', 03:56:52 INFO - 'tscrollx', 03:56:52 INFO - 'cart']}, 03:56:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:52 INFO - 'talos_options': ['--disable-e10s'], 03:56:52 INFO - 'tests': ['tp5o']}, 03:56:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:52 INFO - 'tests': ['tp5o']}, 03:56:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:52 INFO - 'talos_options': ['--disable-e10s', 03:56:52 INFO - '--xperf_path', 03:56:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:56:52 INFO - 'tests': ['tp5n']}, 03:56:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:52 INFO - 'talos_options': ['--xperf_path', 03:56:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:56:52 INFO - 'tests': ['tp5n']}}, 03:56:52 INFO - 'talos.zip': {'path': '', 03:56:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:56:52 INFO - Running post-action listener: _resource_record_post_action 03:56:52 INFO - [mozharness: 2016-09-07 10:56:52.825291Z] Finished populate-webroot step (success) 03:56:52 INFO - [mozharness: 2016-09-07 10:56:52.825397Z] Running create-virtualenv step. 03:56:52 INFO - Running pre-action listener: _resource_record_pre_action 03:56:52 INFO - Running main action method: create_virtualenv 03:56:52 INFO - Creating virtualenv /builds/slave/test/build/venv 03:56:52 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 03:56:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:56:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:56:52 INFO - Using real prefix '/usr' 03:56:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:56:53 INFO - Installing distribute.............................................................................................................................................................................................done. 03:56:55 INFO - Installing pip.................done. 03:56:55 INFO - Return code: 0 03:56:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:56:55 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')]} 03:56:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:55 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')]} 03:56:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:55 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 0x253b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fa3b0>, '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 0x27fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', '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 03:56:55 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 03:56:55 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 03:56:55 INFO - Using env: {'DISPLAY': ':0', 03:56:55 INFO - 'HOME': '/home/cltbld', 03:56:55 INFO - 'LANG': 'en_US.UTF-8', 03:56:55 INFO - 'LANGUAGE': 'en_US:en', 03:56:55 INFO - 'LOGNAME': 'cltbld', 03:56:55 INFO - 'MAIL': '/var/mail/cltbld', 03:56:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:55 INFO - 'MOZ_NO_REMOTE': '1', 03:56:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:55 INFO - 'NO_EM_RESTART': '1', 03:56:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:55 INFO - 'PWD': '/builds/slave/test', 03:56:55 INFO - 'SHELL': '/bin/bash', 03:56:55 INFO - 'SHLVL': '1', 03:56:55 INFO - 'TERM': 'linux', 03:56:55 INFO - 'TMOUT': '86400', 03:56:55 INFO - 'USER': 'cltbld', 03:56:55 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', 03:56:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:55 INFO - '_': '/tools/buildbot/bin/python'} 03:56:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:55 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 03:56:55 INFO - Cleaning up... 03:56:55 INFO - Return code: 0 03:56:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:56:55 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')]} 03:56:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:55 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')]} 03:56:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:55 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fa3b0>, '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 0x27fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', '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 03:56:55 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>=3.1.1'] in /builds/slave/test/build 03:56:55 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>=3.1.1 03:56:55 INFO - Using env: {'DISPLAY': ':0', 03:56:55 INFO - 'HOME': '/home/cltbld', 03:56:55 INFO - 'LANG': 'en_US.UTF-8', 03:56:55 INFO - 'LANGUAGE': 'en_US:en', 03:56:55 INFO - 'LOGNAME': 'cltbld', 03:56:55 INFO - 'MAIL': '/var/mail/cltbld', 03:56:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:55 INFO - 'MOZ_NO_REMOTE': '1', 03:56:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:55 INFO - 'NO_EM_RESTART': '1', 03:56:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:55 INFO - 'PWD': '/builds/slave/test', 03:56:55 INFO - 'SHELL': '/bin/bash', 03:56:55 INFO - 'SHLVL': '1', 03:56:55 INFO - 'TERM': 'linux', 03:56:55 INFO - 'TMOUT': '86400', 03:56:55 INFO - 'USER': 'cltbld', 03:56:55 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', 03:56:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:55 INFO - '_': '/tools/buildbot/bin/python'} 03:56:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:55 INFO - Downloading/unpacking psutil>=3.1.1 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:56:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:56:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:56:57 INFO - Installing collected packages: psutil 03:56:57 INFO - Running setup.py install for psutil 03:56:57 INFO - building 'psutil._psutil_linux' extension 03:56:57 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 03:56:58 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 03:56:58 INFO - building 'psutil._psutil_posix' extension 03:56:58 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 03:56:58 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 03:56:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:56:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:56:58 INFO - Successfully installed psutil 03:56:58 INFO - Cleaning up... 03:56:58 INFO - Return code: 0 03:56:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:56:58 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')]} 03:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:58 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')]} 03:56:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:58 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fa3b0>, '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 0x27fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', '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 03:56:58 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.3'] in /builds/slave/test/build 03:56:58 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.3 03:56:58 INFO - Using env: {'DISPLAY': ':0', 03:56:58 INFO - 'HOME': '/home/cltbld', 03:56:58 INFO - 'LANG': 'en_US.UTF-8', 03:56:58 INFO - 'LANGUAGE': 'en_US:en', 03:56:58 INFO - 'LOGNAME': 'cltbld', 03:56:58 INFO - 'MAIL': '/var/mail/cltbld', 03:56:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:58 INFO - 'MOZ_NO_REMOTE': '1', 03:56:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:58 INFO - 'NO_EM_RESTART': '1', 03:56:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:58 INFO - 'PWD': '/builds/slave/test', 03:56:58 INFO - 'SHELL': '/bin/bash', 03:56:58 INFO - 'SHLVL': '1', 03:56:58 INFO - 'TERM': 'linux', 03:56:58 INFO - 'TMOUT': '86400', 03:56:58 INFO - 'USER': 'cltbld', 03:56:58 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', 03:56:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:58 INFO - '_': '/tools/buildbot/bin/python'} 03:56:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:56:58 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 03:56:58 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 03:56:58 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 03:56:58 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 03:56:58 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 03:56:58 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 03:57:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:57:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:57:00 INFO - Installing collected packages: mozsystemmonitor 03:57:00 INFO - Running setup.py install for mozsystemmonitor 03:57:00 INFO - Successfully installed mozsystemmonitor 03:57:00 INFO - Cleaning up... 03:57:00 INFO - Return code: 0 03:57:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:00 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fa3b0>, '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 0x27fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', '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 03:57:00 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==2.5.1'] in /builds/slave/test/build 03:57:00 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==2.5.1 03:57:00 INFO - Using env: {'DISPLAY': ':0', 03:57:00 INFO - 'HOME': '/home/cltbld', 03:57:00 INFO - 'LANG': 'en_US.UTF-8', 03:57:00 INFO - 'LANGUAGE': 'en_US:en', 03:57:00 INFO - 'LOGNAME': 'cltbld', 03:57:00 INFO - 'MAIL': '/var/mail/cltbld', 03:57:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:00 INFO - 'MOZ_NO_REMOTE': '1', 03:57:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:57:00 INFO - 'NO_EM_RESTART': '1', 03:57:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:57:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:00 INFO - 'PWD': '/builds/slave/test', 03:57:00 INFO - 'SHELL': '/bin/bash', 03:57:00 INFO - 'SHLVL': '1', 03:57:00 INFO - 'TERM': 'linux', 03:57:00 INFO - 'TMOUT': '86400', 03:57:00 INFO - 'USER': 'cltbld', 03:57:00 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', 03:57:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:00 INFO - '_': '/tools/buildbot/bin/python'} 03:57:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:00 INFO - Downloading/unpacking jsonschema==2.5.1 03:57:00 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 03:57:00 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 03:57:00 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 03:57:00 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 03:57:00 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 03:57:00 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 03:57:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:57:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:03 INFO - Downloading functools32-3.2.3-2.tar.gz 03:57:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:57:03 INFO - warning: no files found matching '*.txt' 03:57:03 INFO - no previously-included directories found matching 'build' 03:57:03 INFO - no previously-included directories found matching 'dist' 03:57:03 INFO - no previously-included directories found matching '.git*' 03:57:03 INFO - Installing collected packages: jsonschema, functools32 03:57:03 INFO - Running setup.py install for functools32 03:57:03 INFO - warning: no files found matching '*.txt' 03:57:03 INFO - no previously-included directories found matching 'build' 03:57:03 INFO - no previously-included directories found matching 'dist' 03:57:03 INFO - no previously-included directories found matching '.git*' 03:57:03 INFO - Successfully installed jsonschema functools32 03:57:03 INFO - Cleaning up... 03:57:03 INFO - Return code: 0 03:57:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:03 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fa3b0>, '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 0x27fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', '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 03:57:03 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:57:03 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 functools32==3.2.3-2 03:57:03 INFO - Using env: {'DISPLAY': ':0', 03:57:03 INFO - 'HOME': '/home/cltbld', 03:57:03 INFO - 'LANG': 'en_US.UTF-8', 03:57:03 INFO - 'LANGUAGE': 'en_US:en', 03:57:03 INFO - 'LOGNAME': 'cltbld', 03:57:03 INFO - 'MAIL': '/var/mail/cltbld', 03:57:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:03 INFO - 'MOZ_NO_REMOTE': '1', 03:57:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:57:03 INFO - 'NO_EM_RESTART': '1', 03:57:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:57:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:03 INFO - 'PWD': '/builds/slave/test', 03:57:03 INFO - 'SHELL': '/bin/bash', 03:57:03 INFO - 'SHLVL': '1', 03:57:03 INFO - 'TERM': 'linux', 03:57:03 INFO - 'TMOUT': '86400', 03:57:03 INFO - 'USER': 'cltbld', 03:57:03 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', 03:57:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:03 INFO - '_': '/tools/buildbot/bin/python'} 03:57:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:57:03 INFO - Cleaning up... 03:57:03 INFO - Return code: 0 03:57:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:03 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 0x253b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fa3b0>, '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 0x27fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', '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 03:57:03 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 03:57:03 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 03:57:03 INFO - Using env: {'DISPLAY': ':0', 03:57:03 INFO - 'HOME': '/home/cltbld', 03:57:03 INFO - 'LANG': 'en_US.UTF-8', 03:57:03 INFO - 'LANGUAGE': 'en_US:en', 03:57:03 INFO - 'LOGNAME': 'cltbld', 03:57:03 INFO - 'MAIL': '/var/mail/cltbld', 03:57:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:03 INFO - 'MOZ_NO_REMOTE': '1', 03:57:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:57:03 INFO - 'NO_EM_RESTART': '1', 03:57:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:57:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:03 INFO - 'PWD': '/builds/slave/test', 03:57:03 INFO - 'SHELL': '/bin/bash', 03:57:03 INFO - 'SHLVL': '1', 03:57:03 INFO - 'TERM': 'linux', 03:57:03 INFO - 'TMOUT': '86400', 03:57:03 INFO - 'USER': 'cltbld', 03:57:03 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', 03:57:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:03 INFO - '_': '/tools/buildbot/bin/python'} 03:57:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:03 INFO - Downloading/unpacking blobuploader==1.2.4 03:57:03 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 03:57:03 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 03:57:03 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 03:57:03 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 03:57:03 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 03:57:03 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 03:57:05 INFO - Downloading blobuploader-1.2.4.tar.gz 03:57:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:57:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:57:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57:06 INFO - Downloading docopt-0.6.1.tar.gz 03:57:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:57:06 INFO - Installing collected packages: blobuploader, requests, docopt 03:57:06 INFO - Running setup.py install for blobuploader 03:57:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:57:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:57:06 INFO - Running setup.py install for requests 03:57:06 INFO - Running setup.py install for docopt 03:57:06 INFO - Successfully installed blobuploader requests docopt 03:57:06 INFO - Cleaning up... 03:57:06 INFO - Return code: 0 03:57:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57:06 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')]} 03:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:06 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')]} 03:57:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:06 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 0x253b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fa3b0>, '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 0x27fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', '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 03:57:06 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 03:57:06 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 03:57:06 INFO - Using env: {'DISPLAY': ':0', 03:57:06 INFO - 'HOME': '/home/cltbld', 03:57:06 INFO - 'LANG': 'en_US.UTF-8', 03:57:06 INFO - 'LANGUAGE': 'en_US:en', 03:57:06 INFO - 'LOGNAME': 'cltbld', 03:57:06 INFO - 'MAIL': '/var/mail/cltbld', 03:57:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:06 INFO - 'MOZ_NO_REMOTE': '1', 03:57:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:57:06 INFO - 'NO_EM_RESTART': '1', 03:57:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:57:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:06 INFO - 'PWD': '/builds/slave/test', 03:57:06 INFO - 'SHELL': '/bin/bash', 03:57:06 INFO - 'SHLVL': '1', 03:57:06 INFO - 'TERM': 'linux', 03:57:06 INFO - 'TMOUT': '86400', 03:57:06 INFO - 'USER': 'cltbld', 03:57:06 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', 03:57:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:06 INFO - '_': '/tools/buildbot/bin/python'} 03:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:57:07 INFO - Running setup.py (path:/tmp/pip-6Du5bG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:57:07 INFO - Running setup.py (path:/tmp/pip-XcKvKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:57:07 INFO - Running setup.py (path:/tmp/pip-VTsQpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:57:07 INFO - Running setup.py (path:/tmp/pip-uydouE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:57:07 INFO - Running setup.py (path:/tmp/pip-JWUL1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:57:07 INFO - Running setup.py (path:/tmp/pip-t4xuuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:57:07 INFO - Running setup.py (path:/tmp/pip-CjahUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:57:07 INFO - Running setup.py (path:/tmp/pip-OzUAgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:57:07 INFO - Running setup.py (path:/tmp/pip-nwWM_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:57:07 INFO - Running setup.py (path:/tmp/pip-H9xin_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:57:07 INFO - Running setup.py (path:/tmp/pip-915Wkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:57:07 INFO - Running setup.py (path:/tmp/pip-FQ539u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:57:08 INFO - Running setup.py (path:/tmp/pip-YsPJoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:57:08 INFO - Running setup.py (path:/tmp/pip-fSqOEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:08 INFO - Running setup.py (path:/tmp/pip-d30ITe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:57:08 INFO - Running setup.py (path:/tmp/pip-zymeNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:57:08 INFO - Running setup.py (path:/tmp/pip-SGSl06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:57:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:57:08 INFO - Running setup.py install for manifestparser 03:57:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:57:08 INFO - Running setup.py install for mozcrash 03:57:08 INFO - Running setup.py install for mozdebug 03:57:08 INFO - Running setup.py install for mozdevice 03:57:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:57:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:57:08 INFO - Running setup.py install for mozfile 03:57:08 INFO - Running setup.py install for mozhttpd 03:57:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:57:09 INFO - Running setup.py install for mozinfo 03:57:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:57:09 INFO - Running setup.py install for mozInstall 03:57:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:57:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:57:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:57:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:57:09 INFO - Running setup.py install for mozleak 03:57:09 INFO - Running setup.py install for mozlog 03:57:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:57:09 INFO - Running setup.py install for moznetwork 03:57:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:57:09 INFO - Running setup.py install for mozprocess 03:57:09 INFO - Running setup.py install for mozprofile 03:57:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:57:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:57:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:57:09 INFO - Running setup.py install for mozrunner 03:57:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:57:09 INFO - Running setup.py install for mozscreenshot 03:57:09 INFO - Running setup.py install for moztest 03:57:10 INFO - Running setup.py install for mozversion 03:57:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:57:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:57:10 INFO - Cleaning up... 03:57:10 INFO - Return code: 0 03:57:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57:10 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')]} 03:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:10 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')]} 03:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:10 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 0x253b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fa3b0>, '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 0x27fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', '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 03:57:10 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 03:57:10 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 03:57:10 INFO - Using env: {'DISPLAY': ':0', 03:57:10 INFO - 'HOME': '/home/cltbld', 03:57:10 INFO - 'LANG': 'en_US.UTF-8', 03:57:10 INFO - 'LANGUAGE': 'en_US:en', 03:57:10 INFO - 'LOGNAME': 'cltbld', 03:57:10 INFO - 'MAIL': '/var/mail/cltbld', 03:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:10 INFO - 'MOZ_NO_REMOTE': '1', 03:57:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:57:10 INFO - 'NO_EM_RESTART': '1', 03:57:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:10 INFO - 'PWD': '/builds/slave/test', 03:57:10 INFO - 'SHELL': '/bin/bash', 03:57:10 INFO - 'SHLVL': '1', 03:57:10 INFO - 'TERM': 'linux', 03:57:10 INFO - 'TMOUT': '86400', 03:57:10 INFO - 'USER': 'cltbld', 03:57:10 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', 03:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:10 INFO - '_': '/tools/buildbot/bin/python'} 03:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:57:10 INFO - Running setup.py (path:/tmp/pip-1S2RPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:57:10 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)) 03:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:57:10 INFO - Running setup.py (path:/tmp/pip-wEn_Av-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:57:10 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)) 03:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:57:10 INFO - Running setup.py (path:/tmp/pip-5_oBwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:57:10 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)) 03:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:57:10 INFO - Running setup.py (path:/tmp/pip-z9frlp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:57:10 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)) 03:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:57:10 INFO - Running setup.py (path:/tmp/pip-0vts0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:57:10 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)) 03:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:57:10 INFO - Running setup.py (path:/tmp/pip-_p9Chy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:57:10 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)) 03:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:57:10 INFO - Running setup.py (path:/tmp/pip-1b4nB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:57:10 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)) 03:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:57:10 INFO - Running setup.py (path:/tmp/pip-QuesgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:57:11 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)) 03:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:57:11 INFO - Running setup.py (path:/tmp/pip-O5t3Br-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:57:11 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)) 03:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:57:11 INFO - Running setup.py (path:/tmp/pip-xEixXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 03:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:57:11 INFO - Running setup.py (path:/tmp/pip-T0OirD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:57:11 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)) 03:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:57:11 INFO - Running setup.py (path:/tmp/pip-VKOhag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 03:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:57:11 INFO - Running setup.py (path:/tmp/pip-Nn_hM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:57:11 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)) 03:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:57:11 INFO - Running setup.py (path:/tmp/pip-s7EsuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 03:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:11 INFO - Running setup.py (path:/tmp/pip-dEB3k6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:11 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)) 03:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:57:11 INFO - Running setup.py (path:/tmp/pip-9fnogW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:57:11 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)) 03:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:57:11 INFO - Running setup.py (path:/tmp/pip-Mjxtaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:57:11 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)) 03:57:11 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)) 03:57:11 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)) 03:57:11 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)) 03:57:11 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)) 03:57:11 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)) 03:57:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:13 INFO - Downloading blessings-1.6.tar.gz 03:57:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:57:13 INFO - Installing collected packages: blessings 03:57:13 INFO - Running setup.py install for blessings 03:57:13 INFO - Successfully installed blessings 03:57:13 INFO - Cleaning up... 03:57:13 INFO - Return code: 0 03:57:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:57:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:57:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:57:14 INFO - Reading from file tmpfile_stdout 03:57:14 INFO - Current package versions: 03:57:14 INFO - argparse == 1.2.1 03:57:14 INFO - blessings == 1.6 03:57:14 INFO - blobuploader == 1.2.4 03:57:14 INFO - docopt == 0.6.1 03:57:14 INFO - functools32 == 3.2.3-2 03:57:14 INFO - jsonschema == 2.5.1 03:57:14 INFO - manifestparser == 1.1 03:57:14 INFO - mozInstall == 1.12 03:57:14 INFO - mozcrash == 0.17 03:57:14 INFO - mozdebug == 0.1 03:57:14 INFO - mozdevice == 0.48 03:57:14 INFO - mozfile == 1.2 03:57:14 INFO - mozhttpd == 0.7 03:57:14 INFO - mozinfo == 0.9 03:57:14 INFO - mozleak == 0.1 03:57:14 INFO - mozlog == 3.3 03:57:14 INFO - moznetwork == 0.27 03:57:14 INFO - mozprocess == 0.23 03:57:14 INFO - mozprofile == 0.28 03:57:14 INFO - mozrunner == 6.12 03:57:14 INFO - mozscreenshot == 0.1 03:57:14 INFO - mozsystemmonitor == 0.3 03:57:14 INFO - moztest == 0.7 03:57:14 INFO - mozversion == 1.4 03:57:14 INFO - psutil == 3.1.1 03:57:14 INFO - requests == 1.2.3 03:57:14 INFO - wsgiref == 0.1.2 03:57:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:14 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 0x253b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fa3b0>, '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 0x27fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', '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 03:57:14 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 03:57:14 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 03:57:14 INFO - Using env: {'DISPLAY': ':0', 03:57:14 INFO - 'HOME': '/home/cltbld', 03:57:14 INFO - 'LANG': 'en_US.UTF-8', 03:57:14 INFO - 'LANGUAGE': 'en_US:en', 03:57:14 INFO - 'LOGNAME': 'cltbld', 03:57:14 INFO - 'MAIL': '/var/mail/cltbld', 03:57:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:14 INFO - 'MOZ_NO_REMOTE': '1', 03:57:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:57:14 INFO - 'NO_EM_RESTART': '1', 03:57:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:57:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:14 INFO - 'PWD': '/builds/slave/test', 03:57:14 INFO - 'SHELL': '/bin/bash', 03:57:14 INFO - 'SHLVL': '1', 03:57:14 INFO - 'TERM': 'linux', 03:57:14 INFO - 'TMOUT': '86400', 03:57:14 INFO - 'USER': 'cltbld', 03:57:14 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', 03:57:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:14 INFO - '_': '/tools/buildbot/bin/python'} 03:57:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:14 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)) 03:57:14 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)) 03:57:14 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)) 03:57:14 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)) 03:57:14 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)) 03:57:14 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)) 03:57:14 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)) 03:57:14 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)) 03:57:14 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)) 03:57:14 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)) 03:57:14 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)) 03:57:14 INFO - Cleaning up... 03:57:14 INFO - Return code: 0 03:57:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:57:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fa3b0>, '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 0x27fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', '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 03:57:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:57:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:57:14 INFO - Using env: {'DISPLAY': ':0', 03:57:14 INFO - 'HOME': '/home/cltbld', 03:57:14 INFO - 'LANG': 'en_US.UTF-8', 03:57:14 INFO - 'LANGUAGE': 'en_US:en', 03:57:14 INFO - 'LOGNAME': 'cltbld', 03:57:14 INFO - 'MAIL': '/var/mail/cltbld', 03:57:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:14 INFO - 'MOZ_NO_REMOTE': '1', 03:57:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:57:14 INFO - 'NO_EM_RESTART': '1', 03:57:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:57:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:14 INFO - 'PWD': '/builds/slave/test', 03:57:14 INFO - 'SHELL': '/bin/bash', 03:57:14 INFO - 'SHLVL': '1', 03:57:14 INFO - 'TERM': 'linux', 03:57:14 INFO - 'TMOUT': '86400', 03:57:14 INFO - 'USER': 'cltbld', 03:57:14 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', 03:57:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:14 INFO - '_': '/tools/buildbot/bin/python'} 03:57:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:57:14 INFO - Cleaning up... 03:57:14 INFO - Return code: 0 03:57:14 INFO - Running post-action listener: _resource_record_post_action 03:57:14 INFO - Running post-action listener: _start_resource_monitoring 03:57:14 INFO - Starting resource monitoring. 03:57:14 INFO - [mozharness: 2016-09-07 10:57:14.489101Z] Finished create-virtualenv step (success) 03:57:14 INFO - [mozharness: 2016-09-07 10:57:14.489635Z] Running install step. 03:57:14 INFO - Running pre-action listener: _resource_record_pre_action 03:57:14 INFO - Running main action method: install 03:57:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:57:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:57:14 INFO - Reading from file tmpfile_stdout 03:57:14 INFO - Detecting whether we're running mozinstall >=1.0... 03:57:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:57:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:57:14 INFO - Reading from file tmpfile_stdout 03:57:14 INFO - Output received: 03:57:14 INFO - Usage: mozinstall [options] installer 03:57:14 INFO - Options: 03:57:14 INFO - -h, --help show this help message and exit 03:57:14 INFO - -d DEST, --destination=DEST 03:57:14 INFO - Directory to install application into. [default: 03:57:14 INFO - "/builds/slave/test"] 03:57:14 INFO - --app=APP Application being installed. [default: firefox] 03:57:14 INFO - mkdir: /builds/slave/test/build/application 03:57:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:57:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:57:30 INFO - Reading from file tmpfile_stdout 03:57:30 INFO - Output received: 03:57:30 INFO - /builds/slave/test/build/application/firefox/firefox 03:57:30 INFO - Running post-action listener: _resource_record_post_action 03:57:30 INFO - [mozharness: 2016-09-07 10:57:30.805460Z] Finished install step (success) 03:57:30 INFO - [mozharness: 2016-09-07 10:57:30.805740Z] Running run-tests step. 03:57:30 INFO - Running pre-action listener: _resource_record_pre_action 03:57:30 INFO - Running main action method: run_tests 03:57:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:57:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:57:30 INFO - Python 2.7.3 03:57:30 INFO - Return code: 0 03:57:30 INFO - grabbing minidump binary from tooltool 03:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:30 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fa3b0>, '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 0x27fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:57:30 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 03:57:30 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 03:57:30 INFO - Calling ['/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'] with output_timeout 600 03:57:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:57:30 INFO - Return code: 0 03:57:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:57:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:57:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:57:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:57:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:57:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 03:57:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --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 03:57:30 INFO - Using env: {'DISPLAY': ':0', 03:57:30 INFO - 'HOME': '/home/cltbld', 03:57:30 INFO - 'LANG': 'en_US.UTF-8', 03:57:30 INFO - 'LANGUAGE': 'en_US:en', 03:57:30 INFO - 'LOGNAME': 'cltbld', 03:57:30 INFO - 'MAIL': '/var/mail/cltbld', 03:57:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:57:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:30 INFO - 'MOZ_NO_REMOTE': '1', 03:57:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:57:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:57:30 INFO - 'NO_EM_RESTART': '1', 03:57:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:30 INFO - 'PWD': '/builds/slave/test', 03:57:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:57:30 INFO - 'SHELL': '/bin/bash', 03:57:30 INFO - 'SHLVL': '1', 03:57:30 INFO - 'TERM': 'linux', 03:57:30 INFO - 'TMOUT': '86400', 03:57:30 INFO - 'USER': 'cltbld', 03:57:30 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252', 03:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:30 INFO - '_': '/tools/buildbot/bin/python'} 03:57:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 03:57:31 INFO - mozversion application_buildid: 20160907004009 03:57:31 INFO - mozversion application_changeset: 3a0fd1bb116a3b79b6f33462a04144818b550ee9 03:57:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:57:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:57:31 INFO - mozversion application_name: Firefox 03:57:31 INFO - mozversion application_remotingname: firefox-dev 03:57:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:57:31 INFO - mozversion application_vendor: Mozilla 03:57:31 INFO - mozversion application_version: 50.0a2 03:57:31 INFO - mozversion platform_buildid: 20160907004009 03:57:31 INFO - mozversion platform_changeset: 3a0fd1bb116a3b79b6f33462a04144818b550ee9 03:57:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:57:31 INFO - mozversion platform_version: 50.0a2 03:57:31 INFO - using testdate: 1473245851 03:57:31 INFO - actual date: 1473245851 03:57:31 INFO - starting webserver on 'localhost:51740' 03:57:31 INFO - SUITE-START | Running 2 tests 03:57:31 INFO - TEST-START | dromaeo_css 03:57:31 INFO - operating with platform_type : linux_ 03:57:31 INFO - Initialising browser for dromaeo_css test... 03:57:31 INFO - TEST-INFO | started process 6386 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG66CTl/profile http://localhost:51740/getInfo.html) 03:57:35 INFO - PROCESS | 6386 | __metrics Screen width/height:1600/1200 03:57:35 INFO - PROCESS | 6386 | colorDepth:24 03:57:35 INFO - PROCESS | 6386 | Browser inner width/height: 1024/697 03:57:35 INFO - PROCESS | 6386 | __metrics 03:57:36 INFO - PROCESS | 6386 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:57:36 INFO - PROCESS | 6386 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:57:37 INFO - PROCESS | 6386 | console.error: PushService: 03:57:37 INFO - PROCESS | 6386 | stateChangeProcessEnqueue: Error transitioning state 03:57:37 INFO - PROCESS | 6386 | UnknownError 03:57:37 INFO - TEST-INFO | 6386: exit 0 03:57:37 INFO - Browser initialized. 03:57:37 INFO - Running cycle 1/1 for dromaeo_css test... 03:57:37 INFO - TEST-INFO | started process 6473 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG66CTl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:57:49 INFO - PROCESS | 6473 | RSS: Main: 145469440 03:57:49 INFO - PROCESS | 6473 | 03:58:40 INFO - PROCESS | 6473 | 1473245920309 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] 04:00:49 INFO - PROCESS | 6473 | Cycle 1(1): loaded http://localhost:51740/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51740/tests/dromaeo/cssquery-ext.html) 04:00:50 INFO - PROCESS | 6473 | RSS: Main: 221753344 04:00:50 INFO - PROCESS | 6473 | 04:03:53 INFO - PROCESS | 6473 | Cycle 1(1): loaded http://localhost:51740/tests/dromaeo/cssquery-ext.html (next: http://localhost:51740/tests/dromaeo/cssquery-jquery.html) 04:03:53 INFO - PROCESS | 6473 | RSS: Main: 230076416 04:03:53 INFO - PROCESS | 6473 | 04:06:55 INFO - PROCESS | 6473 | Cycle 1(1): loaded http://localhost:51740/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51740/tests/dromaeo/cssquery-mootools.html) 04:06:56 INFO - PROCESS | 6473 | RSS: Main: 173686784 04:06:56 INFO - PROCESS | 6473 | 04:09:58 INFO - PROCESS | 6473 | Cycle 1(1): loaded http://localhost:51740/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51740/tests/dromaeo/cssquery-prototype.html) 04:09:58 INFO - PROCESS | 6473 | RSS: Main: 222068736 04:09:58 INFO - PROCESS | 6473 | 04:13:01 INFO - PROCESS | 6473 | Cycle 1(1): loaded http://localhost:51740/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51740/tests/dromaeo/cssquery-yui.html) 04:13:01 INFO - PROCESS | 6473 | RSS: Main: 173502464 04:13:01 INFO - PROCESS | 6473 | 04:16:04 INFO - PROCESS | 6473 | Cycle 1(1): loaded http://localhost:51740/tests/dromaeo/cssquery-yui.html (next: http://localhost:51740/tests/dromaeo/cssquery-dojo.html) 04:16:04 INFO - PROCESS | 6473 | RSS: Main: 262967296 04:16:04 INFO - PROCESS | 6473 | 04:16:04 INFO - PROCESS | 6473 | __start_tp_report 04:16:04 INFO - PROCESS | 6473 | _x_x_mozilla_page_load 04:16:04 INFO - PROCESS | 6473 | _x_x_mozilla_page_load_details 04:16:04 INFO - PROCESS | 6473 | |i|pagename|runs| 04:16:04 INFO - PROCESS | 6473 | |0;dojo.html;3201.3277211785526;3222.822744749038;3234.094453553005;3250.821204833733;3267.9509807352892;5501.504864562188;5588;5595.132754423066;5611.859703507414;5628.943710562899;5622.100463925773;5672.205891175228;5703.600747947635;5704.344000439951;5722.198892155102;4990.429928586781;5016.874578135557;5136.8972620547565;5163.19970404586;5235.764390602413;5096.974515127419;5097;5106.157484015133;5157.871053223662;5179.4561571035065;4972.602191824663;5039.571636410891;5050.393952725678;5074.162763144095;5082.288479612857;5114.309568208281;5128.487151284879;5146.356705411824;5152.355955505562;5159.922601160985;4925.753712314406;4966.751662416865;5010.974945125287;5035.395752509686;5062.063518249136;5412.296401467824;5441.211024401439;5450.018996580614;5457.154141108134;5501.394846566875;5948.791792287292;5988.341282458926;5994.820155395301;5997.460228579449;5999.340072592011;5828.0966450200285;5938.495227905633;5963.880722385528;5978.970105149447;5997.0404735242155;5643.040683083886;5658.7170641467765;5659.801906933238;5659.971700141514;5663.433656634304;6126.448619624166;6128.540359473057;6150.815475535741;6156.907646385218;6158.907616385758;5628.887422251614;5813.186153938452;5882.02946513829;5884.558658100574;5973.432523910308;5997.670128142994;6016.458518733335;6059.515238780887;6063.54523410746;6064.878702425926;5994.490468310155;6002.639841609517;6006.909896351558;6025.638461692312;6026;5774.566907481872;5837.9124313135335;5892.086726557391;5951.672658003765;5964.7912323068485;4629.699069560468;4635.397398338195;4639.9792045750555;4640.605548528345;4647.767611619405;4120.237756015147;4136.21411931738;4136.482939632546;4151.107511884959;4164.083901541656;5452.672839629635;5626;5664.85837854057;5690.2887139107615;5696.202531645573;6044.063170208625;6047.818565443044;6049.425304596057;6051.0923361496125;6081.331053584102;5687.77248910039;5732.197492351074;5743.511801496919;5747.827565173052;5751.1373294006235;5056.84829455117;5118.334616499906;5157.767900444489;5162.638615296968;5240.868978275497;6146.969265153735;6224.657643829632;6228.096925945803;6232.407921247474;6235.376462353819;5995.910061348996;5998.040313549811;6001.609895356788;6002.849928751816;6004.669743164168;5942.61373010753;6212.254529456494;6270.655113968775;6281.560290779602;6309;5952.5237980960555;5961.54518615462;5970.910436343545;5971.492423143907;6013.218281623361;6020.4280593343565;6076.635401875901;6094.7257373417415;6094.847628809138;6095.481884039907;5680.147977803363;5834.591578589458;5835.912461312999;5896.204012458264;5996.4902983245165;5899.675517846388;5922.289325280994;5931.703414829328;5931.881362372815;5936.5844390892225;5842.152887831156;5871.060630299132;5884.205632239593;5886.175935368966;5886.705664716661;5643.125315576092;5793.8551536211935;5872.55955803325;5883.705814709333;5927.644341339534;5737.368889422244;5773.538116950718;5776.075827867521;5781.537477001746;5786.363500015079 04:16:04 INFO - PROCESS | 6473 | |1;ext.html;620.8044465993212;637.7895294552798;658.5324419662039;670.0251134599158;678.3826717686906;35304.64695353046;37980.24039519208;38416.5029273097;46208.768956155254;47279;48023.51976480234;48306.75846620762;48449;48501.514984850226;48600.51399486004;9326.393784404027;9395.201407880339;9419.29355298352;9483.478408687519;9490.905090949105;8437.70468033619;10749.301295415811;10992.505337259803;11011.724706882313;11351.602693905716;11257.380844053574;11361;11361.318320900731;11442.8283575746;11482.368469734161;8646.827063458695;11249.887501125007;11297.774044519063;11331.830022549668;11334.546618135306;10240.539175737109;10607.893921060768;10609.15126789855;10889.673309800719;10906.127509799197;46314.76842615799;46704.06591868178;46719;46791;46806.29790553144;3785.734998550103;3905.6094390561;3921.941075909517;4046.332355161395;4286.807093680792;35738.28523429517;35990.8200458996;35993.64006359929;36284;37441.81279093587;4406.581374769392;4456.2647163218035;4502;4516.616087632555;4561.612262957619;380387;382862.08568957256;383789;384013;386940;65114.674426627564;65567.34432655708;66140;66267.66866165638;66288.6685566569;39651.80174099111;40607.59392406097;40622.39066414006;42990.35514467285;43009.78495107567;17889.821101789075;18107.094645267684;18118.45644630637;18152.90923545374;18163.909180454015;12356;13489.595312140651;13516.25660588677;13656.385462654205;13685.657858553615;20981.160753569686;21244.25645102414;21282.042308096174;21295;21328.14687412517;7531.8117047074265;7584.62076896153;7757.456978011598;7808.492447990862;7856.33221176195;1030.8299130643509;1111.74985628233;1127.7293449572044;1184.8815118488083;1208.848250114797;34925.82537087298;35535.64464355375;35571;35628;35743.4638480423;1172.3200543684643;1179.1510112719;1244.296972210698;1247.3389103775014;1291.0768800307826;18402.171902264432;18480.445586632424;18520.722189167172;18705.625887482445;18742.437726868215;603866;611664;613127;613373;614097;65833;66067;66200.66899665471;66293;66689;1354.1942544186193;1437.9568612941628;1440.7478691228994;1485.2647939269914;1502.8797696184497;33761;36301.636983629825;36677.32262780916;36702;37188;55362;56190;56458.717706411204;56548;56633.71683141558;424604;425181;429100;430285;430666;31028.22429439282;31226.843865780527;32013;32072.839635801673;33325.33349332951;52347.73826130845;52906.206406903155;53550.46449535455;53671.73164134155;53811.46188538221;45198;45480.54519454763;45584.54415455803;46486.76756616195;47088;44342.33486497641;46009.30986035279;46417.76791116023;46821.53178468308;47312.526874730815;45105;45424;45745.08509829718;46260.53739462562;46657.53342466532;39100.80449597734;40370;40485.19029619332;40775;41488.79255603703;30196.367549042632;30255.24337158634;30429;30569.54145687861;30839.383212336077 04:16:04 INFO - PROCESS | 6473 | |2;jquery.html;391.2039000633707;417.1157146848681;423.6801215082613;431.84669442347973;435.542680185805;15505.379784808609;20503.48741281465;20981.370558883256;21015.159393624257;21016.474588135305;16683.66632667349;16832.579185520368;19287.132079056442;19719.605607887832;19729.210831566757;5716.485516303522;5883.61756485827;5919;6123.265208175025;6169.074638804171;6164;6171.938280617182;6190.102435146901;6191.783287584935;6192.256929168506;6026.63840169589;6153.230846144232;6181.320054793969;6190.380961903818;6194.566380353377;6047.183630209909;6153.723082461255;6230.501559875222;6254.46837018854;6269.404406581368;6030.7889223876955;6033.336333003366;6034.487068599175;6044.909326360108;6045.395460453919;23716.762832371627;24080.87959560191;24139;24217.394565135837;24315.63526547103;2378.5361854438393;2413.179518963561;2564.9358766030814;2661.108528642907;3002.3394853132277;10854.891451085467;10947.4526273686;11060.612878549293;12377.69055773604;14394.424223031067;2029.2998915374171;2048.5083579940856;2057.3210840422626;2068.565601223745;2098.7586427560723;65927;68138.65930670315;68202;77839.61080194563;87271;9723.319367644339;10622.575096996188;11750.942415182504;11826.763464730657;11946.223495472766;16596.58508537272;16626.25181866819;16641.41755038568;16659.25033373501;16672;8427.831443371098;8446.788830279293;8590.1839325264;8613.78465538354;8643.827123457622;8185.877211841827;8332.208440198172;8404.705835295857;8430.747077587683;8441.11368306319;4934;4937.925931111036;4974.353334066548;5184.144616138295;5192.480751924777;2085.1555120176354;2113.527173207351;2117.3224568138357;2123.50097727034;2152.515683971094;932.9160375566231;987.2496902354262;996.2279233593878;1024.9487525623838;1080.778440419712;19480.70778938317;19924.103415346344;20106.396808095782;20288.289909853083;20534.486637834172;1026.1329176845554;1039.4231201682987;1065.2436769893463;1071.244772435431;1082.1991726122612;10392.37645741258;11331.263467874562;11368.374739389414;11398.658040258806;11415.258008229604;505805;506598;515087;517024;519447;55130.44869551253;76933;90009;90190;90940.54529727441;960.8846938367441;992.1318846009741;998.5221871630029;1037.258360272409;1039.8804137524282;17618.207180676643;17722.645547088727;18105.456836294932;18124.53502828528;18278.908605456887;41927.37108943307;42387.788061059495;43019.56980430281;43420.56579434165;44365;59487;59510.70244648749;60139.39860601338;60481;62017;8676.826463470821;8838.823223535623;8875.911240887766;8929.241014513838;9205.079492050741;12202.938985305016;13312.334383280991;13423.395947182595;13605.727885442433;13657.112287701268;11226.438678066228;11426.714332141762;11684.18210725241;11806.645800625995;11817.881821182022;11046.834297485384;11167.553297867995;11201.327920324806;11469.483873225892;11604.593839215588;7455.844344126669;10653.520591573553;10911.563537458413;11860.347680877634;11925.701857453632;8779.56102194875;8785.209331160226;8785.692500762443;8888;9006;11077.446127693744;11667.824982625438;11727.530898763955;12014.038876890003;12158.209716368408 04:16:04 INFO - PROCESS | 6473 | |3;mootools.html;1504.5711972087956;1587.9444219452319;1734.9653006939861;1807.665581867354;1867.225101582843;9097.317701172413;9714.028597140292;9785.853212201804;9793.853092203623;9808.411495310278;7995.360371170293;9028.503432311236;9295.488748118867;9319.114684105;9571.80856382872;2442.4992876460374;2490.22802931091;2492.9252122436355;2493.0401795308844;2493.3891196656846;2451.1298489036312;2538.8984440622353;2542.1992072497196;2542.7711505964458;2543.694756629207;2524.4193835417864;2536.264483299841;2552.9489410211786;2554.7062087859854;2558.9360265993405;2413.8310318277727;2416.8791560421996;2424.490856920049;2488.0545392750637;2491.2650768023295;2409.542186984467;2424.684790977179;2430.5746494363593;2435.9147429839873;2447.412620970972;9840;9903.059209375098;9911.653092141813;9924.751881202956;9926;5512.062949298629;5666.433356664342;5667.064934285838;5673.716314184316;5674.914876276859;3460.567429071366;3567.3222087803233;3603.387424137903;3609.3142302962615;3610.711143108545;1106.093003736936;1137.4426530999833;1140.378493720924;1151.3840095548926;1161.7095726068483;13576.59270221895;13591.728165436734;13611.795823062663;13614.863851361557;13644.93177534116;4578.221702310549;4698.694584851974;4857.927131093036;4865.756712164449;4870.805167793319;11129.387883666313;11132.387718675389;11153.609623663133;11247.83128253077;11249.04383127427;5570.86072848182;5595.608307418523;5645.181448689917;5649.152627105967;5653.265075540236;4424.623906967944;4584.312353147055;5068.138416469209;5143.691378517301;5163.070647283525;4141.968461226474;4161.334186530141;4206.326987681956;4224.577542245812;4258.87223383299;1289.2973329535425;1293.8770816772392;1304.2956803326097;1324.1326930860303;1330.946762129504;838.8196537744415;955.6273053509137;970.6893793985856;1032.3547782635853;1041.6458404142627;6607.405333520006;6610.4050635443045;6614.6361950093205;6664.166979127609;6844.28135045823;1027;1035.435687550287;1035.8342665173536;1046.3198920701452;1057.9682609521726;4506.954930450719;4556.7721613919175;4612.30815377696;4615.192341340319;4627.467841198305;11924;12417.62747117588;12421.75156496865;12489.875101249054;12503.437345319482;12169.574064907685;12203.69490762738;12283.201591896677;12355.69110772238;12412.255264683967;8752.69365572202;8779.692710755093;8917;8923.598438070303;8978.685745998859;6275.96862015687;6363.459105976044;6392.872142557216;6504.056911747864;6511.088447617432;10667.413292269;10880.564777409132;10921.726956826144;10927.180461465581;11105.111591072533;11228.382438965935;11352.829707554229;11439.771204576029;11469.655910322703;11579.768404632028;8263.834723305621;8372.78594603767;8444.873326900224;8447.788805279917;8467;8872.822543549222;8929;8965.955170224108;8989.235914947303;9004.099590041205;8121.187881211831;8128.9593552031865;8221.506709597443;8287.95856020716;8292.17078292166;7724.652390642547;7811;7899.131095579596;7998.520088794691;8115.83768324642;7027.05134806815;7061;7073.82315442118;7112.182099058467;7175.964120179366;5226.843194704165;5263;5312.468753124811;5349.930013997294;5390.541803946551;5313.362396512443;5482.122860342326;5502.80740174092;5695.288088988877;5737.340205876378 04:16:04 INFO - PROCESS | 6473 | |4;prototype.html;344.35433562071114;353.49626781835883;360.9458581212816;361.27023412706336;362.2664105186998;5434.456554344563;5446.645968012076;5452.809151679692;5598.1322894951245;5655.349634791995;5458.6724796512235;5460.808871689492;5470.617056806025;5472.671639701611;5521.509192518018;4351.3037913933695;4352.107817897323;4360.411344468504;4391.143726973241;4425.7565833879125;4237.703360764748;4353.455818022747;4357.760323182224;4394.384786129928;4402.185595664812;4294.656427485794;4296.806643701041;4301.591348821857;4316.870493885172;4321.222180007598;4309.7629630370475;4326.178026175048;4339.739615623042;4342.870853578061;4358.106588149421;3734.8879533614036;3830.0233440472502;3833.6358045985585;3841.8666493384517;3851.2105018471425;5358.785648574091;5359.624826262163;5361.839144825662;5363.919541206885;5417.972910135424;4929.408470983505;4984;4991.925121123186;4993.625478089158;5013.29813826061;5651.40660230674;5713.5714821388565;5773.134029895507;5786.797462088806;5797;4186.225548273579;4189.853355132586;4206.516250631186;4215.135897141079;4217.671433498469;5729.369769325371;5948.910766338508;5980.97009514954;5984.640921544677;6025.126356678301;4195.930037840331;4253.510846252659;4271.124419493996;4283;4302.214580948871;4377.212101821703;4379.496357918816;4381.846635367747;4381.978090109593;4395.670324725658;4982.501749824988;4987.750612469435;4990.925136122961;4991.7004979700605;5008.874778130576;5437.945620543823;5637.915431268534;5772.451617096369;5799.405163579965;5854.121881717776;1457.4388860288866;1484.450753221315;1489.5531340597777;1502.6694127291978;1503.1807664822697;930.9906900931039;953.880764904387;957.6408846682494;969.471637957315;978.7014960436993;4998.500149984972;5001.899202335028;5009.497150854729;5024.974875125601;5025.9644843838305;5210.76551555181;5231.084560201949;5231.561320636779;5241.580673546184;5255.3168088148295;4811.90682053308;4985.601151907911;5002.574781143643;5004.524570165829;5015.874603134951;2795.273228960485;2802.9859850701023;2812.7046660100805;2820.830750154997;2835.2628316637824;5366;5428.9185662215095;5446.482584154499;5494.862628434321;5501.862453438616;5103.821366252238;5229.843104706865;5233.633645644844;5243.2659427680155;5316.601254905898;5410.8917821643345;5415.296011518478;5440.558252063206;5457.181422786534;5469.890602187855;5125.1037116265725;5242.501962313574;5260.605454590997;5276.498732620395;5310.9734451327495;4869.464358920587;5123.846284611468;5130.666026833125;5133.6919784813035;5151.2273159026445;5381.408045115112;5408;5451.591130665137;5462.235287059735;5501.807436739695;3901.668358189586;3927.368662995857;3931.9410208846316;3935.9606403935595;3938.330483817815;5579.326202139261;5713.8285851424525;5837.591368604157;5957.45106272381;6011.218541589566;5824.417558244142;5861.472467477947;5864.67744274069;5872.823815285548;5890.4993075589055;5841.773227622247;5851.561132914963;5864.266966629171;5868.061110222344;5877.911831322619;5623.775048997994;5693.772249109989;5765.798197063083;5783.826485205451;5832.93770770349;5826.475617194473;5840;5881.647101173943;5891.941080589139;5905.261842269716;5499.312585926759;5712.742926568231;5748.971255143698;5750.7987220447085;5783.306003279633 04:16:04 INFO - PROCESS | 6473 | |5;yui.html;800.1758189065262;811.3995643224014;821.1255013410718;852.3649880838768;923.4690053219398;15842.92078539607;17087.23107460164;17138;17195.140242987833;17240.05179715115;14189.29053547321;14195.077319974194;14195.432182712679;14199.92900035501;14214.573562793106;723.5803234124195;857.6097875466647;874.8294082653869;887.4054383563029;895.6506962284682;885.2961895293225;885.4642941020686;888.7467071884514;888.844452220862;889.1020069729555;916.083916083916;918.196577994255;919.0671468459519;919.3151102428667;919.7010971434278;766.3830616353833;908.318760929303;921.8525035994243;930.2883294279814;932.3287233192091;686.7767975408012;687.2131409536054;687.5737043033301;687.9105716256904;688.0779755128152;15955.840441595552;15972.041677499272;15977.440789572425;16012.759808602881;16033.038017718858;14738.55784326472;14954;15080.437307755556;15240;15550.144742039183;13665.658358541017;13748.65628359299;13787.86212137886;13969.930150349182;14116.364763585663;4616.422947131608;4676.321933319649;4691.577758001762;4778.068276686047;4816.927746083777;87884.56057719671;88284;88301;88771.55614221952;91217.54391228067;11662.650120496399;11705.180637355474;11913.821292680617;12048.397580120958;12080.75838483243;23076.307710768702;23077.76922230756;23091.307260782203;23114.422139446648;23121.190758323377;9055.954720226357;9158.67944621935;9266.212371948324;9274.95362523183;9622.13400793918;4939.604831613462;5025.773840177201;5043.697378157322;5120.769565369567;5205.687658740488;3528.2414280929725;3661.2677464507206;3665.486831843544;3668.9816550917076;3672.7979961102396;645.5803727577113;646.5086534233922;652.1326635574675;661.6195687479718;671.214678825775;591.1221835574169;691.4917535611322;709.5600727548953;717.2504732554493;728.2244409703649;10254.53854576546;10649.73375665599;11108.666739997812;11631.302121872715;11642.301461912313;643.3855667837204;690.2579726793717;719.1765428584346;719.8776208044645;781.281221276427;4789.544993225638;5094.337736094284;5288;5294.470552944752;5299.4700529946695;67665.32334676689;67896.32103678999;68003;68374;108198.45900770603;101034.4948275254;104282;104855;105500.47249763702;107909;57168.42831571631;77169;82168;88951;91127;9255.167034966886;9308.581113849757;9354.766130846783;9740.514989426545;9774.266929980138;39054.6094539051;40629.39055914223;51181;53519.126830560745;53723.731381344405;72432.63783681048;74670;79458.6027069861;79663;79990;13212.537561185312;13737;13739.175649461064;13753.174809511462;14012.299385030912;12288.877111228774;13912.652183695489;14432.783508247594;14802.407903684163;14963.251837408303;13250.271235082164;13807.17156970585;13879.861201387857;14578.198199099152;14777.704445911237;14576.344064517334;14861.405543778561;15455.53633391;15551.377944882079;15970.520884373487;13709.725805483635;13729.519466818614;14519.274036297931;14809;15003.6999260012;12009.939950300193;12416.847028385348;13012.804807928078;13170.275634840176;13203;12172.45223964906;12217.389130543615;12298;12560.874391256337;12676.302803345905 04:16:04 INFO - PROCESS | 6473 | __end_tp_report 04:16:04 INFO - PROCESS | 6473 | __start_cc_report 04:16:04 INFO - PROCESS | 6473 | _x_x_mozilla_cycle_collect,1014 04:16:04 INFO - PROCESS | 6473 | __end_cc_report 04:16:04 INFO - PROCESS | 6473 | __startTimestamp1473246964677__endTimestamp 04:16:04 INFO - PROCESS | 6473 | 04:16:04 INFO - PROCESS | 6473 | ------- Summary: start ------- 04:16:04 INFO - PROCESS | 6473 | Number of tests: 6 04:16:04 INFO - PROCESS | 6473 | 04:16:04 INFO - PROCESS | 6473 | [#0] dojo.html Cycles:165 Average:5573.62 Median:5781.54 stddev:633.75 (11.0%) stddev-sans-first:607.76 04:16:04 INFO - PROCESS | 6473 | Values: 3201.3 3222.8 3234.1 3250.8 3268.0 5501.5 5588.0 5595.1 5611.9 5628.9 5622.1 5672.2 5703.6 5704.3 5722.2 4990.4 5016.9 5136.9 5163.2 5235.8 5097.0 5097.0 5106.2 5157.9 5179.5 4972.6 5039.6 5050.4 5074.2 5082.3 5114.3 5128.5 5146.4 5152.4 5159.9 4925.8 4966.8 5011.0 5035.4 5062.1 5412.3 5441.2 5450.0 5457.2 5501.4 5948.8 5988.3 5994.8 5997.5 5999.3 5828.1 5938.5 5963.9 5979.0 5997.0 5643.0 5658.7 5659.8 5660.0 5663.4 6126.4 6128.5 6150.8 6156.9 6158.9 5628.9 5813.2 5882.0 5884.6 5973.4 5997.7 6016.5 6059.5 6063.5 6064.9 5994.5 6002.6 6006.9 6025.6 6026.0 5774.6 5837.9 5892.1 5951.7 5964.8 4629.7 4635.4 4640.0 4640.6 4647.8 4120.2 4136.2 4136.5 4151.1 4164.1 5452.7 5626.0 5664.9 5690.3 5696.2 6044.1 6047.8 6049.4 6051.1 6081.3 5687.8 5732.2 5743.5 5747.8 5751.1 5056.8 5118.3 5157.8 5162.6 5240.9 6147.0 6224.7 6228.1 6232.4 6235.4 5995.9 5998.0 6001.6 6002.8 6004.7 5942.6 6212.3 6270.7 6281.6 6309.0 5952.5 5961.5 5970.9 5971.5 6013.2 6020.4 6076.6 6094.7 6094.8 6095.5 5680.1 5834.6 5835.9 5896.2 5996.5 5899.7 5922.3 5931.7 5931.9 5936.6 5842.2 5871.1 5884.2 5886.2 5886.7 5643.1 5793.9 5872.6 5883.7 5927.6 5737.4 5773.5 5776.1 5781.5 5786.4 04:16:04 INFO - PROCESS | 6473 | 04:16:04 INFO - PROCESS | 6473 | [#1] ext.html Cycles:180 Average:64865.46 Median:40010.90 stddev:128473.59 (321.1%) stddev-sans-first:128743.44 04:16:04 INFO - PROCESS | 6473 | Values: 620.8 637.8 658.5 670.0 678.4 35304.6 37980.2 38416.5 46208.8 47279.0 48023.5 48306.8 48449.0 48501.5 48600.5 9326.4 9395.2 9419.3 9483.5 9490.9 8437.7 10749.3 10992.5 11011.7 11351.6 11257.4 11361.0 11361.3 11442.8 11482.4 8646.8 11249.9 11297.8 11331.8 11334.5 10240.5 10607.9 10609.2 10889.7 10906.1 46314.8 46704.1 46719.0 46791.0 46806.3 3785.7 3905.6 3921.9 4046.3 4286.8 35738.3 35990.8 35993.6 36284.0 37441.8 4406.6 4456.3 4502.0 4516.6 4561.6 380387.0 382862.1 383789.0 384013.0 386940.0 65114.7 65567.3 66140.0 66267.7 66288.7 39651.8 40607.6 40622.4 42990.4 43009.8 17889.8 18107.1 18118.5 18152.9 18163.9 12356.0 13489.6 13516.3 13656.4 13685.7 20981.2 21244.3 21282.0 21295.0 21328.1 7531.8 7584.6 7757.5 7808.5 7856.3 1030.8 1111.7 1127.7 1184.9 1208.8 34925.8 35535.6 35571.0 35628.0 35743.5 1172.3 1179.2 1244.3 1247.3 1291.1 18402.2 18480.4 18520.7 18705.6 18742.4 603866.0 611664.0 613127.0 613373.0 614097.0 65833.0 66067.0 66200.7 66293.0 66689.0 1354.2 1438.0 1440.7 1485.3 1502.9 33761.0 36301.6 36677.3 36702.0 37188.0 55362.0 56190.0 56458.7 56548.0 56633.7 424604.0 425181.0 429100.0 430285.0 430666.0 31028.2 31226.8 32013.0 32072.8 33325.3 52347.7 52906.2 53550.5 53671.7 53811.5 45198.0 45480.5 45584.5 46486.8 47088.0 44342.3 46009.3 46417.8 46821.5 47312.5 45105.0 45424.0 45745.1 46260.5 46657.5 39100.8 40370.0 40485.2 40775.0 41488.8 30196.4 30255.2 30429.0 30569.5 30839.4 04:16:04 INFO - PROCESS | 6473 | 04:16:04 INFO - PROCESS | 6473 | [#2] jquery.html Cycles:180 Average:29457.82 Median:2831.72 stddev:84121.07 (2970.7%) stddev-sans-first:84328.74 04:16:04 INFO - PROCESS | 6473 | Values: 391.2 417.1 423.7 431.8 435.5 15505.4 20503.5 20981.4 21015.2 21016.5 16683.7 16832.6 19287.1 19719.6 19729.2 5716.5 5883.6 5919.0 6123.3 6169.1 6164.0 6171.9 6190.1 6191.8 6192.3 6026.6 6153.2 6181.3 6190.4 6194.6 6047.2 6153.7 6230.5 6254.5 6269.4 6030.8 6033.3 6034.5 6044.9 6045.4 23716.8 24080.9 24139.0 24217.4 24315.6 2378.5 2413.2 2564.9 2661.1 3002.3 10854.9 10947.5 11060.6 12377.7 14394.4 2029.3 2048.5 2057.3 2068.6 2098.8 65927.0 68138.7 68202.0 77839.6 87271.0 9723.3 10622.6 11750.9 11826.8 11946.2 16596.6 16626.3 16641.4 16659.3 16672.0 8427.8 8446.8 8590.2 8613.8 8643.8 8185.9 8332.2 8404.7 8430.7 8441.1 4934.0 4937.9 4974.4 5184.1 5192.5 2085.2 2113.5 2117.3 2123.5 2152.5 932.9 987.2 996.2 1024.9 1080.8 19480.7 19924.1 20106.4 20288.3 20534.5 1026.1 1039.4 1065.2 1071.2 1082.2 10392.4 11331.3 11368.4 11398.7 11415.3 505805.0 506598.0 515087.0 517024.0 519447.0 55130.4 76933.0 90009.0 90190.0 90940.5 960.9 992.1 998.5 1037.3 1039.9 17618.2 17722.6 18105.5 18124.5 18278.9 41927.4 42387.8 43019.6 43420.6 44365.0 59487.0 59510.7 60139.4 60481.0 62017.0 8676.8 8838.8 8875.9 8929.2 9205.1 12202.9 13312.3 13423.4 13605.7 13657.1 11226.4 11426.7 11684.2 11806.6 11817.9 11046.8 11167.6 11201.3 11469.5 11604.6 7455.8 10653.5 10911.6 11860.3 11925.7 8779.6 8785.2 8785.7 8888.0 9006.0 11077.4 11667.8 11727.5 12014.0 12158.2 04:16:04 INFO - PROCESS | 6473 | 04:16:04 INFO - PROCESS | 6473 | [#3] mootools.html Cycles:180 Average:6264.97 Median:4581.27 stddev:3652.24 (79.7%) stddev-sans-first:3644.97 04:16:04 INFO - PROCESS | 6473 | Values: 1504.6 1587.9 1735.0 1807.7 1867.2 9097.3 9714.0 9785.9 9793.9 9808.4 7995.4 9028.5 9295.5 9319.1 9571.8 2442.5 2490.2 2492.9 2493.0 2493.4 2451.1 2538.9 2542.2 2542.8 2543.7 2524.4 2536.3 2552.9 2554.7 2558.9 2413.8 2416.9 2424.5 2488.1 2491.3 2409.5 2424.7 2430.6 2435.9 2447.4 9840.0 9903.1 9911.7 9924.8 9926.0 5512.1 5666.4 5667.1 5673.7 5674.9 3460.6 3567.3 3603.4 3609.3 3610.7 1106.1 1137.4 1140.4 1151.4 1161.7 13576.6 13591.7 13611.8 13614.9 13644.9 4578.2 4698.7 4857.9 4865.8 4870.8 11129.4 11132.4 11153.6 11247.8 11249.0 5570.9 5595.6 5645.2 5649.2 5653.3 4424.6 4584.3 5068.1 5143.7 5163.1 4142.0 4161.3 4206.3 4224.6 4258.9 1289.3 1293.9 1304.3 1324.1 1330.9 838.8 955.6 970.7 1032.4 1041.6 6607.4 6610.4 6614.6 6664.2 6844.3 1027.0 1035.4 1035.8 1046.3 1058.0 4507.0 4556.8 4612.3 4615.2 4627.5 11924.0 12417.6 12421.8 12489.9 12503.4 12169.6 12203.7 12283.2 12355.7 12412.3 8752.7 8779.7 8917.0 8923.6 8978.7 6276.0 6363.5 6392.9 6504.1 6511.1 10667.4 10880.6 10921.7 10927.2 11105.1 11228.4 11352.8 11439.8 11469.7 11579.8 8263.8 8372.8 8444.9 8447.8 8467.0 8872.8 8929.0 8966.0 8989.2 9004.1 8121.2 8129.0 8221.5 8288.0 8292.2 7724.7 7811.0 7899.1 7998.5 8115.8 7027.1 7061.0 7073.8 7112.2 7176.0 5226.8 5263.0 5312.5 5349.9 5390.5 5313.4 5482.1 5502.8 5695.3 5737.3 04:16:04 INFO - PROCESS | 6473 | 04:16:04 INFO - PROCESS | 6473 | [#4] prototype.html Cycles:180 Average:4700.97 Median:5232.60 stddev:1347.80 (25.8%) stddev-sans-first:1311.31 04:16:04 INFO - PROCESS | 6473 | Values: 344.4 353.5 360.9 361.3 362.3 5434.5 5446.6 5452.8 5598.1 5655.3 5458.7 5460.8 5470.6 5472.7 5521.5 4351.3 4352.1 4360.4 4391.1 4425.8 4237.7 4353.5 4357.8 4394.4 4402.2 4294.7 4296.8 4301.6 4316.9 4321.2 4309.8 4326.2 4339.7 4342.9 4358.1 3734.9 3830.0 3833.6 3841.9 3851.2 5358.8 5359.6 5361.8 5363.9 5418.0 4929.4 4984.0 4991.9 4993.6 5013.3 5651.4 5713.6 5773.1 5786.8 5797.0 4186.2 4189.9 4206.5 4215.1 4217.7 5729.4 5948.9 5981.0 5984.6 6025.1 4195.9 4253.5 4271.1 4283.0 4302.2 4377.2 4379.5 4381.8 4382.0 4395.7 4982.5 4987.8 4990.9 4991.7 5008.9 5437.9 5637.9 5772.5 5799.4 5854.1 1457.4 1484.5 1489.6 1502.7 1503.2 931.0 953.9 957.6 969.5 978.7 4998.5 5001.9 5009.5 5025.0 5026.0 5210.8 5231.1 5231.6 5241.6 5255.3 4811.9 4985.6 5002.6 5004.5 5015.9 2795.3 2803.0 2812.7 2820.8 2835.3 5366.0 5428.9 5446.5 5494.9 5501.9 5103.8 5229.8 5233.6 5243.3 5316.6 5410.9 5415.3 5440.6 5457.2 5469.9 5125.1 5242.5 5260.6 5276.5 5311.0 4869.5 5123.8 5130.7 5133.7 5151.2 5381.4 5408.0 5451.6 5462.2 5501.8 3901.7 3927.4 3931.9 3936.0 3938.3 5579.3 5713.8 5837.6 5957.5 6011.2 5824.4 5861.5 5864.7 5872.8 5890.5 5841.8 5851.6 5864.3 5868.1 5877.9 5623.8 5693.8 5765.8 5783.8 5832.9 5826.5 5840.0 5881.6 5891.9 5905.3 5499.3 5712.7 5749.0 5750.8 5783.3 04:16:04 INFO - PROCESS | 6473 | 04:16:04 INFO - PROCESS | 6473 | [#5] yui.html Cycles:180 Average:20472.02 Median:4734.82 stddev:28026.45 (591.9%) stddev-sans-first:28066.14 04:16:04 INFO - PROCESS | 6473 | Values: 800.2 811.4 821.1 852.4 923.5 15842.9 17087.2 17138.0 17195.1 17240.1 14189.3 14195.1 14195.4 14199.9 14214.6 723.6 857.6 874.8 887.4 895.7 885.3 885.5 888.7 888.8 889.1 916.1 918.2 919.1 919.3 919.7 766.4 908.3 921.9 930.3 932.3 686.8 687.2 687.6 687.9 688.1 15955.8 15972.0 15977.4 16012.8 16033.0 14738.6 14954.0 15080.4 15240.0 15550.1 13665.7 13748.7 13787.9 13969.9 14116.4 4616.4 4676.3 4691.6 4778.1 4816.9 87884.6 88284.0 88301.0 88771.6 91217.5 11662.7 11705.2 11913.8 12048.4 12080.8 23076.3 23077.8 23091.3 23114.4 23121.2 9056.0 9158.7 9266.2 9275.0 9622.1 4939.6 5025.8 5043.7 5120.8 5205.7 3528.2 3661.3 3665.5 3669.0 3672.8 645.6 646.5 652.1 661.6 671.2 591.1 691.5 709.6 717.3 728.2 10254.5 10649.7 11108.7 11631.3 11642.3 643.4 690.3 719.2 719.9 781.3 4789.5 5094.3 5288.0 5294.5 5299.5 67665.3 67896.3 68003.0 68374.0 108198.5 101034.5 104282.0 104855.0 105500.5 107909.0 57168.4 77169.0 82168.0 88951.0 91127.0 9255.2 9308.6 9354.8 9740.5 9774.3 39054.6 40629.4 51181.0 53519.1 53723.7 72432.6 74670.0 79458.6 79663.0 79990.0 13212.5 13737.0 13739.2 13753.2 14012.3 12288.9 13912.7 14432.8 14802.4 14963.3 13250.3 13807.2 13879.9 14578.2 14777.7 14576.3 14861.4 15455.5 15551.4 15970.5 13709.7 13729.5 14519.3 14809.0 15003.7 12009.9 12416.8 13012.8 13170.3 13203.0 12172.5 12217.4 12298.0 12560.9 12676.3 04:16:04 INFO - PROCESS | 6473 | -------- Summary: end -------- 04:16:04 INFO - PROCESS | 6473 | 04:16:05 INFO - TEST-INFO | 6473: exit 0 04:16:05 INFO - TEST-OK | dromaeo_css | took 1114396ms 04:16:05 INFO - TEST-START | kraken 04:16:05 INFO - operating with platform_type : linux_ 04:16:05 INFO - Initialising browser for kraken test... 04:16:05 INFO - TEST-INFO | started process 6681 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmACnH_/profile http://localhost:51740/getInfo.html) 04:16:09 INFO - PROCESS | 6681 | __metrics Screen width/height:1600/1200 04:16:09 INFO - PROCESS | 6681 | colorDepth:24 04:16:09 INFO - PROCESS | 6681 | Browser inner width/height: 1024/697 04:16:09 INFO - PROCESS | 6681 | __metrics 04:16:11 INFO - PROCESS | 6681 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:16:11 INFO - PROCESS | 6681 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:16:12 INFO - TEST-INFO | 6681: exit 0 04:16:12 INFO - Browser initialized. 04:16:12 INFO - Running cycle 1/1 for kraken test... 04:16:12 INFO - TEST-INFO | started process 6770 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmACnH_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:16:23 INFO - PROCESS | 6770 | RSS: Main: 145051648 04:16:23 INFO - PROCESS | 6770 | 04:16:26 INFO - PROCESS | 6770 | Cycle 1(1): loaded http://localhost:51740/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51740/tests/kraken/driver.html?testName=audio-beat-detection) 04:16:27 INFO - PROCESS | 6770 | RSS: Main: 191651840 04:16:27 INFO - PROCESS | 6770 | 04:16:29 INFO - PROCESS | 6770 | Cycle 1(1): loaded http://localhost:51740/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51740/tests/kraken/driver.html?testName=audio-dft) 04:16:30 INFO - PROCESS | 6770 | RSS: Main: 181501952 04:16:30 INFO - PROCESS | 6770 | 04:16:33 INFO - PROCESS | 6770 | Cycle 1(1): loaded http://localhost:51740/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51740/tests/kraken/driver.html?testName=audio-fft) 04:16:33 INFO - PROCESS | 6770 | RSS: Main: 269410304 04:16:33 INFO - PROCESS | 6770 | 04:16:35 INFO - PROCESS | 6770 | Cycle 1(1): loaded http://localhost:51740/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51740/tests/kraken/driver.html?testName=audio-oscillator) 04:16:36 INFO - PROCESS | 6770 | RSS: Main: 175624192 04:16:36 INFO - PROCESS | 6770 | 04:16:38 INFO - PROCESS | 6770 | Cycle 1(1): loaded http://localhost:51740/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51740/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:16:39 INFO - PROCESS | 6770 | RSS: Main: 176750592 04:16:39 INFO - PROCESS | 6770 | 04:16:42 INFO - PROCESS | 6770 | Cycle 1(1): loaded http://localhost:51740/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51740/tests/kraken/driver.html?testName=imaging-darkroom) 04:16:42 INFO - PROCESS | 6770 | RSS: Main: 213221376 04:16:42 INFO - PROCESS | 6770 | 04:16:46 INFO - PROCESS | 6770 | Cycle 1(1): loaded http://localhost:51740/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51740/tests/kraken/driver.html?testName=imaging-desaturate) 04:16:46 INFO - PROCESS | 6770 | RSS: Main: 212430848 04:16:46 INFO - PROCESS | 6770 | 04:16:49 INFO - PROCESS | 6770 | Cycle 1(1): loaded http://localhost:51740/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51740/tests/kraken/driver.html?testName=json-parse-financial) 04:16:50 INFO - PROCESS | 6770 | RSS: Main: 212488192 04:16:50 INFO - PROCESS | 6770 | 04:16:51 INFO - PROCESS | 6770 | Cycle 1(1): loaded http://localhost:51740/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51740/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:16:52 INFO - PROCESS | 6770 | RSS: Main: 176001024 04:16:52 INFO - PROCESS | 6770 | 04:16:54 INFO - PROCESS | 6770 | Cycle 1(1): loaded http://localhost:51740/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51740/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:16:54 INFO - PROCESS | 6770 | RSS: Main: 182337536 04:16:54 INFO - PROCESS | 6770 | 04:16:56 INFO - PROCESS | 6770 | Cycle 1(1): loaded http://localhost:51740/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51740/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:16:57 INFO - PROCESS | 6770 | RSS: Main: 186662912 04:16:57 INFO - PROCESS | 6770 | 04:16:59 INFO - PROCESS | 6770 | Cycle 1(1): loaded http://localhost:51740/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51740/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:17:00 INFO - PROCESS | 6770 | RSS: Main: 180224000 04:17:00 INFO - PROCESS | 6770 | 04:17:02 INFO - PROCESS | 6770 | Cycle 1(1): loaded http://localhost:51740/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51740/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:17:03 INFO - PROCESS | 6770 | RSS: Main: 184373248 04:17:03 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | Cycle 1(1): loaded http://localhost:51740/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51740/tests/kraken/driver.html?testName=ai-astar) 04:17:05 INFO - PROCESS | 6770 | RSS: Main: 236109824 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | __start_tp_report 04:17:05 INFO - PROCESS | 6770 | _x_x_mozilla_page_load 04:17:05 INFO - PROCESS | 6770 | _x_x_mozilla_page_load_details 04:17:05 INFO - PROCESS | 6770 | |i|pagename|runs| 04:17:05 INFO - PROCESS | 6770 | |0;ai-astar;102;113;110;112;111;111;111;112;112;111 04:17:05 INFO - PROCESS | 6770 | |1;audio-beat-detection;142;116;117;175;116;117;144;119;153;125 04:17:05 INFO - PROCESS | 6770 | |2;audio-dft;160;161;160;160;160;162;163;161;161;159 04:17:05 INFO - PROCESS | 6770 | |3;audio-fft;75;75;74;114;75;75;74;75;109;75 04:17:05 INFO - PROCESS | 6770 | |4;audio-oscillator;84;83;128;84;92;85;117;83;84;131 04:17:05 INFO - PROCESS | 6770 | |5;imaging-gaussian-blur;116;117;116;116;116;116;117;117;117;117 04:17:05 INFO - PROCESS | 6770 | |6;imaging-darkroom;145;146;146;146;147;146;146;146;145;145 04:17:05 INFO - PROCESS | 6770 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;94 04:17:05 INFO - PROCESS | 6770 | |8;json-parse-financial;51;49;50;50;50;51;50;50;49;50 04:17:05 INFO - PROCESS | 6770 | |9;json-stringify-tinderbox;60;59;60;59;60;59;61;63;60;59 04:17:05 INFO - PROCESS | 6770 | |10;stanford-crypto-aes;72;72;72;72;73;72;72;72;72;72 04:17:05 INFO - PROCESS | 6770 | |11;stanford-crypto-ccm;128;118;134;130;116;119;117;117;201;125 04:17:05 INFO - PROCESS | 6770 | |12;stanford-crypto-pbkdf2;156;150;150;156;154;150;151;157;150;151 04:17:05 INFO - PROCESS | 6770 | |13;stanford-crypto-sha256-iterative;61;61;61;60;61;60;60;60;60;60 04:17:05 INFO - PROCESS | 6770 | __end_tp_report 04:17:05 INFO - PROCESS | 6770 | __start_cc_report 04:17:05 INFO - PROCESS | 6770 | _x_x_mozilla_cycle_collect,1611 04:17:05 INFO - PROCESS | 6770 | __end_cc_report 04:17:05 INFO - PROCESS | 6770 | __startTimestamp1473247025464__endTimestamp 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | ------- Summary: start ------- 04:17:05 INFO - PROCESS | 6770 | Number of tests: 14 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.50 stddev:3.10 (2.8%) stddev-sans-first:0.88 04:17:05 INFO - PROCESS | 6770 | Values: 102.0 113.0 110.0 112.0 111.0 111.0 111.0 112.0 112.0 111.0 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | [#1] audio-beat-detection Cycles:10 Average:132.40 Median:133.50 stddev:20.31 (15.2%) stddev-sans-first:21.24 04:17:05 INFO - PROCESS | 6770 | Values: 142.0 116.0 117.0 175.0 116.0 117.0 144.0 119.0 153.0 125.0 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 04:17:05 INFO - PROCESS | 6770 | Values: 160.0 161.0 160.0 160.0 160.0 162.0 163.0 161.0 161.0 159.0 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | [#3] audio-fft Cycles:10 Average:82.10 Median:75.00 stddev:15.55 (20.7%) stddev-sans-first:16.27 04:17:05 INFO - PROCESS | 6770 | Values: 75.0 75.0 74.0 114.0 75.0 75.0 74.0 75.0 109.0 75.0 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:84.00 stddev:19.96 (23.8%) stddev-sans-first:20.60 04:17:05 INFO - PROCESS | 6770 | Values: 84.0 83.0 128.0 84.0 92.0 85.0 117.0 83.0 84.0 131.0 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 04:17:05 INFO - PROCESS | 6770 | Values: 116.0 117.0 116.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 04:17:05 INFO - PROCESS | 6770 | Values: 145.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 145.0 145.0 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:17:05 INFO - PROCESS | 6770 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.00 stddev:0.67 (1.3%) stddev-sans-first:0.60 04:17:05 INFO - PROCESS | 6770 | Values: 51.0 49.0 50.0 50.0 50.0 51.0 50.0 50.0 49.0 50.0 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:1.25 (2.1%) stddev-sans-first:1.32 04:17:05 INFO - PROCESS | 6770 | Values: 60.0 59.0 60.0 59.0 60.0 59.0 61.0 63.0 60.0 59.0 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | [#10] stanford-crypto-aes Cycles:10 Average:72.10 Median:72.00 stddev:0.32 (0.4%) stddev-sans-first:0.33 04:17:05 INFO - PROCESS | 6770 | Values: 72.0 72.0 72.0 72.0 73.0 72.0 72.0 72.0 72.0 72.0 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | [#11] stanford-crypto-ccm Cycles:10 Average:130.50 Median:126.50 stddev:25.57 (20.2%) stddev-sans-first:27.10 04:17:05 INFO - PROCESS | 6770 | Values: 128.0 118.0 134.0 130.0 116.0 119.0 117.0 117.0 201.0 125.0 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.50 Median:152.50 stddev:2.92 (1.9%) stddev-sans-first:2.80 04:17:05 INFO - PROCESS | 6770 | Values: 156.0 150.0 150.0 156.0 154.0 150.0 151.0 157.0 150.0 151.0 04:17:05 INFO - PROCESS | 6770 | 04:17:05 INFO - PROCESS | 6770 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.40 Median:60.50 stddev:0.52 (0.9%) stddev-sans-first:0.50 04:17:05 INFO - PROCESS | 6770 | Values: 61.0 61.0 61.0 60.0 61.0 60.0 60.0 60.0 60.0 60.0 04:17:05 INFO - PROCESS | 6770 | -------- Summary: end -------- 04:17:05 INFO - PROCESS | 6770 | 04:17:06 INFO - TEST-INFO | 6770: exit 0 04:17:06 INFO - TEST-OK | kraken | took 60991ms 04:17:06 INFO - SUITE-END | took 1175s 04:17:06 INFO - Completed test suite (00:19:35) 04:17:06 INFO - javascript benchmark 04:17:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3201.3277211785526, 3222.822744749038, 3234.094453553005, 3250.821204833733, 3267.9509807352892, 5501.504864562188, 5588.0, 5595.132754423066, 5611.859703507414, 5628.943710562899, 5622.100463925773, 5672.205891175228, 5703.600747947635, 5704.344000439951, 5722.198892155102, 4990.429928586781, 5016.874578135557, 5136.8972620547565, 5163.19970404586, 5235.764390602413, 5096.974515127419, 5097.0, 5106.157484015133, 5157.871053223662, 5179.4561571035065, 4972.602191824663, 5039.571636410891, 5050.393952725678, 5074.162763144095, 5082.288479612857, 5114.309568208281, 5128.487151284879, 5146.356705411824, 5152.355955505562, 5159.922601160985, 4925.753712314406, 4966.751662416865, 5010.974945125287, 5035.395752509686, 5062.063518249136, 5412.296401467824, 5441.211024401439, 5450.018996580614, 5457.154141108134, 5501.394846566875, 5948.791792287292, 5988.341282458926, 5994.820155395301, 5997.460228579449, 5999.340072592011, 5828.0966450200285, 5938.495227905633, 5963.880722385528, 5978.970105149447, 5997.0404735242155, 5643.040683083886, 5658.7170641467765, 5659.801906933238, 5659.971700141514, 5663.433656634304, 6126.448619624166, 6128.540359473057, 6150.815475535741, 6156.907646385218, 6158.907616385758, 5628.887422251614, 5813.186153938452, 5882.02946513829, 5884.558658100574, 5973.432523910308, 5997.670128142994, 6016.458518733335, 6059.515238780887, 6063.54523410746, 6064.878702425926, 5994.490468310155, 6002.639841609517, 6006.909896351558, 6025.638461692312, 6026.0, 5774.566907481872, 5837.9124313135335, 5892.086726557391, 5951.672658003765, 5964.7912323068485, 4629.699069560468, 4635.397398338195, 4639.9792045750555, 4640.605548528345, 4647.767611619405, 4120.237756015147, 4136.21411931738, 4136.482939632546, 4151.107511884959, 4164.083901541656, 5452.672839629635, 5626.0, 5664.85837854057, 5690.2887139107615, 5696.202531645573, 6044.063170208625, 6047.818565443044, 6049.425304596057, 6051.0923361496125, 6081.331053584102, 5687.77248910039, 5732.197492351074, 5743.511801496919, 5747.827565173052, 5751.1373294006235, 5056.84829455117, 5118.334616499906, 5157.767900444489, 5162.638615296968, 5240.868978275497, 6146.969265153735, 6224.657643829632, 6228.096925945803, 6232.407921247474, 6235.376462353819, 5995.910061348996, 5998.040313549811, 6001.609895356788, 6002.849928751816, 6004.669743164168, 5942.61373010753, 6212.254529456494, 6270.655113968775, 6281.560290779602, 6309.0, 5952.5237980960555, 5961.54518615462, 5970.910436343545, 5971.492423143907, 6013.218281623361, 6020.4280593343565, 6076.635401875901, 6094.7257373417415, 6094.847628809138, 6095.481884039907, 5680.147977803363, 5834.591578589458, 5835.912461312999, 5896.204012458264, 5996.4902983245165, 5899.675517846388, 5922.289325280994, 5931.703414829328, 5931.881362372815, 5936.5844390892225, 5842.152887831156, 5871.060630299132, 5884.205632239593, 5886.175935368966, 5886.705664716661, 5643.125315576092, 5793.8551536211935, 5872.55955803325, 5883.705814709333, 5927.644341339534, 5737.368889422244, 5773.538116950718, 5776.075827867521, 5781.537477001746, 5786.363500015079], "value": 5530.845639292637, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [620.8044465993212, 637.7895294552798, 658.5324419662039, 670.0251134599158, 678.3826717686906, 35304.64695353046, 37980.24039519208, 38416.5029273097, 46208.768956155254, 47279.0, 48023.51976480234, 48306.75846620762, 48449.0, 48501.514984850226, 48600.51399486004, 9326.393784404027, 9395.201407880339, 9419.29355298352, 9483.478408687519, 9490.905090949105, 8437.70468033619, 10749.301295415811, 10992.505337259803, 11011.724706882313, 11351.602693905716, 11257.380844053574, 11361.0, 11361.318320900731, 11442.8283575746, 11482.368469734161, 8646.827063458695, 11249.887501125007, 11297.774044519063, 11331.830022549668, 11334.546618135306, 10240.539175737109, 10607.893921060768, 10609.15126789855, 10889.673309800719, 10906.127509799197, 46314.76842615799, 46704.06591868178, 46719.0, 46791.0, 46806.29790553144, 3785.734998550103, 3905.6094390561, 3921.941075909517, 4046.332355161395, 4286.807093680792, 35738.28523429517, 35990.8200458996, 35993.64006359929, 36284.0, 37441.81279093587, 4406.581374769392, 4456.2647163218035, 4502.0, 4516.616087632555, 4561.612262957619, 380387.0, 382862.08568957256, 383789.0, 384013.0, 386940.0, 65114.674426627564, 65567.34432655708, 66140.0, 66267.66866165638, 66288.6685566569, 39651.80174099111, 40607.59392406097, 40622.39066414006, 42990.35514467285, 43009.78495107567, 17889.821101789075, 18107.094645267684, 18118.45644630637, 18152.90923545374, 18163.909180454015, 12356.0, 13489.595312140651, 13516.25660588677, 13656.385462654205, 13685.657858553615, 20981.160753569686, 21244.25645102414, 21282.042308096174, 21295.0, 21328.14687412517, 7531.8117047074265, 7584.62076896153, 7757.456978011598, 7808.492447990862, 7856.33221176195, 1030.8299130643509, 1111.74985628233, 1127.7293449572044, 1184.8815118488083, 1208.848250114797, 34925.82537087298, 35535.64464355375, 35571.0, 35628.0, 35743.4638480423, 1172.3200543684643, 1179.1510112719, 1244.296972210698, 1247.3389103775014, 1291.0768800307826, 18402.171902264432, 18480.445586632424, 18520.722189167172, 18705.625887482445, 18742.437726868215, 603866.0, 611664.0, 613127.0, 613373.0, 614097.0, 65833.0, 66067.0, 66200.66899665471, 66293.0, 66689.0, 1354.1942544186193, 1437.9568612941628, 1440.7478691228994, 1485.2647939269914, 1502.8797696184497, 33761.0, 36301.636983629825, 36677.32262780916, 36702.0, 37188.0, 55362.0, 56190.0, 56458.717706411204, 56548.0, 56633.71683141558, 424604.0, 425181.0, 429100.0, 430285.0, 430666.0, 31028.22429439282, 31226.843865780527, 32013.0, 32072.839635801673, 33325.33349332951, 52347.73826130845, 52906.206406903155, 53550.46449535455, 53671.73164134155, 53811.46188538221, 45198.0, 45480.54519454763, 45584.54415455803, 46486.76756616195, 47088.0, 44342.33486497641, 46009.30986035279, 46417.76791116023, 46821.53178468308, 47312.526874730815, 45105.0, 45424.0, 45745.08509829718, 46260.53739462562, 46657.53342466532, 39100.80449597734, 40370.0, 40485.19029619332, 40775.0, 41488.79255603703, 30196.367549042632, 30255.24337158634, 30429.0, 30569.54145687861, 30839.383212336077], "value": 21962.79412395302, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.2039000633707, 417.1157146848681, 423.6801215082613, 431.84669442347973, 435.542680185805, 15505.379784808609, 20503.48741281465, 20981.370558883256, 21015.159393624257, 21016.474588135305, 16683.66632667349, 16832.579185520368, 19287.132079056442, 19719.605607887832, 19729.210831566757, 5716.485516303522, 5883.61756485827, 5919.0, 6123.265208175025, 6169.074638804171, 6164.0, 6171.938280617182, 6190.102435146901, 6191.783287584935, 6192.256929168506, 6026.63840169589, 6153.230846144232, 6181.320054793969, 6190.380961903818, 6194.566380353377, 6047.183630209909, 6153.723082461255, 6230.501559875222, 6254.46837018854, 6269.404406581368, 6030.7889223876955, 6033.336333003366, 6034.487068599175, 6044.909326360108, 6045.395460453919, 23716.762832371627, 24080.87959560191, 24139.0, 24217.394565135837, 24315.63526547103, 2378.5361854438393, 2413.179518963561, 2564.9358766030814, 2661.108528642907, 3002.3394853132277, 10854.891451085467, 10947.4526273686, 11060.612878549293, 12377.69055773604, 14394.424223031067, 2029.2998915374171, 2048.5083579940856, 2057.3210840422626, 2068.565601223745, 2098.7586427560723, 65927.0, 68138.65930670315, 68202.0, 77839.61080194563, 87271.0, 9723.319367644339, 10622.575096996188, 11750.942415182504, 11826.763464730657, 11946.223495472766, 16596.58508537272, 16626.25181866819, 16641.41755038568, 16659.25033373501, 16672.0, 8427.831443371098, 8446.788830279293, 8590.1839325264, 8613.78465538354, 8643.827123457622, 8185.877211841827, 8332.208440198172, 8404.705835295857, 8430.747077587683, 8441.11368306319, 4934.0, 4937.925931111036, 4974.353334066548, 5184.144616138295, 5192.480751924777, 2085.1555120176354, 2113.527173207351, 2117.3224568138357, 2123.50097727034, 2152.515683971094, 932.9160375566231, 987.2496902354262, 996.2279233593878, 1024.9487525623838, 1080.778440419712, 19480.70778938317, 19924.103415346344, 20106.396808095782, 20288.289909853083, 20534.486637834172, 1026.1329176845554, 1039.4231201682987, 1065.2436769893463, 1071.244772435431, 1082.1991726122612, 10392.37645741258, 11331.263467874562, 11368.374739389414, 11398.658040258806, 11415.258008229604, 505805.0, 506598.0, 515087.0, 517024.0, 519447.0, 55130.44869551253, 76933.0, 90009.0, 90190.0, 90940.54529727441, 960.8846938367441, 992.1318846009741, 998.5221871630029, 1037.258360272409, 1039.8804137524282, 17618.207180676643, 17722.645547088727, 18105.456836294932, 18124.53502828528, 18278.908605456887, 41927.37108943307, 42387.788061059495, 43019.56980430281, 43420.56579434165, 44365.0, 59487.0, 59510.70244648749, 60139.39860601338, 60481.0, 62017.0, 8676.826463470821, 8838.823223535623, 8875.911240887766, 8929.241014513838, 9205.079492050741, 12202.938985305016, 13312.334383280991, 13423.395947182595, 13605.727885442433, 13657.112287701268, 11226.438678066228, 11426.714332141762, 11684.18210725241, 11806.645800625995, 11817.881821182022, 11046.834297485384, 11167.553297867995, 11201.327920324806, 11469.483873225892, 11604.593839215588, 7455.844344126669, 10653.520591573553, 10911.563537458413, 11860.347680877634, 11925.701857453632, 8779.56102194875, 8785.209331160226, 8785.692500762443, 8888.0, 9006.0, 11077.446127693744, 11667.824982625438, 11727.530898763955, 12014.038876890003, 12158.209716368408], "value": 9510.72230321154, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1504.5711972087956, 1587.9444219452319, 1734.9653006939861, 1807.665581867354, 1867.225101582843, 9097.317701172413, 9714.028597140292, 9785.853212201804, 9793.853092203623, 9808.411495310278, 7995.360371170293, 9028.503432311236, 9295.488748118867, 9319.114684105, 9571.80856382872, 2442.4992876460374, 2490.22802931091, 2492.9252122436355, 2493.0401795308844, 2493.3891196656846, 2451.1298489036312, 2538.8984440622353, 2542.1992072497196, 2542.7711505964458, 2543.694756629207, 2524.4193835417864, 2536.264483299841, 2552.9489410211786, 2554.7062087859854, 2558.9360265993405, 2413.8310318277727, 2416.8791560421996, 2424.490856920049, 2488.0545392750637, 2491.2650768023295, 2409.542186984467, 2424.684790977179, 2430.5746494363593, 2435.9147429839873, 2447.412620970972, 9840.0, 9903.059209375098, 9911.653092141813, 9924.751881202956, 9926.0, 5512.062949298629, 5666.433356664342, 5667.064934285838, 5673.716314184316, 5674.914876276859, 3460.567429071366, 3567.3222087803233, 3603.387424137903, 3609.3142302962615, 3610.711143108545, 1106.093003736936, 1137.4426530999833, 1140.378493720924, 1151.3840095548926, 1161.7095726068483, 13576.59270221895, 13591.728165436734, 13611.795823062663, 13614.863851361557, 13644.93177534116, 4578.221702310549, 4698.694584851974, 4857.927131093036, 4865.756712164449, 4870.805167793319, 11129.387883666313, 11132.387718675389, 11153.609623663133, 11247.83128253077, 11249.04383127427, 5570.86072848182, 5595.608307418523, 5645.181448689917, 5649.152627105967, 5653.265075540236, 4424.623906967944, 4584.312353147055, 5068.138416469209, 5143.691378517301, 5163.070647283525, 4141.968461226474, 4161.334186530141, 4206.326987681956, 4224.577542245812, 4258.87223383299, 1289.2973329535425, 1293.8770816772392, 1304.2956803326097, 1324.1326930860303, 1330.946762129504, 838.8196537744415, 955.6273053509137, 970.6893793985856, 1032.3547782635853, 1041.6458404142627, 6607.405333520006, 6610.4050635443045, 6614.6361950093205, 6664.166979127609, 6844.28135045823, 1027.0, 1035.435687550287, 1035.8342665173536, 1046.3198920701452, 1057.9682609521726, 4506.954930450719, 4556.7721613919175, 4612.30815377696, 4615.192341340319, 4627.467841198305, 11924.0, 12417.62747117588, 12421.75156496865, 12489.875101249054, 12503.437345319482, 12169.574064907685, 12203.69490762738, 12283.201591896677, 12355.69110772238, 12412.255264683967, 8752.69365572202, 8779.692710755093, 8917.0, 8923.598438070303, 8978.685745998859, 6275.96862015687, 6363.459105976044, 6392.872142557216, 6504.056911747864, 6511.088447617432, 10667.413292269, 10880.564777409132, 10921.726956826144, 10927.180461465581, 11105.111591072533, 11228.382438965935, 11352.829707554229, 11439.771204576029, 11469.655910322703, 11579.768404632028, 8263.834723305621, 8372.78594603767, 8444.873326900224, 8447.788805279917, 8467.0, 8872.822543549222, 8929.0, 8965.955170224108, 8989.235914947303, 9004.099590041205, 8121.187881211831, 8128.9593552031865, 8221.506709597443, 8287.95856020716, 8292.17078292166, 7724.652390642547, 7811.0, 7899.131095579596, 7998.520088794691, 8115.83768324642, 7027.05134806815, 7061.0, 7073.82315442118, 7112.182099058467, 7175.964120179366, 5226.843194704165, 5263.0, 5312.468753124811, 5349.930013997294, 5390.541803946551, 5313.362396512443, 5482.122860342326, 5502.80740174092, 5695.288088988877, 5737.340205876378], "value": 4970.916581503723, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.35433562071114, 353.49626781835883, 360.9458581212816, 361.27023412706336, 362.2664105186998, 5434.456554344563, 5446.645968012076, 5452.809151679692, 5598.1322894951245, 5655.349634791995, 5458.6724796512235, 5460.808871689492, 5470.617056806025, 5472.671639701611, 5521.509192518018, 4351.3037913933695, 4352.107817897323, 4360.411344468504, 4391.143726973241, 4425.7565833879125, 4237.703360764748, 4353.455818022747, 4357.760323182224, 4394.384786129928, 4402.185595664812, 4294.656427485794, 4296.806643701041, 4301.591348821857, 4316.870493885172, 4321.222180007598, 4309.7629630370475, 4326.178026175048, 4339.739615623042, 4342.870853578061, 4358.106588149421, 3734.8879533614036, 3830.0233440472502, 3833.6358045985585, 3841.8666493384517, 3851.2105018471425, 5358.785648574091, 5359.624826262163, 5361.839144825662, 5363.919541206885, 5417.972910135424, 4929.408470983505, 4984.0, 4991.925121123186, 4993.625478089158, 5013.29813826061, 5651.40660230674, 5713.5714821388565, 5773.134029895507, 5786.797462088806, 5797.0, 4186.225548273579, 4189.853355132586, 4206.516250631186, 4215.135897141079, 4217.671433498469, 5729.369769325371, 5948.910766338508, 5980.97009514954, 5984.640921544677, 6025.126356678301, 4195.930037840331, 4253.510846252659, 4271.124419493996, 4283.0, 4302.214580948871, 4377.212101821703, 4379.496357918816, 4381.846635367747, 4381.978090109593, 4395.670324725658, 4982.501749824988, 4987.750612469435, 4990.925136122961, 4991.7004979700605, 5008.874778130576, 5437.945620543823, 5637.915431268534, 5772.451617096369, 5799.405163579965, 5854.121881717776, 1457.4388860288866, 1484.450753221315, 1489.5531340597777, 1502.6694127291978, 1503.1807664822697, 930.9906900931039, 953.880764904387, 957.6408846682494, 969.471637957315, 978.7014960436993, 4998.500149984972, 5001.899202335028, 5009.497150854729, 5024.974875125601, 5025.9644843838305, 5210.76551555181, 5231.084560201949, 5231.561320636779, 5241.580673546184, 5255.3168088148295, 4811.90682053308, 4985.601151907911, 5002.574781143643, 5004.524570165829, 5015.874603134951, 2795.273228960485, 2802.9859850701023, 2812.7046660100805, 2820.830750154997, 2835.2628316637824, 5366.0, 5428.9185662215095, 5446.482584154499, 5494.862628434321, 5501.862453438616, 5103.821366252238, 5229.843104706865, 5233.633645644844, 5243.2659427680155, 5316.601254905898, 5410.8917821643345, 5415.296011518478, 5440.558252063206, 5457.181422786534, 5469.890602187855, 5125.1037116265725, 5242.501962313574, 5260.605454590997, 5276.498732620395, 5310.9734451327495, 4869.464358920587, 5123.846284611468, 5130.666026833125, 5133.6919784813035, 5151.2273159026445, 5381.408045115112, 5408.0, 5451.591130665137, 5462.235287059735, 5501.807436739695, 3901.668358189586, 3927.368662995857, 3931.9410208846316, 3935.9606403935595, 3938.330483817815, 5579.326202139261, 5713.8285851424525, 5837.591368604157, 5957.45106272381, 6011.218541589566, 5824.417558244142, 5861.472467477947, 5864.67744274069, 5872.823815285548, 5890.4993075589055, 5841.773227622247, 5851.561132914963, 5864.266966629171, 5868.061110222344, 5877.911831322619, 5623.775048997994, 5693.772249109989, 5765.798197063083, 5783.826485205451, 5832.93770770349, 5826.475617194473, 5840.0, 5881.647101173943, 5891.941080589139, 5905.261842269716, 5499.312585926759, 5712.742926568231, 5748.971255143698, 5750.7987220447085, 5783.306003279633], "value": 4277.415457947499, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [800.1758189065262, 811.3995643224014, 821.1255013410718, 852.3649880838768, 923.4690053219398, 15842.92078539607, 17087.23107460164, 17138.0, 17195.140242987833, 17240.05179715115, 14189.29053547321, 14195.077319974194, 14195.432182712679, 14199.92900035501, 14214.573562793106, 723.5803234124195, 857.6097875466647, 874.8294082653869, 887.4054383563029, 895.6506962284682, 885.2961895293225, 885.4642941020686, 888.7467071884514, 888.844452220862, 889.1020069729555, 916.083916083916, 918.196577994255, 919.0671468459519, 919.3151102428667, 919.7010971434278, 766.3830616353833, 908.318760929303, 921.8525035994243, 930.2883294279814, 932.3287233192091, 686.7767975408012, 687.2131409536054, 687.5737043033301, 687.9105716256904, 688.0779755128152, 15955.840441595552, 15972.041677499272, 15977.440789572425, 16012.759808602881, 16033.038017718858, 14738.55784326472, 14954.0, 15080.437307755556, 15240.0, 15550.144742039183, 13665.658358541017, 13748.65628359299, 13787.86212137886, 13969.930150349182, 14116.364763585663, 4616.422947131608, 4676.321933319649, 4691.577758001762, 4778.068276686047, 4816.927746083777, 87884.56057719671, 88284.0, 88301.0, 88771.55614221952, 91217.54391228067, 11662.650120496399, 11705.180637355474, 11913.821292680617, 12048.397580120958, 12080.75838483243, 23076.307710768702, 23077.76922230756, 23091.307260782203, 23114.422139446648, 23121.190758323377, 9055.954720226357, 9158.67944621935, 9266.212371948324, 9274.95362523183, 9622.13400793918, 4939.604831613462, 5025.773840177201, 5043.697378157322, 5120.769565369567, 5205.687658740488, 3528.2414280929725, 3661.2677464507206, 3665.486831843544, 3668.9816550917076, 3672.7979961102396, 645.5803727577113, 646.5086534233922, 652.1326635574675, 661.6195687479718, 671.214678825775, 591.1221835574169, 691.4917535611322, 709.5600727548953, 717.2504732554493, 728.2244409703649, 10254.53854576546, 10649.73375665599, 11108.666739997812, 11631.302121872715, 11642.301461912313, 643.3855667837204, 690.2579726793717, 719.1765428584346, 719.8776208044645, 781.281221276427, 4789.544993225638, 5094.337736094284, 5288.0, 5294.470552944752, 5299.4700529946695, 67665.32334676689, 67896.32103678999, 68003.0, 68374.0, 108198.45900770603, 101034.4948275254, 104282.0, 104855.0, 105500.47249763702, 107909.0, 57168.42831571631, 77169.0, 82168.0, 88951.0, 91127.0, 9255.167034966886, 9308.581113849757, 9354.766130846783, 9740.514989426545, 9774.266929980138, 39054.6094539051, 40629.39055914223, 51181.0, 53519.126830560745, 53723.731381344405, 72432.63783681048, 74670.0, 79458.6027069861, 79663.0, 79990.0, 13212.537561185312, 13737.0, 13739.175649461064, 13753.174809511462, 14012.299385030912, 12288.877111228774, 13912.652183695489, 14432.783508247594, 14802.407903684163, 14963.251837408303, 13250.271235082164, 13807.17156970585, 13879.861201387857, 14578.198199099152, 14777.704445911237, 14576.344064517334, 14861.405543778561, 15455.53633391, 15551.377944882079, 15970.520884373487, 13709.725805483635, 13729.519466818614, 14519.274036297931, 14809.0, 15003.6999260012, 12009.939950300193, 12416.847028385348, 13012.804807928078, 13170.275634840176, 13203.0, 12172.45223964906, 12217.389130543615, 12298.0, 12560.874391256337, 12676.302803345905], "value": 7909.155258435012, "unit": "score"}], "name": "dromaeo_css", "value": 7610.520054051672, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 110.0, 112.0, 111.0, 111.0, 111.0, 112.0, 112.0, 111.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 117.0, 175.0, 116.0, 117.0, 144.0, 119.0, 153.0, 125.0], "value": 132.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 160.0, 160.0, 162.0, 163.0, 161.0, 161.0, 159.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 114.0, 75.0, 75.0, 74.0, 75.0, 109.0, 75.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 128.0, 84.0, 92.0, 85.0, 117.0, 83.0, 84.0, 131.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 50.0, 50.0, 50.0, 51.0, 50.0, 50.0, 49.0, 50.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 60.0, 59.0, 60.0, 59.0, 61.0, 63.0, 60.0, 59.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 72.0, 73.0, 72.0, 72.0, 72.0, 72.0, 72.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 118.0, 134.0, 130.0, 116.0, 119.0, 117.0, 117.0, 201.0, 125.0], "value": 130.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 150.0, 150.0, 156.0, 154.0, 150.0, 151.0, 157.0, 150.0, 151.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 60.4, "unit": "score"}], "name": "kraken", "value": 1465.5, "alertThreshold": 2.0}]} 04:17:06 INFO - Return code: 0 04:17:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:17:06 INFO - # TBPL SUCCESS # 04:17:06 INFO - Running post-action listener: _resource_record_post_action 04:17:06 INFO - [mozharness: 2016-09-07 11:17:06.794130Z] Finished run-tests step (success) 04:17:06 INFO - Running post-run listener: _resource_record_post_run 04:17:06 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 778240; Write bytes: 325562368; Read time: 560; Write time: 291800 04:17:06 INFO - TinderboxPrint: CPU usage
13.0% 04:17:06 INFO - TinderboxPrint: I/O read bytes / time
778,240 / 560 04:17:06 INFO - TinderboxPrint: I/O write bytes / time
325,562,368 / 291,800 04:17:06 INFO - TinderboxPrint: CPU idle
8,274.9 (87.0%) 04:17:06 INFO - TinderboxPrint: CPU user
1,189.0 (12.5%) 04:17:06 INFO - TinderboxPrint: Swap in / out
0 / 0 04:17:06 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 77176832; Read time: 288; Write time: 60836 04:17:06 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 770048; Write bytes: 247963648; Read time: 256; Write time: 230900 04:17:07 INFO - Running post-run listener: _upload_blobber_files 04:17:07 INFO - Blob upload gear active. 04:17:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:17:07 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 04:17:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:17:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:17:07 INFO - (blobuploader) - INFO - Open directory for files ... 04:17:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:17:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:17:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:17:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:17:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:17:11 INFO - (blobuploader) - INFO - Done attempting. 04:17:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:17:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:17:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:17:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:17:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:17:12 INFO - (blobuploader) - INFO - Done attempting. 04:17:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:17:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:17:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:17:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:17:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:17:13 INFO - (blobuploader) - INFO - Done attempting. 04:17:13 INFO - (blobuploader) - INFO - Iteration through files over. 04:17:13 INFO - Return code: 0 04:17:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:17:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:17:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/394bc6277a0fd8a5206fd340a4f9c749a869175f3af18424833b0ad64b9005ce89960d3e48066a0ed3cfd467039d6250e213bb44d93affd19bfcadbc35f1d634", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/beb70ea9228f34c3a935d8b9a2d3d101d6a588b40b8cfdf41442aa11050753a5bce098049a6de7729e9aa7f54b7213066d13f2e3169e84def6562159338c5910", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08bb961bcdf621fa15c9c5c284ee379560f93e8759a264c00e51eb6396c793afe56c52a6dab87dc1c805f881aac9cc1fab20838019f63c74c643aa7a0390f30e"} 04:17:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:17:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:17:13 INFO - Contents: 04:17:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/394bc6277a0fd8a5206fd340a4f9c749a869175f3af18424833b0ad64b9005ce89960d3e48066a0ed3cfd467039d6250e213bb44d93affd19bfcadbc35f1d634", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/beb70ea9228f34c3a935d8b9a2d3d101d6a588b40b8cfdf41442aa11050753a5bce098049a6de7729e9aa7f54b7213066d13f2e3169e84def6562159338c5910", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08bb961bcdf621fa15c9c5c284ee379560f93e8759a264c00e51eb6396c793afe56c52a6dab87dc1c805f881aac9cc1fab20838019f63c74c643aa7a0390f30e"} 04:17:13 INFO - Running post-run listener: copy_logs_to_upload_dir 04:17:13 INFO - Copying logs to upload dir... 04:17:13 INFO - mkdir: /builds/slave/test/build/upload/logs 04:17:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.278326 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-07 04:17:13.306277) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 04:17:13.309378) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/394bc6277a0fd8a5206fd340a4f9c749a869175f3af18424833b0ad64b9005ce89960d3e48066a0ed3cfd467039d6250e213bb44d93affd19bfcadbc35f1d634", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/beb70ea9228f34c3a935d8b9a2d3d101d6a588b40b8cfdf41442aa11050753a5bce098049a6de7729e9aa7f54b7213066d13f2e3169e84def6562159338c5910", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08bb961bcdf621fa15c9c5c284ee379560f93e8759a264c00e51eb6396c793afe56c52a6dab87dc1c805f881aac9cc1fab20838019f63c74c643aa7a0390f30e"} build_url:https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011284 build_url: 'https://queue.taskcluster.net/v1/task/NGXq8ju_TMm4i5UGKYKdWQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/394bc6277a0fd8a5206fd340a4f9c749a869175f3af18424833b0ad64b9005ce89960d3e48066a0ed3cfd467039d6250e213bb44d93affd19bfcadbc35f1d634", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/beb70ea9228f34c3a935d8b9a2d3d101d6a588b40b8cfdf41442aa11050753a5bce098049a6de7729e9aa7f54b7213066d13f2e3169e84def6562159338c5910", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08bb961bcdf621fa15c9c5c284ee379560f93e8759a264c00e51eb6396c793afe56c52a6dab87dc1c805f881aac9cc1fab20838019f63c74c643aa7a0390f30e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 04:17:13.334002) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 04:17:13.334297) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1473244035.672435-1548701252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004410 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 04:17:13.417441) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 04:17:13.417715) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 04:17:13.417994) ========= ========= Total master_lag: 0.17 =========