builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-038 starttime: 1476571244.03 results: success (0) buildid: 20161015124135 builduid: f4e7374c73384f07a9fd7606d222592f revision: c03e51cec3b5f6b8821687c8db8be309727d5470 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.028382) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.028844) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.029213) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.045275) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.045793) ========= 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=8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-15 15:40:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.9M=0s 2016-10-15 15:40:44 (46.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117498 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.179214) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.179549) ========= 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=8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018375 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.228934) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.229320) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c03e51cec3b5f6b8821687c8db8be309727d5470 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c03e51cec3b5f6b8821687c8db8be309727d5470 --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=8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-15 15:40:44,892 truncating revision to first 12 chars 2016-10-15 15:40:44,893 Setting DEBUG logging. 2016-10-15 15:40:44,893 attempt 1/10 2016-10-15 15:40:44,893 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c03e51cec3b5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-15 15:40:45,215 unpacking tar archive at: mozilla-beta-c03e51cec3b5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.732492 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.975228) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.975587) ========= script_repo_revision: c03e51cec3b5f6b8821687c8db8be309727d5470 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.976044) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.976382) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.987647) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-10-15 15:40:44.988037) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:40:45 INFO - MultiFileLogger online at 20161015 15:40:45 in /builds/slave/test 15:40:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:40:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:40:45 INFO - {'append_to_log': False, 15:40:45 INFO - 'base_work_dir': '/builds/slave/test', 15:40:45 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:40:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:40:45 INFO - 'branch': 'Mozilla-Beta', 15:40:45 INFO - 'buildbot_json_path': 'buildprops.json', 15:40:45 INFO - 'config_files': ('talos/linux_config.py',), 15:40:45 INFO - 'default_actions': ('clobber', 15:40:45 INFO - 'read-buildbot-config', 15:40:45 INFO - 'download-and-extract', 15:40:45 INFO - 'populate-webroot', 15:40:45 INFO - 'create-virtualenv', 15:40:45 INFO - 'install', 15:40:45 INFO - 'run-tests'), 15:40:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:40:45 INFO - 'download_minidump_stackwalk': True, 15:40:45 INFO - 'download_symbols': 'ondemand', 15:40:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:40:45 INFO - 'tooltool.py': '/tools/tooltool.py', 15:40:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:40:45 INFO - '/tools/misc-python/virtualenv.py')}, 15:40:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:40:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:40:45 INFO - 'installer_path': 'installer.exe', 15:40:45 INFO - 'log_level': 'info', 15:40:45 INFO - 'log_name': 'talos', 15:40:45 INFO - 'log_to_console': True, 15:40:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:40:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:40:45 INFO - 'opt_config_files': (), 15:40:45 INFO - 'pip_index': False, 15:40:45 INFO - 'sps_profile': False, 15:40:45 INFO - 'sps_profile_interval': 0, 15:40:45 INFO - 'suite': 'dromaeojs-e10s', 15:40:45 INFO - 'system_bits': '32', 15:40:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:40:45 INFO - 'title': 'talos-linux64-ix-038', 15:40:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:40:45 INFO - 'use_talos_json': True, 15:40:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:40:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:40:45 INFO - 'work_dir': 'build'} 15:40:45 INFO - [mozharness: 2016-10-15 22:40:45.746370Z] Running clobber step. 15:40:45 INFO - Running pre-action listener: _resource_record_pre_action 15:40:45 INFO - Running main action method: clobber 15:40:45 INFO - rmtree: /builds/slave/test/build 15:40:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:40:46 INFO - Running post-action listener: _resource_record_post_action 15:40:46 INFO - [mozharness: 2016-10-15 22:40:46.732281Z] Finished clobber step (success) 15:40:46 INFO - [mozharness: 2016-10-15 22:40:46.732382Z] Running read-buildbot-config step. 15:40:46 INFO - Running pre-action listener: _resource_record_pre_action 15:40:46 INFO - Running main action method: read_buildbot_config 15:40:46 INFO - Using buildbot properties: 15:40:46 INFO - { 15:40:46 INFO - "project": "", 15:40:46 INFO - "product": "firefox", 15:40:46 INFO - "script_repo_revision": "production", 15:40:46 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 15:40:46 INFO - "repository": "", 15:40:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 15:40:46 INFO - "buildid": "20161015124135", 15:40:46 INFO - "pgo_build": "True", 15:40:46 INFO - "basedir": "/builds/slave/test", 15:40:46 INFO - "buildnumber": 198, 15:40:46 INFO - "slavename": "talos-linux64-ix-038", 15:40:46 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:40:46 INFO - "platform": "ubuntu64_hw", 15:40:46 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:40:46 INFO - "branch": "mozilla-beta", 15:40:46 INFO - "repo_path": "releases/mozilla-beta", 15:40:46 INFO - "revision": "c03e51cec3b5f6b8821687c8db8be309727d5470", 15:40:46 INFO - "stage_platform": "linux64-pgo", 15:40:46 INFO - "builduid": "f4e7374c73384f07a9fd7606d222592f", 15:40:46 INFO - "slavebuilddir": "test" 15:40:46 INFO - } 15:40:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 15:40:46 INFO - Running post-action listener: _resource_record_post_action 15:40:46 INFO - [mozharness: 2016-10-15 22:40:46.734833Z] Finished read-buildbot-config step (success) 15:40:46 INFO - [mozharness: 2016-10-15 22:40:46.734922Z] Running download-and-extract step. 15:40:46 INFO - Running pre-action listener: _resource_record_pre_action 15:40:46 INFO - Running main action method: download_and_extract 15:40:46 INFO - mkdir: /builds/slave/test/build/tests 15:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:46 INFO - https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:40:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:40:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:40:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:40:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:40:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:40:46 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:40:46 INFO - retry: Failed, sleeping 30 seconds before retrying 15:41:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 15:41:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:41:16 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:41:16 INFO - retry: Failed, sleeping 60 seconds before retrying 15:42:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 15:42:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:42:16 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:42:16 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 15:42:16 INFO - Caught exception: HTTP Error 404: Not Found 15:42:16 INFO - Caught exception: HTTP Error 404: Not Found 15:42:16 INFO - Caught exception: HTTP Error 404: Not Found 15:42:16 INFO - trying https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:42:16 INFO - Downloading https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:42:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:42:17 INFO - Downloaded 1327 bytes. 15:42:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:42:17 INFO - Using the following test package requirements: 15:42:17 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 15:42:17 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:42:17 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:42:17 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:42:17 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 15:42:17 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:42:17 INFO - u'jsshell-linux-x86_64.zip'], 15:42:17 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:42:17 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:42:17 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 15:42:17 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:42:17 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 15:42:17 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:42:17 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 15:42:17 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:42:17 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:42:17 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:42:17 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:42:17 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:42: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')]} 15:42:17 INFO - https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:42:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:42:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:42:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:42:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:42:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:42:17 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:42:17 INFO - retry: Failed, sleeping 30 seconds before retrying 15:42:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:42:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:42:47 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:42:47 INFO - retry: Failed, sleeping 60 seconds before retrying 15:43:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:43:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:43:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:43:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 15:43:47 INFO - Caught exception: HTTP Error 404: Not Found 15:43:47 INFO - Caught exception: HTTP Error 404: Not Found 15:43:47 INFO - Caught exception: HTTP Error 404: Not Found 15:43:47 INFO - trying https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:43:47 INFO - Downloading https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:43:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:43:48 INFO - Downloaded 21773213 bytes. 15:43:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:43:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:43:49 INFO - Return code: 0 15:43:49 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:43: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')]} 15:43:49 INFO - https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:43:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:43:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:43:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:43:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:43:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:43:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:43:49 INFO - retry: Failed, sleeping 30 seconds before retrying 15:44:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:44:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:44:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:44:19 INFO - retry: Failed, sleeping 60 seconds before retrying 15:45:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:45:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:45:19 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:45:19 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 15:45:19 INFO - Caught exception: HTTP Error 404: Not Found 15:45:19 INFO - Caught exception: HTTP Error 404: Not Found 15:45:19 INFO - Caught exception: HTTP Error 404: Not Found 15:45:19 INFO - trying https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:45:19 INFO - Downloading https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:45:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:45:20 INFO - Downloaded 21773213 bytes. 15:45:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:45:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:45:22 INFO - Return code: 0 15:45:22 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')]} 15:45:22 INFO - https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:45:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:45:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:45:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:45:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:45:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:45:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:45:22 INFO - retry: Failed, sleeping 30 seconds before retrying 15:45:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 15:45:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:45:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:45:52 INFO - retry: Failed, sleeping 60 seconds before retrying 15:46:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 15:46:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:46:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:46:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 15:46:52 INFO - Caught exception: HTTP Error 404: Not Found 15:46:52 INFO - Caught exception: HTTP Error 404: Not Found 15:46:52 INFO - Caught exception: HTTP Error 404: Not Found 15:46:52 INFO - trying https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:46:52 INFO - Downloading https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:46:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:46:53 INFO - Downloaded 14413762 bytes. 15:46:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:46:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:46:54 INFO - Return code: 0 15:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:46:54 INFO - https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:46:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:46:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:46:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:46:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:46:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:46:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:46:54 INFO - retry: Failed, sleeping 30 seconds before retrying 15:47:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 15:47:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:47:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:47:24 INFO - retry: Failed, sleeping 60 seconds before retrying 15:48:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 15:48:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:48:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:48:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 15:48:24 INFO - Caught exception: HTTP Error 404: Not Found 15:48:24 INFO - Caught exception: HTTP Error 404: Not Found 15:48:24 INFO - Caught exception: HTTP Error 404: Not Found 15:48:24 INFO - trying https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:48:24 INFO - Downloading https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:48:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:48:25 INFO - Downloaded 56794117 bytes. 15:48:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:48:25 INFO - mkdir: /builds/slave/test/properties 15:48:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:48:25 INFO - Writing to file /builds/slave/test/properties/build_url 15:48:25 INFO - Contents: 15:48:25 INFO - build_url:https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:48:26 INFO - Running post-action listener: _resource_record_post_action 15:48:26 INFO - Running post-action listener: set_extra_try_arguments 15:48:26 INFO - [mozharness: 2016-10-15 22:48:26.401602Z] Finished download-and-extract step (success) 15:48:26 INFO - [mozharness: 2016-10-15 22:48:26.401834Z] Running populate-webroot step. 15:48:26 INFO - Running pre-action listener: _resource_record_pre_action 15:48:26 INFO - Running main action method: populate_webroot 15:48:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:48:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:48:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:48:26 INFO - '--tppagecycles', 15:48:26 INFO - '7'], 15:48:26 INFO - 'tests': ['tsvgm']}}, 15:48:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:48:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:48:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:48:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:48:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:48:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:48:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:26 INFO - 'talos_options': ['--disable-e10s'], 15:48:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:26 INFO - 'talos_options': ['--disable-e10s'], 15:48:26 INFO - 'tests': ['damp', 'tps']}, 15:48:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:26 INFO - 'tests': ['damp', 'tps']}, 15:48:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:48:26 INFO - 'tests': ['dromaeo_dom']}, 15:48:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:48:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:48:26 INFO - 'tests': ['basic_compositor_video']}, 15:48:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:48:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:48:26 INFO - 'tests': ['a11yr', 15:48:26 INFO - 'ts_paint', 15:48:26 INFO - 'tpaint', 15:48:26 INFO - 'sessionrestore', 15:48:26 INFO - 'sessionrestore_no_auto_restore', 15:48:26 INFO - 'tabpaint']}, 15:48:26 INFO - 'other-e10s': {'tests': ['a11yr', 15:48:26 INFO - 'ts_paint', 15:48:26 INFO - 'tpaint', 15:48:26 INFO - 'sessionrestore', 15:48:26 INFO - 'sessionrestore_no_auto_restore', 15:48:26 INFO - 'tabpaint']}, 15:48:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:48:26 INFO - 'ts_paint', 15:48:26 INFO - 'tpaint', 15:48:26 INFO - 'sessionrestore', 15:48:26 INFO - 'sessionrestore_no_auto_restore', 15:48:26 INFO - 'tabpaint']}, 15:48:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:48:26 INFO - 'ts_paint', 15:48:26 INFO - 'tpaint', 15:48:26 INFO - 'sessionrestore', 15:48:26 INFO - 'sessionrestore_no_auto_restore', 15:48:26 INFO - 'tabpaint']}, 15:48:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:48:26 INFO - 'tests': ['a11yr', 15:48:26 INFO - 'ts_paint', 15:48:26 INFO - 'tpaint', 15:48:26 INFO - 'sessionrestore', 15:48:26 INFO - 'sessionrestore_no_auto_restore', 15:48:26 INFO - 'tabpaint']}, 15:48:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:48:26 INFO - 'tests': ['a11yr', 15:48:26 INFO - 'ts_paint', 15:48:26 INFO - 'tpaint', 15:48:26 INFO - 'sessionrestore', 15:48:26 INFO - 'sessionrestore_no_auto_restore', 15:48:26 INFO - 'tabpaint']}, 15:48:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:48:26 INFO - 'tests': ['tsvgx', 15:48:26 INFO - 'tsvgr_opacity', 15:48:26 INFO - 'tart', 15:48:26 INFO - 'tscrollx', 15:48:26 INFO - 'cart']}, 15:48:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:48:26 INFO - 'tsvgr_opacity', 15:48:26 INFO - 'tart', 15:48:26 INFO - 'tscrollx', 15:48:26 INFO - 'cart']}, 15:48:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:26 INFO - 'talos_options': ['--disable-e10s'], 15:48:26 INFO - 'tests': ['tp5o']}, 15:48:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:26 INFO - 'tests': ['tp5o']}, 15:48:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:26 INFO - 'talos_options': ['--disable-e10s', 15:48:26 INFO - '--xperf_path', 15:48:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:26 INFO - 'tests': ['tp5n']}, 15:48:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:26 INFO - 'talos_options': ['--xperf_path', 15:48:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:26 INFO - 'tests': ['tp5n']}}, 15:48:26 INFO - 'talos.zip': {'path': '', 15:48:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:48:26 INFO - Running post-action listener: _resource_record_post_action 15:48:26 INFO - [mozharness: 2016-10-15 22:48:26.422513Z] Finished populate-webroot step (success) 15:48:26 INFO - [mozharness: 2016-10-15 22:48:26.422602Z] Running create-virtualenv step. 15:48:26 INFO - Running pre-action listener: _resource_record_pre_action 15:48:26 INFO - Running main action method: create_virtualenv 15:48:26 INFO - Creating virtualenv /builds/slave/test/build/venv 15:48:26 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 15:48:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:48:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:48:26 INFO - Using real prefix '/usr' 15:48:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:48:27 INFO - Installing distribute.............................................................................................................................................................................................done. 15:48:28 INFO - Installing pip.................done. 15:48:28 INFO - Return code: 0 15:48:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:48:28 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')]} 15:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:28 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')]} 15:48:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:28 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 0x263f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd570>, '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 0x28fe470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fe900>, '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': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', '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 15:48:28 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 15:48:28 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 15:48:28 INFO - Using env: {'DISPLAY': ':0', 15:48:28 INFO - 'HOME': '/home/cltbld', 15:48:28 INFO - 'LANG': 'en_US.UTF-8', 15:48:28 INFO - 'LANGUAGE': 'en_US:en', 15:48:28 INFO - 'LOGNAME': 'cltbld', 15:48:28 INFO - 'MAIL': '/var/mail/cltbld', 15:48:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:28 INFO - 'MOZ_NO_REMOTE': '1', 15:48:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:28 INFO - 'NO_EM_RESTART': '1', 15:48:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:28 INFO - 'PWD': '/builds/slave/test', 15:48:28 INFO - 'SHELL': '/bin/bash', 15:48:28 INFO - 'SHLVL': '1', 15:48:28 INFO - 'TERM': 'linux', 15:48:28 INFO - 'TMOUT': '86400', 15:48:28 INFO - 'USER': 'cltbld', 15:48:28 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', 15:48:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:28 INFO - '_': '/tools/buildbot/bin/python'} 15:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:29 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 15:48:29 INFO - Cleaning up... 15:48:29 INFO - Return code: 0 15:48:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x263f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd570>, '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 0x28fe470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fe900>, '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': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', '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 15:48:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:48:29 INFO - Using env: {'DISPLAY': ':0', 15:48:29 INFO - 'HOME': '/home/cltbld', 15:48:29 INFO - 'LANG': 'en_US.UTF-8', 15:48:29 INFO - 'LANGUAGE': 'en_US:en', 15:48:29 INFO - 'LOGNAME': 'cltbld', 15:48:29 INFO - 'MAIL': '/var/mail/cltbld', 15:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:29 INFO - 'MOZ_NO_REMOTE': '1', 15:48:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:29 INFO - 'NO_EM_RESTART': '1', 15:48:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:29 INFO - 'PWD': '/builds/slave/test', 15:48:29 INFO - 'SHELL': '/bin/bash', 15:48:29 INFO - 'SHLVL': '1', 15:48:29 INFO - 'TERM': 'linux', 15:48:29 INFO - 'TMOUT': '86400', 15:48:29 INFO - 'USER': 'cltbld', 15:48:29 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', 15:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:29 INFO - '_': '/tools/buildbot/bin/python'} 15:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:29 INFO - Downloading/unpacking psutil>=3.1.1 15:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:48:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:48:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:48:31 INFO - Installing collected packages: psutil 15:48:31 INFO - Running setup.py install for psutil 15:48:31 INFO - building 'psutil._psutil_linux' extension 15:48:31 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 15:48:31 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 15:48:31 INFO - building 'psutil._psutil_posix' extension 15:48:31 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 15:48:31 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 15:48:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:48:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:48:31 INFO - Successfully installed psutil 15:48:31 INFO - Cleaning up... 15:48:32 INFO - Return code: 0 15:48:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:48:32 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')]} 15:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:32 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')]} 15:48:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:32 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 0x263f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd570>, '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 0x28fe470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fe900>, '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': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', '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 15:48:32 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 15:48:32 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 15:48:32 INFO - Using env: {'DISPLAY': ':0', 15:48:32 INFO - 'HOME': '/home/cltbld', 15:48:32 INFO - 'LANG': 'en_US.UTF-8', 15:48:32 INFO - 'LANGUAGE': 'en_US:en', 15:48:32 INFO - 'LOGNAME': 'cltbld', 15:48:32 INFO - 'MAIL': '/var/mail/cltbld', 15:48:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:32 INFO - 'MOZ_NO_REMOTE': '1', 15:48:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:32 INFO - 'NO_EM_RESTART': '1', 15:48:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:32 INFO - 'PWD': '/builds/slave/test', 15:48:32 INFO - 'SHELL': '/bin/bash', 15:48:32 INFO - 'SHLVL': '1', 15:48:32 INFO - 'TERM': 'linux', 15:48:32 INFO - 'TMOUT': '86400', 15:48:32 INFO - 'USER': 'cltbld', 15:48:32 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', 15:48:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:32 INFO - '_': '/tools/buildbot/bin/python'} 15:48:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:48:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:48:34 INFO - Installing collected packages: mozsystemmonitor 15:48:34 INFO - Running setup.py install for mozsystemmonitor 15:48:34 INFO - Successfully installed mozsystemmonitor 15:48:34 INFO - Cleaning up... 15:48:34 INFO - Return code: 0 15:48:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:48:34 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')]} 15:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:34 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')]} 15:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:34 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 0x263f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd570>, '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 0x28fe470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fe900>, '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': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', '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 15:48:34 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 15:48:34 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 15:48:34 INFO - Using env: {'DISPLAY': ':0', 15:48:34 INFO - 'HOME': '/home/cltbld', 15:48:34 INFO - 'LANG': 'en_US.UTF-8', 15:48:34 INFO - 'LANGUAGE': 'en_US:en', 15:48:34 INFO - 'LOGNAME': 'cltbld', 15:48:34 INFO - 'MAIL': '/var/mail/cltbld', 15:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:34 INFO - 'MOZ_NO_REMOTE': '1', 15:48:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:34 INFO - 'NO_EM_RESTART': '1', 15:48:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:34 INFO - 'PWD': '/builds/slave/test', 15:48:34 INFO - 'SHELL': '/bin/bash', 15:48:34 INFO - 'SHLVL': '1', 15:48:34 INFO - 'TERM': 'linux', 15:48:34 INFO - 'TMOUT': '86400', 15:48:34 INFO - 'USER': 'cltbld', 15:48:34 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', 15:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:34 INFO - '_': '/tools/buildbot/bin/python'} 15:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:34 INFO - Downloading/unpacking jsonschema==2.5.1 15:48:34 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 15:48:34 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 15:48:34 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 15:48:34 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 15:48:34 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 15:48:34 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 15:48:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:48:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:48:36 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 15:48:36 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 15:48:36 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 15:48:36 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 15:48:36 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 15:48:36 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 15:48:36 INFO - Downloading functools32-3.2.3-2.tar.gz 15:48:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:48:36 INFO - warning: no files found matching '*.txt' 15:48:36 INFO - no previously-included directories found matching 'build' 15:48:36 INFO - no previously-included directories found matching 'dist' 15:48:36 INFO - no previously-included directories found matching '.git*' 15:48:36 INFO - Installing collected packages: jsonschema, functools32 15:48:36 INFO - Running setup.py install for functools32 15:48:36 INFO - warning: no files found matching '*.txt' 15:48:36 INFO - no previously-included directories found matching 'build' 15:48:36 INFO - no previously-included directories found matching 'dist' 15:48:36 INFO - no previously-included directories found matching '.git*' 15:48:36 INFO - Successfully installed jsonschema functools32 15:48:36 INFO - Cleaning up... 15:48:36 INFO - Return code: 0 15:48:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:36 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 0x263f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd570>, '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 0x28fe470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fe900>, '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': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', '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 15:48:36 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 15:48:36 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 15:48:36 INFO - Using env: {'DISPLAY': ':0', 15:48:36 INFO - 'HOME': '/home/cltbld', 15:48:36 INFO - 'LANG': 'en_US.UTF-8', 15:48:36 INFO - 'LANGUAGE': 'en_US:en', 15:48:36 INFO - 'LOGNAME': 'cltbld', 15:48:36 INFO - 'MAIL': '/var/mail/cltbld', 15:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:36 INFO - 'MOZ_NO_REMOTE': '1', 15:48:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:36 INFO - 'NO_EM_RESTART': '1', 15:48:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:36 INFO - 'PWD': '/builds/slave/test', 15:48:36 INFO - 'SHELL': '/bin/bash', 15:48:36 INFO - 'SHLVL': '1', 15:48:36 INFO - 'TERM': 'linux', 15:48:36 INFO - 'TMOUT': '86400', 15:48:36 INFO - 'USER': 'cltbld', 15:48:36 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', 15:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:36 INFO - '_': '/tools/buildbot/bin/python'} 15:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:48:37 INFO - Cleaning up... 15:48:37 INFO - Return code: 0 15:48:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:37 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 0x263f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd570>, '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 0x28fe470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fe900>, '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': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', '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 15:48:37 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 15:48:37 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 15:48:37 INFO - Using env: {'DISPLAY': ':0', 15:48:37 INFO - 'HOME': '/home/cltbld', 15:48:37 INFO - 'LANG': 'en_US.UTF-8', 15:48:37 INFO - 'LANGUAGE': 'en_US:en', 15:48:37 INFO - 'LOGNAME': 'cltbld', 15:48:37 INFO - 'MAIL': '/var/mail/cltbld', 15:48:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:37 INFO - 'MOZ_NO_REMOTE': '1', 15:48:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:37 INFO - 'NO_EM_RESTART': '1', 15:48:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:37 INFO - 'PWD': '/builds/slave/test', 15:48:37 INFO - 'SHELL': '/bin/bash', 15:48:37 INFO - 'SHLVL': '1', 15:48:37 INFO - 'TERM': 'linux', 15:48:37 INFO - 'TMOUT': '86400', 15:48:37 INFO - 'USER': 'cltbld', 15:48:37 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', 15:48:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:37 INFO - '_': '/tools/buildbot/bin/python'} 15:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:37 INFO - Downloading/unpacking blobuploader==1.2.4 15:48:37 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 15:48:37 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 15:48:37 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 15:48:37 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 15:48:37 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 15:48:37 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 15:48:39 INFO - Downloading blobuploader-1.2.4.tar.gz 15:48:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:48:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:48:39 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 15:48:39 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 15:48:39 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 15:48:39 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 15:48:39 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 15:48:39 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 15:48:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:48:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:48:39 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 15:48:39 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 15:48:39 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 15:48:39 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 15:48:39 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 15:48:39 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 15:48:39 INFO - Downloading docopt-0.6.1.tar.gz 15:48:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:48:40 INFO - Installing collected packages: blobuploader, requests, docopt 15:48:40 INFO - Running setup.py install for blobuploader 15:48:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:48:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:48:40 INFO - Running setup.py install for requests 15:48:40 INFO - Running setup.py install for docopt 15:48:40 INFO - Successfully installed blobuploader requests docopt 15:48:40 INFO - Cleaning up... 15:48:40 INFO - Return code: 0 15:48:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:40 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 0x263f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd570>, '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 0x28fe470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fe900>, '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': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', '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 15:48:40 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 15:48:40 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 15:48:40 INFO - Using env: {'DISPLAY': ':0', 15:48:40 INFO - 'HOME': '/home/cltbld', 15:48:40 INFO - 'LANG': 'en_US.UTF-8', 15:48:40 INFO - 'LANGUAGE': 'en_US:en', 15:48:40 INFO - 'LOGNAME': 'cltbld', 15:48:40 INFO - 'MAIL': '/var/mail/cltbld', 15:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:40 INFO - 'MOZ_NO_REMOTE': '1', 15:48:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:40 INFO - 'NO_EM_RESTART': '1', 15:48:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:40 INFO - 'PWD': '/builds/slave/test', 15:48:40 INFO - 'SHELL': '/bin/bash', 15:48:40 INFO - 'SHLVL': '1', 15:48:40 INFO - 'TERM': 'linux', 15:48:40 INFO - 'TMOUT': '86400', 15:48:40 INFO - 'USER': 'cltbld', 15:48:40 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', 15:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:40 INFO - '_': '/tools/buildbot/bin/python'} 15:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:48:40 INFO - Running setup.py (path:/tmp/pip-fy214n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:48:40 INFO - Running setup.py (path:/tmp/pip-omy0DU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:48:41 INFO - Running setup.py (path:/tmp/pip-Yxeghg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:48:41 INFO - Running setup.py (path:/tmp/pip-HHYJXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:48:41 INFO - Running setup.py (path:/tmp/pip-8KvU4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:48:41 INFO - Running setup.py (path:/tmp/pip-PXtDiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:48:41 INFO - Running setup.py (path:/tmp/pip-z1hJxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:48:41 INFO - Running setup.py (path:/tmp/pip-MJH6j4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:48:41 INFO - Running setup.py (path:/tmp/pip-iN9fLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:48:41 INFO - Running setup.py (path:/tmp/pip-H3tXni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:48:41 INFO - Running setup.py (path:/tmp/pip-JSMyUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:48:41 INFO - Running setup.py (path:/tmp/pip-ihRTgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:48:41 INFO - Running setup.py (path:/tmp/pip-88gk58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:48:41 INFO - Running setup.py (path:/tmp/pip-_x9E1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:41 INFO - Running setup.py (path:/tmp/pip-_Lkkym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:48:41 INFO - Running setup.py (path:/tmp/pip-GUDt0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:48:42 INFO - Running setup.py (path:/tmp/pip-XjDpA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:48:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:48:42 INFO - Running setup.py install for manifestparser 15:48:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:48:42 INFO - Running setup.py install for mozcrash 15:48:42 INFO - Running setup.py install for mozdebug 15:48:42 INFO - Running setup.py install for mozdevice 15:48:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:48:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:48:42 INFO - Running setup.py install for mozfile 15:48:42 INFO - Running setup.py install for mozhttpd 15:48:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:48:42 INFO - Running setup.py install for mozinfo 15:48:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:48:42 INFO - Running setup.py install for mozInstall 15:48:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:48:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:48:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:48:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:48:42 INFO - Running setup.py install for mozleak 15:48:42 INFO - Running setup.py install for mozlog 15:48:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:48:43 INFO - Running setup.py install for moznetwork 15:48:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:48:43 INFO - Running setup.py install for mozprocess 15:48:43 INFO - Running setup.py install for mozprofile 15:48:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:48:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:48:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:48:43 INFO - Running setup.py install for mozrunner 15:48:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:48:43 INFO - Running setup.py install for mozscreenshot 15:48:43 INFO - Running setup.py install for moztest 15:48:43 INFO - Running setup.py install for mozversion 15:48:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:48:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:48:43 INFO - Cleaning up... 15:48:43 INFO - Return code: 0 15:48:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:43 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 0x263f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd570>, '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 0x28fe470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fe900>, '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': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', '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 15:48:43 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 15:48:43 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 15:48:43 INFO - Using env: {'DISPLAY': ':0', 15:48:43 INFO - 'HOME': '/home/cltbld', 15:48:43 INFO - 'LANG': 'en_US.UTF-8', 15:48:43 INFO - 'LANGUAGE': 'en_US:en', 15:48:43 INFO - 'LOGNAME': 'cltbld', 15:48:43 INFO - 'MAIL': '/var/mail/cltbld', 15:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:43 INFO - 'MOZ_NO_REMOTE': '1', 15:48:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:43 INFO - 'NO_EM_RESTART': '1', 15:48:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:43 INFO - 'PWD': '/builds/slave/test', 15:48:43 INFO - 'SHELL': '/bin/bash', 15:48:43 INFO - 'SHLVL': '1', 15:48:43 INFO - 'TERM': 'linux', 15:48:43 INFO - 'TMOUT': '86400', 15:48:43 INFO - 'USER': 'cltbld', 15:48:43 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', 15:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:43 INFO - '_': '/tools/buildbot/bin/python'} 15:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:48:44 INFO - Running setup.py (path:/tmp/pip-hJKWS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:48:44 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)) 15:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:48:44 INFO - Running setup.py (path:/tmp/pip-4DIX9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:48:44 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)) 15:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:48:44 INFO - Running setup.py (path:/tmp/pip-HTyj49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:48:44 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)) 15:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:48:44 INFO - Running setup.py (path:/tmp/pip-Oj7Z9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:48:44 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)) 15:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:48:44 INFO - Running setup.py (path:/tmp/pip-0QAaYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:48:44 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)) 15:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:48:44 INFO - Running setup.py (path:/tmp/pip-wiBYh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:48:44 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)) 15:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:48:44 INFO - Running setup.py (path:/tmp/pip-cGFYvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:48:44 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)) 15:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:48:44 INFO - Running setup.py (path:/tmp/pip-41bcAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:48:44 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)) 15:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:48:44 INFO - Running setup.py (path:/tmp/pip-AFKdw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:48:44 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)) 15:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:48:44 INFO - Running setup.py (path:/tmp/pip-yRwLpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:48:44 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)) 15:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:48:44 INFO - Running setup.py (path:/tmp/pip-0s6OQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:48:44 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)) 15:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:48:44 INFO - Running setup.py (path:/tmp/pip-MYQtI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:48:44 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)) 15:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:48:44 INFO - Running setup.py (path:/tmp/pip-IVFBuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:48:45 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)) 15:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:48:45 INFO - Running setup.py (path:/tmp/pip-gQHof7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:48:45 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)) 15:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:45 INFO - Running setup.py (path:/tmp/pip-O_rheb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:45 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)) 15:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:48:45 INFO - Running setup.py (path:/tmp/pip-0sv4yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:48:45 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)) 15:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:48:45 INFO - Running setup.py (path:/tmp/pip-46gqHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:48:45 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)) 15:48:45 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)) 15:48:45 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)) 15:48:45 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)) 15:48:45 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)) 15:48:45 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)) 15:48:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:48:45 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 15:48:45 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 15:48:45 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 15:48:45 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 15:48:45 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 15:48:45 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 15:48:47 INFO - Downloading blessings-1.6.tar.gz 15:48:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:48:47 INFO - Installing collected packages: blessings 15:48:47 INFO - Running setup.py install for blessings 15:48:47 INFO - Successfully installed blessings 15:48:47 INFO - Cleaning up... 15:48:47 INFO - Return code: 0 15:48:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:48:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:48:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:48:47 INFO - Reading from file tmpfile_stdout 15:48:47 INFO - Current package versions: 15:48:47 INFO - argparse == 1.2.1 15:48:47 INFO - blessings == 1.6 15:48:47 INFO - blobuploader == 1.2.4 15:48:47 INFO - docopt == 0.6.1 15:48:47 INFO - functools32 == 3.2.3-2 15:48:47 INFO - jsonschema == 2.5.1 15:48:47 INFO - manifestparser == 1.1 15:48:47 INFO - mozInstall == 1.12 15:48:47 INFO - mozcrash == 0.17 15:48:47 INFO - mozdebug == 0.1 15:48:47 INFO - mozdevice == 0.48 15:48:47 INFO - mozfile == 1.2 15:48:47 INFO - mozhttpd == 0.7 15:48:47 INFO - mozinfo == 0.9 15:48:47 INFO - mozleak == 0.1 15:48:47 INFO - mozlog == 3.3 15:48:47 INFO - moznetwork == 0.27 15:48:47 INFO - mozprocess == 0.23 15:48:47 INFO - mozprofile == 0.28 15:48:47 INFO - mozrunner == 6.12 15:48:47 INFO - mozscreenshot == 0.1 15:48:47 INFO - mozsystemmonitor == 0.3 15:48:47 INFO - moztest == 0.7 15:48:47 INFO - mozversion == 1.4 15:48:47 INFO - psutil == 3.1.1 15:48:47 INFO - requests == 1.2.3 15:48:47 INFO - wsgiref == 0.1.2 15:48:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:47 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')]} 15:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:47 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')]} 15:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:47 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 0x263f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd570>, '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 0x28fe470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fe900>, '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': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', '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 15:48:47 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 15:48:47 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 15:48:47 INFO - Using env: {'DISPLAY': ':0', 15:48:47 INFO - 'HOME': '/home/cltbld', 15:48:47 INFO - 'LANG': 'en_US.UTF-8', 15:48:47 INFO - 'LANGUAGE': 'en_US:en', 15:48:47 INFO - 'LOGNAME': 'cltbld', 15:48:47 INFO - 'MAIL': '/var/mail/cltbld', 15:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:47 INFO - 'MOZ_NO_REMOTE': '1', 15:48:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:47 INFO - 'NO_EM_RESTART': '1', 15:48:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:47 INFO - 'PWD': '/builds/slave/test', 15:48:47 INFO - 'SHELL': '/bin/bash', 15:48:47 INFO - 'SHLVL': '1', 15:48:47 INFO - 'TERM': 'linux', 15:48:47 INFO - 'TMOUT': '86400', 15:48:47 INFO - 'USER': 'cltbld', 15:48:47 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', 15:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:47 INFO - '_': '/tools/buildbot/bin/python'} 15:48:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:47 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)) 15:48:47 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)) 15:48:47 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)) 15:48:47 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)) 15:48:47 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)) 15:48:47 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)) 15:48:47 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)) 15:48:47 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)) 15:48:47 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)) 15:48:47 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)) 15:48:47 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)) 15:48:47 INFO - Cleaning up... 15:48:47 INFO - Return code: 0 15:48:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:48:47 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')]} 15:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:47 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')]} 15:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:47 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 0x263f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286e400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd570>, '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 0x28fe470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fe900>, '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': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', '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 15:48:47 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 15:48:47 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 15:48:47 INFO - Using env: {'DISPLAY': ':0', 15:48:47 INFO - 'HOME': '/home/cltbld', 15:48:47 INFO - 'LANG': 'en_US.UTF-8', 15:48:47 INFO - 'LANGUAGE': 'en_US:en', 15:48:47 INFO - 'LOGNAME': 'cltbld', 15:48:47 INFO - 'MAIL': '/var/mail/cltbld', 15:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:47 INFO - 'MOZ_NO_REMOTE': '1', 15:48:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:47 INFO - 'NO_EM_RESTART': '1', 15:48:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:47 INFO - 'PWD': '/builds/slave/test', 15:48:47 INFO - 'SHELL': '/bin/bash', 15:48:47 INFO - 'SHLVL': '1', 15:48:47 INFO - 'TERM': 'linux', 15:48:47 INFO - 'TMOUT': '86400', 15:48:47 INFO - 'USER': 'cltbld', 15:48:47 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', 15:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:47 INFO - '_': '/tools/buildbot/bin/python'} 15:48:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:48:48 INFO - Cleaning up... 15:48:48 INFO - Return code: 0 15:48:48 INFO - Running post-action listener: _resource_record_post_action 15:48:48 INFO - Running post-action listener: _start_resource_monitoring 15:48:48 INFO - Starting resource monitoring. 15:48:48 INFO - [mozharness: 2016-10-15 22:48:48.151311Z] Finished create-virtualenv step (success) 15:48:48 INFO - [mozharness: 2016-10-15 22:48:48.151790Z] Running install step. 15:48:48 INFO - Running pre-action listener: _resource_record_pre_action 15:48:48 INFO - Running main action method: install 15:48:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:48:48 INFO - Reading from file tmpfile_stdout 15:48:48 INFO - Detecting whether we're running mozinstall >=1.0... 15:48:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:48:48 INFO - Reading from file tmpfile_stdout 15:48:48 INFO - Output received: 15:48:48 INFO - Usage: mozinstall [options] installer 15:48:48 INFO - Options: 15:48:48 INFO - -h, --help show this help message and exit 15:48:48 INFO - -d DEST, --destination=DEST 15:48:48 INFO - Directory to install application into. [default: 15:48:48 INFO - "/builds/slave/test"] 15:48:48 INFO - --app=APP Application being installed. [default: firefox] 15:48:48 INFO - mkdir: /builds/slave/test/build/application 15:48:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:49:03 INFO - Reading from file tmpfile_stdout 15:49:03 INFO - Output received: 15:49:03 INFO - /builds/slave/test/build/application/firefox/firefox 15:49:03 INFO - Running post-action listener: _resource_record_post_action 15:49:03 INFO - [mozharness: 2016-10-15 22:49:03.846691Z] Finished install step (success) 15:49:03 INFO - [mozharness: 2016-10-15 22:49:03.846966Z] Running run-tests step. 15:49:03 INFO - Running pre-action listener: _resource_record_pre_action 15:49:03 INFO - Running main action method: run_tests 15:49:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:49:03 INFO - Python 2.7.3 15:49:03 INFO - Return code: 0 15:49:03 INFO - grabbing minidump binary from tooltool 15:49: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')]} 15:49:03 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 0x28fd570>, '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 0x28fe470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fe900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:49:03 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 15:49:03 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 15:49:03 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 15:49:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:49:03 INFO - Return code: 0 15:49:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:49:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:49:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:49:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:49:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:49:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:49:03 INFO - Using env: {'DISPLAY': ':0', 15:49:03 INFO - 'HOME': '/home/cltbld', 15:49:03 INFO - 'LANG': 'en_US.UTF-8', 15:49:03 INFO - 'LANGUAGE': 'en_US:en', 15:49:03 INFO - 'LOGNAME': 'cltbld', 15:49:03 INFO - 'MAIL': '/var/mail/cltbld', 15:49:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:49:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:03 INFO - 'MOZ_NO_REMOTE': '1', 15:49:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:49:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:03 INFO - 'NO_EM_RESTART': '1', 15:49:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:03 INFO - 'PWD': '/builds/slave/test', 15:49:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:49:03 INFO - 'SHELL': '/bin/bash', 15:49:03 INFO - 'SHLVL': '1', 15:49:03 INFO - 'TERM': 'linux', 15:49:03 INFO - 'TMOUT': '86400', 15:49:03 INFO - 'USER': 'cltbld', 15:49:03 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701', 15:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:03 INFO - '_': '/tools/buildbot/bin/python'} 15:49:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:49:04 INFO - mozversion application_buildid: 20161015124135 15:49:04 INFO - mozversion application_changeset: c03e51cec3b5f6b8821687c8db8be309727d5470 15:49:04 INFO - mozversion application_display_name: Firefox 15:49:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:49:04 INFO - mozversion application_name: Firefox 15:49:04 INFO - mozversion application_remotingname: firefox 15:49:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:49:04 INFO - mozversion application_vendor: Mozilla 15:49:04 INFO - mozversion application_version: 50.0 15:49:04 INFO - mozversion platform_buildid: 20161015124135 15:49:04 INFO - mozversion platform_changeset: c03e51cec3b5f6b8821687c8db8be309727d5470 15:49:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:49:04 INFO - mozversion platform_version: 50.0 15:49:04 INFO - using testdate: 1476571744 15:49:04 INFO - actual date: 1476571744 15:49:04 INFO - starting webserver on 'localhost:45007' 15:49:04 INFO - SUITE-START | Running 2 tests 15:49:04 INFO - TEST-START | dromaeo_css 15:49:04 INFO - operating with platform_type : linux_ 15:49:04 INFO - Initialising browser for dromaeo_css test... 15:49:04 INFO - TEST-INFO | started process 7656 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKz3RJg/profile http://localhost:45007/getInfo.html) 15:49:09 INFO - PROCESS | 7656 | __metrics Screen width/height:1600/1200 15:49:09 INFO - PROCESS | 7656 | colorDepth:24 15:49:09 INFO - PROCESS | 7656 | Browser inner width/height: 1024/697 15:49:09 INFO - PROCESS | 7656 | __metrics 15:49:09 ERROR - PROCESS | 7656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 15:49:10 INFO - TEST-INFO | 7656: exit 0 15:49:10 INFO - Browser initialized. 15:49:10 INFO - Running cycle 1/1 for dromaeo_css test... 15:49:10 INFO - TEST-INFO | started process 7784 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKz3RJg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:49:22 INFO - PROCESS | 7784 | RSS: Main: 149958656 15:49:22 INFO - PROCESS | 7784 | 15:50:12 INFO - PROCESS | 7784 | 1476571812101 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] 15:52:20 INFO - PROCESS | 7784 | Cycle 1(1): loaded http://localhost:45007/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45007/tests/dromaeo/cssquery-ext.html) 15:52:21 INFO - PROCESS | 7784 | RSS: Main: 158228480 15:52:21 INFO - PROCESS | 7784 | 15:55:23 INFO - PROCESS | 7784 | Cycle 1(1): loaded http://localhost:45007/tests/dromaeo/cssquery-ext.html (next: http://localhost:45007/tests/dromaeo/cssquery-jquery.html) 15:55:24 INFO - PROCESS | 7784 | RSS: Main: 155664384 15:55:24 INFO - PROCESS | 7784 | 15:58:26 INFO - PROCESS | 7784 | Cycle 1(1): loaded http://localhost:45007/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45007/tests/dromaeo/cssquery-mootools.html) 15:58:26 INFO - PROCESS | 7784 | RSS: Main: 155402240 15:58:26 INFO - PROCESS | 7784 | 16:01:28 INFO - PROCESS | 7784 | Cycle 1(1): loaded http://localhost:45007/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45007/tests/dromaeo/cssquery-prototype.html) 16:01:29 INFO - PROCESS | 7784 | RSS: Main: 157011968 16:01:29 INFO - PROCESS | 7784 | 16:04:31 INFO - PROCESS | 7784 | Cycle 1(1): loaded http://localhost:45007/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45007/tests/dromaeo/cssquery-yui.html) 16:04:32 INFO - PROCESS | 7784 | RSS: Main: 158048256 16:04:32 INFO - PROCESS | 7784 | 16:07:34 INFO - PROCESS | 7784 | Cycle 1(1): loaded http://localhost:45007/tests/dromaeo/cssquery-yui.html (next: http://localhost:45007/tests/dromaeo/cssquery-dojo.html) 16:07:34 INFO - PROCESS | 7784 | RSS: Main: 157192192 16:07:34 INFO - PROCESS | 7784 | 16:07:34 INFO - PROCESS | 7784 | __start_tp_report 16:07:34 INFO - PROCESS | 7784 | _x_x_mozilla_page_load 16:07:34 INFO - PROCESS | 7784 | _x_x_mozilla_page_load_details 16:07:34 INFO - PROCESS | 7784 | |i|pagename|runs| 16:07:34 INFO - PROCESS | 7784 | |0;dojo.html;3300.8514616842244;3316.154380632939;3320.319334536419;3337.215754297738;3343.3981883261017;5752.597318187729;5797.71011449428;5831.154482600025;5841.824745257639;5843.561732870041;5782.739776710047;5805.767769289234;5813.854653633651;5846.269216347957;5851.6196447230905;5250.264962905206;5303.893922121555;5322.04203243416;5345.572354211673;5380.354357477088;5359.062164121283;5361.115415956382;5364.517193452589;5369.463053694619;5392.0294347017525;5156.278121063054;5180.766865491062;5366.275552800381;5373.516383525482;5383.623146379755;5189.636725429222;5286.444923283077;5286.5242128208265;5286.629935904488;5334.91997620036;5160.845174644766;5243.8164664236565;5254.31693879797;5265.262863199156;5296.17909224067;5481.314835645544;5649.661020338793;5681.57388195887;5759.568032397587;5803.448672376117;6118.112873633343;6199.907001394983;6204.286507051702;6205.3484384139465;6209.96895015522;6214.8135555933395;6214.937850621481;6216.098665693495;6223.222097237845;6233.2208473940755;5641.802536911229;5762.654240745569;5806.564507661942;5819.88360232793;5840.591158618899;6186.536009799283;6391.904121438182;6402.967985160044;6409.55133140685;6412.1023056771155;6113.296970848321;6134.110553969649;6170.938290617126;6175.536834737412;6188.133661287423;6007;6159.384061593894;6202.627842329384;6241.126242326078;6261.405166509175;6171.537134714914;6288.056791481223;6293.465055470245;6295.905561416583;6298;6279.686015699197;6286.402791734778;6292.779752708633;6324.525660575475;6326.968365158237;4602.240630296031;4691.038337140878;4805.37530121082;4806.687565308243;4812.663113582085;4160.583941605876;4221.831126754957;4223.282042052859;4260.488741351058;4265.7440553566885;6078.422549857756;6090.23872015998;6105.8168254952425;6123.295820980555;6123.601965872204;6091.695415229221;6137.959273351923;6303.527235457359;6349.682515874188;6384.457321127663;5984.491318237911;6060.151578778975;6061.696915154224;6064.211652485237;6109.541784366191;5382.623216374816;5394.109971854678;5406.08096623575;5414.133738601805;5420.512153906167;6183.628982261079;6450.161479007699;6469.644169570719;6510.934890651128;6512.316206798313;6302.684865756785;6312.337204593451;6319.842003949938;6324.747010119544;6326.557141000086;6542.378474044958;6587.143671322698;6588.538802283794;6608.570442921195;6617.07360969455;5905.704714764331;6126.029144987285;6138.600990935575;6186.257649082139;6207.6275423474735;6365.427111559982;6366.77716279928;6377.74489020434;6382.5532212744665;6383.39357761012;6152.16945712341;6169.660668663267;6181.505479561714;6204.348543402878;6212.72042758086;5855.151003104611;5953.868764934648;5975.790847320323;6189.566730329014;6193.504519638328;6017.127516510169;6021.969890150521;6052.182955300978;6056.333803281547;6089.330173680803;6006.849828754316;6068.059450785136;6077.878442431215;6084.908726369196;6096.02463605821;5820.359760426265;5896.823095306976;5918.822435326947;5925.52595792327;5988.161657368059 16:07:34 INFO - PROCESS | 7784 | |1;ext.html;592.5881512348919;602.7317843559616;635.440812085365;648.8345471904663;691.1257259566652;43005.139897202076;47321;47545.52454475454;47546.76226618866;47615.52384476163;48381.27428088582;49083.50916490834;49212.75393623027;49216.50783492164;49248.261276080884;9073.228775554086;9236.953815230949;9260.53697315135;9266.305027122993;9275.072492750738;9191.1727944485;9271.629134834599;9289.907100928971;9298.86051709225;9326.1606455419;9370.672026479075;9374.578143983537;9391.577379017961;9393;9402.059794020612;9252.722418327461;9319.580618872133;9324.720258392259;9364.34449588529;9373.437593744364;9171.724848254564;9256;9261.44431334122;9272.443653380818;9289.256859451227;44418.77790611058;44854;44899.55100449019;44917;44961;3757.906052348686;3807.0292075520565;3909.2963266612;4020.798960051986;4311.590398912098;31759.364812703847;31875.52186717201;32048.19879503008;35789.46315805265;37502.24995500075;4516.345129956171;4532.297493888453;4589.059242855206;4605.930911036337;4630.606398456135;375340;375880;376393;376634;377305.1134744336;39480.605193948264;67229.32770672328;67400.6629966847;67790.32209677939;67880.32119678838;39961.20077598432;41889.58110418918;42258;42422.787886060374;42581;16246.837531624533;16383.836161638232;16415.686745060368;16449.6710065798;16451.095189764437;13483.595492135251;13525.45898164082;13557;13595.728085438235;13610.931945340408;9471.194948429324;9484.478353690612;9487.76280592977;9569.37799043074;9575.138237558653;3505.2638945821664;3536.6286539913453;3540.2034542228307;3556.146524834073;3566.1262990567475;1021.8518314844306;1112.9109671226283;1173.3487914207697;1208.8247204155348;1225.7058306006672;35648.46527302093;35659.64340356615;35970.64029359673;36035.819820901255;36105.819470902476;1157.716359491913;1169.18741474676;1170.5610396101463;1185.17038073349;1234.975300493985;18250.81749182518;18358.265669373344;18493.075346232636;18642.720359194624;18691.532711682317;581790;585315;586231;588306;588705;66027;66427.66786166135;66676.66661666661;66756.33243667598;66867;1395.1280449718927;1429.485385261305;1437.583100900748;1445.5663301009656;1458.1324112153252;34056.318873622884;34855.65144348524;35003.299934000665;35081.82459087688;35166.47250291299;52157.73921130522;52266.47733522616;52639.473605263454;59133.70433147807;59172.70413647904;494381;494541;496488;497774;498281;32649.510257345682;32796.34407311888;33104.66895331112;33133.66866331306;33204.50193247152;54206.18690719721;54543.18185227304;54576.45423545714;55054.17418738642;55886.441135588124;45585.77207113943;47150;47275.29087063628;47492.762536187096;48018.75990620025;46469.76765116153;47282;47499.76250118727;48059;48677.26984095312;38169.23661526809;46676.299855502875;46771.29843052425;48229;48956.51043489519;40248.597514025656;40357.39463908102;40854.59145408508;41019;41096.589034109275;30890.691093088783;31462.685373145974;31522.211944701565;31681.683183167872;31714.84142579272 16:07:34 INFO - PROCESS | 7784 | |2;jquery.html;422.97727939091277;426.6522783931098;433.3110354536288;436.32587652077535;436.3977710759152;19403.902980485094;20854.165833366667;21354.57290854184;21964.121435142613;22193.334199973986;20493.180272789108;20592.794072059274;20848.583028339424;21066.36800895972;21454.785452145476;6283.403076707706;6546.967265163667;6808.489363297747;6896.069030680844;6902.447804175654;6781.355771201753;6783.016462612918;6812.965935170318;6813.693383797716;6829.146356705412;6352.205974253218;6421.325760795119;6440.935590644104;6796.7621133260345;6809.0467334573195;6549.541532092755;6713.194416669982;6739.5619284746335;6760.1549806274215;6765.2219994700745;6533.738650453976;6554.967225163891;6569.146011018537;6593.670316484205;6596.175478065242;24058.15796447116;26225.213243602724;27232.863835680695;27264.863675681496;28289.292767680974;2677.9866100669374;2799.9020034298906;2913.242556935191;2997.8800847966054;3017.7133172348813;15556.844431555654;16232.188390580424;16251.756223656655;16705.415310464195;17011.82988170115;2130.7975742304457;2211.2039665720326;2236.1167338901205;2241.7758224177614;2371.9288421347387;94844;104157.47921260422;104280;104656;108229;19178.8082119177;19314;19324.32364867223;19700.25695117923;19770.802291977183;35210.64789352125;35227;35344.82327588346;35349.64650353515;35360.82319588386;10140;10437.425941573134;10448.634297799541;10455.581776728997;10677.359358438518;8609.741707748777;8630.654773808978;8656.826863462822;8737.475751454933;8994.235489983294;9427.292953028413;9552.522373881417;9749.07383798538;9750.171235444925;9785.755356116155;3383.543221665093;3485.546878905726;3536.876209332661;3684.6499582539614;3764.887053388403;925.8888933327909;1011.701548043329;1023.0792286941663;1091.2143256855052;1093.2456604942565;20594.691079633816;20900;21061.157553697987;21125.89437052805;21265;998.1316254858175;1012.9696109116738;1052.4790228836775;1095.3044816541553;1123.8145705958589;11370.602028928948;11610.187286889837;11774.88225117755;11851;11878.762424751629;504527;530079;534540;556137;557885;97812.51093744485;98411.50794245982;100944;100974;103374;1018.6231094494937;1028.187731691955;1037.7301901505664;1042.5569133118395;1057.2070946789754;18278.811877227938;18483.815161848208;18588;18691.25234990585;18760.812391876454;45476.54523454723;45620;45693;46151;46271.07457850892;58746;59607.70196148992;62743.6862815683;62857;63866.36133638604;9123.817523649623;9134.132257435533;9164.083591640783;9194.402363846328;9253.120953509406;13065.216087034429;13188.670283242996;13219.140755850838;13816.654583635087;14034.649133771738;11703.941480292544;11720.23818451832;11837.171398002058;11891.821622675494;11988.280703157838;11632.127590430582;11781.410929453665;11858.881411186123;11933.701657458632;12039.939800300943;11731.23746956445;11765.411729413323;11767.588134415255;12036.097292702907;12390.938045309716;8993.820123597361;9111.817763644822;9342.392744471588;9426.858597120912;9457.763555911157;11924.224925379822;12008.519659213884;12334.444949977453;12615.116941813985;12639.241645501299 16:07:34 INFO - PROCESS | 7784 | |3;mootools.html;1540.3145600207909;1555.4011705493385;1709.8290170982903;1747.737839324102;1849.8335149836512;8832.160944710258;9416.43501389917;9598.904010959888;10386.169106471483;10388.013138751816;9652.083052110056;10073.798524029515;10079.798404031915;10081.747956301097;10092.343997640148;2590.248827839924;2637.1692916731263;2638.0371164525004;2639.1686618715044;2645.391559941215;2572.0869091472537;2618.4632150409216;2645.828021178627;2650.310919161013;2652.801039922004;2624.1340357682016;2631.1054241557867;2661.1750357389174;2661.8669066546595;2669.746374094458;2550.872456377183;2571.459993401388;2579.85810780407;2585.108137692493;2586.405126820842;2501.262127672341;2529.5699731045765;2555.1823416506727;2556.8849401776965;2558.5906254999295;10102.797944041151;10236.385816850938;10248.948755256251;10262.897371026344;10275.280730348879;5900.350961394243;6064.636121832704;6075.210222671025;6077.696115194222;6079.027355623123;3530.2726354092456;3562.1628917204434;3645.5078564394;3709.647583479565;3709.998300458861;1142.1091548592112;1184.8518935133109;1188.0614314691384;1190.7320852808136;1215.1858254969193;13812.93093534536;14033.929830350886;14190.787138192936;14575.562733118024;14822.70354592913;5064.442911279756;5103.642745007814;5172.741362931838;5173.405058418255;5303.920441193385;11143.33140011602;11152.996230339146;11163.609273675382;11166.330020198815;11204.215704900578;5816.302043754692;5828.88342233153;5828.941710582924;5836.941630583725;5857.853553661107;5220.712860792617;5350.170723537858;5355.732213389315;5432.375276843213;5455.236266922634;4294.957050429518;4398.494173170063;4433.866983990485;4444.755538445416;4456.910861782746;1276.4000919567789;1278.9424475898606;1291.8449786025737;1296.1769276509297;1307.960761177147;950.8336041192764;1070.373831308693;1085.7285678580356;1087.190043417659;1097.018168738974;6590.703418346186;6609.900851487136;6735.29279425663;6837.1111755471475;6962.686679099453;509.38364578859256;514.572904489273;517.7100823538755;518.9351331083615;530.0432718942269;4564.6348292136545;4570.9314360284625;4690.179218636725;4788.880277993008;4866.026794641086;11643.592474263529;12513.43689533955;13017.218966861828;13035.674108147183;13047.543335983195;12012.459439325252;12482.063845211464;12493.50025998968;12496.937515312366;12503.187292825936;9402.15380615748;9604.03959604048;9610.807783844284;9613.759656008655;9624;6789.2871248518195;7121.4302855770375;7166.605836679033;7166.713331466892;7223.313785190398;11466.361642932263;11765.11761617865;11957.282563046245;12033.819492707791;12036.338001409656;11804.645860624196;11836;12064.93967530192;12082.637520874388;12236.082293828176;8438.367122465717;8610.741677749678;8707.259882910024;9081.591328390106;9191.586378612845;9600.903990960001;9680.661176859057;9695.612175513183;9701.272404569827;9721.951390243003;8448.619812108593;8454;8543.145685431407;8560.529170895661;8674.78313042179;8118.634661440131;8292.83414331722;8483.321334293365;8641.913580864111;8832.867506987272;7384.96307518459;7402.7409040683315;7477.177510473953;7514.8497030058;7538.509996850187;5687.402822703722;5750.6262092963825;5867.442592953663;5965.135055417027;5997.070454079625;5770.740316685843;5797.101449275369;5802.361740208489;5856.297244330707;5861.1501332305725 16:07:34 INFO - PROCESS | 7784 | |4;prototype.html;361.6058496239099;362.2429123132652;363.9727020473465;365.61427693783054;367.52038589640506;5591.7483713232905;5595.692236926968;5617.9438205617935;5641.633293835903;5642.633228840127;5637.154426835967;5651.717414129298;5669.149627555868;5670.432956704328;5673.914891276634;4487.775611219442;4496.190685676577;4498.212812757754;4505.369248305232;4507.887302817423;4396.670249731267;4419.166046090869;4480.372747815292;4506.414166158411;4518.864434066983;4425.269830477967;4426.513083560806;4435.2702446045905;4437.88905277369;4464.415132627919;4439.156560253574;4451.287793952968;4451.844185453494;4455.6658250631335;4464.196444639963;3798.2213646202463;3885.30064588374;3887.280853042168;3900.9857437066285;3915.7846318452475;5545.890821835608;5548.972255138739;5550.86122846928;5554.944450555483;5567.2762540869835;5078.365204349457;5084.339035925343;5096.337476128079;5097.541221290065;5102.438731739506;5737.483626473596;5921.61509501881;5956.52347812174;5957.910631340534;5971.402439938641;4305.526392096866;4308.116836048634;4314.546972567867;4321.567843215653;4330.675199360061;6022.759089636409;6186.628802271878;6232.719527621268;6239.750409983595;6257.029035853691;4433.202023635777;4453.242948698729;4454.688171828006;4457.175422546839;4459.509453960061;4514.751688657156;4516.593506584423;4518;4521.163584736834;4524.8416305429155;4806.663533552688;5142.640015198975;5143.639945203875;5143.639945203875;5150.536451719363;5818.330891947396;5935.228420305332;5987.383406480226;5993.880122397615;6012.969935150384;1568.1688704986375;1581.8734501239874;1588.809342878862;1592.633694250329;1605.414023881275;993.9753125183985;1018.4805749067881;1034.4775888176573;1034.8344264917578;1047.6542740895486;5154.355705536808;5156.407013193456;5168.405633352138;5169.81905633301;5192.350956130484;5394.811181608625;5395.70323632204;5414.563446449035;5447.373552041487;5471.452854714496;4961.826336078292;5128.640995130305;5156.8968620627375;5170.3278573785165;5173.793048278027;2876.395956849085;2883.6539615246306;2890.4363649088227;2905.7505272732506;2919.7080291971056;5555.666660000413;5568.610197286151;5596.888062238732;5597.496225339658;5599.44005599445;5180.9740951295;5374.915064550892;5441.374241962146;5462.2079798429;5472.589555783253;5583.659921618782;5591.692456914909;5630.438150641448;5650.7739690412745;5651.124075768263;5246.42289348179;5443.564514838883;5446.509814116748;5454;5461.034027749927;5242.554382877345;5296.808218150885;5299.39057008449;5303.4013333465555;5314.8937021260135;5489.929463754689;5509.393966663583;5573.7983496519455;5621.690807005654;5648.689322087324;3897.2984862724984;4024.6176613221696;4028.6575641069667;4042.4744783178;4082.8162732676506;5372.62391632582;5385.461453854583;5399.409392753932;6025.517958563392;6225.564210505221;6048.304444988707;6080.452759251689;6086.452219300284;6089.330173680803;6122.602030867979;5941.7623295067715;6060.545459090497;6060.939390606214;6081.452669259788;6098.786542470993;6032.276126864805;6050.334463209131;6070.332263451105;6088.543359247986;6106.3283038866575;6045.335013148638;6082.787102451393;6115.969420152871;6130.5402094842175;6133.294671112879;5837.445442682938;5909.261342332208;5943.554233432424;5968.03054991843;5985.790497332572 16:07:34 INFO - PROCESS | 7784 | |5;yui.html;725.8112890648433;737.6090671943872;775.4726785785666;803.2489622203241;803.9155888631698;16547.751783723255;16766.161691915415;16863.156842157903;16895.901766385177;16949.57626059349;13571.253581053063;13592.184468931871;13600;13601;13610.31948402581;714.2785786355767;841.4488510025913;846.6359465429863;863.4646519158099;870.4385671242077;861.483110133921;861.9181177788131;863.386995233385;864.8054187807755;865.8787769712245;925.8935222449403;927.7448701607044;929.0430856218106;930.0234753508822;932.2215949681989;918.091089821079;919.2554031234688;922.3543519536317;926.8100039491889;927.8005228875791;678.5487650712271;679.4428568573771;681.8772620928231;681.9693113809841;682.8327059870363;15251.161186134757;15299.15854627995;15317.234138293152;15337.693246135126;15366.078035317916;14116.223607701573;14255.14469131845;14328.785068223984;14431.711365772731;14550.927245363811;11791.764164716744;11843.822342664867;11872.881271187263;11894.226875253082;12166.87833121675;4125.381192821071;4132.504099508048;4165.146145040259;4322.956770432287;4334.436523251957;87196.12803871944;90414;90599;90945;91700.08299916983;11562.94218528902;11584;11610.825837612443;11685.240459370114;11732.12009099321;25250.116245931305;25294.1147059852;25296.620550691754;25314.74685253124;25489;9130.680426185052;9188.724338269863;9237.676681316256;9266.583003764847;9310.115539023782;5193.792248310101;5210.713410762444;5239.947600523946;5253.080710875582;5260.868478288074;3745.7752534847996;3827.445020472016;3855.4216867469545;3857.4599556062176;3880.165764360676;680.6460640466929;682.7234969837224;682.9795106146823;683.217715715503;709.1773542690557;776.0493395590356;777.3780975219802;781.8123650323995;821.5440430560976;837.7738010737066;10185.287029907984;10472.947635261928;10508.99865218417;10685.679429617125;10728.463576821285;734.1536036867283;775.10862508115;797.4258533855615;808.5714571277232;812.4515951732557;4943.085529177114;4951.804951804983;4982.551570358685;5016.347874776256;5098.719570423588;135018.3249083768;135912;136604;136845;141746.291268543;93929.53035234917;97331.51334243284;115345;118791;120661.39669301597;56237.718811405684;69972;85206.57396712976;88918.9999999987;89403;10162.39025658463;10278.588856445958;10442.791144176921;10756.139508839431;10838.458077095956;54946;55571.44428555663;56137.43862561322;56168;56800.14799777924;70493.64753176406;78905.60547197227;80629.59685201537;82019.58990205011;82205;10821.891781082404;13325.400356983766;13410.865891340962;13550.254735989614;13609.931950340186;13915;14648.926755366156;14707.264636767904;15303.770443443134;15911.522654320388;14083.647908802363;14211.715765684836;14443.638909027359;14919.477818276308;15376.154311512973;13857.86142138606;13902.304884755924;14163.858361416254;14380.424783008562;14811.92594037066;11517.25137866036;11739.706507337385;12416.130870838955;13701.177929324273;13809.378577963815;11065.668029959113;11323.263987840764;11418.200725949104;11686;12601.495940162291;11746.882531174579;11960.58137965167;12255.448504817483;12321.968169576294;12419.316937568521 16:07:34 INFO - PROCESS | 7784 | __end_tp_report 16:07:34 INFO - PROCESS | 7784 | __start_cc_report 16:07:34 INFO - PROCESS | 7784 | _x_x_mozilla_cycle_collect,355 16:07:34 INFO - PROCESS | 7784 | __end_cc_report 16:07:34 INFO - PROCESS | 7784 | __startTimestamp1476572854838__endTimestamp 16:07:34 INFO - PROCESS | 7784 | 16:07:34 INFO - PROCESS | 7784 | ------- Summary: start ------- 16:07:34 INFO - PROCESS | 7784 | Number of tests: 6 16:07:34 INFO - PROCESS | 7784 | 16:07:34 INFO - PROCESS | 7784 | [#0] dojo.html Cycles:165 Average:5800.85 Median:6061.70 stddev:678.23 (11.2%) stddev-sans-first:651.33 16:07:34 INFO - PROCESS | 7784 | Values: 3300.9 3316.2 3320.3 3337.2 3343.4 5752.6 5797.7 5831.2 5841.8 5843.6 5782.7 5805.8 5813.9 5846.3 5851.6 5250.3 5303.9 5322.0 5345.6 5380.4 5359.1 5361.1 5364.5 5369.5 5392.0 5156.3 5180.8 5366.3 5373.5 5383.6 5189.6 5286.4 5286.5 5286.6 5334.9 5160.8 5243.8 5254.3 5265.3 5296.2 5481.3 5649.7 5681.6 5759.6 5803.4 6118.1 6199.9 6204.3 6205.3 6210.0 6214.8 6214.9 6216.1 6223.2 6233.2 5641.8 5762.7 5806.6 5819.9 5840.6 6186.5 6391.9 6403.0 6409.6 6412.1 6113.3 6134.1 6170.9 6175.5 6188.1 6007.0 6159.4 6202.6 6241.1 6261.4 6171.5 6288.1 6293.5 6295.9 6298.0 6279.7 6286.4 6292.8 6324.5 6327.0 4602.2 4691.0 4805.4 4806.7 4812.7 4160.6 4221.8 4223.3 4260.5 4265.7 6078.4 6090.2 6105.8 6123.3 6123.6 6091.7 6138.0 6303.5 6349.7 6384.5 5984.5 6060.2 6061.7 6064.2 6109.5 5382.6 5394.1 5406.1 5414.1 5420.5 6183.6 6450.2 6469.6 6510.9 6512.3 6302.7 6312.3 6319.8 6324.7 6326.6 6542.4 6587.1 6588.5 6608.6 6617.1 5905.7 6126.0 6138.6 6186.3 6207.6 6365.4 6366.8 6377.7 6382.6 6383.4 6152.2 6169.7 6181.5 6204.3 6212.7 5855.2 5953.9 5975.8 6189.6 6193.5 6017.1 6022.0 6052.2 6056.3 6089.3 6006.8 6068.1 6077.9 6084.9 6096.0 5820.4 5896.8 5918.8 5925.5 5988.2 16:07:34 INFO - PROCESS | 7784 | 16:07:34 INFO - PROCESS | 7784 | [#1] ext.html Cycles:180 Average:65288.43 Median:25087.42 stddev:131183.31 (522.9%) stddev-sans-first:131461.39 16:07:34 INFO - PROCESS | 7784 | Values: 592.6 602.7 635.4 648.8 691.1 43005.1 47321.0 47545.5 47546.8 47615.5 48381.3 49083.5 49212.8 49216.5 49248.3 9073.2 9237.0 9260.5 9266.3 9275.1 9191.2 9271.6 9289.9 9298.9 9326.2 9370.7 9374.6 9391.6 9393.0 9402.1 9252.7 9319.6 9324.7 9364.3 9373.4 9171.7 9256.0 9261.4 9272.4 9289.3 44418.8 44854.0 44899.6 44917.0 44961.0 3757.9 3807.0 3909.3 4020.8 4311.6 31759.4 31875.5 32048.2 35789.5 37502.2 4516.3 4532.3 4589.1 4605.9 4630.6 375340.0 375880.0 376393.0 376634.0 377305.1 39480.6 67229.3 67400.7 67790.3 67880.3 39961.2 41889.6 42258.0 42422.8 42581.0 16246.8 16383.8 16415.7 16449.7 16451.1 13483.6 13525.5 13557.0 13595.7 13610.9 9471.2 9484.5 9487.8 9569.4 9575.1 3505.3 3536.6 3540.2 3556.1 3566.1 1021.9 1112.9 1173.3 1208.8 1225.7 35648.5 35659.6 35970.6 36035.8 36105.8 1157.7 1169.2 1170.6 1185.2 1235.0 18250.8 18358.3 18493.1 18642.7 18691.5 581790.0 585315.0 586231.0 588306.0 588705.0 66027.0 66427.7 66676.7 66756.3 66867.0 1395.1 1429.5 1437.6 1445.6 1458.1 34056.3 34855.7 35003.3 35081.8 35166.5 52157.7 52266.5 52639.5 59133.7 59172.7 494381.0 494541.0 496488.0 497774.0 498281.0 32649.5 32796.3 33104.7 33133.7 33204.5 54206.2 54543.2 54576.5 55054.2 55886.4 45585.8 47150.0 47275.3 47492.8 48018.8 46469.8 47282.0 47499.8 48059.0 48677.3 38169.2 46676.3 46771.3 48229.0 48956.5 40248.6 40357.4 40854.6 41019.0 41096.6 30890.7 31462.7 31522.2 31681.7 31714.8 16:07:34 INFO - PROCESS | 7784 | 16:07:34 INFO - PROCESS | 7784 | [#2] jquery.html Cycles:180 Average:33133.38 Median:25141.69 stddev:88648.97 (352.6%) stddev-sans-first:88863.63 16:07:34 INFO - PROCESS | 7784 | Values: 423.0 426.7 433.3 436.3 436.4 19403.9 20854.2 21354.6 21964.1 22193.3 20493.2 20592.8 20848.6 21066.4 21454.8 6283.4 6547.0 6808.5 6896.1 6902.4 6781.4 6783.0 6813.0 6813.7 6829.1 6352.2 6421.3 6440.9 6796.8 6809.0 6549.5 6713.2 6739.6 6760.2 6765.2 6533.7 6555.0 6569.1 6593.7 6596.2 24058.2 26225.2 27232.9 27264.9 28289.3 2678.0 2799.9 2913.2 2997.9 3017.7 15556.8 16232.2 16251.8 16705.4 17011.8 2130.8 2211.2 2236.1 2241.8 2371.9 94844.0 104157.5 104280.0 104656.0 108229.0 19178.8 19314.0 19324.3 19700.3 19770.8 35210.6 35227.0 35344.8 35349.6 35360.8 10140.0 10437.4 10448.6 10455.6 10677.4 8609.7 8630.7 8656.8 8737.5 8994.2 9427.3 9552.5 9749.1 9750.2 9785.8 3383.5 3485.5 3536.9 3684.6 3764.9 925.9 1011.7 1023.1 1091.2 1093.2 20594.7 20900.0 21061.2 21125.9 21265.0 998.1 1013.0 1052.5 1095.3 1123.8 11370.6 11610.2 11774.9 11851.0 11878.8 504527.0 530079.0 534540.0 556137.0 557885.0 97812.5 98411.5 100944.0 100974.0 103374.0 1018.6 1028.2 1037.7 1042.6 1057.2 18278.8 18483.8 18588.0 18691.3 18760.8 45476.5 45620.0 45693.0 46151.0 46271.1 58746.0 59607.7 62743.7 62857.0 63866.4 9123.8 9134.1 9164.1 9194.4 9253.1 13065.2 13188.7 13219.1 13816.7 14034.6 11703.9 11720.2 11837.2 11891.8 11988.3 11632.1 11781.4 11858.9 11933.7 12039.9 11731.2 11765.4 11767.6 12036.1 12390.9 8993.8 9111.8 9342.4 9426.9 9457.8 11924.2 12008.5 12334.4 12615.1 12639.2 16:07:34 INFO - PROCESS | 7784 | 16:07:34 INFO - PROCESS | 7784 | [#3] mootools.html Cycles:180 Average:6562.06 Median:4346.73 stddev:3833.79 (88.2%) stddev-sans-first:3825.98 16:07:34 INFO - PROCESS | 7784 | Values: 1540.3 1555.4 1709.8 1747.7 1849.8 8832.2 9416.4 9598.9 10386.2 10388.0 9652.1 10073.8 10079.8 10081.7 10092.3 2590.2 2637.2 2638.0 2639.2 2645.4 2572.1 2618.5 2645.8 2650.3 2652.8 2624.1 2631.1 2661.2 2661.9 2669.7 2550.9 2571.5 2579.9 2585.1 2586.4 2501.3 2529.6 2555.2 2556.9 2558.6 10102.8 10236.4 10248.9 10262.9 10275.3 5900.4 6064.6 6075.2 6077.7 6079.0 3530.3 3562.2 3645.5 3709.6 3710.0 1142.1 1184.9 1188.1 1190.7 1215.2 13812.9 14033.9 14190.8 14575.6 14822.7 5064.4 5103.6 5172.7 5173.4 5303.9 11143.3 11153.0 11163.6 11166.3 11204.2 5816.3 5828.9 5828.9 5836.9 5857.9 5220.7 5350.2 5355.7 5432.4 5455.2 4295.0 4398.5 4433.9 4444.8 4456.9 1276.4 1278.9 1291.8 1296.2 1308.0 950.8 1070.4 1085.7 1087.2 1097.0 6590.7 6609.9 6735.3 6837.1 6962.7 509.4 514.6 517.7 518.9 530.0 4564.6 4570.9 4690.2 4788.9 4866.0 11643.6 12513.4 13017.2 13035.7 13047.5 12012.5 12482.1 12493.5 12496.9 12503.2 9402.2 9604.0 9610.8 9613.8 9624.0 6789.3 7121.4 7166.6 7166.7 7223.3 11466.4 11765.1 11957.3 12033.8 12036.3 11804.6 11836.0 12064.9 12082.6 12236.1 8438.4 8610.7 8707.3 9081.6 9191.6 9600.9 9680.7 9695.6 9701.3 9722.0 8448.6 8454.0 8543.1 8560.5 8674.8 8118.6 8292.8 8483.3 8641.9 8832.9 7385.0 7402.7 7477.2 7514.8 7538.5 5687.4 5750.6 5867.4 5965.1 5997.1 5770.7 5797.1 5802.4 5856.3 5861.2 16:07:34 INFO - PROCESS | 7784 | 16:07:34 INFO - PROCESS | 7784 | [#4] prototype.html Cycles:180 Average:4845.62 Median:5343.76 stddev:1388.55 (26.0%) stddev-sans-first:1351.05 16:07:34 INFO - PROCESS | 7784 | Values: 361.6 362.2 364.0 365.6 367.5 5591.7 5595.7 5617.9 5641.6 5642.6 5637.2 5651.7 5669.1 5670.4 5673.9 4487.8 4496.2 4498.2 4505.4 4507.9 4396.7 4419.2 4480.4 4506.4 4518.9 4425.3 4426.5 4435.3 4437.9 4464.4 4439.2 4451.3 4451.8 4455.7 4464.2 3798.2 3885.3 3887.3 3901.0 3915.8 5545.9 5549.0 5550.9 5554.9 5567.3 5078.4 5084.3 5096.3 5097.5 5102.4 5737.5 5921.6 5956.5 5957.9 5971.4 4305.5 4308.1 4314.5 4321.6 4330.7 6022.8 6186.6 6232.7 6239.8 6257.0 4433.2 4453.2 4454.7 4457.2 4459.5 4514.8 4516.6 4518.0 4521.2 4524.8 4806.7 5142.6 5143.6 5143.6 5150.5 5818.3 5935.2 5987.4 5993.9 6013.0 1568.2 1581.9 1588.8 1592.6 1605.4 994.0 1018.5 1034.5 1034.8 1047.7 5154.4 5156.4 5168.4 5169.8 5192.4 5394.8 5395.7 5414.6 5447.4 5471.5 4961.8 5128.6 5156.9 5170.3 5173.8 2876.4 2883.7 2890.4 2905.8 2919.7 5555.7 5568.6 5596.9 5597.5 5599.4 5181.0 5374.9 5441.4 5462.2 5472.6 5583.7 5591.7 5630.4 5650.8 5651.1 5246.4 5443.6 5446.5 5454.0 5461.0 5242.6 5296.8 5299.4 5303.4 5314.9 5489.9 5509.4 5573.8 5621.7 5648.7 3897.3 4024.6 4028.7 4042.5 4082.8 5372.6 5385.5 5399.4 6025.5 6225.6 6048.3 6080.5 6086.5 6089.3 6122.6 5941.8 6060.5 6060.9 6081.5 6098.8 6032.3 6050.3 6070.3 6088.5 6106.3 6045.3 6082.8 6116.0 6130.5 6133.3 5837.4 5909.3 5943.6 5968.0 5985.8 16:07:34 INFO - PROCESS | 7784 | 16:07:34 INFO - PROCESS | 7784 | [#5] yui.html Cycles:180 Average:22419.71 Median:3856.44 stddev:33430.42 (866.9%) stddev-sans-first:33484.52 16:07:34 INFO - PROCESS | 7784 | Values: 725.8 737.6 775.5 803.2 803.9 16547.8 16766.2 16863.2 16895.9 16949.6 13571.3 13592.2 13600.0 13601.0 13610.3 714.3 841.4 846.6 863.5 870.4 861.5 861.9 863.4 864.8 865.9 925.9 927.7 929.0 930.0 932.2 918.1 919.3 922.4 926.8 927.8 678.5 679.4 681.9 682.0 682.8 15251.2 15299.2 15317.2 15337.7 15366.1 14116.2 14255.1 14328.8 14431.7 14550.9 11791.8 11843.8 11872.9 11894.2 12166.9 4125.4 4132.5 4165.1 4323.0 4334.4 87196.1 90414.0 90599.0 90945.0 91700.1 11562.9 11584.0 11610.8 11685.2 11732.1 25250.1 25294.1 25296.6 25314.7 25489.0 9130.7 9188.7 9237.7 9266.6 9310.1 5193.8 5210.7 5239.9 5253.1 5260.9 3745.8 3827.4 3855.4 3857.5 3880.2 680.6 682.7 683.0 683.2 709.2 776.0 777.4 781.8 821.5 837.8 10185.3 10472.9 10509.0 10685.7 10728.5 734.2 775.1 797.4 808.6 812.5 4943.1 4951.8 4982.6 5016.3 5098.7 135018.3 135912.0 136604.0 136845.0 141746.3 93929.5 97331.5 115345.0 118791.0 120661.4 56237.7 69972.0 85206.6 88919.0 89403.0 10162.4 10278.6 10442.8 10756.1 10838.5 54946.0 55571.4 56137.4 56168.0 56800.1 70493.6 78905.6 80629.6 82019.6 82205.0 10821.9 13325.4 13410.9 13550.3 13609.9 13915.0 14648.9 14707.3 15303.8 15911.5 14083.6 14211.7 14443.6 14919.5 15376.2 13857.9 13902.3 14163.9 14380.4 14811.9 11517.3 11739.7 12416.1 13701.2 13809.4 11065.7 11323.3 11418.2 11686.0 12601.5 11746.9 11960.6 12255.4 12322.0 12419.3 16:07:34 INFO - PROCESS | 7784 | -------- Summary: end -------- 16:07:34 INFO - PROCESS | 7784 | 16:07:35 INFO - TEST-INFO | 7784: exit 0 16:07:35 INFO - TEST-OK | dromaeo_css | took 1111435ms 16:07:35 INFO - TEST-START | kraken 16:07:35 INFO - operating with platform_type : linux_ 16:07:35 INFO - Initialising browser for kraken test... 16:07:35 INFO - TEST-INFO | started process 8034 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpORzJmw/profile http://localhost:45007/getInfo.html) 16:07:40 INFO - PROCESS | 8034 | __metrics Screen width/height:1600/1200 16:07:40 INFO - PROCESS | 8034 | colorDepth:24 16:07:40 INFO - PROCESS | 8034 | Browser inner width/height: 1024/697 16:07:40 INFO - PROCESS | 8034 | __metrics 16:07:41 ERROR - PROCESS | 8034 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 16:07:42 INFO - TEST-INFO | 8034: exit 0 16:07:42 INFO - Browser initialized. 16:07:42 INFO - Running cycle 1/1 for kraken test... 16:07:42 INFO - TEST-INFO | started process 8159 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpORzJmw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:07:53 INFO - PROCESS | 8159 | RSS: Main: 149839872 16:07:53 INFO - PROCESS | 8159 | 16:07:56 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:45007/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45007/tests/kraken/driver.html?testName=audio-beat-detection) 16:07:57 INFO - PROCESS | 8159 | RSS: Main: 156643328 16:07:57 INFO - PROCESS | 8159 | 16:07:59 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:45007/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45007/tests/kraken/driver.html?testName=audio-dft) 16:08:00 INFO - PROCESS | 8159 | RSS: Main: 154980352 16:08:00 INFO - PROCESS | 8159 | 16:08:03 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:45007/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45007/tests/kraken/driver.html?testName=audio-fft) 16:08:04 INFO - PROCESS | 8159 | RSS: Main: 155779072 16:08:04 INFO - PROCESS | 8159 | 16:08:06 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:45007/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45007/tests/kraken/driver.html?testName=audio-oscillator) 16:08:06 INFO - PROCESS | 8159 | RSS: Main: 155717632 16:08:06 INFO - PROCESS | 8159 | 16:08:08 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:45007/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45007/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:08:09 INFO - PROCESS | 8159 | RSS: Main: 156057600 16:08:09 INFO - PROCESS | 8159 | 16:08:12 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:45007/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45007/tests/kraken/driver.html?testName=imaging-darkroom) 16:08:13 INFO - PROCESS | 8159 | RSS: Main: 156114944 16:08:13 INFO - PROCESS | 8159 | 16:08:16 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:45007/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45007/tests/kraken/driver.html?testName=imaging-desaturate) 16:08:17 INFO - PROCESS | 8159 | RSS: Main: 156753920 16:08:17 INFO - PROCESS | 8159 | 16:08:19 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:45007/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45007/tests/kraken/driver.html?testName=json-parse-financial) 16:08:20 INFO - PROCESS | 8159 | RSS: Main: 156315648 16:08:20 INFO - PROCESS | 8159 | 16:08:22 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:45007/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45007/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:08:22 INFO - PROCESS | 8159 | RSS: Main: 155451392 16:08:22 INFO - PROCESS | 8159 | 16:08:24 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:45007/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45007/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:08:25 INFO - PROCESS | 8159 | RSS: Main: 155947008 16:08:25 INFO - PROCESS | 8159 | 16:08:27 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:45007/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45007/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:08:27 INFO - PROCESS | 8159 | RSS: Main: 156188672 16:08:27 INFO - PROCESS | 8159 | 16:08:30 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:45007/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45007/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:08:30 INFO - PROCESS | 8159 | RSS: Main: 156504064 16:08:30 INFO - PROCESS | 8159 | 16:08:33 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:45007/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45007/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:08:34 INFO - PROCESS | 8159 | RSS: Main: 156626944 16:08:34 INFO - PROCESS | 8159 | 16:08:35 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:45007/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45007/tests/kraken/driver.html?testName=ai-astar) 16:08:36 INFO - PROCESS | 8159 | RSS: Main: 158478336 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | __start_tp_report 16:08:36 INFO - PROCESS | 8159 | _x_x_mozilla_page_load 16:08:36 INFO - PROCESS | 8159 | _x_x_mozilla_page_load_details 16:08:36 INFO - PROCESS | 8159 | |i|pagename|runs| 16:08:36 INFO - PROCESS | 8159 | |0;ai-astar;101;113;108;111;112;112;113;111;111;110 16:08:36 INFO - PROCESS | 8159 | |1;audio-beat-detection;147;119;143;180;122;150;146;123;166;121 16:08:36 INFO - PROCESS | 8159 | |2;audio-dft;163;162;164;160;162;161;161;161;161;161 16:08:36 INFO - PROCESS | 8159 | |3;audio-fft;75;76;75;173;75;76;76;75;163;75 16:08:36 INFO - PROCESS | 8159 | |4;audio-oscillator;83;83;149;90;83;82;138;85;83;140 16:08:36 INFO - PROCESS | 8159 | |5;imaging-gaussian-blur;117;117;117;118;117;120;121;117;118;118 16:08:36 INFO - PROCESS | 8159 | |6;imaging-darkroom;147;147;147;147;148;147;148;150;147;147 16:08:36 INFO - PROCESS | 8159 | |7;imaging-desaturate;95;95;97;95;95;95;95;95;95;95 16:08:36 INFO - PROCESS | 8159 | |8;json-parse-financial;53;50;51;51;51;50;50;51;54;51 16:08:36 INFO - PROCESS | 8159 | |9;json-stringify-tinderbox;60;59;60;59;59;59;59;60;63;60 16:08:36 INFO - PROCESS | 8159 | |10;stanford-crypto-aes;72;72;72;73;74;73;75;73;75;72 16:08:36 INFO - PROCESS | 8159 | |11;stanford-crypto-ccm;129;121;121;122;130;130;131;117;181;120 16:08:36 INFO - PROCESS | 8159 | |12;stanford-crypto-pbkdf2;154;150;155;149;151;152;153;157;151;155 16:08:36 INFO - PROCESS | 8159 | |13;stanford-crypto-sha256-iterative;64;62;64;62;61;62;62;61;59;61 16:08:36 INFO - PROCESS | 8159 | __end_tp_report 16:08:36 INFO - PROCESS | 8159 | __start_cc_report 16:08:36 INFO - PROCESS | 8159 | _x_x_mozilla_cycle_collect,886 16:08:36 INFO - PROCESS | 8159 | __end_cc_report 16:08:36 INFO - PROCESS | 8159 | __startTimestamp1476572916122__endTimestamp 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | ------- Summary: start ------- 16:08:36 INFO - PROCESS | 8159 | Number of tests: 14 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | [#0] ai-astar Cycles:10 Average:110.20 Median:111.50 stddev:3.55 (3.2%) stddev-sans-first:1.56 16:08:36 INFO - PROCESS | 8159 | Values: 101.0 113.0 108.0 111.0 112.0 112.0 113.0 111.0 111.0 110.0 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:146.50 stddev:20.70 (14.1%) stddev-sans-first:21.87 16:08:36 INFO - PROCESS | 8159 | Values: 147.0 119.0 143.0 180.0 122.0 150.0 146.0 123.0 166.0 121.0 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | [#2] audio-dft Cycles:10 Average:161.60 Median:161.50 stddev:1.17 (0.7%) stddev-sans-first:1.13 16:08:36 INFO - PROCESS | 8159 | Values: 163.0 162.0 164.0 160.0 162.0 161.0 161.0 161.0 161.0 161.0 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | [#3] audio-fft Cycles:10 Average:93.90 Median:75.00 stddev:39.13 (52.2%) stddev-sans-first:40.90 16:08:36 INFO - PROCESS | 8159 | Values: 75.0 76.0 75.0 173.0 75.0 76.0 76.0 75.0 163.0 75.0 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | [#4] audio-oscillator Cycles:10 Average:101.60 Median:83.00 stddev:28.33 (34.1%) stddev-sans-first:29.24 16:08:36 INFO - PROCESS | 8159 | Values: 83.0 83.0 149.0 90.0 83.0 82.0 138.0 85.0 83.0 140.0 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:1.41 (1.2%) stddev-sans-first:1.45 16:08:36 INFO - PROCESS | 8159 | Values: 117.0 117.0 117.0 118.0 117.0 120.0 121.0 117.0 118.0 118.0 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:147.00 stddev:0.97 (0.7%) stddev-sans-first:1.01 16:08:36 INFO - PROCESS | 8159 | Values: 147.0 147.0 147.0 147.0 148.0 147.0 148.0 150.0 147.0 147.0 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 16:08:36 INFO - PROCESS | 8159 | Values: 95.0 95.0 97.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.00 stddev:1.32 (2.6%) stddev-sans-first:1.22 16:08:36 INFO - PROCESS | 8159 | Values: 53.0 50.0 51.0 51.0 51.0 50.0 50.0 51.0 54.0 51.0 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:1.23 (2.0%) stddev-sans-first:1.30 16:08:36 INFO - PROCESS | 8159 | Values: 60.0 59.0 60.0 59.0 59.0 59.0 59.0 60.0 63.0 60.0 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | [#10] stanford-crypto-aes Cycles:10 Average:73.10 Median:73.00 stddev:1.20 (1.6%) stddev-sans-first:1.20 16:08:36 INFO - PROCESS | 8159 | Values: 72.0 72.0 72.0 73.0 74.0 73.0 75.0 73.0 75.0 72.0 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | [#11] stanford-crypto-ccm Cycles:10 Average:130.20 Median:129.50 stddev:18.55 (14.3%) stddev-sans-first:19.67 16:08:36 INFO - PROCESS | 8159 | Values: 129.0 121.0 121.0 122.0 130.0 130.0 131.0 117.0 181.0 120.0 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.70 Median:153.50 stddev:2.54 (1.7%) stddev-sans-first:2.65 16:08:36 INFO - PROCESS | 8159 | Values: 154.0 150.0 155.0 149.0 151.0 152.0 153.0 157.0 151.0 155.0 16:08:36 INFO - PROCESS | 8159 | 16:08:36 INFO - PROCESS | 8159 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:1.48 (2.4%) stddev-sans-first:1.33 16:08:36 INFO - PROCESS | 8159 | Values: 64.0 62.0 64.0 62.0 61.0 62.0 62.0 61.0 59.0 61.0 16:08:36 INFO - PROCESS | 8159 | -------- Summary: end -------- 16:08:36 INFO - PROCESS | 8159 | 16:08:37 INFO - TEST-INFO | 8159: exit 0 16:08:37 INFO - TEST-OK | kraken | took 61573ms 16:08:37 INFO - SUITE-END | took 1173s 16:08:37 INFO - Completed test suite (00:19:33) 16:08:37 INFO - javascript benchmark 16:08:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7862.363439558691, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3300.8514616842244, 3316.154380632939, 3320.319334536419, 3337.215754297738, 3343.3981883261017, 5752.597318187729, 5797.71011449428, 5831.154482600025, 5841.824745257639, 5843.561732870041, 5782.739776710047, 5805.767769289234, 5813.854653633651, 5846.269216347957, 5851.6196447230905, 5250.264962905206, 5303.893922121555, 5322.04203243416, 5345.572354211673, 5380.354357477088, 5359.062164121283, 5361.115415956382, 5364.517193452589, 5369.463053694619, 5392.0294347017525, 5156.278121063054, 5180.766865491062, 5366.275552800381, 5373.516383525482, 5383.623146379755, 5189.636725429222, 5286.444923283077, 5286.5242128208265, 5286.629935904488, 5334.91997620036, 5160.845174644766, 5243.8164664236565, 5254.31693879797, 5265.262863199156, 5296.17909224067, 5481.314835645544, 5649.661020338793, 5681.57388195887, 5759.568032397587, 5803.448672376117, 6118.112873633343, 6199.907001394983, 6204.286507051702, 6205.3484384139465, 6209.96895015522, 6214.8135555933395, 6214.937850621481, 6216.098665693495, 6223.222097237845, 6233.2208473940755, 5641.802536911229, 5762.654240745569, 5806.564507661942, 5819.88360232793, 5840.591158618899, 6186.536009799283, 6391.904121438182, 6402.967985160044, 6409.55133140685, 6412.1023056771155, 6113.296970848321, 6134.110553969649, 6170.938290617126, 6175.536834737412, 6188.133661287423, 6007.0, 6159.384061593894, 6202.627842329384, 6241.126242326078, 6261.405166509175, 6171.537134714914, 6288.056791481223, 6293.465055470245, 6295.905561416583, 6298.0, 6279.686015699197, 6286.402791734778, 6292.779752708633, 6324.525660575475, 6326.968365158237, 4602.240630296031, 4691.038337140878, 4805.37530121082, 4806.687565308243, 4812.663113582085, 4160.583941605876, 4221.831126754957, 4223.282042052859, 4260.488741351058, 4265.7440553566885, 6078.422549857756, 6090.23872015998, 6105.8168254952425, 6123.295820980555, 6123.601965872204, 6091.695415229221, 6137.959273351923, 6303.527235457359, 6349.682515874188, 6384.457321127663, 5984.491318237911, 6060.151578778975, 6061.696915154224, 6064.211652485237, 6109.541784366191, 5382.623216374816, 5394.109971854678, 5406.08096623575, 5414.133738601805, 5420.512153906167, 6183.628982261079, 6450.161479007699, 6469.644169570719, 6510.934890651128, 6512.316206798313, 6302.684865756785, 6312.337204593451, 6319.842003949938, 6324.747010119544, 6326.557141000086, 6542.378474044958, 6587.143671322698, 6588.538802283794, 6608.570442921195, 6617.07360969455, 5905.704714764331, 6126.029144987285, 6138.600990935575, 6186.257649082139, 6207.6275423474735, 6365.427111559982, 6366.77716279928, 6377.74489020434, 6382.5532212744665, 6383.39357761012, 6152.16945712341, 6169.660668663267, 6181.505479561714, 6204.348543402878, 6212.72042758086, 5855.151003104611, 5953.868764934648, 5975.790847320323, 6189.566730329014, 6193.504519638328, 6017.127516510169, 6021.969890150521, 6052.182955300978, 6056.333803281547, 6089.330173680803, 6006.849828754316, 6068.059450785136, 6077.878442431215, 6084.908726369196, 6096.02463605821, 5820.359760426265, 5896.823095306976, 5918.822435326947, 5925.52595792327, 5988.161657368059], "value": 5753.622150801415, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.5881512348919, 602.7317843559616, 635.440812085365, 648.8345471904663, 691.1257259566652, 43005.139897202076, 47321.0, 47545.52454475454, 47546.76226618866, 47615.52384476163, 48381.27428088582, 49083.50916490834, 49212.75393623027, 49216.50783492164, 49248.261276080884, 9073.228775554086, 9236.953815230949, 9260.53697315135, 9266.305027122993, 9275.072492750738, 9191.1727944485, 9271.629134834599, 9289.907100928971, 9298.86051709225, 9326.1606455419, 9370.672026479075, 9374.578143983537, 9391.577379017961, 9393.0, 9402.059794020612, 9252.722418327461, 9319.580618872133, 9324.720258392259, 9364.34449588529, 9373.437593744364, 9171.724848254564, 9256.0, 9261.44431334122, 9272.443653380818, 9289.256859451227, 44418.77790611058, 44854.0, 44899.55100449019, 44917.0, 44961.0, 3757.906052348686, 3807.0292075520565, 3909.2963266612, 4020.798960051986, 4311.590398912098, 31759.364812703847, 31875.52186717201, 32048.19879503008, 35789.46315805265, 37502.24995500075, 4516.345129956171, 4532.297493888453, 4589.059242855206, 4605.930911036337, 4630.606398456135, 375340.0, 375880.0, 376393.0, 376634.0, 377305.1134744336, 39480.605193948264, 67229.32770672328, 67400.6629966847, 67790.32209677939, 67880.32119678838, 39961.20077598432, 41889.58110418918, 42258.0, 42422.787886060374, 42581.0, 16246.837531624533, 16383.836161638232, 16415.686745060368, 16449.6710065798, 16451.095189764437, 13483.595492135251, 13525.45898164082, 13557.0, 13595.728085438235, 13610.931945340408, 9471.194948429324, 9484.478353690612, 9487.76280592977, 9569.37799043074, 9575.138237558653, 3505.2638945821664, 3536.6286539913453, 3540.2034542228307, 3556.146524834073, 3566.1262990567475, 1021.8518314844306, 1112.9109671226283, 1173.3487914207697, 1208.8247204155348, 1225.7058306006672, 35648.46527302093, 35659.64340356615, 35970.64029359673, 36035.819820901255, 36105.819470902476, 1157.716359491913, 1169.18741474676, 1170.5610396101463, 1185.17038073349, 1234.975300493985, 18250.81749182518, 18358.265669373344, 18493.075346232636, 18642.720359194624, 18691.532711682317, 581790.0, 585315.0, 586231.0, 588306.0, 588705.0, 66027.0, 66427.66786166135, 66676.66661666661, 66756.33243667598, 66867.0, 1395.1280449718927, 1429.485385261305, 1437.583100900748, 1445.5663301009656, 1458.1324112153252, 34056.318873622884, 34855.65144348524, 35003.299934000665, 35081.82459087688, 35166.47250291299, 52157.73921130522, 52266.47733522616, 52639.473605263454, 59133.70433147807, 59172.70413647904, 494381.0, 494541.0, 496488.0, 497774.0, 498281.0, 32649.510257345682, 32796.34407311888, 33104.66895331112, 33133.66866331306, 33204.50193247152, 54206.18690719721, 54543.18185227304, 54576.45423545714, 55054.17418738642, 55886.441135588124, 45585.77207113943, 47150.0, 47275.29087063628, 47492.762536187096, 48018.75990620025, 46469.76765116153, 47282.0, 47499.76250118727, 48059.0, 48677.26984095312, 38169.23661526809, 46676.299855502875, 46771.29843052425, 48229.0, 48956.51043489519, 40248.597514025656, 40357.39463908102, 40854.59145408508, 41019.0, 41096.589034109275, 30890.691093088783, 31462.685373145974, 31522.211944701565, 31681.683183167872, 31714.84142579272], "value": 20683.46240075748, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.97727939091277, 426.6522783931098, 433.3110354536288, 436.32587652077535, 436.3977710759152, 19403.902980485094, 20854.165833366667, 21354.57290854184, 21964.121435142613, 22193.334199973986, 20493.180272789108, 20592.794072059274, 20848.583028339424, 21066.36800895972, 21454.785452145476, 6283.403076707706, 6546.967265163667, 6808.489363297747, 6896.069030680844, 6902.447804175654, 6781.355771201753, 6783.016462612918, 6812.965935170318, 6813.693383797716, 6829.146356705412, 6352.205974253218, 6421.325760795119, 6440.935590644104, 6796.7621133260345, 6809.0467334573195, 6549.541532092755, 6713.194416669982, 6739.5619284746335, 6760.1549806274215, 6765.2219994700745, 6533.738650453976, 6554.967225163891, 6569.146011018537, 6593.670316484205, 6596.175478065242, 24058.15796447116, 26225.213243602724, 27232.863835680695, 27264.863675681496, 28289.292767680974, 2677.9866100669374, 2799.9020034298906, 2913.242556935191, 2997.8800847966054, 3017.7133172348813, 15556.844431555654, 16232.188390580424, 16251.756223656655, 16705.415310464195, 17011.82988170115, 2130.7975742304457, 2211.2039665720326, 2236.1167338901205, 2241.7758224177614, 2371.9288421347387, 94844.0, 104157.47921260422, 104280.0, 104656.0, 108229.0, 19178.8082119177, 19314.0, 19324.32364867223, 19700.25695117923, 19770.802291977183, 35210.64789352125, 35227.0, 35344.82327588346, 35349.64650353515, 35360.82319588386, 10140.0, 10437.425941573134, 10448.634297799541, 10455.581776728997, 10677.359358438518, 8609.741707748777, 8630.654773808978, 8656.826863462822, 8737.475751454933, 8994.235489983294, 9427.292953028413, 9552.522373881417, 9749.07383798538, 9750.171235444925, 9785.755356116155, 3383.543221665093, 3485.546878905726, 3536.876209332661, 3684.6499582539614, 3764.887053388403, 925.8888933327909, 1011.701548043329, 1023.0792286941663, 1091.2143256855052, 1093.2456604942565, 20594.691079633816, 20900.0, 21061.157553697987, 21125.89437052805, 21265.0, 998.1316254858175, 1012.9696109116738, 1052.4790228836775, 1095.3044816541553, 1123.8145705958589, 11370.602028928948, 11610.187286889837, 11774.88225117755, 11851.0, 11878.762424751629, 504527.0, 530079.0, 534540.0, 556137.0, 557885.0, 97812.51093744485, 98411.50794245982, 100944.0, 100974.0, 103374.0, 1018.6231094494937, 1028.187731691955, 1037.7301901505664, 1042.5569133118395, 1057.2070946789754, 18278.811877227938, 18483.815161848208, 18588.0, 18691.25234990585, 18760.812391876454, 45476.54523454723, 45620.0, 45693.0, 46151.0, 46271.07457850892, 58746.0, 59607.70196148992, 62743.6862815683, 62857.0, 63866.36133638604, 9123.817523649623, 9134.132257435533, 9164.083591640783, 9194.402363846328, 9253.120953509406, 13065.216087034429, 13188.670283242996, 13219.140755850838, 13816.654583635087, 14034.649133771738, 11703.941480292544, 11720.23818451832, 11837.171398002058, 11891.821622675494, 11988.280703157838, 11632.127590430582, 11781.410929453665, 11858.881411186123, 11933.701657458632, 12039.939800300943, 11731.23746956445, 11765.411729413323, 11767.588134415255, 12036.097292702907, 12390.938045309716, 8993.820123597361, 9111.817763644822, 9342.392744471588, 9426.858597120912, 9457.763555911157, 11924.224925379822, 12008.519659213884, 12334.444949977453, 12615.116941813985, 12639.241645501299], "value": 10979.840630594354, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1540.3145600207909, 1555.4011705493385, 1709.8290170982903, 1747.737839324102, 1849.8335149836512, 8832.160944710258, 9416.43501389917, 9598.904010959888, 10386.169106471483, 10388.013138751816, 9652.083052110056, 10073.798524029515, 10079.798404031915, 10081.747956301097, 10092.343997640148, 2590.248827839924, 2637.1692916731263, 2638.0371164525004, 2639.1686618715044, 2645.391559941215, 2572.0869091472537, 2618.4632150409216, 2645.828021178627, 2650.310919161013, 2652.801039922004, 2624.1340357682016, 2631.1054241557867, 2661.1750357389174, 2661.8669066546595, 2669.746374094458, 2550.872456377183, 2571.459993401388, 2579.85810780407, 2585.108137692493, 2586.405126820842, 2501.262127672341, 2529.5699731045765, 2555.1823416506727, 2556.8849401776965, 2558.5906254999295, 10102.797944041151, 10236.385816850938, 10248.948755256251, 10262.897371026344, 10275.280730348879, 5900.350961394243, 6064.636121832704, 6075.210222671025, 6077.696115194222, 6079.027355623123, 3530.2726354092456, 3562.1628917204434, 3645.5078564394, 3709.647583479565, 3709.998300458861, 1142.1091548592112, 1184.8518935133109, 1188.0614314691384, 1190.7320852808136, 1215.1858254969193, 13812.93093534536, 14033.929830350886, 14190.787138192936, 14575.562733118024, 14822.70354592913, 5064.442911279756, 5103.642745007814, 5172.741362931838, 5173.405058418255, 5303.920441193385, 11143.33140011602, 11152.996230339146, 11163.609273675382, 11166.330020198815, 11204.215704900578, 5816.302043754692, 5828.88342233153, 5828.941710582924, 5836.941630583725, 5857.853553661107, 5220.712860792617, 5350.170723537858, 5355.732213389315, 5432.375276843213, 5455.236266922634, 4294.957050429518, 4398.494173170063, 4433.866983990485, 4444.755538445416, 4456.910861782746, 1276.4000919567789, 1278.9424475898606, 1291.8449786025737, 1296.1769276509297, 1307.960761177147, 950.8336041192764, 1070.373831308693, 1085.7285678580356, 1087.190043417659, 1097.018168738974, 6590.703418346186, 6609.900851487136, 6735.29279425663, 6837.1111755471475, 6962.686679099453, 509.38364578859256, 514.572904489273, 517.7100823538755, 518.9351331083615, 530.0432718942269, 4564.6348292136545, 4570.9314360284625, 4690.179218636725, 4788.880277993008, 4866.026794641086, 11643.592474263529, 12513.43689533955, 13017.218966861828, 13035.674108147183, 13047.543335983195, 12012.459439325252, 12482.063845211464, 12493.50025998968, 12496.937515312366, 12503.187292825936, 9402.15380615748, 9604.03959604048, 9610.807783844284, 9613.759656008655, 9624.0, 6789.2871248518195, 7121.4302855770375, 7166.605836679033, 7166.713331466892, 7223.313785190398, 11466.361642932263, 11765.11761617865, 11957.282563046245, 12033.819492707791, 12036.338001409656, 11804.645860624196, 11836.0, 12064.93967530192, 12082.637520874388, 12236.082293828176, 8438.367122465717, 8610.741677749678, 8707.259882910024, 9081.591328390106, 9191.586378612845, 9600.903990960001, 9680.661176859057, 9695.612175513183, 9701.272404569827, 9721.951390243003, 8448.619812108593, 8454.0, 8543.145685431407, 8560.529170895661, 8674.78313042179, 8118.634661440131, 8292.83414331722, 8483.321334293365, 8641.913580864111, 8832.867506987272, 7384.96307518459, 7402.7409040683315, 7477.177510473953, 7514.8497030058, 7538.509996850187, 5687.402822703722, 5750.6262092963825, 5867.442592953663, 5965.135055417027, 5997.070454079625, 5770.740316685843, 5797.101449275369, 5802.361740208489, 5856.297244330707, 5861.1501332305725], "value": 5108.476693593487, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.6058496239099, 362.2429123132652, 363.9727020473465, 365.61427693783054, 367.52038589640506, 5591.7483713232905, 5595.692236926968, 5617.9438205617935, 5641.633293835903, 5642.633228840127, 5637.154426835967, 5651.717414129298, 5669.149627555868, 5670.432956704328, 5673.914891276634, 4487.775611219442, 4496.190685676577, 4498.212812757754, 4505.369248305232, 4507.887302817423, 4396.670249731267, 4419.166046090869, 4480.372747815292, 4506.414166158411, 4518.864434066983, 4425.269830477967, 4426.513083560806, 4435.2702446045905, 4437.88905277369, 4464.415132627919, 4439.156560253574, 4451.287793952968, 4451.844185453494, 4455.6658250631335, 4464.196444639963, 3798.2213646202463, 3885.30064588374, 3887.280853042168, 3900.9857437066285, 3915.7846318452475, 5545.890821835608, 5548.972255138739, 5550.86122846928, 5554.944450555483, 5567.2762540869835, 5078.365204349457, 5084.339035925343, 5096.337476128079, 5097.541221290065, 5102.438731739506, 5737.483626473596, 5921.61509501881, 5956.52347812174, 5957.910631340534, 5971.402439938641, 4305.526392096866, 4308.116836048634, 4314.546972567867, 4321.567843215653, 4330.675199360061, 6022.759089636409, 6186.628802271878, 6232.719527621268, 6239.750409983595, 6257.029035853691, 4433.202023635777, 4453.242948698729, 4454.688171828006, 4457.175422546839, 4459.509453960061, 4514.751688657156, 4516.593506584423, 4518.0, 4521.163584736834, 4524.8416305429155, 4806.663533552688, 5142.640015198975, 5143.639945203875, 5143.639945203875, 5150.536451719363, 5818.330891947396, 5935.228420305332, 5987.383406480226, 5993.880122397615, 6012.969935150384, 1568.1688704986375, 1581.8734501239874, 1588.809342878862, 1592.633694250329, 1605.414023881275, 993.9753125183985, 1018.4805749067881, 1034.4775888176573, 1034.8344264917578, 1047.6542740895486, 5154.355705536808, 5156.407013193456, 5168.405633352138, 5169.81905633301, 5192.350956130484, 5394.811181608625, 5395.70323632204, 5414.563446449035, 5447.373552041487, 5471.452854714496, 4961.826336078292, 5128.640995130305, 5156.8968620627375, 5170.3278573785165, 5173.793048278027, 2876.395956849085, 2883.6539615246306, 2890.4363649088227, 2905.7505272732506, 2919.7080291971056, 5555.666660000413, 5568.610197286151, 5596.888062238732, 5597.496225339658, 5599.44005599445, 5180.9740951295, 5374.915064550892, 5441.374241962146, 5462.2079798429, 5472.589555783253, 5583.659921618782, 5591.692456914909, 5630.438150641448, 5650.7739690412745, 5651.124075768263, 5246.42289348179, 5443.564514838883, 5446.509814116748, 5454.0, 5461.034027749927, 5242.554382877345, 5296.808218150885, 5299.39057008449, 5303.4013333465555, 5314.8937021260135, 5489.929463754689, 5509.393966663583, 5573.7983496519455, 5621.690807005654, 5648.689322087324, 3897.2984862724984, 4024.6176613221696, 4028.6575641069667, 4042.4744783178, 4082.8162732676506, 5372.62391632582, 5385.461453854583, 5399.409392753932, 6025.517958563392, 6225.564210505221, 6048.304444988707, 6080.452759251689, 6086.452219300284, 6089.330173680803, 6122.602030867979, 5941.7623295067715, 6060.545459090497, 6060.939390606214, 6081.452669259788, 6098.786542470993, 6032.276126864805, 6050.334463209131, 6070.332263451105, 6088.543359247986, 6106.3283038866575, 6045.335013148638, 6082.787102451393, 6115.969420152871, 6130.5402094842175, 6133.294671112879, 5837.445442682938, 5909.261342332208, 5943.554233432424, 5968.03054991843, 5985.790497332572], "value": 4413.45831169914, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.8112890648433, 737.6090671943872, 775.4726785785666, 803.2489622203241, 803.9155888631698, 16547.751783723255, 16766.161691915415, 16863.156842157903, 16895.901766385177, 16949.57626059349, 13571.253581053063, 13592.184468931871, 13600.0, 13601.0, 13610.31948402581, 714.2785786355767, 841.4488510025913, 846.6359465429863, 863.4646519158099, 870.4385671242077, 861.483110133921, 861.9181177788131, 863.386995233385, 864.8054187807755, 865.8787769712245, 925.8935222449403, 927.7448701607044, 929.0430856218106, 930.0234753508822, 932.2215949681989, 918.091089821079, 919.2554031234688, 922.3543519536317, 926.8100039491889, 927.8005228875791, 678.5487650712271, 679.4428568573771, 681.8772620928231, 681.9693113809841, 682.8327059870363, 15251.161186134757, 15299.15854627995, 15317.234138293152, 15337.693246135126, 15366.078035317916, 14116.223607701573, 14255.14469131845, 14328.785068223984, 14431.711365772731, 14550.927245363811, 11791.764164716744, 11843.822342664867, 11872.881271187263, 11894.226875253082, 12166.87833121675, 4125.381192821071, 4132.504099508048, 4165.146145040259, 4322.956770432287, 4334.436523251957, 87196.12803871944, 90414.0, 90599.0, 90945.0, 91700.08299916983, 11562.94218528902, 11584.0, 11610.825837612443, 11685.240459370114, 11732.12009099321, 25250.116245931305, 25294.1147059852, 25296.620550691754, 25314.74685253124, 25489.0, 9130.680426185052, 9188.724338269863, 9237.676681316256, 9266.583003764847, 9310.115539023782, 5193.792248310101, 5210.713410762444, 5239.947600523946, 5253.080710875582, 5260.868478288074, 3745.7752534847996, 3827.445020472016, 3855.4216867469545, 3857.4599556062176, 3880.165764360676, 680.6460640466929, 682.7234969837224, 682.9795106146823, 683.217715715503, 709.1773542690557, 776.0493395590356, 777.3780975219802, 781.8123650323995, 821.5440430560976, 837.7738010737066, 10185.287029907984, 10472.947635261928, 10508.99865218417, 10685.679429617125, 10728.463576821285, 734.1536036867283, 775.10862508115, 797.4258533855615, 808.5714571277232, 812.4515951732557, 4943.085529177114, 4951.804951804983, 4982.551570358685, 5016.347874776256, 5098.719570423588, 135018.3249083768, 135912.0, 136604.0, 136845.0, 141746.291268543, 93929.53035234917, 97331.51334243284, 115345.0, 118791.0, 120661.39669301597, 56237.718811405684, 69972.0, 85206.57396712976, 88918.9999999987, 89403.0, 10162.39025658463, 10278.588856445958, 10442.791144176921, 10756.139508839431, 10838.458077095956, 54946.0, 55571.44428555663, 56137.43862561322, 56168.0, 56800.14799777924, 70493.64753176406, 78905.60547197227, 80629.59685201537, 82019.58990205011, 82205.0, 10821.891781082404, 13325.400356983766, 13410.865891340962, 13550.254735989614, 13609.931950340186, 13915.0, 14648.926755366156, 14707.264636767904, 15303.770443443134, 15911.522654320388, 14083.647908802363, 14211.715765684836, 14443.638909027359, 14919.477818276308, 15376.154311512973, 13857.86142138606, 13902.304884755924, 14163.858361416254, 14380.424783008562, 14811.92594037066, 11517.25137866036, 11739.706507337385, 12416.130870838955, 13701.177929324273, 13809.378577963815, 11065.668029959113, 11323.263987840764, 11418.200725949104, 11686.0, 12601.495940162291, 11746.882531174579, 11960.58137965167, 12255.448504817483, 12321.968169576294, 12419.316937568521], "value": 8017.619611475748, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1498.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 108.0, 111.0, 112.0, 112.0, 113.0, 111.0, 111.0, 110.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 143.0, 180.0, 122.0, 150.0, 146.0, 123.0, 166.0, 121.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 164.0, 160.0, 162.0, 161.0, 161.0, 161.0, 161.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 173.0, 75.0, 76.0, 76.0, 75.0, 163.0, 75.0], "value": 93.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 149.0, 90.0, 83.0, 82.0, 138.0, 85.0, 83.0, 140.0], "value": 101.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 120.0, 121.0, 117.0, 118.0, 118.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 148.0, 147.0, 148.0, 150.0, 147.0, 147.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 97.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 51.0, 51.0, 51.0, 50.0, 50.0, 51.0, 54.0, 51.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 60.0, 63.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 73.0, 74.0, 73.0, 75.0, 73.0, 75.0, 72.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 121.0, 121.0, 122.0, 130.0, 130.0, 131.0, 117.0, 181.0, 120.0], "value": 130.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 150.0, 155.0, 149.0, 151.0, 152.0, 153.0, 157.0, 151.0, 155.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 64.0, 62.0, 61.0, 62.0, 62.0, 61.0, 59.0, 61.0], "value": 61.8, "unit": "score"}]}]} 16:08:37 INFO - Return code: 0 16:08:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:08:37 ERROR - # TBPL SUCCESS # 16:08:37 INFO - Running post-action listener: _resource_record_post_action 16:08:37 INFO - [mozharness: 2016-10-15 23:08:37.432588Z] Finished run-tests step (success) 16:08:37 INFO - Running post-run listener: _resource_record_post_run 16:08:37 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 287416320; Read time: 12; Write time: 638528 16:08:37 INFO - TinderboxPrint: CPU usage
13.3% 16:08:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:08:37 INFO - TinderboxPrint: I/O write bytes / time
287,416,320 / 638,528 16:08:37 INFO - TinderboxPrint: CPU idle
8,227.0 (86.7%) 16:08:37 INFO - TinderboxPrint: CPU user
1,198.8 (12.6%) 16:08:37 INFO - TinderboxPrint: Swap in / out
0 / 0 16:08:37 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 71319552; Read time: 0; Write time: 296372 16:08:37 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 216096768; Read time: 0; Write time: 342156 16:08:38 INFO - Running post-run listener: _upload_blobber_files 16:08:38 INFO - Blob upload gear active. 16:08:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:08:38 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 16:08:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:08:38 INFO - (blobuploader) - INFO - Open directory for files ... 16:08:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:08:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:08:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:39 INFO - (blobuploader) - INFO - Done attempting. 16:08:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:08:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:08:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:42 INFO - (blobuploader) - INFO - Done attempting. 16:08:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:08:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:08:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:43 INFO - (blobuploader) - INFO - Done attempting. 16:08:43 INFO - (blobuploader) - INFO - Iteration through files over. 16:08:43 INFO - Return code: 0 16:08:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:08:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:08:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/782a258567bd6756cb7cdce862440f2aad99ef82ef932e0874477c140dcfddd2c385219807dafbf5c261c528e6224ebcc6eb735cb1f5fcbbdf81ad740edbfa0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b6904c6c35708cf4954a13e88745c9d1fbeb3e5e8716acef17f6e66a0810e521cbb7a67f22533349ba8195ea561d142ebcc62fae92b8bd8563fd9329890f81ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d8232be22612e5872721c9ea5b23e398f63e5f0ebdc382fa6cda6209066de436343649278b4c7405d0d0735d585f6bb4fc5d3e33410b1fb2e91a9f03be4d76e"} 16:08:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:08:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:08:43 INFO - Contents: 16:08:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/782a258567bd6756cb7cdce862440f2aad99ef82ef932e0874477c140dcfddd2c385219807dafbf5c261c528e6224ebcc6eb735cb1f5fcbbdf81ad740edbfa0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b6904c6c35708cf4954a13e88745c9d1fbeb3e5e8716acef17f6e66a0810e521cbb7a67f22533349ba8195ea561d142ebcc62fae92b8bd8563fd9329890f81ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d8232be22612e5872721c9ea5b23e398f63e5f0ebdc382fa6cda6209066de436343649278b4c7405d0d0735d585f6bb4fc5d3e33410b1fb2e91a9f03be4d76e"} 16:08:43 INFO - Running post-run listener: copy_logs_to_upload_dir 16:08:43 INFO - Copying logs to upload dir... 16:08:43 INFO - mkdir: /builds/slave/test/build/upload/logs 16:08:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.482234 ========= master_lag: 0.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-10-15 16:08:42.567004) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-15 16:08:42.571564) ========= 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=8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/782a258567bd6756cb7cdce862440f2aad99ef82ef932e0874477c140dcfddd2c385219807dafbf5c261c528e6224ebcc6eb735cb1f5fcbbdf81ad740edbfa0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b6904c6c35708cf4954a13e88745c9d1fbeb3e5e8716acef17f6e66a0810e521cbb7a67f22533349ba8195ea561d142ebcc62fae92b8bd8563fd9329890f81ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d8232be22612e5872721c9ea5b23e398f63e5f0ebdc382fa6cda6209066de436343649278b4c7405d0d0735d585f6bb4fc5d3e33410b1fb2e91a9f03be4d76e"} build_url:https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011477 build_url: 'https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/782a258567bd6756cb7cdce862440f2aad99ef82ef932e0874477c140dcfddd2c385219807dafbf5c261c528e6224ebcc6eb735cb1f5fcbbdf81ad740edbfa0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b6904c6c35708cf4954a13e88745c9d1fbeb3e5e8716acef17f6e66a0810e521cbb7a67f22533349ba8195ea561d142ebcc62fae92b8bd8563fd9329890f81ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d8232be22612e5872721c9ea5b23e398f63e5f0ebdc382fa6cda6209066de436343649278b4c7405d0d0735d585f6bb4fc5d3e33410b1fb2e91a9f03be4d76e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-15 16:08:42.599703) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 16:08:42.600029) ========= 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=8783db0840cc980e80bb0bd400000236-1476570909.537365-1671004701 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004563 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 16:08:42.681043) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-15 16:08:42.684394) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-15 16:08:42.684685) ========= ========= Total master_lag: 0.25 =========