builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-039 starttime: 1476571244.09 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.089595) ========= 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.090074) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.090418) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.117753) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.118043) ========= 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=5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706 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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 104M=0s 2016-10-15 15:40:44 (104 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086061 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.222633) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.223315) ========= 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=5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015561 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.277758) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.278531) ========= 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=5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-15 15:40:44,650 truncating revision to first 12 chars 2016-10-15 15:40:44,651 Setting DEBUG logging. 2016-10-15 15:40:44,651 attempt 1/10 2016-10-15 15:40:44,651 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:44,955 unpacking tar archive at: mozilla-beta-c03e51cec3b5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.663269 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.963199) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.963921) ========= script_repo_revision: c03e51cec3b5f6b8821687c8db8be309727d5470 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.964962) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.965721) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-15 15:40:44.977582) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-10-15 15:40:44.977890) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706 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 --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', 15:40:45 INFO - 'system_bits': '32', 15:40:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:40:45 INFO - 'title': 'talos-linux64-ix-039', 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.443455Z] 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.402152Z] Finished clobber step (success) 15:40:46 INFO - [mozharness: 2016-10-15 22:40:46.402252Z] 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", 15:40:46 INFO - "repository": "", 15:40:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 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": 195, 15:40:46 INFO - "slavename": "talos-linux64-ix-039", 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.404689Z] Finished read-buildbot-config step (success) 15:40:46 INFO - [mozharness: 2016-10-15 22:40:46.404778Z] 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:21 INFO - Return code: 0 15:45:21 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:21 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:21 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:21 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:21 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:21 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:21 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:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:45:21 INFO - retry: Failed, sleeping 30 seconds before retrying 15:45:51 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:51 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:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:45:51 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:53 INFO - Return code: 0 15:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:46:53 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:53 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:53 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:53 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:53 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:53 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:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:46:53 INFO - retry: Failed, sleeping 30 seconds before retrying 15:47:23 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:23 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:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:47:23 INFO - retry: Failed, sleeping 60 seconds before retrying 15:48:23 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:23 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:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:48:23 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:23 INFO - Caught exception: HTTP Error 404: Not Found 15:48:23 INFO - Caught exception: HTTP Error 404: Not Found 15:48:23 INFO - Caught exception: HTTP Error 404: Not Found 15:48:23 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:23 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:23 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.109517Z] Finished download-and-extract step (success) 15:48:26 INFO - [mozharness: 2016-10-15 22:48:26.109749Z] 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.131364Z] Finished populate-webroot step (success) 15:48:26 INFO - [mozharness: 2016-10-15 22:48:26.131453Z] 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:26 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 0x12f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b13b0>, '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 0x15b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b2740>, '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', 15:48:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:28 INFO - '_': '/tools/buildbot/bin/python'} 15:48:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:28 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:28 INFO - Cleaning up... 15:48:28 INFO - Return code: 0 15:48:28 INFO - Installing psutil>=3.1.1 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', '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 0x12f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b13b0>, '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 0x15b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b2740>, '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', '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', 'psutil>=3.1.1'] 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 psutil>=3.1.1 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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', 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 - 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:31 INFO - Return code: 0 15:48:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:48:31 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:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:31 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:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:31 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 0x12f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b13b0>, '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 0x15b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b2740>, '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', '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:31 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:31 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:31 INFO - Using env: {'DISPLAY': ':0', 15:48:31 INFO - 'HOME': '/home/cltbld', 15:48:31 INFO - 'LANG': 'en_US.UTF-8', 15:48:31 INFO - 'LANGUAGE': 'en_US:en', 15:48:31 INFO - 'LOGNAME': 'cltbld', 15:48:31 INFO - 'MAIL': '/var/mail/cltbld', 15:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:31 INFO - 'MOZ_NO_REMOTE': '1', 15:48:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:31 INFO - 'NO_EM_RESTART': '1', 15:48:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:31 INFO - 'PWD': '/builds/slave/test', 15:48:31 INFO - 'SHELL': '/bin/bash', 15:48:31 INFO - 'SHLVL': '1', 15:48:31 INFO - 'TERM': 'linux', 15:48:31 INFO - 'TMOUT': '86400', 15:48:31 INFO - 'USER': 'cltbld', 15:48:31 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', 15:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:31 INFO - '_': '/tools/buildbot/bin/python'} 15:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:48:31 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:31 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 - 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:31 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:31 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:31 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:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:48:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:48:33 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:33 INFO - Installing collected packages: mozsystemmonitor 15:48:33 INFO - Running setup.py install for mozsystemmonitor 15:48:33 INFO - Successfully installed mozsystemmonitor 15:48:33 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 0x12f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b13b0>, '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 0x15b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b2740>, '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', 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 0x12f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b13b0>, '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 0x15b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b2740>, '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', 15:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:36 INFO - '_': '/tools/buildbot/bin/python'} 15:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:48:36 INFO - Cleaning up... 15:48:36 INFO - Return code: 0 15:48:36 INFO - Installing blobuploader==1.2.4 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', '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 0x12f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b13b0>, '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 0x15b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b2740>, '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', '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', 'blobuploader==1.2.4'] 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 blobuploader==1.2.4 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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', 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 - 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:38 INFO - Downloading blobuploader-1.2.4.tar.gz 15:48:38 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:39 INFO - Installing collected packages: blobuploader, requests, docopt 15:48:39 INFO - Running setup.py install for blobuploader 15:48:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:48:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:48:39 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 0x12f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b13b0>, '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 0x15b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b2740>, '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', 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-0kQdrO-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-uOqN2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:48:40 INFO - Running setup.py (path:/tmp/pip-jwloJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:48:40 INFO - Running setup.py (path:/tmp/pip-rTHOjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:48:40 INFO - Running setup.py (path:/tmp/pip-mNomtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:48:40 INFO - Running setup.py (path:/tmp/pip-J9szF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:48:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:48:40 INFO - Running setup.py (path:/tmp/pip-2buYl4-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-DsamXT-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-OUE17s-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-Lp_fYC-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-AdU2Gf-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-Ganmgc-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-YxSetw-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-jRW19a-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-gNNSx5-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-HhfnR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:48:41 INFO - Running setup.py (path:/tmp/pip-FYWPOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:48:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:48:41 INFO - Running setup.py install for manifestparser 15:48:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:48:41 INFO - Running setup.py install for mozcrash 15:48:41 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:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:48:42 INFO - Running setup.py install for moznetwork 15:48:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:48:42 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 0x12f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b13b0>, '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 0x15b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b2740>, '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', 15:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:43 INFO - '_': '/tools/buildbot/bin/python'} 15:48:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:48:43 INFO - Running setup.py (path:/tmp/pip-skMOJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:48:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:48:43 INFO - Running setup.py (path:/tmp/pip-lReM9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:48:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:48:43 INFO - Running setup.py (path:/tmp/pip-wdNyCt-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-rdnZOG-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-03DHe7-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-kwECsS-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-G92wfG-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-9Ef6sT-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-702i7K-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-7dZOA5-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-qILJrB-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-kVQL_O-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-TGHmwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:48:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:48:44 INFO - Running setup.py (path:/tmp/pip-sO98Nx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:48:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:44 INFO - Running setup.py (path:/tmp/pip-KqoLlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:48:44 INFO - Running setup.py (path:/tmp/pip-ksnnuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:48:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:48:44 INFO - Running setup.py (path:/tmp/pip-565nft-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:46 INFO - Downloading blessings-1.6.tar.gz 15:48:46 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 0x12f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b13b0>, '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 0x15b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b2740>, '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', 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 0x12f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b13b0>, '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 0x15b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b2740>, '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', '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': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', 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): jsonschema in ./venv/lib/python2.7/site-packages 15:48:47 INFO - Cleaning up... 15:48:47 INFO - Return code: 0 15:48:47 INFO - Running post-action listener: _resource_record_post_action 15:48:47 INFO - Running post-action listener: _start_resource_monitoring 15:48:47 INFO - Starting resource monitoring. 15:48:47 INFO - [mozharness: 2016-10-15 22:48:47.904586Z] Finished create-virtualenv step (success) 15:48:47 INFO - [mozharness: 2016-10-15 22:48:47.905013Z] Running install step. 15:48:47 INFO - Running pre-action listener: _resource_record_pre_action 15:48:47 INFO - Running main action method: install 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: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:02 INFO - Reading from file tmpfile_stdout 15:49:02 INFO - Output received: 15:49:02 INFO - /builds/slave/test/build/application/firefox/firefox 15:49:02 INFO - Running post-action listener: _resource_record_post_action 15:49:02 INFO - [mozharness: 2016-10-15 22:49:02.492249Z] Finished install step (success) 15:49:02 INFO - [mozharness: 2016-10-15 22:49:02.492531Z] Running run-tests step. 15:49:02 INFO - Running pre-action listener: _resource_record_pre_action 15:49:02 INFO - Running main action method: run_tests 15:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:49:02 INFO - Python 2.7.3 15:49:02 INFO - Return code: 0 15:49:02 INFO - grabbing minidump binary from tooltool 15:49:02 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:02 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 0x15b13b0>, '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 0x15b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b2740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:49:02 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:02 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:02 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:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:49:02 INFO - Return code: 0 15:49:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:49:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:49:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:49:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:49:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-039 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:49:02 INFO - Using env: {'DISPLAY': ':0', 15:49:02 INFO - 'HOME': '/home/cltbld', 15:49:02 INFO - 'LANG': 'en_US.UTF-8', 15:49:02 INFO - 'LANGUAGE': 'en_US:en', 15:49:02 INFO - 'LOGNAME': 'cltbld', 15:49:02 INFO - 'MAIL': '/var/mail/cltbld', 15:49:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:49:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:02 INFO - 'MOZ_NO_REMOTE': '1', 15:49:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:49:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:02 INFO - 'NO_EM_RESTART': '1', 15:49:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:02 INFO - 'PWD': '/builds/slave/test', 15:49:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:49:02 INFO - 'SHELL': '/bin/bash', 15:49:02 INFO - 'SHLVL': '1', 15:49:02 INFO - 'TERM': 'linux', 15:49:02 INFO - 'TMOUT': '86400', 15:49:02 INFO - 'USER': 'cltbld', 15:49:02 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706', 15:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:02 INFO - '_': '/tools/buildbot/bin/python'} 15:49:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/So4cfYWWR8qvsdk_yLoFlg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:49:02 INFO - mozversion application_buildid: 20161015124135 15:49:02 INFO - mozversion application_changeset: c03e51cec3b5f6b8821687c8db8be309727d5470 15:49:02 INFO - mozversion application_display_name: Firefox 15:49:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:49:02 INFO - mozversion application_name: Firefox 15:49:02 INFO - mozversion application_remotingname: firefox 15:49:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:49:02 INFO - mozversion application_vendor: Mozilla 15:49:02 INFO - mozversion application_version: 50.0 15:49:02 INFO - mozversion platform_buildid: 20161015124135 15:49:02 INFO - mozversion platform_changeset: c03e51cec3b5f6b8821687c8db8be309727d5470 15:49:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:49:02 INFO - mozversion platform_version: 50.0 15:49:02 INFO - using testdate: 1476571742 15:49:02 INFO - actual date: 1476571742 15:49:02 INFO - starting webserver on 'localhost:59426' 15:49:02 INFO - SUITE-START | Running 2 tests 15:49:02 INFO - TEST-START | dromaeo_css 15:49:02 INFO - operating with platform_type : linux_ 15:49:02 INFO - Initialising browser for dromaeo_css test... 15:49:02 INFO - TEST-INFO | started process 8148 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp42eYqX/profile http://localhost:59426/getInfo.html) 15:49:06 INFO - PROCESS | 8148 | __metrics Screen width/height:1600/1200 15:49:06 INFO - PROCESS | 8148 | colorDepth:24 15:49:06 INFO - PROCESS | 8148 | Browser inner width/height: 1024/697 15:49:06 INFO - PROCESS | 8148 | __metrics 15:49:07 INFO - PROCESS | 8148 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:49:07 INFO - PROCESS | 8148 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:49:08 INFO - TEST-INFO | 8148: exit 0 15:49:08 INFO - Browser initialized. 15:49:08 INFO - Running cycle 1/1 for dromaeo_css test... 15:49:08 INFO - TEST-INFO | started process 8237 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp42eYqX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:49:20 INFO - PROCESS | 8237 | RSS: Main: 144011264 15:49:20 INFO - PROCESS | 8237 | 15:50:11 INFO - PROCESS | 8237 | 1476571811757 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:19 INFO - PROCESS | 8237 | Cycle 1(1): loaded http://localhost:59426/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59426/tests/dromaeo/cssquery-ext.html) 15:52:20 INFO - PROCESS | 8237 | RSS: Main: 270151680 15:52:20 INFO - PROCESS | 8237 | 15:55:23 INFO - PROCESS | 8237 | Cycle 1(1): loaded http://localhost:59426/tests/dromaeo/cssquery-ext.html (next: http://localhost:59426/tests/dromaeo/cssquery-jquery.html) 15:55:23 INFO - PROCESS | 8237 | RSS: Main: 225640448 15:55:23 INFO - PROCESS | 8237 | 15:58:25 INFO - PROCESS | 8237 | Cycle 1(1): loaded http://localhost:59426/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59426/tests/dromaeo/cssquery-mootools.html) 15:58:26 INFO - PROCESS | 8237 | RSS: Main: 177070080 15:58:26 INFO - PROCESS | 8237 | 16:01:28 INFO - PROCESS | 8237 | Cycle 1(1): loaded http://localhost:59426/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59426/tests/dromaeo/cssquery-prototype.html) 16:01:28 INFO - PROCESS | 8237 | RSS: Main: 175120384 16:01:28 INFO - PROCESS | 8237 | 16:04:31 INFO - PROCESS | 8237 | Cycle 1(1): loaded http://localhost:59426/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59426/tests/dromaeo/cssquery-yui.html) 16:04:31 INFO - PROCESS | 8237 | RSS: Main: 177053696 16:04:31 INFO - PROCESS | 8237 | 16:07:34 INFO - PROCESS | 8237 | Cycle 1(1): loaded http://localhost:59426/tests/dromaeo/cssquery-yui.html (next: http://localhost:59426/tests/dromaeo/cssquery-dojo.html) 16:07:34 INFO - PROCESS | 8237 | RSS: Main: 268505088 16:07:34 INFO - PROCESS | 8237 | 16:07:34 INFO - PROCESS | 8237 | __start_tp_report 16:07:34 INFO - PROCESS | 8237 | _x_x_mozilla_page_load 16:07:34 INFO - PROCESS | 8237 | _x_x_mozilla_page_load_details 16:07:34 INFO - PROCESS | 8237 | |i|pagename|runs| 16:07:34 INFO - PROCESS | 8237 | |0;dojo.html;3230.822304773237;3230.8223047732376;3251.3009702913882;3276.3611095836322;3286.6220384655758;5538.612297139197;5635.267415236013;5641.435856414357;5647.124695672167;5657.490825825675;5626.184203290531;5640.182173584828;5680.091185410335;5684.857878553038;5688;5096.796128154879;5131.820386286481;5139;5194.58443324535;5203.68777873327;4970.950290497103;5074.822381216658;5184.351956005499;5198.2982297389935;5210.505002024802;5077.593792496591;5158.690478571279;5170.663906846062;5193.688378697271;5195.558377537914;5192.273081768555;5209.7134657593815;5214.97392513035;5220.399654039796;5248.081585722522;4891.8287859925085;5023.899522009576;5121.2318152277085;5125.3080834087295;5187.948120518784;5526.806561770359;5611.607187496877;5622.240997465372;5626.971865140689;5658.603897727161;6031.139087591775;6055.152278680988;6065.302490213638;6072.392760723937;6079.878402431971;5817.0692689169955;5988.431099045583;6042.9395706042815;6099.634021958653;6114.724837382329;5624.887502249932;5692.0323544997445;5693.089105743103;5693.5445164387165;5732.0542110551705;6173.660448675366;6207.131001659771;6210.72051757672;6211.378862113843;6218;5621.4659607337235;5844.649321040752;5966.194563733927;5966.701664916737;5967.6419414835245;5974;6057.757689692451;6064.969675151596;6097.634141951497;6099.664518451527;5971.313298970674;6001.159837622737;6001.879962400728;6010.819675409744;6013.6692481913915;5715.485606295453;5918.348981612019;5946.10808378738;6001.699915004232;6002.54980876436;4624.514425985223;4625.074985003012;4631.536846315409;4648.511906249795;4653.162430762495;4131.958680413218;4134.193832202692;4141.97929010353;4159.563245859202;4164;5515.420880807537;5625.803096891589;5821.912671309934;5822.155787410802;5831.9708401458565;5904;6000.039993601003;6012.819615411545;6015.248093988252;6026.819195424145;5700.686462244534;5702.6863522506665;5703.942960570341;5704.201411802351;5727.6849773262875;5075.314832497639;5082.618803589746;5191.299174611455;5195.246689230041;5202.505761952609;6194.318624951252;6206;6213.844653883689;6215.751369945242;6224.035274532454;5976.61152025117;5992.970035149797;6001.969990150022;6002.939970600238;6041.24484439445;5981.700914954322;6176.166217560572;6178.969105154445;6240.188775459162;6243.282022567463;5903.940960590339;5971.671558064349;5982.970085149546;5985.191999080243;5989.071693887455;6075.605085669417;6080.148779171008;6081.118237855398;6081.361457047124;6090.908636370369;5641.012822755919;5835.620684655484;5950.672713000653;5953.374895635896;5958.6424814511265;5864.560157988082;5935.970320148372;5954.434328738763;5977.850553736017;5984.581079324405;5882.647041177543;5915.08316210988;5916.852078697895;5923.822285331447;5926.911096333472;5665.830025099089;5819.272590926134;5880.323762767165;5910.408959104056;5983.222181116427;5752.71236438168;5818.2436283282905;5822.475977162076;5825.330087040044;5855.3266374365785 16:07:34 INFO - PROCESS | 8237 | |1;ext.html;573.6214098694862;628.2555172121034;630.9179806625139;641.2049059166633;644.764660898772;44413.77793111034;45647.31529027067;45809.54190458095;45835;50564.24153637698;47232.29151562721;47737.28394074083;47748.28377574339;47873.28190077143;47890.521094789045;8544.31645468361;9267.258619310456;9412.858807117864;9416.576254068548;11160.386178760165;10928.344299342065;11308.773824523505;11320.433978301093;11321.433928303593;11324.26392284503;11403.429828508582;11433.085353171726;11444.828327575093;11464.885351146466;11467.08263338931;11351.545938162464;11409.771804563945;11424.71438214043;11432.2569033013;11444.828327575093;11180;11280.32318060919;11301.604443844426;11301.660950171427;11303.095752339876;44403.333949990774;44730.32904506403;45156.096878062584;45204.32193517067;45205.32192017123;3592.0904067704982;3619.3304238715923;3675.7426980111404;3744.307303148899;4028.7582745035393;33949.490757638654;34060.82969585136;34528.82735586331;34568;34595.30809383798;4576.565226303496;4594.2419500782335;4658.813647454098;4660.953390466086;4744.1934871072;374794;377882;377897;379693.1015344933;381977;65919.34080659132;66110.66944665246;66288.6685566569;66294;66375.66812165908;39172;39400.60599394027;39930;42659;42665;17130.40043598468;17297.740533892003;17300.134993250285;17308;17309.56726081858;11960.700982475508;12298.139130260975;12886.226826390446;13090.345482725827;13153.868461315456;20477.38567842967;20635.793642063687;20690.68963965542;20699.27552535654;20725.79274207269;7264.709411623581;7310.890336644955;7369.557826530426;7418.146913105062;7468.4025277977635;1047.1936608811172;1047.7590154264562;1051.9737006574744;1130.513879032024;1131.6095946898306;34823;34873.30253394918;35336.82331588326;35470.93587192388;35479.64520354815;1084.7776205877776;1105.6793529876245;1108.2187058124002;1151.113642495274;1151.447305293447;17632;18085;18193;18271.634567308578;18303.816961830213;649116;650421;652350;654210;654423;63023.6848815753;63035.684821575596;63260.68369658214;63679;63753.36246637567;1328.5018118205674;1427.3505554972462;1446.768517037269;1459.875910547594;1481.7851411545482;32127;34028.31943361169;34273.14317142092;34395.312093757486;34592;50468.242976356116;50984.235236472225;51289;51315;51479.74260128675;397930;399057;400870;401609;403286;26557.867210663822;27240.182794516197;29565.704342956295;29686.257843554085;29954.550681740227;50637;50646.493535064175;50922.490775093254;51356;51501.48498514967;42183.578164219194;42474.575254247065;42520.362194566485;42728.35907461328;43992.12015759731;43263;43683.34474982941;44195;44704;44959.55040449685;42719;43501.34747978846;43550;43637.56362436335;43754.562454375045;35828;37844;37952.43071353987;38333.616663833;39241;29077.563836542045;29176.7082329174;29442.263943401587;29611.555826662185;29614.259643509085 16:07:34 INFO - PROCESS | 8237 | |2;jquery.html;396.3836234655114;418.18684092706025;418.2909967604911;430.5780335271434;440.1658856466995;18718;20388.388348349574;20405;20696;21061;20122;20325;20334.898325508388;20917.058732357043;21025.57948841026;5804.187413762066;6025.879482410349;6028.065649824284;6039.788607398742;6040.909386359208;5871.823845284648;5946.256717910262;5962.135490353896;5992.370801065891;6093.360197179299;5930.436608522184;5931.436513531229;5983.551233657493;5992.101184822268;5992.49063829575;5935.910961335583;6035.969820150893;6056.06131049688;6071.696415179267;6073.817785466421;5310.734463276859;5409.540189083933;5431.565474762009;5432.945670543283;5447.945520544784;24827.503449931082;25187.62218566723;25313.36716582082;25456.745432545624;25521.489570208676;2563.371973866396;2564.9358766031;2611.112221844582;2972.152936413112;3113.486274764661;15452.227388630523;15471.690566188614;15537.689246215126;15563.299651515708;15694.686106277924;1970.1528342812585;2003.4089943466713;2005.5487515309085;2011.406635042666;2026.5845501672122;58572;77403.6129819353;77407.61296193539;80552;86664.13335866686;9986.650467233612;10584.682459526226;11522.827157592643;11610.477528511237;11764.058875289957;16669;16699.91650041742;16719.91640041792;16744.581385465463;16781.83218167827;8190.426670133033;8254.257116859511;8369.3304535637;8457.70398036078;8606.827863442697;7778.49439786412;7800.141984381714;7932.960335198287;7960.4825686330205;8011.319037881729;7056.470764692669;7138.286171382924;7143.856842947095;7158.248383919614;7159.713611455483;2047.129969762845;2060.1553363121047;2120.703101565782;2152.5264441822774;2165.631842586764;896.0815164456484;987.6691308411763;993.3642468819961;998.6454808542862;1042.166266986408;21212.438488116513;21304.680429793567;21444.785552144593;21935.561288774454;22010.44973875634;990.8018396320765;1014.4065721552975;1036.7926414717087;1053.873535175784;1065.7921705267554;11250.212485126127;12161.574344898063;12204.938975305067;12233.632991010283;12333.75332493363;427788;551265;566648;567569;568218;101718.49140754249;102209;103925;104141;105523.4723826376;1011.7622358745689;1048.9213309001855;1075.2097208551709;1075.7848430313968;1084.3656460970262;17800.821991780434;18916.33792817245;19130.52173695669;19228.519287017938;19626.50933726668;45890;46287.305690413996;46543.30185047295;46893.06213875772;47052.7647361761;66194.66902665456;67193.66403167952;67309.32690673227;67410.32589674236;67975.66012169908;8467.544412120662;8659.913400865911;8687.478751274944;8764.21122099014;8812.647494100163;9782.972787857458;13632.454701811816;14478.203698796668;14511.419543218153;14558.781618275947;12076.637700868989;12491.812622810483;12533.248005119722;12593.559225427067;12683.365831708194;11239.775204496027;12021.639350819489;12435.626931192079;12797.232166070065;13026;12205.511779528719;12653.240805551697;12722.173058751152;12930.418131184308;12979.805302920275;9342.112499312554;9361.251099912128;9434.95282523583;9511.524423778921;9514.619415223591;10955.287906286067;12356.752864942831;12696.936515317733;12834.871651283367;13083.18711477037 16:07:34 INFO - PROCESS | 8237 | |3;mootools.html;1404.20662325786;1517.309624121025;1537.3619947721695;1658.7760652311933;1821.9635607287855;9203.861942070866;9642.710718678441;9687.951560242198;9901.306908516406;9944.552495137717;9767.609295628185;9778.804423911517;9863.802723945535;9887.11016008559;9888.950555247231;2531.4304281536693;2550.808689348297;2554.4891021795625;2556.143691863228;2558.6417901493805;2472.7279999200073;2547.1212431711124;2565.486902619474;2567.974320256792;2570.742925707433;2496.0889275414434;2527.5829488134527;2528.1404322530334;2530.8228424010326;2539.200151952138;2488.22864911877;2494.825362224645;2496.6379874918125;2536.961945570818;2546.2997675639162;2434;2443.6945381827272;2443.6945381827272;2469.407342237868;2539.162076514746;9728.562214700285;9905.059019393146;9918.504074796303;9922.454265015422;9929.702108936743;5611.410801865786;5814.622049566765;5858.56060795442;5956.851078723066;5960.135780311831;3495.4931534927546;3524.859005639771;3556.4309710446464;3605.5853576838476;3625.474306225609;1143.2168964259508;1146.2606618730956;1152.948117334722;1152.9884701152964;1170.6605084525477;13859.930700346535;13861.4455421783;13899;13932.999999999898;13948.860511394858;4906.288588154697;4961.5534601886;5072.746362681851;5081.212412076134;5141.126008578476;10787.67636970892;10790.352578845295;10795.514201860778;10823.891761082446;10833.079188269085;5860.443257890493;5861.325947516005;5865.032269675541;5866.061430171152;5869.972754767899;4685.976570117127;4807.4471435784635;5307.15085586312;5318.73406329682;5460.945390546044;4243.384709217208;4252.340887162495;4342.826286948487;4378.846740364072;4381.079973205662;1243.521244320943;1268.6320966919675;1273.1724379153625;1275.1265383212528;1280.3406245783428;971.3928794503436;980.2501086668705;988.0366642523483;992.3252188511881;1017.038898241155;6344.841378965562;6373.904391434132;6437.871242575216;6495.610263384212;6519.4132528072705;469.76746510477534;486.7006790823618;492.66991115952396;493.9407271127488;495.41293567123074;4146.813393397304;4193.916121677549;4195.517515485758;4200.558941311179;4214.578542145822;12198;12541.435635396429;12596.622101336974;12643.11498195136;12650.683732906567;11394.1454390921;12494.437750301258;12614.49542018309;12616.116871819067;12635.810462843065;9032.548372581345;9253.583588738524;9254.305927055364;9255.259579233645;9256.25949924018;6494.350564943468;6593.571417857824;6660.367265109807;6756.493263005196;6882.242953275236;11204.943975280072;11413.942930285295;11472.541098355972;11527.942360288145;11607.76784464323;10451.372917624967;10976.451177441255;11683.357415342238;11691.240069395462;11897.702557436134;8039.397045221749;8377.45546539473;8390.790230244293;8454.408191426786;9018.278537717099;9421.811563768822;9512.191463725661;9529.904700952902;9533.856992145262;9538;8003.799905002422;8049.51702897828;8103.6758529658155;8219.095899451175;8434.030086540126;7968.7609371718945;8025.8394832104195;8113.148119447373;8164.632591533276;8216.26053655174;7214.675339609626;7594.088709354692;7661.808454788675;7695.615219239127;7696.615169241628;5679.858003549944;5734.197212390185;5743.59794814359;5781.537477001913;5870.7945221917025;5590.217369568345;5614.606977511535;5716.885662286814;5761.740721667619;5794.333651629948 16:07:34 INFO - PROCESS | 8237 | |4;prototype.html;354.8509625957098;358.4283068505732;362.28991177292505;362.8185907046476;364.704589282681;5483.506484416402;5493.23094766733;5526.640768350055;5545.889082218363;5548.334199896018;5376.946230537694;5402.189671549269;5435.728213589325;5469.753861076251;5477.20580515825;4111.856085037017;4195.643370313512;4215.957840421603;4230.196262710076;4243.851465198719;4202.85290014848;4212.789360531977;4213.199492096507;4226.535081141072;4249.51130619978;4185.518665353477;4187.309093999486;4201.68487363449;4207.747535147901;4209.663226941837;4136.420901073837;4181.749095054276;4194.098268872177;4211.199552297719;4220.282551966157;3687.4284485904727;3690.3910854708943;3709.536307961505;3733.4959780429836;3734.495843061179;5285.947140528584;5295.258663787073;5311.176767601028;5322.281491998608;5333.306670132897;4731.4795372509;4805.3512775775525;4872.19608764557;4880.267959806022;4885.2427873679635;5595.720213989325;5610.551155907518;5616.494515493584;5620.9437905620825;5643.463870932255;4088.223237584879;4089.85685501009;4091.93357354537;4094.4472496213225;4111.588841115894;5661.5187709044785;5853.209816674737;5855.180274761537;5861.706914654293;5930.584859059867;4213.073123912734;4222.514410842792;4256.702030857872;4278.44380230568;4298.570142985676;4311.6335111515245;4323.654107671442;4327.956720432755;4329.675274354436;4335.414719012956;4641.767911604406;4874.513273451631;4912.140375434285;4919.06537757832;4924.507549245047;5645.435456454404;5651.632643878134;5653.434656534314;5653.802116925888;5711.171880077365;1478.2534819915875;1500.8949373543965;1507.6155580326947;1509.622594351412;1516.4010215964631;923.4690053219425;964.7973925475728;969.6993931880999;972.6838777397375;986.9013098690074;4942.678725882806;4952.2819191217795;4969.987155202149;4984.576311013532;5016.974915125401;5097.9745101275;5120.92318615221;5152.845414637492;5171.2501687255135;5175.637705360588;4565.383673204141;4908.558229759339;4954.653174277766;4973.850784476472;4981.950180498221;2711.5086702313647;2735.8221715588425;2737.7125401832523;2744.6121873969773;2752.875486323038;5285.656432331963;5315.176147697113;5346.518813306743;5397.298351214317;5429.647072940246;5264.42091369949;5320.388155362106;5551.361593416808;5660.433956604389;5947.226860508106;5902.321233058167;6085.17850090241;6092.817215483543;6099.878002439927;6107.236595425483;5869.6771677556435;5879.559033072451;5886.470217680429;5890.352061273342;5914.290285165807;5545.445455454423;5737.8565535861935;5783.161441590862;5789.334226563999;5789.9710501447225;6100.786472473442;6122.94881214798;6125.356837531995;6126.5098792097415;6133.6319820810895;4106.568810274878;4239;4242.766647834399;4258.808353624032;4266.893327666709;5835.766569337179;5837.1244313351635;5855.209546711139;5857;5864.413558644102;5707.800226992035;5731.9682457158115;5755.510781583613;5767.971160144172;5768.740406681625;5455.8363249102595;5712.343420408035;5736.368999410145;5757.6757345809865;5798.333191682842;5715.799947001835;5722.5693576605845;5722.942770572407;5730.942690573208;5742.310922689304;5584.553235741212;5745;5760.8271751847515;5772.191893135048;5833.329167145833;5611.831645050655;5616.691081990366;5619.241402410786;5627.324721033502;5657.8019769307875 16:07:34 INFO - PROCESS | 8237 | |5;yui.html;675.6790524500866;692.4944790303078;721.1562471907868;741.3253938915586;755.7090520149741;14739.263036848155;15648.765268520981;15729.528114156565;15771.842281577181;15817.84182158178;12927.353632318394;12934.676633084155;12947.482100715984;12950.611481655542;12954.93522532386;706.441910890396;837.7905523619095;856.0668870930684;861.2205953611991;865.2515574028452;850.1371108325055;852.0371979663004;852.4970267542149;852.9786755331104;853.5049671190726;906.954652267384;906.959186836594;907.6278725722456;908.4821651658557;908.954552272387;896.4486840592988;900.2797761790574;900.2932697832235;900.4147304252223;900.5092224737534;664.6278084272823;665.3379887012405;666.3003845961721;667.8998150277497;668.004673037176;14093.5067272646;14097;14128.576142715736;14141.85858141426;14142.787858182135;14166.29168541579;15141.924290378478;15222.162781047038;15394.692106157927;15677.529674109795;11417.600383986604;11445.885541144566;12071.879281207252;12271.141020128594;13133.146345487512;4672.322513235596;4679.836205732817;4691.906161876744;4714.646401519865;4831.492693267227;85686.57156714177;85903.57048214781;87055.12944870534;87280;88907.55546222292;11532.677905874523;11643.767124657628;11686.357250351311;11750.353730544732;11773.587924422776;19562.119704613327;19703.901480492506;19928.402147935583;19940.102695378744;20453;8414.78963025917;8539.658413663508;8600.483970961637;8810.955945220232;8812.77968050791;5009.599232061426;5022.547970682656;5083.186690129562;5094.1849304111165;5247.63266571344;3265.2816380396275;3406.710429613461;3409.931801363959;3420.3843308204764;3424.07549961509;660.8480049588621;663.4526515624628;669.1100835888354;682.5495173185674;685.7359916432209;588.5585810642018;617.8208319587359;662.1689779326914;671.3842333954066;707.2326525719549;8422.83154336922;8534.829303413897;8987.46075235475;9008.864867027;9271.397359171768;587.4389957590577;588.793922127252;617.9227596550431;670.1489108831756;734.713461749918;4189.874303770831;4224.704270701083;4278.187144442546;4290.613844753987;4373.562643735601;121264;127348.36325818497;130914.34542827225;131438;133718;109939;112739.43630281797;114851.42574287242;115747.42126289314;118633.40683296528;94723.52638236765;97166;97356;97429;100181.65574746787;8416.410851240355;8472;8507.149285071444;8588.785280368042;8633.093525180017;57204.42795571991;58770.41229587649;58911;60986.390136098074;61561.69219153876;77814.61092694501;85500.57249713711;85896.57051714702;87227;87458.56270718605;10236.590536378759;11275.605353812578;11310.377929213972;11713.297202167898;11717.238379505305;12252.387380630755;12488.625341239414;12649.430775615303;12825.73330538837;13038.739225215253;11717.179797414099;11852.466639001437;11905.821412678644;11936.76126477483;12234.204776689487;11937.462814173557;12130.878691212974;12695.36523173856;12720.618381448572;12807.871921281041;11625.418729063682;12074;12078.637640870787;12707;12812.743745124859;9685.17675997548;9767.21862251004;9769.560369783234;9934.552945117342;10605.522751476356;10102.191824653863;10314.123299519324;10331.380117192992;10445;10464.58141674355 16:07:34 INFO - PROCESS | 8237 | __end_tp_report 16:07:34 INFO - PROCESS | 8237 | __start_cc_report 16:07:34 INFO - PROCESS | 8237 | _x_x_mozilla_cycle_collect,1062 16:07:34 INFO - PROCESS | 8237 | __end_cc_report 16:07:34 INFO - PROCESS | 8237 | __startTimestamp1476572854454__endTimestamp 16:07:34 INFO - PROCESS | 8237 | 16:07:34 INFO - PROCESS | 8237 | ------- Summary: start ------- 16:07:34 INFO - PROCESS | 8237 | Number of tests: 6 16:07:34 INFO - PROCESS | 8237 | 16:07:34 INFO - PROCESS | 8237 | [#0] dojo.html Cycles:165 Average:5601.99 Median:5822.48 stddev:630.18 (10.8%) stddev-sans-first:604.04 16:07:34 INFO - PROCESS | 8237 | Values: 3230.8 3230.8 3251.3 3276.4 3286.6 5538.6 5635.3 5641.4 5647.1 5657.5 5626.2 5640.2 5680.1 5684.9 5688.0 5096.8 5131.8 5139.0 5194.6 5203.7 4971.0 5074.8 5184.4 5198.3 5210.5 5077.6 5158.7 5170.7 5193.7 5195.6 5192.3 5209.7 5215.0 5220.4 5248.1 4891.8 5023.9 5121.2 5125.3 5187.9 5526.8 5611.6 5622.2 5627.0 5658.6 6031.1 6055.2 6065.3 6072.4 6079.9 5817.1 5988.4 6042.9 6099.6 6114.7 5624.9 5692.0 5693.1 5693.5 5732.1 6173.7 6207.1 6210.7 6211.4 6218.0 5621.5 5844.6 5966.2 5966.7 5967.6 5974.0 6057.8 6065.0 6097.6 6099.7 5971.3 6001.2 6001.9 6010.8 6013.7 5715.5 5918.3 5946.1 6001.7 6002.5 4624.5 4625.1 4631.5 4648.5 4653.2 4132.0 4134.2 4142.0 4159.6 4164.0 5515.4 5625.8 5821.9 5822.2 5832.0 5904.0 6000.0 6012.8 6015.2 6026.8 5700.7 5702.7 5703.9 5704.2 5727.7 5075.3 5082.6 5191.3 5195.2 5202.5 6194.3 6206.0 6213.8 6215.8 6224.0 5976.6 5993.0 6002.0 6002.9 6041.2 5981.7 6176.2 6179.0 6240.2 6243.3 5903.9 5971.7 5983.0 5985.2 5989.1 6075.6 6080.1 6081.1 6081.4 6090.9 5641.0 5835.6 5950.7 5953.4 5958.6 5864.6 5936.0 5954.4 5977.9 5984.6 5882.6 5915.1 5916.9 5923.8 5926.9 5665.8 5819.3 5880.3 5910.4 5983.2 5752.7 5818.2 5822.5 5825.3 5855.3 16:07:34 INFO - PROCESS | 8237 | 16:07:34 INFO - PROCESS | 8237 | [#1] ext.html Cycles:180 Average:64329.58 Median:208822.77 stddev:131167.49 (62.8%) stddev-sans-first:131448.10 16:07:34 INFO - PROCESS | 8237 | Values: 573.6 628.3 630.9 641.2 644.8 44413.8 45647.3 45809.5 45835.0 50564.2 47232.3 47737.3 47748.3 47873.3 47890.5 8544.3 9267.3 9412.9 9416.6 11160.4 10928.3 11308.8 11320.4 11321.4 11324.3 11403.4 11433.1 11444.8 11464.9 11467.1 11351.5 11409.8 11424.7 11432.3 11444.8 11180.0 11280.3 11301.6 11301.7 11303.1 44403.3 44730.3 45156.1 45204.3 45205.3 3592.1 3619.3 3675.7 3744.3 4028.8 33949.5 34060.8 34528.8 34568.0 34595.3 4576.6 4594.2 4658.8 4661.0 4744.2 374794.0 377882.0 377897.0 379693.1 381977.0 65919.3 66110.7 66288.7 66294.0 66375.7 39172.0 39400.6 39930.0 42659.0 42665.0 17130.4 17297.7 17300.1 17308.0 17309.6 11960.7 12298.1 12886.2 13090.3 13153.9 20477.4 20635.8 20690.7 20699.3 20725.8 7264.7 7310.9 7369.6 7418.1 7468.4 1047.2 1047.8 1052.0 1130.5 1131.6 34823.0 34873.3 35336.8 35470.9 35479.6 1084.8 1105.7 1108.2 1151.1 1151.4 17632.0 18085.0 18193.0 18271.6 18303.8 649116.0 650421.0 652350.0 654210.0 654423.0 63023.7 63035.7 63260.7 63679.0 63753.4 1328.5 1427.4 1446.8 1459.9 1481.8 32127.0 34028.3 34273.1 34395.3 34592.0 50468.2 50984.2 51289.0 51315.0 51479.7 397930.0 399057.0 400870.0 401609.0 403286.0 26557.9 27240.2 29565.7 29686.3 29954.6 50637.0 50646.5 50922.5 51356.0 51501.5 42183.6 42474.6 42520.4 42728.4 43992.1 43263.0 43683.3 44195.0 44704.0 44959.6 42719.0 43501.3 43550.0 43637.6 43754.6 35828.0 37844.0 37952.4 38333.6 39241.0 29077.6 29176.7 29442.3 29611.6 29614.3 16:07:34 INFO - PROCESS | 8237 | 16:07:34 INFO - PROCESS | 8237 | [#2] jquery.html Cycles:180 Average:31560.09 Median:25385.06 stddev:88824.42 (349.9%) stddev-sans-first:89042.78 16:07:34 INFO - PROCESS | 8237 | Values: 396.4 418.2 418.3 430.6 440.2 18718.0 20388.4 20405.0 20696.0 21061.0 20122.0 20325.0 20334.9 20917.1 21025.6 5804.2 6025.9 6028.1 6039.8 6040.9 5871.8 5946.3 5962.1 5992.4 6093.4 5930.4 5931.4 5983.6 5992.1 5992.5 5935.9 6036.0 6056.1 6071.7 6073.8 5310.7 5409.5 5431.6 5432.9 5447.9 24827.5 25187.6 25313.4 25456.7 25521.5 2563.4 2564.9 2611.1 2972.2 3113.5 15452.2 15471.7 15537.7 15563.3 15694.7 1970.2 2003.4 2005.5 2011.4 2026.6 58572.0 77403.6 77407.6 80552.0 86664.1 9986.7 10584.7 11522.8 11610.5 11764.1 16669.0 16699.9 16719.9 16744.6 16781.8 8190.4 8254.3 8369.3 8457.7 8606.8 7778.5 7800.1 7933.0 7960.5 8011.3 7056.5 7138.3 7143.9 7158.2 7159.7 2047.1 2060.2 2120.7 2152.5 2165.6 896.1 987.7 993.4 998.6 1042.2 21212.4 21304.7 21444.8 21935.6 22010.4 990.8 1014.4 1036.8 1053.9 1065.8 11250.2 12161.6 12204.9 12233.6 12333.8 427788.0 551265.0 566648.0 567569.0 568218.0 101718.5 102209.0 103925.0 104141.0 105523.5 1011.8 1048.9 1075.2 1075.8 1084.4 17800.8 18916.3 19130.5 19228.5 19626.5 45890.0 46287.3 46543.3 46893.1 47052.8 66194.7 67193.7 67309.3 67410.3 67975.7 8467.5 8659.9 8687.5 8764.2 8812.6 9783.0 13632.5 14478.2 14511.4 14558.8 12076.6 12491.8 12533.2 12593.6 12683.4 11239.8 12021.6 12435.6 12797.2 13026.0 12205.5 12653.2 12722.2 12930.4 12979.8 9342.1 9361.3 9435.0 9511.5 9514.6 10955.3 12356.8 12696.9 12834.9 13083.2 16:07:34 INFO - PROCESS | 8237 | 16:07:34 INFO - PROCESS | 8237 | [#3] mootools.html Cycles:180 Average:6370.79 Median:2699.48 stddev:3745.25 (138.7%) stddev-sans-first:3737.16 16:07:34 INFO - PROCESS | 8237 | Values: 1404.2 1517.3 1537.4 1658.8 1822.0 9203.9 9642.7 9688.0 9901.3 9944.6 9767.6 9778.8 9863.8 9887.1 9889.0 2531.4 2550.8 2554.5 2556.1 2558.6 2472.7 2547.1 2565.5 2568.0 2570.7 2496.1 2527.6 2528.1 2530.8 2539.2 2488.2 2494.8 2496.6 2537.0 2546.3 2434.0 2443.7 2443.7 2469.4 2539.2 9728.6 9905.1 9918.5 9922.5 9929.7 5611.4 5814.6 5858.6 5956.9 5960.1 3495.5 3524.9 3556.4 3605.6 3625.5 1143.2 1146.3 1152.9 1153.0 1170.7 13859.9 13861.4 13899.0 13933.0 13948.9 4906.3 4961.6 5072.7 5081.2 5141.1 10787.7 10790.4 10795.5 10823.9 10833.1 5860.4 5861.3 5865.0 5866.1 5870.0 4686.0 4807.4 5307.2 5318.7 5460.9 4243.4 4252.3 4342.8 4378.8 4381.1 1243.5 1268.6 1273.2 1275.1 1280.3 971.4 980.3 988.0 992.3 1017.0 6344.8 6373.9 6437.9 6495.6 6519.4 469.8 486.7 492.7 493.9 495.4 4146.8 4193.9 4195.5 4200.6 4214.6 12198.0 12541.4 12596.6 12643.1 12650.7 11394.1 12494.4 12614.5 12616.1 12635.8 9032.5 9253.6 9254.3 9255.3 9256.3 6494.4 6593.6 6660.4 6756.5 6882.2 11204.9 11413.9 11472.5 11527.9 11607.8 10451.4 10976.5 11683.4 11691.2 11897.7 8039.4 8377.5 8390.8 8454.4 9018.3 9421.8 9512.2 9529.9 9533.9 9538.0 8003.8 8049.5 8103.7 8219.1 8434.0 7968.8 8025.8 8113.1 8164.6 8216.3 7214.7 7594.1 7661.8 7695.6 7696.6 5679.9 5734.2 5743.6 5781.5 5870.8 5590.2 5614.6 5716.9 5761.7 5794.3 16:07:34 INFO - PROCESS | 8237 | 16:07:34 INFO - PROCESS | 8237 | [#4] prototype.html Cycles:180 Average:4725.51 Median:5317.78 stddev:1385.98 (26.1%) stddev-sans-first:1350.49 16:07:34 INFO - PROCESS | 8237 | Values: 354.9 358.4 362.3 362.8 364.7 5483.5 5493.2 5526.6 5545.9 5548.3 5376.9 5402.2 5435.7 5469.8 5477.2 4111.9 4195.6 4216.0 4230.2 4243.9 4202.9 4212.8 4213.2 4226.5 4249.5 4185.5 4187.3 4201.7 4207.7 4209.7 4136.4 4181.7 4194.1 4211.2 4220.3 3687.4 3690.4 3709.5 3733.5 3734.5 5285.9 5295.3 5311.2 5322.3 5333.3 4731.5 4805.4 4872.2 4880.3 4885.2 5595.7 5610.6 5616.5 5620.9 5643.5 4088.2 4089.9 4091.9 4094.4 4111.6 5661.5 5853.2 5855.2 5861.7 5930.6 4213.1 4222.5 4256.7 4278.4 4298.6 4311.6 4323.7 4328.0 4329.7 4335.4 4641.8 4874.5 4912.1 4919.1 4924.5 5645.4 5651.6 5653.4 5653.8 5711.2 1478.3 1500.9 1507.6 1509.6 1516.4 923.5 964.8 969.7 972.7 986.9 4942.7 4952.3 4970.0 4984.6 5017.0 5098.0 5120.9 5152.8 5171.3 5175.6 4565.4 4908.6 4954.7 4973.9 4982.0 2711.5 2735.8 2737.7 2744.6 2752.9 5285.7 5315.2 5346.5 5397.3 5429.6 5264.4 5320.4 5551.4 5660.4 5947.2 5902.3 6085.2 6092.8 6099.9 6107.2 5869.7 5879.6 5886.5 5890.4 5914.3 5545.4 5737.9 5783.2 5789.3 5790.0 6100.8 6122.9 6125.4 6126.5 6133.6 4106.6 4239.0 4242.8 4258.8 4266.9 5835.8 5837.1 5855.2 5857.0 5864.4 5707.8 5732.0 5755.5 5768.0 5768.7 5455.8 5712.3 5736.4 5757.7 5798.3 5715.8 5722.6 5722.9 5730.9 5742.3 5584.6 5745.0 5760.8 5772.2 5833.3 5611.8 5616.7 5619.2 5627.3 5657.8 16:07:34 INFO - PROCESS | 8237 | 16:07:34 INFO - PROCESS | 8237 | [#5] yui.html Cycles:180 Average:22345.69 Median:4773.07 stddev:34380.44 (720.3%) stddev-sans-first:34438.38 16:07:34 INFO - PROCESS | 8237 | Values: 675.7 692.5 721.2 741.3 755.7 14739.3 15648.8 15729.5 15771.8 15817.8 12927.4 12934.7 12947.5 12950.6 12954.9 706.4 837.8 856.1 861.2 865.3 850.1 852.0 852.5 853.0 853.5 907.0 907.0 907.6 908.5 909.0 896.4 900.3 900.3 900.4 900.5 664.6 665.3 666.3 667.9 668.0 14093.5 14097.0 14128.6 14141.9 14142.8 14166.3 15141.9 15222.2 15394.7 15677.5 11417.6 11445.9 12071.9 12271.1 13133.1 4672.3 4679.8 4691.9 4714.6 4831.5 85686.6 85903.6 87055.1 87280.0 88907.6 11532.7 11643.8 11686.4 11750.4 11773.6 19562.1 19703.9 19928.4 19940.1 20453.0 8414.8 8539.7 8600.5 8811.0 8812.8 5009.6 5022.5 5083.2 5094.2 5247.6 3265.3 3406.7 3409.9 3420.4 3424.1 660.8 663.5 669.1 682.5 685.7 588.6 617.8 662.2 671.4 707.2 8422.8 8534.8 8987.5 9008.9 9271.4 587.4 588.8 617.9 670.1 734.7 4189.9 4224.7 4278.2 4290.6 4373.6 121264.0 127348.4 130914.3 131438.0 133718.0 109939.0 112739.4 114851.4 115747.4 118633.4 94723.5 97166.0 97356.0 97429.0 100181.7 8416.4 8472.0 8507.1 8588.8 8633.1 57204.4 58770.4 58911.0 60986.4 61561.7 77814.6 85500.6 85896.6 87227.0 87458.6 10236.6 11275.6 11310.4 11713.3 11717.2 12252.4 12488.6 12649.4 12825.7 13038.7 11717.2 11852.5 11905.8 11936.8 12234.2 11937.5 12130.9 12695.4 12720.6 12807.9 11625.4 12074.0 12078.6 12707.0 12812.7 9685.2 9767.2 9769.6 9934.6 10605.5 10102.2 10314.1 10331.4 10445.0 10464.6 16:07:34 INFO - PROCESS | 8237 | -------- Summary: end -------- 16:07:34 INFO - PROCESS | 8237 | 16:07:35 INFO - TEST-INFO | 8237: exit 0 16:07:35 INFO - TEST-OK | dromaeo_css | took 1112464ms 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 8429 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIe_3DK/profile http://localhost:59426/getInfo.html) 16:07:39 INFO - PROCESS | 8429 | __metrics Screen width/height:1600/1200 16:07:39 INFO - PROCESS | 8429 | colorDepth:24 16:07:39 INFO - PROCESS | 8429 | Browser inner width/height: 1024/697 16:07:39 INFO - PROCESS | 8429 | __metrics 16:07:40 INFO - PROCESS | 8429 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:07:40 INFO - PROCESS | 8429 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:07:40 INFO - PROCESS | 8429 | console.error: PushService: 16:07:40 INFO - PROCESS | 8429 | stateChangeProcessEnqueue: Error transitioning state 16:07:40 INFO - PROCESS | 8429 | UnknownError 16:07:41 INFO - TEST-INFO | 8429: exit 0 16:07:41 INFO - Browser initialized. 16:07:41 INFO - Running cycle 1/1 for kraken test... 16:07:41 INFO - TEST-INFO | started process 8516 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIe_3DK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:07:52 INFO - PROCESS | 8516 | RSS: Main: 144154624 16:07:52 INFO - PROCESS | 8516 | 16:07:55 INFO - PROCESS | 8516 | Cycle 1(1): loaded http://localhost:59426/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59426/tests/kraken/driver.html?testName=audio-beat-detection) 16:07:56 INFO - PROCESS | 8516 | RSS: Main: 199065600 16:07:56 INFO - PROCESS | 8516 | 16:07:58 INFO - PROCESS | 8516 | Cycle 1(1): loaded http://localhost:59426/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59426/tests/kraken/driver.html?testName=audio-dft) 16:07:59 INFO - PROCESS | 8516 | RSS: Main: 184414208 16:07:59 INFO - PROCESS | 8516 | 16:08:02 INFO - PROCESS | 8516 | Cycle 1(1): loaded http://localhost:59426/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59426/tests/kraken/driver.html?testName=audio-fft) 16:08:02 INFO - PROCESS | 8516 | RSS: Main: 275038208 16:08:02 INFO - PROCESS | 8516 | 16:08:04 INFO - PROCESS | 8516 | Cycle 1(1): loaded http://localhost:59426/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59426/tests/kraken/driver.html?testName=audio-oscillator) 16:08:05 INFO - PROCESS | 8516 | RSS: Main: 181248000 16:08:05 INFO - PROCESS | 8516 | 16:08:07 INFO - PROCESS | 8516 | Cycle 1(1): loaded http://localhost:59426/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59426/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:08:08 INFO - PROCESS | 8516 | RSS: Main: 179576832 16:08:08 INFO - PROCESS | 8516 | 16:08:11 INFO - PROCESS | 8516 | Cycle 1(1): loaded http://localhost:59426/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59426/tests/kraken/driver.html?testName=imaging-darkroom) 16:08:11 INFO - PROCESS | 8516 | RSS: Main: 215310336 16:08:11 INFO - PROCESS | 8516 | 16:08:15 INFO - PROCESS | 8516 | Cycle 1(1): loaded http://localhost:59426/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59426/tests/kraken/driver.html?testName=imaging-desaturate) 16:08:15 INFO - PROCESS | 8516 | RSS: Main: 214597632 16:08:15 INFO - PROCESS | 8516 | 16:08:18 INFO - PROCESS | 8516 | Cycle 1(1): loaded http://localhost:59426/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59426/tests/kraken/driver.html?testName=json-parse-financial) 16:08:19 INFO - PROCESS | 8516 | RSS: Main: 214466560 16:08:19 INFO - PROCESS | 8516 | 16:08:20 INFO - PROCESS | 8516 | Cycle 1(1): loaded http://localhost:59426/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59426/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:08:21 INFO - PROCESS | 8516 | RSS: Main: 175980544 16:08:21 INFO - PROCESS | 8516 | 16:08:22 INFO - PROCESS | 8516 | Cycle 1(1): loaded http://localhost:59426/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59426/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:08:23 INFO - PROCESS | 8516 | RSS: Main: 182190080 16:08:23 INFO - PROCESS | 8516 | 16:08:25 INFO - PROCESS | 8516 | Cycle 1(1): loaded http://localhost:59426/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59426/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:08:25 INFO - PROCESS | 8516 | RSS: Main: 189480960 16:08:25 INFO - PROCESS | 8516 | 16:08:28 INFO - PROCESS | 8516 | Cycle 1(1): loaded http://localhost:59426/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59426/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:08:29 INFO - PROCESS | 8516 | RSS: Main: 197042176 16:08:29 INFO - PROCESS | 8516 | 16:08:31 INFO - PROCESS | 8516 | Cycle 1(1): loaded http://localhost:59426/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59426/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:08:32 INFO - PROCESS | 8516 | RSS: Main: 194494464 16:08:32 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | Cycle 1(1): loaded http://localhost:59426/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59426/tests/kraken/driver.html?testName=ai-astar) 16:08:34 INFO - PROCESS | 8516 | RSS: Main: 243761152 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | __start_tp_report 16:08:34 INFO - PROCESS | 8516 | _x_x_mozilla_page_load 16:08:34 INFO - PROCESS | 8516 | _x_x_mozilla_page_load_details 16:08:34 INFO - PROCESS | 8516 | |i|pagename|runs| 16:08:34 INFO - PROCESS | 8516 | |0;ai-astar;113;113;120;111;111;111;112;112;111;111 16:08:34 INFO - PROCESS | 8516 | |1;audio-beat-detection;140;119;146;175;120;142;148;117;181;119 16:08:34 INFO - PROCESS | 8516 | |2;audio-dft;159;159;160;159;162;159;161;158;160;159 16:08:34 INFO - PROCESS | 8516 | |3;audio-fft;75;76;75;184;77;76;74;75;163;75 16:08:34 INFO - PROCESS | 8516 | |4;audio-oscillator;84;83;158;84;83;85;146;83;83;141 16:08:34 INFO - PROCESS | 8516 | |5;imaging-gaussian-blur;117;117;116;116;117;116;116;116;116;117 16:08:34 INFO - PROCESS | 8516 | |6;imaging-darkroom;146;146;145;145;146;146;146;146;146;146 16:08:34 INFO - PROCESS | 8516 | |7;imaging-desaturate;94;95;95;94;94;94;94;95;94;95 16:08:34 INFO - PROCESS | 8516 | |8;json-parse-financial;51;49;50;50;49;51;55;50;51;51 16:08:34 INFO - PROCESS | 8516 | |9;json-stringify-tinderbox;59;59;58;59;59;59;58;59;58;59 16:08:34 INFO - PROCESS | 8516 | |10;stanford-crypto-aes;73;74;82;73;77;73;73;73;73;79 16:08:34 INFO - PROCESS | 8516 | |11;stanford-crypto-ccm;125;129;124;126;122;120;128;121;161;123 16:08:34 INFO - PROCESS | 8516 | |12;stanford-crypto-pbkdf2;153;153;154;157;155;153;161;151;152;153 16:08:34 INFO - PROCESS | 8516 | |13;stanford-crypto-sha256-iterative;61;61;61;61;63;61;63;61;61;60 16:08:34 INFO - PROCESS | 8516 | __end_tp_report 16:08:34 INFO - PROCESS | 8516 | __start_cc_report 16:08:34 INFO - PROCESS | 8516 | _x_x_mozilla_cycle_collect,1678 16:08:34 INFO - PROCESS | 8516 | __end_cc_report 16:08:34 INFO - PROCESS | 8516 | __startTimestamp1476572914310__endTimestamp 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | ------- Summary: start ------- 16:08:34 INFO - PROCESS | 8516 | Number of tests: 14 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | [#0] ai-astar Cycles:10 Average:112.50 Median:112.00 stddev:2.76 (2.5%) stddev-sans-first:2.92 16:08:34 INFO - PROCESS | 8516 | Values: 113.0 113.0 120.0 111.0 111.0 111.0 112.0 112.0 111.0 111.0 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:144.00 stddev:23.13 (16.1%) stddev-sans-first:24.53 16:08:34 INFO - PROCESS | 8516 | Values: 140.0 119.0 146.0 175.0 120.0 142.0 148.0 117.0 181.0 119.0 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | [#2] audio-dft Cycles:10 Average:159.60 Median:159.50 stddev:1.17 (0.7%) stddev-sans-first:1.22 16:08:34 INFO - PROCESS | 8516 | Values: 159.0 159.0 160.0 159.0 162.0 159.0 161.0 158.0 160.0 159.0 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | [#3] audio-fft Cycles:10 Average:95.00 Median:75.00 stddev:41.68 (55.6%) stddev-sans-first:43.57 16:08:34 INFO - PROCESS | 8516 | Values: 75.0 76.0 75.0 184.0 77.0 76.0 74.0 75.0 163.0 75.0 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | [#4] audio-oscillator Cycles:10 Average:103.00 Median:83.00 stddev:31.56 (38.0%) stddev-sans-first:32.72 16:08:34 INFO - PROCESS | 8516 | Values: 84.0 83.0 158.0 84.0 83.0 85.0 146.0 83.0 83.0 141.0 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 16:08:34 INFO - PROCESS | 8516 | Values: 117.0 117.0 116.0 116.0 117.0 116.0 116.0 116.0 116.0 117.0 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 16:08:34 INFO - PROCESS | 8516 | Values: 146.0 146.0 145.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 16:08:34 INFO - PROCESS | 8516 | Values: 94.0 95.0 95.0 94.0 94.0 94.0 94.0 95.0 94.0 95.0 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:1.70 (3.3%) stddev-sans-first:1.80 16:08:34 INFO - PROCESS | 8516 | Values: 51.0 49.0 50.0 50.0 49.0 51.0 55.0 50.0 51.0 51.0 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 16:08:34 INFO - PROCESS | 8516 | Values: 59.0 59.0 58.0 59.0 59.0 59.0 58.0 59.0 58.0 59.0 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:73.50 stddev:3.23 (4.4%) stddev-sans-first:3.35 16:08:34 INFO - PROCESS | 8516 | Values: 73.0 74.0 82.0 73.0 77.0 73.0 73.0 73.0 73.0 79.0 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:125.50 stddev:11.99 (9.6%) stddev-sans-first:12.67 16:08:34 INFO - PROCESS | 8516 | Values: 125.0 129.0 124.0 126.0 122.0 120.0 128.0 121.0 161.0 123.0 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.20 Median:153.50 stddev:2.90 (1.9%) stddev-sans-first:3.04 16:08:34 INFO - PROCESS | 8516 | Values: 153.0 153.0 154.0 157.0 155.0 153.0 161.0 151.0 152.0 153.0 16:08:34 INFO - PROCESS | 8516 | 16:08:34 INFO - PROCESS | 8516 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.00 stddev:0.95 (1.6%) stddev-sans-first:1.00 16:08:34 INFO - PROCESS | 8516 | Values: 61.0 61.0 61.0 61.0 63.0 61.0 63.0 61.0 61.0 60.0 16:08:34 INFO - PROCESS | 8516 | -------- Summary: end -------- 16:08:34 INFO - PROCESS | 8516 | 16:08:35 INFO - TEST-INFO | 8516: exit 0 16:08:35 INFO - TEST-OK | kraken | took 60081ms 16:08:35 INFO - SUITE-END | took 1172s 16:08:35 INFO - Completed test suite (00:19:32) 16:08:35 INFO - javascript benchmark 16:08:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3230.822304773237, 3230.8223047732376, 3251.3009702913882, 3276.3611095836322, 3286.6220384655758, 5538.612297139197, 5635.267415236013, 5641.435856414357, 5647.124695672167, 5657.490825825675, 5626.184203290531, 5640.182173584828, 5680.091185410335, 5684.857878553038, 5688.0, 5096.796128154879, 5131.820386286481, 5139.0, 5194.58443324535, 5203.68777873327, 4970.950290497103, 5074.822381216658, 5184.351956005499, 5198.2982297389935, 5210.505002024802, 5077.593792496591, 5158.690478571279, 5170.663906846062, 5193.688378697271, 5195.558377537914, 5192.273081768555, 5209.7134657593815, 5214.97392513035, 5220.399654039796, 5248.081585722522, 4891.8287859925085, 5023.899522009576, 5121.2318152277085, 5125.3080834087295, 5187.948120518784, 5526.806561770359, 5611.607187496877, 5622.240997465372, 5626.971865140689, 5658.603897727161, 6031.139087591775, 6055.152278680988, 6065.302490213638, 6072.392760723937, 6079.878402431971, 5817.0692689169955, 5988.431099045583, 6042.9395706042815, 6099.634021958653, 6114.724837382329, 5624.887502249932, 5692.0323544997445, 5693.089105743103, 5693.5445164387165, 5732.0542110551705, 6173.660448675366, 6207.131001659771, 6210.72051757672, 6211.378862113843, 6218.0, 5621.4659607337235, 5844.649321040752, 5966.194563733927, 5966.701664916737, 5967.6419414835245, 5974.0, 6057.757689692451, 6064.969675151596, 6097.634141951497, 6099.664518451527, 5971.313298970674, 6001.159837622737, 6001.879962400728, 6010.819675409744, 6013.6692481913915, 5715.485606295453, 5918.348981612019, 5946.10808378738, 6001.699915004232, 6002.54980876436, 4624.514425985223, 4625.074985003012, 4631.536846315409, 4648.511906249795, 4653.162430762495, 4131.958680413218, 4134.193832202692, 4141.97929010353, 4159.563245859202, 4164.0, 5515.420880807537, 5625.803096891589, 5821.912671309934, 5822.155787410802, 5831.9708401458565, 5904.0, 6000.039993601003, 6012.819615411545, 6015.248093988252, 6026.819195424145, 5700.686462244534, 5702.6863522506665, 5703.942960570341, 5704.201411802351, 5727.6849773262875, 5075.314832497639, 5082.618803589746, 5191.299174611455, 5195.246689230041, 5202.505761952609, 6194.318624951252, 6206.0, 6213.844653883689, 6215.751369945242, 6224.035274532454, 5976.61152025117, 5992.970035149797, 6001.969990150022, 6002.939970600238, 6041.24484439445, 5981.700914954322, 6176.166217560572, 6178.969105154445, 6240.188775459162, 6243.282022567463, 5903.940960590339, 5971.671558064349, 5982.970085149546, 5985.191999080243, 5989.071693887455, 6075.605085669417, 6080.148779171008, 6081.118237855398, 6081.361457047124, 6090.908636370369, 5641.012822755919, 5835.620684655484, 5950.672713000653, 5953.374895635896, 5958.6424814511265, 5864.560157988082, 5935.970320148372, 5954.434328738763, 5977.850553736017, 5984.581079324405, 5882.647041177543, 5915.08316210988, 5916.852078697895, 5923.822285331447, 5926.911096333472, 5665.830025099089, 5819.272590926134, 5880.323762767165, 5910.408959104056, 5983.222181116427, 5752.71236438168, 5818.2436283282905, 5822.475977162076, 5825.330087040044, 5855.3266374365785], "value": 5559.8054146701825, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.6214098694862, 628.2555172121034, 630.9179806625139, 641.2049059166633, 644.764660898772, 44413.77793111034, 45647.31529027067, 45809.54190458095, 45835.0, 50564.24153637698, 47232.29151562721, 47737.28394074083, 47748.28377574339, 47873.28190077143, 47890.521094789045, 8544.31645468361, 9267.258619310456, 9412.858807117864, 9416.576254068548, 11160.386178760165, 10928.344299342065, 11308.773824523505, 11320.433978301093, 11321.433928303593, 11324.26392284503, 11403.429828508582, 11433.085353171726, 11444.828327575093, 11464.885351146466, 11467.08263338931, 11351.545938162464, 11409.771804563945, 11424.71438214043, 11432.2569033013, 11444.828327575093, 11180.0, 11280.32318060919, 11301.604443844426, 11301.660950171427, 11303.095752339876, 44403.333949990774, 44730.32904506403, 45156.096878062584, 45204.32193517067, 45205.32192017123, 3592.0904067704982, 3619.3304238715923, 3675.7426980111404, 3744.307303148899, 4028.7582745035393, 33949.490757638654, 34060.82969585136, 34528.82735586331, 34568.0, 34595.30809383798, 4576.565226303496, 4594.2419500782335, 4658.813647454098, 4660.953390466086, 4744.1934871072, 374794.0, 377882.0, 377897.0, 379693.1015344933, 381977.0, 65919.34080659132, 66110.66944665246, 66288.6685566569, 66294.0, 66375.66812165908, 39172.0, 39400.60599394027, 39930.0, 42659.0, 42665.0, 17130.40043598468, 17297.740533892003, 17300.134993250285, 17308.0, 17309.56726081858, 11960.700982475508, 12298.139130260975, 12886.226826390446, 13090.345482725827, 13153.868461315456, 20477.38567842967, 20635.793642063687, 20690.68963965542, 20699.27552535654, 20725.79274207269, 7264.709411623581, 7310.890336644955, 7369.557826530426, 7418.146913105062, 7468.4025277977635, 1047.1936608811172, 1047.7590154264562, 1051.9737006574744, 1130.513879032024, 1131.6095946898306, 34823.0, 34873.30253394918, 35336.82331588326, 35470.93587192388, 35479.64520354815, 1084.7776205877776, 1105.6793529876245, 1108.2187058124002, 1151.113642495274, 1151.447305293447, 17632.0, 18085.0, 18193.0, 18271.634567308578, 18303.816961830213, 649116.0, 650421.0, 652350.0, 654210.0, 654423.0, 63023.6848815753, 63035.684821575596, 63260.68369658214, 63679.0, 63753.36246637567, 1328.5018118205674, 1427.3505554972462, 1446.768517037269, 1459.875910547594, 1481.7851411545482, 32127.0, 34028.31943361169, 34273.14317142092, 34395.312093757486, 34592.0, 50468.242976356116, 50984.235236472225, 51289.0, 51315.0, 51479.74260128675, 397930.0, 399057.0, 400870.0, 401609.0, 403286.0, 26557.867210663822, 27240.182794516197, 29565.704342956295, 29686.257843554085, 29954.550681740227, 50637.0, 50646.493535064175, 50922.490775093254, 51356.0, 51501.48498514967, 42183.578164219194, 42474.575254247065, 42520.362194566485, 42728.35907461328, 43992.12015759731, 43263.0, 43683.34474982941, 44195.0, 44704.0, 44959.55040449685, 42719.0, 43501.34747978846, 43550.0, 43637.56362436335, 43754.562454375045, 35828.0, 37844.0, 37952.43071353987, 38333.616663833, 39241.0, 29077.563836542045, 29176.7082329174, 29442.263943401587, 29611.555826662185, 29614.259643509085], "value": 21440.732971080008, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.3836234655114, 418.18684092706025, 418.2909967604911, 430.5780335271434, 440.1658856466995, 18718.0, 20388.388348349574, 20405.0, 20696.0, 21061.0, 20122.0, 20325.0, 20334.898325508388, 20917.058732357043, 21025.57948841026, 5804.187413762066, 6025.879482410349, 6028.065649824284, 6039.788607398742, 6040.909386359208, 5871.823845284648, 5946.256717910262, 5962.135490353896, 5992.370801065891, 6093.360197179299, 5930.436608522184, 5931.436513531229, 5983.551233657493, 5992.101184822268, 5992.49063829575, 5935.910961335583, 6035.969820150893, 6056.06131049688, 6071.696415179267, 6073.817785466421, 5310.734463276859, 5409.540189083933, 5431.565474762009, 5432.945670543283, 5447.945520544784, 24827.503449931082, 25187.62218566723, 25313.36716582082, 25456.745432545624, 25521.489570208676, 2563.371973866396, 2564.9358766031, 2611.112221844582, 2972.152936413112, 3113.486274764661, 15452.227388630523, 15471.690566188614, 15537.689246215126, 15563.299651515708, 15694.686106277924, 1970.1528342812585, 2003.4089943466713, 2005.5487515309085, 2011.406635042666, 2026.5845501672122, 58572.0, 77403.6129819353, 77407.61296193539, 80552.0, 86664.13335866686, 9986.650467233612, 10584.682459526226, 11522.827157592643, 11610.477528511237, 11764.058875289957, 16669.0, 16699.91650041742, 16719.91640041792, 16744.581385465463, 16781.83218167827, 8190.426670133033, 8254.257116859511, 8369.3304535637, 8457.70398036078, 8606.827863442697, 7778.49439786412, 7800.141984381714, 7932.960335198287, 7960.4825686330205, 8011.319037881729, 7056.470764692669, 7138.286171382924, 7143.856842947095, 7158.248383919614, 7159.713611455483, 2047.129969762845, 2060.1553363121047, 2120.703101565782, 2152.5264441822774, 2165.631842586764, 896.0815164456484, 987.6691308411763, 993.3642468819961, 998.6454808542862, 1042.166266986408, 21212.438488116513, 21304.680429793567, 21444.785552144593, 21935.561288774454, 22010.44973875634, 990.8018396320765, 1014.4065721552975, 1036.7926414717087, 1053.873535175784, 1065.7921705267554, 11250.212485126127, 12161.574344898063, 12204.938975305067, 12233.632991010283, 12333.75332493363, 427788.0, 551265.0, 566648.0, 567569.0, 568218.0, 101718.49140754249, 102209.0, 103925.0, 104141.0, 105523.4723826376, 1011.7622358745689, 1048.9213309001855, 1075.2097208551709, 1075.7848430313968, 1084.3656460970262, 17800.821991780434, 18916.33792817245, 19130.52173695669, 19228.519287017938, 19626.50933726668, 45890.0, 46287.305690413996, 46543.30185047295, 46893.06213875772, 47052.7647361761, 66194.66902665456, 67193.66403167952, 67309.32690673227, 67410.32589674236, 67975.66012169908, 8467.544412120662, 8659.913400865911, 8687.478751274944, 8764.21122099014, 8812.647494100163, 9782.972787857458, 13632.454701811816, 14478.203698796668, 14511.419543218153, 14558.781618275947, 12076.637700868989, 12491.812622810483, 12533.248005119722, 12593.559225427067, 12683.365831708194, 11239.775204496027, 12021.639350819489, 12435.626931192079, 12797.232166070065, 13026.0, 12205.511779528719, 12653.240805551697, 12722.173058751152, 12930.418131184308, 12979.805302920275, 9342.112499312554, 9361.251099912128, 9434.95282523583, 9511.524423778921, 9514.619415223591, 10955.287906286067, 12356.752864942831, 12696.936515317733, 12834.871651283367, 13083.18711477037], "value": 9974.047251563385, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1404.20662325786, 1517.309624121025, 1537.3619947721695, 1658.7760652311933, 1821.9635607287855, 9203.861942070866, 9642.710718678441, 9687.951560242198, 9901.306908516406, 9944.552495137717, 9767.609295628185, 9778.804423911517, 9863.802723945535, 9887.11016008559, 9888.950555247231, 2531.4304281536693, 2550.808689348297, 2554.4891021795625, 2556.143691863228, 2558.6417901493805, 2472.7279999200073, 2547.1212431711124, 2565.486902619474, 2567.974320256792, 2570.742925707433, 2496.0889275414434, 2527.5829488134527, 2528.1404322530334, 2530.8228424010326, 2539.200151952138, 2488.22864911877, 2494.825362224645, 2496.6379874918125, 2536.961945570818, 2546.2997675639162, 2434.0, 2443.6945381827272, 2443.6945381827272, 2469.407342237868, 2539.162076514746, 9728.562214700285, 9905.059019393146, 9918.504074796303, 9922.454265015422, 9929.702108936743, 5611.410801865786, 5814.622049566765, 5858.56060795442, 5956.851078723066, 5960.135780311831, 3495.4931534927546, 3524.859005639771, 3556.4309710446464, 3605.5853576838476, 3625.474306225609, 1143.2168964259508, 1146.2606618730956, 1152.948117334722, 1152.9884701152964, 1170.6605084525477, 13859.930700346535, 13861.4455421783, 13899.0, 13932.999999999898, 13948.860511394858, 4906.288588154697, 4961.5534601886, 5072.746362681851, 5081.212412076134, 5141.126008578476, 10787.67636970892, 10790.352578845295, 10795.514201860778, 10823.891761082446, 10833.079188269085, 5860.443257890493, 5861.325947516005, 5865.032269675541, 5866.061430171152, 5869.972754767899, 4685.976570117127, 4807.4471435784635, 5307.15085586312, 5318.73406329682, 5460.945390546044, 4243.384709217208, 4252.340887162495, 4342.826286948487, 4378.846740364072, 4381.079973205662, 1243.521244320943, 1268.6320966919675, 1273.1724379153625, 1275.1265383212528, 1280.3406245783428, 971.3928794503436, 980.2501086668705, 988.0366642523483, 992.3252188511881, 1017.038898241155, 6344.841378965562, 6373.904391434132, 6437.871242575216, 6495.610263384212, 6519.4132528072705, 469.76746510477534, 486.7006790823618, 492.66991115952396, 493.9407271127488, 495.41293567123074, 4146.813393397304, 4193.916121677549, 4195.517515485758, 4200.558941311179, 4214.578542145822, 12198.0, 12541.435635396429, 12596.622101336974, 12643.11498195136, 12650.683732906567, 11394.1454390921, 12494.437750301258, 12614.49542018309, 12616.116871819067, 12635.810462843065, 9032.548372581345, 9253.583588738524, 9254.305927055364, 9255.259579233645, 9256.25949924018, 6494.350564943468, 6593.571417857824, 6660.367265109807, 6756.493263005196, 6882.242953275236, 11204.943975280072, 11413.942930285295, 11472.541098355972, 11527.942360288145, 11607.76784464323, 10451.372917624967, 10976.451177441255, 11683.357415342238, 11691.240069395462, 11897.702557436134, 8039.397045221749, 8377.45546539473, 8390.790230244293, 8454.408191426786, 9018.278537717099, 9421.811563768822, 9512.191463725661, 9529.904700952902, 9533.856992145262, 9538.0, 8003.799905002422, 8049.51702897828, 8103.6758529658155, 8219.095899451175, 8434.030086540126, 7968.7609371718945, 8025.8394832104195, 8113.148119447373, 8164.632591533276, 8216.26053655174, 7214.675339609626, 7594.088709354692, 7661.808454788675, 7695.615219239127, 7696.615169241628, 5679.858003549944, 5734.197212390185, 5743.59794814359, 5781.537477001913, 5870.7945221917025, 5590.217369568345, 5614.606977511535, 5716.885662286814, 5761.740721667619, 5794.333651629948], "value": 4942.923113532003, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.8509625957098, 358.4283068505732, 362.28991177292505, 362.8185907046476, 364.704589282681, 5483.506484416402, 5493.23094766733, 5526.640768350055, 5545.889082218363, 5548.334199896018, 5376.946230537694, 5402.189671549269, 5435.728213589325, 5469.753861076251, 5477.20580515825, 4111.856085037017, 4195.643370313512, 4215.957840421603, 4230.196262710076, 4243.851465198719, 4202.85290014848, 4212.789360531977, 4213.199492096507, 4226.535081141072, 4249.51130619978, 4185.518665353477, 4187.309093999486, 4201.68487363449, 4207.747535147901, 4209.663226941837, 4136.420901073837, 4181.749095054276, 4194.098268872177, 4211.199552297719, 4220.282551966157, 3687.4284485904727, 3690.3910854708943, 3709.536307961505, 3733.4959780429836, 3734.495843061179, 5285.947140528584, 5295.258663787073, 5311.176767601028, 5322.281491998608, 5333.306670132897, 4731.4795372509, 4805.3512775775525, 4872.19608764557, 4880.267959806022, 4885.2427873679635, 5595.720213989325, 5610.551155907518, 5616.494515493584, 5620.9437905620825, 5643.463870932255, 4088.223237584879, 4089.85685501009, 4091.93357354537, 4094.4472496213225, 4111.588841115894, 5661.5187709044785, 5853.209816674737, 5855.180274761537, 5861.706914654293, 5930.584859059867, 4213.073123912734, 4222.514410842792, 4256.702030857872, 4278.44380230568, 4298.570142985676, 4311.6335111515245, 4323.654107671442, 4327.956720432755, 4329.675274354436, 4335.414719012956, 4641.767911604406, 4874.513273451631, 4912.140375434285, 4919.06537757832, 4924.507549245047, 5645.435456454404, 5651.632643878134, 5653.434656534314, 5653.802116925888, 5711.171880077365, 1478.2534819915875, 1500.8949373543965, 1507.6155580326947, 1509.622594351412, 1516.4010215964631, 923.4690053219425, 964.7973925475728, 969.6993931880999, 972.6838777397375, 986.9013098690074, 4942.678725882806, 4952.2819191217795, 4969.987155202149, 4984.576311013532, 5016.974915125401, 5097.9745101275, 5120.92318615221, 5152.845414637492, 5171.2501687255135, 5175.637705360588, 4565.383673204141, 4908.558229759339, 4954.653174277766, 4973.850784476472, 4981.950180498221, 2711.5086702313647, 2735.8221715588425, 2737.7125401832523, 2744.6121873969773, 2752.875486323038, 5285.656432331963, 5315.176147697113, 5346.518813306743, 5397.298351214317, 5429.647072940246, 5264.42091369949, 5320.388155362106, 5551.361593416808, 5660.433956604389, 5947.226860508106, 5902.321233058167, 6085.17850090241, 6092.817215483543, 6099.878002439927, 6107.236595425483, 5869.6771677556435, 5879.559033072451, 5886.470217680429, 5890.352061273342, 5914.290285165807, 5545.445455454423, 5737.8565535861935, 5783.161441590862, 5789.334226563999, 5789.9710501447225, 6100.786472473442, 6122.94881214798, 6125.356837531995, 6126.5098792097415, 6133.6319820810895, 4106.568810274878, 4239.0, 4242.766647834399, 4258.808353624032, 4266.893327666709, 5835.766569337179, 5837.1244313351635, 5855.209546711139, 5857.0, 5864.413558644102, 5707.800226992035, 5731.9682457158115, 5755.510781583613, 5767.971160144172, 5768.740406681625, 5455.8363249102595, 5712.343420408035, 5736.368999410145, 5757.6757345809865, 5798.333191682842, 5715.799947001835, 5722.5693576605845, 5722.942770572407, 5730.942690573208, 5742.310922689304, 5584.553235741212, 5745.0, 5760.8271751847515, 5772.191893135048, 5833.329167145833, 5611.831645050655, 5616.691081990366, 5619.241402410786, 5627.324721033502, 5657.8019769307875], "value": 4292.654966745543, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.6790524500866, 692.4944790303078, 721.1562471907868, 741.3253938915586, 755.7090520149741, 14739.263036848155, 15648.765268520981, 15729.528114156565, 15771.842281577181, 15817.84182158178, 12927.353632318394, 12934.676633084155, 12947.482100715984, 12950.611481655542, 12954.93522532386, 706.441910890396, 837.7905523619095, 856.0668870930684, 861.2205953611991, 865.2515574028452, 850.1371108325055, 852.0371979663004, 852.4970267542149, 852.9786755331104, 853.5049671190726, 906.954652267384, 906.959186836594, 907.6278725722456, 908.4821651658557, 908.954552272387, 896.4486840592988, 900.2797761790574, 900.2932697832235, 900.4147304252223, 900.5092224737534, 664.6278084272823, 665.3379887012405, 666.3003845961721, 667.8998150277497, 668.004673037176, 14093.5067272646, 14097.0, 14128.576142715736, 14141.85858141426, 14142.787858182135, 14166.29168541579, 15141.924290378478, 15222.162781047038, 15394.692106157927, 15677.529674109795, 11417.600383986604, 11445.885541144566, 12071.879281207252, 12271.141020128594, 13133.146345487512, 4672.322513235596, 4679.836205732817, 4691.906161876744, 4714.646401519865, 4831.492693267227, 85686.57156714177, 85903.57048214781, 87055.12944870534, 87280.0, 88907.55546222292, 11532.677905874523, 11643.767124657628, 11686.357250351311, 11750.353730544732, 11773.587924422776, 19562.119704613327, 19703.901480492506, 19928.402147935583, 19940.102695378744, 20453.0, 8414.78963025917, 8539.658413663508, 8600.483970961637, 8810.955945220232, 8812.77968050791, 5009.599232061426, 5022.547970682656, 5083.186690129562, 5094.1849304111165, 5247.63266571344, 3265.2816380396275, 3406.710429613461, 3409.931801363959, 3420.3843308204764, 3424.07549961509, 660.8480049588621, 663.4526515624628, 669.1100835888354, 682.5495173185674, 685.7359916432209, 588.5585810642018, 617.8208319587359, 662.1689779326914, 671.3842333954066, 707.2326525719549, 8422.83154336922, 8534.829303413897, 8987.46075235475, 9008.864867027, 9271.397359171768, 587.4389957590577, 588.793922127252, 617.9227596550431, 670.1489108831756, 734.713461749918, 4189.874303770831, 4224.704270701083, 4278.187144442546, 4290.613844753987, 4373.562643735601, 121264.0, 127348.36325818497, 130914.34542827225, 131438.0, 133718.0, 109939.0, 112739.43630281797, 114851.42574287242, 115747.42126289314, 118633.40683296528, 94723.52638236765, 97166.0, 97356.0, 97429.0, 100181.65574746787, 8416.410851240355, 8472.0, 8507.149285071444, 8588.785280368042, 8633.093525180017, 57204.42795571991, 58770.41229587649, 58911.0, 60986.390136098074, 61561.69219153876, 77814.61092694501, 85500.57249713711, 85896.57051714702, 87227.0, 87458.56270718605, 10236.590536378759, 11275.605353812578, 11310.377929213972, 11713.297202167898, 11717.238379505305, 12252.387380630755, 12488.625341239414, 12649.430775615303, 12825.73330538837, 13038.739225215253, 11717.179797414099, 11852.466639001437, 11905.821412678644, 11936.76126477483, 12234.204776689487, 11937.462814173557, 12130.878691212974, 12695.36523173856, 12720.618381448572, 12807.871921281041, 11625.418729063682, 12074.0, 12078.637640870787, 12707.0, 12812.743745124859, 9685.17675997548, 9767.21862251004, 9769.560369783234, 9934.552945117342, 10605.522751476356, 10102.191824653863, 10314.123299519324, 10331.380117192992, 10445.0, 10464.58141674355], "value": 7522.86269661034, "unit": "score"}], "name": "dromaeo_css", "value": 7580.853277671442, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 120.0, 111.0, 111.0, 111.0, 112.0, 112.0, 111.0, 111.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 119.0, 146.0, 175.0, 120.0, 142.0, 148.0, 117.0, 181.0, 119.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 159.0, 160.0, 159.0, 162.0, 159.0, 161.0, 158.0, 160.0, 159.0], "value": 159.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 184.0, 77.0, 76.0, 74.0, 75.0, 163.0, 75.0], "value": 95.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 158.0, 84.0, 83.0, 85.0, 146.0, 83.0, 83.0, 141.0], "value": 103.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 50.0, 50.0, 49.0, 51.0, 55.0, 50.0, 51.0, 51.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 59.0, 59.0, 59.0, 58.0, 59.0, 58.0, 59.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 82.0, 73.0, 77.0, 73.0, 73.0, 73.0, 73.0, 79.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 129.0, 124.0, 126.0, 122.0, 120.0, 128.0, 121.0, 161.0, 123.0], "value": 127.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 154.0, 157.0, 155.0, 153.0, 161.0, 151.0, 152.0, 153.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 63.0, 61.0, 63.0, 61.0, 61.0, 60.0], "value": 61.3, "unit": "score"}], "name": "kraken", "value": 1495.2, "alertThreshold": 2.0}]} 16:08:35 INFO - Return code: 0 16:08:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:08:35 INFO - # TBPL SUCCESS # 16:08:35 INFO - Running post-action listener: _resource_record_post_action 16:08:35 INFO - [mozharness: 2016-10-15 23:08:35.641774Z] Finished run-tests step (success) 16:08:35 INFO - Running post-run listener: _resource_record_post_run 16:08:35 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 2236416; Write bytes: 288526336; Read time: 232; Write time: 237996 16:08:35 INFO - TinderboxPrint: CPU usage
12.9% 16:08:35 INFO - TinderboxPrint: I/O read bytes / time
2,236,416 / 232 16:08:35 INFO - TinderboxPrint: I/O write bytes / time
288,526,336 / 237,996 16:08:35 INFO - TinderboxPrint: CPU idle
8,250.6 (87.1%) 16:08:35 INFO - TinderboxPrint: CPU user
1,181.1 (12.5%) 16:08:35 INFO - TinderboxPrint: Swap in / out
0 / 0 16:08:35 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 71299072; Read time: 0; Write time: 104724 16:08:35 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 495616; Write bytes: 217227264; Read time: 148; Write time: 133272 16:08:36 INFO - Running post-run listener: _upload_blobber_files 16:08:36 INFO - Blob upload gear active. 16:08:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:08:36 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:36 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:36 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:36 INFO - (blobuploader) - INFO - Open directory for files ... 16:08:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:08:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: 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/dromaeojs_raw.log ... 16:08:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:08:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:40 INFO - (blobuploader) - INFO - Done attempting. 16:08:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:08:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:08:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:41 INFO - (blobuploader) - INFO - Done attempting. 16:08:41 INFO - (blobuploader) - INFO - Iteration through files over. 16:08:41 INFO - Return code: 0 16:08:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:08:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:08:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/89f6ba4f66b7b1dad01213c69738ac7ca69f1fa37400729a76c4c0a9304e47f836d9e6968c7f8cfb1f5b37116e33c792ac800f1628e4155006a3b88fa6711ae9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a245ff7394e53d618547291fb72b4a882ae9c110fdaa7a95ccdd2fbac25ce37b84da1cc85c0ed49e1d3c0ed9b354ba7c02a609c772b784db4e4b442773768e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca6b4e16f7758d6ea74fa4d25c644cbce785e9917d54c5ef580dc6abc97d3145c1de3297582843f3d71f6d0e36bf61c6713d082fe46d32d4314d95f8796d43df"} 16:08:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:08:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:08:41 INFO - Contents: 16:08:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/89f6ba4f66b7b1dad01213c69738ac7ca69f1fa37400729a76c4c0a9304e47f836d9e6968c7f8cfb1f5b37116e33c792ac800f1628e4155006a3b88fa6711ae9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a245ff7394e53d618547291fb72b4a882ae9c110fdaa7a95ccdd2fbac25ce37b84da1cc85c0ed49e1d3c0ed9b354ba7c02a609c772b784db4e4b442773768e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca6b4e16f7758d6ea74fa4d25c644cbce785e9917d54c5ef580dc6abc97d3145c1de3297582843f3d71f6d0e36bf61c6713d082fe46d32d4314d95f8796d43df"} 16:08:41 INFO - Running post-run listener: copy_logs_to_upload_dir 16:08:41 INFO - Copying logs to upload dir... 16:08:41 INFO - mkdir: /builds/slave/test/build/upload/logs 16:08:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.236705 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-10-15 16:08:41.218418) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-15 16:08:41.221639) ========= 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=5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/89f6ba4f66b7b1dad01213c69738ac7ca69f1fa37400729a76c4c0a9304e47f836d9e6968c7f8cfb1f5b37116e33c792ac800f1628e4155006a3b88fa6711ae9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a245ff7394e53d618547291fb72b4a882ae9c110fdaa7a95ccdd2fbac25ce37b84da1cc85c0ed49e1d3c0ed9b354ba7c02a609c772b784db4e4b442773768e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca6b4e16f7758d6ea74fa4d25c644cbce785e9917d54c5ef580dc6abc97d3145c1de3297582843f3d71f6d0e36bf61c6713d082fe46d32d4314d95f8796d43df"} 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.011484 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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/89f6ba4f66b7b1dad01213c69738ac7ca69f1fa37400729a76c4c0a9304e47f836d9e6968c7f8cfb1f5b37116e33c792ac800f1628e4155006a3b88fa6711ae9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a245ff7394e53d618547291fb72b4a882ae9c110fdaa7a95ccdd2fbac25ce37b84da1cc85c0ed49e1d3c0ed9b354ba7c02a609c772b784db4e4b442773768e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca6b4e16f7758d6ea74fa4d25c644cbce785e9917d54c5ef580dc6abc97d3145c1de3297582843f3d71f6d0e36bf61c6713d082fe46d32d4314d95f8796d43df"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-15 16:08:41.246606) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 16:08:41.246927) ========= 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=5bd038846534b2fdfa68eeae000001e2-1476570060.238841-1694518706 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004545 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 16:08:41.331002) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-15 16:08:41.331340) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-15 16:08:41.331643) ========= ========= Total master_lag: 0.18 =========