builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1470168458.57 results: success (0) buildid: 20160802064150 builduid: b28f423eef134474846e2dc77fa9c09a revision: a983a8015011296941c597f44cc678108db67510 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 13:07:38.571524) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 13:07:38.571966) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 13:07:38.572241) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 13:07:38.586890) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 13:07:38.587205) ========= 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=00204705a9daa57ed339a427000001b9-1470168456.341222-796203241 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-02 13:07:38-- 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% 229M=0s 2016-08-02 13:07:38 (229 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.077543 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 13:07:38.678575) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 13:07:38.678887) ========= 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=00204705a9daa57ed339a427000001b9-1470168456.341222-796203241 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014922 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 13:07:38.728243) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 13:07:38.728605) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a983a8015011296941c597f44cc678108db67510 --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 a983a8015011296941c597f44cc678108db67510 --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=00204705a9daa57ed339a427000001b9-1470168456.341222-796203241 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-02 13:07:38,718 truncating revision to first 12 chars 2016-08-02 13:07:38,718 Setting DEBUG logging. 2016-08-02 13:07:38,718 attempt 1/10 2016-08-02 13:07:38,718 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a983a8015011?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-02 13:07:39,032 unpacking tar archive at: mozilla-beta-a983a8015011/testing/mozharness/ program finished with exit code 0 elapsedTime=0.662364 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 13:07:39.404345) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 13:07:39.404653) ========= script_repo_revision: a983a8015011296941c597f44cc678108db67510 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 13:07:39.405063) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 13:07:39.405351) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 13:07:39.413842) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2016-08-02 13:07:39.414112) ========= /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=00204705a9daa57ed339a427000001b9-1470168456.341222-796203241 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:07:39 INFO - MultiFileLogger online at 20160802 13:07:39 in /builds/slave/test 13:07:39 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 13:07:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:07:39 INFO - {'append_to_log': False, 13:07:39 INFO - 'base_work_dir': '/builds/slave/test', 13:07:39 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:07:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:07:39 INFO - 'branch': 'Mozilla-Beta', 13:07:39 INFO - 'buildbot_json_path': 'buildprops.json', 13:07:39 INFO - 'config_files': ('talos/linux_config.py',), 13:07:39 INFO - 'default_actions': ('clobber', 13:07:39 INFO - 'read-buildbot-config', 13:07:39 INFO - 'download-and-extract', 13:07:39 INFO - 'populate-webroot', 13:07:39 INFO - 'create-virtualenv', 13:07:39 INFO - 'install', 13:07:39 INFO - 'run-tests'), 13:07:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:07:39 INFO - 'download_minidump_stackwalk': True, 13:07:39 INFO - 'download_symbols': 'ondemand', 13:07:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:07:39 INFO - 'tooltool.py': '/tools/tooltool.py', 13:07:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:07:39 INFO - '/tools/misc-python/virtualenv.py')}, 13:07:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:07:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:07:39 INFO - 'installer_path': 'installer.exe', 13:07:39 INFO - 'log_level': 'info', 13:07:39 INFO - 'log_name': 'talos', 13:07:39 INFO - 'log_to_console': True, 13:07:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:07:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:07:39 INFO - 'opt_config_files': (), 13:07:39 INFO - 'pip_index': False, 13:07:39 INFO - 'sps_profile': False, 13:07:39 INFO - 'sps_profile_interval': 0, 13:07:39 INFO - 'suite': 'dromaeojs', 13:07:39 INFO - 'system_bits': '32', 13:07:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:07:39 INFO - 'title': 'talos-linux64-ix-016', 13:07:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:07:39 INFO - 'use_talos_json': True, 13:07:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:07:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:07:39 INFO - 'work_dir': 'build'} 13:07:39 INFO - ##### 13:07:39 INFO - ##### Running clobber step. 13:07:39 INFO - ##### 13:07:39 INFO - Running pre-action listener: _resource_record_pre_action 13:07:39 INFO - Running main action method: clobber 13:07:39 INFO - rmtree: /builds/slave/test/build 13:07:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:07:39 INFO - Running post-action listener: _resource_record_post_action 13:07:39 INFO - ##### 13:07:39 INFO - ##### Finished clobber step (success) 13:07:39 INFO - ##### 13:07:39 INFO - ##### 13:07:39 INFO - ##### Running read-buildbot-config step. 13:07:39 INFO - ##### 13:07:39 INFO - Running pre-action listener: _resource_record_pre_action 13:07:39 INFO - Running main action method: read_buildbot_config 13:07:39 INFO - Using buildbot properties: 13:07:39 INFO - { 13:07:39 INFO - "project": "", 13:07:39 INFO - "product": "firefox", 13:07:39 INFO - "script_repo_revision": "production", 13:07:39 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 13:07:39 INFO - "repository": "", 13:07:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 13:07:39 INFO - "buildid": "20160802064150", 13:07:39 INFO - "pgo_build": "True", 13:07:39 INFO - "basedir": "/builds/slave/test", 13:07:39 INFO - "buildnumber": 319, 13:07:39 INFO - "slavename": "talos-linux64-ix-016", 13:07:39 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 13:07:39 INFO - "platform": "ubuntu64_hw", 13:07:39 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 13:07:39 INFO - "branch": "mozilla-beta", 13:07:39 INFO - "repo_path": "releases/mozilla-beta", 13:07:39 INFO - "revision": "a983a8015011296941c597f44cc678108db67510", 13:07:39 INFO - "stage_platform": "linux64-pgo", 13:07:39 INFO - "builduid": "b28f423eef134474846e2dc77fa9c09a", 13:07:39 INFO - "slavebuilddir": "test" 13:07:39 INFO - } 13:07:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2. 13:07:39 INFO - Running post-action listener: _resource_record_post_action 13:07:39 INFO - ##### 13:07:39 INFO - ##### Finished read-buildbot-config step (success) 13:07:39 INFO - ##### 13:07:39 INFO - ##### 13:07:39 INFO - ##### Running download-and-extract step. 13:07:39 INFO - ##### 13:07:39 INFO - Running pre-action listener: _resource_record_pre_action 13:07:39 INFO - Running main action method: download_and_extract 13:07:39 INFO - mkdir: /builds/slave/test/build/tests 13:07: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')]} 13:07:39 INFO - https://queue.taskcluster.net/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:07:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 13:07:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 13:07:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 13:07:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 13:07:40 INFO - Downloaded 1327 bytes. 13:07:40 INFO - Reading from file /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 13:07:40 INFO - Using the following test package requirements: 13:07:40 INFO - {u'common': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 13:07:40 INFO - u'cppunittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 13:07:40 INFO - u'firefox-49.0.en-US.linux-x86_64.cppunittest.tests.zip'], 13:07:40 INFO - u'gtest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 13:07:40 INFO - u'firefox-49.0.en-US.linux-x86_64.gtest.tests.zip'], 13:07:40 INFO - u'jittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 13:07:40 INFO - u'jsshell-linux-x86_64.zip'], 13:07:40 INFO - u'mochitest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 13:07:40 INFO - u'firefox-49.0.en-US.linux-x86_64.mochitest.tests.zip'], 13:07:40 INFO - u'mozbase': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 13:07:40 INFO - u'reftest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 13:07:40 INFO - u'firefox-49.0.en-US.linux-x86_64.reftest.tests.zip'], 13:07:40 INFO - u'talos': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 13:07:40 INFO - u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'], 13:07:40 INFO - u'web-platform': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 13:07:40 INFO - u'firefox-49.0.en-US.linux-x86_64.web-platform.tests.zip'], 13:07:40 INFO - u'xpcshell': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 13:07:40 INFO - u'firefox-49.0.en-US.linux-x86_64.xpcshell.tests.zip']} 13:07:40 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:07:40 INFO - https://queue.taskcluster.net/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:07:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 13:07:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 13:07:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 13:07:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:07:41 INFO - Downloaded 21967583 bytes. 13:07:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:07:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 13:07:42 INFO - Return code: 0 13:07:42 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 13:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:07:42 INFO - https://queue.taskcluster.net/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:07:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 13:07:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 13:07:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 13:07:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:07:43 INFO - Downloaded 21967583 bytes. 13:07:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:07:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 13:07:45 INFO - Return code: 0 13:07: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')]} 13:07:45 INFO - https://queue.taskcluster.net/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:07:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 13:07:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 13:07:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 13:07:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 13:07:45 INFO - Downloaded 10833369 bytes. 13:07:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:07:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 13:07:46 INFO - Return code: 0 13:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:07:46 INFO - https://queue.taskcluster.net/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:07:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 13:07:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 13:07:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 13:07:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 13:07:47 INFO - Downloaded 55274872 bytes. 13:07:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 13:07:47 INFO - mkdir: /builds/slave/test/properties 13:07:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:07:47 INFO - Writing to file /builds/slave/test/properties/build_url 13:07:47 INFO - Contents: 13:07:47 INFO - build_url:https://queue.taskcluster.net/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 13:07:48 INFO - Running post-action listener: _resource_record_post_action 13:07:48 INFO - Running post-action listener: set_extra_try_arguments 13:07:48 INFO - ##### 13:07:48 INFO - ##### Finished download-and-extract step (success) 13:07:48 INFO - ##### 13:07:48 INFO - ##### 13:07:48 INFO - ##### Running populate-webroot step. 13:07:48 INFO - ##### 13:07:48 INFO - Running pre-action listener: _resource_record_pre_action 13:07:48 INFO - Running main action method: populate_webroot 13:07:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:07:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:07:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:07:48 INFO - '--tppagecycles', 13:07:48 INFO - '7'], 13:07:48 INFO - 'tests': ['tsvgm']}}, 13:07:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:07:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:07:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:07:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:07:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:07:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:07:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:48 INFO - 'talos_options': ['--disable-e10s'], 13:07:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:07:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:07:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:48 INFO - 'talos_options': ['--disable-e10s'], 13:07:48 INFO - 'tests': ['damp', 'tps']}, 13:07:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:48 INFO - 'tests': ['damp', 'tps']}, 13:07:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:07:48 INFO - 'tests': ['dromaeo_dom']}, 13:07:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:07:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:07:48 INFO - 'tests': ['a11yr', 13:07:48 INFO - 'ts_paint', 13:07:48 INFO - 'tpaint', 13:07:48 INFO - 'sessionrestore', 13:07:48 INFO - 'sessionrestore_no_auto_restore', 13:07:48 INFO - 'tabpaint']}, 13:07:48 INFO - 'other-e10s': {'tests': ['a11yr', 13:07:48 INFO - 'ts_paint', 13:07:48 INFO - 'tpaint', 13:07:48 INFO - 'sessionrestore', 13:07:48 INFO - 'sessionrestore_no_auto_restore', 13:07:48 INFO - 'tabpaint']}, 13:07:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:07:48 INFO - 'ts_paint', 13:07:48 INFO - 'tpaint', 13:07:48 INFO - 'sessionrestore', 13:07:48 INFO - 'sessionrestore_no_auto_restore', 13:07:48 INFO - 'tabpaint']}, 13:07:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:07:48 INFO - 'ts_paint', 13:07:48 INFO - 'tpaint', 13:07:48 INFO - 'sessionrestore', 13:07:48 INFO - 'sessionrestore_no_auto_restore', 13:07:48 INFO - 'tabpaint']}, 13:07:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:07:48 INFO - 'tests': ['a11yr', 13:07:48 INFO - 'ts_paint', 13:07:48 INFO - 'tpaint', 13:07:48 INFO - 'sessionrestore', 13:07:48 INFO - 'sessionrestore_no_auto_restore', 13:07:48 INFO - 'tabpaint']}, 13:07:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:07:48 INFO - 'tests': ['a11yr', 13:07:48 INFO - 'ts_paint', 13:07:48 INFO - 'tpaint', 13:07:48 INFO - 'sessionrestore', 13:07:48 INFO - 'sessionrestore_no_auto_restore', 13:07:48 INFO - 'tabpaint']}, 13:07:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:07:48 INFO - 'tests': ['tsvgx', 13:07:48 INFO - 'tsvgr_opacity', 13:07:48 INFO - 'tart', 13:07:48 INFO - 'tscrollx', 13:07:48 INFO - 'cart']}, 13:07:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:07:48 INFO - 'tsvgr_opacity', 13:07:48 INFO - 'tart', 13:07:48 INFO - 'tscrollx', 13:07:48 INFO - 'cart']}, 13:07:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:48 INFO - 'talos_options': ['--disable-e10s'], 13:07:48 INFO - 'tests': ['tp5o']}, 13:07:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:48 INFO - 'tests': ['tp5o']}, 13:07:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:48 INFO - 'talos_options': ['--disable-e10s', 13:07:48 INFO - '--xperf_path', 13:07:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:07:48 INFO - 'tests': ['tp5n']}, 13:07:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:48 INFO - 'talos_options': ['--xperf_path', 13:07:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:07:48 INFO - 'tests': ['tp5n']}}, 13:07:48 INFO - 'talos.zip': {'path': '', 13:07:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:07:48 INFO - Running post-action listener: _resource_record_post_action 13:07:48 INFO - ##### 13:07:48 INFO - ##### Finished populate-webroot step (success) 13:07:48 INFO - ##### 13:07:48 INFO - ##### 13:07:48 INFO - ##### Running create-virtualenv step. 13:07:48 INFO - ##### 13:07:48 INFO - Running pre-action listener: _resource_record_pre_action 13:07:48 INFO - Running main action method: create_virtualenv 13:07:48 INFO - Creating virtualenv /builds/slave/test/build/venv 13:07:48 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 13:07:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:07:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:07:48 INFO - Using real prefix '/usr' 13:07:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:07:49 INFO - Installing distribute.............................................................................................................................................................................................done. 13:07:51 INFO - Installing pip.................done. 13:07:51 INFO - Return code: 0 13:07:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d4c78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x120c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13661c0>, '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 0x13670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1367550>, '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': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', '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 13:07:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:07:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:07:51 INFO - Using env: {'DISPLAY': ':0', 13:07:51 INFO - 'HOME': '/home/cltbld', 13:07:51 INFO - 'LANG': 'en_US.UTF-8', 13:07:51 INFO - 'LANGUAGE': 'en_US:en', 13:07:51 INFO - 'LOGNAME': 'cltbld', 13:07:51 INFO - 'MAIL': '/var/mail/cltbld', 13:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:51 INFO - 'MOZ_NO_REMOTE': '1', 13:07:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:51 INFO - 'NO_EM_RESTART': '1', 13:07:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:51 INFO - 'PWD': '/builds/slave/test', 13:07:51 INFO - 'SHELL': '/bin/bash', 13:07:51 INFO - 'SHLVL': '1', 13:07:51 INFO - 'TERM': 'linux', 13:07:51 INFO - 'TMOUT': '86400', 13:07:51 INFO - 'USER': 'cltbld', 13:07:51 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', 13:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:51 INFO - '_': '/tools/buildbot/bin/python'} 13:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:51 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 13:07:51 INFO - Cleaning up... 13:07:51 INFO - Return code: 0 13:07:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ff3d4c78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x120c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13661c0>, '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 0x13670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1367550>, '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': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', '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 13:07:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:07:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:07:51 INFO - Using env: {'DISPLAY': ':0', 13:07:51 INFO - 'HOME': '/home/cltbld', 13:07:51 INFO - 'LANG': 'en_US.UTF-8', 13:07:51 INFO - 'LANGUAGE': 'en_US:en', 13:07:51 INFO - 'LOGNAME': 'cltbld', 13:07:51 INFO - 'MAIL': '/var/mail/cltbld', 13:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:51 INFO - 'MOZ_NO_REMOTE': '1', 13:07:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:51 INFO - 'NO_EM_RESTART': '1', 13:07:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:51 INFO - 'PWD': '/builds/slave/test', 13:07:51 INFO - 'SHELL': '/bin/bash', 13:07:51 INFO - 'SHLVL': '1', 13:07:51 INFO - 'TERM': 'linux', 13:07:51 INFO - 'TMOUT': '86400', 13:07:51 INFO - 'USER': 'cltbld', 13:07:51 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', 13:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:51 INFO - '_': '/tools/buildbot/bin/python'} 13:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:51 INFO - Downloading/unpacking psutil>=3.1.1 13:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:07:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:07:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:07:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:07:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:07:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:07:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:07:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:07:53 INFO - Installing collected packages: psutil 13:07:53 INFO - Running setup.py install for psutil 13:07:53 INFO - building 'psutil._psutil_linux' extension 13:07:53 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 13:07:54 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 13:07:54 INFO - building 'psutil._psutil_posix' extension 13:07:54 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 13:07:54 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 13:07:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:07:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:07:54 INFO - Successfully installed psutil 13:07:54 INFO - Cleaning up... 13:07:54 INFO - Return code: 0 13:07:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:07:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:54 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 0x7ff3d4c78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x120c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13661c0>, '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 0x13670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1367550>, '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': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', '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 13:07:54 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 13:07:54 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 13:07:54 INFO - Using env: {'DISPLAY': ':0', 13:07:54 INFO - 'HOME': '/home/cltbld', 13:07:54 INFO - 'LANG': 'en_US.UTF-8', 13:07:54 INFO - 'LANGUAGE': 'en_US:en', 13:07:54 INFO - 'LOGNAME': 'cltbld', 13:07:54 INFO - 'MAIL': '/var/mail/cltbld', 13:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:54 INFO - 'MOZ_NO_REMOTE': '1', 13:07:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:54 INFO - 'NO_EM_RESTART': '1', 13:07:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:54 INFO - 'PWD': '/builds/slave/test', 13:07:54 INFO - 'SHELL': '/bin/bash', 13:07:54 INFO - 'SHLVL': '1', 13:07:54 INFO - 'TERM': 'linux', 13:07:54 INFO - 'TMOUT': '86400', 13:07:54 INFO - 'USER': 'cltbld', 13:07:54 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', 13:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:54 INFO - '_': '/tools/buildbot/bin/python'} 13:07:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:07:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:07:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:07:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:07:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:07:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:07:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:07:56 INFO - Installing collected packages: mozsystemmonitor 13:07:56 INFO - Running setup.py install for mozsystemmonitor 13:07:56 INFO - Successfully installed mozsystemmonitor 13:07:56 INFO - Cleaning up... 13:07:56 INFO - Return code: 0 13:07:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:07: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')]} 13:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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')]} 13:07:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:56 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 0x7ff3d4c78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x120c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13661c0>, '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 0x13670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1367550>, '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': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', '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 13:07:56 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 13:07:56 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 13:07:56 INFO - Using env: {'DISPLAY': ':0', 13:07:56 INFO - 'HOME': '/home/cltbld', 13:07:56 INFO - 'LANG': 'en_US.UTF-8', 13:07:56 INFO - 'LANGUAGE': 'en_US:en', 13:07:56 INFO - 'LOGNAME': 'cltbld', 13:07:56 INFO - 'MAIL': '/var/mail/cltbld', 13:07:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:56 INFO - 'MOZ_NO_REMOTE': '1', 13:07:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:56 INFO - 'NO_EM_RESTART': '1', 13:07:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:56 INFO - 'PWD': '/builds/slave/test', 13:07:56 INFO - 'SHELL': '/bin/bash', 13:07:56 INFO - 'SHLVL': '1', 13:07:56 INFO - 'TERM': 'linux', 13:07:56 INFO - 'TMOUT': '86400', 13:07:56 INFO - 'USER': 'cltbld', 13:07:56 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', 13:07:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:56 INFO - '_': '/tools/buildbot/bin/python'} 13:07:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:56 INFO - Downloading/unpacking jsonschema==2.5.1 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:07:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:07:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:07:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:07:58 INFO - Downloading functools32-3.2.3-2.tar.gz 13:07:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:07:59 INFO - warning: no files found matching '*.txt' 13:07:59 INFO - no previously-included directories found matching 'build' 13:07:59 INFO - no previously-included directories found matching 'dist' 13:07:59 INFO - no previously-included directories found matching '.git*' 13:07:59 INFO - Installing collected packages: jsonschema, functools32 13:07:59 INFO - Running setup.py install for functools32 13:07:59 INFO - warning: no files found matching '*.txt' 13:07:59 INFO - no previously-included directories found matching 'build' 13:07:59 INFO - no previously-included directories found matching 'dist' 13:07:59 INFO - no previously-included directories found matching '.git*' 13:07:59 INFO - Successfully installed jsonschema functools32 13:07:59 INFO - Cleaning up... 13:07:59 INFO - Return code: 0 13:07:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:07:59 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')]} 13:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:59 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')]} 13:07:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:59 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 0x7ff3d4c78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x120c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13661c0>, '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 0x13670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1367550>, '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': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', '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 13:07:59 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 13:07:59 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 13:07:59 INFO - Using env: {'DISPLAY': ':0', 13:07:59 INFO - 'HOME': '/home/cltbld', 13:07:59 INFO - 'LANG': 'en_US.UTF-8', 13:07:59 INFO - 'LANGUAGE': 'en_US:en', 13:07:59 INFO - 'LOGNAME': 'cltbld', 13:07:59 INFO - 'MAIL': '/var/mail/cltbld', 13:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:59 INFO - 'MOZ_NO_REMOTE': '1', 13:07:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:59 INFO - 'NO_EM_RESTART': '1', 13:07:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:59 INFO - 'PWD': '/builds/slave/test', 13:07:59 INFO - 'SHELL': '/bin/bash', 13:07:59 INFO - 'SHLVL': '1', 13:07:59 INFO - 'TERM': 'linux', 13:07:59 INFO - 'TMOUT': '86400', 13:07:59 INFO - 'USER': 'cltbld', 13:07:59 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', 13:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:59 INFO - '_': '/tools/buildbot/bin/python'} 13:07:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:59 INFO - Downloading/unpacking blobuploader==1.2.4 13:07:59 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 13:07:59 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 13:07:59 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 13:07:59 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 13:07:59 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 13:07:59 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 13:08:01 INFO - Downloading blobuploader-1.2.4.tar.gz 13:08:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:08:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:08: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 13:08: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 13:08: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 13:08: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 13:08: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 13:08: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 13:08:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:08:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:08: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 13:08: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 13:08: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 13:08: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 13:08: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 13:08: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 13:08:02 INFO - Downloading docopt-0.6.1.tar.gz 13:08:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:08:02 INFO - Installing collected packages: blobuploader, requests, docopt 13:08:02 INFO - Running setup.py install for blobuploader 13:08:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:08:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:08:02 INFO - Running setup.py install for requests 13:08:02 INFO - Running setup.py install for docopt 13:08:02 INFO - Successfully installed blobuploader requests docopt 13:08:02 INFO - Cleaning up... 13:08:02 INFO - Return code: 0 13:08:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:08:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:02 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 0x7ff3d4c78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x120c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13661c0>, '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 0x13670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1367550>, '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': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', '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 13:08:02 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 13:08:02 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 13:08:02 INFO - Using env: {'DISPLAY': ':0', 13:08:02 INFO - 'HOME': '/home/cltbld', 13:08:02 INFO - 'LANG': 'en_US.UTF-8', 13:08:02 INFO - 'LANGUAGE': 'en_US:en', 13:08:02 INFO - 'LOGNAME': 'cltbld', 13:08:02 INFO - 'MAIL': '/var/mail/cltbld', 13:08:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:02 INFO - 'MOZ_NO_REMOTE': '1', 13:08:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:08:02 INFO - 'NO_EM_RESTART': '1', 13:08:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:08:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:02 INFO - 'PWD': '/builds/slave/test', 13:08:02 INFO - 'SHELL': '/bin/bash', 13:08:02 INFO - 'SHLVL': '1', 13:08:02 INFO - 'TERM': 'linux', 13:08:02 INFO - 'TMOUT': '86400', 13:08:02 INFO - 'USER': 'cltbld', 13:08:02 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', 13:08:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:02 INFO - '_': '/tools/buildbot/bin/python'} 13:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:08:02 INFO - Running setup.py (path:/tmp/pip-aSeaSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:08:03 INFO - Running setup.py (path:/tmp/pip-vVRDmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:08:03 INFO - Running setup.py (path:/tmp/pip-y8aNra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:08:03 INFO - Running setup.py (path:/tmp/pip-AcgmfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:08:03 INFO - Running setup.py (path:/tmp/pip-Iu0KcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:08:03 INFO - Running setup.py (path:/tmp/pip-PO1gAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:08:03 INFO - Running setup.py (path:/tmp/pip-70X_rl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:08:03 INFO - Running setup.py (path:/tmp/pip-EAedep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:08:03 INFO - Running setup.py (path:/tmp/pip-i4ZRPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:08:03 INFO - Running setup.py (path:/tmp/pip-izFtLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:08:03 INFO - Running setup.py (path:/tmp/pip-bjjmqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:08:03 INFO - Running setup.py (path:/tmp/pip-QgGbWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:08:03 INFO - Running setup.py (path:/tmp/pip-rZ_yMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:08:03 INFO - Running setup.py (path:/tmp/pip-rs9i4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:08:03 INFO - Running setup.py (path:/tmp/pip-li5qMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:08:04 INFO - Running setup.py (path:/tmp/pip-tplSAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:08:04 INFO - Running setup.py (path:/tmp/pip-nTK1E1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:08:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:08:04 INFO - Running setup.py install for manifestparser 13:08:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:08:04 INFO - Running setup.py install for mozcrash 13:08:04 INFO - Running setup.py install for mozdebug 13:08:04 INFO - Running setup.py install for mozdevice 13:08:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:08:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:08:04 INFO - Running setup.py install for mozfile 13:08:04 INFO - Running setup.py install for mozhttpd 13:08:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:08:04 INFO - Running setup.py install for mozinfo 13:08:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:08:04 INFO - Running setup.py install for mozInstall 13:08:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:08:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:08:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:08:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:08:04 INFO - Running setup.py install for mozleak 13:08:05 INFO - Running setup.py install for mozlog 13:08:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:08:05 INFO - Running setup.py install for moznetwork 13:08:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:08:05 INFO - Running setup.py install for mozprocess 13:08:05 INFO - Running setup.py install for mozprofile 13:08:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:08:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:08:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:08:05 INFO - Running setup.py install for mozrunner 13:08:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:08:05 INFO - Running setup.py install for mozscreenshot 13:08:05 INFO - Running setup.py install for moztest 13:08:05 INFO - Running setup.py install for mozversion 13:08:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:08:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:08:05 INFO - Cleaning up... 13:08:05 INFO - Return code: 0 13:08:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:08:05 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')]} 13:08:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:05 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')]} 13:08:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:05 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 0x7ff3d4c78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x120c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13661c0>, '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 0x13670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1367550>, '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': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', '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 13:08:05 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 13:08:05 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 13:08:05 INFO - Using env: {'DISPLAY': ':0', 13:08:05 INFO - 'HOME': '/home/cltbld', 13:08:05 INFO - 'LANG': 'en_US.UTF-8', 13:08:05 INFO - 'LANGUAGE': 'en_US:en', 13:08:05 INFO - 'LOGNAME': 'cltbld', 13:08:05 INFO - 'MAIL': '/var/mail/cltbld', 13:08:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:05 INFO - 'MOZ_NO_REMOTE': '1', 13:08:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:08:05 INFO - 'NO_EM_RESTART': '1', 13:08:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:08:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:05 INFO - 'PWD': '/builds/slave/test', 13:08:05 INFO - 'SHELL': '/bin/bash', 13:08:05 INFO - 'SHLVL': '1', 13:08:05 INFO - 'TERM': 'linux', 13:08:05 INFO - 'TMOUT': '86400', 13:08:05 INFO - 'USER': 'cltbld', 13:08:05 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', 13:08:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:05 INFO - '_': '/tools/buildbot/bin/python'} 13:08:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:08:06 INFO - Running setup.py (path:/tmp/pip-UDcwxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:08:06 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)) 13:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:08:06 INFO - Running setup.py (path:/tmp/pip-CSM5g0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:08:06 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)) 13:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:08:06 INFO - Running setup.py (path:/tmp/pip-nwG41_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:08:06 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)) 13:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:08:06 INFO - Running setup.py (path:/tmp/pip-wO7D76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:08:06 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)) 13:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:08:06 INFO - Running setup.py (path:/tmp/pip-_4wope-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:08:06 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)) 13:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:08:06 INFO - Running setup.py (path:/tmp/pip-DvIMmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:08:06 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)) 13:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:08:06 INFO - Running setup.py (path:/tmp/pip-bs20ck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:08:06 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)) 13:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:08:06 INFO - Running setup.py (path:/tmp/pip-hMklPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:08:06 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)) 13:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:08:06 INFO - Running setup.py (path:/tmp/pip-opgV_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:08:06 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)) 13:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:08:06 INFO - Running setup.py (path:/tmp/pip-CXG3uF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 13:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:08:06 INFO - Running setup.py (path:/tmp/pip-i4fqeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:08:06 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)) 13:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:08:06 INFO - Running setup.py (path:/tmp/pip-0KaFPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:08:07 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)) 13:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:08:07 INFO - Running setup.py (path:/tmp/pip-UOmmhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:08:07 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)) 13:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:08:07 INFO - Running setup.py (path:/tmp/pip-YesphV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:08:07 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)) 13:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:08:07 INFO - Running setup.py (path:/tmp/pip-Ny3FFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:08:07 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)) 13:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:08:07 INFO - Running setup.py (path:/tmp/pip-m8cxNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:08:07 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)) 13:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:08:07 INFO - Running setup.py (path:/tmp/pip-LkpVYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:08:07 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)) 13:08:07 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)) 13:08:07 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)) 13:08:07 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)) 13:08:07 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)) 13:08:07 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)) 13:08:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:08:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:08:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:08:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:08:09 INFO - Downloading blessings-1.6.tar.gz 13:08:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:08:09 INFO - Installing collected packages: blessings 13:08:09 INFO - Running setup.py install for blessings 13:08:09 INFO - Successfully installed blessings 13:08:09 INFO - Cleaning up... 13:08:09 INFO - Return code: 0 13:08:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:08:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:08:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:08:09 INFO - Reading from file tmpfile_stdout 13:08:09 INFO - Current package versions: 13:08:09 INFO - argparse == 1.2.1 13:08:09 INFO - blessings == 1.6 13:08:09 INFO - blobuploader == 1.2.4 13:08:09 INFO - docopt == 0.6.1 13:08:09 INFO - functools32 == 3.2.3-2 13:08:09 INFO - jsonschema == 2.5.1 13:08:09 INFO - manifestparser == 1.1 13:08:09 INFO - mozInstall == 1.12 13:08:09 INFO - mozcrash == 0.17 13:08:09 INFO - mozdebug == 0.1 13:08:09 INFO - mozdevice == 0.48 13:08:09 INFO - mozfile == 1.2 13:08:09 INFO - mozhttpd == 0.7 13:08:09 INFO - mozinfo == 0.9 13:08:09 INFO - mozleak == 0.1 13:08:09 INFO - mozlog == 3.2 13:08:09 INFO - moznetwork == 0.27 13:08:09 INFO - mozprocess == 0.23 13:08:09 INFO - mozprofile == 0.28 13:08:09 INFO - mozrunner == 6.12 13:08:09 INFO - mozscreenshot == 0.1 13:08:09 INFO - mozsystemmonitor == 0.3 13:08:09 INFO - moztest == 0.7 13:08:09 INFO - mozversion == 1.4 13:08:09 INFO - psutil == 3.1.1 13:08:09 INFO - requests == 1.2.3 13:08:09 INFO - wsgiref == 0.1.2 13:08:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:08:09 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')]} 13:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:09 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')]} 13:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:09 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 0x7ff3d4c78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x120c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13661c0>, '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 0x13670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1367550>, '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': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', '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 13:08:09 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 13:08:09 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 13:08:09 INFO - Using env: {'DISPLAY': ':0', 13:08:09 INFO - 'HOME': '/home/cltbld', 13:08:09 INFO - 'LANG': 'en_US.UTF-8', 13:08:09 INFO - 'LANGUAGE': 'en_US:en', 13:08:09 INFO - 'LOGNAME': 'cltbld', 13:08:09 INFO - 'MAIL': '/var/mail/cltbld', 13:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:09 INFO - 'MOZ_NO_REMOTE': '1', 13:08:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:08:09 INFO - 'NO_EM_RESTART': '1', 13:08:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:09 INFO - 'PWD': '/builds/slave/test', 13:08:09 INFO - 'SHELL': '/bin/bash', 13:08:09 INFO - 'SHLVL': '1', 13:08:09 INFO - 'TERM': 'linux', 13:08:09 INFO - 'TMOUT': '86400', 13:08:09 INFO - 'USER': 'cltbld', 13:08:09 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', 13:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:09 INFO - '_': '/tools/buildbot/bin/python'} 13:08:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:09 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)) 13:08:09 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)) 13:08:09 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)) 13:08:09 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)) 13:08:09 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)) 13:08:09 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)) 13:08:09 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)) 13:08:09 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)) 13:08:09 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)) 13:08:09 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)) 13:08:09 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)) 13:08:09 INFO - Cleaning up... 13:08:09 INFO - Return code: 0 13:08:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:08:09 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')]} 13:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:09 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')]} 13:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:09 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 0x7ff3d4c78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x120c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13661c0>, '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 0x13670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1367550>, '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': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', '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 13:08:09 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 13:08:09 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 13:08:09 INFO - Using env: {'DISPLAY': ':0', 13:08:09 INFO - 'HOME': '/home/cltbld', 13:08:09 INFO - 'LANG': 'en_US.UTF-8', 13:08:09 INFO - 'LANGUAGE': 'en_US:en', 13:08:09 INFO - 'LOGNAME': 'cltbld', 13:08:09 INFO - 'MAIL': '/var/mail/cltbld', 13:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:09 INFO - 'MOZ_NO_REMOTE': '1', 13:08:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:08:09 INFO - 'NO_EM_RESTART': '1', 13:08:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:09 INFO - 'PWD': '/builds/slave/test', 13:08:09 INFO - 'SHELL': '/bin/bash', 13:08:09 INFO - 'SHLVL': '1', 13:08:09 INFO - 'TERM': 'linux', 13:08:09 INFO - 'TMOUT': '86400', 13:08:09 INFO - 'USER': 'cltbld', 13:08:09 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', 13:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:09 INFO - '_': '/tools/buildbot/bin/python'} 13:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:08:10 INFO - Cleaning up... 13:08:10 INFO - Return code: 0 13:08:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:08: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')]} 13:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08: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')]} 13:08:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d4c78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x120c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13661c0>, '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 0x13670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1367550>, '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': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', '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 13:08:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 13:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:08:10 INFO - Using env: {'DISPLAY': ':0', 13:08:10 INFO - 'HOME': '/home/cltbld', 13:08:10 INFO - 'LANG': 'en_US.UTF-8', 13:08:10 INFO - 'LANGUAGE': 'en_US:en', 13:08:10 INFO - 'LOGNAME': 'cltbld', 13:08:10 INFO - 'MAIL': '/var/mail/cltbld', 13:08:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:10 INFO - 'MOZ_NO_REMOTE': '1', 13:08:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:08:10 INFO - 'NO_EM_RESTART': '1', 13:08:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:08:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:10 INFO - 'PWD': '/builds/slave/test', 13:08:10 INFO - 'SHELL': '/bin/bash', 13:08:10 INFO - 'SHLVL': '1', 13:08:10 INFO - 'TERM': 'linux', 13:08:10 INFO - 'TMOUT': '86400', 13:08:10 INFO - 'USER': 'cltbld', 13:08:10 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', 13:08:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:10 INFO - '_': '/tools/buildbot/bin/python'} 13:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:10 INFO - Downloading/unpacking flake8 13:08:10 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 13:08:10 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 13:08:10 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 13:08:10 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 13:08:10 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 13:08:10 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 13:08:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:08:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:08:12 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 13:08:12 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 13:08:12 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 13:08:12 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 13:08:12 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 13:08:12 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 13:08:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:08:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:08:12 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 13:08:12 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 13:08:12 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 13:08:12 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 13:08:12 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 13:08:12 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 13:08:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:08:12 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 13:08:12 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 13:08:12 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 13:08:12 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 13:08:12 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 13:08:12 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 13:08:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:08:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:08:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:08:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:08:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:08:13 INFO - no previously-included directories found matching 'docs/_build' 13:08:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:08:13 INFO - Running setup.py install for pep8 13:08:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:08:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:08:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:08:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:08:13 INFO - no previously-included directories found matching 'docs/_build' 13:08:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:08:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:08:13 INFO - Cleaning up... 13:08:13 INFO - Return code: 0 13:08:13 INFO - Running post-action listener: _resource_record_post_action 13:08:13 INFO - Running post-action listener: _start_resource_monitoring 13:08:13 INFO - Starting resource monitoring. 13:08:13 INFO - ##### 13:08:13 INFO - ##### Finished create-virtualenv step (success) 13:08:13 INFO - ##### 13:08:13 INFO - ##### 13:08:13 INFO - ##### Running install step. 13:08:13 INFO - ##### 13:08:13 INFO - Running pre-action listener: _resource_record_pre_action 13:08:13 INFO - Running main action method: install 13:08:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:08:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:08:13 INFO - Reading from file tmpfile_stdout 13:08:13 INFO - Detecting whether we're running mozinstall >=1.0... 13:08:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:08:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:08:13 INFO - Reading from file tmpfile_stdout 13:08:13 INFO - Output received: 13:08:13 INFO - Usage: mozinstall [options] installer 13:08:13 INFO - Options: 13:08:13 INFO - -h, --help show this help message and exit 13:08:13 INFO - -d DEST, --destination=DEST 13:08:13 INFO - Directory to install application into. [default: 13:08:13 INFO - "/builds/slave/test"] 13:08:13 INFO - --app=APP Application being installed. [default: firefox] 13:08:13 INFO - mkdir: /builds/slave/test/build/application 13:08:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 13:08:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 13:08:26 INFO - Reading from file tmpfile_stdout 13:08:26 INFO - Output received: 13:08:26 INFO - /builds/slave/test/build/application/firefox/firefox 13:08:26 INFO - Running post-action listener: _resource_record_post_action 13:08:26 INFO - ##### 13:08:26 INFO - ##### Finished install step (success) 13:08:26 INFO - ##### 13:08:26 INFO - ##### 13:08:26 INFO - ##### Running run-tests step. 13:08:26 INFO - ##### 13:08:26 INFO - Running pre-action listener: _resource_record_pre_action 13:08:26 INFO - Running main action method: run_tests 13:08:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:08:26 INFO - Python 2.7.3 13:08:26 INFO - Return code: 0 13:08:26 INFO - grabbing minidump binary from tooltool 13:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:08:26 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13661c0>, '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 0x13670c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1367550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:08:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:08:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 13:08:26 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 13:08:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:08:26 INFO - Return code: 0 13:08:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:08:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:08:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:08:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:08:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:08:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:08:27 INFO - Return code: 0 13:08:27 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/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 13:08:27 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/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --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 13:08:27 INFO - Using env: {'DISPLAY': ':0', 13:08:27 INFO - 'HOME': '/home/cltbld', 13:08:27 INFO - 'LANG': 'en_US.UTF-8', 13:08:27 INFO - 'LANGUAGE': 'en_US:en', 13:08:27 INFO - 'LOGNAME': 'cltbld', 13:08:27 INFO - 'MAIL': '/var/mail/cltbld', 13:08:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:08:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:27 INFO - 'MOZ_NO_REMOTE': '1', 13:08:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:08:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:08:27 INFO - 'NO_EM_RESTART': '1', 13:08:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:27 INFO - 'PWD': '/builds/slave/test', 13:08:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:08:27 INFO - 'SHELL': '/bin/bash', 13:08:27 INFO - 'SHLVL': '1', 13:08:27 INFO - 'TERM': 'linux', 13:08:27 INFO - 'TMOUT': '86400', 13:08:27 INFO - 'USER': 'cltbld', 13:08:27 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1470168456.341222-796203241', 13:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:27 INFO - '_': '/tools/buildbot/bin/python'} 13:08:27 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/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 13:08:27 INFO - mozversion application_buildid: 20160802064150 13:08:27 INFO - mozversion application_changeset: a983a8015011296941c597f44cc678108db67510 13:08:27 INFO - mozversion application_display_name: Firefox 13:08:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:08:27 INFO - mozversion application_name: Firefox 13:08:27 INFO - mozversion application_remotingname: firefox 13:08:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:08:27 INFO - mozversion application_vendor: Mozilla 13:08:27 INFO - mozversion application_version: 49.0 13:08:27 INFO - mozversion platform_buildid: 20160802064150 13:08:27 INFO - mozversion platform_changeset: a983a8015011296941c597f44cc678108db67510 13:08:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:08:27 INFO - mozversion platform_version: 49.0 13:08:27 INFO - using testdate: 1470168507 13:08:27 INFO - actual date: 1470168507 13:08:27 INFO - starting webserver on 'localhost:52184' 13:08:27 INFO - SUITE-START | Running 2 tests 13:08:27 INFO - TEST-START | dromaeo_css 13:08:27 INFO - operating with platform_type : linux_ 13:08:27 INFO - Initialising browser for dromaeo_css test... 13:08:27 INFO - TEST-INFO | started process 4960 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnZ48oY/profile http://localhost:52184/getInfo.html) 13:08:31 INFO - PROCESS | 4960 | __metrics Screen width/height:1600/1200 13:08:31 INFO - PROCESS | 4960 | colorDepth:24 13:08:31 INFO - PROCESS | 4960 | Browser inner width/height: 1024/697 13:08:31 INFO - PROCESS | 4960 | __metrics 13:08:32 INFO - PROCESS | 4960 | 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" 13:08:32 ERROR - PROCESS | 4960 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:08:33 INFO - TEST-INFO | 4960: exit 0 13:08:33 INFO - Browser initialized. 13:08:33 INFO - Running cycle 1/1 for dromaeo_css test... 13:08:33 INFO - TEST-INFO | started process 5049 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnZ48oY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:08:45 INFO - PROCESS | 5049 | RSS: Main: 141139968 13:08:45 INFO - PROCESS | 5049 | 13:09:36 INFO - PROCESS | 5049 | 1470168576611 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] 13:11:44 INFO - PROCESS | 5049 | Cycle 1(1): loaded http://localhost:52184/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52184/tests/dromaeo/cssquery-ext.html) 13:11:45 INFO - PROCESS | 5049 | RSS: Main: 253194240 13:11:45 INFO - PROCESS | 5049 | 13:14:48 INFO - PROCESS | 5049 | Cycle 1(1): loaded http://localhost:52184/tests/dromaeo/cssquery-ext.html (next: http://localhost:52184/tests/dromaeo/cssquery-jquery.html) 13:14:48 INFO - PROCESS | 5049 | RSS: Main: 218783744 13:14:48 INFO - PROCESS | 5049 | 13:17:50 INFO - PROCESS | 5049 | Cycle 1(1): loaded http://localhost:52184/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52184/tests/dromaeo/cssquery-mootools.html) 13:17:51 INFO - PROCESS | 5049 | RSS: Main: 230105088 13:17:51 INFO - PROCESS | 5049 | 13:20:52 INFO - PROCESS | 5049 | Cycle 1(1): loaded http://localhost:52184/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52184/tests/dromaeo/cssquery-prototype.html) 13:20:53 INFO - PROCESS | 5049 | RSS: Main: 217886720 13:20:53 INFO - PROCESS | 5049 | 13:23:56 INFO - PROCESS | 5049 | Cycle 1(1): loaded http://localhost:52184/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52184/tests/dromaeo/cssquery-yui.html) 13:23:56 INFO - PROCESS | 5049 | RSS: Main: 215748608 13:23:56 INFO - PROCESS | 5049 | 13:26:58 INFO - PROCESS | 5049 | Cycle 1(1): loaded http://localhost:52184/tests/dromaeo/cssquery-yui.html (next: http://localhost:52184/tests/dromaeo/cssquery-dojo.html) 13:26:59 INFO - PROCESS | 5049 | RSS: Main: 256045056 13:26:59 INFO - PROCESS | 5049 | 13:26:59 INFO - PROCESS | 5049 | __start_tp_report 13:26:59 INFO - PROCESS | 5049 | _x_x_mozilla_page_load 13:26:59 INFO - PROCESS | 5049 | _x_x_mozilla_page_load_details 13:26:59 INFO - PROCESS | 5049 | |i|pagename|runs| 13:26:59 INFO - PROCESS | 5049 | |0;dojo.html;3368.141124013376;3377.273886114485;3384.9153771155725;3421.093410246284;3428.4685873689587;5546.584006199536;5585.05054140796;5585.636933599313;5589.077802162648;5589.357223919245;5558.194061861028;5615.80344687936;5623.297087864017;5624.775008999645;5642.266505354298;4892.681975671588;4954.851354459354;5076.593872490211;5084.771185296652;5128;4962.826301079463;4994.550490455858;5038.2190760432195;5047.242913562958;5047.49525047496;5056.393232812049;5080;5113.488651134894;5130.384353877521;5142.922856157161;5179.093658609729;5185.637005409623;5186.896262074775;5195.766190521435;5212.609054320903;4874.902501949977;4939.086269040239;4979.029089327582;5098.4136824265315;5118.488151184889;5372.247885296062;5378.273933019032;5393.5954803389905;5425.403205647375;5441.211024401479;5955.464008239236;5970.731317090698;5973.7311820967725;5974.312954010301;5997.970010149965;5898.64608123514;5970.1343305220935;5979.850503737398;6031.909521357183;6066.8786624267705;5608.074667679828;5611.270534830487;5628.324601047901;5633.605647604711;5635.0138725723245;5897.528197744256;6087.269527656709;6093.721151878967;6100.72546735398;6133.509319254449;5761.366249712528;5866.2960444746905;5866.853328666817;5874.295084589877;5893.233879595625;5960.344362120163;5961.225040744762;5983.670898100646;5987.850303742354;5994.040953447515;5783.768649254067;5838.211841401357;5952.613080149863;5965.075413310857;5967.8209653710455;5893.793717219963;5956.880862382728;5961.344252132262;5965.910511342333;5998.850028749229;4622.260438329851;4624.1445332613575;4625.32932724753;4628.560286772751;4649.465311489222;4030.133521292936;4033.757974521538;4078.3678529827926;4106.589341065869;4147.6474499667265;5620.18507316445;5641.097424412074;5652.208690783294;5658.207850900877;5673.914891276634;5973.283206015306;5981.581289309706;5986.850328741816;5988.700564971734;6024.337322894478;5555.138953462219;5572.665640061609;5614.157876318503;5615.073512870413;5641.887162256732;5128.538431541179;5163.096458119814;5168.405633352138;5170.1469257572835;5177.197534382177;6164.630122192682;6213.96893015532;6215.689215539205;6215.81352559424;6215.875682486325;5838.2702162229725;5904.143899134688;5980.282366116094;6020.789272375446;6064.151018857452;6240.313565507699;6254.906176407449;6255;6257.749690012349;6268.216472940882;5713.457221563945;5908.615939963888;5914.201582786269;5918.201042859159;5946.940530594638;5940.257467816523;5958.910616340845;6018.849528761816;6063.939360606514;6072;5873.823785286278;5913.822585322448;5914.260717410324;5917.437843404869;5917.585768996301;5672;5676.403977582295;5854.297484301911;5900.645961242168;5922.851928701817;5642.915356269741;5710.685912274698;5712.37163911961;5713.657180569179;5742.799002034909;5808.361080281081;5811.709414529341;5843.590948633725;5845.444682755132;5855.912161317498;5599.272094627672;5686.317641883001;5695.45893140151;5717.714114294352;5743.712814359349 13:26:59 INFO - PROCESS | 5049 | |1;ext.html;609.4058293164165;639.9392057754513;657.7664928950222;658.7299207324992;667.0661074495705;34787;47495.52504474954;48288.51711482884;48575.514244857586;48608.51391486084;50064.49935500644;50514.74742626291;50749;50773.49226507734;50796.238056429094;8345.916540834605;8960.910390896106;9125.908740912571;9126.817463650723;10686.14510839131;10544.156467482582;11027.062699670538;11031.448427578629;11036.834447483254;11043.061339786087;10966.835497467544;11011.28426652269;11011.504482298276;11013.504392302326;11022.338659680445;10963.725906852353;10997.12023038155;11005.394703291317;11015.228933974626;11018.944905275463;10894.237403381767;10966;10977.725556861062;10979.945100274528;10980.505877235464;46615.766921165516;46927.296090558324;47008.059838803376;47130.29304560435;47161.764191179165;3703.814569800744;3740.60723624021;3784.1674831537016;3813.2754776592637;3893.7469064511;34641.653583464344;35289.47065794015;35420;35495.82252088723;35676.28647427037;3520.1727594015388;3545;3556.3598552260582;3591;3683.8710645127294;380720;384672.0766396178;385567.9999999972;386990;387449;39345.21309573793;68959.31040689528;69036.309636904;69356;69531.65234173796;40737.79631101885;42220.788896055936;42227.36658950118;42267.57732422698;42279.365809512885;17397;17641.55896102582;17653.7351939721;17655;17659.293628254727;13661.453541858413;14672.926635366755;14873.851261487463;14902.925485372503;15155.469558565617;9518.571664275125;9523.428594284365;9533.570989305497;9574.377665451724;9600.231981441468;3449.862005519751;3498.7201023918024;3544.5392099026963;3571.39286321326;3574.8748793792092;1040.666986564292;1059.5549869055014;1221.9816702749465;1237.6287113865803;1245.2777389114292;33456.330873382394;33856;33862.49206261908;34047.82976085104;34465.483017754756;1172.9853676569705;1175.4064197580167;1187.6140254417176;1220.1641875315438;1262.7790136726037;18272;18288.451346459628;18358.81641183598;18450.907745461187;18496.907515462604;599075;600402;601157;603201;603556;67400.32599674039;68035;68072.65963670249;68112.3188768106;68301.31698683048;1375.8692924172187;1414.8160739104057;1462.978055329171;1496.9850301497063;1516.8862335324895;31531.68468315379;34709.1322716924;34893;35022.82488587541;35315.47026794651;57463;57639.135412968;57882.421175789386;58039;58203.41796582149;429644;431842;431967;432039;432510;24910.626340604544;27334.179974600793;29785.5532167022;29850.701492984794;31584.526232106076;49541.256881147536;50014.74992625014;50517.74741126271;51266.48733512617;51332.23001654904;41928;42039.7898010508;42243.57756422397;42310.57689423066;43208.56791432171;41340;41621.37567936423;41903;42366.57633423626;43054.569454305056;41906.790466047474;42446.36330455107;42458;45922.08155836798;46820;38038.61961380351;40910.18179636331;41159.7942010288;41289.58710412857;41591.37612935869;29215.269618259714;30243.395132096794;30791;30961.535576965915;31084.378312434077 13:26:59 INFO - PROCESS | 5049 | |2;jquery.html;430.11181909357174;436.1408026188412;436.89514516516033;439.03432314714104;450.2031404414185;19304.517387065313;19975.200991960322;20641.38075857725;20756;20899;19885.701714474293;20255.392338229838;20365.083571239295;20403.08186131624;20415.693764593543;5613.382527921925;5806.622569532967;5846.912296315538;5857.619254748449;5860.58975871687;5782.306123265193;5783.074708046714;5859.53123750099;5892.3812999635065;5904.7047647617665;5812.854678633025;5825.854353641172;5827.970860145693;5830.591858569902;5836.241288632493;5730.627509211909;5839.883202335929;5843.678597677105;5847.386024467433;5851.912221316684;5065.316182315377;5082.415522214918;5214;5217.608679349064;5218.425973142951;23595.29214123579;23640;23926.162584309466;23996;24023.039078436843;2497.2633073243437;2500.3874050857657;2557.09223225754;2918.4746745585785;2925.9905332660196;14597.12417254968;14955.177465239407;14966.850331496655;15046.54860354191;15477.5356739298;2006.56858775364;2019.6162729081575;2070.5858828234414;2147.924822631216;2189.7372315322264;83637.58181209055;85604.57197713971;86238;87236.12763872344;87595.56202219012;10987.230893837355;11283.605073822377;11450.885491145149;11560.710982225344;11657.300561966309;16339.754903676454;16387.83612163887;16447.177641117898;16477.258523366476;16503.91748041252;9029.51013082834;9199.77000574991;9200.125988031126;9303.209227215628;9365.203957663538;9050.954745226232;9052.094790520896;9128.863067053999;9223.53882305882;9252.074792520694;6942.895856562156;7132.607706576085;7143.571385716874;7145.6784444700115;7163.176234732969;2063.2572273981355;2069.3274685727197;2079.6048750737614;2097.643400621898;2144.2066435418683;972.4457059476172;1048.9318194317345;1061.2200032975757;1102.9283096598695;1112.6217086190572;20021.199152034045;20225.393238202876;20645.690314645293;20656.483587910123;20744.377668669964;1026.553449249564;1038.9948050259852;1060.9098226650667;1065.706930594093;1159.5999380213814;11055;11784.351860647575;11880.524779008743;12010.819837702442;12032.097592680584;505108;533825;538406;554837;576566;88068;89013.55493222493;89898.55050724704;90220;90439;1075.2097208551709;1086.4676308608882;1101.2291396022656;1115.9497822598003;1127.2165844737992;18040.2783888643;18199.727004095213;18429.26282948721;18458.630827383644;18794.342198023005;40226.79886600548;40444.393334099426;41164.382534261415;41402.1719565613;41885;61305;63067;66210.33789662042;66704.6664766673;67331;8378.622961966848;8606.354523410895;8681.91318086811;8988.775280618036;9115;13435.932820335836;13569.86430135686;13623.978201634718;13832.86167138356;13882.583522494342;11782.410879456163;11855.288682679067;11856.822147667619;12015;12100.697482563006;11030.558777649125;11948.701282468008;12030.458629361874;12032.639020829389;12097.75804483923;11431.885681143414;11558.653240402802;11712.70718232051;11775.17573769862;12059.15585908978;8612.612432440681;8854.601542930455;8962.775930601787;9041.32190085733;9065.546722663708;11749.177557570887;12177.330246836154;12356.443960021641;12364.814527781911;12388.56640017595 13:26:59 INFO - PROCESS | 5049 | |3;mootools.html;1426.080178285007;1591.8726501879846;1627.8779091568133;1820.2991848138463;1946.7274581558584;8562.272206862417;8808.383413161073;8818.91181088189;8893.021767605558;9043.593038313275;8555.743327700164;8855.114488551142;9052;9085.863712044325;9102.453852768838;2428.8056955443567;2473.9257822265363;2475.6781618389673;2478.5166892455977;2482.5407299649532;2343.8359314847967;2411.6985376827897;2416.2388847513066;2416.4200591857916;2419.2258477287273;2355.9057637694473;2397.9040838366445;2399.1962692497946;2400.0879665726998;2409.6144616861307;2327.394877331889;2332.265336419023;2347.7182738071456;2355.4346956730346;2367.4791545859885;2300.1144559344607;2352.588297048027;2358.0449917783326;2366.9644955325684;2377.239283429303;7804.414668899855;8473.70342038032;9025.684101056431;9028.774280643036;9044.77388065297;5408.91886621701;5606.719664016783;5610.495055444989;5611.831645050655;5617.775288988436;3397.779144355609;3415.7096646785053;3484.790912545255;3492.2142517933517;3504.0889368764306;1065.5471424644495;1077.6928575355985;1080.4057768227522;1084.9294795838246;1098.8846171152074;12431.191972521847;12488.750224995449;12505.74988500234;12531.122821402505;12590.24458532491;4756.4881611637975;4791.496892826203;4878.975605121952;4897.0450762101045;4921.680090794087;10651.254412191227;10759.892401076046;10821.350718956888;10859.239853210358;10869.891301086887;5585;5595.300587426572;5596.27248457706;5597.8600534986135;5604.271444712243;4997.075541024922;5037.319961805183;5093.1087059764395;5143.048536020848;5172.586193104543;4051.331530297527;4069.7354671946227;4081.510218773766;4118.670506359484;4145.357469592218;1241.7268200995766;1264.5131624324702;1266.2402558464848;1280.1614942212866;1287.658770425838;938.6949241496404;972.2416515118219;975.7267964969819;980.1668581705493;995.5548476054307;5902.4097590240635;5947.762089516457;6031.5476339273855;6306.180196574416;6434.935650643527;1019.7807471393686;1057.1754031855164;1068.5672302717412;1069.7539565899888;1069.812782763029;4432.84485043022;4524.954750452519;4534.750588717587;4550.7497087660495;4600.102979918951;10080.59677612902;10456.947715261527;10889.83665245022;11187.160962927814;11237.3257604544;10813.945930270298;11240.04459620925;11411.942940285246;11447.656570302905;11471.827922581168;8469.74590762278;8577.01364343096;8605.569721513899;8635.740927772176;8755.430896991675;6404.647744373918;6409.327020662763;6437.25971513282;6546.476281897532;6551.44312733423;10465.633702820365;10527.368357898551;10563.419011954109;10563.788724225626;10653.840192396965;10739.731506712082;10856.294340867818;10859.782804344028;10973.122150228122;11078.944605276922;7795.298423141944;7832.099308579358;7872.763817085496;7881.290683838482;7894.960525197337;8854.380193386402;8911.37620366568;8949.731508054769;8952.820943580962;9032.232260257952;7698.230176982257;7762.5730584818375;7869.921300786918;7898.526088434713;7991.400644951768;7796.61016949139;7850.646720897459;7872.84254314899;7958.721444749406;8199.385046121446;7544.245575442412;7585.6965721370525;7622.2758837910305;7649.235076492529;7653.57905315213;5144.382674079135;5205.5835533158015;5263.210518422267;5335.653182543278;5404.189371594292;5224.686518808883;5278.4727645329785;5304.734763261744;5453.263809385682;5525.336959564878 13:26:59 INFO - PROCESS | 5049 | |4;prototype.html;318.3839271010593;335.42307231561733;336.53271735234387;353.9752217344785;354.9449835275532;5353.544948679357;5452.291202143726;5498.917516237255;5522.9171562426545;5568.554515638749;5420.566354691625;5440.510354068133;5458.9454105459035;5467.890642187154;5509.393966663663;4335.328024156253;4355;4366.519682834885;4382.824687012531;4422.226110430662;4192.67306644812;4272.50866150392;4315.568443155691;4369.431973843396;4371.453568303962;4202.453681021463;4203.621674049335;4247.277962746325;4277.443932288798;4281.422008028923;4224.788760561975;4225.3169237534075;4252.107057517894;4254.87235382939;4297.570242975708;3618;3729.4965179700666;3733.925321493582;3738.495303134069;3741.494898188737;5288.312519372495;5288.947110528884;5295.576353891679;5309.867253318698;5321.946780532184;4838.661293709441;4850.781714822806;4877.975610121962;4886.828960986349;4897.853064408074;5534.4742249486235;5816.331121920991;5829.883402331972;5830.318053695305;5833.154192642086;4114.280000999813;4125.855595054189;4130.194612050651;4133.656561617457;4137.296659567851;5817.709114544298;5963.4334738199805;5990.430909063632;5999.76000959961;6007.828473447679;4168.124693814272;4170.603792639695;4171.70798044134;4171.937420938689;4172.248995180897;4234.322508398642;4248.086661367821;4250.639795265485;4250.851220207278;4252.893677658021;4851.611871050308;4863.173260545645;4865.1242776300605;4866.7079975201605;4886.193778026586;5509.449055094458;5722.77108915647;5753.108268218432;5774.7478483391515;5782.132680098019;1403.9789403158961;1447.9203643799692;1450.717110163529;1460.5545308680955;1470.955871323862;880.2078129683337;896.7040876510736;900.9054049324726;901.0358915959991;905.3979103895967;4878.682885612424;4934.4572097069295;4943.530364615356;4957;4965.230389289666;5173.767180476888;5203.505666961632;5207.6875387476875;5207.739613019334;5240.454066050448;4687.460941991646;4748.572628463455;4882.926756098661;4943.887625284282;4994.725290109079;2742.986285068602;2751.4866823247135;2762.309422644339;2762.668479782439;2808.9016884409357;5335.519803217729;5383.973080134574;5396.649217800909;5453.263809385682;5499.202615620713;4890.535399137148;5250.212468129811;5254.738862672931;5386.8383948481605;5405.540529055074;5405.8107966221;5427.855996881289;5436.782528698887;5436.891262174734;5464.562834973192;5201.973990130025;5202.765875535534;5216.452272511484;5251.474852514718;5263.315768950012;5156;5468.753906074316;5893.675847828411;5898;5913.852153696192;6065.514758819196;6149.200603921461;6161.661108639068;6163.414475624809;6180.381961803783;4022.919541609093;4278.251306021496;4307.784610769511;4308.935365969575;4335.393044973644;5661.320641523043;5866.50134738535;5875.236219291465;5886.969780288347;5905.232319798399;5630.408807075198;5733.853229354229;5809.448102430262;5820.30156381237;5829.47534721877;5761.366249712444;5772.567057470623;5792.565557583114;5874.148248504029;5876.911846322223;5598.3282006159525;5667.773289068391;5676.318841739017;5686.886262274814;5700.971495142498;5751.482366587027;5772.7690892363835;5785.768569257351;5825.737841797045;5835.270591176103;5490.5882058844945;5638.577106716931;5672.177534257592;5678.432156784289;5705.258316418839 13:26:59 INFO - PROCESS | 5049 | |5;yui.html;719.1262615921654;752.3647877398289;870.799716065305;880.8634661627448;923.2936803345442;15045.774313385307;15459.61350966225;15682.84317156828;15804.44684436045;15808.762868556982;14120.152790832557;14700.55898323052;15301.69396612067;15351.462698805544;15733.527994160166;741.7626359564941;865.1694373401542;903.9548022598876;904.1004200820176;907.6278725722456;892.6474042753109;897.3001059173855;901.6528636474939;903.7830920579047;904.6743172457943;946.9914541013833;948.5684013773716;952.8570714392862;953.1897886796203;953.2183609440261;660.0791895306045;915.0986278515657;941.6327632223438;945.3240932733095;945.7966537194502;690.4303949241895;692.6986760759036;696.9163700355939;697.6965020216222;697.7348607529157;14836.035657682216;14864;14912.105273683614;14912.850871491362;14912.850871491362;14502.927485362612;14873.628159295997;14965.102093874402;14973.401063957428;15078.246087695572;13563;13738.450461981509;13776.586702398945;13779.311034448338;13929.373178207004;4598.793054312564;4601.171789077965;4663.580277775017;4682.29765535169;4789.090072886141;80315;81416;81624.59187704083;81834;82058;11438.027767639678;11574.18980671345;11582.47878845454;11597.826032609517;11697.76604467906;21444.57110857774;22204.111835526397;22282.442938926655;23285.18501852427;23362.415939601648;8757.211850933447;8898.777530561658;8933.46399216049;9016.459012459274;9174.220191283815;4809.44691360498;4811.230203167546;4813.253945638432;4830.589399901048;4908.11653902301;3983.50206224222;4110.547839737626;4112.09533902541;4113.03343714227;4117.444145040441;641.3842710997494;669.7220653428773;675.8952362383839;697.8534507753327;718.0844423360153;676.4385559985201;678.5962352400314;698.9440844732409;741.7885902517756;752.9360004399578;9989.750256243507;10277.588896444058;10350.275480716427;10479.84280235797;10593.364398136137;670.2928410526941;764.6215123513896;773.0535615681976;788.7969119440061;792.6116203060574;4774.641901857374;4838.612910967114;4942.184539550934;4942.258661200849;4982.551570358685;79693.60153199197;85131.57434212789;131856;137147.31426343005;138201;79123.20876791158;80423.39153216903;113284;113713;114254.42872785583;97062.51468742611;97232.51383743178;98594;99446;100639.49680251698;9338.579763910777;9355.906440935503;9366.718998430058;9439.669611563562;9558.426494410358;58434.7078264623;58992.41007589869;59913.700431497564;61175;61382;86231.13768862482;87120;88231;88253.1174688245;91441;11254.21220514556;11753.706157346134;11968.700782480508;12030.15788894769;12424.440900159696;13018.80471792905;13245.602631921058;13402.06185567001;13515;13689.589312320646;9051.749985008812;12603.621891343275;13061.477540898257;13147;13606;12877;13442.529511467052;13443.798343025059;13469.259190744604;13529.458821647024;12820.487180513048;12873.678158046123;12917.22496650204;13186.142900711424;13341.266230357423;11131.609486118732;11232.7753444929;11381.0186640327;11440.599579014695;11641.301521908712;10948.34309941406;11079.778404431705;11285;11388.316700997966;11500.194986350874 13:26:59 INFO - PROCESS | 5049 | __end_tp_report 13:26:59 INFO - PROCESS | 5049 | __start_cc_report 13:26:59 INFO - PROCESS | 5049 | _x_x_mozilla_cycle_collect,855 13:26:59 INFO - PROCESS | 5049 | __end_cc_report 13:26:59 INFO - PROCESS | 5049 | __startTimestamp1470169619222__endTimestamp 13:26:59 INFO - PROCESS | 5049 | 13:26:59 INFO - PROCESS | 5049 | ------- Summary: start ------- 13:26:59 INFO - PROCESS | 5049 | Number of tests: 6 13:26:59 INFO - PROCESS | 5049 | 13:26:59 INFO - PROCESS | 5049 | [#0] dojo.html Cycles:165 Average:5546.45 Median:5712.37 stddev:610.66 (10.7%) stddev-sans-first:588.14 13:26:59 INFO - PROCESS | 5049 | Values: 3368.1 3377.3 3384.9 3421.1 3428.5 5546.6 5585.1 5585.6 5589.1 5589.4 5558.2 5615.8 5623.3 5624.8 5642.3 4892.7 4954.9 5076.6 5084.8 5128.0 4962.8 4994.6 5038.2 5047.2 5047.5 5056.4 5080.0 5113.5 5130.4 5142.9 5179.1 5185.6 5186.9 5195.8 5212.6 4874.9 4939.1 4979.0 5098.4 5118.5 5372.2 5378.3 5393.6 5425.4 5441.2 5955.5 5970.7 5973.7 5974.3 5998.0 5898.6 5970.1 5979.9 6031.9 6066.9 5608.1 5611.3 5628.3 5633.6 5635.0 5897.5 6087.3 6093.7 6100.7 6133.5 5761.4 5866.3 5866.9 5874.3 5893.2 5960.3 5961.2 5983.7 5987.9 5994.0 5783.8 5838.2 5952.6 5965.1 5967.8 5893.8 5956.9 5961.3 5965.9 5998.9 4622.3 4624.1 4625.3 4628.6 4649.5 4030.1 4033.8 4078.4 4106.6 4147.6 5620.2 5641.1 5652.2 5658.2 5673.9 5973.3 5981.6 5986.9 5988.7 6024.3 5555.1 5572.7 5614.2 5615.1 5641.9 5128.5 5163.1 5168.4 5170.1 5177.2 6164.6 6214.0 6215.7 6215.8 6215.9 5838.3 5904.1 5980.3 6020.8 6064.2 6240.3 6254.9 6255.0 6257.7 6268.2 5713.5 5908.6 5914.2 5918.2 5946.9 5940.3 5958.9 6018.8 6063.9 6072.0 5873.8 5913.8 5914.3 5917.4 5917.6 5672.0 5676.4 5854.3 5900.6 5922.9 5642.9 5710.7 5712.4 5713.7 5742.8 5808.4 5811.7 5843.6 5845.4 5855.9 5599.3 5686.3 5695.5 5717.7 5743.7 13:26:59 INFO - PROCESS | 5049 | 13:26:59 INFO - PROCESS | 5049 | [#1] ext.html Cycles:180 Average:63975.07 Median:209379.31 stddev:127978.28 (61.1%) stddev-sans-first:128248.86 13:26:59 INFO - PROCESS | 5049 | Values: 609.4 639.9 657.8 658.7 667.1 34787.0 47495.5 48288.5 48575.5 48608.5 50064.5 50514.7 50749.0 50773.5 50796.2 8345.9 8960.9 9125.9 9126.8 10686.1 10544.2 11027.1 11031.4 11036.8 11043.1 10966.8 11011.3 11011.5 11013.5 11022.3 10963.7 10997.1 11005.4 11015.2 11018.9 10894.2 10966.0 10977.7 10979.9 10980.5 46615.8 46927.3 47008.1 47130.3 47161.8 3703.8 3740.6 3784.2 3813.3 3893.7 34641.7 35289.5 35420.0 35495.8 35676.3 3520.2 3545.0 3556.4 3591.0 3683.9 380720.0 384672.1 385568.0 386990.0 387449.0 39345.2 68959.3 69036.3 69356.0 69531.7 40737.8 42220.8 42227.4 42267.6 42279.4 17397.0 17641.6 17653.7 17655.0 17659.3 13661.5 14672.9 14873.9 14902.9 15155.5 9518.6 9523.4 9533.6 9574.4 9600.2 3449.9 3498.7 3544.5 3571.4 3574.9 1040.7 1059.6 1222.0 1237.6 1245.3 33456.3 33856.0 33862.5 34047.8 34465.5 1173.0 1175.4 1187.6 1220.2 1262.8 18272.0 18288.5 18358.8 18450.9 18496.9 599075.0 600402.0 601157.0 603201.0 603556.0 67400.3 68035.0 68072.7 68112.3 68301.3 1375.9 1414.8 1463.0 1497.0 1516.9 31531.7 34709.1 34893.0 35022.8 35315.5 57463.0 57639.1 57882.4 58039.0 58203.4 429644.0 431842.0 431967.0 432039.0 432510.0 24910.6 27334.2 29785.6 29850.7 31584.5 49541.3 50014.7 50517.7 51266.5 51332.2 41928.0 42039.8 42243.6 42310.6 43208.6 41340.0 41621.4 41903.0 42366.6 43054.6 41906.8 42446.4 42458.0 45922.1 46820.0 38038.6 40910.2 41159.8 41289.6 41591.4 29215.3 30243.4 30791.0 30961.5 31084.4 13:26:59 INFO - PROCESS | 5049 | 13:26:59 INFO - PROCESS | 5049 | [#2] jquery.html Cycles:180 Average:31237.95 Median:23783.08 stddev:89141.64 (374.8%) stddev-sans-first:89361.69 13:26:59 INFO - PROCESS | 5049 | Values: 430.1 436.1 436.9 439.0 450.2 19304.5 19975.2 20641.4 20756.0 20899.0 19885.7 20255.4 20365.1 20403.1 20415.7 5613.4 5806.6 5846.9 5857.6 5860.6 5782.3 5783.1 5859.5 5892.4 5904.7 5812.9 5825.9 5828.0 5830.6 5836.2 5730.6 5839.9 5843.7 5847.4 5851.9 5065.3 5082.4 5214.0 5217.6 5218.4 23595.3 23640.0 23926.2 23996.0 24023.0 2497.3 2500.4 2557.1 2918.5 2926.0 14597.1 14955.2 14966.9 15046.5 15477.5 2006.6 2019.6 2070.6 2147.9 2189.7 83637.6 85604.6 86238.0 87236.1 87595.6 10987.2 11283.6 11450.9 11560.7 11657.3 16339.8 16387.8 16447.2 16477.3 16503.9 9029.5 9199.8 9200.1 9303.2 9365.2 9051.0 9052.1 9128.9 9223.5 9252.1 6942.9 7132.6 7143.6 7145.7 7163.2 2063.3 2069.3 2079.6 2097.6 2144.2 972.4 1048.9 1061.2 1102.9 1112.6 20021.2 20225.4 20645.7 20656.5 20744.4 1026.6 1039.0 1060.9 1065.7 1159.6 11055.0 11784.4 11880.5 12010.8 12032.1 505108.0 533825.0 538406.0 554837.0 576566.0 88068.0 89013.6 89898.6 90220.0 90439.0 1075.2 1086.5 1101.2 1115.9 1127.2 18040.3 18199.7 18429.3 18458.6 18794.3 40226.8 40444.4 41164.4 41402.2 41885.0 61305.0 63067.0 66210.3 66704.7 67331.0 8378.6 8606.4 8681.9 8988.8 9115.0 13435.9 13569.9 13624.0 13832.9 13882.6 11782.4 11855.3 11856.8 12015.0 12100.7 11030.6 11948.7 12030.5 12032.6 12097.8 11431.9 11558.7 11712.7 11775.2 12059.2 8612.6 8854.6 8962.8 9041.3 9065.5 11749.2 12177.3 12356.4 12364.8 12388.6 13:26:59 INFO - PROCESS | 5049 | 13:26:59 INFO - PROCESS | 5049 | [#3] mootools.html Cycles:180 Average:6012.77 Median:4773.99 stddev:3383.36 (70.9%) stddev-sans-first:3375.29 13:26:59 INFO - PROCESS | 5049 | Values: 1426.1 1591.9 1627.9 1820.3 1946.7 8562.3 8808.4 8818.9 8893.0 9043.6 8555.7 8855.1 9052.0 9085.9 9102.5 2428.8 2473.9 2475.7 2478.5 2482.5 2343.8 2411.7 2416.2 2416.4 2419.2 2355.9 2397.9 2399.2 2400.1 2409.6 2327.4 2332.3 2347.7 2355.4 2367.5 2300.1 2352.6 2358.0 2367.0 2377.2 7804.4 8473.7 9025.7 9028.8 9044.8 5408.9 5606.7 5610.5 5611.8 5617.8 3397.8 3415.7 3484.8 3492.2 3504.1 1065.5 1077.7 1080.4 1084.9 1098.9 12431.2 12488.8 12505.7 12531.1 12590.2 4756.5 4791.5 4879.0 4897.0 4921.7 10651.3 10759.9 10821.4 10859.2 10869.9 5585.0 5595.3 5596.3 5597.9 5604.3 4997.1 5037.3 5093.1 5143.0 5172.6 4051.3 4069.7 4081.5 4118.7 4145.4 1241.7 1264.5 1266.2 1280.2 1287.7 938.7 972.2 975.7 980.2 995.6 5902.4 5947.8 6031.5 6306.2 6434.9 1019.8 1057.2 1068.6 1069.8 1069.8 4432.8 4525.0 4534.8 4550.7 4600.1 10080.6 10456.9 10889.8 11187.2 11237.3 10813.9 11240.0 11411.9 11447.7 11471.8 8469.7 8577.0 8605.6 8635.7 8755.4 6404.6 6409.3 6437.3 6546.5 6551.4 10465.6 10527.4 10563.4 10563.8 10653.8 10739.7 10856.3 10859.8 10973.1 11078.9 7795.3 7832.1 7872.8 7881.3 7895.0 8854.4 8911.4 8949.7 8952.8 9032.2 7698.2 7762.6 7869.9 7898.5 7991.4 7796.6 7850.6 7872.8 7958.7 8199.4 7544.2 7585.7 7622.3 7649.2 7653.6 5144.4 5205.6 5263.2 5335.7 5404.2 5224.7 5278.5 5304.7 5453.3 5525.3 13:26:59 INFO - PROCESS | 5049 | 13:26:59 INFO - PROCESS | 5049 | [#4] prototype.html Cycles:180 Average:4699.22 Median:5250.84 stddev:1375.04 (26.2%) stddev-sans-first:1339.00 13:26:59 INFO - PROCESS | 5049 | Values: 318.4 335.4 336.5 354.0 354.9 5353.5 5452.3 5498.9 5522.9 5568.6 5420.6 5440.5 5458.9 5467.9 5509.4 4335.3 4355.0 4366.5 4382.8 4422.2 4192.7 4272.5 4315.6 4369.4 4371.5 4202.5 4203.6 4247.3 4277.4 4281.4 4224.8 4225.3 4252.1 4254.9 4297.6 3618.0 3729.5 3733.9 3738.5 3741.5 5288.3 5288.9 5295.6 5309.9 5321.9 4838.7 4850.8 4878.0 4886.8 4897.9 5534.5 5816.3 5829.9 5830.3 5833.2 4114.3 4125.9 4130.2 4133.7 4137.3 5817.7 5963.4 5990.4 5999.8 6007.8 4168.1 4170.6 4171.7 4171.9 4172.2 4234.3 4248.1 4250.6 4250.9 4252.9 4851.6 4863.2 4865.1 4866.7 4886.2 5509.4 5722.8 5753.1 5774.7 5782.1 1404.0 1447.9 1450.7 1460.6 1471.0 880.2 896.7 900.9 901.0 905.4 4878.7 4934.5 4943.5 4957.0 4965.2 5173.8 5203.5 5207.7 5207.7 5240.5 4687.5 4748.6 4882.9 4943.9 4994.7 2743.0 2751.5 2762.3 2762.7 2808.9 5335.5 5384.0 5396.6 5453.3 5499.2 4890.5 5250.2 5254.7 5386.8 5405.5 5405.8 5427.9 5436.8 5436.9 5464.6 5202.0 5202.8 5216.5 5251.5 5263.3 5156.0 5468.8 5893.7 5898.0 5913.9 6065.5 6149.2 6161.7 6163.4 6180.4 4022.9 4278.3 4307.8 4308.9 4335.4 5661.3 5866.5 5875.2 5887.0 5905.2 5630.4 5733.9 5809.4 5820.3 5829.5 5761.4 5772.6 5792.6 5874.1 5876.9 5598.3 5667.8 5676.3 5686.9 5701.0 5751.5 5772.8 5785.8 5825.7 5835.3 5490.6 5638.6 5672.2 5678.4 5705.3 13:26:59 INFO - PROCESS | 5049 | 13:26:59 INFO - PROCESS | 5049 | [#5] yui.html Cycles:180 Average:22001.80 Median:4799.27 stddev:32406.89 (675.2%) stddev-sans-first:32458.40 13:26:59 INFO - PROCESS | 5049 | Values: 719.1 752.4 870.8 880.9 923.3 15045.8 15459.6 15682.8 15804.4 15808.8 14120.2 14700.6 15301.7 15351.5 15733.5 741.8 865.2 904.0 904.1 907.6 892.6 897.3 901.7 903.8 904.7 947.0 948.6 952.9 953.2 953.2 660.1 915.1 941.6 945.3 945.8 690.4 692.7 696.9 697.7 697.7 14836.0 14864.0 14912.1 14912.9 14912.9 14502.9 14873.6 14965.1 14973.4 15078.2 13563.0 13738.5 13776.6 13779.3 13929.4 4598.8 4601.2 4663.6 4682.3 4789.1 80315.0 81416.0 81624.6 81834.0 82058.0 11438.0 11574.2 11582.5 11597.8 11697.8 21444.6 22204.1 22282.4 23285.2 23362.4 8757.2 8898.8 8933.5 9016.5 9174.2 4809.4 4811.2 4813.3 4830.6 4908.1 3983.5 4110.5 4112.1 4113.0 4117.4 641.4 669.7 675.9 697.9 718.1 676.4 678.6 698.9 741.8 752.9 9989.8 10277.6 10350.3 10479.8 10593.4 670.3 764.6 773.1 788.8 792.6 4774.6 4838.6 4942.2 4942.3 4982.6 79693.6 85131.6 131856.0 137147.3 138201.0 79123.2 80423.4 113284.0 113713.0 114254.4 97062.5 97232.5 98594.0 99446.0 100639.5 9338.6 9355.9 9366.7 9439.7 9558.4 58434.7 58992.4 59913.7 61175.0 61382.0 86231.1 87120.0 88231.0 88253.1 91441.0 11254.2 11753.7 11968.7 12030.2 12424.4 13018.8 13245.6 13402.1 13515.0 13689.6 9051.7 12603.6 13061.5 13147.0 13606.0 12877.0 13442.5 13443.8 13469.3 13529.5 12820.5 12873.7 12917.2 13186.1 13341.3 11131.6 11232.8 11381.0 11440.6 11641.3 10948.3 11079.8 11285.0 11388.3 11500.2 13:26:59 INFO - PROCESS | 5049 | -------- Summary: end -------- 13:26:59 INFO - PROCESS | 5049 | 13:26:59 INFO - TEST-INFO | 5049: exit 0 13:26:59 INFO - TEST-OK | dromaeo_css | took 1112026ms 13:26:59 INFO - TEST-START | kraken 13:26:59 INFO - operating with platform_type : linux_ 13:26:59 INFO - Initialising browser for kraken test... 13:26:59 INFO - TEST-INFO | started process 5247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkWMnOI/profile http://localhost:52184/getInfo.html) 13:27:03 INFO - PROCESS | 5247 | __metrics Screen width/height:1600/1200 13:27:03 INFO - PROCESS | 5247 | colorDepth:24 13:27:03 INFO - PROCESS | 5247 | Browser inner width/height: 1024/697 13:27:03 INFO - PROCESS | 5247 | __metrics 13:27:04 INFO - PROCESS | 5247 | 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" 13:27:04 ERROR - PROCESS | 5247 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:27:05 INFO - TEST-INFO | 5247: exit 0 13:27:05 INFO - Browser initialized. 13:27:05 INFO - Running cycle 1/1 for kraken test... 13:27:05 INFO - TEST-INFO | started process 5339 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkWMnOI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:27:17 INFO - PROCESS | 5339 | RSS: Main: 141594624 13:27:17 INFO - PROCESS | 5339 | 13:27:19 INFO - PROCESS | 5339 | Cycle 1(1): loaded http://localhost:52184/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52184/tests/kraken/driver.html?testName=audio-beat-detection) 13:27:20 INFO - PROCESS | 5339 | RSS: Main: 191303680 13:27:20 INFO - PROCESS | 5339 | 13:27:23 INFO - PROCESS | 5339 | Cycle 1(1): loaded http://localhost:52184/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52184/tests/kraken/driver.html?testName=audio-dft) 13:27:23 INFO - PROCESS | 5339 | RSS: Main: 186036224 13:27:23 INFO - PROCESS | 5339 | 13:27:26 INFO - PROCESS | 5339 | Cycle 1(1): loaded http://localhost:52184/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52184/tests/kraken/driver.html?testName=audio-fft) 13:27:27 INFO - PROCESS | 5339 | RSS: Main: 277475328 13:27:27 INFO - PROCESS | 5339 | 13:27:29 INFO - PROCESS | 5339 | Cycle 1(1): loaded http://localhost:52184/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52184/tests/kraken/driver.html?testName=audio-oscillator) 13:27:29 INFO - PROCESS | 5339 | RSS: Main: 186408960 13:27:29 INFO - PROCESS | 5339 | 13:27:31 INFO - PROCESS | 5339 | Cycle 1(1): loaded http://localhost:52184/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52184/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:27:32 INFO - PROCESS | 5339 | RSS: Main: 187305984 13:27:32 INFO - PROCESS | 5339 | 13:27:35 INFO - PROCESS | 5339 | Cycle 1(1): loaded http://localhost:52184/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52184/tests/kraken/driver.html?testName=imaging-darkroom) 13:27:36 INFO - PROCESS | 5339 | RSS: Main: 221495296 13:27:36 INFO - PROCESS | 5339 | 13:27:39 INFO - PROCESS | 5339 | Cycle 1(1): loaded http://localhost:52184/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52184/tests/kraken/driver.html?testName=imaging-desaturate) 13:27:40 INFO - PROCESS | 5339 | RSS: Main: 221917184 13:27:40 INFO - PROCESS | 5339 | 13:27:42 INFO - PROCESS | 5339 | Cycle 1(1): loaded http://localhost:52184/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52184/tests/kraken/driver.html?testName=json-parse-financial) 13:27:43 INFO - PROCESS | 5339 | RSS: Main: 220614656 13:27:43 INFO - PROCESS | 5339 | 13:27:44 INFO - PROCESS | 5339 | Cycle 1(1): loaded http://localhost:52184/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52184/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:27:45 INFO - PROCESS | 5339 | RSS: Main: 198844416 13:27:45 INFO - PROCESS | 5339 | 13:27:47 INFO - PROCESS | 5339 | Cycle 1(1): loaded http://localhost:52184/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52184/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:27:47 INFO - PROCESS | 5339 | RSS: Main: 200310784 13:27:47 INFO - PROCESS | 5339 | 13:27:49 INFO - PROCESS | 5339 | Cycle 1(1): loaded http://localhost:52184/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52184/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:27:50 INFO - PROCESS | 5339 | RSS: Main: 208224256 13:27:50 INFO - PROCESS | 5339 | 13:27:52 INFO - PROCESS | 5339 | Cycle 1(1): loaded http://localhost:52184/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52184/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:27:53 INFO - PROCESS | 5339 | RSS: Main: 214183936 13:27:53 INFO - PROCESS | 5339 | 13:27:56 INFO - PROCESS | 5339 | Cycle 1(1): loaded http://localhost:52184/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52184/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:27:56 INFO - PROCESS | 5339 | RSS: Main: 215269376 13:27:56 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | Cycle 1(1): loaded http://localhost:52184/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52184/tests/kraken/driver.html?testName=ai-astar) 13:27:58 INFO - PROCESS | 5339 | RSS: Main: 256811008 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | __start_tp_report 13:27:58 INFO - PROCESS | 5339 | _x_x_mozilla_page_load 13:27:58 INFO - PROCESS | 5339 | _x_x_mozilla_page_load_details 13:27:58 INFO - PROCESS | 5339 | |i|pagename|runs| 13:27:58 INFO - PROCESS | 5339 | |0;ai-astar;110;105;110;111;109;112;109;111;111;110 13:27:58 INFO - PROCESS | 5339 | |1;audio-beat-detection;155;125;132;169;124;143;146;131;180;124 13:27:58 INFO - PROCESS | 5339 | |2;audio-dft;162;161;162;161;159;160;159;161;158;161 13:27:58 INFO - PROCESS | 5339 | |3;audio-fft;76;74;75;116;74;77;75;76;102;75 13:27:58 INFO - PROCESS | 5339 | |4;audio-oscillator;86;86;111;84;85;85;119;84;84;127 13:27:58 INFO - PROCESS | 5339 | |5;imaging-gaussian-blur;116;117;116;116;117;117;117;117;116;116 13:27:58 INFO - PROCESS | 5339 | |6;imaging-darkroom;145;146;146;146;146;146;145;146;145;145 13:27:58 INFO - PROCESS | 5339 | |7;imaging-desaturate;94;95;95;95;94;95;95;95;95;95 13:27:58 INFO - PROCESS | 5339 | |8;json-parse-financial;51;50;50;50;51;58;52;52;53;53 13:27:58 INFO - PROCESS | 5339 | |9;json-stringify-tinderbox;61;60;60;61;60;60;60;60;61;61 13:27:58 INFO - PROCESS | 5339 | |10;stanford-crypto-aes;76;75;76;86;77;81;76;80;105;75 13:27:58 INFO - PROCESS | 5339 | |11;stanford-crypto-ccm;122;117;123;119;117;118;124;135;137;118 13:27:58 INFO - PROCESS | 5339 | |12;stanford-crypto-pbkdf2;156;157;156;152;152;151;160;153;160;151 13:27:58 INFO - PROCESS | 5339 | |13;stanford-crypto-sha256-iterative;61;61;61;61;61;64;60;61;62;59 13:27:58 INFO - PROCESS | 5339 | __end_tp_report 13:27:58 INFO - PROCESS | 5339 | __start_cc_report 13:27:58 INFO - PROCESS | 5339 | _x_x_mozilla_cycle_collect,1523 13:27:58 INFO - PROCESS | 5339 | __end_cc_report 13:27:58 INFO - PROCESS | 5339 | __startTimestamp1470169678704__endTimestamp 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | ------- Summary: start ------- 13:27:58 INFO - PROCESS | 5339 | Number of tests: 14 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | [#0] ai-astar Cycles:10 Average:109.80 Median:110.50 stddev:1.93 (1.7%) stddev-sans-first:2.05 13:27:58 INFO - PROCESS | 5339 | Values: 110.0 105.0 110.0 111.0 109.0 112.0 109.0 111.0 111.0 110.0 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:144.50 stddev:19.75 (13.7%) stddev-sans-first:20.45 13:27:58 INFO - PROCESS | 5339 | Values: 155.0 125.0 132.0 169.0 124.0 143.0 146.0 131.0 180.0 124.0 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:1.35 (0.8%) stddev-sans-first:1.30 13:27:58 INFO - PROCESS | 5339 | Values: 162.0 161.0 162.0 161.0 159.0 160.0 159.0 161.0 158.0 161.0 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.00 stddev:14.64 (19.5%) stddev-sans-first:15.36 13:27:58 INFO - PROCESS | 5339 | Values: 76.0 74.0 75.0 116.0 74.0 77.0 75.0 76.0 102.0 75.0 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:84.50 stddev:16.93 (20.0%) stddev-sans-first:17.64 13:27:58 INFO - PROCESS | 5339 | Values: 86.0 86.0 111.0 84.0 85.0 85.0 119.0 84.0 84.0 127.0 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 13:27:58 INFO - PROCESS | 5339 | Values: 116.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 116.0 116.0 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 13:27:58 INFO - PROCESS | 5339 | Values: 145.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 145.0 145.0 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 13:27:58 INFO - PROCESS | 5339 | Values: 94.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.00 stddev:2.40 (4.6%) stddev-sans-first:2.52 13:27:58 INFO - PROCESS | 5339 | Values: 51.0 50.0 50.0 50.0 51.0 58.0 52.0 52.0 53.0 53.0 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.50 stddev:0.52 (0.9%) stddev-sans-first:0.50 13:27:58 INFO - PROCESS | 5339 | Values: 61.0 60.0 60.0 61.0 60.0 60.0 60.0 60.0 61.0 61.0 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:76.50 stddev:9.21 (12.0%) stddev-sans-first:9.61 13:27:58 INFO - PROCESS | 5339 | Values: 76.0 75.0 76.0 86.0 77.0 81.0 76.0 80.0 105.0 75.0 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:122.50 stddev:7.30 (6.0%) stddev-sans-first:7.74 13:27:58 INFO - PROCESS | 5339 | Values: 122.0 117.0 123.0 119.0 117.0 118.0 124.0 135.0 137.0 118.0 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.80 Median:156.00 stddev:3.49 (2.2%) stddev-sans-first:3.67 13:27:58 INFO - PROCESS | 5339 | Values: 156.0 157.0 156.0 152.0 152.0 151.0 160.0 153.0 160.0 151.0 13:27:58 INFO - PROCESS | 5339 | 13:27:58 INFO - PROCESS | 5339 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:61.00 stddev:1.29 (2.1%) stddev-sans-first:1.36 13:27:58 INFO - PROCESS | 5339 | Values: 61.0 61.0 61.0 61.0 61.0 64.0 60.0 61.0 62.0 59.0 13:27:58 INFO - PROCESS | 5339 | -------- Summary: end -------- 13:27:58 INFO - PROCESS | 5339 | 13:27:59 INFO - TEST-INFO | 5339: exit 0 13:27:59 INFO - TEST-OK | kraken | took 59893ms 13:27:59 INFO - SUITE-END | took 1171s 13:27:59 INFO - Completed test suite (00:19:31) 13:27:59 INFO - javascript benchmark 13:27:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3368.141124013376, 3377.273886114485, 3384.9153771155725, 3421.093410246284, 3428.4685873689587, 5546.584006199536, 5585.05054140796, 5585.636933599313, 5589.077802162648, 5589.357223919245, 5558.194061861028, 5615.80344687936, 5623.297087864017, 5624.775008999645, 5642.266505354298, 4892.681975671588, 4954.851354459354, 5076.593872490211, 5084.771185296652, 5128.0, 4962.826301079463, 4994.550490455858, 5038.2190760432195, 5047.242913562958, 5047.49525047496, 5056.393232812049, 5080.0, 5113.488651134894, 5130.384353877521, 5142.922856157161, 5179.093658609729, 5185.637005409623, 5186.896262074775, 5195.766190521435, 5212.609054320903, 4874.902501949977, 4939.086269040239, 4979.029089327582, 5098.4136824265315, 5118.488151184889, 5372.247885296062, 5378.273933019032, 5393.5954803389905, 5425.403205647375, 5441.211024401479, 5955.464008239236, 5970.731317090698, 5973.7311820967725, 5974.312954010301, 5997.970010149965, 5898.64608123514, 5970.1343305220935, 5979.850503737398, 6031.909521357183, 6066.8786624267705, 5608.074667679828, 5611.270534830487, 5628.324601047901, 5633.605647604711, 5635.0138725723245, 5897.528197744256, 6087.269527656709, 6093.721151878967, 6100.72546735398, 6133.509319254449, 5761.366249712528, 5866.2960444746905, 5866.853328666817, 5874.295084589877, 5893.233879595625, 5960.344362120163, 5961.225040744762, 5983.670898100646, 5987.850303742354, 5994.040953447515, 5783.768649254067, 5838.211841401357, 5952.613080149863, 5965.075413310857, 5967.8209653710455, 5893.793717219963, 5956.880862382728, 5961.344252132262, 5965.910511342333, 5998.850028749229, 4622.260438329851, 4624.1445332613575, 4625.32932724753, 4628.560286772751, 4649.465311489222, 4030.133521292936, 4033.757974521538, 4078.3678529827926, 4106.589341065869, 4147.6474499667265, 5620.18507316445, 5641.097424412074, 5652.208690783294, 5658.207850900877, 5673.914891276634, 5973.283206015306, 5981.581289309706, 5986.850328741816, 5988.700564971734, 6024.337322894478, 5555.138953462219, 5572.665640061609, 5614.157876318503, 5615.073512870413, 5641.887162256732, 5128.538431541179, 5163.096458119814, 5168.405633352138, 5170.1469257572835, 5177.197534382177, 6164.630122192682, 6213.96893015532, 6215.689215539205, 6215.81352559424, 6215.875682486325, 5838.2702162229725, 5904.143899134688, 5980.282366116094, 6020.789272375446, 6064.151018857452, 6240.313565507699, 6254.906176407449, 6255.0, 6257.749690012349, 6268.216472940882, 5713.457221563945, 5908.615939963888, 5914.201582786269, 5918.201042859159, 5946.940530594638, 5940.257467816523, 5958.910616340845, 6018.849528761816, 6063.939360606514, 6072.0, 5873.823785286278, 5913.822585322448, 5914.260717410324, 5917.437843404869, 5917.585768996301, 5672.0, 5676.403977582295, 5854.297484301911, 5900.645961242168, 5922.851928701817, 5642.915356269741, 5710.685912274698, 5712.37163911961, 5713.657180569179, 5742.799002034909, 5808.361080281081, 5811.709414529341, 5843.590948633725, 5845.444682755132, 5855.912161317498, 5599.272094627672, 5686.317641883001, 5695.45893140151, 5717.714114294352, 5743.712814359349], "value": 5507.410995088548, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.4058293164165, 639.9392057754513, 657.7664928950222, 658.7299207324992, 667.0661074495705, 34787.0, 47495.52504474954, 48288.51711482884, 48575.514244857586, 48608.51391486084, 50064.49935500644, 50514.74742626291, 50749.0, 50773.49226507734, 50796.238056429094, 8345.916540834605, 8960.910390896106, 9125.908740912571, 9126.817463650723, 10686.14510839131, 10544.156467482582, 11027.062699670538, 11031.448427578629, 11036.834447483254, 11043.061339786087, 10966.835497467544, 11011.28426652269, 11011.504482298276, 11013.504392302326, 11022.338659680445, 10963.725906852353, 10997.12023038155, 11005.394703291317, 11015.228933974626, 11018.944905275463, 10894.237403381767, 10966.0, 10977.725556861062, 10979.945100274528, 10980.505877235464, 46615.766921165516, 46927.296090558324, 47008.059838803376, 47130.29304560435, 47161.764191179165, 3703.814569800744, 3740.60723624021, 3784.1674831537016, 3813.2754776592637, 3893.7469064511, 34641.653583464344, 35289.47065794015, 35420.0, 35495.82252088723, 35676.28647427037, 3520.1727594015388, 3545.0, 3556.3598552260582, 3591.0, 3683.8710645127294, 380720.0, 384672.0766396178, 385567.9999999972, 386990.0, 387449.0, 39345.21309573793, 68959.31040689528, 69036.309636904, 69356.0, 69531.65234173796, 40737.79631101885, 42220.788896055936, 42227.36658950118, 42267.57732422698, 42279.365809512885, 17397.0, 17641.55896102582, 17653.7351939721, 17655.0, 17659.293628254727, 13661.453541858413, 14672.926635366755, 14873.851261487463, 14902.925485372503, 15155.469558565617, 9518.571664275125, 9523.428594284365, 9533.570989305497, 9574.377665451724, 9600.231981441468, 3449.862005519751, 3498.7201023918024, 3544.5392099026963, 3571.39286321326, 3574.8748793792092, 1040.666986564292, 1059.5549869055014, 1221.9816702749465, 1237.6287113865803, 1245.2777389114292, 33456.330873382394, 33856.0, 33862.49206261908, 34047.82976085104, 34465.483017754756, 1172.9853676569705, 1175.4064197580167, 1187.6140254417176, 1220.1641875315438, 1262.7790136726037, 18272.0, 18288.451346459628, 18358.81641183598, 18450.907745461187, 18496.907515462604, 599075.0, 600402.0, 601157.0, 603201.0, 603556.0, 67400.32599674039, 68035.0, 68072.65963670249, 68112.3188768106, 68301.31698683048, 1375.8692924172187, 1414.8160739104057, 1462.978055329171, 1496.9850301497063, 1516.8862335324895, 31531.68468315379, 34709.1322716924, 34893.0, 35022.82488587541, 35315.47026794651, 57463.0, 57639.135412968, 57882.421175789386, 58039.0, 58203.41796582149, 429644.0, 431842.0, 431967.0, 432039.0, 432510.0, 24910.626340604544, 27334.179974600793, 29785.5532167022, 29850.701492984794, 31584.526232106076, 49541.256881147536, 50014.74992625014, 50517.74741126271, 51266.48733512617, 51332.23001654904, 41928.0, 42039.7898010508, 42243.57756422397, 42310.57689423066, 43208.56791432171, 41340.0, 41621.37567936423, 41903.0, 42366.57633423626, 43054.569454305056, 41906.790466047474, 42446.36330455107, 42458.0, 45922.08155836798, 46820.0, 38038.61961380351, 40910.18179636331, 41159.7942010288, 41289.58710412857, 41591.37612935869, 29215.269618259714, 30243.395132096794, 30791.0, 30961.535576965915, 31084.378312434077], "value": 20728.436314528753, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.11181909357174, 436.1408026188412, 436.89514516516033, 439.03432314714104, 450.2031404414185, 19304.517387065313, 19975.200991960322, 20641.38075857725, 20756.0, 20899.0, 19885.701714474293, 20255.392338229838, 20365.083571239295, 20403.08186131624, 20415.693764593543, 5613.382527921925, 5806.622569532967, 5846.912296315538, 5857.619254748449, 5860.58975871687, 5782.306123265193, 5783.074708046714, 5859.53123750099, 5892.3812999635065, 5904.7047647617665, 5812.854678633025, 5825.854353641172, 5827.970860145693, 5830.591858569902, 5836.241288632493, 5730.627509211909, 5839.883202335929, 5843.678597677105, 5847.386024467433, 5851.912221316684, 5065.316182315377, 5082.415522214918, 5214.0, 5217.608679349064, 5218.425973142951, 23595.29214123579, 23640.0, 23926.162584309466, 23996.0, 24023.039078436843, 2497.2633073243437, 2500.3874050857657, 2557.09223225754, 2918.4746745585785, 2925.9905332660196, 14597.12417254968, 14955.177465239407, 14966.850331496655, 15046.54860354191, 15477.5356739298, 2006.56858775364, 2019.6162729081575, 2070.5858828234414, 2147.924822631216, 2189.7372315322264, 83637.58181209055, 85604.57197713971, 86238.0, 87236.12763872344, 87595.56202219012, 10987.230893837355, 11283.605073822377, 11450.885491145149, 11560.710982225344, 11657.300561966309, 16339.754903676454, 16387.83612163887, 16447.177641117898, 16477.258523366476, 16503.91748041252, 9029.51013082834, 9199.77000574991, 9200.125988031126, 9303.209227215628, 9365.203957663538, 9050.954745226232, 9052.094790520896, 9128.863067053999, 9223.53882305882, 9252.074792520694, 6942.895856562156, 7132.607706576085, 7143.571385716874, 7145.6784444700115, 7163.176234732969, 2063.2572273981355, 2069.3274685727197, 2079.6048750737614, 2097.643400621898, 2144.2066435418683, 972.4457059476172, 1048.9318194317345, 1061.2200032975757, 1102.9283096598695, 1112.6217086190572, 20021.199152034045, 20225.393238202876, 20645.690314645293, 20656.483587910123, 20744.377668669964, 1026.553449249564, 1038.9948050259852, 1060.9098226650667, 1065.706930594093, 1159.5999380213814, 11055.0, 11784.351860647575, 11880.524779008743, 12010.819837702442, 12032.097592680584, 505108.0, 533825.0, 538406.0, 554837.0, 576566.0, 88068.0, 89013.55493222493, 89898.55050724704, 90220.0, 90439.0, 1075.2097208551709, 1086.4676308608882, 1101.2291396022656, 1115.9497822598003, 1127.2165844737992, 18040.2783888643, 18199.727004095213, 18429.26282948721, 18458.630827383644, 18794.342198023005, 40226.79886600548, 40444.393334099426, 41164.382534261415, 41402.1719565613, 41885.0, 61305.0, 63067.0, 66210.33789662042, 66704.6664766673, 67331.0, 8378.622961966848, 8606.354523410895, 8681.91318086811, 8988.775280618036, 9115.0, 13435.932820335836, 13569.86430135686, 13623.978201634718, 13832.86167138356, 13882.583522494342, 11782.410879456163, 11855.288682679067, 11856.822147667619, 12015.0, 12100.697482563006, 11030.558777649125, 11948.701282468008, 12030.458629361874, 12032.639020829389, 12097.75804483923, 11431.885681143414, 11558.653240402802, 11712.70718232051, 11775.17573769862, 12059.15585908978, 8612.612432440681, 8854.601542930455, 8962.775930601787, 9041.32190085733, 9065.546722663708, 11749.177557570887, 12177.330246836154, 12356.443960021641, 12364.814527781911, 12388.56640017595], "value": 9887.370402375805, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.080178285007, 1591.8726501879846, 1627.8779091568133, 1820.2991848138463, 1946.7274581558584, 8562.272206862417, 8808.383413161073, 8818.91181088189, 8893.021767605558, 9043.593038313275, 8555.743327700164, 8855.114488551142, 9052.0, 9085.863712044325, 9102.453852768838, 2428.8056955443567, 2473.9257822265363, 2475.6781618389673, 2478.5166892455977, 2482.5407299649532, 2343.8359314847967, 2411.6985376827897, 2416.2388847513066, 2416.4200591857916, 2419.2258477287273, 2355.9057637694473, 2397.9040838366445, 2399.1962692497946, 2400.0879665726998, 2409.6144616861307, 2327.394877331889, 2332.265336419023, 2347.7182738071456, 2355.4346956730346, 2367.4791545859885, 2300.1144559344607, 2352.588297048027, 2358.0449917783326, 2366.9644955325684, 2377.239283429303, 7804.414668899855, 8473.70342038032, 9025.684101056431, 9028.774280643036, 9044.77388065297, 5408.91886621701, 5606.719664016783, 5610.495055444989, 5611.831645050655, 5617.775288988436, 3397.779144355609, 3415.7096646785053, 3484.790912545255, 3492.2142517933517, 3504.0889368764306, 1065.5471424644495, 1077.6928575355985, 1080.4057768227522, 1084.9294795838246, 1098.8846171152074, 12431.191972521847, 12488.750224995449, 12505.74988500234, 12531.122821402505, 12590.24458532491, 4756.4881611637975, 4791.496892826203, 4878.975605121952, 4897.0450762101045, 4921.680090794087, 10651.254412191227, 10759.892401076046, 10821.350718956888, 10859.239853210358, 10869.891301086887, 5585.0, 5595.300587426572, 5596.27248457706, 5597.8600534986135, 5604.271444712243, 4997.075541024922, 5037.319961805183, 5093.1087059764395, 5143.048536020848, 5172.586193104543, 4051.331530297527, 4069.7354671946227, 4081.510218773766, 4118.670506359484, 4145.357469592218, 1241.7268200995766, 1264.5131624324702, 1266.2402558464848, 1280.1614942212866, 1287.658770425838, 938.6949241496404, 972.2416515118219, 975.7267964969819, 980.1668581705493, 995.5548476054307, 5902.4097590240635, 5947.762089516457, 6031.5476339273855, 6306.180196574416, 6434.935650643527, 1019.7807471393686, 1057.1754031855164, 1068.5672302717412, 1069.7539565899888, 1069.812782763029, 4432.84485043022, 4524.954750452519, 4534.750588717587, 4550.7497087660495, 4600.102979918951, 10080.59677612902, 10456.947715261527, 10889.83665245022, 11187.160962927814, 11237.3257604544, 10813.945930270298, 11240.04459620925, 11411.942940285246, 11447.656570302905, 11471.827922581168, 8469.74590762278, 8577.01364343096, 8605.569721513899, 8635.740927772176, 8755.430896991675, 6404.647744373918, 6409.327020662763, 6437.25971513282, 6546.476281897532, 6551.44312733423, 10465.633702820365, 10527.368357898551, 10563.419011954109, 10563.788724225626, 10653.840192396965, 10739.731506712082, 10856.294340867818, 10859.782804344028, 10973.122150228122, 11078.944605276922, 7795.298423141944, 7832.099308579358, 7872.763817085496, 7881.290683838482, 7894.960525197337, 8854.380193386402, 8911.37620366568, 8949.731508054769, 8952.820943580962, 9032.232260257952, 7698.230176982257, 7762.5730584818375, 7869.921300786918, 7898.526088434713, 7991.400644951768, 7796.61016949139, 7850.646720897459, 7872.84254314899, 7958.721444749406, 8199.385046121446, 7544.245575442412, 7585.6965721370525, 7622.2758837910305, 7649.235076492529, 7653.57905315213, 5144.382674079135, 5205.5835533158015, 5263.210518422267, 5335.653182543278, 5404.189371594292, 5224.686518808883, 5278.4727645329785, 5304.734763261744, 5453.263809385682, 5525.336959564878], "value": 4816.698465713344, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.3839271010593, 335.42307231561733, 336.53271735234387, 353.9752217344785, 354.9449835275532, 5353.544948679357, 5452.291202143726, 5498.917516237255, 5522.9171562426545, 5568.554515638749, 5420.566354691625, 5440.510354068133, 5458.9454105459035, 5467.890642187154, 5509.393966663663, 4335.328024156253, 4355.0, 4366.519682834885, 4382.824687012531, 4422.226110430662, 4192.67306644812, 4272.50866150392, 4315.568443155691, 4369.431973843396, 4371.453568303962, 4202.453681021463, 4203.621674049335, 4247.277962746325, 4277.443932288798, 4281.422008028923, 4224.788760561975, 4225.3169237534075, 4252.107057517894, 4254.87235382939, 4297.570242975708, 3618.0, 3729.4965179700666, 3733.925321493582, 3738.495303134069, 3741.494898188737, 5288.312519372495, 5288.947110528884, 5295.576353891679, 5309.867253318698, 5321.946780532184, 4838.661293709441, 4850.781714822806, 4877.975610121962, 4886.828960986349, 4897.853064408074, 5534.4742249486235, 5816.331121920991, 5829.883402331972, 5830.318053695305, 5833.154192642086, 4114.280000999813, 4125.855595054189, 4130.194612050651, 4133.656561617457, 4137.296659567851, 5817.709114544298, 5963.4334738199805, 5990.430909063632, 5999.76000959961, 6007.828473447679, 4168.124693814272, 4170.603792639695, 4171.70798044134, 4171.937420938689, 4172.248995180897, 4234.322508398642, 4248.086661367821, 4250.639795265485, 4250.851220207278, 4252.893677658021, 4851.611871050308, 4863.173260545645, 4865.1242776300605, 4866.7079975201605, 4886.193778026586, 5509.449055094458, 5722.77108915647, 5753.108268218432, 5774.7478483391515, 5782.132680098019, 1403.9789403158961, 1447.9203643799692, 1450.717110163529, 1460.5545308680955, 1470.955871323862, 880.2078129683337, 896.7040876510736, 900.9054049324726, 901.0358915959991, 905.3979103895967, 4878.682885612424, 4934.4572097069295, 4943.530364615356, 4957.0, 4965.230389289666, 5173.767180476888, 5203.505666961632, 5207.6875387476875, 5207.739613019334, 5240.454066050448, 4687.460941991646, 4748.572628463455, 4882.926756098661, 4943.887625284282, 4994.725290109079, 2742.986285068602, 2751.4866823247135, 2762.309422644339, 2762.668479782439, 2808.9016884409357, 5335.519803217729, 5383.973080134574, 5396.649217800909, 5453.263809385682, 5499.202615620713, 4890.535399137148, 5250.212468129811, 5254.738862672931, 5386.8383948481605, 5405.540529055074, 5405.8107966221, 5427.855996881289, 5436.782528698887, 5436.891262174734, 5464.562834973192, 5201.973990130025, 5202.765875535534, 5216.452272511484, 5251.474852514718, 5263.315768950012, 5156.0, 5468.753906074316, 5893.675847828411, 5898.0, 5913.852153696192, 6065.514758819196, 6149.200603921461, 6161.661108639068, 6163.414475624809, 6180.381961803783, 4022.919541609093, 4278.251306021496, 4307.784610769511, 4308.935365969575, 4335.393044973644, 5661.320641523043, 5866.50134738535, 5875.236219291465, 5886.969780288347, 5905.232319798399, 5630.408807075198, 5733.853229354229, 5809.448102430262, 5820.30156381237, 5829.47534721877, 5761.366249712444, 5772.567057470623, 5792.565557583114, 5874.148248504029, 5876.911846322223, 5598.3282006159525, 5667.773289068391, 5676.318841739017, 5686.886262274814, 5700.971495142498, 5751.482366587027, 5772.7690892363835, 5785.768569257351, 5825.737841797045, 5835.270591176103, 5490.5882058844945, 5638.577106716931, 5672.177534257592, 5678.432156784289, 5705.258316418839], "value": 4257.790643653126, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.1262615921654, 752.3647877398289, 870.799716065305, 880.8634661627448, 923.2936803345442, 15045.774313385307, 15459.61350966225, 15682.84317156828, 15804.44684436045, 15808.762868556982, 14120.152790832557, 14700.55898323052, 15301.69396612067, 15351.462698805544, 15733.527994160166, 741.7626359564941, 865.1694373401542, 903.9548022598876, 904.1004200820176, 907.6278725722456, 892.6474042753109, 897.3001059173855, 901.6528636474939, 903.7830920579047, 904.6743172457943, 946.9914541013833, 948.5684013773716, 952.8570714392862, 953.1897886796203, 953.2183609440261, 660.0791895306045, 915.0986278515657, 941.6327632223438, 945.3240932733095, 945.7966537194502, 690.4303949241895, 692.6986760759036, 696.9163700355939, 697.6965020216222, 697.7348607529157, 14836.035657682216, 14864.0, 14912.105273683614, 14912.850871491362, 14912.850871491362, 14502.927485362612, 14873.628159295997, 14965.102093874402, 14973.401063957428, 15078.246087695572, 13563.0, 13738.450461981509, 13776.586702398945, 13779.311034448338, 13929.373178207004, 4598.793054312564, 4601.171789077965, 4663.580277775017, 4682.29765535169, 4789.090072886141, 80315.0, 81416.0, 81624.59187704083, 81834.0, 82058.0, 11438.027767639678, 11574.18980671345, 11582.47878845454, 11597.826032609517, 11697.76604467906, 21444.57110857774, 22204.111835526397, 22282.442938926655, 23285.18501852427, 23362.415939601648, 8757.211850933447, 8898.777530561658, 8933.46399216049, 9016.459012459274, 9174.220191283815, 4809.44691360498, 4811.230203167546, 4813.253945638432, 4830.589399901048, 4908.11653902301, 3983.50206224222, 4110.547839737626, 4112.09533902541, 4113.03343714227, 4117.444145040441, 641.3842710997494, 669.7220653428773, 675.8952362383839, 697.8534507753327, 718.0844423360153, 676.4385559985201, 678.5962352400314, 698.9440844732409, 741.7885902517756, 752.9360004399578, 9989.750256243507, 10277.588896444058, 10350.275480716427, 10479.84280235797, 10593.364398136137, 670.2928410526941, 764.6215123513896, 773.0535615681976, 788.7969119440061, 792.6116203060574, 4774.641901857374, 4838.612910967114, 4942.184539550934, 4942.258661200849, 4982.551570358685, 79693.60153199197, 85131.57434212789, 131856.0, 137147.31426343005, 138201.0, 79123.20876791158, 80423.39153216903, 113284.0, 113713.0, 114254.42872785583, 97062.51468742611, 97232.51383743178, 98594.0, 99446.0, 100639.49680251698, 9338.579763910777, 9355.906440935503, 9366.718998430058, 9439.669611563562, 9558.426494410358, 58434.7078264623, 58992.41007589869, 59913.700431497564, 61175.0, 61382.0, 86231.13768862482, 87120.0, 88231.0, 88253.1174688245, 91441.0, 11254.21220514556, 11753.706157346134, 11968.700782480508, 12030.15788894769, 12424.440900159696, 13018.80471792905, 13245.602631921058, 13402.06185567001, 13515.0, 13689.589312320646, 9051.749985008812, 12603.621891343275, 13061.477540898257, 13147.0, 13606.0, 12877.0, 13442.529511467052, 13443.798343025059, 13469.259190744604, 13529.458821647024, 12820.487180513048, 12873.678158046123, 12917.22496650204, 13186.142900711424, 13341.266230357423, 11131.609486118732, 11232.7753444929, 11381.0186640327, 11440.599579014695, 11641.301521908712, 10948.34309941406, 11079.778404431705, 11285.0, 11388.316700997966, 11500.194986350874], "value": 7909.010625407556, "unit": "score"}], "name": "dromaeo_css", "value": 7535.5674096269695, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 110.0, 111.0, 109.0, 112.0, 109.0, 111.0, 111.0, 110.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 125.0, 132.0, 169.0, 124.0, 143.0, 146.0, 131.0, 180.0, 124.0], "value": 142.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 161.0, 159.0, 160.0, 159.0, 161.0, 158.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 75.0, 116.0, 74.0, 77.0, 75.0, 76.0, 102.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 111.0, 84.0, 85.0, 85.0, 119.0, 84.0, 84.0, 127.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 50.0, 51.0, 58.0, 52.0, 52.0, 53.0, 53.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0, 61.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 86.0, 77.0, 81.0, 76.0, 80.0, 105.0, 75.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 117.0, 123.0, 119.0, 117.0, 118.0, 124.0, 135.0, 137.0, 118.0], "value": 123.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 157.0, 156.0, 152.0, 152.0, 151.0, 160.0, 153.0, 160.0, 151.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 61.0, 64.0, 60.0, 61.0, 62.0, 59.0], "value": 61.1, "unit": "score"}], "name": "kraken", "value": 1479.1, "alertThreshold": 2.0}]} 13:27:59 INFO - Return code: 0 13:27:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:28:00 ERROR - # TBPL SUCCESS # 13:28:00 INFO - Running post-action listener: _resource_record_post_action 13:28:00 INFO - ##### 13:28:00 INFO - ##### Finished run-tests step (success) 13:28:00 INFO - ##### 13:28:00 INFO - Running post-run listener: _resource_record_post_run 13:28:00 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 684032; Write bytes: 226820096; Read time: 144; Write time: 107544 13:28:00 INFO - TinderboxPrint: CPU usage
13.1% 13:28:00 INFO - TinderboxPrint: I/O read bytes / time
684,032 / 144 13:28:00 INFO - TinderboxPrint: I/O write bytes / time
226,820,096 / 107,544 13:28:00 INFO - TinderboxPrint: CPU idle
8,229.4 (86.9%) 13:28:00 INFO - TinderboxPrint: CPU user
1,195.8 (12.6%) 13:28:00 INFO - TinderboxPrint: Swap in / out
0 / 0 13:28:00 INFO - install - Wall time: 13s; CPU: 13.0%; Read bytes: 0; Write bytes: 7999488; Read time: 0; Write time: 1188 13:28:00 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 659456; Write bytes: 218820608; Read time: 124; Write time: 106356 13:28:00 INFO - Running post-run listener: _upload_blobber_files 13:28:00 INFO - Blob upload gear active. 13:28:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:28:00 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 13:28:00 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'] 13:28:00 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 13:28:01 INFO - (blobuploader) - INFO - Open directory for files ... 13:28:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:28:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:28:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:28:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:28:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:28:02 INFO - (blobuploader) - INFO - Done attempting. 13:28:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:28:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:28:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:28:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:28:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:28:07 INFO - (blobuploader) - INFO - Done attempting. 13:28:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:28:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:28:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:28:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:28:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:28:08 INFO - (blobuploader) - INFO - Done attempting. 13:28:08 INFO - (blobuploader) - INFO - Iteration through files over. 13:28:08 INFO - Return code: 0 13:28:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:28:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:28:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/146ce1205e239c431d6b42121eebdc8369d7976bf941367867812bf032a4a695aaeae32b088e27465feef42012766628924f6a20f14b26bb3845814413e6867a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b578075af7d481315885868b48c4ac3b2efe4df388eb36c0c44cc81b578cfa6b80e1ddc0f31aed60728e94914e01e883603153df991fc7e331e1d37e505e40b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a0e263817dde2b1af27b3d9074e59fedcc0c9872691f50408b78d140e3028481d430152e4c7856dc9226f0b09e6b82eecde420ce8a0a385a0eda89b5a1576567"} 13:28:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:28:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:28:08 INFO - Contents: 13:28:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/146ce1205e239c431d6b42121eebdc8369d7976bf941367867812bf032a4a695aaeae32b088e27465feef42012766628924f6a20f14b26bb3845814413e6867a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b578075af7d481315885868b48c4ac3b2efe4df388eb36c0c44cc81b578cfa6b80e1ddc0f31aed60728e94914e01e883603153df991fc7e331e1d37e505e40b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a0e263817dde2b1af27b3d9074e59fedcc0c9872691f50408b78d140e3028481d430152e4c7856dc9226f0b09e6b82eecde420ce8a0a385a0eda89b5a1576567"} 13:28:08 INFO - Running post-run listener: copy_logs_to_upload_dir 13:28:08 INFO - Copying logs to upload dir... 13:28:08 INFO - mkdir: /builds/slave/test/build/upload/logs 13:28:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1228.739037 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2016-08-02 13:28:08.175133) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 13:28:08.178860) ========= 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=00204705a9daa57ed339a427000001b9-1470168456.341222-796203241 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/146ce1205e239c431d6b42121eebdc8369d7976bf941367867812bf032a4a695aaeae32b088e27465feef42012766628924f6a20f14b26bb3845814413e6867a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b578075af7d481315885868b48c4ac3b2efe4df388eb36c0c44cc81b578cfa6b80e1ddc0f31aed60728e94914e01e883603153df991fc7e331e1d37e505e40b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a0e263817dde2b1af27b3d9074e59fedcc0c9872691f50408b78d140e3028481d430152e4c7856dc9226f0b09e6b82eecde420ce8a0a385a0eda89b5a1576567"} build_url:https://queue.taskcluster.net/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011220 build_url: 'https://queue.taskcluster.net/v1/task/TyxvaCHUSeSSydd9AtgB3g/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/146ce1205e239c431d6b42121eebdc8369d7976bf941367867812bf032a4a695aaeae32b088e27465feef42012766628924f6a20f14b26bb3845814413e6867a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b578075af7d481315885868b48c4ac3b2efe4df388eb36c0c44cc81b578cfa6b80e1ddc0f31aed60728e94914e01e883603153df991fc7e331e1d37e505e40b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a0e263817dde2b1af27b3d9074e59fedcc0c9872691f50408b78d140e3028481d430152e4c7856dc9226f0b09e6b82eecde420ce8a0a385a0eda89b5a1576567"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 13:28:08.206212) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 13:28:08.206578) ========= 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=00204705a9daa57ed339a427000001b9-1470168456.341222-796203241 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004360 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 13:28:08.289586) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 13:28:08.289960) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 13:28:08.290400) ========= ========= Total master_lag: 0.18 =========