builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1475588762.96 results: success (0) buildid: 20161004034701 builduid: 7da91618efc44e90aca206ce919e1c56 revision: ed69e6af42aab15b1bdf09d3705d2bd23fdc850d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 06:46:02.961340) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 06:46:02.961821) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 06:46:02.962118) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 06:46:02.977158) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 06:46:02.977541) ========= 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=7de08804244eb36560b44971000001c3-1475588762.426609-300452998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-04 06:46:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-10-04 06:46:03 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.167179 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 06:46:03.158824) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 06:46:03.159154) ========= 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=7de08804244eb36560b44971000001c3-1475588762.426609-300452998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020602 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 06:46:03.209278) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 06:46:03.209703) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ed69e6af42aab15b1bdf09d3705d2bd23fdc850d --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 ed69e6af42aab15b1bdf09d3705d2bd23fdc850d --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=7de08804244eb36560b44971000001c3-1475588762.426609-300452998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-04 06:46:03,379 truncating revision to first 12 chars 2016-10-04 06:46:03,379 Setting DEBUG logging. 2016-10-04 06:46:03,379 attempt 1/10 2016-10-04 06:46:03,379 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ed69e6af42aa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-04 06:46:03,687 unpacking tar archive at: mozilla-beta-ed69e6af42aa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.622103 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 06:46:03.846420) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 06:46:03.846742) ========= script_repo_revision: ed69e6af42aab15b1bdf09d3705d2bd23fdc850d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 06:46:03.847132) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 06:46:03.847415) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 06:46:03.857198) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-04 06:46:03.857565) ========= /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=7de08804244eb36560b44971000001c3-1475588762.426609-300452998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:46:04 INFO - MultiFileLogger online at 20161004 06:46:04 in /builds/slave/test 06:46:04 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 06:46:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:46:04 INFO - {'append_to_log': False, 06:46:04 INFO - 'base_work_dir': '/builds/slave/test', 06:46:04 INFO - 'blob_upload_branch': 'Mozilla-Beta', 06:46:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:46:04 INFO - 'branch': 'Mozilla-Beta', 06:46:04 INFO - 'buildbot_json_path': 'buildprops.json', 06:46:04 INFO - 'config_files': ('talos/linux_config.py',), 06:46:04 INFO - 'default_actions': ('clobber', 06:46:04 INFO - 'read-buildbot-config', 06:46:04 INFO - 'download-and-extract', 06:46:04 INFO - 'populate-webroot', 06:46:04 INFO - 'create-virtualenv', 06:46:04 INFO - 'install', 06:46:04 INFO - 'run-tests'), 06:46:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:46:04 INFO - 'download_minidump_stackwalk': True, 06:46:04 INFO - 'download_symbols': 'ondemand', 06:46:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:46:04 INFO - 'tooltool.py': '/tools/tooltool.py', 06:46:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:46:04 INFO - '/tools/misc-python/virtualenv.py')}, 06:46:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:46:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:46:04 INFO - 'installer_path': 'installer.exe', 06:46:04 INFO - 'log_level': 'info', 06:46:04 INFO - 'log_name': 'talos', 06:46:04 INFO - 'log_to_console': True, 06:46:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:46:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:46:04 INFO - 'opt_config_files': (), 06:46:04 INFO - 'pip_index': False, 06:46:04 INFO - 'sps_profile': False, 06:46:04 INFO - 'sps_profile_interval': 0, 06:46:04 INFO - 'suite': 'dromaeojs', 06:46:04 INFO - 'system_bits': '32', 06:46:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:46:04 INFO - 'title': 'talos-linux64-ix-003', 06:46:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:46:04 INFO - 'use_talos_json': True, 06:46:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:46:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:46:04 INFO - 'work_dir': 'build'} 06:46:04 INFO - [mozharness: 2016-10-04 13:46:04.123796Z] Running clobber step. 06:46:04 INFO - Running pre-action listener: _resource_record_pre_action 06:46:04 INFO - Running main action method: clobber 06:46:04 INFO - rmtree: /builds/slave/test/build 06:46:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:46:04 INFO - Running post-action listener: _resource_record_post_action 06:46:04 INFO - [mozharness: 2016-10-04 13:46:04.671644Z] Finished clobber step (success) 06:46:04 INFO - [mozharness: 2016-10-04 13:46:04.671744Z] Running read-buildbot-config step. 06:46:04 INFO - Running pre-action listener: _resource_record_pre_action 06:46:04 INFO - Running main action method: read_buildbot_config 06:46:04 INFO - Using buildbot properties: 06:46:04 INFO - { 06:46:04 INFO - "project": "", 06:46:04 INFO - "product": "firefox", 06:46:04 INFO - "script_repo_revision": "production", 06:46:04 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 06:46:04 INFO - "repository": "", 06:46:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 06:46:04 INFO - "buildid": "20161004034701", 06:46:04 INFO - "pgo_build": "True", 06:46:04 INFO - "basedir": "/builds/slave/test", 06:46:04 INFO - "buildnumber": 179, 06:46:04 INFO - "slavename": "talos-linux64-ix-003", 06:46:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 06:46:04 INFO - "platform": "ubuntu64_hw", 06:46:04 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 06:46:04 INFO - "branch": "mozilla-beta", 06:46:04 INFO - "repo_path": "releases/mozilla-beta", 06:46:04 INFO - "revision": "ed69e6af42aab15b1bdf09d3705d2bd23fdc850d", 06:46:04 INFO - "stage_platform": "linux64-pgo", 06:46:04 INFO - "builduid": "7da91618efc44e90aca206ce919e1c56", 06:46:04 INFO - "slavebuilddir": "test" 06:46:04 INFO - } 06:46:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 06:46:04 INFO - Running post-action listener: _resource_record_post_action 06:46:04 INFO - [mozharness: 2016-10-04 13:46:04.674257Z] Finished read-buildbot-config step (success) 06:46:04 INFO - [mozharness: 2016-10-04 13:46:04.674349Z] Running download-and-extract step. 06:46:04 INFO - Running pre-action listener: _resource_record_pre_action 06:46:04 INFO - Running main action method: download_and_extract 06:46:04 INFO - mkdir: /builds/slave/test/build/tests 06:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:46:04 INFO - https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:46:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 06:46:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 06:46:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:46:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:46:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 06:46:04 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:46:04 INFO - retry: Failed, sleeping 30 seconds before retrying 06:46:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:46:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 06:46:34 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:46:34 INFO - retry: Failed, sleeping 60 seconds before retrying 06:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:47:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 06:47:34 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:47:34 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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! 06:47:34 INFO - Caught exception: HTTP Error 404: Not Found 06:47:34 INFO - Caught exception: HTTP Error 404: Not Found 06:47:34 INFO - Caught exception: HTTP Error 404: Not Found 06:47:34 INFO - trying https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 06:47:34 INFO - Downloading https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:47:35 INFO - Downloaded 1327 bytes. 06:47:35 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 06:47:35 INFO - Using the following test package requirements: 06:47:35 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 06:47:35 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:47:35 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 06:47:35 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:47:35 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 06:47:35 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:47:35 INFO - u'jsshell-linux-x86_64.zip'], 06:47:35 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:47:35 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 06:47:35 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 06:47:35 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:47:35 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 06:47:35 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:47:35 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 06:47:35 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:47:35 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 06:47:35 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:47:35 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 06:47:35 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:35 INFO - https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:47:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:47:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:47:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:47:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:47:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:47:35 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:47:35 INFO - retry: Failed, sleeping 30 seconds before retrying 06:48:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:48:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:48:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:48:05 INFO - retry: Failed, sleeping 60 seconds before retrying 06:49:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:49:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:49:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:49:05 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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! 06:49:05 INFO - Caught exception: HTTP Error 404: Not Found 06:49:05 INFO - Caught exception: HTTP Error 404: Not Found 06:49:05 INFO - Caught exception: HTTP Error 404: Not Found 06:49:05 INFO - trying https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:49:05 INFO - Downloading https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:49:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:49:06 INFO - Downloaded 21773290 bytes. 06:49:06 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'] 06:49:06 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 06:49:07 INFO - Return code: 0 06:49:07 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 06:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:07 INFO - https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:49:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:49:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:49:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:49:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:49:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:49:07 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:49:07 INFO - retry: Failed, sleeping 30 seconds before retrying 06:49:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:49:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:49:37 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:49:37 INFO - retry: Failed, sleeping 60 seconds before retrying 06:50:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:50:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:50:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:50:37 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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! 06:50:37 INFO - Caught exception: HTTP Error 404: Not Found 06:50:37 INFO - Caught exception: HTTP Error 404: Not Found 06:50:37 INFO - Caught exception: HTTP Error 404: Not Found 06:50:37 INFO - trying https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:50:37 INFO - Downloading https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:50:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:50:38 INFO - Downloaded 21773290 bytes. 06:50:38 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'] 06:50:38 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 06:50:39 INFO - Return code: 0 06:50:39 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')]} 06:50:39 INFO - https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:50:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 06:50:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 06:50:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:50:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:50:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 06:50:39 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:50:39 INFO - retry: Failed, sleeping 30 seconds before retrying 06:51:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:51:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 06:51:09 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:51:09 INFO - retry: Failed, sleeping 60 seconds before retrying 06:52:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:52:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 06:52:09 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:52:09 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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! 06:52:09 INFO - Caught exception: HTTP Error 404: Not Found 06:52:09 INFO - Caught exception: HTTP Error 404: Not Found 06:52:09 INFO - Caught exception: HTTP Error 404: Not Found 06:52:09 INFO - trying https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 06:52:09 INFO - Downloading https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:52:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:52:10 INFO - Downloaded 14413740 bytes. 06:52:10 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'] 06:52:10 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 06:52:10 INFO - Return code: 0 06:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:52:10 INFO - https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:52:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:52:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:52:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:52:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:52:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:52:10 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:52:10 INFO - retry: Failed, sleeping 30 seconds before retrying 06:52:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:52:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:52:40 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:52:40 INFO - retry: Failed, sleeping 60 seconds before retrying 06:53:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:53:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:53:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:53:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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! 06:53:41 INFO - Caught exception: HTTP Error 404: Not Found 06:53:41 INFO - Caught exception: HTTP Error 404: Not Found 06:53:41 INFO - Caught exception: HTTP Error 404: Not Found 06:53:41 INFO - trying https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:53:41 INFO - Downloading https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:53:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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 06:53:42 INFO - Downloaded 56790920 bytes. 06:53:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:53:42 INFO - mkdir: /builds/slave/test/properties 06:53:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:53:42 INFO - Writing to file /builds/slave/test/properties/build_url 06:53:42 INFO - Contents: 06:53:42 INFO - build_url:https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:53:42 INFO - Running post-action listener: _resource_record_post_action 06:53:42 INFO - Running post-action listener: set_extra_try_arguments 06:53:42 INFO - [mozharness: 2016-10-04 13:53:42.865670Z] Finished download-and-extract step (success) 06:53:42 INFO - [mozharness: 2016-10-04 13:53:42.865905Z] Running populate-webroot step. 06:53:42 INFO - Running pre-action listener: _resource_record_pre_action 06:53:42 INFO - Running main action method: populate_webroot 06:53:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:53:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:53:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:53:42 INFO - '--tppagecycles', 06:53:42 INFO - '7'], 06:53:42 INFO - 'tests': ['tsvgm']}}, 06:53:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:53:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:53:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:53:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:53:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:53:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:53:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:53:42 INFO - 'talos_options': ['--disable-e10s'], 06:53:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:53:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:53:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:53:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:53:42 INFO - 'talos_options': ['--disable-e10s'], 06:53:42 INFO - 'tests': ['damp', 'tps']}, 06:53:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:53:42 INFO - 'tests': ['damp', 'tps']}, 06:53:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:53:42 INFO - 'tests': ['dromaeo_dom']}, 06:53:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:53:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:53:42 INFO - 'tests': ['basic_compositor_video']}, 06:53:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:53:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:53:42 INFO - 'tests': ['a11yr', 06:53:42 INFO - 'ts_paint', 06:53:42 INFO - 'tpaint', 06:53:42 INFO - 'sessionrestore', 06:53:42 INFO - 'sessionrestore_no_auto_restore', 06:53:42 INFO - 'tabpaint']}, 06:53:42 INFO - 'other-e10s': {'tests': ['a11yr', 06:53:42 INFO - 'ts_paint', 06:53:42 INFO - 'tpaint', 06:53:42 INFO - 'sessionrestore', 06:53:42 INFO - 'sessionrestore_no_auto_restore', 06:53:42 INFO - 'tabpaint']}, 06:53:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:53:42 INFO - 'ts_paint', 06:53:42 INFO - 'tpaint', 06:53:42 INFO - 'sessionrestore', 06:53:42 INFO - 'sessionrestore_no_auto_restore', 06:53:42 INFO - 'tabpaint']}, 06:53:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:53:42 INFO - 'ts_paint', 06:53:42 INFO - 'tpaint', 06:53:42 INFO - 'sessionrestore', 06:53:42 INFO - 'sessionrestore_no_auto_restore', 06:53:42 INFO - 'tabpaint']}, 06:53:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:53:42 INFO - 'tests': ['a11yr', 06:53:42 INFO - 'ts_paint', 06:53:42 INFO - 'tpaint', 06:53:42 INFO - 'sessionrestore', 06:53:42 INFO - 'sessionrestore_no_auto_restore', 06:53:42 INFO - 'tabpaint']}, 06:53:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:53:42 INFO - 'tests': ['a11yr', 06:53:42 INFO - 'ts_paint', 06:53:42 INFO - 'tpaint', 06:53:42 INFO - 'sessionrestore', 06:53:42 INFO - 'sessionrestore_no_auto_restore', 06:53:42 INFO - 'tabpaint']}, 06:53:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:53:42 INFO - 'tests': ['tsvgx', 06:53:42 INFO - 'tsvgr_opacity', 06:53:42 INFO - 'tart', 06:53:42 INFO - 'tscrollx', 06:53:42 INFO - 'cart']}, 06:53:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:53:42 INFO - 'tsvgr_opacity', 06:53:42 INFO - 'tart', 06:53:42 INFO - 'tscrollx', 06:53:42 INFO - 'cart']}, 06:53:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:53:42 INFO - 'talos_options': ['--disable-e10s'], 06:53:42 INFO - 'tests': ['tp5o']}, 06:53:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:53:42 INFO - 'tests': ['tp5o']}, 06:53:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:53:42 INFO - 'talos_options': ['--disable-e10s', 06:53:42 INFO - '--xperf_path', 06:53:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:53:42 INFO - 'tests': ['tp5n']}, 06:53:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:53:42 INFO - 'talos_options': ['--xperf_path', 06:53:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:53:42 INFO - 'tests': ['tp5n']}}, 06:53:42 INFO - 'talos.zip': {'path': '', 06:53:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:53:42 INFO - Running post-action listener: _resource_record_post_action 06:53:42 INFO - [mozharness: 2016-10-04 13:53:42.883056Z] Finished populate-webroot step (success) 06:53:42 INFO - [mozharness: 2016-10-04 13:53:42.883146Z] Running create-virtualenv step. 06:53:42 INFO - Running pre-action listener: _resource_record_pre_action 06:53:42 INFO - Running main action method: create_virtualenv 06:53:42 INFO - Creating virtualenv /builds/slave/test/build/venv 06:53:42 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 06:53:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:53:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:53:42 INFO - Using real prefix '/usr' 06:53:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:53:43 INFO - Installing distribute.............................................................................................................................................................................................done. 06:53:45 INFO - Installing pip.................done. 06:53:45 INFO - Return code: 0 06:53:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:53:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:53:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:53:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:45 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 0x22ba030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25793b0>, '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 0x257a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257a740>, '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': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', '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 06:53:45 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 06:53:45 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 06:53:45 INFO - Using env: {'DISPLAY': ':0', 06:53:45 INFO - 'HOME': '/home/cltbld', 06:53:45 INFO - 'LANG': 'en_US.UTF-8', 06:53:45 INFO - 'LANGUAGE': 'en_US:en', 06:53:45 INFO - 'LOGNAME': 'cltbld', 06:53:45 INFO - 'MAIL': '/var/mail/cltbld', 06:53:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:53:45 INFO - 'MOZ_NO_REMOTE': '1', 06:53:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:53:45 INFO - 'NO_EM_RESTART': '1', 06:53:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:53:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:53:45 INFO - 'PWD': '/builds/slave/test', 06:53:45 INFO - 'SHELL': '/bin/bash', 06:53:45 INFO - 'SHLVL': '1', 06:53:45 INFO - 'TERM': 'linux', 06:53:45 INFO - 'TMOUT': '86400', 06:53:45 INFO - 'USER': 'cltbld', 06:53:45 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', 06:53:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:53:45 INFO - '_': '/tools/buildbot/bin/python'} 06:53:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:53:45 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 06:53:45 INFO - Cleaning up... 06:53:45 INFO - Return code: 0 06:53:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:53:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:53:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:53:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:45 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 0x22ba030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25793b0>, '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 0x257a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257a740>, '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': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', '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 06:53:45 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 06:53:45 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 06:53:45 INFO - Using env: {'DISPLAY': ':0', 06:53:45 INFO - 'HOME': '/home/cltbld', 06:53:45 INFO - 'LANG': 'en_US.UTF-8', 06:53:45 INFO - 'LANGUAGE': 'en_US:en', 06:53:45 INFO - 'LOGNAME': 'cltbld', 06:53:45 INFO - 'MAIL': '/var/mail/cltbld', 06:53:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:53:45 INFO - 'MOZ_NO_REMOTE': '1', 06:53:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:53:45 INFO - 'NO_EM_RESTART': '1', 06:53:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:53:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:53:45 INFO - 'PWD': '/builds/slave/test', 06:53:45 INFO - 'SHELL': '/bin/bash', 06:53:45 INFO - 'SHLVL': '1', 06:53:45 INFO - 'TERM': 'linux', 06:53:45 INFO - 'TMOUT': '86400', 06:53:45 INFO - 'USER': 'cltbld', 06:53:45 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', 06:53:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:53:45 INFO - '_': '/tools/buildbot/bin/python'} 06:53:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:53:45 INFO - Downloading/unpacking psutil>=3.1.1 06:53: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 06:53: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 06:53: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 06:53: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 06:53: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 06:53: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 06:53:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:53:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:53:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:53:47 INFO - Installing collected packages: psutil 06:53:47 INFO - Running setup.py install for psutil 06:53:47 INFO - building 'psutil._psutil_linux' extension 06:53:47 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 06:53:48 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 06:53:48 INFO - building 'psutil._psutil_posix' extension 06:53:48 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 06:53:48 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 06:53:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:53:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:53:48 INFO - Successfully installed psutil 06:53:48 INFO - Cleaning up... 06:53:48 INFO - Return code: 0 06:53:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:53:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:53:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:53:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:53:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:53:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25793b0>, '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 0x257a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257a740>, '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': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', '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 06:53:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:53:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:53:48 INFO - Using env: {'DISPLAY': ':0', 06:53:48 INFO - 'HOME': '/home/cltbld', 06:53:48 INFO - 'LANG': 'en_US.UTF-8', 06:53:48 INFO - 'LANGUAGE': 'en_US:en', 06:53:48 INFO - 'LOGNAME': 'cltbld', 06:53:48 INFO - 'MAIL': '/var/mail/cltbld', 06:53:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:53:48 INFO - 'MOZ_NO_REMOTE': '1', 06:53:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:53:48 INFO - 'NO_EM_RESTART': '1', 06:53:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:53:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:53:48 INFO - 'PWD': '/builds/slave/test', 06:53:48 INFO - 'SHELL': '/bin/bash', 06:53:48 INFO - 'SHLVL': '1', 06:53:48 INFO - 'TERM': 'linux', 06:53:48 INFO - 'TMOUT': '86400', 06:53:48 INFO - 'USER': 'cltbld', 06:53:48 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', 06:53:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:53:48 INFO - '_': '/tools/buildbot/bin/python'} 06:53:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:53:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:53:48 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 06:53:48 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 06:53:48 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 06:53:48 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 06:53:48 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 06:53:48 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 06:53:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:53:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:53:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:53:50 INFO - Installing collected packages: mozsystemmonitor 06:53:50 INFO - Running setup.py install for mozsystemmonitor 06:53:50 INFO - Successfully installed mozsystemmonitor 06:53:50 INFO - Cleaning up... 06:53:50 INFO - Return code: 0 06:53:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:53:50 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')]} 06:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:53:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:50 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')]} 06:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:53:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:50 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 0x22ba030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25793b0>, '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 0x257a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257a740>, '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': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', '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 06:53:50 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 06:53:50 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 06:53:50 INFO - Using env: {'DISPLAY': ':0', 06:53:50 INFO - 'HOME': '/home/cltbld', 06:53:50 INFO - 'LANG': 'en_US.UTF-8', 06:53:50 INFO - 'LANGUAGE': 'en_US:en', 06:53:50 INFO - 'LOGNAME': 'cltbld', 06:53:50 INFO - 'MAIL': '/var/mail/cltbld', 06:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:53:50 INFO - 'MOZ_NO_REMOTE': '1', 06:53:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:53:50 INFO - 'NO_EM_RESTART': '1', 06:53:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:53:50 INFO - 'PWD': '/builds/slave/test', 06:53:50 INFO - 'SHELL': '/bin/bash', 06:53:50 INFO - 'SHLVL': '1', 06:53:50 INFO - 'TERM': 'linux', 06:53:50 INFO - 'TMOUT': '86400', 06:53:50 INFO - 'USER': 'cltbld', 06:53:50 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', 06:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:53:50 INFO - '_': '/tools/buildbot/bin/python'} 06:53:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:53:50 INFO - Downloading/unpacking jsonschema==2.5.1 06:53:50 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 06:53:50 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 06:53:50 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 06:53:50 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 06:53:50 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 06:53:50 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 06:53:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:53:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:53:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:53:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:53:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:53:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:53:53 INFO - Downloading functools32-3.2.3-2.tar.gz 06:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:53:53 INFO - warning: no files found matching '*.txt' 06:53:53 INFO - no previously-included directories found matching 'build' 06:53:53 INFO - no previously-included directories found matching 'dist' 06:53:53 INFO - no previously-included directories found matching '.git*' 06:53:53 INFO - Installing collected packages: jsonschema, functools32 06:53:53 INFO - Running setup.py install for functools32 06:53:53 INFO - warning: no files found matching '*.txt' 06:53:53 INFO - no previously-included directories found matching 'build' 06:53:53 INFO - no previously-included directories found matching 'dist' 06:53:53 INFO - no previously-included directories found matching '.git*' 06:53:53 INFO - Successfully installed jsonschema functools32 06:53:53 INFO - Cleaning up... 06:53:53 INFO - Return code: 0 06:53:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:53: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')]} 06:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:53:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53: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')]} 06:53:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:53:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22ba030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25793b0>, '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 0x257a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257a740>, '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': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', '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 06:53:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:53:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:53:53 INFO - Using env: {'DISPLAY': ':0', 06:53:53 INFO - 'HOME': '/home/cltbld', 06:53:53 INFO - 'LANG': 'en_US.UTF-8', 06:53:53 INFO - 'LANGUAGE': 'en_US:en', 06:53:53 INFO - 'LOGNAME': 'cltbld', 06:53:53 INFO - 'MAIL': '/var/mail/cltbld', 06:53:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:53:53 INFO - 'MOZ_NO_REMOTE': '1', 06:53:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:53:53 INFO - 'NO_EM_RESTART': '1', 06:53:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:53:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:53:53 INFO - 'PWD': '/builds/slave/test', 06:53:53 INFO - 'SHELL': '/bin/bash', 06:53:53 INFO - 'SHLVL': '1', 06:53:53 INFO - 'TERM': 'linux', 06:53:53 INFO - 'TMOUT': '86400', 06:53:53 INFO - 'USER': 'cltbld', 06:53:53 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', 06:53:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:53:53 INFO - '_': '/tools/buildbot/bin/python'} 06:53:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:53:53 INFO - Cleaning up... 06:53:53 INFO - Return code: 0 06:53:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:53: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')]} 06:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:53:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53: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')]} 06:53:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:53:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25793b0>, '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 0x257a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257a740>, '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': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', '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 06:53:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:53:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:53:53 INFO - Using env: {'DISPLAY': ':0', 06:53:53 INFO - 'HOME': '/home/cltbld', 06:53:53 INFO - 'LANG': 'en_US.UTF-8', 06:53:53 INFO - 'LANGUAGE': 'en_US:en', 06:53:53 INFO - 'LOGNAME': 'cltbld', 06:53:53 INFO - 'MAIL': '/var/mail/cltbld', 06:53:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:53:53 INFO - 'MOZ_NO_REMOTE': '1', 06:53:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:53:53 INFO - 'NO_EM_RESTART': '1', 06:53:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:53:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:53:53 INFO - 'PWD': '/builds/slave/test', 06:53:53 INFO - 'SHELL': '/bin/bash', 06:53:53 INFO - 'SHLVL': '1', 06:53:53 INFO - 'TERM': 'linux', 06:53:53 INFO - 'TMOUT': '86400', 06:53:53 INFO - 'USER': 'cltbld', 06:53:53 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', 06:53:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:53:53 INFO - '_': '/tools/buildbot/bin/python'} 06:53:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:53:53 INFO - Downloading/unpacking blobuploader==1.2.4 06:53:53 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 06:53:53 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 06:53:53 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 06:53:53 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 06:53:53 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 06:53:53 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 06:53:55 INFO - Downloading blobuploader-1.2.4.tar.gz 06:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:53:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:53:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:53:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:53:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:53:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:53:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:53:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:53:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:53:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:53:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:53:56 INFO - Downloading docopt-0.6.1.tar.gz 06:53:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:53:56 INFO - Installing collected packages: blobuploader, requests, docopt 06:53:56 INFO - Running setup.py install for blobuploader 06:53:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:53:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:53:56 INFO - Running setup.py install for requests 06:53:56 INFO - Running setup.py install for docopt 06:53:56 INFO - Successfully installed blobuploader requests docopt 06:53:56 INFO - Cleaning up... 06:53:56 INFO - Return code: 0 06:53:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:53:56 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')]} 06:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:53:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:56 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')]} 06:53:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:53:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:53:56 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 0x22ba030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25793b0>, '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 0x257a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257a740>, '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': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', '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 06:53:56 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 06:53:56 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 06:53:56 INFO - Using env: {'DISPLAY': ':0', 06:53:56 INFO - 'HOME': '/home/cltbld', 06:53:56 INFO - 'LANG': 'en_US.UTF-8', 06:53:56 INFO - 'LANGUAGE': 'en_US:en', 06:53:56 INFO - 'LOGNAME': 'cltbld', 06:53:56 INFO - 'MAIL': '/var/mail/cltbld', 06:53:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:53:56 INFO - 'MOZ_NO_REMOTE': '1', 06:53:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:53:56 INFO - 'NO_EM_RESTART': '1', 06:53:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:53:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:53:56 INFO - 'PWD': '/builds/slave/test', 06:53:56 INFO - 'SHELL': '/bin/bash', 06:53:56 INFO - 'SHLVL': '1', 06:53:56 INFO - 'TERM': 'linux', 06:53:56 INFO - 'TMOUT': '86400', 06:53:56 INFO - 'USER': 'cltbld', 06:53:56 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', 06:53:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:53:56 INFO - '_': '/tools/buildbot/bin/python'} 06:53:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:53:57 INFO - Running setup.py (path:/tmp/pip-wRCesm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:53:57 INFO - Running setup.py (path:/tmp/pip-TNdL_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:53:57 INFO - Running setup.py (path:/tmp/pip-IhO_QR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:53:57 INFO - Running setup.py (path:/tmp/pip-lIwm8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:53:57 INFO - Running setup.py (path:/tmp/pip-PYBKx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:53:57 INFO - Running setup.py (path:/tmp/pip-ETYlCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:53:57 INFO - Running setup.py (path:/tmp/pip-o7HJbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:53:57 INFO - Running setup.py (path:/tmp/pip-KLj0Do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:53:57 INFO - Running setup.py (path:/tmp/pip-0u6yNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:53:57 INFO - Running setup.py (path:/tmp/pip-6FEfCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:53:57 INFO - Running setup.py (path:/tmp/pip-W250o2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:53:57 INFO - Running setup.py (path:/tmp/pip-OkMMsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:53:58 INFO - Running setup.py (path:/tmp/pip-j05lSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:53:58 INFO - Running setup.py (path:/tmp/pip-noeTKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:53:58 INFO - Running setup.py (path:/tmp/pip-KCDmpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:53:58 INFO - Running setup.py (path:/tmp/pip-mD_w6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:53:58 INFO - Running setup.py (path:/tmp/pip-PSWqeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:53:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:53:58 INFO - Running setup.py install for manifestparser 06:53:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:53:58 INFO - Running setup.py install for mozcrash 06:53:58 INFO - Running setup.py install for mozdebug 06:53:58 INFO - Running setup.py install for mozdevice 06:53:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:53:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:53:58 INFO - Running setup.py install for mozfile 06:53:58 INFO - Running setup.py install for mozhttpd 06:53:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:53:59 INFO - Running setup.py install for mozinfo 06:53:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:53:59 INFO - Running setup.py install for mozInstall 06:53:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:53:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:53:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:53:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:53:59 INFO - Running setup.py install for mozleak 06:53:59 INFO - Running setup.py install for mozlog 06:53:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:53:59 INFO - Running setup.py install for moznetwork 06:53:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:53:59 INFO - Running setup.py install for mozprocess 06:53:59 INFO - Running setup.py install for mozprofile 06:53:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:53:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:53:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:53:59 INFO - Running setup.py install for mozrunner 06:53:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:53:59 INFO - Running setup.py install for mozscreenshot 06:53:59 INFO - Running setup.py install for moztest 06:54:00 INFO - Running setup.py install for mozversion 06:54:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:54:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:54:00 INFO - Cleaning up... 06:54:00 INFO - Return code: 0 06:54:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:54:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:54:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:54:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:54:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:54:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:54:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25793b0>, '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 0x257a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257a740>, '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': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', '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 06:54:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:54:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:54:00 INFO - Using env: {'DISPLAY': ':0', 06:54:00 INFO - 'HOME': '/home/cltbld', 06:54:00 INFO - 'LANG': 'en_US.UTF-8', 06:54:00 INFO - 'LANGUAGE': 'en_US:en', 06:54:00 INFO - 'LOGNAME': 'cltbld', 06:54:00 INFO - 'MAIL': '/var/mail/cltbld', 06:54:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:54:00 INFO - 'MOZ_NO_REMOTE': '1', 06:54:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:54:00 INFO - 'NO_EM_RESTART': '1', 06:54:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:54:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:54:00 INFO - 'PWD': '/builds/slave/test', 06:54:00 INFO - 'SHELL': '/bin/bash', 06:54:00 INFO - 'SHLVL': '1', 06:54:00 INFO - 'TERM': 'linux', 06:54:00 INFO - 'TMOUT': '86400', 06:54:00 INFO - 'USER': 'cltbld', 06:54:00 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', 06:54:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:54:00 INFO - '_': '/tools/buildbot/bin/python'} 06:54:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:54:00 INFO - Running setup.py (path:/tmp/pip-lphZCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:54:00 INFO - Running setup.py (path:/tmp/pip-uXOWKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:54:00 INFO - Running setup.py (path:/tmp/pip-J2PNAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:54:00 INFO - Running setup.py (path:/tmp/pip-mSv5uR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:54:00 INFO - Running setup.py (path:/tmp/pip-R3jfPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:54:00 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)) 06:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:54:00 INFO - Running setup.py (path:/tmp/pip-L9cpKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:54:00 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)) 06:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:54:00 INFO - Running setup.py (path:/tmp/pip-9ZTttq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:54:00 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)) 06:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:54:00 INFO - Running setup.py (path:/tmp/pip-DOJaig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:54:01 INFO - Running setup.py (path:/tmp/pip-IcvH8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:54:01 INFO - Running setup.py (path:/tmp/pip-Znu_1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:54:01 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)) 06:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:54:01 INFO - Running setup.py (path:/tmp/pip-XSQ9c8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:54:01 INFO - Running setup.py (path:/tmp/pip-7ficAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:54:01 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)) 06:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:54:01 INFO - Running setup.py (path:/tmp/pip-uLYTaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:54:01 INFO - Running setup.py (path:/tmp/pip-1YikHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:54:01 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)) 06:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:54:01 INFO - Running setup.py (path:/tmp/pip-aM7y2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:54:01 INFO - Running setup.py (path:/tmp/pip-qvGO0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:54:01 INFO - Running setup.py (path:/tmp/pip-Go7MlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:54:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:54:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:54:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:54:03 INFO - Downloading blessings-1.6.tar.gz 06:54:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:54:03 INFO - Installing collected packages: blessings 06:54:03 INFO - Running setup.py install for blessings 06:54:03 INFO - Successfully installed blessings 06:54:03 INFO - Cleaning up... 06:54:03 INFO - Return code: 0 06:54:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:54:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:54:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:54:04 INFO - Reading from file tmpfile_stdout 06:54:04 INFO - Current package versions: 06:54:04 INFO - argparse == 1.2.1 06:54:04 INFO - blessings == 1.6 06:54:04 INFO - blobuploader == 1.2.4 06:54:04 INFO - docopt == 0.6.1 06:54:04 INFO - functools32 == 3.2.3-2 06:54:04 INFO - jsonschema == 2.5.1 06:54:04 INFO - manifestparser == 1.1 06:54:04 INFO - mozInstall == 1.12 06:54:04 INFO - mozcrash == 0.17 06:54:04 INFO - mozdebug == 0.1 06:54:04 INFO - mozdevice == 0.48 06:54:04 INFO - mozfile == 1.2 06:54:04 INFO - mozhttpd == 0.7 06:54:04 INFO - mozinfo == 0.9 06:54:04 INFO - mozleak == 0.1 06:54:04 INFO - mozlog == 3.3 06:54:04 INFO - moznetwork == 0.27 06:54:04 INFO - mozprocess == 0.23 06:54:04 INFO - mozprofile == 0.28 06:54:04 INFO - mozrunner == 6.12 06:54:04 INFO - mozscreenshot == 0.1 06:54:04 INFO - mozsystemmonitor == 0.3 06:54:04 INFO - moztest == 0.7 06:54:04 INFO - mozversion == 1.4 06:54:04 INFO - psutil == 3.1.1 06:54:04 INFO - requests == 1.2.3 06:54:04 INFO - wsgiref == 0.1.2 06:54:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:54:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25793b0>, '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 0x257a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257a740>, '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': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', '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 06:54:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:54:04 INFO - Using env: {'DISPLAY': ':0', 06:54:04 INFO - 'HOME': '/home/cltbld', 06:54:04 INFO - 'LANG': 'en_US.UTF-8', 06:54:04 INFO - 'LANGUAGE': 'en_US:en', 06:54:04 INFO - 'LOGNAME': 'cltbld', 06:54:04 INFO - 'MAIL': '/var/mail/cltbld', 06:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:54:04 INFO - 'MOZ_NO_REMOTE': '1', 06:54:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:54:04 INFO - 'NO_EM_RESTART': '1', 06:54:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:54:04 INFO - 'PWD': '/builds/slave/test', 06:54:04 INFO - 'SHELL': '/bin/bash', 06:54:04 INFO - 'SHLVL': '1', 06:54:04 INFO - 'TERM': 'linux', 06:54:04 INFO - 'TMOUT': '86400', 06:54:04 INFO - 'USER': 'cltbld', 06:54:04 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', 06:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:54:04 INFO - '_': '/tools/buildbot/bin/python'} 06:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:54:04 INFO - Cleaning up... 06:54:04 INFO - Return code: 0 06:54:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:54:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ba030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25793b0>, '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 0x257a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257a740>, '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': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', '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 06:54:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:54:04 INFO - Using env: {'DISPLAY': ':0', 06:54:04 INFO - 'HOME': '/home/cltbld', 06:54:04 INFO - 'LANG': 'en_US.UTF-8', 06:54:04 INFO - 'LANGUAGE': 'en_US:en', 06:54:04 INFO - 'LOGNAME': 'cltbld', 06:54:04 INFO - 'MAIL': '/var/mail/cltbld', 06:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:54:04 INFO - 'MOZ_NO_REMOTE': '1', 06:54:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:54:04 INFO - 'NO_EM_RESTART': '1', 06:54:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:54:04 INFO - 'PWD': '/builds/slave/test', 06:54:04 INFO - 'SHELL': '/bin/bash', 06:54:04 INFO - 'SHLVL': '1', 06:54:04 INFO - 'TERM': 'linux', 06:54:04 INFO - 'TMOUT': '86400', 06:54:04 INFO - 'USER': 'cltbld', 06:54:04 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', 06:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:54:04 INFO - '_': '/tools/buildbot/bin/python'} 06:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:54:04 INFO - Cleaning up... 06:54:04 INFO - Return code: 0 06:54:04 INFO - Running post-action listener: _resource_record_post_action 06:54:04 INFO - Running post-action listener: _start_resource_monitoring 06:54:04 INFO - Starting resource monitoring. 06:54:04 INFO - [mozharness: 2016-10-04 13:54:04.533761Z] Finished create-virtualenv step (success) 06:54:04 INFO - [mozharness: 2016-10-04 13:54:04.534189Z] Running install step. 06:54:04 INFO - Running pre-action listener: _resource_record_pre_action 06:54:04 INFO - Running main action method: install 06:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:54:04 INFO - Reading from file tmpfile_stdout 06:54:04 INFO - Detecting whether we're running mozinstall >=1.0... 06:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:54:04 INFO - Reading from file tmpfile_stdout 06:54:04 INFO - Output received: 06:54:04 INFO - Usage: mozinstall [options] installer 06:54:04 INFO - Options: 06:54:04 INFO - -h, --help show this help message and exit 06:54:04 INFO - -d DEST, --destination=DEST 06:54:04 INFO - Directory to install application into. [default: 06:54:04 INFO - "/builds/slave/test"] 06:54:04 INFO - --app=APP Application being installed. [default: firefox] 06:54:04 INFO - mkdir: /builds/slave/test/build/application 06:54:04 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'] 06:54:04 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 06:54:18 INFO - Reading from file tmpfile_stdout 06:54:18 INFO - Output received: 06:54:18 INFO - /builds/slave/test/build/application/firefox/firefox 06:54:18 INFO - Running post-action listener: _resource_record_post_action 06:54:18 INFO - [mozharness: 2016-10-04 13:54:18.827444Z] Finished install step (success) 06:54:18 INFO - [mozharness: 2016-10-04 13:54:18.827724Z] Running run-tests step. 06:54:18 INFO - Running pre-action listener: _resource_record_pre_action 06:54:18 INFO - Running main action method: run_tests 06:54:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:54:18 INFO - Python 2.7.3 06:54:18 INFO - Return code: 0 06:54:18 INFO - grabbing minidump binary from tooltool 06:54:18 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')]} 06:54:18 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 0x25793b0>, '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 0x257a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257a740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:54:18 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 06:54:18 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 06:54:18 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 06:54:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:54:18 INFO - Return code: 0 06:54:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:54:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:54:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:54:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:54:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:54:18 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/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 06:54:18 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/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 06:54:18 INFO - Using env: {'DISPLAY': ':0', 06:54:18 INFO - 'HOME': '/home/cltbld', 06:54:18 INFO - 'LANG': 'en_US.UTF-8', 06:54:18 INFO - 'LANGUAGE': 'en_US:en', 06:54:18 INFO - 'LOGNAME': 'cltbld', 06:54:18 INFO - 'MAIL': '/var/mail/cltbld', 06:54:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:54:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:54:18 INFO - 'MOZ_NO_REMOTE': '1', 06:54:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:54:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:54:18 INFO - 'NO_EM_RESTART': '1', 06:54:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:54:18 INFO - 'PWD': '/builds/slave/test', 06:54:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:54:18 INFO - 'SHELL': '/bin/bash', 06:54:18 INFO - 'SHLVL': '1', 06:54:18 INFO - 'TERM': 'linux', 06:54:18 INFO - 'TMOUT': '86400', 06:54:18 INFO - 'USER': 'cltbld', 06:54:18 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1475588762.426609-300452998', 06:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:54:18 INFO - '_': '/tools/buildbot/bin/python'} 06:54:18 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/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 06:54:19 INFO - mozversion application_buildid: 20161004034701 06:54:19 INFO - mozversion application_changeset: ed69e6af42aab15b1bdf09d3705d2bd23fdc850d 06:54:19 INFO - mozversion application_display_name: Firefox 06:54:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:54:19 INFO - mozversion application_name: Firefox 06:54:19 INFO - mozversion application_remotingname: firefox 06:54:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:54:19 INFO - mozversion application_vendor: Mozilla 06:54:19 INFO - mozversion application_version: 50.0 06:54:19 INFO - mozversion platform_buildid: 20161004034701 06:54:19 INFO - mozversion platform_changeset: ed69e6af42aab15b1bdf09d3705d2bd23fdc850d 06:54:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:54:19 INFO - mozversion platform_version: 50.0 06:54:19 INFO - using testdate: 1475589259 06:54:19 INFO - actual date: 1475589259 06:54:19 INFO - starting webserver on 'localhost:47351' 06:54:19 INFO - SUITE-START | Running 2 tests 06:54:19 INFO - TEST-START | dromaeo_css 06:54:19 INFO - operating with platform_type : linux_ 06:54:19 INFO - Initialising browser for dromaeo_css test... 06:54:19 INFO - TEST-INFO | started process 19330 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnUkPPR/profile http://localhost:47351/getInfo.html) 06:54:23 INFO - PROCESS | 19330 | __metrics Screen width/height:1600/1200 06:54:23 INFO - PROCESS | 19330 | colorDepth:24 06:54:23 INFO - PROCESS | 19330 | Browser inner width/height: 1024/697 06:54:23 INFO - PROCESS | 19330 | __metrics 06:54:24 INFO - PROCESS | 19330 | 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" 06:54:24 INFO - PROCESS | 19330 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:54:24 INFO - PROCESS | 19330 | console.error: PushService: 06:54:24 INFO - PROCESS | 19330 | stateChangeProcessEnqueue: Error transitioning state 06:54:24 INFO - PROCESS | 19330 | UnknownError 06:54:25 INFO - TEST-INFO | 19330: exit 0 06:54:25 INFO - Browser initialized. 06:54:25 INFO - Running cycle 1/1 for dromaeo_css test... 06:54:25 INFO - TEST-INFO | started process 19417 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnUkPPR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:54:37 INFO - PROCESS | 19417 | RSS: Main: 143241216 06:54:37 INFO - PROCESS | 19417 | 06:55:28 INFO - PROCESS | 19417 | 1475589328072 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] 06:57:36 INFO - PROCESS | 19417 | Cycle 1(1): loaded http://localhost:47351/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47351/tests/dromaeo/cssquery-ext.html) 06:57:37 INFO - PROCESS | 19417 | RSS: Main: 269975552 06:57:37 INFO - PROCESS | 19417 | 07:00:40 INFO - PROCESS | 19417 | Cycle 1(1): loaded http://localhost:47351/tests/dromaeo/cssquery-ext.html (next: http://localhost:47351/tests/dromaeo/cssquery-jquery.html) 07:00:40 INFO - PROCESS | 19417 | RSS: Main: 235204608 07:00:40 INFO - PROCESS | 19417 | 07:03:42 INFO - PROCESS | 19417 | Cycle 1(1): loaded http://localhost:47351/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47351/tests/dromaeo/cssquery-mootools.html) 07:03:43 INFO - PROCESS | 19417 | RSS: Main: 224821248 07:03:43 INFO - PROCESS | 19417 | 07:06:45 INFO - PROCESS | 19417 | Cycle 1(1): loaded http://localhost:47351/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47351/tests/dromaeo/cssquery-prototype.html) 07:06:45 INFO - PROCESS | 19417 | RSS: Main: 175513600 07:06:45 INFO - PROCESS | 19417 | 07:09:48 INFO - PROCESS | 19417 | Cycle 1(1): loaded http://localhost:47351/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47351/tests/dromaeo/cssquery-yui.html) 07:09:48 INFO - PROCESS | 19417 | RSS: Main: 177811456 07:09:48 INFO - PROCESS | 19417 | 07:12:51 INFO - PROCESS | 19417 | Cycle 1(1): loaded http://localhost:47351/tests/dromaeo/cssquery-yui.html (next: http://localhost:47351/tests/dromaeo/cssquery-dojo.html) 07:12:51 INFO - PROCESS | 19417 | RSS: Main: 264732672 07:12:51 INFO - PROCESS | 19417 | 07:12:51 INFO - PROCESS | 19417 | __start_tp_report 07:12:51 INFO - PROCESS | 19417 | _x_x_mozilla_page_load 07:12:51 INFO - PROCESS | 19417 | _x_x_mozilla_page_load_details 07:12:51 INFO - PROCESS | 19417 | |i|pagename|runs| 07:12:51 INFO - PROCESS | 19417 | |0;dojo.html;3239.287356781508;3249.57755491786;3261.086895669213;3298.901032969012;3325.634180240173;5613.494785469307;5685.516731077853;5690.231818704484;5702.173184888194;5704.771809127635;5627.915581266284;5703.77184912603;5710.314762228528;5711.05767548354;5723.54211663067;5157.097507936096;5160.716160611165;5161.9741901290445;5217.713025783581;5233.476652334774;4992.201247800353;5234.6859188448625;5242.7640756166065;5254.185601231815;5282.630215884889;4969.031038947407;5056.646034777567;5067.113255180348;5089.3129427527365;5113.054084994288;5061.16490779021;5089.7455127243675;5109.182530795092;5171.3535808024;5201.297824793643;5002.349694539686;5077.898442031139;5128.205128205134;5141.408737995141;5145.974270128663;5462.945370546323;5473.7263136843;5494.752736126844;5547.029269877755;5617.185508101303;5888.411158884121;5899.410058994066;5945.435183657545;5989.8203053908455;5996.940030599725;5876.44173803488;6013.879722405527;6021.909671354933;6031.909521357183;6039.607425517372;5574.526165275956;5645.943540564583;5651.265335506399;5699.715014249354;5704;6224.906626400608;6232.56372053961;6237.532185086136;6240;6244.906326405107;5716.171155182476;5825.650460972355;5971.343152253248;5973.104034394789;5973.790917317873;5948.345681974979;6033.879322413527;6038.215032045894;6075.1191077294425;6078.270607527036;5996.610220335664;6000.519958403317;6006.759729610854;6010.549208809357;6012.729427175787;5751.654900705971;5868.706564671749;6010.789622363196;6020.397960204032;6021.187139736167;4563;4578.061497393026;4602.309653551994;4603.58567728906;4606.147862645421;4088.550259471456;4115.73247738896;4120.423140760296;4132.669386449077;4145.689073319513;5580.441955804468;5650.180723795027;5726.856828579235;5729.341125770507;5729.598928075078;6008.279006519246;6012.579119461683;6082.148499210114;6088.969555152196;6103.816885493442;5753.1370294456165;5760.884782304331;5761.452661997104;5761.91357129635;5762.538996880239;4949.554540091337;5003.749812509433;5077.644564880423;5080.796768129308;5082.618803589746;6146.139540464339;6253.593516421418;6261.029540421242;6268.1851359323;6268.655223962817;6053.848653783514;6065.939340606537;6066.939330606814;6071.392860713893;6104.32852386246;6019.518438524995;6133.76314253068;6159.630422174684;6277.089821975879;6278.058291256166;5904.675242861684;5973.5519836013345;5983.1025346196675;5986.281646202309;6012.7294271757;6070.817875463566;6091.360407157184;6091.42131497507;6092.421219984094;6094.634321940698;5691.516221121251;5790.305163380422;5945.286565612154;5956.344802071682;5957.940420595912;5841.386654401226;5937.584369094122;5938.168656388023;5947.672877991752;5959.1657167995645;5846.035404158266;5875.529957603467;5880.882382352243;5880.941190588211;5886.911696324473;5690.743916523849;5859.677717725566;5880.882382352414;5882.088276317178;5941.554383421347;5835.503982161564;5907.852303692442;5912.142739302863;5929.258842644669;5937.168796368425 07:12:51 INFO - PROCESS | 19417 | |1;ext.html;593.9524838012959;644.9258335291441;670.4133882852506;676.590662649097;683.1290105115977;35517.46723799142;38748;38785.99999999993;38831.61168388315;38841.22317553647;39261;40093.7995310023;51816;52961.47038529613;52978;9217.539123043838;9356.064393560624;9357.532123393838;9363.6722714705;9365.953170234174;9147.771305717344;9233.445993240393;9238.6304547818;9244.583993720265;9253.676121335755;8671.783205419852;8899.065598112202;11091.112710983143;11322.150838687132;11364.943175284112;11203.831942520868;11268.661940141808;11278.718032049183;11282.717932051683;11284.774304513905;11019.559217631366;11086.113110951184;11087.611933582286;11092.55629774808;11094.389808560525;46462.76768616135;46540;46815.29777053347;46919.76540117278;46981.76509117467;3996.440498330236;4091.2635725569385;4117.97941010293;4223.91552168958;4398.868033958986;35605.46591801125;35666.39183859994;35789.284214315565;36204.27591448183;36958.260834783425;4401.581849724271;4443.333499974998;4489.59593636571;4579.7252164870215;4582.152301824173;394575;394814;394838;394886;397089;67697.32302677009;67948.32051679419;68024.6598767003;68066.31933680699;68068;40599.39100913489;41269.58730412718;41314.586854131674;43471.13057738828;43586.56413435889;16260.105694186934;16348.591285217726;16360.263788129325;16363.018218906904;16363.672726545403;13091.345432728325;13101.803472947913;13115.213087214797;13167.341632918316;13198.934005329911;21378.679319810217;21419.250326238507;21464;21516.246931357327;21627.35117946464;6201.906971395432;7618.352440042548;7738.4969976951315;7739.574323412154;7855.175206603339;1030.3354336452946;1055.773008803111;1075.9408232547128;1090.6019302954364;1113.549012649878;35499.82250088784;35670.64329356725;35918.281634367166;36059.63940360563;36140.277194455964;1145.352875625269;1158.7450760832603;1165.7843298989715;1179.9233049851732;1251.1492185313891;17949.551261218574;18706.78405903612;18815.247390104516;19004.524886877938;19076.61846763057;613276;615955;618597;620465;621902;66467.6706465868;67080;67303;67344.32655673478;67439.66280168567;1322.7817410127413;1388.7430825297351;1423.8576142385677;1432.6131944375168;1453.2951518513723;34765.82617086898;36811.44782828313;36871.44692829663;36981;37181.81409093046;50937.745311273204;51144.74427627838;51194.23208651948;51451.48548514467;51470;462326;462864;465226;465519;466396;28145.45200014019;28582.57126143068;28661.71338286674;28846.855765721037;28980.130596082152;53188.20217696815;53585;54044.18933716076;54084.72957635344;54837.725811372286;44945;45727.54272457366;46153.307700385194;46728;47608;44517.55482445134;46008;46244;46645.766771167284;46680.29979550242;44723.55276447194;44752.55247447614;44782.55217447914;46100.538994609626;46192.538074618824;38448.423273651475;40017.39973900452;40125.59874401218;40184.397234042095;40774.79612601918;29753.702462975092;29764.55353169661;29940.70059299379;30003.249918752204;30264.5460318091 07:12:51 INFO - PROCESS | 19417 | |2;jquery.html;416.53764321603035;427.1222637479979;438.9385486031956;441.4462335389771;447.68661936644355;18780.342688005923;19461.708074378897;20191.990400479954;20398.388048358534;20602.278920237793;19217.423477295702;19611.607767844635;19866.40400787975;20037.899810500927;20126;5564.777408903639;5744.741486633112;5759.798407055754;5761.971190144043;5774.105013722879;5571.916421253684;5586.55307575395;5647.322321321447;5659;5690;5542.5565954723525;5631.774729010835;5671.517920976722;5673.347565030012;5678.26182596264;5590.972045139769;5632.183333416652;5655.632383895054;5802.448767367094;5806.912896306559;5620.606557540974;5704.572157088193;5728.169415434781;5732.111522713986;5984.760609575612;14497.275136243146;14862.108273503625;14887.851121488755;14927.626809329746;23375.76624233753;2362.1850461590725;2473.1220416751976;2516.345750104986;2626.1858823764696;2682.543967525525;11262.21164518484;11397.202195846294;13000.219986800728;13335.533256335983;14643.63390915225;1990.6375500761928;2015.7581090269116;2088.5092003379195;2093.42430831521;2113.3659902029485;63512.68243658798;65866.67066664684;79188.60405697992;81633.59183204046;86486.56756716176;11703.765924681458;11915.582954596548;12142.114523352619;12277.447514861853;12297.323647199311;17330.39343622967;17337.82662173362;17346.566335841704;17353.3058677654;17366.044867532408;8257.587120644064;8282.958585207036;8378.49729016261;8383.874241886377;8473;7726.845463090819;7774.611269436506;7807.102183248999;7945.682172713026;7967.721129760431;5031.496850315012;5058.342415485963;5140.408852981955;5257.973710131501;5265.631405801557;2090.393785802131;2114.2177394364185;2129.542148438093;2156.4177672028777;2167.5989941860807;483.0580368281794;998.8501724741346;1021.0912288063629;1057.4659796802566;1087.6030248959073;12202.328871911952;12848.935755321163;13162.473501060042;13177.538786142632;13208.33958302081;497.7959036794897;498.436766453912;500.3995205753111;508.5143687778163;508.8066534716784;6903.1716194057035;6945.340192681687;6978;7026.824329391806;7124.92875071253;571337;572312;582716;592207;598497;73374.63312683403;85805;87965;99075;102520.41178558714;490.63202598051464;504.92930989661477;505.71174430574956;507.5787096709722;508.30362403507434;11883.227590206609;11939.164258501822;12058.15592908488;12212.450439730055;12281.93859030699;33083.669163308055;43435;43594.78202608967;44404.11191776082;45130.0973980512;62001.68999155156;62078;62457;63127;63722.36277637164;9166.67916622915;9184.816303673755;9243.907560924305;9347.439153650803;9357.344985850925;10385.584576617153;13516.86483135156;13878.722425551634;14412.927935360256;14822;12316.445759940645;12327.397438332959;12603.810942835682;12724.554640587536;12838.614841554769;11547.4803633835;11986.64040078799;12120;12186.57346992851;12659.367031648564;12222.816657749963;12392.876071239172;12420.503179872703;12602.306873122066;12660.493580256687;8985.371024028516;9011.684591039282;9072.773180670536;9123.042080581443;9199.816003680024;9287.76780580491;11525.942370288094;12213.633590992284;12528.436220369922;12758.68103297425 07:12:51 INFO - PROCESS | 19417 | |3;mootools.html;1544.8686861616761;1577.0380068158422;1791.4177892185044;1879.2295158984818;1930.1989674285164;8859.955700221499;9588.76028099298;9596.376235544685;9609.711708648743;9612.759681007978;8065.354771618271;8281.254687078163;8288.254057134856;8501.617427215775;9421.576029078691;2284.440312123532;2338.894749736266;2345.8944347504316;2354.1289722802585;2365.2431222008963;2402.3393566769105;2420.6006009008493;2435.8051355891484;2438.3538362333907;2480.06997375984;2394.2697477269426;2409.2410890569413;2411.168146989286;2416.214730212679;2417.625268083441;2389.9880500597474;2412.372783076395;2416.4079800448912;2418.419579300964;2419.697537807774;2295.7130358705163;2359.988200059006;2363.2319496163645;2367.289813056076;2367.3963139399516;9444.244460443148;9546.522673866279;9589.42463452195;9600.423974561549;9605.711828645151;5433.212184233264;5522.420145884705;5560.4161563036105;5576.749046292926;5579.944200558024;3328.9334213315597;3389.9491507627404;3488.9127771805656;3537.3455910656353;3543.645635436436;1222.7493363860387;1233.808759642257;1240.9503619855195;1246.4079562207878;1261.1109168036512;13306.86693133066;13319.933400333033;13374.799378009338;13404;13447.193168409829;4822.276658501253;4844.128056949712;4845.75771211438;4929.556339929423;5046.772895219724;11114.166437517219;11119.833202501968;11136.276142050741;11145.442727863574;11166.330020198651;5584.385717571064;5596.384397716248;5629.043062679354;5631.521320687705;5632.915506267409;5091.719955402488;5109.335786347751;5173.1464308388695;5184.948150518522;5187.481251874858;4266;4271.380649805761;4307.935380969288;4317.639943417813;4341.869743907688;1316.1773891317926;1387.5559820857432;1389.1873254146235;1395.0653062448184;1407.7893012009663;1024.7899180667946;1041.063043261071;1051.3901936876593;1061.5488417422719;1064.084886997182;6409.134766806422;6457.741690332334;6464.25661048976;6507.446867016357;6640.302768209365;469.76276980124817;476.80212711724937;485.99028019439413;488.8435700575853;505.27493047476986;4569.314602809605;4656.231721765938;4705.106029854385;4712;4792.2092854678585;11290.322580645188;11792.29246245228;11800.645980620595;11808.46861891217;11808.822867656992;11495.080393568494;11541.365224912543;11547.711307217387;11591.536338546364;11627.709307267216;8300.543470109202;8608.397412181088;8626.827463450696;8810.471371717846;8842.557872106368;6596.472282217315;6627.37039981201;6680.198376194693;6788.490863185182;6918.377346038881;10366;10843.566257349617;10895.346279223271;10902.509387077442;11020.944895275472;9704;10805.729856753644;11049.060829829554;11113.499892505019;11193.216474846682;8215.917840821516;8286.212809783061;8414.116517765791;8613.48319100856;8626.223639872442;8634.309255259439;8637.093105224112;8730.65077396897;8750.037495875322;8816.382853200214;8044.798880028046;8140.918590814016;8351.41540092212;8371.372147089114;8371.455855369382;8109.472884262504;8110.878336825071;8156.306713929383;8209.220124088202;8284.130166332689;7418.109826820816;7497.10034795828;7507;7509.061367329084;7565.394768418623;5368.382635996754;5390.919136213039;5435.592330575302;5480.6437581557075;5661.575381846295;5842.795502157404;5884.823455296348;5925.377835327228;5953.523718102455;5996.640201587918 07:12:51 INFO - PROCESS | 19417 | |4;prototype.html;341.49117814456457;344.65534465534455;346.64468919357654;350.74570936071353;351.68172803612737;5647;5667.603267771253;5682.090865461527;5701.429857014296;5712.771489140435;5520.806771762989;5533.6956467394275;5622.522085622727;5628.915566266509;5631.4087020862835;3900.356441187215;4141.875743727685;4342.54403287655;4345.587169218919;4379.781010949456;4323.589259020404;4327.610515053644;4339.587739164775;4360.127974405116;4360.825566977317;4345.587169218919;4371;4392.450943632046;4400.965773043332;4406.669499787513;4157.958420415787;4310.3103503439615;4346.978265108686;4410.71330363527;4415.381846541487;3794.165283637595;3816.1795214029116;3819.8090095495113;3821.789801560913;3831.6551510363925;5424.593155513352;5444.373897001856;5460.672359658433;5472.671639701631;5482.17767334899;4885.14509960759;4966.404031516205;4977.925331120036;5018.436909794051;5020.874478138039;5764.164196191528;5765.942340576583;5770.740316685759;5780.393058728857;5838.678872662002;4203.537610862833;4217.36739489076;4248.4052232687445;4263.8081286342185;4286.849960251377;5946.7026648667825;6096.085587161917;6114.571979961404;6133.723982420757;6145.231846019247;4276.3157894737315;4316.072044510445;4327.632151267115;4344.9348259776125;4378.365137055109;4395.25280702275;4424.623906967944;4435.35687325336;4439.245328294198;4457.821687132543;4886.242632391985;4941.604671626261;4954.95945851368;4982.078315511642;5061.7469126544265;5773.826785196451;5793.971030144907;5811.360750317462;5847.152162936435;5852.15143804146;1498.3182651893362;1514.121809350574;1515.4014164404998;1516.696660667871;1517.7647464642998;922.1884741427501;946.6746554823302;968.1480297338297;969.5733877094056;992.8907820139779;5005.649604527721;5025.592834006484;5055.545000949932;5097.057044446789;5108.492994566612;5265.4997775211295;5275.839315350617;5293.814716484904;5318.069337865935;5350.705711185922;4751.976240118777;5012.370979431699;5049.899002019939;5105.2342148678;5124.897502050013;2802.8458434786285;2806.8596570171408;2807.4525467534045;2814.240155158096;2821.7460428561526;5398.6220964532895;5405.729713514387;5420.430854760272;5449.727513624303;5472;5109;5343.679379237258;5377.300950876361;5407.513323800877;5858.209141765892;6136;6145.692715364214;6214.502839772807;6237.220347456659;6241.937580624136;5986.461218490357;6025;6026.939730602638;6042.184305118929;6098.481629061579;5828.446297601721;5857.970710146422;5922.851928701817;5940.940590594038;5983.640981541122;6256.343083976117;6258.43674069336;6263.6241825490615;6265.780697675559;6277.623342599459;4069.8575549855614;4216.30430978885;4250.978745106255;4261.296886013773;4278.914421711611;5990.820275391745;6007.369226231358;6024.759009639741;6033.276006879202;6045.274567051806;5830.650160990354;5835.44563266489;5873.618214816023;5940.316863560746;5945.227120474311;5565.77736890536;5848.590598658053;5901.642622196833;5914.172015917689;5920.763169473345;5901.704914754159;5910.911336329872;5914.7338369774325;5933.703314834327;5934.851628709317;5708.286464191976;5917.171595976481;5927.229460170151;6006.219191505076;6043.456088952015;5778.537716982547;5786.884262314814;5802.013657678121;5806.65160090396;5817.854553636193 07:12:51 INFO - PROCESS | 19417 | |5;yui.html;860.4622111180512;877.6182360673106;881.6825942660641;896.4486840593033;903.4263242840798;14296.928515357422;15085.698286034287;15149.242537873117;15172.924135379322;15177.772333415007;14278.92860535696;14303;14314.713705725879;14323.28383580822;14375.856241437583;736.2343163110359;869.1048220333067;874.0516539554575;880.6741505642889;884.2748945864394;885.7962668586229;887.7381172554112;887.786931136529;887.9422837515573;888.1651247827022;939.9483028433433;940.7412961435591;942.208544822349;942.4062840410531;942.6936245720168;938.0431959401373;938.4697645830119;938.8357037518475;939.1641439119155;939.2861425316775;690.623610132479;690.7409721354492;690.8445599740069;692.5706062241443;693.1578132568902;14574.781378279335;14582.197979111144;14603.853961460356;14606.19665918374;14607;13980.231087290194;14290.642733931734;15475.845241547553;15866.682666346609;16131.596710082342;13274.601761947157;13333.399997000159;13996.16023038621;14039.508617198451;14446.566603001927;4185.811638476245;4205.831766729358;4212.894677633053;4287.464066991626;4293.141371725636;74868;83306.58346708288;83855.58072209661;83885.99999999939;83906;11690.76618467643;11757.588484403004;11808.94095529517;11952.64142075739;12174.452149653287;24685.62971555428;25419.87290063538;25596.48807023849;25725.356866078124;25766.09818656338;9444.669436569824;9569.61721531145;9612.615495380107;9628.855567166498;9687.273454490942;5063.02636100587;5266.210068489757;5303.416624171338;5313.335833020872;5523.28197334344;4098.323776576832;4243;4244.172386384687;4248.171606536695;4249.490061192615;723.7575412236854;738.5753191914563;743.7211045857804;770.3567521119928;781.8592653322343;690.7064497588505;724.3408498266657;743.325167644077;746.1531162131021;797.2625321577517;10528.578856845794;10574.841377379345;10608;10631.308964917256;10748.89251107479;806.8749343851713;809.6882700160363;813.7355359508065;829.7344849648175;834.2575108153748;5170.3278573785165;5186.688798672017;5202.76587553561;5252.448492908266;5298.549623282064;81188.59405702933;82114.17885821065;82186;112679;129009;72262;87506.56246718725;104277.47861260797;119080.40459797645;122506;102124.48937755264;105951.4702426483;106870;107689;111352;9747.268954828558;9782.951085244527;10150.847737283799;10190.184785217312;10257.640982565872;60986.695066524386;62145;62451.375486246376;62554.687226563576;63536;83603;87401.56299218463;91430.54284728534;92469;94309.52845235729;13822;13990.860091399363;14320.641983950485;14416.351264193347;14527;14171.503997360043;14286.499972500913;14311.284435777961;14311.284435777961;14326.713465730836;12532;12731.363431828186;12971.740565188833;12989.090763646454;13195.80206296887;12128.939355303168;12243.510259589517;12266.4480098394;12956.740865182832;13363.732725345633;11664.125190610568;11858.407079646156;11877.881221187678;12058.216215945935;12574.37128143571;10145.289829712145;11056.281341712764;11066.557337706401;11079.446027698421;11236.438178091226;11000;11202.775944481227;11251.549938002388;11274.210805243554;11274.887251127711 07:12:51 INFO - PROCESS | 19417 | __end_tp_report 07:12:51 INFO - PROCESS | 19417 | __start_cc_report 07:12:51 INFO - PROCESS | 19417 | _x_x_mozilla_cycle_collect,1028 07:12:51 INFO - PROCESS | 19417 | __end_cc_report 07:12:51 INFO - PROCESS | 19417 | __startTimestamp1475590371632__endTimestamp 07:12:51 INFO - PROCESS | 19417 | 07:12:51 INFO - PROCESS | 19417 | ------- Summary: start ------- 07:12:51 INFO - PROCESS | 19417 | Number of tests: 6 07:12:51 INFO - PROCESS | 19417 | 07:12:51 INFO - PROCESS | 19417 | [#0] dojo.html Cycles:165 Average:5598.80 Median:5841.39 stddev:638.59 (10.9%) stddev-sans-first:613.13 07:12:51 INFO - PROCESS | 19417 | Values: 3239.3 3249.6 3261.1 3298.9 3325.6 5613.5 5685.5 5690.2 5702.2 5704.8 5627.9 5703.8 5710.3 5711.1 5723.5 5157.1 5160.7 5162.0 5217.7 5233.5 4992.2 5234.7 5242.8 5254.2 5282.6 4969.0 5056.6 5067.1 5089.3 5113.1 5061.2 5089.7 5109.2 5171.4 5201.3 5002.3 5077.9 5128.2 5141.4 5146.0 5462.9 5473.7 5494.8 5547.0 5617.2 5888.4 5899.4 5945.4 5989.8 5996.9 5876.4 6013.9 6021.9 6031.9 6039.6 5574.5 5645.9 5651.3 5699.7 5704.0 6224.9 6232.6 6237.5 6240.0 6244.9 5716.2 5825.7 5971.3 5973.1 5973.8 5948.3 6033.9 6038.2 6075.1 6078.3 5996.6 6000.5 6006.8 6010.5 6012.7 5751.7 5868.7 6010.8 6020.4 6021.2 4563.0 4578.1 4602.3 4603.6 4606.1 4088.6 4115.7 4120.4 4132.7 4145.7 5580.4 5650.2 5726.9 5729.3 5729.6 6008.3 6012.6 6082.1 6089.0 6103.8 5753.1 5760.9 5761.5 5761.9 5762.5 4949.6 5003.7 5077.6 5080.8 5082.6 6146.1 6253.6 6261.0 6268.2 6268.7 6053.8 6065.9 6066.9 6071.4 6104.3 6019.5 6133.8 6159.6 6277.1 6278.1 5904.7 5973.6 5983.1 5986.3 6012.7 6070.8 6091.4 6091.4 6092.4 6094.6 5691.5 5790.3 5945.3 5956.3 5957.9 5841.4 5937.6 5938.2 5947.7 5959.2 5846.0 5875.5 5880.9 5880.9 5886.9 5690.7 5859.7 5880.9 5882.1 5941.6 5835.5 5907.9 5912.1 5929.3 5937.2 07:12:51 INFO - PROCESS | 19417 | 07:12:51 INFO - PROCESS | 19417 | [#1] ext.html Cycles:180 Average:66058.44 Median:40687.09 stddev:133141.63 (327.2%) stddev-sans-first:133424.40 07:12:51 INFO - PROCESS | 19417 | Values: 594.0 644.9 670.4 676.6 683.1 35517.5 38748.0 38786.0 38831.6 38841.2 39261.0 40093.8 51816.0 52961.5 52978.0 9217.5 9356.1 9357.5 9363.7 9366.0 9147.8 9233.4 9238.6 9244.6 9253.7 8671.8 8899.1 11091.1 11322.2 11364.9 11203.8 11268.7 11278.7 11282.7 11284.8 11019.6 11086.1 11087.6 11092.6 11094.4 46462.8 46540.0 46815.3 46919.8 46981.8 3996.4 4091.3 4118.0 4223.9 4398.9 35605.5 35666.4 35789.3 36204.3 36958.3 4401.6 4443.3 4489.6 4579.7 4582.2 394575.0 394814.0 394838.0 394886.0 397089.0 67697.3 67948.3 68024.7 68066.3 68068.0 40599.4 41269.6 41314.6 43471.1 43586.6 16260.1 16348.6 16360.3 16363.0 16363.7 13091.3 13101.8 13115.2 13167.3 13198.9 21378.7 21419.3 21464.0 21516.2 21627.4 6201.9 7618.4 7738.5 7739.6 7855.2 1030.3 1055.8 1075.9 1090.6 1113.5 35499.8 35670.6 35918.3 36059.6 36140.3 1145.4 1158.7 1165.8 1179.9 1251.1 17949.6 18706.8 18815.2 19004.5 19076.6 613276.0 615955.0 618597.0 620465.0 621902.0 66467.7 67080.0 67303.0 67344.3 67439.7 1322.8 1388.7 1423.9 1432.6 1453.3 34765.8 36811.4 36871.4 36981.0 37181.8 50937.7 51144.7 51194.2 51451.5 51470.0 462326.0 462864.0 465226.0 465519.0 466396.0 28145.5 28582.6 28661.7 28846.9 28980.1 53188.2 53585.0 54044.2 54084.7 54837.7 44945.0 45727.5 46153.3 46728.0 47608.0 44517.6 46008.0 46244.0 46645.8 46680.3 44723.6 44752.6 44782.6 46100.5 46192.5 38448.4 40017.4 40125.6 40184.4 40774.8 29753.7 29764.6 29940.7 30003.2 30264.5 07:12:51 INFO - PROCESS | 19417 | 07:12:51 INFO - PROCESS | 19417 | [#2] jquery.html Cycles:180 Average:31189.50 Median:22425.90 stddev:95779.69 (427.1%) stddev-sans-first:96020.51 07:12:51 INFO - PROCESS | 19417 | Values: 416.5 427.1 438.9 441.4 447.7 18780.3 19461.7 20192.0 20398.4 20602.3 19217.4 19611.6 19866.4 20037.9 20126.0 5564.8 5744.7 5759.8 5762.0 5774.1 5571.9 5586.6 5647.3 5659.0 5690.0 5542.6 5631.8 5671.5 5673.3 5678.3 5591.0 5632.2 5655.6 5802.4 5806.9 5620.6 5704.6 5728.2 5732.1 5984.8 14497.3 14862.1 14887.9 14927.6 23375.8 2362.2 2473.1 2516.3 2626.2 2682.5 11262.2 11397.2 13000.2 13335.5 14643.6 1990.6 2015.8 2088.5 2093.4 2113.4 63512.7 65866.7 79188.6 81633.6 86486.6 11703.8 11915.6 12142.1 12277.4 12297.3 17330.4 17337.8 17346.6 17353.3 17366.0 8257.6 8283.0 8378.5 8383.9 8473.0 7726.8 7774.6 7807.1 7945.7 7967.7 5031.5 5058.3 5140.4 5258.0 5265.6 2090.4 2114.2 2129.5 2156.4 2167.6 483.1 998.9 1021.1 1057.5 1087.6 12202.3 12848.9 13162.5 13177.5 13208.3 497.8 498.4 500.4 508.5 508.8 6903.2 6945.3 6978.0 7026.8 7124.9 571337.0 572312.0 582716.0 592207.0 598497.0 73374.6 85805.0 87965.0 99075.0 102520.4 490.6 504.9 505.7 507.6 508.3 11883.2 11939.2 12058.2 12212.5 12281.9 33083.7 43435.0 43594.8 44404.1 45130.1 62001.7 62078.0 62457.0 63127.0 63722.4 9166.7 9184.8 9243.9 9347.4 9357.3 10385.6 13516.9 13878.7 14412.9 14822.0 12316.4 12327.4 12603.8 12724.6 12838.6 11547.5 11986.6 12120.0 12186.6 12659.4 12222.8 12392.9 12420.5 12602.3 12660.5 8985.4 9011.7 9072.8 9123.0 9199.8 9287.8 11525.9 12213.6 12528.4 12758.7 07:12:51 INFO - PROCESS | 19417 | 07:12:51 INFO - PROCESS | 19417 | [#3] mootools.html Cycles:180 Average:6185.35 Median:4807.24 stddev:3543.52 (73.7%) stddev-sans-first:3536.30 07:12:51 INFO - PROCESS | 19417 | Values: 1544.9 1577.0 1791.4 1879.2 1930.2 8860.0 9588.8 9596.4 9609.7 9612.8 8065.4 8281.3 8288.3 8501.6 9421.6 2284.4 2338.9 2345.9 2354.1 2365.2 2402.3 2420.6 2435.8 2438.4 2480.1 2394.3 2409.2 2411.2 2416.2 2417.6 2390.0 2412.4 2416.4 2418.4 2419.7 2295.7 2360.0 2363.2 2367.3 2367.4 9444.2 9546.5 9589.4 9600.4 9605.7 5433.2 5522.4 5560.4 5576.7 5579.9 3328.9 3389.9 3488.9 3537.3 3543.6 1222.7 1233.8 1241.0 1246.4 1261.1 13306.9 13319.9 13374.8 13404.0 13447.2 4822.3 4844.1 4845.8 4929.6 5046.8 11114.2 11119.8 11136.3 11145.4 11166.3 5584.4 5596.4 5629.0 5631.5 5632.9 5091.7 5109.3 5173.1 5184.9 5187.5 4266.0 4271.4 4307.9 4317.6 4341.9 1316.2 1387.6 1389.2 1395.1 1407.8 1024.8 1041.1 1051.4 1061.5 1064.1 6409.1 6457.7 6464.3 6507.4 6640.3 469.8 476.8 486.0 488.8 505.3 4569.3 4656.2 4705.1 4712.0 4792.2 11290.3 11792.3 11800.6 11808.5 11808.8 11495.1 11541.4 11547.7 11591.5 11627.7 8300.5 8608.4 8626.8 8810.5 8842.6 6596.5 6627.4 6680.2 6788.5 6918.4 10366.0 10843.6 10895.3 10902.5 11020.9 9704.0 10805.7 11049.1 11113.5 11193.2 8215.9 8286.2 8414.1 8613.5 8626.2 8634.3 8637.1 8730.7 8750.0 8816.4 8044.8 8140.9 8351.4 8371.4 8371.5 8109.5 8110.9 8156.3 8209.2 8284.1 7418.1 7497.1 7507.0 7509.1 7565.4 5368.4 5390.9 5435.6 5480.6 5661.6 5842.8 5884.8 5925.4 5953.5 5996.6 07:12:51 INFO - PROCESS | 19417 | 07:12:51 INFO - PROCESS | 19417 | [#4] prototype.html Cycles:180 Average:4841.28 Median:5413.97 stddev:1429.39 (26.4%) stddev-sans-first:1392.93 07:12:51 INFO - PROCESS | 19417 | Values: 341.5 344.7 346.6 350.7 351.7 5647.0 5667.6 5682.1 5701.4 5712.8 5520.8 5533.7 5622.5 5628.9 5631.4 3900.4 4141.9 4342.5 4345.6 4379.8 4323.6 4327.6 4339.6 4360.1 4360.8 4345.6 4371.0 4392.5 4401.0 4406.7 4158.0 4310.3 4347.0 4410.7 4415.4 3794.2 3816.2 3819.8 3821.8 3831.7 5424.6 5444.4 5460.7 5472.7 5482.2 4885.1 4966.4 4977.9 5018.4 5020.9 5764.2 5765.9 5770.7 5780.4 5838.7 4203.5 4217.4 4248.4 4263.8 4286.8 5946.7 6096.1 6114.6 6133.7 6145.2 4276.3 4316.1 4327.6 4344.9 4378.4 4395.3 4424.6 4435.4 4439.2 4457.8 4886.2 4941.6 4955.0 4982.1 5061.7 5773.8 5794.0 5811.4 5847.2 5852.2 1498.3 1514.1 1515.4 1516.7 1517.8 922.2 946.7 968.1 969.6 992.9 5005.6 5025.6 5055.5 5097.1 5108.5 5265.5 5275.8 5293.8 5318.1 5350.7 4752.0 5012.4 5049.9 5105.2 5124.9 2802.8 2806.9 2807.5 2814.2 2821.7 5398.6 5405.7 5420.4 5449.7 5472.0 5109.0 5343.7 5377.3 5407.5 5858.2 6136.0 6145.7 6214.5 6237.2 6241.9 5986.5 6025.0 6026.9 6042.2 6098.5 5828.4 5858.0 5922.9 5940.9 5983.6 6256.3 6258.4 6263.6 6265.8 6277.6 4069.9 4216.3 4251.0 4261.3 4278.9 5990.8 6007.4 6024.8 6033.3 6045.3 5830.7 5835.4 5873.6 5940.3 5945.2 5565.8 5848.6 5901.6 5914.2 5920.8 5901.7 5910.9 5914.7 5933.7 5934.9 5708.3 5917.2 5927.2 6006.2 6043.5 5778.5 5786.9 5802.0 5806.7 5817.9 07:12:51 INFO - PROCESS | 19417 | 07:12:51 INFO - PROCESS | 19417 | [#5] yui.html Cycles:180 Average:22068.79 Median:4209.36 stddev:31901.47 (757.9%) stddev-sans-first:31951.22 07:12:51 INFO - PROCESS | 19417 | Values: 860.5 877.6 881.7 896.4 903.4 14296.9 15085.7 15149.2 15172.9 15177.8 14278.9 14303.0 14314.7 14323.3 14375.9 736.2 869.1 874.1 880.7 884.3 885.8 887.7 887.8 887.9 888.2 939.9 940.7 942.2 942.4 942.7 938.0 938.5 938.8 939.2 939.3 690.6 690.7 690.8 692.6 693.2 14574.8 14582.2 14603.9 14606.2 14607.0 13980.2 14290.6 15475.8 15866.7 16131.6 13274.6 13333.4 13996.2 14039.5 14446.6 4185.8 4205.8 4212.9 4287.5 4293.1 74868.0 83306.6 83855.6 83886.0 83906.0 11690.8 11757.6 11808.9 11952.6 12174.5 24685.6 25419.9 25596.5 25725.4 25766.1 9444.7 9569.6 9612.6 9628.9 9687.3 5063.0 5266.2 5303.4 5313.3 5523.3 4098.3 4243.0 4244.2 4248.2 4249.5 723.8 738.6 743.7 770.4 781.9 690.7 724.3 743.3 746.2 797.3 10528.6 10574.8 10608.0 10631.3 10748.9 806.9 809.7 813.7 829.7 834.3 5170.3 5186.7 5202.8 5252.4 5298.5 81188.6 82114.2 82186.0 112679.0 129009.0 72262.0 87506.6 104277.5 119080.4 122506.0 102124.5 105951.5 106870.0 107689.0 111352.0 9747.3 9783.0 10150.8 10190.2 10257.6 60986.7 62145.0 62451.4 62554.7 63536.0 83603.0 87401.6 91430.5 92469.0 94309.5 13822.0 13990.9 14320.6 14416.4 14527.0 14171.5 14286.5 14311.3 14311.3 14326.7 12532.0 12731.4 12971.7 12989.1 13195.8 12128.9 12243.5 12266.4 12956.7 13363.7 11664.1 11858.4 11877.9 12058.2 12574.4 10145.3 11056.3 11066.6 11079.4 11236.4 11000.0 11202.8 11251.5 11274.2 11274.9 07:12:51 INFO - PROCESS | 19417 | -------- Summary: end -------- 07:12:51 INFO - PROCESS | 19417 | 07:12:52 INFO - TEST-INFO | 19417: exit 0 07:12:52 INFO - TEST-OK | dromaeo_css | took 1113234ms 07:12:52 INFO - TEST-START | kraken 07:12:52 INFO - operating with platform_type : linux_ 07:12:52 INFO - Initialising browser for kraken test... 07:12:52 INFO - TEST-INFO | started process 19613 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOA92hx/profile http://localhost:47351/getInfo.html) 07:12:56 INFO - PROCESS | 19613 | __metrics Screen width/height:1600/1200 07:12:56 INFO - PROCESS | 19613 | colorDepth:24 07:12:56 INFO - PROCESS | 19613 | Browser inner width/height: 1024/697 07:12:56 INFO - PROCESS | 19613 | __metrics 07:12:57 INFO - PROCESS | 19613 | 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" 07:12:57 INFO - PROCESS | 19613 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:12:57 INFO - PROCESS | 19613 | console.error: PushService: 07:12:57 INFO - PROCESS | 19613 | stateChangeProcessEnqueue: Error transitioning state 07:12:57 INFO - PROCESS | 19613 | UnknownError 07:12:58 INFO - TEST-INFO | 19613: exit 0 07:12:58 INFO - Browser initialized. 07:12:58 INFO - Running cycle 1/1 for kraken test... 07:12:58 INFO - TEST-INFO | started process 19700 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOA92hx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:13:10 INFO - PROCESS | 19700 | RSS: Main: 143929344 07:13:10 INFO - PROCESS | 19700 | 07:13:12 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:47351/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47351/tests/kraken/driver.html?testName=audio-beat-detection) 07:13:13 INFO - PROCESS | 19700 | RSS: Main: 199352320 07:13:13 INFO - PROCESS | 19700 | 07:13:15 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:47351/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47351/tests/kraken/driver.html?testName=audio-dft) 07:13:16 INFO - PROCESS | 19700 | RSS: Main: 183271424 07:13:16 INFO - PROCESS | 19700 | 07:13:19 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:47351/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47351/tests/kraken/driver.html?testName=audio-fft) 07:13:19 INFO - PROCESS | 19700 | RSS: Main: 274665472 07:13:19 INFO - PROCESS | 19700 | 07:13:21 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:47351/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47351/tests/kraken/driver.html?testName=audio-oscillator) 07:13:22 INFO - PROCESS | 19700 | RSS: Main: 179986432 07:13:22 INFO - PROCESS | 19700 | 07:13:24 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:47351/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47351/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:13:25 INFO - PROCESS | 19700 | RSS: Main: 179195904 07:13:25 INFO - PROCESS | 19700 | 07:13:28 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:47351/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47351/tests/kraken/driver.html?testName=imaging-darkroom) 07:13:28 INFO - PROCESS | 19700 | RSS: Main: 214892544 07:13:28 INFO - PROCESS | 19700 | 07:13:32 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:47351/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47351/tests/kraken/driver.html?testName=imaging-desaturate) 07:13:32 INFO - PROCESS | 19700 | RSS: Main: 213393408 07:13:32 INFO - PROCESS | 19700 | 07:13:35 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:47351/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47351/tests/kraken/driver.html?testName=json-parse-financial) 07:13:36 INFO - PROCESS | 19700 | RSS: Main: 213454848 07:13:36 INFO - PROCESS | 19700 | 07:13:37 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:47351/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47351/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:13:38 INFO - PROCESS | 19700 | RSS: Main: 175648768 07:13:38 INFO - PROCESS | 19700 | 07:13:39 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:47351/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47351/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:13:40 INFO - PROCESS | 19700 | RSS: Main: 182358016 07:13:40 INFO - PROCESS | 19700 | 07:13:42 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:47351/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47351/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:13:43 INFO - PROCESS | 19700 | RSS: Main: 184221696 07:13:43 INFO - PROCESS | 19700 | 07:13:45 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:47351/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47351/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:13:46 INFO - PROCESS | 19700 | RSS: Main: 185856000 07:13:46 INFO - PROCESS | 19700 | 07:13:48 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:47351/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47351/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:13:49 INFO - PROCESS | 19700 | RSS: Main: 183537664 07:13:49 INFO - PROCESS | 19700 | 07:13:50 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:47351/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47351/tests/kraken/driver.html?testName=ai-astar) 07:13:51 INFO - PROCESS | 19700 | RSS: Main: 234737664 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | __start_tp_report 07:13:51 INFO - PROCESS | 19700 | _x_x_mozilla_page_load 07:13:51 INFO - PROCESS | 19700 | _x_x_mozilla_page_load_details 07:13:51 INFO - PROCESS | 19700 | |i|pagename|runs| 07:13:51 INFO - PROCESS | 19700 | |0;ai-astar;114;110;119;109;109;110;109;110;110;111 07:13:51 INFO - PROCESS | 19700 | |1;audio-beat-detection;147;120;140;171;116;142;147;118;179;116 07:13:51 INFO - PROCESS | 19700 | |2;audio-dft;160;163;160;160;160;160;161;160;159;159 07:13:51 INFO - PROCESS | 19700 | |3;audio-fft;74;74;75;166;76;75;75;74;166;75 07:13:51 INFO - PROCESS | 19700 | |4;audio-oscillator;82;81;152;82;81;83;143;81;82;146 07:13:51 INFO - PROCESS | 19700 | |5;imaging-gaussian-blur;116;117;117;117;117;117;116;117;117;117 07:13:51 INFO - PROCESS | 19700 | |6;imaging-darkroom;145;145;145;145;145;146;146;146;145;145 07:13:51 INFO - PROCESS | 19700 | |7;imaging-desaturate;95;94;94;94;95;95;95;94;95;94 07:13:51 INFO - PROCESS | 19700 | |8;json-parse-financial;50;51;51;51;49;52;53;50;52;52 07:13:51 INFO - PROCESS | 19700 | |9;json-stringify-tinderbox;60;60;60;59;60;60;61;61;60;61 07:13:51 INFO - PROCESS | 19700 | |10;stanford-crypto-aes;72;73;72;72;72;78;72;73;73;71 07:13:51 INFO - PROCESS | 19700 | |11;stanford-crypto-ccm;120;127;117;133;127;120;116;125;145;118 07:13:51 INFO - PROCESS | 19700 | |12;stanford-crypto-pbkdf2;152;153;154;155;150;154;151;149;151;153 07:13:51 INFO - PROCESS | 19700 | |13;stanford-crypto-sha256-iterative;64;59;60;62;59;62;60;61;61;59 07:13:51 INFO - PROCESS | 19700 | __end_tp_report 07:13:51 INFO - PROCESS | 19700 | __start_cc_report 07:13:51 INFO - PROCESS | 19700 | _x_x_mozilla_cycle_collect,1630 07:13:51 INFO - PROCESS | 19700 | __end_cc_report 07:13:51 INFO - PROCESS | 19700 | __startTimestamp1475590431205__endTimestamp 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | ------- Summary: start ------- 07:13:51 INFO - PROCESS | 19700 | Number of tests: 14 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | [#0] ai-astar Cycles:10 Average:111.10 Median:110.00 stddev:3.14 (2.9%) stddev-sans-first:3.15 07:13:51 INFO - PROCESS | 19700 | Values: 114.0 110.0 119.0 109.0 109.0 110.0 109.0 110.0 110.0 111.0 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | [#1] audio-beat-detection Cycles:10 Average:139.60 Median:144.50 stddev:22.65 (15.7%) stddev-sans-first:23.87 07:13:51 INFO - PROCESS | 19700 | Values: 147.0 120.0 140.0 171.0 116.0 142.0 147.0 118.0 179.0 116.0 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:1.14 (0.7%) stddev-sans-first:1.20 07:13:51 INFO - PROCESS | 19700 | Values: 160.0 163.0 160.0 160.0 160.0 160.0 161.0 160.0 159.0 159.0 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | [#3] audio-fft Cycles:10 Average:93.00 Median:75.00 stddev:38.48 (51.3%) stddev-sans-first:40.19 07:13:51 INFO - PROCESS | 19700 | Values: 74.0 74.0 75.0 166.0 76.0 75.0 75.0 74.0 166.0 75.0 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | [#4] audio-oscillator Cycles:10 Average:101.30 Median:81.50 stddev:31.62 (38.8%) stddev-sans-first:32.75 07:13:51 INFO - PROCESS | 19700 | Values: 82.0 81.0 152.0 82.0 81.0 83.0 143.0 81.0 82.0 146.0 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 07:13:51 INFO - PROCESS | 19700 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 07:13:51 INFO - PROCESS | 19700 | Values: 145.0 145.0 145.0 145.0 145.0 146.0 146.0 146.0 145.0 145.0 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 07:13:51 INFO - PROCESS | 19700 | Values: 95.0 94.0 94.0 94.0 95.0 95.0 95.0 94.0 95.0 94.0 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.50 stddev:1.20 (2.3%) stddev-sans-first:1.20 07:13:51 INFO - PROCESS | 19700 | Values: 50.0 51.0 51.0 51.0 49.0 52.0 53.0 50.0 52.0 52.0 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 07:13:51 INFO - PROCESS | 19700 | Values: 60.0 60.0 60.0 59.0 60.0 60.0 61.0 61.0 60.0 61.0 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | [#10] stanford-crypto-aes Cycles:10 Average:72.80 Median:72.50 stddev:1.93 (2.7%) stddev-sans-first:2.03 07:13:51 INFO - PROCESS | 19700 | Values: 72.0 73.0 72.0 72.0 72.0 78.0 72.0 73.0 73.0 71.0 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | [#11] stanford-crypto-ccm Cycles:10 Average:124.80 Median:126.00 stddev:8.92 (7.1%) stddev-sans-first:9.29 07:13:51 INFO - PROCESS | 19700 | Values: 120.0 127.0 117.0 133.0 127.0 120.0 116.0 125.0 145.0 118.0 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.20 Median:153.00 stddev:1.93 (1.3%) stddev-sans-first:2.05 07:13:51 INFO - PROCESS | 19700 | Values: 152.0 153.0 154.0 155.0 150.0 154.0 151.0 149.0 151.0 153.0 07:13:51 INFO - PROCESS | 19700 | 07:13:51 INFO - PROCESS | 19700 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.70 Median:61.00 stddev:1.64 (2.7%) stddev-sans-first:1.22 07:13:51 INFO - PROCESS | 19700 | Values: 64.0 59.0 60.0 62.0 59.0 62.0 60.0 61.0 61.0 59.0 07:13:51 INFO - PROCESS | 19700 | -------- Summary: end -------- 07:13:51 INFO - PROCESS | 19700 | 07:13:52 INFO - TEST-INFO | 19700: exit 0 07:13:52 INFO - TEST-OK | kraken | took 59853ms 07:13:52 INFO - SUITE-END | took 1173s 07:13:52 INFO - Completed test suite (00:19:33) 07:13:52 INFO - javascript benchmark 07:13:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3239.287356781508, 3249.57755491786, 3261.086895669213, 3298.901032969012, 3325.634180240173, 5613.494785469307, 5685.516731077853, 5690.231818704484, 5702.173184888194, 5704.771809127635, 5627.915581266284, 5703.77184912603, 5710.314762228528, 5711.05767548354, 5723.54211663067, 5157.097507936096, 5160.716160611165, 5161.9741901290445, 5217.713025783581, 5233.476652334774, 4992.201247800353, 5234.6859188448625, 5242.7640756166065, 5254.185601231815, 5282.630215884889, 4969.031038947407, 5056.646034777567, 5067.113255180348, 5089.3129427527365, 5113.054084994288, 5061.16490779021, 5089.7455127243675, 5109.182530795092, 5171.3535808024, 5201.297824793643, 5002.349694539686, 5077.898442031139, 5128.205128205134, 5141.408737995141, 5145.974270128663, 5462.945370546323, 5473.7263136843, 5494.752736126844, 5547.029269877755, 5617.185508101303, 5888.411158884121, 5899.410058994066, 5945.435183657545, 5989.8203053908455, 5996.940030599725, 5876.44173803488, 6013.879722405527, 6021.909671354933, 6031.909521357183, 6039.607425517372, 5574.526165275956, 5645.943540564583, 5651.265335506399, 5699.715014249354, 5704.0, 6224.906626400608, 6232.56372053961, 6237.532185086136, 6240.0, 6244.906326405107, 5716.171155182476, 5825.650460972355, 5971.343152253248, 5973.104034394789, 5973.790917317873, 5948.345681974979, 6033.879322413527, 6038.215032045894, 6075.1191077294425, 6078.270607527036, 5996.610220335664, 6000.519958403317, 6006.759729610854, 6010.549208809357, 6012.729427175787, 5751.654900705971, 5868.706564671749, 6010.789622363196, 6020.397960204032, 6021.187139736167, 4563.0, 4578.061497393026, 4602.309653551994, 4603.58567728906, 4606.147862645421, 4088.550259471456, 4115.73247738896, 4120.423140760296, 4132.669386449077, 4145.689073319513, 5580.441955804468, 5650.180723795027, 5726.856828579235, 5729.341125770507, 5729.598928075078, 6008.279006519246, 6012.579119461683, 6082.148499210114, 6088.969555152196, 6103.816885493442, 5753.1370294456165, 5760.884782304331, 5761.452661997104, 5761.91357129635, 5762.538996880239, 4949.554540091337, 5003.749812509433, 5077.644564880423, 5080.796768129308, 5082.618803589746, 6146.139540464339, 6253.593516421418, 6261.029540421242, 6268.1851359323, 6268.655223962817, 6053.848653783514, 6065.939340606537, 6066.939330606814, 6071.392860713893, 6104.32852386246, 6019.518438524995, 6133.76314253068, 6159.630422174684, 6277.089821975879, 6278.058291256166, 5904.675242861684, 5973.5519836013345, 5983.1025346196675, 5986.281646202309, 6012.7294271757, 6070.817875463566, 6091.360407157184, 6091.42131497507, 6092.421219984094, 6094.634321940698, 5691.516221121251, 5790.305163380422, 5945.286565612154, 5956.344802071682, 5957.940420595912, 5841.386654401226, 5937.584369094122, 5938.168656388023, 5947.672877991752, 5959.1657167995645, 5846.035404158266, 5875.529957603467, 5880.882382352243, 5880.941190588211, 5886.911696324473, 5690.743916523849, 5859.677717725566, 5880.882382352414, 5882.088276317178, 5941.554383421347, 5835.503982161564, 5907.852303692442, 5912.142739302863, 5929.258842644669, 5937.168796368425], "value": 5555.6459833177905, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.9524838012959, 644.9258335291441, 670.4133882852506, 676.590662649097, 683.1290105115977, 35517.46723799142, 38748.0, 38785.99999999993, 38831.61168388315, 38841.22317553647, 39261.0, 40093.7995310023, 51816.0, 52961.47038529613, 52978.0, 9217.539123043838, 9356.064393560624, 9357.532123393838, 9363.6722714705, 9365.953170234174, 9147.771305717344, 9233.445993240393, 9238.6304547818, 9244.583993720265, 9253.676121335755, 8671.783205419852, 8899.065598112202, 11091.112710983143, 11322.150838687132, 11364.943175284112, 11203.831942520868, 11268.661940141808, 11278.718032049183, 11282.717932051683, 11284.774304513905, 11019.559217631366, 11086.113110951184, 11087.611933582286, 11092.55629774808, 11094.389808560525, 46462.76768616135, 46540.0, 46815.29777053347, 46919.76540117278, 46981.76509117467, 3996.440498330236, 4091.2635725569385, 4117.97941010293, 4223.91552168958, 4398.868033958986, 35605.46591801125, 35666.39183859994, 35789.284214315565, 36204.27591448183, 36958.260834783425, 4401.581849724271, 4443.333499974998, 4489.59593636571, 4579.7252164870215, 4582.152301824173, 394575.0, 394814.0, 394838.0, 394886.0, 397089.0, 67697.32302677009, 67948.32051679419, 68024.6598767003, 68066.31933680699, 68068.0, 40599.39100913489, 41269.58730412718, 41314.586854131674, 43471.13057738828, 43586.56413435889, 16260.105694186934, 16348.591285217726, 16360.263788129325, 16363.018218906904, 16363.672726545403, 13091.345432728325, 13101.803472947913, 13115.213087214797, 13167.341632918316, 13198.934005329911, 21378.679319810217, 21419.250326238507, 21464.0, 21516.246931357327, 21627.35117946464, 6201.906971395432, 7618.352440042548, 7738.4969976951315, 7739.574323412154, 7855.175206603339, 1030.3354336452946, 1055.773008803111, 1075.9408232547128, 1090.6019302954364, 1113.549012649878, 35499.82250088784, 35670.64329356725, 35918.281634367166, 36059.63940360563, 36140.277194455964, 1145.352875625269, 1158.7450760832603, 1165.7843298989715, 1179.9233049851732, 1251.1492185313891, 17949.551261218574, 18706.78405903612, 18815.247390104516, 19004.524886877938, 19076.61846763057, 613276.0, 615955.0, 618597.0, 620465.0, 621902.0, 66467.6706465868, 67080.0, 67303.0, 67344.32655673478, 67439.66280168567, 1322.7817410127413, 1388.7430825297351, 1423.8576142385677, 1432.6131944375168, 1453.2951518513723, 34765.82617086898, 36811.44782828313, 36871.44692829663, 36981.0, 37181.81409093046, 50937.745311273204, 51144.74427627838, 51194.23208651948, 51451.48548514467, 51470.0, 462326.0, 462864.0, 465226.0, 465519.0, 466396.0, 28145.45200014019, 28582.57126143068, 28661.71338286674, 28846.855765721037, 28980.130596082152, 53188.20217696815, 53585.0, 54044.18933716076, 54084.72957635344, 54837.725811372286, 44945.0, 45727.54272457366, 46153.307700385194, 46728.0, 47608.0, 44517.55482445134, 46008.0, 46244.0, 46645.766771167284, 46680.29979550242, 44723.55276447194, 44752.55247447614, 44782.55217447914, 46100.538994609626, 46192.538074618824, 38448.423273651475, 40017.39973900452, 40125.59874401218, 40184.397234042095, 40774.79612601918, 29753.702462975092, 29764.55353169661, 29940.70059299379, 30003.249918752204, 30264.5460318091], "value": 21666.577260100785, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.53764321603035, 427.1222637479979, 438.9385486031956, 441.4462335389771, 447.68661936644355, 18780.342688005923, 19461.708074378897, 20191.990400479954, 20398.388048358534, 20602.278920237793, 19217.423477295702, 19611.607767844635, 19866.40400787975, 20037.899810500927, 20126.0, 5564.777408903639, 5744.741486633112, 5759.798407055754, 5761.971190144043, 5774.105013722879, 5571.916421253684, 5586.55307575395, 5647.322321321447, 5659.0, 5690.0, 5542.5565954723525, 5631.774729010835, 5671.517920976722, 5673.347565030012, 5678.26182596264, 5590.972045139769, 5632.183333416652, 5655.632383895054, 5802.448767367094, 5806.912896306559, 5620.606557540974, 5704.572157088193, 5728.169415434781, 5732.111522713986, 5984.760609575612, 14497.275136243146, 14862.108273503625, 14887.851121488755, 14927.626809329746, 23375.76624233753, 2362.1850461590725, 2473.1220416751976, 2516.345750104986, 2626.1858823764696, 2682.543967525525, 11262.21164518484, 11397.202195846294, 13000.219986800728, 13335.533256335983, 14643.63390915225, 1990.6375500761928, 2015.7581090269116, 2088.5092003379195, 2093.42430831521, 2113.3659902029485, 63512.68243658798, 65866.67066664684, 79188.60405697992, 81633.59183204046, 86486.56756716176, 11703.765924681458, 11915.582954596548, 12142.114523352619, 12277.447514861853, 12297.323647199311, 17330.39343622967, 17337.82662173362, 17346.566335841704, 17353.3058677654, 17366.044867532408, 8257.587120644064, 8282.958585207036, 8378.49729016261, 8383.874241886377, 8473.0, 7726.845463090819, 7774.611269436506, 7807.102183248999, 7945.682172713026, 7967.721129760431, 5031.496850315012, 5058.342415485963, 5140.408852981955, 5257.973710131501, 5265.631405801557, 2090.393785802131, 2114.2177394364185, 2129.542148438093, 2156.4177672028777, 2167.5989941860807, 483.0580368281794, 998.8501724741346, 1021.0912288063629, 1057.4659796802566, 1087.6030248959073, 12202.328871911952, 12848.935755321163, 13162.473501060042, 13177.538786142632, 13208.33958302081, 497.7959036794897, 498.436766453912, 500.3995205753111, 508.5143687778163, 508.8066534716784, 6903.1716194057035, 6945.340192681687, 6978.0, 7026.824329391806, 7124.92875071253, 571337.0, 572312.0, 582716.0, 592207.0, 598497.0, 73374.63312683403, 85805.0, 87965.0, 99075.0, 102520.41178558714, 490.63202598051464, 504.92930989661477, 505.71174430574956, 507.5787096709722, 508.30362403507434, 11883.227590206609, 11939.164258501822, 12058.15592908488, 12212.450439730055, 12281.93859030699, 33083.669163308055, 43435.0, 43594.78202608967, 44404.11191776082, 45130.0973980512, 62001.68999155156, 62078.0, 62457.0, 63127.0, 63722.36277637164, 9166.67916622915, 9184.816303673755, 9243.907560924305, 9347.439153650803, 9357.344985850925, 10385.584576617153, 13516.86483135156, 13878.722425551634, 14412.927935360256, 14822.0, 12316.445759940645, 12327.397438332959, 12603.810942835682, 12724.554640587536, 12838.614841554769, 11547.4803633835, 11986.64040078799, 12120.0, 12186.57346992851, 12659.367031648564, 12222.816657749963, 12392.876071239172, 12420.503179872703, 12602.306873122066, 12660.493580256687, 8985.371024028516, 9011.684591039282, 9072.773180670536, 9123.042080581443, 9199.816003680024, 9287.76780580491, 11525.942370288094, 12213.633590992284, 12528.436220369922, 12758.68103297425], "value": 8827.237137576136, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1544.8686861616761, 1577.0380068158422, 1791.4177892185044, 1879.2295158984818, 1930.1989674285164, 8859.955700221499, 9588.76028099298, 9596.376235544685, 9609.711708648743, 9612.759681007978, 8065.354771618271, 8281.254687078163, 8288.254057134856, 8501.617427215775, 9421.576029078691, 2284.440312123532, 2338.894749736266, 2345.8944347504316, 2354.1289722802585, 2365.2431222008963, 2402.3393566769105, 2420.6006009008493, 2435.8051355891484, 2438.3538362333907, 2480.06997375984, 2394.2697477269426, 2409.2410890569413, 2411.168146989286, 2416.214730212679, 2417.625268083441, 2389.9880500597474, 2412.372783076395, 2416.4079800448912, 2418.419579300964, 2419.697537807774, 2295.7130358705163, 2359.988200059006, 2363.2319496163645, 2367.289813056076, 2367.3963139399516, 9444.244460443148, 9546.522673866279, 9589.42463452195, 9600.423974561549, 9605.711828645151, 5433.212184233264, 5522.420145884705, 5560.4161563036105, 5576.749046292926, 5579.944200558024, 3328.9334213315597, 3389.9491507627404, 3488.9127771805656, 3537.3455910656353, 3543.645635436436, 1222.7493363860387, 1233.808759642257, 1240.9503619855195, 1246.4079562207878, 1261.1109168036512, 13306.86693133066, 13319.933400333033, 13374.799378009338, 13404.0, 13447.193168409829, 4822.276658501253, 4844.128056949712, 4845.75771211438, 4929.556339929423, 5046.772895219724, 11114.166437517219, 11119.833202501968, 11136.276142050741, 11145.442727863574, 11166.330020198651, 5584.385717571064, 5596.384397716248, 5629.043062679354, 5631.521320687705, 5632.915506267409, 5091.719955402488, 5109.335786347751, 5173.1464308388695, 5184.948150518522, 5187.481251874858, 4266.0, 4271.380649805761, 4307.935380969288, 4317.639943417813, 4341.869743907688, 1316.1773891317926, 1387.5559820857432, 1389.1873254146235, 1395.0653062448184, 1407.7893012009663, 1024.7899180667946, 1041.063043261071, 1051.3901936876593, 1061.5488417422719, 1064.084886997182, 6409.134766806422, 6457.741690332334, 6464.25661048976, 6507.446867016357, 6640.302768209365, 469.76276980124817, 476.80212711724937, 485.99028019439413, 488.8435700575853, 505.27493047476986, 4569.314602809605, 4656.231721765938, 4705.106029854385, 4712.0, 4792.2092854678585, 11290.322580645188, 11792.29246245228, 11800.645980620595, 11808.46861891217, 11808.822867656992, 11495.080393568494, 11541.365224912543, 11547.711307217387, 11591.536338546364, 11627.709307267216, 8300.543470109202, 8608.397412181088, 8626.827463450696, 8810.471371717846, 8842.557872106368, 6596.472282217315, 6627.37039981201, 6680.198376194693, 6788.490863185182, 6918.377346038881, 10366.0, 10843.566257349617, 10895.346279223271, 10902.509387077442, 11020.944895275472, 9704.0, 10805.729856753644, 11049.060829829554, 11113.499892505019, 11193.216474846682, 8215.917840821516, 8286.212809783061, 8414.116517765791, 8613.48319100856, 8626.223639872442, 8634.309255259439, 8637.093105224112, 8730.65077396897, 8750.037495875322, 8816.382853200214, 8044.798880028046, 8140.918590814016, 8351.41540092212, 8371.372147089114, 8371.455855369382, 8109.472884262504, 8110.878336825071, 8156.306713929383, 8209.220124088202, 8284.130166332689, 7418.109826820816, 7497.10034795828, 7507.0, 7509.061367329084, 7565.394768418623, 5368.382635996754, 5390.919136213039, 5435.592330575302, 5480.6437581557075, 5661.575381846295, 5842.795502157404, 5884.823455296348, 5925.377835327228, 5953.523718102455, 5996.640201587918], "value": 4861.729373184657, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.49117814456457, 344.65534465534455, 346.64468919357654, 350.74570936071353, 351.68172803612737, 5647.0, 5667.603267771253, 5682.090865461527, 5701.429857014296, 5712.771489140435, 5520.806771762989, 5533.6956467394275, 5622.522085622727, 5628.915566266509, 5631.4087020862835, 3900.356441187215, 4141.875743727685, 4342.54403287655, 4345.587169218919, 4379.781010949456, 4323.589259020404, 4327.610515053644, 4339.587739164775, 4360.127974405116, 4360.825566977317, 4345.587169218919, 4371.0, 4392.450943632046, 4400.965773043332, 4406.669499787513, 4157.958420415787, 4310.3103503439615, 4346.978265108686, 4410.71330363527, 4415.381846541487, 3794.165283637595, 3816.1795214029116, 3819.8090095495113, 3821.789801560913, 3831.6551510363925, 5424.593155513352, 5444.373897001856, 5460.672359658433, 5472.671639701631, 5482.17767334899, 4885.14509960759, 4966.404031516205, 4977.925331120036, 5018.436909794051, 5020.874478138039, 5764.164196191528, 5765.942340576583, 5770.740316685759, 5780.393058728857, 5838.678872662002, 4203.537610862833, 4217.36739489076, 4248.4052232687445, 4263.8081286342185, 4286.849960251377, 5946.7026648667825, 6096.085587161917, 6114.571979961404, 6133.723982420757, 6145.231846019247, 4276.3157894737315, 4316.072044510445, 4327.632151267115, 4344.9348259776125, 4378.365137055109, 4395.25280702275, 4424.623906967944, 4435.35687325336, 4439.245328294198, 4457.821687132543, 4886.242632391985, 4941.604671626261, 4954.95945851368, 4982.078315511642, 5061.7469126544265, 5773.826785196451, 5793.971030144907, 5811.360750317462, 5847.152162936435, 5852.15143804146, 1498.3182651893362, 1514.121809350574, 1515.4014164404998, 1516.696660667871, 1517.7647464642998, 922.1884741427501, 946.6746554823302, 968.1480297338297, 969.5733877094056, 992.8907820139779, 5005.649604527721, 5025.592834006484, 5055.545000949932, 5097.057044446789, 5108.492994566612, 5265.4997775211295, 5275.839315350617, 5293.814716484904, 5318.069337865935, 5350.705711185922, 4751.976240118777, 5012.370979431699, 5049.899002019939, 5105.2342148678, 5124.897502050013, 2802.8458434786285, 2806.8596570171408, 2807.4525467534045, 2814.240155158096, 2821.7460428561526, 5398.6220964532895, 5405.729713514387, 5420.430854760272, 5449.727513624303, 5472.0, 5109.0, 5343.679379237258, 5377.300950876361, 5407.513323800877, 5858.209141765892, 6136.0, 6145.692715364214, 6214.502839772807, 6237.220347456659, 6241.937580624136, 5986.461218490357, 6025.0, 6026.939730602638, 6042.184305118929, 6098.481629061579, 5828.446297601721, 5857.970710146422, 5922.851928701817, 5940.940590594038, 5983.640981541122, 6256.343083976117, 6258.43674069336, 6263.6241825490615, 6265.780697675559, 6277.623342599459, 4069.8575549855614, 4216.30430978885, 4250.978745106255, 4261.296886013773, 4278.914421711611, 5990.820275391745, 6007.369226231358, 6024.759009639741, 6033.276006879202, 6045.274567051806, 5830.650160990354, 5835.44563266489, 5873.618214816023, 5940.316863560746, 5945.227120474311, 5565.77736890536, 5848.590598658053, 5901.642622196833, 5914.172015917689, 5920.763169473345, 5901.704914754159, 5910.911336329872, 5914.7338369774325, 5933.703314834327, 5934.851628709317, 5708.286464191976, 5917.171595976481, 5927.229460170151, 6006.219191505076, 6043.456088952015, 5778.537716982547, 5786.884262314814, 5802.013657678121, 5806.65160090396, 5817.854553636193], "value": 4385.9133650487065, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [860.4622111180512, 877.6182360673106, 881.6825942660641, 896.4486840593033, 903.4263242840798, 14296.928515357422, 15085.698286034287, 15149.242537873117, 15172.924135379322, 15177.772333415007, 14278.92860535696, 14303.0, 14314.713705725879, 14323.28383580822, 14375.856241437583, 736.2343163110359, 869.1048220333067, 874.0516539554575, 880.6741505642889, 884.2748945864394, 885.7962668586229, 887.7381172554112, 887.786931136529, 887.9422837515573, 888.1651247827022, 939.9483028433433, 940.7412961435591, 942.208544822349, 942.4062840410531, 942.6936245720168, 938.0431959401373, 938.4697645830119, 938.8357037518475, 939.1641439119155, 939.2861425316775, 690.623610132479, 690.7409721354492, 690.8445599740069, 692.5706062241443, 693.1578132568902, 14574.781378279335, 14582.197979111144, 14603.853961460356, 14606.19665918374, 14607.0, 13980.231087290194, 14290.642733931734, 15475.845241547553, 15866.682666346609, 16131.596710082342, 13274.601761947157, 13333.399997000159, 13996.16023038621, 14039.508617198451, 14446.566603001927, 4185.811638476245, 4205.831766729358, 4212.894677633053, 4287.464066991626, 4293.141371725636, 74868.0, 83306.58346708288, 83855.58072209661, 83885.99999999939, 83906.0, 11690.76618467643, 11757.588484403004, 11808.94095529517, 11952.64142075739, 12174.452149653287, 24685.62971555428, 25419.87290063538, 25596.48807023849, 25725.356866078124, 25766.09818656338, 9444.669436569824, 9569.61721531145, 9612.615495380107, 9628.855567166498, 9687.273454490942, 5063.02636100587, 5266.210068489757, 5303.416624171338, 5313.335833020872, 5523.28197334344, 4098.323776576832, 4243.0, 4244.172386384687, 4248.171606536695, 4249.490061192615, 723.7575412236854, 738.5753191914563, 743.7211045857804, 770.3567521119928, 781.8592653322343, 690.7064497588505, 724.3408498266657, 743.325167644077, 746.1531162131021, 797.2625321577517, 10528.578856845794, 10574.841377379345, 10608.0, 10631.308964917256, 10748.89251107479, 806.8749343851713, 809.6882700160363, 813.7355359508065, 829.7344849648175, 834.2575108153748, 5170.3278573785165, 5186.688798672017, 5202.76587553561, 5252.448492908266, 5298.549623282064, 81188.59405702933, 82114.17885821065, 82186.0, 112679.0, 129009.0, 72262.0, 87506.56246718725, 104277.47861260797, 119080.40459797645, 122506.0, 102124.48937755264, 105951.4702426483, 106870.0, 107689.0, 111352.0, 9747.268954828558, 9782.951085244527, 10150.847737283799, 10190.184785217312, 10257.640982565872, 60986.695066524386, 62145.0, 62451.375486246376, 62554.687226563576, 63536.0, 83603.0, 87401.56299218463, 91430.54284728534, 92469.0, 94309.52845235729, 13822.0, 13990.860091399363, 14320.641983950485, 14416.351264193347, 14527.0, 14171.503997360043, 14286.499972500913, 14311.284435777961, 14311.284435777961, 14326.713465730836, 12532.0, 12731.363431828186, 12971.740565188833, 12989.090763646454, 13195.80206296887, 12128.939355303168, 12243.510259589517, 12266.4480098394, 12956.740865182832, 13363.732725345633, 11664.125190610568, 11858.407079646156, 11877.881221187678, 12058.216215945935, 12574.37128143571, 10145.289829712145, 11056.281341712764, 11066.557337706401, 11079.446027698421, 11236.438178091226, 11000.0, 11202.775944481227, 11251.549938002388, 11274.210805243554, 11274.887251127711], "value": 8065.988760563176, "unit": "score"}], "name": "dromaeo_css", "value": 7533.271749033418, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 110.0, 119.0, 109.0, 109.0, 110.0, 109.0, 110.0, 110.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 120.0, 140.0, 171.0, 116.0, 142.0, 147.0, 118.0, 179.0, 116.0], "value": 139.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 160.0, 160.0, 160.0, 160.0, 161.0, 160.0, 159.0, 159.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 166.0, 76.0, 75.0, 75.0, 74.0, 166.0, 75.0], "value": 93.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 152.0, 82.0, 81.0, 83.0, 143.0, 81.0, 82.0, 146.0], "value": 101.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 51.0, 51.0, 49.0, 52.0, 53.0, 50.0, 52.0, 52.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 59.0, 60.0, 60.0, 61.0, 61.0, 60.0, 61.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 72.0, 72.0, 72.0, 78.0, 72.0, 73.0, 73.0, 71.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 127.0, 117.0, 133.0, 127.0, 120.0, 116.0, 125.0, 145.0, 118.0], "value": 124.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 154.0, 155.0, 150.0, 154.0, 151.0, 149.0, 151.0, 153.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 59.0, 60.0, 62.0, 59.0, 62.0, 60.0, 61.0, 61.0, 59.0], "value": 60.7, "unit": "score"}], "name": "kraken", "value": 1483.6, "alertThreshold": 2.0}]} 07:13:52 INFO - Return code: 0 07:13:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:13:52 INFO - # TBPL SUCCESS # 07:13:52 INFO - Running post-action listener: _resource_record_post_action 07:13:52 INFO - [mozharness: 2016-10-04 14:13:52.502369Z] Finished run-tests step (success) 07:13:52 INFO - Running post-run listener: _resource_record_post_run 07:13:52 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289337344; Read time: 20; Write time: 159176 07:13:52 INFO - TinderboxPrint: CPU usage
12.9% 07:13:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 07:13:52 INFO - TinderboxPrint: I/O write bytes / time
289,337,344 / 159,176 07:13:52 INFO - TinderboxPrint: CPU idle
8,248.5 (87.0%) 07:13:52 INFO - TinderboxPrint: CPU user
1,183.5 (12.5%) 07:13:52 INFO - TinderboxPrint: Swap in / out
0 / 0 07:13:52 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70111232; Read time: 0; Write time: 45004 07:13:52 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 219209728; Read time: 0; Write time: 114172 07:13:53 INFO - Running post-run listener: _upload_blobber_files 07:13:53 INFO - Blob upload gear active. 07:13:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:13:53 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 07:13:53 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'] 07:13:53 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 07:13:53 INFO - (blobuploader) - INFO - Open directory for files ... 07:13:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:13:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:13:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:13:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:13:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:13:54 INFO - (blobuploader) - INFO - Done attempting. 07:13:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:13:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:13:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:13:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:13:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:13:55 INFO - (blobuploader) - INFO - Done attempting. 07:13:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:13:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:13:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:13:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:13:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:13:58 INFO - (blobuploader) - INFO - Done attempting. 07:13:58 INFO - (blobuploader) - INFO - Iteration through files over. 07:13:58 INFO - Return code: 0 07:13:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:13:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:13:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/da290c34b00535da46d6dc169323209e1c05fda69e9e4a2074e77318609c65ccbafba02537d101786e1d167d313b021b03026923b0240e6b6700eeab827fd653", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33072f91d5627e199a9e0258ce523b076ffd234a45a769fa3c0a693e20700b5a67d69149f325e0752f55992c4d5f02ebcc0c3c8e5616e3ebc859749f989b0403", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b1158f8c99aba7ff08cfe7b068773181023931456ab3743579da0d58f4ce9314e8277387f0b77a4c1e7fdd027da00e6314ed9e6b1bf6eae0a933254114bb962"} 07:13:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:13:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:13:58 INFO - Contents: 07:13:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/da290c34b00535da46d6dc169323209e1c05fda69e9e4a2074e77318609c65ccbafba02537d101786e1d167d313b021b03026923b0240e6b6700eeab827fd653", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33072f91d5627e199a9e0258ce523b076ffd234a45a769fa3c0a693e20700b5a67d69149f325e0752f55992c4d5f02ebcc0c3c8e5616e3ebc859749f989b0403", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b1158f8c99aba7ff08cfe7b068773181023931456ab3743579da0d58f4ce9314e8277387f0b77a4c1e7fdd027da00e6314ed9e6b1bf6eae0a933254114bb962"} 07:13:58 INFO - Running post-run listener: copy_logs_to_upload_dir 07:13:58 INFO - Copying logs to upload dir... 07:13:58 INFO - mkdir: /builds/slave/test/build/upload/logs 07:13:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.726875 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-04 07:13:58.596295) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 07:13:58.599627) ========= 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=7de08804244eb36560b44971000001c3-1475588762.426609-300452998 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/da290c34b00535da46d6dc169323209e1c05fda69e9e4a2074e77318609c65ccbafba02537d101786e1d167d313b021b03026923b0240e6b6700eeab827fd653", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33072f91d5627e199a9e0258ce523b076ffd234a45a769fa3c0a693e20700b5a67d69149f325e0752f55992c4d5f02ebcc0c3c8e5616e3ebc859749f989b0403", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b1158f8c99aba7ff08cfe7b068773181023931456ab3743579da0d58f4ce9314e8277387f0b77a4c1e7fdd027da00e6314ed9e6b1bf6eae0a933254114bb962"} build_url:https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011429 build_url: 'https://queue.taskcluster.net/v1/task/McuwC0JhQGaMIwIYLNDBaQ/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/da290c34b00535da46d6dc169323209e1c05fda69e9e4a2074e77318609c65ccbafba02537d101786e1d167d313b021b03026923b0240e6b6700eeab827fd653", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33072f91d5627e199a9e0258ce523b076ffd234a45a769fa3c0a693e20700b5a67d69149f325e0752f55992c4d5f02ebcc0c3c8e5616e3ebc859749f989b0403", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b1158f8c99aba7ff08cfe7b068773181023931456ab3743579da0d58f4ce9314e8277387f0b77a4c1e7fdd027da00e6314ed9e6b1bf6eae0a933254114bb962"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 07:13:58.625072) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 07:13:58.625438) ========= 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=7de08804244eb36560b44971000001c3-1475588762.426609-300452998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004357 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 07:13:58.708110) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 07:13:58.708432) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 07:13:58.708739) ========= ========= Total master_lag: 0.16 =========