builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1469851017.45 results: success (0) buildid: 20160729182925 builduid: 2dd017203036455b9f594a3790630745 revision: 8847dd9e9a2da7a4ad074ab97e085295e50746c3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-29 20:56:57.445723) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-29 20:56:57.446151) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-29 20:56:57.446455) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-29 20:56:57.467263) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 20:56:57.467577) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1469849280.534460-1284470387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-29 20:56:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 24.4M=0s 2016-07-29 20:56:57 (24.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106616 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 20:56:57.588989) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 20:56:57.589311) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1469849280.534460-1284470387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019614 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 20:56:57.638904) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-29 20:56:57.639244) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8847dd9e9a2da7a4ad074ab97e085295e50746c3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8847dd9e9a2da7a4ad074ab97e085295e50746c3 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1469849280.534460-1284470387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-29 20:56:57,824 truncating revision to first 12 chars 2016-07-29 20:56:57,824 Setting DEBUG logging. 2016-07-29 20:56:57,824 attempt 1/10 2016-07-29 20:56:57,824 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8847dd9e9a2d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-29 20:56:58,845 unpacking tar archive at: mozilla-aurora-8847dd9e9a2d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.341761 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-29 20:56:58.994937) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-29 20:56:58.995236) ========= script_repo_revision: 8847dd9e9a2da7a4ad074ab97e085295e50746c3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-29 20:56:58.995818) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-29 20:56:58.996291) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-29 20:56:59.006613) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-07-29 20:56:59.006948) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1469849280.534460-1284470387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:56:59 INFO - MultiFileLogger online at 20160729 20:56:59 in /builds/slave/test 20:56:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 20:56:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:56:59 INFO - {'append_to_log': False, 20:56:59 INFO - 'base_work_dir': '/builds/slave/test', 20:56:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:56:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:56:59 INFO - 'branch': 'Mozilla-Aurora', 20:56:59 INFO - 'buildbot_json_path': 'buildprops.json', 20:56:59 INFO - 'config_files': ('talos/linux_config.py',), 20:56:59 INFO - 'default_actions': ('clobber', 20:56:59 INFO - 'read-buildbot-config', 20:56:59 INFO - 'download-and-extract', 20:56:59 INFO - 'populate-webroot', 20:56:59 INFO - 'create-virtualenv', 20:56:59 INFO - 'install', 20:56:59 INFO - 'run-tests'), 20:56:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:56:59 INFO - 'download_minidump_stackwalk': True, 20:56:59 INFO - 'download_symbols': 'ondemand', 20:56:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:56:59 INFO - 'tooltool.py': '/tools/tooltool.py', 20:56:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:56:59 INFO - '/tools/misc-python/virtualenv.py')}, 20:56:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:56:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:56:59 INFO - 'installer_path': 'installer.exe', 20:56:59 INFO - 'log_level': 'info', 20:56:59 INFO - 'log_name': 'talos', 20:56:59 INFO - 'log_to_console': True, 20:56:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:56:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:56:59 INFO - 'opt_config_files': (), 20:56:59 INFO - 'pip_index': False, 20:56:59 INFO - 'sps_profile': False, 20:56:59 INFO - 'sps_profile_interval': 0, 20:56:59 INFO - 'suite': 'dromaeojs', 20:56:59 INFO - 'system_bits': '32', 20:56:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:56:59 INFO - 'title': 'talos-linux64-ix-003', 20:56:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:56:59 INFO - 'use_talos_json': True, 20:56:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:56:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:56:59 INFO - 'work_dir': 'build'} 20:56:59 INFO - ##### 20:56:59 INFO - ##### Running clobber step. 20:56:59 INFO - ##### 20:56:59 INFO - Running pre-action listener: _resource_record_pre_action 20:56:59 INFO - Running main action method: clobber 20:56:59 INFO - rmtree: /builds/slave/test/build 20:56:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:57:00 INFO - Running post-action listener: _resource_record_post_action 20:57:00 INFO - ##### 20:57:00 INFO - ##### Finished clobber step (success) 20:57:00 INFO - ##### 20:57:00 INFO - ##### 20:57:00 INFO - ##### Running read-buildbot-config step. 20:57:00 INFO - ##### 20:57:00 INFO - Running pre-action listener: _resource_record_pre_action 20:57:00 INFO - Running main action method: read_buildbot_config 20:57:00 INFO - Using buildbot properties: 20:57:00 INFO - { 20:57:00 INFO - "project": "", 20:57:00 INFO - "product": "firefox", 20:57:00 INFO - "script_repo_revision": "production", 20:57:00 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 20:57:00 INFO - "repository": "", 20:57:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 20:57:00 INFO - "buildid": "20160729182925", 20:57:00 INFO - "pgo_build": "True", 20:57:00 INFO - "basedir": "/builds/slave/test", 20:57:00 INFO - "buildnumber": 644, 20:57:00 INFO - "slavename": "talos-linux64-ix-003", 20:57:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 20:57:00 INFO - "platform": "ubuntu64_hw", 20:57:00 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 20:57:00 INFO - "branch": "mozilla-aurora", 20:57:00 INFO - "repo_path": "releases/mozilla-aurora", 20:57:00 INFO - "revision": "8847dd9e9a2da7a4ad074ab97e085295e50746c3", 20:57:00 INFO - "stage_platform": "linux64-pgo", 20:57:00 INFO - "builduid": "2dd017203036455b9f594a3790630745", 20:57:00 INFO - "slavebuilddir": "test" 20:57:00 INFO - } 20:57:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 20:57:00 INFO - Running post-action listener: _resource_record_post_action 20:57:00 INFO - ##### 20:57:00 INFO - ##### Finished read-buildbot-config step (success) 20:57:00 INFO - ##### 20:57:00 INFO - ##### 20:57:00 INFO - ##### Running download-and-extract step. 20:57:00 INFO - ##### 20:57:00 INFO - Running pre-action listener: _resource_record_pre_action 20:57:00 INFO - Running main action method: download_and_extract 20:57:00 INFO - mkdir: /builds/slave/test/build/tests 20:57:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:00 INFO - https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 20:57:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 20:57:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 20:57:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 20:57:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 20:57:01 INFO - Downloaded 1361 bytes. 20:57:01 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 20:57:01 INFO - Using the following test package requirements: 20:57:01 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 20:57:01 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 20:57:01 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 20:57:01 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 20:57:01 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 20:57:01 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 20:57:01 INFO - u'jsshell-linux-x86_64.zip'], 20:57:01 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 20:57:01 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 20:57:01 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 20:57:01 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 20:57:01 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 20:57:01 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 20:57:01 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 20:57:01 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 20:57:01 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 20:57:01 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 20:57:01 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 20:57:01 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:57:01 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')]} 20:57:01 INFO - https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:57:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 20:57:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 20:57:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 20:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:57:02 INFO - Downloaded 24495819 bytes. 20:57:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:57:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:57:04 INFO - Return code: 0 20:57:04 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 20:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:04 INFO - https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:57:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 20:57:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 20:57:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 20:57:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:57:06 INFO - Downloaded 24495819 bytes. 20:57:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:57:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:57:07 INFO - Return code: 0 20:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:07 INFO - https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:57:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 20:57:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 20:57:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 20:57:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 20:57:08 INFO - Downloaded 10833369 bytes. 20:57:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:57:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 20:57:08 INFO - Return code: 0 20:57:08 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')]} 20:57:08 INFO - https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:57:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 20:57:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 20:57:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 20:57:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 20:57:10 INFO - Downloaded 63641382 bytes. 20:57:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 20:57:10 INFO - mkdir: /builds/slave/test/properties 20:57:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:57:10 INFO - Writing to file /builds/slave/test/properties/build_url 20:57:10 INFO - Contents: 20:57:10 INFO - build_url:https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 20:57:10 INFO - Running post-action listener: _resource_record_post_action 20:57:10 INFO - Running post-action listener: set_extra_try_arguments 20:57:10 INFO - ##### 20:57:10 INFO - ##### Finished download-and-extract step (success) 20:57:10 INFO - ##### 20:57:10 INFO - ##### 20:57:10 INFO - ##### Running populate-webroot step. 20:57:10 INFO - ##### 20:57:10 INFO - Running pre-action listener: _resource_record_pre_action 20:57:10 INFO - Running main action method: populate_webroot 20:57:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:57:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:57:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:57:10 INFO - '--tppagecycles', 20:57:10 INFO - '7'], 20:57:10 INFO - 'tests': ['tsvgm']}}, 20:57:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:57:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:57:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:57:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:57:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:57:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:57:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:10 INFO - 'talos_options': ['--disable-e10s'], 20:57:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:57:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:57:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:10 INFO - 'talos_options': ['--disable-e10s'], 20:57:10 INFO - 'tests': ['damp', 'tps']}, 20:57:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:10 INFO - 'tests': ['damp', 'tps']}, 20:57:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:57:10 INFO - 'tests': ['dromaeo_dom']}, 20:57:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:57:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:57:10 INFO - 'tests': ['a11yr', 20:57:10 INFO - 'ts_paint', 20:57:10 INFO - 'tpaint', 20:57:10 INFO - 'sessionrestore', 20:57:10 INFO - 'sessionrestore_no_auto_restore', 20:57:10 INFO - 'tabpaint']}, 20:57:10 INFO - 'other-e10s': {'tests': ['a11yr', 20:57:10 INFO - 'ts_paint', 20:57:10 INFO - 'tpaint', 20:57:10 INFO - 'sessionrestore', 20:57:10 INFO - 'sessionrestore_no_auto_restore', 20:57:10 INFO - 'tabpaint']}, 20:57:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:57:10 INFO - 'ts_paint', 20:57:10 INFO - 'tpaint', 20:57:10 INFO - 'sessionrestore', 20:57:10 INFO - 'sessionrestore_no_auto_restore', 20:57:10 INFO - 'tabpaint']}, 20:57:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:57:10 INFO - 'ts_paint', 20:57:10 INFO - 'tpaint', 20:57:10 INFO - 'sessionrestore', 20:57:10 INFO - 'sessionrestore_no_auto_restore', 20:57:10 INFO - 'tabpaint']}, 20:57:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:57:10 INFO - 'tests': ['a11yr', 20:57:10 INFO - 'ts_paint', 20:57:10 INFO - 'tpaint', 20:57:10 INFO - 'sessionrestore', 20:57:10 INFO - 'sessionrestore_no_auto_restore', 20:57:10 INFO - 'tabpaint']}, 20:57:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:57:10 INFO - 'tests': ['a11yr', 20:57:10 INFO - 'ts_paint', 20:57:10 INFO - 'tpaint', 20:57:10 INFO - 'sessionrestore', 20:57:10 INFO - 'sessionrestore_no_auto_restore', 20:57:10 INFO - 'tabpaint']}, 20:57:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:57:10 INFO - 'tests': ['tsvgx', 20:57:10 INFO - 'tsvgr_opacity', 20:57:10 INFO - 'tart', 20:57:10 INFO - 'tscrollx', 20:57:10 INFO - 'cart']}, 20:57:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:57:10 INFO - 'tsvgr_opacity', 20:57:10 INFO - 'tart', 20:57:10 INFO - 'tscrollx', 20:57:10 INFO - 'cart']}, 20:57:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:10 INFO - 'talos_options': ['--disable-e10s'], 20:57:10 INFO - 'tests': ['tp5o']}, 20:57:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:10 INFO - 'tests': ['tp5o']}, 20:57:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:10 INFO - 'talos_options': ['--disable-e10s', 20:57:10 INFO - '--xperf_path', 20:57:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:57:10 INFO - 'tests': ['tp5n']}, 20:57:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:10 INFO - 'talos_options': ['--xperf_path', 20:57:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:57:10 INFO - 'tests': ['tp5n']}}, 20:57:10 INFO - 'talos.zip': {'path': '', 20:57:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:57:10 INFO - Running post-action listener: _resource_record_post_action 20:57:10 INFO - ##### 20:57:10 INFO - ##### Finished populate-webroot step (success) 20:57:10 INFO - ##### 20:57:10 INFO - ##### 20:57:10 INFO - ##### Running create-virtualenv step. 20:57:10 INFO - ##### 20:57:10 INFO - Running pre-action listener: _resource_record_pre_action 20:57:10 INFO - Running main action method: create_virtualenv 20:57:10 INFO - Creating virtualenv /builds/slave/test/build/venv 20:57:10 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 20:57:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:57:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:57:10 INFO - Using real prefix '/usr' 20:57:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:57:11 INFO - Installing distribute.............................................................................................................................................................................................done. 20:57:13 INFO - Installing pip.................done. 20:57:13 INFO - Return code: 0 20:57:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:57:13 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')]} 20:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:13 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')]} 20:57:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:13 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 0x7fbb8532ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15be1c0>, '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 0x15bf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bf550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', '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 20:57:13 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 20:57:13 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 20:57:13 INFO - Using env: {'DISPLAY': ':0', 20:57:13 INFO - 'HOME': '/home/cltbld', 20:57:13 INFO - 'LANG': 'en_US.UTF-8', 20:57:13 INFO - 'LANGUAGE': 'en_US:en', 20:57:13 INFO - 'LOGNAME': 'cltbld', 20:57:13 INFO - 'MAIL': '/var/mail/cltbld', 20:57:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:13 INFO - 'MOZ_NO_REMOTE': '1', 20:57:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:13 INFO - 'NO_EM_RESTART': '1', 20:57:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:13 INFO - 'PWD': '/builds/slave/test', 20:57:13 INFO - 'SHELL': '/bin/bash', 20:57:13 INFO - 'SHLVL': '1', 20:57:13 INFO - 'TERM': 'linux', 20:57:13 INFO - 'TMOUT': '86400', 20:57:13 INFO - 'USER': 'cltbld', 20:57:13 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', 20:57:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:13 INFO - '_': '/tools/buildbot/bin/python'} 20:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:13 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 20:57:13 INFO - Cleaning up... 20:57:13 INFO - Return code: 0 20:57:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:57:13 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')]} 20:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:13 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')]} 20:57:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:13 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 0x7fbb8532ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15be1c0>, '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 0x15bf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bf550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', '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 20:57:13 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 20:57:13 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 20:57:13 INFO - Using env: {'DISPLAY': ':0', 20:57:13 INFO - 'HOME': '/home/cltbld', 20:57:13 INFO - 'LANG': 'en_US.UTF-8', 20:57:13 INFO - 'LANGUAGE': 'en_US:en', 20:57:13 INFO - 'LOGNAME': 'cltbld', 20:57:13 INFO - 'MAIL': '/var/mail/cltbld', 20:57:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:13 INFO - 'MOZ_NO_REMOTE': '1', 20:57:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:13 INFO - 'NO_EM_RESTART': '1', 20:57:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:13 INFO - 'PWD': '/builds/slave/test', 20:57:13 INFO - 'SHELL': '/bin/bash', 20:57:13 INFO - 'SHLVL': '1', 20:57:13 INFO - 'TERM': 'linux', 20:57:13 INFO - 'TMOUT': '86400', 20:57:13 INFO - 'USER': 'cltbld', 20:57:13 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', 20:57:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:13 INFO - '_': '/tools/buildbot/bin/python'} 20:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:13 INFO - Downloading/unpacking psutil>=3.1.1 20:57:13 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 20:57:13 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 20:57:13 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 20:57:13 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 20:57:13 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 20:57:13 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 20:57:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:57:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:57:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:57:15 INFO - Installing collected packages: psutil 20:57:15 INFO - Running setup.py install for psutil 20:57:15 INFO - building 'psutil._psutil_linux' extension 20:57:15 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 20:57:16 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 20:57:16 INFO - building 'psutil._psutil_posix' extension 20:57:16 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 20:57:16 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 20:57:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:57:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:57:16 INFO - Successfully installed psutil 20:57:16 INFO - Cleaning up... 20:57:16 INFO - Return code: 0 20:57:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:57:16 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')]} 20:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:16 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')]} 20:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:16 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 0x7fbb8532ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15be1c0>, '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 0x15bf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bf550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', '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 20:57:16 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 20:57:16 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 20:57:16 INFO - Using env: {'DISPLAY': ':0', 20:57:16 INFO - 'HOME': '/home/cltbld', 20:57:16 INFO - 'LANG': 'en_US.UTF-8', 20:57:16 INFO - 'LANGUAGE': 'en_US:en', 20:57:16 INFO - 'LOGNAME': 'cltbld', 20:57:16 INFO - 'MAIL': '/var/mail/cltbld', 20:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:16 INFO - 'MOZ_NO_REMOTE': '1', 20:57:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:16 INFO - 'NO_EM_RESTART': '1', 20:57:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:16 INFO - 'PWD': '/builds/slave/test', 20:57:16 INFO - 'SHELL': '/bin/bash', 20:57:16 INFO - 'SHLVL': '1', 20:57:16 INFO - 'TERM': 'linux', 20:57:16 INFO - 'TMOUT': '86400', 20:57:16 INFO - 'USER': 'cltbld', 20:57:16 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', 20:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:16 INFO - '_': '/tools/buildbot/bin/python'} 20:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:57:16 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 20:57:16 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 20:57:16 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 20:57:16 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 20:57:16 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 20:57:16 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 20:57:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:57:18 INFO - Installing collected packages: mozsystemmonitor 20:57:18 INFO - Running setup.py install for mozsystemmonitor 20:57:18 INFO - Successfully installed mozsystemmonitor 20:57:18 INFO - Cleaning up... 20:57:18 INFO - Return code: 0 20:57:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:18 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 0x7fbb8532ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15be1c0>, '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 0x15bf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bf550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', '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 20:57:18 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 20:57:18 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 20:57:18 INFO - Using env: {'DISPLAY': ':0', 20:57:18 INFO - 'HOME': '/home/cltbld', 20:57:18 INFO - 'LANG': 'en_US.UTF-8', 20:57:18 INFO - 'LANGUAGE': 'en_US:en', 20:57:18 INFO - 'LOGNAME': 'cltbld', 20:57:18 INFO - 'MAIL': '/var/mail/cltbld', 20:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:18 INFO - 'MOZ_NO_REMOTE': '1', 20:57:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:18 INFO - 'NO_EM_RESTART': '1', 20:57:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:18 INFO - 'PWD': '/builds/slave/test', 20:57:18 INFO - 'SHELL': '/bin/bash', 20:57:18 INFO - 'SHLVL': '1', 20:57:18 INFO - 'TERM': 'linux', 20:57:18 INFO - 'TMOUT': '86400', 20:57:18 INFO - 'USER': 'cltbld', 20:57:18 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', 20:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:18 INFO - '_': '/tools/buildbot/bin/python'} 20:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:18 INFO - Downloading/unpacking jsonschema==2.5.1 20:57:18 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 20:57:18 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 20:57:18 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 20:57:18 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 20:57:18 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 20:57:18 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 20:57:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:57:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:57:20 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 20:57:20 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 20:57:20 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 20:57:20 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 20:57:20 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 20:57:20 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 20:57:20 INFO - Downloading functools32-3.2.3-2.tar.gz 20:57:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:57:20 INFO - warning: no files found matching '*.txt' 20:57:20 INFO - no previously-included directories found matching 'build' 20:57:20 INFO - no previously-included directories found matching 'dist' 20:57:20 INFO - no previously-included directories found matching '.git*' 20:57:20 INFO - Installing collected packages: jsonschema, functools32 20:57:21 INFO - Running setup.py install for functools32 20:57:21 INFO - warning: no files found matching '*.txt' 20:57:21 INFO - no previously-included directories found matching 'build' 20:57:21 INFO - no previously-included directories found matching 'dist' 20:57:21 INFO - no previously-included directories found matching '.git*' 20:57:21 INFO - Successfully installed jsonschema functools32 20:57:21 INFO - Cleaning up... 20:57:21 INFO - Return code: 0 20:57:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:21 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 0x7fbb8532ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15be1c0>, '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 0x15bf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bf550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', '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 20:57:21 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 20:57:21 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 20:57:21 INFO - Using env: {'DISPLAY': ':0', 20:57:21 INFO - 'HOME': '/home/cltbld', 20:57:21 INFO - 'LANG': 'en_US.UTF-8', 20:57:21 INFO - 'LANGUAGE': 'en_US:en', 20:57:21 INFO - 'LOGNAME': 'cltbld', 20:57:21 INFO - 'MAIL': '/var/mail/cltbld', 20:57:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:21 INFO - 'MOZ_NO_REMOTE': '1', 20:57:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:21 INFO - 'NO_EM_RESTART': '1', 20:57:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:21 INFO - 'PWD': '/builds/slave/test', 20:57:21 INFO - 'SHELL': '/bin/bash', 20:57:21 INFO - 'SHLVL': '1', 20:57:21 INFO - 'TERM': 'linux', 20:57:21 INFO - 'TMOUT': '86400', 20:57:21 INFO - 'USER': 'cltbld', 20:57:21 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', 20:57:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:21 INFO - '_': '/tools/buildbot/bin/python'} 20:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:21 INFO - Downloading/unpacking blobuploader==1.2.4 20:57:21 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 20:57:21 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 20:57:21 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 20:57:21 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 20:57:21 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 20:57:21 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 20:57:23 INFO - Downloading blobuploader-1.2.4.tar.gz 20:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:57:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:57:23 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 20:57:23 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 20:57:23 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 20:57:23 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 20:57:23 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 20:57:23 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 20:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:57:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:57:23 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 20:57:23 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 20:57:23 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 20:57:23 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 20:57:23 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 20:57:23 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 20:57:23 INFO - Downloading docopt-0.6.1.tar.gz 20:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:57:24 INFO - Installing collected packages: blobuploader, requests, docopt 20:57:24 INFO - Running setup.py install for blobuploader 20:57:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:57:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:57:24 INFO - Running setup.py install for requests 20:57:24 INFO - Running setup.py install for docopt 20:57:24 INFO - Successfully installed blobuploader requests docopt 20:57:24 INFO - Cleaning up... 20:57:24 INFO - Return code: 0 20:57:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:57:24 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')]} 20:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:24 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')]} 20:57:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:24 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 0x7fbb8532ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15be1c0>, '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 0x15bf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bf550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', '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 20:57:24 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 20:57:24 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 20:57:24 INFO - Using env: {'DISPLAY': ':0', 20:57:24 INFO - 'HOME': '/home/cltbld', 20:57:24 INFO - 'LANG': 'en_US.UTF-8', 20:57:24 INFO - 'LANGUAGE': 'en_US:en', 20:57:24 INFO - 'LOGNAME': 'cltbld', 20:57:24 INFO - 'MAIL': '/var/mail/cltbld', 20:57:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:24 INFO - 'MOZ_NO_REMOTE': '1', 20:57:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:24 INFO - 'NO_EM_RESTART': '1', 20:57:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:24 INFO - 'PWD': '/builds/slave/test', 20:57:24 INFO - 'SHELL': '/bin/bash', 20:57:24 INFO - 'SHLVL': '1', 20:57:24 INFO - 'TERM': 'linux', 20:57:24 INFO - 'TMOUT': '86400', 20:57:24 INFO - 'USER': 'cltbld', 20:57:24 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', 20:57:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:24 INFO - '_': '/tools/buildbot/bin/python'} 20:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:57:24 INFO - Running setup.py (path:/tmp/pip-IpmpQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:57:24 INFO - Running setup.py (path:/tmp/pip-pe6gTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:57:25 INFO - Running setup.py (path:/tmp/pip-MhTS1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:57:25 INFO - Running setup.py (path:/tmp/pip-QQG6Zx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:57:25 INFO - Running setup.py (path:/tmp/pip-kttd3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:57:25 INFO - Running setup.py (path:/tmp/pip-e6Zs7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:57:25 INFO - Running setup.py (path:/tmp/pip-UEq1Ny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:57:25 INFO - Running setup.py (path:/tmp/pip-5aLmlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:57:25 INFO - Running setup.py (path:/tmp/pip-hyRWX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:57:25 INFO - Running setup.py (path:/tmp/pip-NoIDsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:57:25 INFO - Running setup.py (path:/tmp/pip-Xo4S4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:57:25 INFO - Running setup.py (path:/tmp/pip-rBuZ3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:57:25 INFO - Running setup.py (path:/tmp/pip-0SFX9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:57:25 INFO - Running setup.py (path:/tmp/pip-M3qcwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:57:25 INFO - Running setup.py (path:/tmp/pip-DjYn7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:57:25 INFO - Running setup.py (path:/tmp/pip-NSmweJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:57:26 INFO - Running setup.py (path:/tmp/pip-vAAjnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:57:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:57:26 INFO - Running setup.py install for manifestparser 20:57:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:57:26 INFO - Running setup.py install for mozcrash 20:57:26 INFO - Running setup.py install for mozdebug 20:57:26 INFO - Running setup.py install for mozdevice 20:57:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:57:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:57:26 INFO - Running setup.py install for mozfile 20:57:26 INFO - Running setup.py install for mozhttpd 20:57:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:57:26 INFO - Running setup.py install for mozinfo 20:57:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:57:26 INFO - Running setup.py install for mozInstall 20:57:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:57:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:57:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:57:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:57:26 INFO - Running setup.py install for mozleak 20:57:26 INFO - Running setup.py install for mozlog 20:57:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:57:27 INFO - Running setup.py install for moznetwork 20:57:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:57:27 INFO - Running setup.py install for mozprocess 20:57:27 INFO - Running setup.py install for mozprofile 20:57:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:57:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:57:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:57:27 INFO - Running setup.py install for mozrunner 20:57:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:57:27 INFO - Running setup.py install for mozscreenshot 20:57:27 INFO - Running setup.py install for moztest 20:57:27 INFO - Running setup.py install for mozversion 20:57:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:57:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:57:27 INFO - Cleaning up... 20:57:27 INFO - Return code: 0 20:57:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:57:27 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')]} 20:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:27 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')]} 20:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:27 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 0x7fbb8532ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15be1c0>, '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 0x15bf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bf550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', '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 20:57:27 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 20:57:27 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 20:57:27 INFO - Using env: {'DISPLAY': ':0', 20:57:27 INFO - 'HOME': '/home/cltbld', 20:57:27 INFO - 'LANG': 'en_US.UTF-8', 20:57:27 INFO - 'LANGUAGE': 'en_US:en', 20:57:27 INFO - 'LOGNAME': 'cltbld', 20:57:27 INFO - 'MAIL': '/var/mail/cltbld', 20:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:27 INFO - 'MOZ_NO_REMOTE': '1', 20:57:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:27 INFO - 'NO_EM_RESTART': '1', 20:57:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:27 INFO - 'PWD': '/builds/slave/test', 20:57:27 INFO - 'SHELL': '/bin/bash', 20:57:27 INFO - 'SHLVL': '1', 20:57:27 INFO - 'TERM': 'linux', 20:57:27 INFO - 'TMOUT': '86400', 20:57:27 INFO - 'USER': 'cltbld', 20:57:27 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', 20:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:27 INFO - '_': '/tools/buildbot/bin/python'} 20:57:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:57:28 INFO - Running setup.py (path:/tmp/pip-sK2qeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:57:28 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)) 20:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:57:28 INFO - Running setup.py (path:/tmp/pip-Wm1ruV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:57:28 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)) 20:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:57:28 INFO - Running setup.py (path:/tmp/pip-fofMtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:57:28 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)) 20:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:57:28 INFO - Running setup.py (path:/tmp/pip-h0Q7Jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:57:28 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)) 20:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:57:28 INFO - Running setup.py (path:/tmp/pip-HdwHR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:57:28 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)) 20:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:57:28 INFO - Running setup.py (path:/tmp/pip-MWP2RE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:57:28 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)) 20:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:57:28 INFO - Running setup.py (path:/tmp/pip-aP7sT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:57:28 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)) 20:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:57:28 INFO - Running setup.py (path:/tmp/pip-XuOiuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:57:28 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)) 20:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:57:28 INFO - Running setup.py (path:/tmp/pip-aDLqCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:57:28 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)) 20:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:57:28 INFO - Running setup.py (path:/tmp/pip-fT6747-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:57:28 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)) 20:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:57:28 INFO - Running setup.py (path:/tmp/pip-pEBF9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:57:28 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)) 20:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:57:28 INFO - Running setup.py (path:/tmp/pip-rJd8m7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:57:28 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)) 20:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:57:28 INFO - Running setup.py (path:/tmp/pip-QTjgCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:57:29 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)) 20:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:57:29 INFO - Running setup.py (path:/tmp/pip-colkQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:57:29 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)) 20:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:57:29 INFO - Running setup.py (path:/tmp/pip-UkroL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:57:29 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)) 20:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:57:29 INFO - Running setup.py (path:/tmp/pip-02wLPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:57:29 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)) 20:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:57:29 INFO - Running setup.py (path:/tmp/pip-xn7mZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:57:29 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)) 20:57:29 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)) 20:57:29 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)) 20:57:29 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)) 20:57:29 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)) 20:57:29 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)) 20:57:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:31 INFO - Downloading blessings-1.6.tar.gz 20:57:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:57:31 INFO - Installing collected packages: blessings 20:57:31 INFO - Running setup.py install for blessings 20:57:31 INFO - Successfully installed blessings 20:57:31 INFO - Cleaning up... 20:57:31 INFO - Return code: 0 20:57:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:57:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:57:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:57:31 INFO - Reading from file tmpfile_stdout 20:57:31 INFO - Current package versions: 20:57:31 INFO - argparse == 1.2.1 20:57:31 INFO - blessings == 1.6 20:57:31 INFO - blobuploader == 1.2.4 20:57:31 INFO - docopt == 0.6.1 20:57:31 INFO - functools32 == 3.2.3-2 20:57:31 INFO - jsonschema == 2.5.1 20:57:31 INFO - manifestparser == 1.1 20:57:31 INFO - mozInstall == 1.12 20:57:31 INFO - mozcrash == 0.17 20:57:31 INFO - mozdebug == 0.1 20:57:31 INFO - mozdevice == 0.48 20:57:31 INFO - mozfile == 1.2 20:57:31 INFO - mozhttpd == 0.7 20:57:31 INFO - mozinfo == 0.9 20:57:31 INFO - mozleak == 0.1 20:57:31 INFO - mozlog == 3.2 20:57:31 INFO - moznetwork == 0.27 20:57:31 INFO - mozprocess == 0.23 20:57:31 INFO - mozprofile == 0.28 20:57:31 INFO - mozrunner == 6.12 20:57:31 INFO - mozscreenshot == 0.1 20:57:31 INFO - mozsystemmonitor == 0.3 20:57:31 INFO - moztest == 0.7 20:57:31 INFO - mozversion == 1.4 20:57:31 INFO - psutil == 3.1.1 20:57:31 INFO - requests == 1.2.3 20:57:31 INFO - wsgiref == 0.1.2 20:57:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:31 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 0x7fbb8532ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15be1c0>, '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 0x15bf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bf550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', '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 20:57:31 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 20:57:31 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 20:57:31 INFO - Using env: {'DISPLAY': ':0', 20:57:31 INFO - 'HOME': '/home/cltbld', 20:57:31 INFO - 'LANG': 'en_US.UTF-8', 20:57:31 INFO - 'LANGUAGE': 'en_US:en', 20:57:31 INFO - 'LOGNAME': 'cltbld', 20:57:31 INFO - 'MAIL': '/var/mail/cltbld', 20:57:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:31 INFO - 'MOZ_NO_REMOTE': '1', 20:57:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:31 INFO - 'NO_EM_RESTART': '1', 20:57:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:31 INFO - 'PWD': '/builds/slave/test', 20:57:31 INFO - 'SHELL': '/bin/bash', 20:57:31 INFO - 'SHLVL': '1', 20:57:31 INFO - 'TERM': 'linux', 20:57:31 INFO - 'TMOUT': '86400', 20:57:31 INFO - 'USER': 'cltbld', 20:57:31 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', 20:57:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:31 INFO - '_': '/tools/buildbot/bin/python'} 20:57:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:31 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)) 20:57:31 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)) 20:57:31 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)) 20:57:31 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)) 20:57:31 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)) 20:57:31 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)) 20:57:31 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)) 20:57:31 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)) 20:57:31 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)) 20:57:31 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)) 20:57:31 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)) 20:57:31 INFO - Cleaning up... 20:57:31 INFO - Return code: 0 20:57:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb8532ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15be1c0>, '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 0x15bf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bf550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', '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 20:57:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:57:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:57:31 INFO - Using env: {'DISPLAY': ':0', 20:57:31 INFO - 'HOME': '/home/cltbld', 20:57:31 INFO - 'LANG': 'en_US.UTF-8', 20:57:31 INFO - 'LANGUAGE': 'en_US:en', 20:57:31 INFO - 'LOGNAME': 'cltbld', 20:57:31 INFO - 'MAIL': '/var/mail/cltbld', 20:57:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:31 INFO - 'MOZ_NO_REMOTE': '1', 20:57:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:31 INFO - 'NO_EM_RESTART': '1', 20:57:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:31 INFO - 'PWD': '/builds/slave/test', 20:57:31 INFO - 'SHELL': '/bin/bash', 20:57:31 INFO - 'SHLVL': '1', 20:57:31 INFO - 'TERM': 'linux', 20:57:31 INFO - 'TMOUT': '86400', 20:57:31 INFO - 'USER': 'cltbld', 20:57:31 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', 20:57:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:31 INFO - '_': '/tools/buildbot/bin/python'} 20:57:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:57:32 INFO - Cleaning up... 20:57:32 INFO - Return code: 0 20:57:32 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:57:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb8532ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15be1c0>, '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 0x15bf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bf550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', '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 20:57:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 20:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 20:57:32 INFO - Using env: {'DISPLAY': ':0', 20:57:32 INFO - 'HOME': '/home/cltbld', 20:57:32 INFO - 'LANG': 'en_US.UTF-8', 20:57:32 INFO - 'LANGUAGE': 'en_US:en', 20:57:32 INFO - 'LOGNAME': 'cltbld', 20:57:32 INFO - 'MAIL': '/var/mail/cltbld', 20:57:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:32 INFO - 'MOZ_NO_REMOTE': '1', 20:57:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:32 INFO - 'NO_EM_RESTART': '1', 20:57:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:32 INFO - 'PWD': '/builds/slave/test', 20:57:32 INFO - 'SHELL': '/bin/bash', 20:57:32 INFO - 'SHLVL': '1', 20:57:32 INFO - 'TERM': 'linux', 20:57:32 INFO - 'TMOUT': '86400', 20:57:32 INFO - 'USER': 'cltbld', 20:57:32 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', 20:57:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:32 INFO - '_': '/tools/buildbot/bin/python'} 20:57:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:32 INFO - Downloading/unpacking flake8 20:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:57:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:57:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:57:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:57:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:57:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:57:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:57:34 INFO - no previously-included directories found matching 'docs/_build' 20:57:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:57:35 INFO - Running setup.py install for pep8 20:57:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:57:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:57:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:57:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:57:35 INFO - no previously-included directories found matching 'docs/_build' 20:57:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:57:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:57:35 INFO - Cleaning up... 20:57:35 INFO - Return code: 0 20:57:35 INFO - Running post-action listener: _resource_record_post_action 20:57:35 INFO - Running post-action listener: _start_resource_monitoring 20:57:35 INFO - Starting resource monitoring. 20:57:35 INFO - ##### 20:57:35 INFO - ##### Finished create-virtualenv step (success) 20:57:35 INFO - ##### 20:57:35 INFO - ##### 20:57:35 INFO - ##### Running install step. 20:57:35 INFO - ##### 20:57:35 INFO - Running pre-action listener: _resource_record_pre_action 20:57:35 INFO - Running main action method: install 20:57:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:57:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:57:35 INFO - Reading from file tmpfile_stdout 20:57:35 INFO - Detecting whether we're running mozinstall >=1.0... 20:57:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:57:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:57:35 INFO - Reading from file tmpfile_stdout 20:57:35 INFO - Output received: 20:57:35 INFO - Usage: mozinstall [options] installer 20:57:35 INFO - Options: 20:57:35 INFO - -h, --help show this help message and exit 20:57:35 INFO - -d DEST, --destination=DEST 20:57:35 INFO - Directory to install application into. [default: 20:57:35 INFO - "/builds/slave/test"] 20:57:35 INFO - --app=APP Application being installed. [default: firefox] 20:57:35 INFO - mkdir: /builds/slave/test/build/application 20:57:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:57:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 20:57:51 INFO - Reading from file tmpfile_stdout 20:57:51 INFO - Output received: 20:57:51 INFO - /builds/slave/test/build/application/firefox/firefox 20:57:51 INFO - Running post-action listener: _resource_record_post_action 20:57:51 INFO - ##### 20:57:51 INFO - ##### Finished install step (success) 20:57:51 INFO - ##### 20:57:51 INFO - ##### 20:57:51 INFO - ##### Running run-tests step. 20:57:51 INFO - ##### 20:57:51 INFO - Running pre-action listener: _resource_record_pre_action 20:57:51 INFO - Running main action method: run_tests 20:57:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:57:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:57:51 INFO - Python 2.7.3 20:57:51 INFO - Return code: 0 20:57:51 INFO - grabbing minidump binary from tooltool 20:57: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')]} 20:57:51 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 0x15be1c0>, '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 0x15bf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bf550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:57:51 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 20:57:51 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 20:57:51 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 20:57:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:57:51 INFO - Return code: 0 20:57:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:57:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:57:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:57:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:57:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:57:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:57:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:57:52 INFO - Return code: 0 20:57:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:57:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:57:52 INFO - Using env: {'DISPLAY': ':0', 20:57:52 INFO - 'HOME': '/home/cltbld', 20:57:52 INFO - 'LANG': 'en_US.UTF-8', 20:57:52 INFO - 'LANGUAGE': 'en_US:en', 20:57:52 INFO - 'LOGNAME': 'cltbld', 20:57:52 INFO - 'MAIL': '/var/mail/cltbld', 20:57:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:57:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:57:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:52 INFO - 'MOZ_NO_REMOTE': '1', 20:57:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:57:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:52 INFO - 'NO_EM_RESTART': '1', 20:57:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:52 INFO - 'PWD': '/builds/slave/test', 20:57:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:57:52 INFO - 'SHELL': '/bin/bash', 20:57:52 INFO - 'SHLVL': '1', 20:57:52 INFO - 'TERM': 'linux', 20:57:52 INFO - 'TMOUT': '86400', 20:57:52 INFO - 'USER': 'cltbld', 20:57:52 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1469849280.534460-1284470387', 20:57:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:52 INFO - '_': '/tools/buildbot/bin/python'} 20:57:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:57:52 INFO - mozversion application_buildid: 20160729182925 20:57:52 INFO - mozversion application_changeset: 8847dd9e9a2da7a4ad074ab97e085295e50746c3 20:57:52 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:57:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:57:52 INFO - mozversion application_name: Firefox 20:57:52 INFO - mozversion application_remotingname: firefox-dev 20:57:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:57:52 INFO - mozversion application_vendor: Mozilla 20:57:52 INFO - mozversion application_version: 49.0a2 20:57:52 INFO - mozversion platform_buildid: 20160729182925 20:57:52 INFO - mozversion platform_changeset: 8847dd9e9a2da7a4ad074ab97e085295e50746c3 20:57:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:57:52 INFO - mozversion platform_version: 49.0a2 20:57:52 INFO - using testdate: 1469851072 20:57:52 INFO - actual date: 1469851072 20:57:52 INFO - starting webserver on 'localhost:60049' 20:57:52 INFO - SUITE-START | Running 2 tests 20:57:52 INFO - TEST-START | dromaeo_css 20:57:52 INFO - operating with platform_type : linux_ 20:57:52 INFO - Initialising browser for dromaeo_css test... 20:57:52 INFO - TEST-INFO | started process 3261 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiC_XL6/profile http://localhost:60049/getInfo.html) 20:57:56 INFO - PROCESS | 3261 | __metrics Screen width/height:1600/1200 20:57:56 INFO - PROCESS | 3261 | colorDepth:24 20:57:56 INFO - PROCESS | 3261 | Browser inner width/height: 1024/697 20:57:56 INFO - PROCESS | 3261 | __metrics 20:57:57 INFO - PROCESS | 3261 | 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" 20:57:57 ERROR - PROCESS | 3261 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:57:58 INFO - TEST-INFO | 3261: exit 0 20:57:58 INFO - Browser initialized. 20:57:58 INFO - Running cycle 1/1 for dromaeo_css test... 20:57:58 INFO - TEST-INFO | started process 3352 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiC_XL6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:58:10 INFO - PROCESS | 3352 | RSS: Main: 145358848 20:58:10 INFO - PROCESS | 3352 | 20:59:01 INFO - PROCESS | 3352 | 1469851141688 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] 21:01:09 INFO - PROCESS | 3352 | Cycle 1(1): loaded http://localhost:60049/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60049/tests/dromaeo/cssquery-ext.html) 21:01:10 INFO - PROCESS | 3352 | RSS: Main: 222375936 21:01:10 INFO - PROCESS | 3352 | 21:04:12 INFO - PROCESS | 3352 | Cycle 1(1): loaded http://localhost:60049/tests/dromaeo/cssquery-ext.html (next: http://localhost:60049/tests/dromaeo/cssquery-jquery.html) 21:04:13 INFO - PROCESS | 3352 | RSS: Main: 233648128 21:04:13 INFO - PROCESS | 3352 | 21:07:15 INFO - PROCESS | 3352 | Cycle 1(1): loaded http://localhost:60049/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60049/tests/dromaeo/cssquery-mootools.html) 21:07:16 INFO - PROCESS | 3352 | RSS: Main: 234209280 21:07:16 INFO - PROCESS | 3352 | 21:10:18 INFO - PROCESS | 3352 | Cycle 1(1): loaded http://localhost:60049/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60049/tests/dromaeo/cssquery-prototype.html) 21:10:18 INFO - PROCESS | 3352 | RSS: Main: 217866240 21:10:18 INFO - PROCESS | 3352 | 21:13:21 INFO - PROCESS | 3352 | Cycle 1(1): loaded http://localhost:60049/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60049/tests/dromaeo/cssquery-yui.html) 21:13:21 INFO - PROCESS | 3352 | RSS: Main: 219570176 21:13:21 INFO - PROCESS | 3352 | 21:16:24 INFO - PROCESS | 3352 | Cycle 1(1): loaded http://localhost:60049/tests/dromaeo/cssquery-yui.html (next: http://localhost:60049/tests/dromaeo/cssquery-dojo.html) 21:16:24 INFO - PROCESS | 3352 | RSS: Main: 270815232 21:16:24 INFO - PROCESS | 3352 | 21:16:24 INFO - PROCESS | 3352 | __start_tp_report 21:16:24 INFO - PROCESS | 3352 | _x_x_mozilla_page_load 21:16:24 INFO - PROCESS | 3352 | _x_x_mozilla_page_load_details 21:16:24 INFO - PROCESS | 3352 | |i|pagename|runs| 21:16:24 INFO - PROCESS | 3352 | |0;dojo.html;3289.9013029609114;3302.4881143422776;3336.3494118646868;3338.4491558892782;3375.021243839287;5328.12086005809;5416.187571864222;5430.647007944481;5430.918536221955;5432.483914028166;5415.512603865652;5472.589555783312;5480.479354461329;5495.422980587041;5523.171524271362;4902.240152776325;4957.677750946195;4982.202847544394;5024.346834911456;5080.958403527268;4854.344663470421;4963.776630051656;5002.099622068026;5012.373453318335;5017.949820501803;4898.583620392253;4900.8039678413;4913.656044076916;4923.507649235084;5010.824621138243;4996.800127994894;5000.999999999964;5025.974870125626;5030.723310217936;5054.191329387317;4790.089882922269;4881.951180488185;4934.876628084291;4951.752412379366;4973.701577905337;5219.686818790885;5227.973860130713;5228.816991405321;5269.394019687747;5273.657212281228;5769.07694768835;5814.447627475383;5816.709164541756;5848.853778655525;5853.88292234153;5812.360640329561;5813.941860581383;5814.244148260741;5851.590388672795;5877.735501902382;5500.50495455411;5533.474319939599;5547.278853749027;5548.4451554845;5552.833414997556;5719.8570035749435;6027.8794424111275;6033.909491357633;6038.516918646497;6038.969805150946;5629.521490673339;5654.9151762723595;5673.858153546194;5723.942760572424;5736.569757268222;5769.88460230793;5773.364929857712;5782.45066718661;5802.100674395476;5814.273215848019;5630.5214056805635;5711.514521265656;5741.282339707536;5752.424757524298;5754.0505816540635;5755.510781583528;5797.594168408255;5811.157382179645;5813.040848260074;5839.941600583939;4477.70894891831;4488.483824360241;4488.798004089824;4508.774561271924;4514.525974772667;3950.2692001979726;3955.980220098939;3986.4618276532296;4031.213913286881;4062.3500239961463;5518.724063796794;5540.168974653834;5541.168824676331;5545.500904918576;5577.3028371453565;5750.338711048199;5787.5080618147085;5790.160426738099;5811.070228763378;5833.533317334603;5388.110961691355;5428.321459817523;5518.53092487135;5523.309586301712;5535.225068490415;4983.651144419856;4991.825286115041;4992;4994.650374473825;5009.89980200394;6023.608465449732;6038.788642397583;6040;6045.395460454008;6077.1491991121275;5679.488846003879;5728.684922329312;5766.538676905921;5772.39389864075;5785.739641716217;6037.849053773515;6038.215032045981;6050;6061.39386061408;6068.4538391544975;5692.629979051514;5706.172604972338;5708.54331653475;5712.200291959212;5723.399043100581;5648.15277708347;5668.546516278605;5707.771689132388;5776.075827867521;5786.623869448473;5627.324721033502;5636.210930469655;5647;5673.205751194753;5674.517665998436;5684.63049901755;5689.31728192603;5693.601447898607;5693.857653558694;5723.456271654186;5543.113101903676;5579.386267510652;5599.944000559943;5601.719914004365;5604.579656525695;5519.2824932758485;5607.831765047055;5621.55027597783;5652.68910209926;5679.403662615366;5577.051901176728;5589.161625756169;5603.80386686464;5620.213170156263;5628.493435590816 21:16:24 INFO - PROCESS | 3352 | |1;ext.html;618.0790621973258;620.2402057479588;637.8405398650337;665.8435267712086;679.8980152977052;32853.34293314132;36444;36467;36499.08752281194;36503.26993460129;37052.62947370525;37822.24355512888;37835.432468512925;47040.52959470404;48310.75844620772;9029.683961061364;9154.954225228865;9162.083791620851;9171.541422928827;9201.263898888074;8965.865512017324;9054.27565794738;9057.275417966579;9058.411203271799;9061.637534498612;8545.188207120314;8855.512946787923;10914.399708016055;11094.112471002341;11134.832977505344;10978.286411383275;11031.779364412707;11041.889581104166;11042.779144417147;11043.33739975596;8428.8314233715;10886.67339979802;10948.288361256573;10957.561697532168;10961.56153753849;34599;37812.62187378119;47895;48892.26661600079;48944.510554894354;3625.23869987303;3758.1544152565452;3798.9810050949845;3824.8852534424013;3870.4581358609817;34742.826285868665;34827;35088.29823403543;35426.29147417063;36095.458568121496;4479.283314669669;4481.193385190664;4502.977485112586;4516.390287311204;4553.567411095941;371796;372594;373566;373879.13060434797;374495;37941.43087853684;38984.41523377151;38994.41508377377;67248.66375668188;68075.31924680789;40313.19373612511;40423.19153616911;40516.18967620631;40719.38920916188;40782.592174078476;17317.653646926992;17725.7341139883;17728.822711772977;17738.73391899097;17769.644607107784;14507;14724.55826325212;14746.11523308605;14747;14754.852451475563;8612.354073444516;8915.866262006075;9188.081191880892;9201.447913125234;9277.628894844265;3419.4357930941114;3439.329330780474;3458.844352004166;3460.3425349183576;3501.1597216667815;1096.2655021135859;1098.368438148052;1169.4620474581618;1173.8826117388194;1197.8203269509643;34035.48946765801;34127;34319.31361372759;34341.828290858386;34552;1112.360392774158;1125.3416751200475;1134.4554613785429;1170.9590164344377;1265.968235887767;18133.727994080098;18426.355077572487;18440.432563232007;18590.628187436174;18591.721124183146;599101;599628;602614;604065;604786;56391.15413268804;60829.69585152046;62805.68597157076;64405.35594643994;64756;1253.5361916091106;1272.6945533071996;1280.750253700538;1282.2498838179583;1436.5187662133303;33545.16137096592;35396.29207415889;35485.64514354823;35526;35904.28191436209;55765.44234557765;56245.71877140588;56540.717296413255;56550;56571.15143272936;522511;529959;537198;539664;726997;29505.11484655464;29849.70150298469;30269.394612108073;30900.845495772377;30972.380552389277;50793.49206507888;50944.74527627338;51364.48635513597;51761.741191293804;51873.48126518686;42795;43001.56998430101;43045.56954430541;43211.783941080095;44090.338644920994;42794.008838761976;43015.139697206505;45431.54568454273;45654.54345456503;47003;43969.78015110032;48286.75856620695;49171.75414123049;49767.50232497774;49811.25283120829;44844.10311793681;46104.538954611366;47688.761556191996;47919.5208047929;48609;35233.29533409369;35333.29333413266;35885.1028724284;36239;36391 21:16:24 INFO - PROCESS | 3352 | |2;jquery.html;425.37469919218745;425.92972159593666;431.52532214564;432.2543612268835;450.26381865649654;18779;19676.508087297825;20658.89670551649;20716.171353145874;20832.47918802027;17956.191971361288;18728.344507942224;19036.238550457998;19087.14107865146;19826.40520784379;5761.337446193678;5972.343042265347;6001.2498437695285;6010.549208809335;6012.729427175787;5865.501432378253;5867.941320586782;5877.2947246330505;5941.019731744257;5950.880982380349;5751.884962300752;5869.178315035899;5872.9706351468185;5889.116632505115;5913.201717768111;5831.950248955165;5871.44221298978;5883.705814709268;5899.734511946973;5926.614770039933;5130.615203859726;5273.235380869791;5274.26160337553;5284.550813180885;5305.602079843989;23454.530909381887;23663.645045324334;24408.633870491958;24498.632520512205;24506.754932450625;2583.186296316654;2650.562657161566;2655.123809142978;2664.9600255995983;2834.6173266609158;14636.487722929647;14713.337899794535;14740.410383584644;15072.698546029018;15142.848571514254;2005.0375819606525;2011.5416323165734;2015.748031496063;2052.404802607242;2135.7116789233564;53252.46747532514;53632.19551706727;55272.72363638196;58853;68649;10997.395143267036;11185;11449.912258335446;11467.197296189186;11642.068634509218;14188.645283867985;15311.693766124838;16041.759373609406;16125.75811362807;16396.918015410087;9049.276057915482;9070.455772653662;9089.636414543476;9090.86363704545;9118.817623647623;8435.367347448966;8465.915340846635;8906.73279801607;8932.821343573221;8978.371513993958;6976.5465244758925;6997.755078572225;7101.573905565682;7177.856442871113;7195.244499327495;1902.6966527928414;1992.4221975626906;2049.282751037125;2076.792320767911;2084.155916853677;1013.9543720532594;1014.5333146752429;1024.5696807340935;1070.1652710885521;1104.2607892569235;19361.806381936;19953.303969162494;20062.899685501772;20188.89905550463;20494.38516844497;1043.7077618266896;1103.1450625765096;1107.2360071550765;1133.586241022021;1154.3189518184145;11180.720481987853;11339.94330028361;11406.771864562663;11643.243189192675;11692.590759323553;462069;476811;481186;481969;484237;78462.44743335707;79198.20801792023;80490.59754701189;80602;81688.59155704302;1088.967330980072;1100.5047728522197;1116.4250411038322;1116.6817457024708;1119.1997721629075;17342.826571734626;17569.209385577426;17572.91213543924;17613;17955.281788728305;26873.313086225866;36006.63993360138;36095.097622559646;37295.6270437303;39048;48479.5152048475;49025.5097449021;50079.24881126859;50746;51003;8760.474371537728;8887.933447986283;8906.109389061301;9087.863682044906;9157.221636161;12829.93585032069;12886;13135.934320328719;13154.539591114266;13655.180689158682;11245.775084498428;11348.54605815758;11396.373199474108;11558.711032224262;11783.705407364541;11467.139964502863;11764.764704705687;11769.588064418047;11856.11079169083;12284.447199876206;12022.579209727617;12181.573644922386;12233;12374.319412432402;12468.127231093737;8647.524386158822;8835.072317406837;8891.955540222258;8998.23515001206;9048.41185322952;11732.41337933117;11858.940705296764;11918.106142039209;12114.818277725664;12234.449449774604 21:16:24 INFO - PROCESS | 3352 | |3;mootools.html;1310.5347601601425;1466.9339879705415;1469.8162729658793;1662.0775469614366;1843.2903332217093;8720.215180633742;9053.818923621524;9059.592318345674;9339.95330023349;9352.438853668784;8988.101189881008;9196.586153623086;9197.310201734863;9209.125133112344;9214.308926830498;2155.4395857077193;2397.8920948557356;2414.5774489464316;2452.681151450309;2468.5062987402503;2452.0682140786475;2496.5131799299143;2505.2734706934966;2509.82431229814;2510.2845688978646;2358.7641235876445;2371.774681405264;2373.964390534143;2375.857448553092;2381.3570336009266;2150.290404166621;2325.848819826706;2376.346504711201;2393.868337241451;2406.4224586099385;2297.7242730872404;2317.0268487235403;2322.372959300996;2325.860448373103;2328.69726935499;8722.127787221227;8722.34582406322;8738.86891696625;8756.299496040365;8779.03430622631;5284.550813180885;5420.159875219348;5422.701751403672;5426.403095659474;5432.728363581805;3573.910652233689;3596.352656521825;3630.9636903631153;3782.3002744492355;3790.431435284702;1154.4227886056972;1155.2317708723688;1155.8092914669069;1183.230899915062;1190.922590031645;13281.601551953458;13291.601251962456;13307;13336.799948000787;13359.799603005962;4873.098476781806;4955.380577427822;4975.726335051607;4986.202207646759;5022.773975171053;11243.887561124448;11311.773764524827;11362.431878406047;11388.658340249805;11389.316641001566;5006.3491746073505;5554.583406244547;5605.971970140205;5606.6916319602;5623.240862483594;4993.226049962262;5152.098382782998;5164.7934082636275;5175.896482070413;5187.507186817246;4268.87193384199;4272.807723652443;4316.978415107905;4361.498427680857;4369.191699535596;1310.6461255460972;1341.6914109754812;1350.1021820489461;1357.3145561491383;1360.217874722019;992.211192102266;993.9304248702666;1004.6232662751469;1008.2992320337295;1009.7778488732467;6144.8771024580155;6209.472194863487;6218.253809542884;6248.31268560458;6306.936930630635;482.60667555941797;494.140196058855;499.9225120106389;502.75867583559364;508.0626244578746;4083.632473077437;4092.5702801205443;4135.97932010338;4142.212979533879;4142.502899652061;11376.089912806956;11729.120315976335;11765.058795296356;11830.112741544419;11852.703682407837;11602.70993225176;11607.6517704469;11612.825807612893;11697.356645384416;11750.70623234426;8356.582170891552;8713.433626814236;8721.956390218134;8792.60433280504;8890.155435233642;5990.1913241713555;6199.752009919733;6208.192934918432;6238.719257633326;6245.750169993149;10826.404547749635;10931.234813562975;11004.449777510932;11150.888491114985;11184;11041.39272340029;11298;11304.265222760494;11319.717007074889;11325.263857849213;8286.958565207377;8319.750407487785;8411.074781773877;8429.789255268668;8430.494170349553;8901.287896968097;8956.462612243286;8973.32700047486;8977.820443590961;9034.909650903406;7750.341220996278;7904.486208396436;8039.919600804151;8086.353091752763;8167.224113709188;7652.196519365387;7670.27132422419;7723.53658780475;7744.225577442211;7786.143524212219;7235.746748863765;7239.638018098969;7278.308560686726;7303;7487.101547814297;5369.033573956725;5371.194320851903;5404.864878378072;5410.350757908918;5485.4788795064405;5208.088584497622;5234.685918844882;5253.658512196695;5280.656757310762;5405.648632838853 21:16:24 INFO - PROCESS | 3352 | |4;prototype.html;344.1430837215334;349.87229661173666;351.14321056621856;352.6086044490616;355.2025702298339;5405.378381486125;5439.891202175954;5442.809501667432;5458.563314934806;5489.886393738409;5343.545798607113;5343.706096164709;5401.972990135044;5407.3781515125775;5428.9185662215095;3943.98028009861;4049.5950404959563;4082.122343696105;4196.9370459443135;4248.553901840309;4174.666026717855;4220.26145424551;4224.134052519242;4233.597808208216;4234.470691163605;4179;4194.328907374821;4202.432671589327;4208.221479026374;4226.112516371566;4071.857484988026;4158.542560318348;4238.894027649302;4244.108737165199;4253.638440732541;3758.8120593970193;3780.2988655105314;3791.7345785795005;3795.1214148996146;3800.5439347278502;5207.505286997729;5220.164773636238;5232.607554433394;5234.162533994542;5234.482000219931;4777.996620709655;4805.758547221256;4814.638902082358;4843;4866.221404575251;5561.388247292794;5569;5577.749001294911;5597.524210442118;5607.859803504904;4132.607402296807;4162.83348666053;4166.250074986501;4170;4183.142455796555;5572.972135139339;5586.525145362649;5685.431456854364;5858.619189752695;5864.794732184396;4077.612626800449;4103.774292413946;4106.691998100155;4127.938080928789;4144.254034273799;4138.627523522898;4146.543880173179;4190.182914331676;4192.832286708497;4207.305794543866;4558.156741002925;4804.8798780030775;4806.831760888352;4820.566149046603;4840.845823608461;5551.250581171571;5570.41510641385;5587.552995760329;5630.127330263816;5653.491185793216;1422.9359678814478;1436.5115860607234;1440.4238304678213;1456.919869407193;1467.2370367408887;912.1015799437548;938.7418459923575;939.736873675372;945.5886689290181;960.4477425480377;4789.616830653539;4935.3090567320605;4954.826581069718;4974.701517908937;4987.401511818605;5144.845654630292;5151.728652305813;5223.764930578133;5257.185136303879;5274.231028709882;4684.74233917131;4962.196359057121;4982.377202849644;5011.298418221452;5013.273075404119;2819.5629677400034;2831.716828317152;2833.3058400691552;2836.262571731365;2839.920830084555;5305.389880163831;5364.383095943938;5424.267723857308;5431.343440250762;5476.342838859283;5095.528678578185;5175.482451754794;5239.738013099406;5268.2361057647195;5300.390455097713;5402.324709411324;5428.728563571805;5429.53848922846;5467.16849855295;5469.890602187934;5161.838586318048;5199.648091496314;5216.426193118678;5243.187305967581;5312.043832110257;4933.284673722362;5081.796728130981;5147.433782284021;5152.711822044489;5161.354830646169;5864.237649105589;5993.340732519507;6046.27444706638;6065.150878877049;6138.785142519991;4080.9795951020055;4096.856610018635;4099.1801639671585;4105.404716316058;4114.156597897364;5658.632188907708;5794.362620111869;5843.532517398683;5867.149263356704;5894.528437724886;5620.156976453564;5672.12082127271;5686.260786097946;5689.914651280152;5711.5716321276905;5623.606347555632;5681.346645135697;5710.8572285691525;5713.857153571194;5732.885342293047;5348.625596208228;5555.805546806004;5559.305086864142;5584.776608935759;5605.159226115952;5634.4365563443325;5640.351359593536;5701.287339082614;5707.885842283048;5708.34354049273;5378.650387724785;5542.334919809648;5586.608937374344;5616.354119276336;5624.803131890364 21:16:24 INFO - PROCESS | 3352 | |5;yui.html;740.7074205688755;782.405371917343;800.2717527050386;863.5509535041782;868.9695860644874;15069.62325941852;16066.678666426664;16243;16572.83427165728;16734.58163545912;15207.619809504768;15354.232288385592;15366.385344586202;15374.308156132973;15429;717.9389751871071;738.7488253993641;741.2809574712547;874.6326542852001;884.783228109111;734.6951015328632;842.7303262955857;862.5730263519536;865.9740207793744;872.5942436766902;901.8557030875062;902.6885724425063;906.3972458315245;906.4380084347714;906.7415786500816;740.829609189884;897.2956229359921;897.5287973813735;897.7755561109723;897.9820403591924;648.672420427681;667.8931370980669;668.0847239282215;668.2014992084461;668.5119862500402;15082.54752357431;15210.08739475635;15245.085294882343;15255.313510892036;15273.923630381889;13113;13568.253746043963;13781.310934453237;13812.723745525134;13885.652858678513;13069.869301307055;13128.409221584956;13194.472221111144;13281.60155195336;13387.799183012263;4611.100835337111;4624.491305956342;4674.2053850845095;4720.952790472085;4747.8575642730775;65338.67330663364;66940;67314;75796.62101689511;76911.61544192243;12292.446839892227;12344.197627154206;12369.381530923418;12469.501219951282;12493.25040497573;23303.06787728472;23348.416289592897;23426.414339641644;23440.531189376117;23513.764862351498;8953.731388058368;9033.096690330914;9223.49270790113;9227.077292270718;9237.030111838294;4794.688345257547;5034.144195486776;5062.291279220912;5139.408967968657;5255.816046438356;3936.4488971544006;4051.736637118578;4060.675145988314;4068.5524592294823;4068.7558746475306;673.6564352180326;692.19705142035;696.2550071423528;716.6774951271844;716.9670602858919;707.5224223649017;771.4561234329897;789.7630710786741;822.7284995951321;848.9278411334982;9742.659006934866;10333.328333658288;11177;11341.489632966537;11782.94108529469;740.2042803985629;773.0800347586354;798.1699033005725;819.6721311475338;831.3592276404567;4945.406551213805;5244.265802787613;5267.710275934784;5301.284326615934;5325.547328477045;94198;106681.46659266653;108426;113210.43394783138;114560.42719786514;60732;61088.38911610827;61921;72329.63835180896;82853.17146828576;61128.38871611316;80174.599127004;82613.17386826055;82614.17385826065;83171.16828831634;9363.812723745623;9455.763605909908;9478.857817132886;9492.382995105296;9511.619535218513;43063;46541.30188047114;51040.48959510357;53566;55981.440185599255;53121.203181951525;53239.733801330745;63156.68421657862;67333.32666673466;77617;14323;14441.927790361402;15036.774448383501;15438.768418473508;15477.690446191238;15647.13940733225;16326.755098673291;16444.588885277484;16458.177091145633;16984;12894.742105157657;13290.335483225994;14607.977441578987;14853.108813471226;15576.143312117942;15242;15620.140892251035;15998.520044398687;16069.629407950335;16352.100634465218;15222.55560790511;15519.301631426388;15578.143202123538;15708.21458927072;15780.526584202493;12378.75242495163;12579;12745.61763147107;12943.288119153536;13370.264635445144;11845.348505832262;11995.700107497383;12290.324032178316;12378.81431778506;12718.173318734252 21:16:24 INFO - PROCESS | 3352 | __end_tp_report 21:16:24 INFO - PROCESS | 3352 | __start_cc_report 21:16:24 INFO - PROCESS | 3352 | _x_x_mozilla_cycle_collect,1106 21:16:24 INFO - PROCESS | 3352 | __end_cc_report 21:16:24 INFO - PROCESS | 3352 | __startTimestamp1469852184643__endTimestamp 21:16:24 INFO - PROCESS | 3352 | 21:16:24 INFO - PROCESS | 3352 | ------- Summary: start ------- 21:16:24 INFO - PROCESS | 3352 | Number of tests: 6 21:16:24 INFO - PROCESS | 3352 | 21:16:24 INFO - PROCESS | 3352 | [#0] dojo.html Cycles:165 Average:5398.77 Median:5603.80 stddev:581.99 (10.4%) stddev-sans-first:559.76 21:16:24 INFO - PROCESS | 3352 | Values: 3289.9 3302.5 3336.3 3338.4 3375.0 5328.1 5416.2 5430.6 5430.9 5432.5 5415.5 5472.6 5480.5 5495.4 5523.2 4902.2 4957.7 4982.2 5024.3 5081.0 4854.3 4963.8 5002.1 5012.4 5017.9 4898.6 4900.8 4913.7 4923.5 5010.8 4996.8 5001.0 5026.0 5030.7 5054.2 4790.1 4882.0 4934.9 4951.8 4973.7 5219.7 5228.0 5228.8 5269.4 5273.7 5769.1 5814.4 5816.7 5848.9 5853.9 5812.4 5813.9 5814.2 5851.6 5877.7 5500.5 5533.5 5547.3 5548.4 5552.8 5719.9 6027.9 6033.9 6038.5 6039.0 5629.5 5654.9 5673.9 5723.9 5736.6 5769.9 5773.4 5782.5 5802.1 5814.3 5630.5 5711.5 5741.3 5752.4 5754.1 5755.5 5797.6 5811.2 5813.0 5839.9 4477.7 4488.5 4488.8 4508.8 4514.5 3950.3 3956.0 3986.5 4031.2 4062.4 5518.7 5540.2 5541.2 5545.5 5577.3 5750.3 5787.5 5790.2 5811.1 5833.5 5388.1 5428.3 5518.5 5523.3 5535.2 4983.7 4991.8 4992.0 4994.7 5009.9 6023.6 6038.8 6040.0 6045.4 6077.1 5679.5 5728.7 5766.5 5772.4 5785.7 6037.8 6038.2 6050.0 6061.4 6068.5 5692.6 5706.2 5708.5 5712.2 5723.4 5648.2 5668.5 5707.8 5776.1 5786.6 5627.3 5636.2 5647.0 5673.2 5674.5 5684.6 5689.3 5693.6 5693.9 5723.5 5543.1 5579.4 5599.9 5601.7 5604.6 5519.3 5607.8 5621.6 5652.7 5679.4 5577.1 5589.2 5603.8 5620.2 5628.5 21:16:24 INFO - PROCESS | 3352 | 21:16:24 INFO - PROCESS | 3352 | [#1] ext.html Cycles:180 Average:66875.18 Median:3811.93 stddev:140628.57 (3689.2%) stddev-sans-first:140935.08 21:16:24 INFO - PROCESS | 3352 | Values: 618.1 620.2 637.8 665.8 679.9 32853.3 36444.0 36467.0 36499.1 36503.3 37052.6 37822.2 37835.4 47040.5 48310.8 9029.7 9155.0 9162.1 9171.5 9201.3 8965.9 9054.3 9057.3 9058.4 9061.6 8545.2 8855.5 10914.4 11094.1 11134.8 10978.3 11031.8 11041.9 11042.8 11043.3 8428.8 10886.7 10948.3 10957.6 10961.6 34599.0 37812.6 47895.0 48892.3 48944.5 3625.2 3758.2 3799.0 3824.9 3870.5 34742.8 34827.0 35088.3 35426.3 36095.5 4479.3 4481.2 4503.0 4516.4 4553.6 371796.0 372594.0 373566.0 373879.1 374495.0 37941.4 38984.4 38994.4 67248.7 68075.3 40313.2 40423.2 40516.2 40719.4 40782.6 17317.7 17725.7 17728.8 17738.7 17769.6 14507.0 14724.6 14746.1 14747.0 14754.9 8612.4 8915.9 9188.1 9201.4 9277.6 3419.4 3439.3 3458.8 3460.3 3501.2 1096.3 1098.4 1169.5 1173.9 1197.8 34035.5 34127.0 34319.3 34341.8 34552.0 1112.4 1125.3 1134.5 1171.0 1266.0 18133.7 18426.4 18440.4 18590.6 18591.7 599101.0 599628.0 602614.0 604065.0 604786.0 56391.2 60829.7 62805.7 64405.4 64756.0 1253.5 1272.7 1280.8 1282.2 1436.5 33545.2 35396.3 35485.6 35526.0 35904.3 55765.4 56245.7 56540.7 56550.0 56571.2 522511.0 529959.0 537198.0 539664.0 726997.0 29505.1 29849.7 30269.4 30900.8 30972.4 50793.5 50944.7 51364.5 51761.7 51873.5 42795.0 43001.6 43045.6 43211.8 44090.3 42794.0 43015.1 45431.5 45654.5 47003.0 43969.8 48286.8 49171.8 49767.5 49811.3 44844.1 46104.5 47688.8 47919.5 48609.0 35233.3 35333.3 35885.1 36239.0 36391.0 21:16:24 INFO - PROCESS | 3352 | 21:16:24 INFO - PROCESS | 3352 | [#2] jquery.html Cycles:180 Average:27693.65 Median:23559.09 stddev:78005.66 (331.1%) stddev-sans-first:78197.62 21:16:24 INFO - PROCESS | 3352 | Values: 425.4 425.9 431.5 432.3 450.3 18779.0 19676.5 20658.9 20716.2 20832.5 17956.2 18728.3 19036.2 19087.1 19826.4 5761.3 5972.3 6001.2 6010.5 6012.7 5865.5 5867.9 5877.3 5941.0 5950.9 5751.9 5869.2 5873.0 5889.1 5913.2 5832.0 5871.4 5883.7 5899.7 5926.6 5130.6 5273.2 5274.3 5284.6 5305.6 23454.5 23663.6 24408.6 24498.6 24506.8 2583.2 2650.6 2655.1 2665.0 2834.6 14636.5 14713.3 14740.4 15072.7 15142.8 2005.0 2011.5 2015.7 2052.4 2135.7 53252.5 53632.2 55272.7 58853.0 68649.0 10997.4 11185.0 11449.9 11467.2 11642.1 14188.6 15311.7 16041.8 16125.8 16396.9 9049.3 9070.5 9089.6 9090.9 9118.8 8435.4 8465.9 8906.7 8932.8 8978.4 6976.5 6997.8 7101.6 7177.9 7195.2 1902.7 1992.4 2049.3 2076.8 2084.2 1014.0 1014.5 1024.6 1070.2 1104.3 19361.8 19953.3 20062.9 20188.9 20494.4 1043.7 1103.1 1107.2 1133.6 1154.3 11180.7 11339.9 11406.8 11643.2 11692.6 462069.0 476811.0 481186.0 481969.0 484237.0 78462.4 79198.2 80490.6 80602.0 81688.6 1089.0 1100.5 1116.4 1116.7 1119.2 17342.8 17569.2 17572.9 17613.0 17955.3 26873.3 36006.6 36095.1 37295.6 39048.0 48479.5 49025.5 50079.2 50746.0 51003.0 8760.5 8887.9 8906.1 9087.9 9157.2 12829.9 12886.0 13135.9 13154.5 13655.2 11245.8 11348.5 11396.4 11558.7 11783.7 11467.1 11764.8 11769.6 11856.1 12284.4 12022.6 12181.6 12233.0 12374.3 12468.1 8647.5 8835.1 8892.0 8998.2 9048.4 11732.4 11858.9 11918.1 12114.8 12234.4 21:16:24 INFO - PROCESS | 3352 | 21:16:24 INFO - PROCESS | 3352 | [#3] mootools.html Cycles:180 Average:6110.78 Median:2724.76 stddev:3575.63 (131.2%) stddev-sans-first:3567.46 21:16:24 INFO - PROCESS | 3352 | Values: 1310.5 1466.9 1469.8 1662.1 1843.3 8720.2 9053.8 9059.6 9340.0 9352.4 8988.1 9196.6 9197.3 9209.1 9214.3 2155.4 2397.9 2414.6 2452.7 2468.5 2452.1 2496.5 2505.3 2509.8 2510.3 2358.8 2371.8 2374.0 2375.9 2381.4 2150.3 2325.8 2376.3 2393.9 2406.4 2297.7 2317.0 2322.4 2325.9 2328.7 8722.1 8722.3 8738.9 8756.3 8779.0 5284.6 5420.2 5422.7 5426.4 5432.7 3573.9 3596.4 3631.0 3782.3 3790.4 1154.4 1155.2 1155.8 1183.2 1190.9 13281.6 13291.6 13307.0 13336.8 13359.8 4873.1 4955.4 4975.7 4986.2 5022.8 11243.9 11311.8 11362.4 11388.7 11389.3 5006.3 5554.6 5606.0 5606.7 5623.2 4993.2 5152.1 5164.8 5175.9 5187.5 4268.9 4272.8 4317.0 4361.5 4369.2 1310.6 1341.7 1350.1 1357.3 1360.2 992.2 993.9 1004.6 1008.3 1009.8 6144.9 6209.5 6218.3 6248.3 6306.9 482.6 494.1 499.9 502.8 508.1 4083.6 4092.6 4136.0 4142.2 4142.5 11376.1 11729.1 11765.1 11830.1 11852.7 11602.7 11607.7 11612.8 11697.4 11750.7 8356.6 8713.4 8722.0 8792.6 8890.2 5990.2 6199.8 6208.2 6238.7 6245.8 10826.4 10931.2 11004.4 11150.9 11184.0 11041.4 11298.0 11304.3 11319.7 11325.3 8287.0 8319.8 8411.1 8429.8 8430.5 8901.3 8956.5 8973.3 8977.8 9034.9 7750.3 7904.5 8039.9 8086.4 8167.2 7652.2 7670.3 7723.5 7744.2 7786.1 7235.7 7239.6 7278.3 7303.0 7487.1 5369.0 5371.2 5404.9 5410.4 5485.5 5208.1 5234.7 5253.7 5280.7 5405.6 21:16:24 INFO - PROCESS | 3352 | 21:16:24 INFO - PROCESS | 3352 | [#4] prototype.html Cycles:180 Average:4626.87 Median:5203.58 stddev:1333.00 (25.6%) stddev-sans-first:1297.40 21:16:24 INFO - PROCESS | 3352 | Values: 344.1 349.9 351.1 352.6 355.2 5405.4 5439.9 5442.8 5458.6 5489.9 5343.5 5343.7 5402.0 5407.4 5428.9 3944.0 4049.6 4082.1 4196.9 4248.6 4174.7 4220.3 4224.1 4233.6 4234.5 4179.0 4194.3 4202.4 4208.2 4226.1 4071.9 4158.5 4238.9 4244.1 4253.6 3758.8 3780.3 3791.7 3795.1 3800.5 5207.5 5220.2 5232.6 5234.2 5234.5 4778.0 4805.8 4814.6 4843.0 4866.2 5561.4 5569.0 5577.7 5597.5 5607.9 4132.6 4162.8 4166.3 4170.0 4183.1 5573.0 5586.5 5685.4 5858.6 5864.8 4077.6 4103.8 4106.7 4127.9 4144.3 4138.6 4146.5 4190.2 4192.8 4207.3 4558.2 4804.9 4806.8 4820.6 4840.8 5551.3 5570.4 5587.6 5630.1 5653.5 1422.9 1436.5 1440.4 1456.9 1467.2 912.1 938.7 939.7 945.6 960.4 4789.6 4935.3 4954.8 4974.7 4987.4 5144.8 5151.7 5223.8 5257.2 5274.2 4684.7 4962.2 4982.4 5011.3 5013.3 2819.6 2831.7 2833.3 2836.3 2839.9 5305.4 5364.4 5424.3 5431.3 5476.3 5095.5 5175.5 5239.7 5268.2 5300.4 5402.3 5428.7 5429.5 5467.2 5469.9 5161.8 5199.6 5216.4 5243.2 5312.0 4933.3 5081.8 5147.4 5152.7 5161.4 5864.2 5993.3 6046.3 6065.2 6138.8 4081.0 4096.9 4099.2 4105.4 4114.2 5658.6 5794.4 5843.5 5867.1 5894.5 5620.2 5672.1 5686.3 5689.9 5711.6 5623.6 5681.3 5710.9 5713.9 5732.9 5348.6 5555.8 5559.3 5584.8 5605.2 5634.4 5640.4 5701.3 5707.9 5708.3 5378.7 5542.3 5586.6 5616.4 5624.8 21:16:24 INFO - PROCESS | 3352 | 21:16:24 INFO - PROCESS | 3352 | [#5] yui.html Cycles:180 Average:19542.04 Median:4870.05 stddev:25835.35 (530.5%) stddev-sans-first:25869.25 21:16:24 INFO - PROCESS | 3352 | Values: 740.7 782.4 800.3 863.6 869.0 15069.6 16066.7 16243.0 16572.8 16734.6 15207.6 15354.2 15366.4 15374.3 15429.0 717.9 738.7 741.3 874.6 884.8 734.7 842.7 862.6 866.0 872.6 901.9 902.7 906.4 906.4 906.7 740.8 897.3 897.5 897.8 898.0 648.7 667.9 668.1 668.2 668.5 15082.5 15210.1 15245.1 15255.3 15273.9 13113.0 13568.3 13781.3 13812.7 13885.7 13069.9 13128.4 13194.5 13281.6 13387.8 4611.1 4624.5 4674.2 4721.0 4747.9 65338.7 66940.0 67314.0 75796.6 76911.6 12292.4 12344.2 12369.4 12469.5 12493.3 23303.1 23348.4 23426.4 23440.5 23513.8 8953.7 9033.1 9223.5 9227.1 9237.0 4794.7 5034.1 5062.3 5139.4 5255.8 3936.4 4051.7 4060.7 4068.6 4068.8 673.7 692.2 696.3 716.7 717.0 707.5 771.5 789.8 822.7 848.9 9742.7 10333.3 11177.0 11341.5 11782.9 740.2 773.1 798.2 819.7 831.4 4945.4 5244.3 5267.7 5301.3 5325.5 94198.0 106681.5 108426.0 113210.4 114560.4 60732.0 61088.4 61921.0 72329.6 82853.2 61128.4 80174.6 82613.2 82614.2 83171.2 9363.8 9455.8 9478.9 9492.4 9511.6 43063.0 46541.3 51040.5 53566.0 55981.4 53121.2 53239.7 63156.7 67333.3 77617.0 14323.0 14441.9 15036.8 15438.8 15477.7 15647.1 16326.8 16444.6 16458.2 16984.0 12894.7 13290.3 14608.0 14853.1 15576.1 15242.0 15620.1 15998.5 16069.6 16352.1 15222.6 15519.3 15578.1 15708.2 15780.5 12378.8 12579.0 12745.6 12943.3 13370.3 11845.3 11995.7 12290.3 12378.8 12718.2 21:16:24 INFO - PROCESS | 3352 | -------- Summary: end -------- 21:16:24 INFO - PROCESS | 3352 | 21:16:25 INFO - TEST-INFO | 3352: exit 0 21:16:25 INFO - TEST-OK | dromaeo_css | took 1112882ms 21:16:25 INFO - TEST-START | kraken 21:16:25 INFO - operating with platform_type : linux_ 21:16:25 INFO - Initialising browser for kraken test... 21:16:25 INFO - TEST-INFO | started process 3544 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8M4rLw/profile http://localhost:60049/getInfo.html) 21:16:29 INFO - PROCESS | 3544 | __metrics Screen width/height:1600/1200 21:16:29 INFO - PROCESS | 3544 | colorDepth:24 21:16:29 INFO - PROCESS | 3544 | Browser inner width/height: 1024/697 21:16:29 INFO - PROCESS | 3544 | __metrics 21:16:30 INFO - PROCESS | 3544 | 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" 21:16:30 ERROR - PROCESS | 3544 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:16:31 INFO - TEST-INFO | 3544: exit 0 21:16:31 INFO - Browser initialized. 21:16:31 INFO - Running cycle 1/1 for kraken test... 21:16:31 INFO - TEST-INFO | started process 3632 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8M4rLw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:16:43 INFO - PROCESS | 3632 | RSS: Main: 145334272 21:16:43 INFO - PROCESS | 3632 | 21:16:45 INFO - PROCESS | 3632 | Cycle 1(1): loaded http://localhost:60049/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60049/tests/kraken/driver.html?testName=audio-beat-detection) 21:16:46 INFO - PROCESS | 3632 | RSS: Main: 194740224 21:16:46 INFO - PROCESS | 3632 | 21:16:49 INFO - PROCESS | 3632 | Cycle 1(1): loaded http://localhost:60049/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60049/tests/kraken/driver.html?testName=audio-dft) 21:16:49 INFO - PROCESS | 3632 | RSS: Main: 191979520 21:16:49 INFO - PROCESS | 3632 | 21:16:52 INFO - PROCESS | 3632 | Cycle 1(1): loaded http://localhost:60049/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60049/tests/kraken/driver.html?testName=audio-fft) 21:16:53 INFO - PROCESS | 3632 | RSS: Main: 278851584 21:16:53 INFO - PROCESS | 3632 | 21:16:55 INFO - PROCESS | 3632 | Cycle 1(1): loaded http://localhost:60049/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60049/tests/kraken/driver.html?testName=audio-oscillator) 21:16:56 INFO - PROCESS | 3632 | RSS: Main: 187047936 21:16:56 INFO - PROCESS | 3632 | 21:16:58 INFO - PROCESS | 3632 | Cycle 1(1): loaded http://localhost:60049/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60049/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:16:58 INFO - PROCESS | 3632 | RSS: Main: 187957248 21:16:58 INFO - PROCESS | 3632 | 21:17:01 INFO - PROCESS | 3632 | Cycle 1(1): loaded http://localhost:60049/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60049/tests/kraken/driver.html?testName=imaging-darkroom) 21:17:02 INFO - PROCESS | 3632 | RSS: Main: 222945280 21:17:02 INFO - PROCESS | 3632 | 21:17:05 INFO - PROCESS | 3632 | Cycle 1(1): loaded http://localhost:60049/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60049/tests/kraken/driver.html?testName=imaging-desaturate) 21:17:06 INFO - PROCESS | 3632 | RSS: Main: 223387648 21:17:06 INFO - PROCESS | 3632 | 21:17:09 INFO - PROCESS | 3632 | Cycle 1(1): loaded http://localhost:60049/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60049/tests/kraken/driver.html?testName=json-parse-financial) 21:17:09 INFO - PROCESS | 3632 | RSS: Main: 223473664 21:17:09 INFO - PROCESS | 3632 | 21:17:11 INFO - PROCESS | 3632 | Cycle 1(1): loaded http://localhost:60049/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60049/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:17:11 INFO - PROCESS | 3632 | RSS: Main: 201428992 21:17:11 INFO - PROCESS | 3632 | 21:17:13 INFO - PROCESS | 3632 | Cycle 1(1): loaded http://localhost:60049/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60049/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:17:14 INFO - PROCESS | 3632 | RSS: Main: 202264576 21:17:14 INFO - PROCESS | 3632 | 21:17:16 INFO - PROCESS | 3632 | Cycle 1(1): loaded http://localhost:60049/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60049/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:17:16 INFO - PROCESS | 3632 | RSS: Main: 211255296 21:17:16 INFO - PROCESS | 3632 | 21:17:19 INFO - PROCESS | 3632 | Cycle 1(1): loaded http://localhost:60049/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60049/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:17:19 INFO - PROCESS | 3632 | RSS: Main: 216051712 21:17:19 INFO - PROCESS | 3632 | 21:17:22 INFO - PROCESS | 3632 | Cycle 1(1): loaded http://localhost:60049/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60049/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:17:23 INFO - PROCESS | 3632 | RSS: Main: 215318528 21:17:23 INFO - PROCESS | 3632 | 21:17:24 INFO - PROCESS | 3632 | Cycle 1(1): loaded http://localhost:60049/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60049/tests/kraken/driver.html?testName=ai-astar) 21:17:25 INFO - PROCESS | 3632 | RSS: Main: 258641920 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | __start_tp_report 21:17:25 INFO - PROCESS | 3632 | _x_x_mozilla_page_load 21:17:25 INFO - PROCESS | 3632 | _x_x_mozilla_page_load_details 21:17:25 INFO - PROCESS | 3632 | |i|pagename|runs| 21:17:25 INFO - PROCESS | 3632 | |0;ai-astar;110;104;109;110;112;110;110;111;108;109 21:17:25 INFO - PROCESS | 3632 | |1;audio-beat-detection;155;122;131;170;128;124;146;127;179;124 21:17:25 INFO - PROCESS | 3632 | |2;audio-dft;160;160;161;161;160;160;160;159;160;158 21:17:25 INFO - PROCESS | 3632 | |3;audio-fft;75;75;75;116;75;74;74;73;96;76 21:17:25 INFO - PROCESS | 3632 | |4;audio-oscillator;84;83;118;83;83;84;114;85;85;122 21:17:25 INFO - PROCESS | 3632 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;119;116;119 21:17:25 INFO - PROCESS | 3632 | |6;imaging-darkroom;146;145;146;146;145;145;146;145;146;145 21:17:25 INFO - PROCESS | 3632 | |7;imaging-desaturate;95;94;94;95;95;95;95;95;94;95 21:17:25 INFO - PROCESS | 3632 | |8;json-parse-financial;53;50;51;51;52;54;53;54;51;51 21:17:25 INFO - PROCESS | 3632 | |9;json-stringify-tinderbox;63;60;60;60;61;60;61;61;61;61 21:17:25 INFO - PROCESS | 3632 | |10;stanford-crypto-aes;76;76;76;75;77;83;76;75;109;77 21:17:25 INFO - PROCESS | 3632 | |11;stanford-crypto-ccm;129;124;119;119;119;118;119;117;132;130 21:17:25 INFO - PROCESS | 3632 | |12;stanford-crypto-pbkdf2;153;154;151;157;160;151;159;153;153;150 21:17:25 INFO - PROCESS | 3632 | |13;stanford-crypto-sha256-iterative;61;60;60;61;64;64;60;62;60;61 21:17:25 INFO - PROCESS | 3632 | __end_tp_report 21:17:25 INFO - PROCESS | 3632 | __start_cc_report 21:17:25 INFO - PROCESS | 3632 | _x_x_mozilla_cycle_collect,1577 21:17:25 INFO - PROCESS | 3632 | __end_cc_report 21:17:25 INFO - PROCESS | 3632 | __startTimestamp1469852245078__endTimestamp 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | ------- Summary: start ------- 21:17:25 INFO - PROCESS | 3632 | Number of tests: 14 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | [#0] ai-astar Cycles:10 Average:109.30 Median:110.00 stddev:2.16 (2.0%) stddev-sans-first:2.28 21:17:25 INFO - PROCESS | 3632 | Values: 110.0 104.0 109.0 110.0 112.0 110.0 110.0 111.0 108.0 109.0 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:138.50 stddev:20.84 (15.0%) stddev-sans-first:21.44 21:17:25 INFO - PROCESS | 3632 | Values: 155.0 122.0 131.0 170.0 128.0 124.0 146.0 127.0 179.0 124.0 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | [#2] audio-dft Cycles:10 Average:159.90 Median:160.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 21:17:25 INFO - PROCESS | 3632 | Values: 160.0 160.0 161.0 161.0 160.0 160.0 160.0 159.0 160.0 158.0 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.00 stddev:14.07 (18.8%) stddev-sans-first:14.76 21:17:25 INFO - PROCESS | 3632 | Values: 75.0 75.0 75.0 116.0 75.0 74.0 74.0 73.0 96.0 76.0 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:83.50 stddev:16.62 (19.9%) stddev-sans-first:17.22 21:17:25 INFO - PROCESS | 3632 | Values: 84.0 83.0 118.0 83.0 83.0 84.0 114.0 85.0 85.0 122.0 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:1.03 (0.9%) stddev-sans-first:1.00 21:17:25 INFO - PROCESS | 3632 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 119.0 116.0 119.0 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 21:17:25 INFO - PROCESS | 3632 | Values: 146.0 145.0 146.0 146.0 145.0 145.0 146.0 145.0 146.0 145.0 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 21:17:25 INFO - PROCESS | 3632 | Values: 95.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.50 stddev:1.41 (2.7%) stddev-sans-first:1.45 21:17:25 INFO - PROCESS | 3632 | Values: 53.0 50.0 51.0 51.0 52.0 54.0 53.0 54.0 51.0 51.0 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:61.00 stddev:0.92 (1.5%) stddev-sans-first:0.53 21:17:25 INFO - PROCESS | 3632 | Values: 63.0 60.0 60.0 60.0 61.0 60.0 61.0 61.0 61.0 61.0 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:76.00 stddev:10.45 (13.7%) stddev-sans-first:10.98 21:17:25 INFO - PROCESS | 3632 | Values: 76.0 76.0 76.0 75.0 77.0 83.0 76.0 75.0 109.0 77.0 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | [#11] stanford-crypto-ccm Cycles:10 Average:122.60 Median:121.50 stddev:5.68 (4.7%) stddev-sans-first:5.53 21:17:25 INFO - PROCESS | 3632 | Values: 129.0 124.0 119.0 119.0 119.0 118.0 119.0 117.0 132.0 130.0 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.10 Median:153.50 stddev:3.45 (2.2%) stddev-sans-first:3.63 21:17:25 INFO - PROCESS | 3632 | Values: 153.0 154.0 151.0 157.0 160.0 151.0 159.0 153.0 153.0 150.0 21:17:25 INFO - PROCESS | 3632 | 21:17:25 INFO - PROCESS | 3632 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.00 stddev:1.57 (2.6%) stddev-sans-first:1.66 21:17:25 INFO - PROCESS | 3632 | Values: 61.0 60.0 60.0 61.0 64.0 64.0 60.0 62.0 60.0 61.0 21:17:25 INFO - PROCESS | 3632 | -------- Summary: end -------- 21:17:25 INFO - PROCESS | 3632 | 21:17:26 INFO - TEST-INFO | 3632: exit 0 21:17:26 INFO - TEST-OK | kraken | took 60596ms 21:17:26 INFO - SUITE-END | took 1173s 21:17:26 INFO - Completed test suite (00:19:33) 21:17:26 INFO - javascript benchmark 21:17:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3289.9013029609114, 3302.4881143422776, 3336.3494118646868, 3338.4491558892782, 3375.021243839287, 5328.12086005809, 5416.187571864222, 5430.647007944481, 5430.918536221955, 5432.483914028166, 5415.512603865652, 5472.589555783312, 5480.479354461329, 5495.422980587041, 5523.171524271362, 4902.240152776325, 4957.677750946195, 4982.202847544394, 5024.346834911456, 5080.958403527268, 4854.344663470421, 4963.776630051656, 5002.099622068026, 5012.373453318335, 5017.949820501803, 4898.583620392253, 4900.8039678413, 4913.656044076916, 4923.507649235084, 5010.824621138243, 4996.800127994894, 5000.999999999964, 5025.974870125626, 5030.723310217936, 5054.191329387317, 4790.089882922269, 4881.951180488185, 4934.876628084291, 4951.752412379366, 4973.701577905337, 5219.686818790885, 5227.973860130713, 5228.816991405321, 5269.394019687747, 5273.657212281228, 5769.07694768835, 5814.447627475383, 5816.709164541756, 5848.853778655525, 5853.88292234153, 5812.360640329561, 5813.941860581383, 5814.244148260741, 5851.590388672795, 5877.735501902382, 5500.50495455411, 5533.474319939599, 5547.278853749027, 5548.4451554845, 5552.833414997556, 5719.8570035749435, 6027.8794424111275, 6033.909491357633, 6038.516918646497, 6038.969805150946, 5629.521490673339, 5654.9151762723595, 5673.858153546194, 5723.942760572424, 5736.569757268222, 5769.88460230793, 5773.364929857712, 5782.45066718661, 5802.100674395476, 5814.273215848019, 5630.5214056805635, 5711.514521265656, 5741.282339707536, 5752.424757524298, 5754.0505816540635, 5755.510781583528, 5797.594168408255, 5811.157382179645, 5813.040848260074, 5839.941600583939, 4477.70894891831, 4488.483824360241, 4488.798004089824, 4508.774561271924, 4514.525974772667, 3950.2692001979726, 3955.980220098939, 3986.4618276532296, 4031.213913286881, 4062.3500239961463, 5518.724063796794, 5540.168974653834, 5541.168824676331, 5545.500904918576, 5577.3028371453565, 5750.338711048199, 5787.5080618147085, 5790.160426738099, 5811.070228763378, 5833.533317334603, 5388.110961691355, 5428.321459817523, 5518.53092487135, 5523.309586301712, 5535.225068490415, 4983.651144419856, 4991.825286115041, 4992.0, 4994.650374473825, 5009.89980200394, 6023.608465449732, 6038.788642397583, 6040.0, 6045.395460454008, 6077.1491991121275, 5679.488846003879, 5728.684922329312, 5766.538676905921, 5772.39389864075, 5785.739641716217, 6037.849053773515, 6038.215032045981, 6050.0, 6061.39386061408, 6068.4538391544975, 5692.629979051514, 5706.172604972338, 5708.54331653475, 5712.200291959212, 5723.399043100581, 5648.15277708347, 5668.546516278605, 5707.771689132388, 5776.075827867521, 5786.623869448473, 5627.324721033502, 5636.210930469655, 5647.0, 5673.205751194753, 5674.517665998436, 5684.63049901755, 5689.31728192603, 5693.601447898607, 5693.857653558694, 5723.456271654186, 5543.113101903676, 5579.386267510652, 5599.944000559943, 5601.719914004365, 5604.579656525695, 5519.2824932758485, 5607.831765047055, 5621.55027597783, 5652.68910209926, 5679.403662615366, 5577.051901176728, 5589.161625756169, 5603.80386686464, 5620.213170156263, 5628.493435590816], "value": 5362.28379457168, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.0790621973258, 620.2402057479588, 637.8405398650337, 665.8435267712086, 679.8980152977052, 32853.34293314132, 36444.0, 36467.0, 36499.08752281194, 36503.26993460129, 37052.62947370525, 37822.24355512888, 37835.432468512925, 47040.52959470404, 48310.75844620772, 9029.683961061364, 9154.954225228865, 9162.083791620851, 9171.541422928827, 9201.263898888074, 8965.865512017324, 9054.27565794738, 9057.275417966579, 9058.411203271799, 9061.637534498612, 8545.188207120314, 8855.512946787923, 10914.399708016055, 11094.112471002341, 11134.832977505344, 10978.286411383275, 11031.779364412707, 11041.889581104166, 11042.779144417147, 11043.33739975596, 8428.8314233715, 10886.67339979802, 10948.288361256573, 10957.561697532168, 10961.56153753849, 34599.0, 37812.62187378119, 47895.0, 48892.26661600079, 48944.510554894354, 3625.23869987303, 3758.1544152565452, 3798.9810050949845, 3824.8852534424013, 3870.4581358609817, 34742.826285868665, 34827.0, 35088.29823403543, 35426.29147417063, 36095.458568121496, 4479.283314669669, 4481.193385190664, 4502.977485112586, 4516.390287311204, 4553.567411095941, 371796.0, 372594.0, 373566.0, 373879.13060434797, 374495.0, 37941.43087853684, 38984.41523377151, 38994.41508377377, 67248.66375668188, 68075.31924680789, 40313.19373612511, 40423.19153616911, 40516.18967620631, 40719.38920916188, 40782.592174078476, 17317.653646926992, 17725.7341139883, 17728.822711772977, 17738.73391899097, 17769.644607107784, 14507.0, 14724.55826325212, 14746.11523308605, 14747.0, 14754.852451475563, 8612.354073444516, 8915.866262006075, 9188.081191880892, 9201.447913125234, 9277.628894844265, 3419.4357930941114, 3439.329330780474, 3458.844352004166, 3460.3425349183576, 3501.1597216667815, 1096.2655021135859, 1098.368438148052, 1169.4620474581618, 1173.8826117388194, 1197.8203269509643, 34035.48946765801, 34127.0, 34319.31361372759, 34341.828290858386, 34552.0, 1112.360392774158, 1125.3416751200475, 1134.4554613785429, 1170.9590164344377, 1265.968235887767, 18133.727994080098, 18426.355077572487, 18440.432563232007, 18590.628187436174, 18591.721124183146, 599101.0, 599628.0, 602614.0, 604065.0, 604786.0, 56391.15413268804, 60829.69585152046, 62805.68597157076, 64405.35594643994, 64756.0, 1253.5361916091106, 1272.6945533071996, 1280.750253700538, 1282.2498838179583, 1436.5187662133303, 33545.16137096592, 35396.29207415889, 35485.64514354823, 35526.0, 35904.28191436209, 55765.44234557765, 56245.71877140588, 56540.717296413255, 56550.0, 56571.15143272936, 522511.0, 529959.0, 537198.0, 539664.0, 726997.0, 29505.11484655464, 29849.70150298469, 30269.394612108073, 30900.845495772377, 30972.380552389277, 50793.49206507888, 50944.74527627338, 51364.48635513597, 51761.741191293804, 51873.48126518686, 42795.0, 43001.56998430101, 43045.56954430541, 43211.783941080095, 44090.338644920994, 42794.008838761976, 43015.139697206505, 45431.54568454273, 45654.54345456503, 47003.0, 43969.78015110032, 48286.75856620695, 49171.75414123049, 49767.50232497774, 49811.25283120829, 44844.10311793681, 46104.538954611366, 47688.761556191996, 47919.5208047929, 48609.0, 35233.29533409369, 35333.29333413266, 35885.1028724284, 36239.0, 36391.0], "value": 20506.608615264187, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.37469919218745, 425.92972159593666, 431.52532214564, 432.2543612268835, 450.26381865649654, 18779.0, 19676.508087297825, 20658.89670551649, 20716.171353145874, 20832.47918802027, 17956.191971361288, 18728.344507942224, 19036.238550457998, 19087.14107865146, 19826.40520784379, 5761.337446193678, 5972.343042265347, 6001.2498437695285, 6010.549208809335, 6012.729427175787, 5865.501432378253, 5867.941320586782, 5877.2947246330505, 5941.019731744257, 5950.880982380349, 5751.884962300752, 5869.178315035899, 5872.9706351468185, 5889.116632505115, 5913.201717768111, 5831.950248955165, 5871.44221298978, 5883.705814709268, 5899.734511946973, 5926.614770039933, 5130.615203859726, 5273.235380869791, 5274.26160337553, 5284.550813180885, 5305.602079843989, 23454.530909381887, 23663.645045324334, 24408.633870491958, 24498.632520512205, 24506.754932450625, 2583.186296316654, 2650.562657161566, 2655.123809142978, 2664.9600255995983, 2834.6173266609158, 14636.487722929647, 14713.337899794535, 14740.410383584644, 15072.698546029018, 15142.848571514254, 2005.0375819606525, 2011.5416323165734, 2015.748031496063, 2052.404802607242, 2135.7116789233564, 53252.46747532514, 53632.19551706727, 55272.72363638196, 58853.0, 68649.0, 10997.395143267036, 11185.0, 11449.912258335446, 11467.197296189186, 11642.068634509218, 14188.645283867985, 15311.693766124838, 16041.759373609406, 16125.75811362807, 16396.918015410087, 9049.276057915482, 9070.455772653662, 9089.636414543476, 9090.86363704545, 9118.817623647623, 8435.367347448966, 8465.915340846635, 8906.73279801607, 8932.821343573221, 8978.371513993958, 6976.5465244758925, 6997.755078572225, 7101.573905565682, 7177.856442871113, 7195.244499327495, 1902.6966527928414, 1992.4221975626906, 2049.282751037125, 2076.792320767911, 2084.155916853677, 1013.9543720532594, 1014.5333146752429, 1024.5696807340935, 1070.1652710885521, 1104.2607892569235, 19361.806381936, 19953.303969162494, 20062.899685501772, 20188.89905550463, 20494.38516844497, 1043.7077618266896, 1103.1450625765096, 1107.2360071550765, 1133.586241022021, 1154.3189518184145, 11180.720481987853, 11339.94330028361, 11406.771864562663, 11643.243189192675, 11692.590759323553, 462069.0, 476811.0, 481186.0, 481969.0, 484237.0, 78462.44743335707, 79198.20801792023, 80490.59754701189, 80602.0, 81688.59155704302, 1088.967330980072, 1100.5047728522197, 1116.4250411038322, 1116.6817457024708, 1119.1997721629075, 17342.826571734626, 17569.209385577426, 17572.91213543924, 17613.0, 17955.281788728305, 26873.313086225866, 36006.63993360138, 36095.097622559646, 37295.6270437303, 39048.0, 48479.5152048475, 49025.5097449021, 50079.24881126859, 50746.0, 51003.0, 8760.474371537728, 8887.933447986283, 8906.109389061301, 9087.863682044906, 9157.221636161, 12829.93585032069, 12886.0, 13135.934320328719, 13154.539591114266, 13655.180689158682, 11245.775084498428, 11348.54605815758, 11396.373199474108, 11558.711032224262, 11783.705407364541, 11467.139964502863, 11764.764704705687, 11769.588064418047, 11856.11079169083, 12284.447199876206, 12022.579209727617, 12181.573644922386, 12233.0, 12374.319412432402, 12468.127231093737, 8647.524386158822, 8835.072317406837, 8891.955540222258, 8998.23515001206, 9048.41185322952, 11732.41337933117, 11858.940705296764, 11918.106142039209, 12114.818277725664, 12234.449449774604], "value": 9526.098532004473, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1310.5347601601425, 1466.9339879705415, 1469.8162729658793, 1662.0775469614366, 1843.2903332217093, 8720.215180633742, 9053.818923621524, 9059.592318345674, 9339.95330023349, 9352.438853668784, 8988.101189881008, 9196.586153623086, 9197.310201734863, 9209.125133112344, 9214.308926830498, 2155.4395857077193, 2397.8920948557356, 2414.5774489464316, 2452.681151450309, 2468.5062987402503, 2452.0682140786475, 2496.5131799299143, 2505.2734706934966, 2509.82431229814, 2510.2845688978646, 2358.7641235876445, 2371.774681405264, 2373.964390534143, 2375.857448553092, 2381.3570336009266, 2150.290404166621, 2325.848819826706, 2376.346504711201, 2393.868337241451, 2406.4224586099385, 2297.7242730872404, 2317.0268487235403, 2322.372959300996, 2325.860448373103, 2328.69726935499, 8722.127787221227, 8722.34582406322, 8738.86891696625, 8756.299496040365, 8779.03430622631, 5284.550813180885, 5420.159875219348, 5422.701751403672, 5426.403095659474, 5432.728363581805, 3573.910652233689, 3596.352656521825, 3630.9636903631153, 3782.3002744492355, 3790.431435284702, 1154.4227886056972, 1155.2317708723688, 1155.8092914669069, 1183.230899915062, 1190.922590031645, 13281.601551953458, 13291.601251962456, 13307.0, 13336.799948000787, 13359.799603005962, 4873.098476781806, 4955.380577427822, 4975.726335051607, 4986.202207646759, 5022.773975171053, 11243.887561124448, 11311.773764524827, 11362.431878406047, 11388.658340249805, 11389.316641001566, 5006.3491746073505, 5554.583406244547, 5605.971970140205, 5606.6916319602, 5623.240862483594, 4993.226049962262, 5152.098382782998, 5164.7934082636275, 5175.896482070413, 5187.507186817246, 4268.87193384199, 4272.807723652443, 4316.978415107905, 4361.498427680857, 4369.191699535596, 1310.6461255460972, 1341.6914109754812, 1350.1021820489461, 1357.3145561491383, 1360.217874722019, 992.211192102266, 993.9304248702666, 1004.6232662751469, 1008.2992320337295, 1009.7778488732467, 6144.8771024580155, 6209.472194863487, 6218.253809542884, 6248.31268560458, 6306.936930630635, 482.60667555941797, 494.140196058855, 499.9225120106389, 502.75867583559364, 508.0626244578746, 4083.632473077437, 4092.5702801205443, 4135.97932010338, 4142.212979533879, 4142.502899652061, 11376.089912806956, 11729.120315976335, 11765.058795296356, 11830.112741544419, 11852.703682407837, 11602.70993225176, 11607.6517704469, 11612.825807612893, 11697.356645384416, 11750.70623234426, 8356.582170891552, 8713.433626814236, 8721.956390218134, 8792.60433280504, 8890.155435233642, 5990.1913241713555, 6199.752009919733, 6208.192934918432, 6238.719257633326, 6245.750169993149, 10826.404547749635, 10931.234813562975, 11004.449777510932, 11150.888491114985, 11184.0, 11041.39272340029, 11298.0, 11304.265222760494, 11319.717007074889, 11325.263857849213, 8286.958565207377, 8319.750407487785, 8411.074781773877, 8429.789255268668, 8430.494170349553, 8901.287896968097, 8956.462612243286, 8973.32700047486, 8977.820443590961, 9034.909650903406, 7750.341220996278, 7904.486208396436, 8039.919600804151, 8086.353091752763, 8167.224113709188, 7652.196519365387, 7670.27132422419, 7723.53658780475, 7744.225577442211, 7786.143524212219, 7235.746748863765, 7239.638018098969, 7278.308560686726, 7303.0, 7487.101547814297, 5369.033573956725, 5371.194320851903, 5404.864878378072, 5410.350757908918, 5485.4788795064405, 5208.088584497622, 5234.685918844882, 5253.658512196695, 5280.656757310762, 5405.648632838853], "value": 4773.618953340222, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.1430837215334, 349.87229661173666, 351.14321056621856, 352.6086044490616, 355.2025702298339, 5405.378381486125, 5439.891202175954, 5442.809501667432, 5458.563314934806, 5489.886393738409, 5343.545798607113, 5343.706096164709, 5401.972990135044, 5407.3781515125775, 5428.9185662215095, 3943.98028009861, 4049.5950404959563, 4082.122343696105, 4196.9370459443135, 4248.553901840309, 4174.666026717855, 4220.26145424551, 4224.134052519242, 4233.597808208216, 4234.470691163605, 4179.0, 4194.328907374821, 4202.432671589327, 4208.221479026374, 4226.112516371566, 4071.857484988026, 4158.542560318348, 4238.894027649302, 4244.108737165199, 4253.638440732541, 3758.8120593970193, 3780.2988655105314, 3791.7345785795005, 3795.1214148996146, 3800.5439347278502, 5207.505286997729, 5220.164773636238, 5232.607554433394, 5234.162533994542, 5234.482000219931, 4777.996620709655, 4805.758547221256, 4814.638902082358, 4843.0, 4866.221404575251, 5561.388247292794, 5569.0, 5577.749001294911, 5597.524210442118, 5607.859803504904, 4132.607402296807, 4162.83348666053, 4166.250074986501, 4170.0, 4183.142455796555, 5572.972135139339, 5586.525145362649, 5685.431456854364, 5858.619189752695, 5864.794732184396, 4077.612626800449, 4103.774292413946, 4106.691998100155, 4127.938080928789, 4144.254034273799, 4138.627523522898, 4146.543880173179, 4190.182914331676, 4192.832286708497, 4207.305794543866, 4558.156741002925, 4804.8798780030775, 4806.831760888352, 4820.566149046603, 4840.845823608461, 5551.250581171571, 5570.41510641385, 5587.552995760329, 5630.127330263816, 5653.491185793216, 1422.9359678814478, 1436.5115860607234, 1440.4238304678213, 1456.919869407193, 1467.2370367408887, 912.1015799437548, 938.7418459923575, 939.736873675372, 945.5886689290181, 960.4477425480377, 4789.616830653539, 4935.3090567320605, 4954.826581069718, 4974.701517908937, 4987.401511818605, 5144.845654630292, 5151.728652305813, 5223.764930578133, 5257.185136303879, 5274.231028709882, 4684.74233917131, 4962.196359057121, 4982.377202849644, 5011.298418221452, 5013.273075404119, 2819.5629677400034, 2831.716828317152, 2833.3058400691552, 2836.262571731365, 2839.920830084555, 5305.389880163831, 5364.383095943938, 5424.267723857308, 5431.343440250762, 5476.342838859283, 5095.528678578185, 5175.482451754794, 5239.738013099406, 5268.2361057647195, 5300.390455097713, 5402.324709411324, 5428.728563571805, 5429.53848922846, 5467.16849855295, 5469.890602187934, 5161.838586318048, 5199.648091496314, 5216.426193118678, 5243.187305967581, 5312.043832110257, 4933.284673722362, 5081.796728130981, 5147.433782284021, 5152.711822044489, 5161.354830646169, 5864.237649105589, 5993.340732519507, 6046.27444706638, 6065.150878877049, 6138.785142519991, 4080.9795951020055, 4096.856610018635, 4099.1801639671585, 4105.404716316058, 4114.156597897364, 5658.632188907708, 5794.362620111869, 5843.532517398683, 5867.149263356704, 5894.528437724886, 5620.156976453564, 5672.12082127271, 5686.260786097946, 5689.914651280152, 5711.5716321276905, 5623.606347555632, 5681.346645135697, 5710.8572285691525, 5713.857153571194, 5732.885342293047, 5348.625596208228, 5555.805546806004, 5559.305086864142, 5584.776608935759, 5605.159226115952, 5634.4365563443325, 5640.351359593536, 5701.287339082614, 5707.885842283048, 5708.34354049273, 5378.650387724785, 5542.334919809648, 5586.608937374344, 5616.354119276336, 5624.803131890364], "value": 4207.964665041117, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.7074205688755, 782.405371917343, 800.2717527050386, 863.5509535041782, 868.9695860644874, 15069.62325941852, 16066.678666426664, 16243.0, 16572.83427165728, 16734.58163545912, 15207.619809504768, 15354.232288385592, 15366.385344586202, 15374.308156132973, 15429.0, 717.9389751871071, 738.7488253993641, 741.2809574712547, 874.6326542852001, 884.783228109111, 734.6951015328632, 842.7303262955857, 862.5730263519536, 865.9740207793744, 872.5942436766902, 901.8557030875062, 902.6885724425063, 906.3972458315245, 906.4380084347714, 906.7415786500816, 740.829609189884, 897.2956229359921, 897.5287973813735, 897.7755561109723, 897.9820403591924, 648.672420427681, 667.8931370980669, 668.0847239282215, 668.2014992084461, 668.5119862500402, 15082.54752357431, 15210.08739475635, 15245.085294882343, 15255.313510892036, 15273.923630381889, 13113.0, 13568.253746043963, 13781.310934453237, 13812.723745525134, 13885.652858678513, 13069.869301307055, 13128.409221584956, 13194.472221111144, 13281.60155195336, 13387.799183012263, 4611.100835337111, 4624.491305956342, 4674.2053850845095, 4720.952790472085, 4747.8575642730775, 65338.67330663364, 66940.0, 67314.0, 75796.62101689511, 76911.61544192243, 12292.446839892227, 12344.197627154206, 12369.381530923418, 12469.501219951282, 12493.25040497573, 23303.06787728472, 23348.416289592897, 23426.414339641644, 23440.531189376117, 23513.764862351498, 8953.731388058368, 9033.096690330914, 9223.49270790113, 9227.077292270718, 9237.030111838294, 4794.688345257547, 5034.144195486776, 5062.291279220912, 5139.408967968657, 5255.816046438356, 3936.4488971544006, 4051.736637118578, 4060.675145988314, 4068.5524592294823, 4068.7558746475306, 673.6564352180326, 692.19705142035, 696.2550071423528, 716.6774951271844, 716.9670602858919, 707.5224223649017, 771.4561234329897, 789.7630710786741, 822.7284995951321, 848.9278411334982, 9742.659006934866, 10333.328333658288, 11177.0, 11341.489632966537, 11782.94108529469, 740.2042803985629, 773.0800347586354, 798.1699033005725, 819.6721311475338, 831.3592276404567, 4945.406551213805, 5244.265802787613, 5267.710275934784, 5301.284326615934, 5325.547328477045, 94198.0, 106681.46659266653, 108426.0, 113210.43394783138, 114560.42719786514, 60732.0, 61088.38911610827, 61921.0, 72329.63835180896, 82853.17146828576, 61128.38871611316, 80174.599127004, 82613.17386826055, 82614.17385826065, 83171.16828831634, 9363.812723745623, 9455.763605909908, 9478.857817132886, 9492.382995105296, 9511.619535218513, 43063.0, 46541.30188047114, 51040.48959510357, 53566.0, 55981.440185599255, 53121.203181951525, 53239.733801330745, 63156.68421657862, 67333.32666673466, 77617.0, 14323.0, 14441.927790361402, 15036.774448383501, 15438.768418473508, 15477.690446191238, 15647.13940733225, 16326.755098673291, 16444.588885277484, 16458.177091145633, 16984.0, 12894.742105157657, 13290.335483225994, 14607.977441578987, 14853.108813471226, 15576.143312117942, 15242.0, 15620.140892251035, 15998.520044398687, 16069.629407950335, 16352.100634465218, 15222.55560790511, 15519.301631426388, 15578.143202123538, 15708.21458927072, 15780.526584202493, 12378.75242495163, 12579.0, 12745.61763147107, 12943.288119153536, 13370.264635445144, 11845.348505832262, 11995.700107497383, 12290.324032178316, 12378.81431778506, 12718.173318734252], "value": 7884.723822934324, "unit": "score"}], "name": "dromaeo_css", "value": 7412.84785440747, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 104.0, 109.0, 110.0, 112.0, 110.0, 110.0, 111.0, 108.0, 109.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 122.0, 131.0, 170.0, 128.0, 124.0, 146.0, 127.0, 179.0, 124.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 161.0, 160.0, 160.0, 160.0, 159.0, 160.0, 158.0], "value": 159.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 116.0, 75.0, 74.0, 74.0, 73.0, 96.0, 76.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 118.0, 83.0, 83.0, 84.0, 114.0, 85.0, 85.0, 122.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 119.0, 116.0, 119.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 51.0, 51.0, 52.0, 54.0, 53.0, 54.0, 51.0, 51.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 60.0, 60.0, 61.0, 60.0, 61.0, 61.0, 61.0, 61.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 75.0, 77.0, 83.0, 76.0, 75.0, 109.0, 77.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 124.0, 119.0, 119.0, 119.0, 118.0, 119.0, 117.0, 132.0, 130.0], "value": 122.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 154.0, 151.0, 157.0, 160.0, 151.0, 159.0, 153.0, 153.0, 150.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 61.0, 64.0, 64.0, 60.0, 62.0, 60.0, 61.0], "value": 61.3, "unit": "score"}], "name": "kraken", "value": 1472.9999999999998, "alertThreshold": 2.0}]} 21:17:26 INFO - Return code: 0 21:17:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:17:26 ERROR - # TBPL SUCCESS # 21:17:26 INFO - Running post-action listener: _resource_record_post_action 21:17:26 INFO - ##### 21:17:26 INFO - ##### Finished run-tests step (success) 21:17:26 INFO - ##### 21:17:26 INFO - Running post-run listener: _resource_record_post_run 21:17:26 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 8192; Write bytes: 516026368; Read time: 24; Write time: 429000 21:17:26 INFO - TinderboxPrint: CPU usage
13.1% 21:17:26 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 24 21:17:26 INFO - TinderboxPrint: I/O write bytes / time
516,026,368 / 429,000 21:17:26 INFO - TinderboxPrint: CPU idle
8,256.6 (86.9%) 21:17:26 INFO - TinderboxPrint: CPU user
1,204.2 (12.7%) 21:17:26 INFO - TinderboxPrint: Swap in / out
0 / 0 21:17:26 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 265039872; Read time: 0; Write time: 267112 21:17:26 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 8192; Write bytes: 250986496; Read time: 24; Write time: 161888 21:17:27 INFO - Running post-run listener: _upload_blobber_files 21:17:27 INFO - Blob upload gear active. 21:17:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:17:27 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 21:17:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:17:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:17:27 INFO - (blobuploader) - INFO - Open directory for files ... 21:17:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:17:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:17:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:17:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:17:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:17:28 INFO - (blobuploader) - INFO - Done attempting. 21:17:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:17:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:17:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:17:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:17:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:17:29 INFO - (blobuploader) - INFO - Done attempting. 21:17:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:17:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:17:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:17:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:17:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:17:32 INFO - (blobuploader) - INFO - Done attempting. 21:17:32 INFO - (blobuploader) - INFO - Iteration through files over. 21:17:32 INFO - Return code: 0 21:17:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:17:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:17:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41ba78ba7bdf1063fdabb662cfc5f8276422d6df9a7d9ae6196bdf9d277edd6a5428182f60e9dc663e06c96dbd33b9a16b80863bd3989d086f07d02f0faba2f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd068e4c32a1b6477aa4f5b968c16080133c1ceee833232dd6468d1be907fe5c879d811162f67e94e89b54a846ec20f79cc6d106368133fc00fb5de90ed4086e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92c2dc21a59ef5ae85f3e24f4e5901a56f9174a5427f2282cfb31f267147069d7a97ba34a5e8c560d36b6202ecacd005bd952e8c9f395bfae24c6cbf0103fd8c"} 21:17:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:17:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:17:32 INFO - Contents: 21:17:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41ba78ba7bdf1063fdabb662cfc5f8276422d6df9a7d9ae6196bdf9d277edd6a5428182f60e9dc663e06c96dbd33b9a16b80863bd3989d086f07d02f0faba2f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd068e4c32a1b6477aa4f5b968c16080133c1ceee833232dd6468d1be907fe5c879d811162f67e94e89b54a846ec20f79cc6d106368133fc00fb5de90ed4086e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92c2dc21a59ef5ae85f3e24f4e5901a56f9174a5427f2282cfb31f267147069d7a97ba34a5e8c560d36b6202ecacd005bd952e8c9f395bfae24c6cbf0103fd8c"} 21:17:32 INFO - Running post-run listener: copy_logs_to_upload_dir 21:17:32 INFO - Copying logs to upload dir... 21:17:32 INFO - mkdir: /builds/slave/test/build/upload/logs 21:17:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.817840 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-07-29 21:17:32.839249) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-29 21:17:32.842743) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1469849280.534460-1284470387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41ba78ba7bdf1063fdabb662cfc5f8276422d6df9a7d9ae6196bdf9d277edd6a5428182f60e9dc663e06c96dbd33b9a16b80863bd3989d086f07d02f0faba2f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd068e4c32a1b6477aa4f5b968c16080133c1ceee833232dd6468d1be907fe5c879d811162f67e94e89b54a846ec20f79cc6d106368133fc00fb5de90ed4086e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92c2dc21a59ef5ae85f3e24f4e5901a56f9174a5427f2282cfb31f267147069d7a97ba34a5e8c560d36b6202ecacd005bd952e8c9f395bfae24c6cbf0103fd8c"} build_url:https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011326 build_url: 'https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41ba78ba7bdf1063fdabb662cfc5f8276422d6df9a7d9ae6196bdf9d277edd6a5428182f60e9dc663e06c96dbd33b9a16b80863bd3989d086f07d02f0faba2f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd068e4c32a1b6477aa4f5b968c16080133c1ceee833232dd6468d1be907fe5c879d811162f67e94e89b54a846ec20f79cc6d106368133fc00fb5de90ed4086e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92c2dc21a59ef5ae85f3e24f4e5901a56f9174a5427f2282cfb31f267147069d7a97ba34a5e8c560d36b6202ecacd005bd952e8c9f395bfae24c6cbf0103fd8c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-29 21:17:32.868052) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 21:17:32.868419) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1469849280.534460-1284470387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004484 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 21:17:32.953331) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-29 21:17:32.953657) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-29 21:17:32.953951) ========= ========= Total master_lag: 0.17 =========