builder: graphics_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-036 starttime: 1483126016.29 results: success (0) buildid: 20161230093919 builduid: faa3eef4d0af4db7b1b2bc72612d210f revision: a00632dc133aa17fbb42197911b8ce7833bf9386 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 11:26:56.293627) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 11:26:56.293990) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 11:26:56.294255) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 11:26:56.314435) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 11:26:56.314713) ========= 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=14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-30 11:26:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 102M=0s 2016-12-30 11:26:56 (102 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128185 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 11:26:56.455910) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 11:26:56.456186) ========= 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=14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014326 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 11:26:56.508895) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 11:26:56.509238) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev a00632dc133aa17fbb42197911b8ce7833bf9386 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev a00632dc133aa17fbb42197911b8ce7833bf9386 --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=14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-30 11:26:56,656 truncating revision to first 12 chars 2016-12-30 11:26:56,656 Setting DEBUG logging. 2016-12-30 11:26:56,656 attempt 1/10 2016-12-30 11:26:56,656 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/a00632dc133a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-30 11:26:56,981 unpacking tar archive at: graphics-a00632dc133a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.655741 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 11:26:57.178557) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 11:26:57.178826) ========= script_repo_revision: a00632dc133aa17fbb42197911b8ce7833bf9386 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 11:26:57.179189) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 11:26:57.179458) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 11:26:57.188766) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-30 11:26:57.189037) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:26:57 INFO - MultiFileLogger online at 20161230 11:26:57 in /builds/slave/test 11:26:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 11:26:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:26:57 INFO - {'append_to_log': False, 11:26:57 INFO - 'base_work_dir': '/builds/slave/test', 11:26:57 INFO - 'blob_upload_branch': 'Graphics', 11:26:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:26:57 INFO - 'branch': 'Graphics', 11:26:57 INFO - 'buildbot_json_path': 'buildprops.json', 11:26:57 INFO - 'config_files': ('talos/linux_config.py',), 11:26:57 INFO - 'default_actions': ('clobber', 11:26:57 INFO - 'read-buildbot-config', 11:26:57 INFO - 'download-and-extract', 11:26:57 INFO - 'populate-webroot', 11:26:57 INFO - 'create-virtualenv', 11:26:57 INFO - 'install', 11:26:57 INFO - 'run-tests'), 11:26:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:26:57 INFO - 'download_minidump_stackwalk': True, 11:26:57 INFO - 'download_symbols': 'ondemand', 11:26:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:26:57 INFO - 'tooltool.py': '/tools/tooltool.py', 11:26:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:26:57 INFO - '/tools/misc-python/virtualenv.py')}, 11:26:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:26:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:26:57 INFO - 'installer_path': 'installer.exe', 11:26:57 INFO - 'log_level': 'info', 11:26:57 INFO - 'log_name': 'talos', 11:26:57 INFO - 'log_to_console': True, 11:26:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:26:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:26:57 INFO - 'opt_config_files': (), 11:26:57 INFO - 'pip_index': False, 11:26:57 INFO - 'sps_profile': False, 11:26:57 INFO - 'sps_profile_interval': 0, 11:26:57 INFO - 'suite': 'dromaeojs-e10s', 11:26:57 INFO - 'system_bits': '32', 11:26:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:26:57 INFO - 'title': 'talos-linux64-ix-036', 11:26:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:26:57 INFO - 'use_talos_json': True, 11:26:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:26:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:26:57 INFO - 'work_dir': 'build'} 11:26:57 INFO - [mozharness: 2016-12-30 19:26:57.436838Z] Running clobber step. 11:26:57 INFO - Running pre-action listener: _resource_record_pre_action 11:26:57 INFO - Running main action method: clobber 11:26:57 INFO - rmtree: /builds/slave/test/build 11:26:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:26:57 INFO - Running post-action listener: _resource_record_post_action 11:26:57 INFO - [mozharness: 2016-12-30 19:26:57.894642Z] Finished clobber step (success) 11:26:57 INFO - [mozharness: 2016-12-30 19:26:57.894742Z] Running read-buildbot-config step. 11:26:57 INFO - Running pre-action listener: _resource_record_pre_action 11:26:57 INFO - Running main action method: read_buildbot_config 11:26:57 INFO - Using buildbot properties: 11:26:57 INFO - { 11:26:57 INFO - "project": "", 11:26:57 INFO - "product": "firefox", 11:26:57 INFO - "script_repo_revision": "default", 11:26:57 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs-e10s", 11:26:57 INFO - "repository": "", 11:26:57 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs-e10s", 11:26:57 INFO - "buildid": "20161230093919", 11:26:57 INFO - "pgo_build": "False", 11:26:57 INFO - "basedir": "/builds/slave/test", 11:26:57 INFO - "buildnumber": 44, 11:26:57 INFO - "slavename": "talos-linux64-ix-036", 11:26:57 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:26:57 INFO - "platform": "ubuntu64_hw", 11:26:57 INFO - "scheduler": "tests-graphics-linux64-talos-8-3600", 11:26:57 INFO - "branch": "graphics", 11:26:57 INFO - "repo_path": "projects/graphics", 11:26:57 INFO - "revision": "a00632dc133aa17fbb42197911b8ce7833bf9386", 11:26:57 INFO - "stage_platform": "linux64", 11:26:57 INFO - "builduid": "faa3eef4d0af4db7b1b2bc72612d210f", 11:26:57 INFO - "slavebuilddir": "test" 11:26:57 INFO - } 11:26:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 11:26:57 INFO - Running post-action listener: _resource_record_post_action 11:26:57 INFO - [mozharness: 2016-12-30 19:26:57.897163Z] Finished read-buildbot-config step (success) 11:26:57 INFO - [mozharness: 2016-12-30 19:26:57.897262Z] Running download-and-extract step. 11:26:57 INFO - Running pre-action listener: _resource_record_pre_action 11:26:57 INFO - Running main action method: download_and_extract 11:26:57 INFO - mkdir: /builds/slave/test/build/tests 11:26:57 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:57 INFO - trying https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 11:26:57 INFO - Downloading https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 11:26:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 11:26:58 INFO - Downloaded 1361 bytes. 11:26:58 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 11:26:58 INFO - Using the following test package requirements: 11:26:58 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 11:26:58 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:58 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:26:58 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:58 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:26:58 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:58 INFO - u'jsshell-linux-x86_64.zip'], 11:26:58 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:58 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:26:58 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 11:26:58 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:58 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:26:58 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:58 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:26:58 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:58 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:26:58 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:26:58 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:26:58 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:26:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 11:26:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:26:58 INFO - Fetch https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 11:26:59 INFO - Expected file size: 20246119 11:26:59 INFO - Obtained file size: 20246119 11:27:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:27:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 11:27:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:27:03 INFO - Fetch https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 11:27:04 INFO - Expected file size: 20246119 11:27:04 INFO - Obtained file size: 20246119 11:27:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 11:27:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:27:08 INFO - Fetch https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 11:27:09 INFO - Expected file size: 14409776 11:27:09 INFO - Obtained file size: 14409776 11:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:09 INFO - trying https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 11:27:09 INFO - Downloading https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 11:27:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 11:27:10 INFO - Downloaded 55929340 bytes. 11:27:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 11:27:10 INFO - mkdir: /builds/slave/test/properties 11:27:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:27:10 INFO - Writing to file /builds/slave/test/properties/build_url 11:27:10 INFO - Contents: 11:27:10 INFO - build_url:https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 11:27:11 INFO - Running post-action listener: _resource_record_post_action 11:27:11 INFO - Running post-action listener: set_extra_try_arguments 11:27:11 INFO - [mozharness: 2016-12-30 19:27:11.294188Z] Finished download-and-extract step (success) 11:27:11 INFO - [mozharness: 2016-12-30 19:27:11.294413Z] Running populate-webroot step. 11:27:11 INFO - Running pre-action listener: _resource_record_pre_action 11:27:11 INFO - Running main action method: populate_webroot 11:27:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:27:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:27:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:27:11 INFO - '--tppagecycles', 11:27:11 INFO - '7'], 11:27:11 INFO - 'tests': ['tsvgm']}}, 11:27:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:27:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:27:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:27:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:27:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:27:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:27:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:27:11 INFO - 'talos_options': ['--disable-e10s'], 11:27:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:27:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:27:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:27:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:27:11 INFO - 'talos_options': ['--disable-e10s'], 11:27:11 INFO - 'tests': ['damp', 'tps']}, 11:27:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:27:11 INFO - 'tests': ['damp', 'tps']}, 11:27:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:27:11 INFO - 'tests': ['dromaeo_dom']}, 11:27:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:27:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:27:11 INFO - 'tests': ['basic_compositor_video']}, 11:27:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:27:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:27:11 INFO - 'tests': ['a11yr', 11:27:11 INFO - 'ts_paint', 11:27:11 INFO - 'tpaint', 11:27:11 INFO - 'sessionrestore', 11:27:11 INFO - 'sessionrestore_no_auto_restore', 11:27:11 INFO - 'tabpaint']}, 11:27:11 INFO - 'other-e10s': {'tests': ['a11yr', 11:27:11 INFO - 'ts_paint', 11:27:11 INFO - 'tpaint', 11:27:11 INFO - 'sessionrestore', 11:27:11 INFO - 'sessionrestore_no_auto_restore', 11:27:11 INFO - 'tabpaint']}, 11:27:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:27:11 INFO - 'ts_paint', 11:27:11 INFO - 'tpaint', 11:27:11 INFO - 'sessionrestore', 11:27:11 INFO - 'sessionrestore_no_auto_restore', 11:27:11 INFO - 'tabpaint']}, 11:27:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:27:11 INFO - 'ts_paint', 11:27:11 INFO - 'tpaint', 11:27:11 INFO - 'sessionrestore', 11:27:11 INFO - 'sessionrestore_no_auto_restore', 11:27:11 INFO - 'tabpaint']}, 11:27:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:27:11 INFO - 'tests': ['a11yr', 11:27:11 INFO - 'ts_paint', 11:27:11 INFO - 'tpaint', 11:27:11 INFO - 'sessionrestore', 11:27:11 INFO - 'sessionrestore_no_auto_restore', 11:27:11 INFO - 'tabpaint']}, 11:27:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:27:11 INFO - 'tests': ['a11yr', 11:27:11 INFO - 'ts_paint', 11:27:11 INFO - 'tpaint', 11:27:11 INFO - 'sessionrestore', 11:27:11 INFO - 'sessionrestore_no_auto_restore', 11:27:11 INFO - 'tabpaint']}, 11:27:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:27:11 INFO - 'tests': ['tsvgx', 11:27:11 INFO - 'tsvgr_opacity', 11:27:11 INFO - 'tart', 11:27:11 INFO - 'tscrollx', 11:27:11 INFO - 'cart', 11:27:11 INFO - 'tsvg_static']}, 11:27:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:27:11 INFO - 'tsvgr_opacity', 11:27:11 INFO - 'tart', 11:27:11 INFO - 'tscrollx', 11:27:11 INFO - 'cart', 11:27:11 INFO - 'tsvg_static']}, 11:27:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:27:11 INFO - 'talos_options': ['--disable-e10s'], 11:27:11 INFO - 'tests': ['tp5o']}, 11:27:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:27:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:27:11 INFO - 'talos_options': ['--disable-e10s', 11:27:11 INFO - '--xperf_path', 11:27:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:27:11 INFO - 'tests': ['tp5n']}, 11:27:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:27:11 INFO - 'talos_options': ['--xperf_path', 11:27:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:27:11 INFO - 'tests': ['tp5n']}}, 11:27:11 INFO - 'talos.zip': {'path': '', 11:27:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:27:11 INFO - Running post-action listener: _resource_record_post_action 11:27:11 INFO - [mozharness: 2016-12-30 19:27:11.309271Z] Finished populate-webroot step (success) 11:27:11 INFO - [mozharness: 2016-12-30 19:27:11.309360Z] Running create-virtualenv step. 11:27:11 INFO - Running pre-action listener: _resource_record_pre_action 11:27:11 INFO - Running main action method: create_virtualenv 11:27:11 INFO - Creating virtualenv /builds/slave/test/build/venv 11:27:11 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 11:27:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:27:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:27:11 INFO - Using real prefix '/usr' 11:27:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:27:11 INFO - Installing distribute.............................................................................................................................................................................................done. 11:27:13 INFO - Installing pip.................done. 11:27:13 INFO - Return code: 0 11:27:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:27:14 INFO - Reading from file tmpfile_stdout 11:27:14 INFO - Output received: 11:27:14 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 11:27:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:27:14 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:14 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:14 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 0x176f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x193ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade3f0>, '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 0x1adecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adf1a0>, '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': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', '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 11:27:14 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 11:27:14 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 11:27:14 INFO - Using env: {'DISPLAY': ':0', 11:27:14 INFO - 'HOME': '/home/cltbld', 11:27:14 INFO - 'LANG': 'en_US.UTF-8', 11:27:14 INFO - 'LANGUAGE': 'en_US:en', 11:27:14 INFO - 'LOGNAME': 'cltbld', 11:27:14 INFO - 'MAIL': '/var/mail/cltbld', 11:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:14 INFO - 'MOZ_NO_REMOTE': '1', 11:27:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:14 INFO - 'NO_EM_RESTART': '1', 11:27:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:14 INFO - 'PWD': '/builds/slave/test', 11:27:14 INFO - 'SHELL': '/bin/bash', 11:27:14 INFO - 'SHLVL': '1', 11:27:14 INFO - 'TERM': 'linux', 11:27:14 INFO - 'TMOUT': '86400', 11:27:14 INFO - 'USER': 'cltbld', 11:27:14 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', 11:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:14 INFO - '_': '/tools/buildbot/bin/python'} 11:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:14 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 11:27:14 INFO - Cleaning up... 11:27:14 INFO - Return code: 0 11:27:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:27:14 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:14 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:14 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 0x176f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x193ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade3f0>, '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 0x1adecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adf1a0>, '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': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', '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 11:27:14 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 11:27:14 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 11:27:14 INFO - Using env: {'DISPLAY': ':0', 11:27:14 INFO - 'HOME': '/home/cltbld', 11:27:14 INFO - 'LANG': 'en_US.UTF-8', 11:27:14 INFO - 'LANGUAGE': 'en_US:en', 11:27:14 INFO - 'LOGNAME': 'cltbld', 11:27:14 INFO - 'MAIL': '/var/mail/cltbld', 11:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:14 INFO - 'MOZ_NO_REMOTE': '1', 11:27:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:14 INFO - 'NO_EM_RESTART': '1', 11:27:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:14 INFO - 'PWD': '/builds/slave/test', 11:27:14 INFO - 'SHELL': '/bin/bash', 11:27:14 INFO - 'SHLVL': '1', 11:27:14 INFO - 'TERM': 'linux', 11:27:14 INFO - 'TMOUT': '86400', 11:27:14 INFO - 'USER': 'cltbld', 11:27:14 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', 11:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:14 INFO - '_': '/tools/buildbot/bin/python'} 11:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:14 INFO - Downloading/unpacking psutil>=3.1.1 11:27:14 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 11:27:14 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 11:27:14 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 11:27:14 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 11:27:14 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 11:27:14 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 11:27:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:27:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:27:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:27:16 INFO - Installing collected packages: psutil 11:27:16 INFO - Running setup.py install for psutil 11:27:16 INFO - building 'psutil._psutil_linux' extension 11:27:16 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 11:27: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 11:27:16 INFO - building 'psutil._psutil_posix' extension 11:27: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 11:27:17 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 11:27:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:27:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:27:17 INFO - Successfully installed psutil 11:27:17 INFO - Cleaning up... 11:27:17 INFO - Return code: 0 11:27:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:17 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 0x176f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x193ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade3f0>, '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 0x1adecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adf1a0>, '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': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', '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 11:27:17 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 11:27:17 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 11:27:17 INFO - Using env: {'DISPLAY': ':0', 11:27:17 INFO - 'HOME': '/home/cltbld', 11:27:17 INFO - 'LANG': 'en_US.UTF-8', 11:27:17 INFO - 'LANGUAGE': 'en_US:en', 11:27:17 INFO - 'LOGNAME': 'cltbld', 11:27:17 INFO - 'MAIL': '/var/mail/cltbld', 11:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:17 INFO - 'MOZ_NO_REMOTE': '1', 11:27:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:17 INFO - 'NO_EM_RESTART': '1', 11:27:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:17 INFO - 'PWD': '/builds/slave/test', 11:27:17 INFO - 'SHELL': '/bin/bash', 11:27:17 INFO - 'SHLVL': '1', 11:27:17 INFO - 'TERM': 'linux', 11:27:17 INFO - 'TMOUT': '86400', 11:27:17 INFO - 'USER': 'cltbld', 11:27:17 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', 11:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:17 INFO - '_': '/tools/buildbot/bin/python'} 11:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:27:17 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 11:27:17 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 11:27:17 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 11:27:17 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 11:27:17 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 11:27:17 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 11:27:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:27:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:27:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:27:19 INFO - Installing collected packages: mozsystemmonitor 11:27:19 INFO - Running setup.py install for mozsystemmonitor 11:27:19 INFO - Successfully installed mozsystemmonitor 11:27:19 INFO - Cleaning up... 11:27:19 INFO - Return code: 0 11:27:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:27:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:19 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 0x176f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x193ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade3f0>, '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 0x1adecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adf1a0>, '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': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', '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 11:27:19 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 11:27:19 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 11:27:19 INFO - Using env: {'DISPLAY': ':0', 11:27:19 INFO - 'HOME': '/home/cltbld', 11:27:19 INFO - 'LANG': 'en_US.UTF-8', 11:27:19 INFO - 'LANGUAGE': 'en_US:en', 11:27:19 INFO - 'LOGNAME': 'cltbld', 11:27:19 INFO - 'MAIL': '/var/mail/cltbld', 11:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:19 INFO - 'MOZ_NO_REMOTE': '1', 11:27:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:19 INFO - 'NO_EM_RESTART': '1', 11:27:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:19 INFO - 'PWD': '/builds/slave/test', 11:27:19 INFO - 'SHELL': '/bin/bash', 11:27:19 INFO - 'SHLVL': '1', 11:27:19 INFO - 'TERM': 'linux', 11:27:19 INFO - 'TMOUT': '86400', 11:27:19 INFO - 'USER': 'cltbld', 11:27:19 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', 11:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:19 INFO - '_': '/tools/buildbot/bin/python'} 11:27:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:19 INFO - Downloading/unpacking jsonschema==2.5.1 11:27:19 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 11:27:19 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 11:27:19 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 11:27:19 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 11:27:19 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 11:27:19 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 11:27:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:27:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:27: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 11:27: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 11:27: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 11:27: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 11:27: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 11:27: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 11:27:22 INFO - Downloading functools32-3.2.3-2.tar.gz 11:27:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:27:22 INFO - warning: no files found matching '*.txt' 11:27:22 INFO - no previously-included directories found matching 'build' 11:27:22 INFO - no previously-included directories found matching 'dist' 11:27:22 INFO - no previously-included directories found matching '.git*' 11:27:22 INFO - Installing collected packages: jsonschema, functools32 11:27:22 INFO - Running setup.py install for functools32 11:27:22 INFO - warning: no files found matching '*.txt' 11:27:22 INFO - no previously-included directories found matching 'build' 11:27:22 INFO - no previously-included directories found matching 'dist' 11:27:22 INFO - no previously-included directories found matching '.git*' 11:27:22 INFO - Successfully installed jsonschema functools32 11:27:22 INFO - Cleaning up... 11:27:22 INFO - Return code: 0 11:27:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:27:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x176f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x193ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade3f0>, '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 0x1adecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adf1a0>, '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': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', '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 11:27:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:27:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:27:22 INFO - Using env: {'DISPLAY': ':0', 11:27:22 INFO - 'HOME': '/home/cltbld', 11:27:22 INFO - 'LANG': 'en_US.UTF-8', 11:27:22 INFO - 'LANGUAGE': 'en_US:en', 11:27:22 INFO - 'LOGNAME': 'cltbld', 11:27:22 INFO - 'MAIL': '/var/mail/cltbld', 11:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:22 INFO - 'MOZ_NO_REMOTE': '1', 11:27:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:22 INFO - 'NO_EM_RESTART': '1', 11:27:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:22 INFO - 'PWD': '/builds/slave/test', 11:27:22 INFO - 'SHELL': '/bin/bash', 11:27:22 INFO - 'SHLVL': '1', 11:27:22 INFO - 'TERM': 'linux', 11:27:22 INFO - 'TMOUT': '86400', 11:27:22 INFO - 'USER': 'cltbld', 11:27:22 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', 11:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:22 INFO - '_': '/tools/buildbot/bin/python'} 11:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:27:22 INFO - Cleaning up... 11:27:22 INFO - Return code: 0 11:27:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:27:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:22 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 0x176f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x193ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade3f0>, '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 0x1adecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adf1a0>, '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': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', '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 11:27:22 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 11:27:22 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 11:27:22 INFO - Using env: {'DISPLAY': ':0', 11:27:22 INFO - 'HOME': '/home/cltbld', 11:27:22 INFO - 'LANG': 'en_US.UTF-8', 11:27:22 INFO - 'LANGUAGE': 'en_US:en', 11:27:22 INFO - 'LOGNAME': 'cltbld', 11:27:22 INFO - 'MAIL': '/var/mail/cltbld', 11:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:22 INFO - 'MOZ_NO_REMOTE': '1', 11:27:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:22 INFO - 'NO_EM_RESTART': '1', 11:27:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:22 INFO - 'PWD': '/builds/slave/test', 11:27:22 INFO - 'SHELL': '/bin/bash', 11:27:22 INFO - 'SHLVL': '1', 11:27:22 INFO - 'TERM': 'linux', 11:27:22 INFO - 'TMOUT': '86400', 11:27:22 INFO - 'USER': 'cltbld', 11:27:22 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', 11:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:22 INFO - '_': '/tools/buildbot/bin/python'} 11:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:22 INFO - Downloading/unpacking blobuploader==1.2.4 11:27:22 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 11:27:22 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 11:27:22 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 11:27:22 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 11:27:22 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 11:27:22 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 11:27:24 INFO - Downloading blobuploader-1.2.4.tar.gz 11:27:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:27:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:27:24 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 11:27:24 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 11:27:24 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 11:27:24 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 11:27:24 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 11:27:24 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 11:27:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:27:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:27:25 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 11:27:25 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 11:27:25 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 11:27:25 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 11:27:25 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 11:27:25 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 11:27:25 INFO - Downloading docopt-0.6.1.tar.gz 11:27:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:27:25 INFO - Installing collected packages: blobuploader, requests, docopt 11:27:25 INFO - Running setup.py install for blobuploader 11:27:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:27:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:27:25 INFO - Running setup.py install for requests 11:27:26 INFO - Running setup.py install for docopt 11:27:26 INFO - Successfully installed blobuploader requests docopt 11:27:26 INFO - Cleaning up... 11:27:26 INFO - Return code: 0 11:27:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:27:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:26 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 0x176f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x193ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade3f0>, '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 0x1adecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adf1a0>, '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': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', '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 11:27:26 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 11:27:26 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 11:27:26 INFO - Using env: {'DISPLAY': ':0', 11:27:26 INFO - 'HOME': '/home/cltbld', 11:27:26 INFO - 'LANG': 'en_US.UTF-8', 11:27:26 INFO - 'LANGUAGE': 'en_US:en', 11:27:26 INFO - 'LOGNAME': 'cltbld', 11:27:26 INFO - 'MAIL': '/var/mail/cltbld', 11:27:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:26 INFO - 'MOZ_NO_REMOTE': '1', 11:27:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:26 INFO - 'NO_EM_RESTART': '1', 11:27:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:26 INFO - 'PWD': '/builds/slave/test', 11:27:26 INFO - 'SHELL': '/bin/bash', 11:27:26 INFO - 'SHLVL': '1', 11:27:26 INFO - 'TERM': 'linux', 11:27:26 INFO - 'TMOUT': '86400', 11:27:26 INFO - 'USER': 'cltbld', 11:27:26 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', 11:27:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:26 INFO - '_': '/tools/buildbot/bin/python'} 11:27:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:27:26 INFO - Running setup.py (path:/tmp/pip-cfbOhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:27:26 INFO - Running setup.py (path:/tmp/pip-oA4JcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:27:26 INFO - Running setup.py (path:/tmp/pip-I2kPi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:27:26 INFO - Running setup.py (path:/tmp/pip-yvOXff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:27:26 INFO - Running setup.py (path:/tmp/pip-nqIzZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:27:26 INFO - Running setup.py (path:/tmp/pip-n7TO4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:27:26 INFO - Running setup.py (path:/tmp/pip-TWaiT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:27:26 INFO - Running setup.py (path:/tmp/pip-XbtBuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:27:26 INFO - Running setup.py (path:/tmp/pip-zbYd39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:27:27 INFO - Running setup.py (path:/tmp/pip-QFKONa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:27:27 INFO - Running setup.py (path:/tmp/pip-eK6QdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:27:27 INFO - Running setup.py (path:/tmp/pip-vSVgJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:27:27 INFO - Running setup.py (path:/tmp/pip-sjnuoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:27:27 INFO - Running setup.py (path:/tmp/pip-cRdOm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:27:27 INFO - Running setup.py (path:/tmp/pip-Agvnk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:27:27 INFO - Running setup.py (path:/tmp/pip-yi3nWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:27:27 INFO - Running setup.py (path:/tmp/pip-nhm6Dy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:27:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:27:27 INFO - Running setup.py install for manifestparser 11:27:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:27:27 INFO - Running setup.py install for mozcrash 11:27:27 INFO - Running setup.py install for mozdebug 11:27:27 INFO - Running setup.py install for mozdevice 11:27:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:27:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:27:28 INFO - Running setup.py install for mozfile 11:27:28 INFO - Running setup.py install for mozhttpd 11:27:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:27:28 INFO - Running setup.py install for mozinfo 11:27:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:27:28 INFO - Running setup.py install for mozInstall 11:27:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:27:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:27:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:27:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:27:28 INFO - Running setup.py install for mozleak 11:27:28 INFO - Running setup.py install for mozlog 11:27:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:27:28 INFO - Running setup.py install for moznetwork 11:27:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:27:28 INFO - Running setup.py install for mozprocess 11:27:28 INFO - Running setup.py install for mozprofile 11:27:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:27:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:27:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:27:28 INFO - Running setup.py install for mozrunner 11:27:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:27:29 INFO - Running setup.py install for mozscreenshot 11:27:29 INFO - Running setup.py install for moztest 11:27:29 INFO - Running setup.py install for mozversion 11:27:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:27:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:27:29 INFO - Cleaning up... 11:27:29 INFO - Return code: 0 11:27:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:29 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 0x176f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x193ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade3f0>, '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 0x1adecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adf1a0>, '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': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', '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 11:27:29 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 11:27:29 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 11:27:29 INFO - Using env: {'DISPLAY': ':0', 11:27:29 INFO - 'HOME': '/home/cltbld', 11:27:29 INFO - 'LANG': 'en_US.UTF-8', 11:27:29 INFO - 'LANGUAGE': 'en_US:en', 11:27:29 INFO - 'LOGNAME': 'cltbld', 11:27:29 INFO - 'MAIL': '/var/mail/cltbld', 11:27:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:29 INFO - 'MOZ_NO_REMOTE': '1', 11:27:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:29 INFO - 'NO_EM_RESTART': '1', 11:27:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:29 INFO - 'PWD': '/builds/slave/test', 11:27:29 INFO - 'SHELL': '/bin/bash', 11:27:29 INFO - 'SHLVL': '1', 11:27:29 INFO - 'TERM': 'linux', 11:27:29 INFO - 'TMOUT': '86400', 11:27:29 INFO - 'USER': 'cltbld', 11:27:29 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', 11:27:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:29 INFO - '_': '/tools/buildbot/bin/python'} 11:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:27:29 INFO - Running setup.py (path:/tmp/pip-KwgImV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:27:29 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)) 11:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:27:29 INFO - Running setup.py (path:/tmp/pip-pUFsrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:27:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 11:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:27:29 INFO - Running setup.py (path:/tmp/pip-a8erwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:27:29 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)) 11:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:27:29 INFO - Running setup.py (path:/tmp/pip-HG4tko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:27:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 11:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:27:29 INFO - Running setup.py (path:/tmp/pip-ALl4f1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:27:30 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)) 11:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:27:30 INFO - Running setup.py (path:/tmp/pip-VzBQxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:27:30 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)) 11:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:27:30 INFO - Running setup.py (path:/tmp/pip-NxmCKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:27:30 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)) 11:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:27:30 INFO - Running setup.py (path:/tmp/pip-1PTNg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:27:30 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)) 11:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:27:30 INFO - Running setup.py (path:/tmp/pip-zcrk5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:27:30 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)) 11:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:27:30 INFO - Running setup.py (path:/tmp/pip-OZF7WJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:27:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 11:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:27:30 INFO - Running setup.py (path:/tmp/pip-nvIQyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:27:30 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)) 11:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:27:30 INFO - Running setup.py (path:/tmp/pip-nSSNwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:27:30 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)) 11:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:27:30 INFO - Running setup.py (path:/tmp/pip-y8QXeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:27:30 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)) 11:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:27:30 INFO - Running setup.py (path:/tmp/pip-PbccsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:27:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 11:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:27:30 INFO - Running setup.py (path:/tmp/pip-MvfaO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:27:30 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)) 11:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:27:30 INFO - Running setup.py (path:/tmp/pip-rIBxYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:27:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 11:27:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:27:30 INFO - Running setup.py (path:/tmp/pip-b4U9bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:27:30 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)) 11:27:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:27:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:27:30 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)) 11:27:30 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:27:30 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:27:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:27:30 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 11:27:30 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 11:27:30 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 11:27:30 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 11:27:30 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 11:27:30 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 11:27:32 INFO - Downloading blessings-1.6.tar.gz 11:27:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:27:32 INFO - Installing collected packages: blessings 11:27:32 INFO - Running setup.py install for blessings 11:27:33 INFO - Successfully installed blessings 11:27:33 INFO - Cleaning up... 11:27:33 INFO - Return code: 0 11:27:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:27:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:27:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:27:33 INFO - Reading from file tmpfile_stdout 11:27:33 INFO - Current package versions: 11:27:33 INFO - argparse == 1.2.1 11:27:33 INFO - blessings == 1.6 11:27:33 INFO - blobuploader == 1.2.4 11:27:33 INFO - docopt == 0.6.1 11:27:33 INFO - functools32 == 3.2.3-2 11:27:33 INFO - jsonschema == 2.5.1 11:27:33 INFO - manifestparser == 1.1 11:27:33 INFO - mozInstall == 1.12 11:27:33 INFO - mozcrash == 1.0 11:27:33 INFO - mozdebug == 0.1 11:27:33 INFO - mozdevice == 0.49 11:27:33 INFO - mozfile == 1.2 11:27:33 INFO - mozhttpd == 0.7 11:27:33 INFO - mozinfo == 0.9 11:27:33 INFO - mozleak == 0.1 11:27:33 INFO - mozlog == 3.4 11:27:33 INFO - moznetwork == 0.27 11:27:33 INFO - mozprocess == 0.23 11:27:33 INFO - mozprofile == 0.28 11:27:33 INFO - mozrunner == 6.13 11:27:33 INFO - mozscreenshot == 0.1 11:27:33 INFO - mozsystemmonitor == 0.3 11:27:33 INFO - moztest == 0.8 11:27:33 INFO - mozversion == 1.4 11:27:33 INFO - psutil == 3.1.1 11:27:33 INFO - requests == 1.2.3 11:27:33 INFO - wsgiref == 0.1.2 11:27:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:27:33 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:33 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:33 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 0x176f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x193ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade3f0>, '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 0x1adecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adf1a0>, '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': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', '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 11:27:33 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 11:27:33 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 11:27:33 INFO - Using env: {'DISPLAY': ':0', 11:27:33 INFO - 'HOME': '/home/cltbld', 11:27:33 INFO - 'LANG': 'en_US.UTF-8', 11:27:33 INFO - 'LANGUAGE': 'en_US:en', 11:27:33 INFO - 'LOGNAME': 'cltbld', 11:27:33 INFO - 'MAIL': '/var/mail/cltbld', 11:27:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:33 INFO - 'MOZ_NO_REMOTE': '1', 11:27:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:33 INFO - 'NO_EM_RESTART': '1', 11:27:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:33 INFO - 'PWD': '/builds/slave/test', 11:27:33 INFO - 'SHELL': '/bin/bash', 11:27:33 INFO - 'SHLVL': '1', 11:27:33 INFO - 'TERM': 'linux', 11:27:33 INFO - 'TMOUT': '86400', 11:27:33 INFO - 'USER': 'cltbld', 11:27:33 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', 11:27:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:33 INFO - '_': '/tools/buildbot/bin/python'} 11:27:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:33 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)) 11:27:33 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)) 11:27:33 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)) 11:27:33 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)) 11:27:33 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)) 11:27:33 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)) 11:27:33 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)) 11:27:33 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)) 11:27:33 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)) 11:27:33 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)) 11:27:33 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)) 11:27:33 INFO - Cleaning up... 11:27:33 INFO - Return code: 0 11:27:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:27:33 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:33 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:33 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 0x176f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x193ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade3f0>, '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 0x1adecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adf1a0>, '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': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', '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 11:27:33 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 11:27:33 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 11:27:33 INFO - Using env: {'DISPLAY': ':0', 11:27:33 INFO - 'HOME': '/home/cltbld', 11:27:33 INFO - 'LANG': 'en_US.UTF-8', 11:27:33 INFO - 'LANGUAGE': 'en_US:en', 11:27:33 INFO - 'LOGNAME': 'cltbld', 11:27:33 INFO - 'MAIL': '/var/mail/cltbld', 11:27:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:33 INFO - 'MOZ_NO_REMOTE': '1', 11:27:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:33 INFO - 'NO_EM_RESTART': '1', 11:27:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:33 INFO - 'PWD': '/builds/slave/test', 11:27:33 INFO - 'SHELL': '/bin/bash', 11:27:33 INFO - 'SHLVL': '1', 11:27:33 INFO - 'TERM': 'linux', 11:27:33 INFO - 'TMOUT': '86400', 11:27:33 INFO - 'USER': 'cltbld', 11:27:33 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', 11:27:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:33 INFO - '_': '/tools/buildbot/bin/python'} 11:27:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:27:33 INFO - Cleaning up... 11:27:33 INFO - Return code: 0 11:27:33 INFO - Running post-action listener: _resource_record_post_action 11:27:33 INFO - Running post-action listener: _start_resource_monitoring 11:27:33 INFO - Starting resource monitoring. 11:27:33 INFO - [mozharness: 2016-12-30 19:27:33.858535Z] Finished create-virtualenv step (success) 11:27:33 INFO - [mozharness: 2016-12-30 19:27:33.859003Z] Running install step. 11:27:33 INFO - Running pre-action listener: _resource_record_pre_action 11:27:33 INFO - Running main action method: install 11:27:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:27:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:27:34 INFO - Reading from file tmpfile_stdout 11:27:34 INFO - Detecting whether we're running mozinstall >=1.0... 11:27:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:27:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:27:34 INFO - Reading from file tmpfile_stdout 11:27:34 INFO - Output received: 11:27:34 INFO - Usage: mozinstall [options] installer 11:27:34 INFO - Options: 11:27:34 INFO - -h, --help show this help message and exit 11:27:34 INFO - -d DEST, --destination=DEST 11:27:34 INFO - Directory to install application into. [default: 11:27:34 INFO - "/builds/slave/test"] 11:27:34 INFO - --app=APP Application being installed. [default: firefox] 11:27:34 INFO - mkdir: /builds/slave/test/build/application 11:27:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:27:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 11:27:50 INFO - Reading from file tmpfile_stdout 11:27:50 INFO - Output received: 11:27:50 INFO - /builds/slave/test/build/application/firefox/firefox 11:27:50 INFO - Running post-action listener: _resource_record_post_action 11:27:50 INFO - [mozharness: 2016-12-30 19:27:50.268142Z] Finished install step (success) 11:27:50 INFO - [mozharness: 2016-12-30 19:27:50.268385Z] Running run-tests step. 11:27:50 INFO - Running pre-action listener: _resource_record_pre_action 11:27:50 INFO - Running main action method: run_tests 11:27:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:27:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:27:50 INFO - Python 2.7.3 11:27:50 INFO - Return code: 0 11:27:50 INFO - grabbing minidump binary from tooltool 11:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:50 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 0x1ade3f0>, '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 0x1adecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adf1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:27:50 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 11:27:50 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 11:27:50 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 11:27:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:27:50 INFO - Return code: 0 11:27:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:27:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:27:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:27:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:27:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:27:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:27:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:27:50 INFO - Using env: {'DISPLAY': ':0', 11:27:50 INFO - 'HOME': '/home/cltbld', 11:27:50 INFO - 'LANG': 'en_US.UTF-8', 11:27:50 INFO - 'LANGUAGE': 'en_US:en', 11:27:50 INFO - 'LOGNAME': 'cltbld', 11:27:50 INFO - 'MAIL': '/var/mail/cltbld', 11:27:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:27:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:50 INFO - 'MOZ_NO_REMOTE': '1', 11:27:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:27:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:50 INFO - 'NO_EM_RESTART': '1', 11:27:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:50 INFO - 'PWD': '/builds/slave/test', 11:27:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:27:50 INFO - 'SHELL': '/bin/bash', 11:27:50 INFO - 'SHLVL': '1', 11:27:50 INFO - 'TERM': 'linux', 11:27:50 INFO - 'TMOUT': '86400', 11:27:50 INFO - 'USER': 'cltbld', 11:27:50 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237', 11:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:50 INFO - '_': '/tools/buildbot/bin/python'} 11:27:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:27:50 INFO - mozversion application_buildid: 20161230093919 11:27:50 INFO - mozversion application_changeset: a00632dc133aa17fbb42197911b8ce7833bf9386 11:27:50 INFO - mozversion application_display_name: Nightly 11:27:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:27:50 INFO - mozversion application_name: Firefox 11:27:50 INFO - mozversion application_remotingname: firefox 11:27:50 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 11:27:50 INFO - mozversion application_vendor: Mozilla 11:27:50 INFO - mozversion application_version: 53.0a1 11:27:50 INFO - mozversion platform_buildid: 20161230093919 11:27:50 INFO - mozversion platform_changeset: a00632dc133aa17fbb42197911b8ce7833bf9386 11:27:50 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 11:27:50 INFO - mozversion platform_version: 53.0a1 11:27:50 INFO - using testdate: 1483126070 11:27:50 INFO - actual date: 1483126070 11:27:50 INFO - starting webserver on 'localhost:41566' 11:27:50 INFO - SUITE-START | Running 2 tests 11:27:50 INFO - TEST-START | dromaeo_css 11:27:50 INFO - operating with platform_type : linux_ 11:27:50 INFO - Initialising browser for dromaeo_css test... 11:27:50 INFO - TEST-INFO | started process 22328 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_QTGr_/profile http://localhost:41566/getInfo.html) 11:27:51 INFO - PROCESS | 22328 | 1483126071065 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 11:27:55 INFO - PROCESS | 22328 | __metrics Screen width/height:1600/1200 11:27:55 INFO - PROCESS | 22328 | colorDepth:24 11:27:55 INFO - PROCESS | 22328 | Browser inner width/height: 1024/697 11:27:55 INFO - PROCESS | 22328 | __metrics 11:27:55 ERROR - PROCESS | 22328 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 11:27:56 INFO - TEST-INFO | 22328: exit 0 11:27:56 INFO - Browser initialized. 11:27:56 INFO - Running cycle 1/1 for dromaeo_css test... 11:27:56 INFO - TEST-INFO | started process 22451 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_QTGr_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:28:08 INFO - PROCESS | 22451 | RSS: Main: 158404608 11:28:08 INFO - PROCESS | 22451 | 11:31:12 INFO - PROCESS | 22451 | Cycle 1(1): loaded http://localhost:41566/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41566/tests/dromaeo/cssquery-ext.html) 11:31:13 INFO - PROCESS | 22451 | RSS: Main: 166162432 11:31:13 INFO - PROCESS | 22451 | 11:32:57 INFO - PROCESS | 22451 | ************************* 11:32:57 INFO - PROCESS | 22451 | A coding exception was thrown and uncaught in a Task. 11:32:57 INFO - PROCESS | 22451 | 11:32:57 INFO - PROCESS | 22451 | Full message: ReferenceError: fetch is not defined 11:32:57 INFO - PROCESS | 22451 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 11:32:57 INFO - PROCESS | 22451 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 11:32:57 INFO - PROCESS | 22451 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 11:32:57 INFO - PROCESS | 22451 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 11:32:57 INFO - PROCESS | 22451 | TaskImpl@resource://gre/modules/Task.jsm:277:3 11:32:57 INFO - PROCESS | 22451 | asyncFunction@resource://gre/modules/Task.jsm:252:14 11:32:57 INFO - PROCESS | 22451 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 11:32:57 INFO - PROCESS | 22451 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 11:32:57 INFO - PROCESS | 22451 | TaskImpl@resource://gre/modules/Task.jsm:277:3 11:32:57 INFO - PROCESS | 22451 | asyncFunction@resource://gre/modules/Task.jsm:252:14 11:32:57 INFO - PROCESS | 22451 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 11:32:57 INFO - PROCESS | 22451 | 11:32:57 INFO - PROCESS | 22451 | ************************* 11:34:16 INFO - PROCESS | 22451 | Cycle 1(1): loaded http://localhost:41566/tests/dromaeo/cssquery-ext.html (next: http://localhost:41566/tests/dromaeo/cssquery-jquery.html) 11:34:17 INFO - PROCESS | 22451 | RSS: Main: 163164160 11:34:17 INFO - PROCESS | 22451 | 11:37:19 INFO - PROCESS | 22451 | Cycle 1(1): loaded http://localhost:41566/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41566/tests/dromaeo/cssquery-mootools.html) 11:37:19 INFO - PROCESS | 22451 | RSS: Main: 169684992 11:37:19 INFO - PROCESS | 22451 | 11:40:21 INFO - PROCESS | 22451 | Cycle 1(1): loaded http://localhost:41566/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41566/tests/dromaeo/cssquery-prototype.html) 11:40:22 INFO - PROCESS | 22451 | RSS: Main: 164253696 11:40:22 INFO - PROCESS | 22451 | 11:43:25 INFO - PROCESS | 22451 | Cycle 1(1): loaded http://localhost:41566/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41566/tests/dromaeo/cssquery-yui.html) 11:43:26 INFO - PROCESS | 22451 | RSS: Main: 166510592 11:43:26 INFO - PROCESS | 22451 | 11:46:28 INFO - PROCESS | 22451 | Cycle 1(1): loaded http://localhost:41566/tests/dromaeo/cssquery-yui.html (next: http://localhost:41566/tests/dromaeo/cssquery-dojo.html) 11:46:29 INFO - PROCESS | 22451 | RSS: Main: 166752256 11:46:29 INFO - PROCESS | 22451 | 11:46:29 INFO - PROCESS | 22451 | __start_tp_report 11:46:29 INFO - PROCESS | 22451 | _x_x_mozilla_page_load 11:46:29 INFO - PROCESS | 22451 | _x_x_mozilla_page_load_details 11:46:29 INFO - PROCESS | 22451 | |i|pagename|runs| 11:46:29 INFO - PROCESS | 22451 | |0;dojo.html;2620.423506828497;2621.5674413721736;2622.383739821142;2655.867206639668;2661.201639508147;4841.8063277468855;4889.682170658904;4911.705297682137;4920.778564964577;4928.753562321887;4856.465788763233;4867.610591152708;4893.4861839506875;4897.975510122454;4960.578350840183;4471.999999999984;4474.239379305526;4481.439820022497;4504.166729155117;4562.726236425809;4448.33275008748;4554.84058057968;4588.082383523292;4609.055143695551;4618.884527886813;4405.383246345515;4509.751963641998;4564.292534657134;4590.049924244299;4660.557247061541;4423.889402764925;4427.933580996288;4461.977690111528;4468.061707041526;4530.569595888385;4433.933490997638;4437.667174961891;4445.177642136216;4454.0646464242545;4492.124035813018;4821.276808478721;4833.2266837306215;4856.2958371036;4866.975665121687;4874.049560335736;5083.415407228142;5130.358705161855;5211.557017653504;5218.321618189649;5269.762860671279;5303.575713942875;5311.1767676009895;5316.867078323035;5318.654287471302;5361.865953351197;4899.167141585939;4940.777665005047;4965;4971.975140124277;5011.824586139504;5233.633645644844;5309.442508536548;5329.520343169133;5352.91970620441;5379.085555455582;5171.663841850268;5173.922391164136;5178.792848286026;5185.196294574347;5223.4776522347465;5070.543651071347;5104.132297509433;5242.7378631068295;5248.029114613809;5269.578433725293;5107.208382700687;5144.151215049475;5169.405518365361;5174.327337446108;5229.006488767122;5160.948390516121;5165.767540460688;5168.81909133186;5170.1469257572835;5220.895582088413;4326.113146804897;4416.33754936762;4418.469783625986;4430.158269928767;4464.888377790581;3780.281746468162;3796.905077373088;3834.386498160281;3855.7108216883844;3911.2959667259597;4509.098180363941;4510.120526497301;4514.300283456004;4515.864524064284;4553.157665831832;5202.401723801811;5246.76389562463;5250.448702886142;5260.079486089919;5265.921011184835;4341.717788343748;4379.233634114017;4394.494633117169;4427.247367947392;4443.422355093817;4579.450465944108;4625;4629.606483448877;4647.256438969748;4649;4719.126961512063;4727.503612120747;4742.644301677319;4747.596454301423;4805.7597120143155;5229.843104706865;5267.525922666979;5272.578193744568;5316.707581082923;5331.09371406854;4838.879028024327;4839.782209800497;4846.660733748604;4849.102915960559;4889.193283108248;4983.551480366785;5046.066477701637;5120.769565369493;5137.460566640599;5166.922496162636;5196.844094677166;5221.712805795718;5234.607404444606;5265.420803711665;5300.6024548158275;5113.92329115071;5116.488351164854;5117.820876269313;5121.590272778094;5123.948760512347;4986.80052797884;5030.2957585937265;5057.988402319477;5059.241113832954;5059.949400506095;4934.654574179773;4987.177115775857;4999.10016197088;5030.044291584587;5055.014272216882;5168.612354073384;5175.767090480862;5175.948240517697;5179.611529135405;5224.242484839753;5031.471695471922;5033.370828646419;5049.2426136079885;5056.721880296619;5096.0827051131155 11:46:29 INFO - PROCESS | 22451 | |1;ext.html;496.08224784149314;504.70979186967503;507.6015327967546;514.8661348049507;520.1132069820956;35569.46645800312;37733.99999999996;37866.24267514648;37928.67249646263;38022.8098859506;38687.41968870469;38937;39018.21963560727;39026.4146037809;39129.80435097827;7450.515716478411;7579.545227286353;7726.763717805153;7727.188645192258;7729.111152217511;7416.517926334798;9924.007599240054;10106.747331316703;10141.13800326973;10352.792944141112;9980.451075190862;10037.949810250939;10040.849387259197;10047.64833230837;10069.748256293615;9911.553980070878;9957;9975.700728978143;9984.600615975389;9985.450800205987;9785.706428807147;9865.654702085392;9870.161036311922;9879.407235565817;9881.407115573089;35336.64663353359;35731.28537429263;36049.639503605154;36155.63844361549;36184.819075904714;3671.706263498914;3721.5720192177973;3721.5906250312446;3759.755615884959;3792.2225943681756;26735.197944061514;27452.862735686394;27486;27499;27608.723912761016;3352.2960178362327;3404.1829960809227;3610.3681855675413;3614.6385361463645;3617.2042150726797;221777;222211;223331.88334058388;223339;223514;48662;48939;49211.50788492141;49260;49498.50501494939;52190.21714674283;52252;52252.73873630608;52257.47742522526;52260.47739522632;13887.583372498639;14056.43774249039;14078.366473508717;14208.715825683636;14212.573622791333;8703.347248956354;8932.955335223412;10615.787684246272;10756.408397538211;10781.460926953621;17649.470515884543;17761.644767104586;17844;18263.817361826477;18312.90843545774;7091.574505529685;7215.314545118205;7228.674709638079;7442.367398771057;7476;784.1061190243128;803.220875750521;804.6660635836063;808.244291587356;828.9502629842108;26430.86784566065;27232.910683572434;27419.31451713723;27533.586996195074;27652.8199257088;943.3679434778578;948.7201275623707;951.6241084771476;956.9760755981156;964.4406244378243;13442.596722098353;13591.252481113634;13793.793093103612;14053.227072510905;14107.85892141086;281359;365537;365666;366105;366831;42766.358504622454;43016.56983430188;43054.784726076796;43155;43232.56767432348;1209.12338554549;1212.7089498520468;1218.622227109599;1232.7041510037527;1240.5347994502063;24138.310318278254;24193.39516512101;25266.621000685373;26716.7328326722;28600;41129.177416450904;41848.37227441652;41886;41937.37093943532;41979.37030944477;339429;344526;346177;346757;347723;29000.854995724887;30463;31099.37801243917;34055.318893621494;34172;30209.69790302069;41364.586354136074;43338;43973.56026439695;49139.26291105708;33747.81882634096;33923.15192120217;34065.82967085149;36347.63652363443;36900;36619;36720.63279367172;37019.44470832994;37139;37717.558276536336;35977.1005724859;36053.45919811152;37143.6285637151;37187.25625487529;37200.25599488049;30480.390392192476;32598.674013259562;34861.26720111906;35026.299474009866;35213;25708.742912570633;25960.22119336423;26212.475750485264;26276;26410.471790564465 11:46:29 INFO - PROCESS | 22451 | |2;jquery.html;356.607731495355;364.6699736738252;371.67292782351524;377.3283555271614;383.55890725665483;15444.61388465289;16120.677586448264;16536.173191340444;16585.83414165861;16632.25154868031;15556.222188890539;15726.528204153865;16034.278457469441;16073.83926160738;16176.919115404437;4975.751212439382;5045.545900868921;5054.1913293872985;5073;5073.644844860862;5019.29729837823;5068.923966140493;5349.625526213166;5376.1398176291805;5415.079436495786;5378.273933019032;5380.461953804608;5391.379991300992;5391.541718953894;5393.2719082923695;5307.248607959384;5899.292084949812;6003.0695242237525;6035.094735789623;6053.757849686007;5194.870128246786;5382.165764306539;5386.138217885159;5387.164989426645;5388.110961691316;12191.817122743167;12532.561360352343;12536.874631253753;12537.43581538833;12598.37008149598;2123.2250228666426;2194.5391467791783;2195.879226642534;2196.3959911024435;2238.552289542098;8461.196186362286;8850.291976641918;8885.466871987637;9217.907820921839;9296.8605470918;1616.8706503479812;1656.7349224124082;1677.3123019562042;1687.4094067076426;1708.1288542843115;52119.47880521184;62916.685416573084;66084.40029396633;66911;68174.65912670405;9798.510074496247;9940.80118397628;9998.200143988464;10052.145567626834;10298.845517317246;13924.721505569833;13942.442302307794;13959.720805583831;13966.930165349107;13996.30018499071;7072.8939065914055;7294.598797066106;7327.780166595011;7444.739434119888;7450.329470347587;6871.415929646036;6910.861782764317;7072.8939065914055;7130.5365151265005;7136.892946605805;4167.437395951569;4242.6817988650355;4283.32950149442;4401.911961760747;4443.733375997451;1519.9392024318904;1573.5751347136209;1759.8416142547233;1839.9080045997646;1893.9242430302634;788.9289963903276;819.9344052475907;822.6709316273539;823.5017814222429;829.0797215091244;16057.116858572657;16106.677866442606;16406.09766462832;16543.503694888932;16612.750808737877;810.2464707821664;817.207308910356;817.4604760857806;821.9013718353716;840.4915026408935;9026.368154229272;9314.627414903329;9389.154976052188;9444.763880902896;9576.712698619052;318937;324047;326874.73125268443;326925;329066;70376;70421.2957870425;70603.29396705967;71870.28129718741;72626.273737263;897.9416337937882;908.6138391183721;936.4615346175976;937.1471960515897;944.1313991128178;14234.857651423767;14251.429942802586;14282.143071415749;14353.138811671333;14936.17851018161;29499.262518437212;32471.512927306583;32628.83685581557;34025.65974340225;34971;43097.78451107725;43118.35322470228;43416.13167736564;45716.77141614271;46122.76938615285;6937.236903940461;7007.089078419773;7081.610511421921;7287.890681639886;7365.189829118685;9890;10937.781244375226;11200.719982000515;11483.712907177387;12381.442835072263;9336.533173341442;9772.657956971472;9846.212303015885;10076.848847267443;10371.807242166946;9726.270529710442;10211.7447063824;10258.487075646337;10349.689509314732;10442.947785261025;10253.33353332031;10396.337970641976;10879.702517505699;10944.288621239593;11286.322820630789;7862.1351651319455;7889.802754931173;7971.242731940456;8033.156518565466;8081.434299598972;7608.429367797326;9967.302288839712;10657.786844263226;10718.517666704862;10997.395143267197 11:46:29 INFO - PROCESS | 22451 | |3;mootools.html;1303.713183099718;1304.282644545501;1328.1234385305695;1331.6537700197946;1345.1794405412697;6451.677416129198;6705.362990515905;6901;7355.779326620203;7391.926080739191;6884.414824739903;7135.250798666143;7154.069970903788;7299.744508942188;7343.302386273309;2057.4856285928518;2103.5582527669208;2104.284543255293;2108.947276318089;2121.087932189158;1863.7856646485625;1929;2080.5318803269297;2126.5321629241544;2136.134865379524;2054.044869135859;2113.5138918048856;2123.893805309728;2124.989375053123;2137.6045431595203;2053.938381848547;2083.749950005994;2103.7791031941656;2104.515961328903;2109.5253567947243;2139.3474990128007;2144.1637761273114;2148.215901196068;2170.0560256288563;2176.4885251965775;7245.855082898365;7280.635968201569;7341.559506429631;7364.263573642646;7386.630668466609;4329.328954012133;4457.2199865023495;4462.732236065846;4464.531224221475;4465.553444655508;2836.3334616365137;2929.2383980165096;2980.4784162771643;2991.521356582958;3009;1161.628278950736;1164.3188734590274;1176.5764324843049;1196.1865931166803;1198.4666823263651;9650.372725772802;9677.951610241975;9716.514174291256;9720.75698107546;9766.706998790047;3742.8689995850013;3943.921121577552;3944.447777311179;3958.247932892741;4029.838806447768;7695.191629966953;7771.72798952034;7775.494592851446;7776.65005074773;7801.29788319054;4391.8462853799965;4433.977830110829;4436.44544431946;4436.489803672554;4438.5561443856;3886.3004659161056;4114.362273847559;4116.670666346685;4117.114820313647;4120.093579412584;3217.131374528911;3235.692609202122;3316.369889720945;3326.334733053399;3328.8502017409273;954.5465903695689;995.3928103856641;999.2805180270194;999.5002498750624;1017.2675673515057;610.9725062372204;611.0559186057552;630.136712703599;634.0489266100849;638.1098367776925;4515.209838278288;4557.977210113994;4601.28680054592;4625.976870115695;4723.669343145947;753.333300029467;786.4141214795014;825.4510750351069;828.4780588229377;828.5028982610506;2979.9702002979693;3017;3047.268655522703;3081.2913030003224;3105.8414401990854;8219.917800822035;8577.313814894793;8602.78493037679;8604.956975215084;8654.48073115615;8535.786605334917;8575.656973721107;8581.485110893367;8603.784905377415;8619.784505387415;6811.897821532681;6829.2487826338065;6906.37842594169;6906.689198986058;6917.792466226021;4620.953790462052;4666.929996049994;4713.222318317439;4798.520147985313;4844.757762111951;7775.494592851446;7799.64901579419;7829.530228186327;7881.211878812073;7886.842263154819;8035.276825085771;8039.799005024687;8077.394195435249;8153.714619988272;8172.550509722022;5703.885922281448;5767.5385969121535;5772.278465191851;5775.306963164448;5778.219940308005;6705.798826035227;6715.966420167868;6721.798346049626;6788.796336109925;6885.827854303682;5231.1107111790325;5933.614315069507;6027.487663548647;6056.152138700498;6198.597091189239;5850.882982340415;5956.672383018976;6011.87976240464;6013.639181649115;6089.269287685506;5486.204500347348;5551.666899986014;5597.524210442158;5599.80400685974;5611.410801865909;4023.1953609277675;4033.959660403476;4095.262852686545;4113.8971525712095;4138.710290279772;3986.4418981343215;4073.9185216294914;4084.509858816961;4127.09203975125;4215.494140703013 11:46:29 INFO - PROCESS | 22451 | |4;prototype.html;339.53992340378784;344.9375922159748;355.142331269983;355.4842606620523;357.6030606027311;5007.524285192905;5030.673006254591;5038.370203724535;5048.999999999995;5049.19212925931;4930.728809915462;4971.602271818255;4978.581104385251;4983.501649835024;5006.54941055305;3775.1505911169907;3856.82644281008;3883.1262965832743;3940.7832569208826;3976.310068221009;3965.345717956534;3972.3842804365368;3981.800909954505;3982.9601703983026;3996.5403978542404;3948.9210215795665;3954.3673012318036;3959.663428608572;3967.2462232175794;3989.301872172373;3910.061585219555;3915.6084391560903;3929.9214015719813;3983.4423180754716;4001.4397984282077;3527.1005893496995;3555.1467647764607;3556.1465248340214;3574.160072382988;3579.158897659048;4770.379850619432;4818.325434439182;4847.854564363075;4848;4882.975585122087;4533.365328853963;4543.5002149763495;4584.426946631671;4589.678722489427;4595.609373203249;5044.899102017976;5202.115640341151;5219.773353262019;5246.37043554772;5265.262863199156;3665.102049997741;3666.5233519642543;3667.7799332040163;3675.8713445029293;3676.558812942464;5496.31296087989;5507.568032311628;5510.897820435929;5514.944850551483;5564.33228012641;3660.1581636223286;3751.531058617673;3761.5674197467642;3770.7368031709684;3785.4511095891494;3794.886153415402;3816.2176753765966;3819.618038196158;3825.5026846509777;3846;4387.670924680598;4414.543200743747;4417.624501917308;4419.977900110479;4420.668449866209;5181.3005244291535;5239.947600523946;5308.814191503279;5313.3964357464565;5341.278927344759;1282.8332316798756;1288.4910460368103;1289.7678417884683;1290.6386211860695;1297.1179597873536;821.650798410673;858.4420126917554;864.567716141929;865.476386785998;866.9899567004495;4572.474165470947;4654.348391225231;4677.391939047902;4679.321498382716;4680.297955306663;5474.260974768435;5482.753276102585;5485.369182543979;5508.622844288928;5527.2538207341495;4606.539346065367;4691.835785747482;4695.38009386761;4712.764361781898;4725.5510726480925;2817.21118086936;2823.5905793659804;2836.886519542954;2838.205302515299;2852.415254872746;4881.8535443936735;4911.901761964741;4912.70523768575;4932.704037757746;4981.71207003376;5206.698325418645;5380.381256155514;5464.398916119224;5474.835754927359;5503.752331145108;4831.057943700945;4833.854984350475;4845.643219898434;4864.124457597666;4878.56092951636;5265.210218467108;5270.73646317675;5296.451377577109;5298.039725301663;5300.5494532963585;4938.7406211415855;5070.949290507047;5111.798727299008;5229.738513074407;5230.790768369223;4734.1478533862855;4760.738159401266;4761.523847615349;4763.546773411626;4791.323036937044;3598.172420343283;3608.1160115770754;3627.673509384168;3637.9818100909324;3668.192997540537;5134.7946082156295;5261;5273.5781137509675;5274.3670759509105;5422.186671999231;5209.869753256047;5218.373795144607;5222.451642577475;5248.60635452335;5275.788968441373;5255.553277971415;5269.473052694853;5275.525202731773;5287.603429742708;5297.682139071668;4946.3817022872145;5103.157978933435;5145.485451454824;5181.326427564392;5217.165253559412;5051.87370315745;5166.3800343958965;5180.404253510744;5275.340582427197;5275.472452754694;5150.819721309736;5184.429712731521;5186.636935414635;5216.478352164753;5220.1647736362 11:46:29 INFO - PROCESS | 22451 | |5;yui.html;661.3122352753137;669.9296573859741;717.1895757793692;727.7380143148467;762.5805806806256;12964.222146671207;13863.514776982782;13872.16766993981;13904.513342033044;13926.65183370415;12225.266484010966;12277.508899644003;12351.876481235186;12457;12475.563355282567;669.3641041011035;691.3777600159846;777.3004296133469;797.7885860247039;817.9141190175035;812.2121542104179;827.0778082438069;830.4311546590575;833.6998680475054;834.9123342049088;874.64576846377;883.0286684646869;885.2431171348501;885.9335549833756;886.2068448738376;879.0550158579489;880.0495464897895;880.1110878013188;880.3617377401397;880.5685214244974;637.7449020391834;639.46285120499;640.2796853539768;643.572024603638;644.4168027934727;12816.102872798907;12873;13038.478460861554;13045.478180872753;13427.060105792598;11817.468213930393;11845.94077029618;12803.935980320039;13511.459541618324;13600.31998400076;11655;11727.53089876404;11735.178537502377;11811.586594469238;11837.881621183764;4099.1801639671885;4099.9180016399505;4117.485314335708;4118.6499147572495;4131.8224306072625;61630.691846540925;62705;63480.682596586725;64065.67967160181;64175;8639.222469977733;8735.912640873637;8742.912570874338;8784.341174411817;8975.37172397939;15833.683326333408;15852.207389630472;15856.52430427089;15930.123843188505;15935.521934341989;6320.336364681642;6322.525810564226;6345.36546345371;6519.576227545194;6592.571482853599;3547.0777597824235;3568.946465803015;3611.0680785780005;3611.1874828163977;3611.6749492545273;2902.201894479035;2933.603963464948;2941.7646588272887;2973.2566858285427;2988.9850550747105;492.72161228905554;495.25216922446646;495.774422637699;495.90825697246123;502.06866263082185;542.9674219546761;547.6495043172373;564.3689736661053;566.742132329789;588.0444278048171;6498.707558159894;7365.447591430664;7400.925990740131;7573.166951635316;8163.38774591908;519.3326575350657;534.855588990978;547.4197350808156;554.8252300525321;565.5588640860136;3333.949990750141;3365.899023029313;3379.8648054078053;3478.6869181773764;3496.5629296337956;65477.34522654808;66041.66979165074;66309.66845165743;68914.3108568918;68997.65501172462;57149.14276285859;57260;57940;59435.70282148562;60214.39785602088;52597.474025260024;52612;52797;52981;53035.73482132565;6522;6574.342565743387;6727.663616819237;6733.494987876027;6886.69009894566;37037;37520.437193441576;37940.62059379481;38136.04659883525;38473.4228986571;43676.781616091714;44121.338179926686;45496.31755523736;46011.76994115008;47432.814179645786;8590.312774978112;8671.523066231417;8800.427972181787;8815.603297851483;8889.822203555763;9369.344145909721;9395.671151509665;9445.433274003306;9519.762005949906;9558.904410955802;9251.76870578241;9297.907020929704;9447.811043779224;9636.903630963601;9644.517774111408;8670.26302764272;8733.214010739064;8771.122887711177;8835.116488351114;8868.86696699537;8649.481031138152;8667.609957551798;8671.653133874826;8672.176143266379;8675.089115642766;8171.264586187034;8253.09215986253;8259.628316725642;8345.95827020861;8439.578021098798;7837.686492540234;7915.604219789103;8189.22202390772;8238.176182381714;8262.380321475794 11:46:29 INFO - PROCESS | 22451 | __end_tp_report 11:46:29 INFO - PROCESS | 22451 | __start_cc_report 11:46:29 INFO - PROCESS | 22451 | _x_x_mozilla_cycle_collect,336 11:46:29 INFO - PROCESS | 22451 | __end_cc_report 11:46:29 INFO - PROCESS | 22451 | __startTimestamp1483127189022__endTimestamp 11:46:29 INFO - PROCESS | 22451 | 11:46:29 INFO - PROCESS | 22451 | ------- Summary: start ------- 11:46:29 INFO - PROCESS | 22451 | Number of tests: 6 11:46:29 INFO - PROCESS | 22451 | 11:46:29 INFO - PROCESS | 22451 | [#0] dojo.html Cycles:165 Average:4816.86 Median:4960.58 stddev:522.82 (10.5%) stddev-sans-first:495.21 11:46:29 INFO - PROCESS | 22451 | Values: 2620.4 2621.6 2622.4 2655.9 2661.2 4841.8 4889.7 4911.7 4920.8 4928.8 4856.5 4867.6 4893.5 4898.0 4960.6 4472.0 4474.2 4481.4 4504.2 4562.7 4448.3 4554.8 4588.1 4609.1 4618.9 4405.4 4509.8 4564.3 4590.0 4660.6 4423.9 4427.9 4462.0 4468.1 4530.6 4433.9 4437.7 4445.2 4454.1 4492.1 4821.3 4833.2 4856.3 4867.0 4874.0 5083.4 5130.4 5211.6 5218.3 5269.8 5303.6 5311.2 5316.9 5318.7 5361.9 4899.2 4940.8 4965.0 4972.0 5011.8 5233.6 5309.4 5329.5 5352.9 5379.1 5171.7 5173.9 5178.8 5185.2 5223.5 5070.5 5104.1 5242.7 5248.0 5269.6 5107.2 5144.2 5169.4 5174.3 5229.0 5160.9 5165.8 5168.8 5170.1 5220.9 4326.1 4416.3 4418.5 4430.2 4464.9 3780.3 3796.9 3834.4 3855.7 3911.3 4509.1 4510.1 4514.3 4515.9 4553.2 5202.4 5246.8 5250.4 5260.1 5265.9 4341.7 4379.2 4394.5 4427.2 4443.4 4579.5 4625.0 4629.6 4647.3 4649.0 4719.1 4727.5 4742.6 4747.6 4805.8 5229.8 5267.5 5272.6 5316.7 5331.1 4838.9 4839.8 4846.7 4849.1 4889.2 4983.6 5046.1 5120.8 5137.5 5166.9 5196.8 5221.7 5234.6 5265.4 5300.6 5113.9 5116.5 5117.8 5121.6 5123.9 4986.8 5030.3 5058.0 5059.2 5059.9 4934.7 4987.2 4999.1 5030.0 5055.0 5168.6 5175.8 5175.9 5179.6 5224.2 5031.5 5033.4 5049.2 5056.7 5096.1 11:46:29 INFO - PROCESS | 22451 | 11:46:29 INFO - PROCESS | 22451 | [#1] ext.html Cycles:180 Average:46068.86 Median:365885.50 stddev:81919.07 (22.4%) stddev-sans-first:82077.42 11:46:29 INFO - PROCESS | 22451 | Values: 496.1 504.7 507.6 514.9 520.1 35569.5 37734.0 37866.2 37928.7 38022.8 38687.4 38937.0 39018.2 39026.4 39129.8 7450.5 7579.5 7726.8 7727.2 7729.1 7416.5 9924.0 10106.7 10141.1 10352.8 9980.5 10037.9 10040.8 10047.6 10069.7 9911.6 9957.0 9975.7 9984.6 9985.5 9785.7 9865.7 9870.2 9879.4 9881.4 35336.6 35731.3 36049.6 36155.6 36184.8 3671.7 3721.6 3721.6 3759.8 3792.2 26735.2 27452.9 27486.0 27499.0 27608.7 3352.3 3404.2 3610.4 3614.6 3617.2 221777.0 222211.0 223331.9 223339.0 223514.0 48662.0 48939.0 49211.5 49260.0 49498.5 52190.2 52252.0 52252.7 52257.5 52260.5 13887.6 14056.4 14078.4 14208.7 14212.6 8703.3 8933.0 10615.8 10756.4 10781.5 17649.5 17761.6 17844.0 18263.8 18312.9 7091.6 7215.3 7228.7 7442.4 7476.0 784.1 803.2 804.7 808.2 829.0 26430.9 27232.9 27419.3 27533.6 27652.8 943.4 948.7 951.6 957.0 964.4 13442.6 13591.3 13793.8 14053.2 14107.9 281359.0 365537.0 365666.0 366105.0 366831.0 42766.4 43016.6 43054.8 43155.0 43232.6 1209.1 1212.7 1218.6 1232.7 1240.5 24138.3 24193.4 25266.6 26716.7 28600.0 41129.2 41848.4 41886.0 41937.4 41979.4 339429.0 344526.0 346177.0 346757.0 347723.0 29000.9 30463.0 31099.4 34055.3 34172.0 30209.7 41364.6 43338.0 43973.6 49139.3 33747.8 33923.2 34065.8 36347.6 36900.0 36619.0 36720.6 37019.4 37139.0 37717.6 35977.1 36053.5 37143.6 37187.3 37200.3 30480.4 32598.7 34861.3 35026.3 35213.0 25708.7 25960.2 26212.5 26276.0 26410.5 11:46:29 INFO - PROCESS | 22451 | 11:46:29 INFO - PROCESS | 22451 | [#2] jquery.html Cycles:180 Average:21539.84 Median:4997.52 stddev:53829.17 (1077.1%) stddev-sans-first:53956.68 11:46:29 INFO - PROCESS | 22451 | Values: 356.6 364.7 371.7 377.3 383.6 15444.6 16120.7 16536.2 16585.8 16632.3 15556.2 15726.5 16034.3 16073.8 16176.9 4975.8 5045.5 5054.2 5073.0 5073.6 5019.3 5068.9 5349.6 5376.1 5415.1 5378.3 5380.5 5391.4 5391.5 5393.3 5307.2 5899.3 6003.1 6035.1 6053.8 5194.9 5382.2 5386.1 5387.2 5388.1 12191.8 12532.6 12536.9 12537.4 12598.4 2123.2 2194.5 2195.9 2196.4 2238.6 8461.2 8850.3 8885.5 9217.9 9296.9 1616.9 1656.7 1677.3 1687.4 1708.1 52119.5 62916.7 66084.4 66911.0 68174.7 9798.5 9940.8 9998.2 10052.1 10298.8 13924.7 13942.4 13959.7 13966.9 13996.3 7072.9 7294.6 7327.8 7444.7 7450.3 6871.4 6910.9 7072.9 7130.5 7136.9 4167.4 4242.7 4283.3 4401.9 4443.7 1519.9 1573.6 1759.8 1839.9 1893.9 788.9 819.9 822.7 823.5 829.1 16057.1 16106.7 16406.1 16543.5 16612.8 810.2 817.2 817.5 821.9 840.5 9026.4 9314.6 9389.2 9444.8 9576.7 318937.0 324047.0 326874.7 326925.0 329066.0 70376.0 70421.3 70603.3 71870.3 72626.3 897.9 908.6 936.5 937.1 944.1 14234.9 14251.4 14282.1 14353.1 14936.2 29499.3 32471.5 32628.8 34025.7 34971.0 43097.8 43118.4 43416.1 45716.8 46122.8 6937.2 7007.1 7081.6 7287.9 7365.2 9890.0 10937.8 11200.7 11483.7 12381.4 9336.5 9772.7 9846.2 10076.8 10371.8 9726.3 10211.7 10258.5 10349.7 10442.9 10253.3 10396.3 10879.7 10944.3 11286.3 7862.1 7889.8 7971.2 8033.2 8081.4 7608.4 9967.3 10657.8 10718.5 10997.4 11:46:29 INFO - PROCESS | 22451 | 11:46:29 INFO - PROCESS | 22451 | [#3] mootools.html Cycles:180 Average:4662.89 Median:5037.93 stddev:2539.23 (50.4%) stddev-sans-first:2533.80 11:46:29 INFO - PROCESS | 22451 | Values: 1303.7 1304.3 1328.1 1331.7 1345.2 6451.7 6705.4 6901.0 7355.8 7391.9 6884.4 7135.3 7154.1 7299.7 7343.3 2057.5 2103.6 2104.3 2108.9 2121.1 1863.8 1929.0 2080.5 2126.5 2136.1 2054.0 2113.5 2123.9 2125.0 2137.6 2053.9 2083.7 2103.8 2104.5 2109.5 2139.3 2144.2 2148.2 2170.1 2176.5 7245.9 7280.6 7341.6 7364.3 7386.6 4329.3 4457.2 4462.7 4464.5 4465.6 2836.3 2929.2 2980.5 2991.5 3009.0 1161.6 1164.3 1176.6 1196.2 1198.5 9650.4 9678.0 9716.5 9720.8 9766.7 3742.9 3943.9 3944.4 3958.2 4029.8 7695.2 7771.7 7775.5 7776.7 7801.3 4391.8 4434.0 4436.4 4436.5 4438.6 3886.3 4114.4 4116.7 4117.1 4120.1 3217.1 3235.7 3316.4 3326.3 3328.9 954.5 995.4 999.3 999.5 1017.3 611.0 611.1 630.1 634.0 638.1 4515.2 4558.0 4601.3 4626.0 4723.7 753.3 786.4 825.5 828.5 828.5 2980.0 3017.0 3047.3 3081.3 3105.8 8219.9 8577.3 8602.8 8605.0 8654.5 8535.8 8575.7 8581.5 8603.8 8619.8 6811.9 6829.2 6906.4 6906.7 6917.8 4621.0 4666.9 4713.2 4798.5 4844.8 7775.5 7799.6 7829.5 7881.2 7886.8 8035.3 8039.8 8077.4 8153.7 8172.6 5703.9 5767.5 5772.3 5775.3 5778.2 6705.8 6716.0 6721.8 6788.8 6885.8 5231.1 5933.6 6027.5 6056.2 6198.6 5850.9 5956.7 6011.9 6013.6 6089.3 5486.2 5551.7 5597.5 5599.8 5611.4 4023.2 4034.0 4095.3 4113.9 4138.7 3986.4 4073.9 4084.5 4127.1 4215.5 11:46:29 INFO - PROCESS | 22451 | 11:46:29 INFO - PROCESS | 22451 | [#4] prototype.html Cycles:180 Average:4328.12 Median:4847.93 stddev:1253.85 (25.9%) stddev-sans-first:1221.10 11:46:29 INFO - PROCESS | 22451 | Values: 339.5 344.9 355.1 355.5 357.6 5007.5 5030.7 5038.4 5049.0 5049.2 4930.7 4971.6 4978.6 4983.5 5006.5 3775.2 3856.8 3883.1 3940.8 3976.3 3965.3 3972.4 3981.8 3983.0 3996.5 3948.9 3954.4 3959.7 3967.2 3989.3 3910.1 3915.6 3929.9 3983.4 4001.4 3527.1 3555.1 3556.1 3574.2 3579.2 4770.4 4818.3 4847.9 4848.0 4883.0 4533.4 4543.5 4584.4 4589.7 4595.6 5044.9 5202.1 5219.8 5246.4 5265.3 3665.1 3666.5 3667.8 3675.9 3676.6 5496.3 5507.6 5510.9 5514.9 5564.3 3660.2 3751.5 3761.6 3770.7 3785.5 3794.9 3816.2 3819.6 3825.5 3846.0 4387.7 4414.5 4417.6 4420.0 4420.7 5181.3 5239.9 5308.8 5313.4 5341.3 1282.8 1288.5 1289.8 1290.6 1297.1 821.7 858.4 864.6 865.5 867.0 4572.5 4654.3 4677.4 4679.3 4680.3 5474.3 5482.8 5485.4 5508.6 5527.3 4606.5 4691.8 4695.4 4712.8 4725.6 2817.2 2823.6 2836.9 2838.2 2852.4 4881.9 4911.9 4912.7 4932.7 4981.7 5206.7 5380.4 5464.4 5474.8 5503.8 4831.1 4833.9 4845.6 4864.1 4878.6 5265.2 5270.7 5296.5 5298.0 5300.5 4938.7 5070.9 5111.8 5229.7 5230.8 4734.1 4760.7 4761.5 4763.5 4791.3 3598.2 3608.1 3627.7 3638.0 3668.2 5134.8 5261.0 5273.6 5274.4 5422.2 5209.9 5218.4 5222.5 5248.6 5275.8 5255.6 5269.5 5275.5 5287.6 5297.7 4946.4 5103.2 5145.5 5181.3 5217.2 5051.9 5166.4 5180.4 5275.3 5275.5 5150.8 5184.4 5186.6 5216.5 5220.2 11:46:29 INFO - PROCESS | 22451 | 11:46:29 INFO - PROCESS | 22451 | [#5] yui.html Cycles:180 Average:14314.46 Median:32059.21 stddev:18833.80 (58.7%) stddev-sans-first:18858.73 11:46:29 INFO - PROCESS | 22451 | Values: 661.3 669.9 717.2 727.7 762.6 12964.2 13863.5 13872.2 13904.5 13926.7 12225.3 12277.5 12351.9 12457.0 12475.6 669.4 691.4 777.3 797.8 817.9 812.2 827.1 830.4 833.7 834.9 874.6 883.0 885.2 885.9 886.2 879.1 880.0 880.1 880.4 880.6 637.7 639.5 640.3 643.6 644.4 12816.1 12873.0 13038.5 13045.5 13427.1 11817.5 11845.9 12803.9 13511.5 13600.3 11655.0 11727.5 11735.2 11811.6 11837.9 4099.2 4099.9 4117.5 4118.6 4131.8 61630.7 62705.0 63480.7 64065.7 64175.0 8639.2 8735.9 8742.9 8784.3 8975.4 15833.7 15852.2 15856.5 15930.1 15935.5 6320.3 6322.5 6345.4 6519.6 6592.6 3547.1 3568.9 3611.1 3611.2 3611.7 2902.2 2933.6 2941.8 2973.3 2989.0 492.7 495.3 495.8 495.9 502.1 543.0 547.6 564.4 566.7 588.0 6498.7 7365.4 7400.9 7573.2 8163.4 519.3 534.9 547.4 554.8 565.6 3333.9 3365.9 3379.9 3478.7 3496.6 65477.3 66041.7 66309.7 68914.3 68997.7 57149.1 57260.0 57940.0 59435.7 60214.4 52597.5 52612.0 52797.0 52981.0 53035.7 6522.0 6574.3 6727.7 6733.5 6886.7 37037.0 37520.4 37940.6 38136.0 38473.4 43676.8 44121.3 45496.3 46011.8 47432.8 8590.3 8671.5 8800.4 8815.6 8889.8 9369.3 9395.7 9445.4 9519.8 9558.9 9251.8 9297.9 9447.8 9636.9 9644.5 8670.3 8733.2 8771.1 8835.1 8868.9 8649.5 8667.6 8671.7 8672.2 8675.1 8171.3 8253.1 8259.6 8346.0 8439.6 7837.7 7915.6 8189.2 8238.2 8262.4 11:46:29 INFO - PROCESS | 22451 | -------- Summary: end -------- 11:46:29 INFO - PROCESS | 22451 | 11:46:29 INFO - TEST-INFO | 22451: exit 0 11:46:29 INFO - TEST-OK | dromaeo_css | took 1119390ms 11:46:29 INFO - TEST-START | kraken 11:46:29 INFO - operating with platform_type : linux_ 11:46:29 INFO - Initialising browser for kraken test... 11:46:29 INFO - TEST-INFO | started process 22805 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGOaQmM/profile http://localhost:41566/getInfo.html) 11:46:30 INFO - PROCESS | 22805 | 1483127190448 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 11:46:34 INFO - PROCESS | 22805 | __metrics Screen width/height:1600/1200 11:46:34 INFO - PROCESS | 22805 | colorDepth:24 11:46:34 INFO - PROCESS | 22805 | Browser inner width/height: 1024/697 11:46:34 INFO - PROCESS | 22805 | __metrics 11:46:35 ERROR - PROCESS | 22805 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 11:46:36 INFO - TEST-INFO | 22805: exit 0 11:46:36 INFO - Browser initialized. 11:46:36 INFO - Running cycle 1/1 for kraken test... 11:46:36 INFO - TEST-INFO | started process 22929 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGOaQmM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:46:48 INFO - PROCESS | 22929 | RSS: Main: 158457856 11:46:48 INFO - PROCESS | 22929 | 11:46:50 INFO - PROCESS | 22929 | Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41566/tests/kraken/driver.html?testName=audio-beat-detection) 11:46:51 INFO - PROCESS | 22929 | RSS: Main: 163495936 11:46:51 INFO - PROCESS | 22929 | 11:46:54 INFO - PROCESS | 22929 | Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41566/tests/kraken/driver.html?testName=audio-dft) 11:46:55 INFO - PROCESS | 22929 | RSS: Main: 162521088 11:46:55 INFO - PROCESS | 22929 | 11:46:58 INFO - PROCESS | 22929 | Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41566/tests/kraken/driver.html?testName=audio-fft) 11:46:58 INFO - PROCESS | 22929 | RSS: Main: 159301632 11:46:58 INFO - PROCESS | 22929 | 11:47:01 INFO - PROCESS | 22929 | Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41566/tests/kraken/driver.html?testName=audio-oscillator) 11:47:01 INFO - PROCESS | 22929 | RSS: Main: 160350208 11:47:01 INFO - PROCESS | 22929 | 11:47:03 INFO - PROCESS | 22929 | Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41566/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:47:04 INFO - PROCESS | 22929 | RSS: Main: 160464896 11:47:04 INFO - PROCESS | 22929 | 11:47:07 INFO - PROCESS | 22929 | Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41566/tests/kraken/driver.html?testName=imaging-darkroom) 11:47:08 INFO - PROCESS | 22929 | RSS: Main: 164245504 11:47:08 INFO - PROCESS | 22929 | 11:47:11 INFO - PROCESS | 22929 | Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41566/tests/kraken/driver.html?testName=imaging-desaturate) 11:47:12 INFO - PROCESS | 22929 | RSS: Main: 161931264 11:47:12 INFO - PROCESS | 22929 | 11:47:15 INFO - PROCESS | 22929 | Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41566/tests/kraken/driver.html?testName=json-parse-financial) 11:47:15 INFO - PROCESS | 22929 | RSS: Main: 162693120 11:47:15 INFO - PROCESS | 22929 | 11:47:17 INFO - PROCESS | 22929 | Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41566/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:47:18 INFO - PROCESS | 22929 | RSS: Main: 163258368 11:47:18 INFO - PROCESS | 22929 | 11:47:20 INFO - PROCESS | 22929 | Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:47:20 INFO - PROCESS | 22929 | RSS: Main: 163356672 11:47:20 INFO - PROCESS | 22929 | 11:47:23 INFO - PROCESS | 22929 | Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:47:23 INFO - PROCESS | 22929 | RSS: Main: 163635200 11:47:23 INFO - PROCESS | 22929 | 11:47:26 INFO - PROCESS | 22929 | Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:47:27 INFO - PROCESS | 22929 | RSS: Main: 164016128 11:47:27 INFO - PROCESS | 22929 | 11:47:30 INFO - PROCESS | 22929 | Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:47:31 INFO - PROCESS | 22929 | RSS: Main: 163532800 11:47:31 INFO - PROCESS | 22929 | 11:47:32 INFO - PROCESS | 22929 | Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41566/tests/kraken/driver.html?testName=ai-astar) 11:47:33 INFO - PROCESS | 22929 | RSS: Main: 165109760 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | __start_tp_report 11:47:33 INFO - PROCESS | 22929 | _x_x_mozilla_page_load 11:47:33 INFO - PROCESS | 22929 | _x_x_mozilla_page_load_details 11:47:33 INFO - PROCESS | 22929 | |i|pagename|runs| 11:47:33 INFO - PROCESS | 22929 | |0;ai-astar;109;117;114;115;114;114;116;114;115;114 11:47:33 INFO - PROCESS | 22929 | |1;audio-beat-detection;155;128;129;177;133;133;133;128;146;127 11:47:33 INFO - PROCESS | 22929 | |2;audio-dft;166;166;166;176;165;166;163;162;164;163 11:47:33 INFO - PROCESS | 22929 | |3;audio-fft;77;77;80;110;78;79;78;78;91;79 11:47:33 INFO - PROCESS | 22929 | |4;audio-oscillator;86;85;109;86;85;85;103;86;86;113 11:47:33 INFO - PROCESS | 22929 | |5;imaging-gaussian-blur;118;118;119;119;119;122;119;119;119;118 11:47:33 INFO - PROCESS | 22929 | |6;imaging-darkroom;152;150;151;151;151;151;151;150;151;151 11:47:33 INFO - PROCESS | 22929 | |7;imaging-desaturate;97;96;96;95;96;95;96;97;96;96 11:47:33 INFO - PROCESS | 22929 | |8;json-parse-financial;70;70;69;70;72;71;72;72;71;71 11:47:33 INFO - PROCESS | 22929 | |9;json-stringify-tinderbox;66;68;68;68;68;69;68;69;68;68 11:47:33 INFO - PROCESS | 22929 | |10;stanford-crypto-aes;89;87;91;87;92;93;85;92;101;92 11:47:33 INFO - PROCESS | 22929 | |11;stanford-crypto-ccm;139;136;150;141;150;136;135;155;198;148 11:47:33 INFO - PROCESS | 22929 | |12;stanford-crypto-pbkdf2;178;177;183;180;180;183;183;188;185;177 11:47:33 INFO - PROCESS | 22929 | |13;stanford-crypto-sha256-iterative;70;71;70;73;73;72;69;69;69;69 11:47:33 INFO - PROCESS | 22929 | __end_tp_report 11:47:33 INFO - PROCESS | 22929 | __start_cc_report 11:47:33 INFO - PROCESS | 22929 | _x_x_mozilla_cycle_collect,805 11:47:33 INFO - PROCESS | 22929 | __end_cc_report 11:47:33 INFO - PROCESS | 22929 | __startTimestamp1483127253181__endTimestamp 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | ------- Summary: start ------- 11:47:33 INFO - PROCESS | 22929 | Number of tests: 14 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.50 stddev:2.10 (1.8%) stddev-sans-first:1.09 11:47:33 INFO - PROCESS | 22929 | Values: 109.0 117.0 114.0 115.0 114.0 114.0 116.0 114.0 115.0 114.0 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:133.00 stddev:16.13 (12.1%) stddev-sans-first:16.03 11:47:33 INFO - PROCESS | 22929 | Values: 155.0 128.0 129.0 177.0 133.0 133.0 133.0 128.0 146.0 127.0 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | [#2] audio-dft Cycles:10 Average:165.70 Median:166.00 stddev:3.92 (2.4%) stddev-sans-first:4.15 11:47:33 INFO - PROCESS | 22929 | Values: 166.0 166.0 166.0 176.0 165.0 166.0 163.0 162.0 164.0 163.0 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | [#3] audio-fft Cycles:10 Average:82.70 Median:78.50 stddev:10.44 (13.3%) stddev-sans-first:10.86 11:47:33 INFO - PROCESS | 22929 | Values: 77.0 77.0 80.0 110.0 78.0 79.0 78.0 78.0 91.0 79.0 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | [#4] audio-oscillator Cycles:10 Average:92.40 Median:85.50 stddev:11.26 (13.2%) stddev-sans-first:11.70 11:47:33 INFO - PROCESS | 22929 | Values: 86.0 85.0 109.0 86.0 85.0 85.0 103.0 86.0 86.0 113.0 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | [#5] imaging-gaussian-blur Cycles:10 Average:119.00 Median:119.00 stddev:1.15 (1.0%) stddev-sans-first:1.17 11:47:33 INFO - PROCESS | 22929 | Values: 118.0 118.0 119.0 119.0 119.0 122.0 119.0 119.0 119.0 118.0 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.00 stddev:0.57 (0.4%) stddev-sans-first:0.44 11:47:33 INFO - PROCESS | 22929 | Values: 152.0 150.0 151.0 151.0 151.0 151.0 151.0 150.0 151.0 151.0 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 11:47:33 INFO - PROCESS | 22929 | Values: 97.0 96.0 96.0 95.0 96.0 95.0 96.0 97.0 96.0 96.0 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | [#8] json-parse-financial Cycles:10 Average:70.80 Median:71.00 stddev:1.03 (1.5%) stddev-sans-first:1.05 11:47:33 INFO - PROCESS | 22929 | Values: 70.0 70.0 69.0 70.0 72.0 71.0 72.0 72.0 71.0 71.0 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.00 stddev:0.82 (1.2%) stddev-sans-first:0.44 11:47:33 INFO - PROCESS | 22929 | Values: 66.0 68.0 68.0 68.0 68.0 69.0 68.0 69.0 68.0 68.0 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:91.50 stddev:4.46 (4.9%) stddev-sans-first:4.68 11:47:33 INFO - PROCESS | 22929 | Values: 89.0 87.0 91.0 87.0 92.0 93.0 85.0 92.0 101.0 92.0 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | [#11] stanford-crypto-ccm Cycles:10 Average:148.80 Median:149.00 stddev:18.67 (12.5%) stddev-sans-first:19.46 11:47:33 INFO - PROCESS | 22929 | Values: 139.0 136.0 150.0 141.0 150.0 136.0 135.0 155.0 198.0 148.0 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.40 Median:183.00 stddev:3.63 (2.0%) stddev-sans-first:3.63 11:47:33 INFO - PROCESS | 22929 | Values: 178.0 177.0 183.0 180.0 180.0 183.0 183.0 188.0 185.0 177.0 11:47:33 INFO - PROCESS | 22929 | 11:47:33 INFO - PROCESS | 22929 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:70.50 stddev:1.65 (2.3%) stddev-sans-first:1.74 11:47:33 INFO - PROCESS | 22929 | Values: 70.0 71.0 70.0 73.0 73.0 72.0 69.0 69.0 69.0 69.0 11:47:33 INFO - PROCESS | 22929 | -------- Summary: end -------- 11:47:33 INFO - PROCESS | 22929 | 11:47:34 INFO - TEST-INFO | 22929: exit 0 11:47:34 INFO - TEST-OK | kraken | took 64436ms 11:47:34 INFO - SUITE-END | took 1183s 11:47:34 INFO - Completed test suite (00:19:44) 11:47:34 INFO - javascript benchmark 11:47:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6238.359934085004, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2620.423506828497, 2621.5674413721736, 2622.383739821142, 2655.867206639668, 2661.201639508147, 4841.8063277468855, 4889.682170658904, 4911.705297682137, 4920.778564964577, 4928.753562321887, 4856.465788763233, 4867.610591152708, 4893.4861839506875, 4897.975510122454, 4960.578350840183, 4471.999999999984, 4474.239379305526, 4481.439820022497, 4504.166729155117, 4562.726236425809, 4448.33275008748, 4554.84058057968, 4588.082383523292, 4609.055143695551, 4618.884527886813, 4405.383246345515, 4509.751963641998, 4564.292534657134, 4590.049924244299, 4660.557247061541, 4423.889402764925, 4427.933580996288, 4461.977690111528, 4468.061707041526, 4530.569595888385, 4433.933490997638, 4437.667174961891, 4445.177642136216, 4454.0646464242545, 4492.124035813018, 4821.276808478721, 4833.2266837306215, 4856.2958371036, 4866.975665121687, 4874.049560335736, 5083.415407228142, 5130.358705161855, 5211.557017653504, 5218.321618189649, 5269.762860671279, 5303.575713942875, 5311.1767676009895, 5316.867078323035, 5318.654287471302, 5361.865953351197, 4899.167141585939, 4940.777665005047, 4965.0, 4971.975140124277, 5011.824586139504, 5233.633645644844, 5309.442508536548, 5329.520343169133, 5352.91970620441, 5379.085555455582, 5171.663841850268, 5173.922391164136, 5178.792848286026, 5185.196294574347, 5223.4776522347465, 5070.543651071347, 5104.132297509433, 5242.7378631068295, 5248.029114613809, 5269.578433725293, 5107.208382700687, 5144.151215049475, 5169.405518365361, 5174.327337446108, 5229.006488767122, 5160.948390516121, 5165.767540460688, 5168.81909133186, 5170.1469257572835, 5220.895582088413, 4326.113146804897, 4416.33754936762, 4418.469783625986, 4430.158269928767, 4464.888377790581, 3780.281746468162, 3796.905077373088, 3834.386498160281, 3855.7108216883844, 3911.2959667259597, 4509.098180363941, 4510.120526497301, 4514.300283456004, 4515.864524064284, 4553.157665831832, 5202.401723801811, 5246.76389562463, 5250.448702886142, 5260.079486089919, 5265.921011184835, 4341.717788343748, 4379.233634114017, 4394.494633117169, 4427.247367947392, 4443.422355093817, 4579.450465944108, 4625.0, 4629.606483448877, 4647.256438969748, 4649.0, 4719.126961512063, 4727.503612120747, 4742.644301677319, 4747.596454301423, 4805.7597120143155, 5229.843104706865, 5267.525922666979, 5272.578193744568, 5316.707581082923, 5331.09371406854, 4838.879028024327, 4839.782209800497, 4846.660733748604, 4849.102915960559, 4889.193283108248, 4983.551480366785, 5046.066477701637, 5120.769565369493, 5137.460566640599, 5166.922496162636, 5196.844094677166, 5221.712805795718, 5234.607404444606, 5265.420803711665, 5300.6024548158275, 5113.92329115071, 5116.488351164854, 5117.820876269313, 5121.590272778094, 5123.948760512347, 4986.80052797884, 5030.2957585937265, 5057.988402319477, 5059.241113832954, 5059.949400506095, 4934.654574179773, 4987.177115775857, 4999.10016197088, 5030.044291584587, 5055.014272216882, 5168.612354073384, 5175.767090480862, 5175.948240517697, 5179.611529135405, 5224.242484839753, 5031.471695471922, 5033.370828646419, 5049.2426136079885, 5056.721880296619, 5096.0827051131155], "value": 4781.738016119629, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.08224784149314, 504.70979186967503, 507.6015327967546, 514.8661348049507, 520.1132069820956, 35569.46645800312, 37733.99999999996, 37866.24267514648, 37928.67249646263, 38022.8098859506, 38687.41968870469, 38937.0, 39018.21963560727, 39026.4146037809, 39129.80435097827, 7450.515716478411, 7579.545227286353, 7726.763717805153, 7727.188645192258, 7729.111152217511, 7416.517926334798, 9924.007599240054, 10106.747331316703, 10141.13800326973, 10352.792944141112, 9980.451075190862, 10037.949810250939, 10040.849387259197, 10047.64833230837, 10069.748256293615, 9911.553980070878, 9957.0, 9975.700728978143, 9984.600615975389, 9985.450800205987, 9785.706428807147, 9865.654702085392, 9870.161036311922, 9879.407235565817, 9881.407115573089, 35336.64663353359, 35731.28537429263, 36049.639503605154, 36155.63844361549, 36184.819075904714, 3671.706263498914, 3721.5720192177973, 3721.5906250312446, 3759.755615884959, 3792.2225943681756, 26735.197944061514, 27452.862735686394, 27486.0, 27499.0, 27608.723912761016, 3352.2960178362327, 3404.1829960809227, 3610.3681855675413, 3614.6385361463645, 3617.2042150726797, 221777.0, 222211.0, 223331.88334058388, 223339.0, 223514.0, 48662.0, 48939.0, 49211.50788492141, 49260.0, 49498.50501494939, 52190.21714674283, 52252.0, 52252.73873630608, 52257.47742522526, 52260.47739522632, 13887.583372498639, 14056.43774249039, 14078.366473508717, 14208.715825683636, 14212.573622791333, 8703.347248956354, 8932.955335223412, 10615.787684246272, 10756.408397538211, 10781.460926953621, 17649.470515884543, 17761.644767104586, 17844.0, 18263.817361826477, 18312.90843545774, 7091.574505529685, 7215.314545118205, 7228.674709638079, 7442.367398771057, 7476.0, 784.1061190243128, 803.220875750521, 804.6660635836063, 808.244291587356, 828.9502629842108, 26430.86784566065, 27232.910683572434, 27419.31451713723, 27533.586996195074, 27652.8199257088, 943.3679434778578, 948.7201275623707, 951.6241084771476, 956.9760755981156, 964.4406244378243, 13442.596722098353, 13591.252481113634, 13793.793093103612, 14053.227072510905, 14107.85892141086, 281359.0, 365537.0, 365666.0, 366105.0, 366831.0, 42766.358504622454, 43016.56983430188, 43054.784726076796, 43155.0, 43232.56767432348, 1209.12338554549, 1212.7089498520468, 1218.622227109599, 1232.7041510037527, 1240.5347994502063, 24138.310318278254, 24193.39516512101, 25266.621000685373, 26716.7328326722, 28600.0, 41129.177416450904, 41848.37227441652, 41886.0, 41937.37093943532, 41979.37030944477, 339429.0, 344526.0, 346177.0, 346757.0, 347723.0, 29000.854995724887, 30463.0, 31099.37801243917, 34055.318893621494, 34172.0, 30209.69790302069, 41364.586354136074, 43338.0, 43973.56026439695, 49139.26291105708, 33747.81882634096, 33923.15192120217, 34065.82967085149, 36347.63652363443, 36900.0, 36619.0, 36720.63279367172, 37019.44470832994, 37139.0, 37717.558276536336, 35977.1005724859, 36053.45919811152, 37143.6285637151, 37187.25625487529, 37200.25599488049, 30480.390392192476, 32598.674013259562, 34861.26720111906, 35026.299474009866, 35213.0, 25708.742912570633, 25960.22119336423, 26212.475750485264, 26276.0, 26410.471790564465], "value": 17669.37982403318, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.607731495355, 364.6699736738252, 371.67292782351524, 377.3283555271614, 383.55890725665483, 15444.61388465289, 16120.677586448264, 16536.173191340444, 16585.83414165861, 16632.25154868031, 15556.222188890539, 15726.528204153865, 16034.278457469441, 16073.83926160738, 16176.919115404437, 4975.751212439382, 5045.545900868921, 5054.1913293872985, 5073.0, 5073.644844860862, 5019.29729837823, 5068.923966140493, 5349.625526213166, 5376.1398176291805, 5415.079436495786, 5378.273933019032, 5380.461953804608, 5391.379991300992, 5391.541718953894, 5393.2719082923695, 5307.248607959384, 5899.292084949812, 6003.0695242237525, 6035.094735789623, 6053.757849686007, 5194.870128246786, 5382.165764306539, 5386.138217885159, 5387.164989426645, 5388.110961691316, 12191.817122743167, 12532.561360352343, 12536.874631253753, 12537.43581538833, 12598.37008149598, 2123.2250228666426, 2194.5391467791783, 2195.879226642534, 2196.3959911024435, 2238.552289542098, 8461.196186362286, 8850.291976641918, 8885.466871987637, 9217.907820921839, 9296.8605470918, 1616.8706503479812, 1656.7349224124082, 1677.3123019562042, 1687.4094067076426, 1708.1288542843115, 52119.47880521184, 62916.685416573084, 66084.40029396633, 66911.0, 68174.65912670405, 9798.510074496247, 9940.80118397628, 9998.200143988464, 10052.145567626834, 10298.845517317246, 13924.721505569833, 13942.442302307794, 13959.720805583831, 13966.930165349107, 13996.30018499071, 7072.8939065914055, 7294.598797066106, 7327.780166595011, 7444.739434119888, 7450.329470347587, 6871.415929646036, 6910.861782764317, 7072.8939065914055, 7130.5365151265005, 7136.892946605805, 4167.437395951569, 4242.6817988650355, 4283.32950149442, 4401.911961760747, 4443.733375997451, 1519.9392024318904, 1573.5751347136209, 1759.8416142547233, 1839.9080045997646, 1893.9242430302634, 788.9289963903276, 819.9344052475907, 822.6709316273539, 823.5017814222429, 829.0797215091244, 16057.116858572657, 16106.677866442606, 16406.09766462832, 16543.503694888932, 16612.750808737877, 810.2464707821664, 817.207308910356, 817.4604760857806, 821.9013718353716, 840.4915026408935, 9026.368154229272, 9314.627414903329, 9389.154976052188, 9444.763880902896, 9576.712698619052, 318937.0, 324047.0, 326874.73125268443, 326925.0, 329066.0, 70376.0, 70421.2957870425, 70603.29396705967, 71870.28129718741, 72626.273737263, 897.9416337937882, 908.6138391183721, 936.4615346175976, 937.1471960515897, 944.1313991128178, 14234.857651423767, 14251.429942802586, 14282.143071415749, 14353.138811671333, 14936.17851018161, 29499.262518437212, 32471.512927306583, 32628.83685581557, 34025.65974340225, 34971.0, 43097.78451107725, 43118.35322470228, 43416.13167736564, 45716.77141614271, 46122.76938615285, 6937.236903940461, 7007.089078419773, 7081.610511421921, 7287.890681639886, 7365.189829118685, 9890.0, 10937.781244375226, 11200.719982000515, 11483.712907177387, 12381.442835072263, 9336.533173341442, 9772.657956971472, 9846.212303015885, 10076.848847267443, 10371.807242166946, 9726.270529710442, 10211.7447063824, 10258.487075646337, 10349.689509314732, 10442.947785261025, 10253.33353332031, 10396.337970641976, 10879.702517505699, 10944.288621239593, 11286.322820630789, 7862.1351651319455, 7889.802754931173, 7971.242731940456, 8033.156518565466, 8081.434299598972, 7608.429367797326, 9967.302288839712, 10657.786844263226, 10718.517666704862, 10997.395143267197], "value": 7878.007958265582, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1303.713183099718, 1304.282644545501, 1328.1234385305695, 1331.6537700197946, 1345.1794405412697, 6451.677416129198, 6705.362990515905, 6901.0, 7355.779326620203, 7391.926080739191, 6884.414824739903, 7135.250798666143, 7154.069970903788, 7299.744508942188, 7343.302386273309, 2057.4856285928518, 2103.5582527669208, 2104.284543255293, 2108.947276318089, 2121.087932189158, 1863.7856646485625, 1929.0, 2080.5318803269297, 2126.5321629241544, 2136.134865379524, 2054.044869135859, 2113.5138918048856, 2123.893805309728, 2124.989375053123, 2137.6045431595203, 2053.938381848547, 2083.749950005994, 2103.7791031941656, 2104.515961328903, 2109.5253567947243, 2139.3474990128007, 2144.1637761273114, 2148.215901196068, 2170.0560256288563, 2176.4885251965775, 7245.855082898365, 7280.635968201569, 7341.559506429631, 7364.263573642646, 7386.630668466609, 4329.328954012133, 4457.2199865023495, 4462.732236065846, 4464.531224221475, 4465.553444655508, 2836.3334616365137, 2929.2383980165096, 2980.4784162771643, 2991.521356582958, 3009.0, 1161.628278950736, 1164.3188734590274, 1176.5764324843049, 1196.1865931166803, 1198.4666823263651, 9650.372725772802, 9677.951610241975, 9716.514174291256, 9720.75698107546, 9766.706998790047, 3742.8689995850013, 3943.921121577552, 3944.447777311179, 3958.247932892741, 4029.838806447768, 7695.191629966953, 7771.72798952034, 7775.494592851446, 7776.65005074773, 7801.29788319054, 4391.8462853799965, 4433.977830110829, 4436.44544431946, 4436.489803672554, 4438.5561443856, 3886.3004659161056, 4114.362273847559, 4116.670666346685, 4117.114820313647, 4120.093579412584, 3217.131374528911, 3235.692609202122, 3316.369889720945, 3326.334733053399, 3328.8502017409273, 954.5465903695689, 995.3928103856641, 999.2805180270194, 999.5002498750624, 1017.2675673515057, 610.9725062372204, 611.0559186057552, 630.136712703599, 634.0489266100849, 638.1098367776925, 4515.209838278288, 4557.977210113994, 4601.28680054592, 4625.976870115695, 4723.669343145947, 753.333300029467, 786.4141214795014, 825.4510750351069, 828.4780588229377, 828.5028982610506, 2979.9702002979693, 3017.0, 3047.268655522703, 3081.2913030003224, 3105.8414401990854, 8219.917800822035, 8577.313814894793, 8602.78493037679, 8604.956975215084, 8654.48073115615, 8535.786605334917, 8575.656973721107, 8581.485110893367, 8603.784905377415, 8619.784505387415, 6811.897821532681, 6829.2487826338065, 6906.37842594169, 6906.689198986058, 6917.792466226021, 4620.953790462052, 4666.929996049994, 4713.222318317439, 4798.520147985313, 4844.757762111951, 7775.494592851446, 7799.64901579419, 7829.530228186327, 7881.211878812073, 7886.842263154819, 8035.276825085771, 8039.799005024687, 8077.394195435249, 8153.714619988272, 8172.550509722022, 5703.885922281448, 5767.5385969121535, 5772.278465191851, 5775.306963164448, 5778.219940308005, 6705.798826035227, 6715.966420167868, 6721.798346049626, 6788.796336109925, 6885.827854303682, 5231.1107111790325, 5933.614315069507, 6027.487663548647, 6056.152138700498, 6198.597091189239, 5850.882982340415, 5956.672383018976, 6011.87976240464, 6013.639181649115, 6089.269287685506, 5486.204500347348, 5551.666899986014, 5597.524210442158, 5599.80400685974, 5611.410801865909, 4023.1953609277675, 4033.959660403476, 4095.262852686545, 4113.8971525712095, 4138.710290279772, 3986.4418981343215, 4073.9185216294914, 4084.509858816961, 4127.09203975125, 4215.494140703013], "value": 3795.9000717672907, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.53992340378784, 344.9375922159748, 355.142331269983, 355.4842606620523, 357.6030606027311, 5007.524285192905, 5030.673006254591, 5038.370203724535, 5048.999999999995, 5049.19212925931, 4930.728809915462, 4971.602271818255, 4978.581104385251, 4983.501649835024, 5006.54941055305, 3775.1505911169907, 3856.82644281008, 3883.1262965832743, 3940.7832569208826, 3976.310068221009, 3965.345717956534, 3972.3842804365368, 3981.800909954505, 3982.9601703983026, 3996.5403978542404, 3948.9210215795665, 3954.3673012318036, 3959.663428608572, 3967.2462232175794, 3989.301872172373, 3910.061585219555, 3915.6084391560903, 3929.9214015719813, 3983.4423180754716, 4001.4397984282077, 3527.1005893496995, 3555.1467647764607, 3556.1465248340214, 3574.160072382988, 3579.158897659048, 4770.379850619432, 4818.325434439182, 4847.854564363075, 4848.0, 4882.975585122087, 4533.365328853963, 4543.5002149763495, 4584.426946631671, 4589.678722489427, 4595.609373203249, 5044.899102017976, 5202.115640341151, 5219.773353262019, 5246.37043554772, 5265.262863199156, 3665.102049997741, 3666.5233519642543, 3667.7799332040163, 3675.8713445029293, 3676.558812942464, 5496.31296087989, 5507.568032311628, 5510.897820435929, 5514.944850551483, 5564.33228012641, 3660.1581636223286, 3751.531058617673, 3761.5674197467642, 3770.7368031709684, 3785.4511095891494, 3794.886153415402, 3816.2176753765966, 3819.618038196158, 3825.5026846509777, 3846.0, 4387.670924680598, 4414.543200743747, 4417.624501917308, 4419.977900110479, 4420.668449866209, 5181.3005244291535, 5239.947600523946, 5308.814191503279, 5313.3964357464565, 5341.278927344759, 1282.8332316798756, 1288.4910460368103, 1289.7678417884683, 1290.6386211860695, 1297.1179597873536, 821.650798410673, 858.4420126917554, 864.567716141929, 865.476386785998, 866.9899567004495, 4572.474165470947, 4654.348391225231, 4677.391939047902, 4679.321498382716, 4680.297955306663, 5474.260974768435, 5482.753276102585, 5485.369182543979, 5508.622844288928, 5527.2538207341495, 4606.539346065367, 4691.835785747482, 4695.38009386761, 4712.764361781898, 4725.5510726480925, 2817.21118086936, 2823.5905793659804, 2836.886519542954, 2838.205302515299, 2852.415254872746, 4881.8535443936735, 4911.901761964741, 4912.70523768575, 4932.704037757746, 4981.71207003376, 5206.698325418645, 5380.381256155514, 5464.398916119224, 5474.835754927359, 5503.752331145108, 4831.057943700945, 4833.854984350475, 4845.643219898434, 4864.124457597666, 4878.56092951636, 5265.210218467108, 5270.73646317675, 5296.451377577109, 5298.039725301663, 5300.5494532963585, 4938.7406211415855, 5070.949290507047, 5111.798727299008, 5229.738513074407, 5230.790768369223, 4734.1478533862855, 4760.738159401266, 4761.523847615349, 4763.546773411626, 4791.323036937044, 3598.172420343283, 3608.1160115770754, 3627.673509384168, 3637.9818100909324, 3668.192997540537, 5134.7946082156295, 5261.0, 5273.5781137509675, 5274.3670759509105, 5422.186671999231, 5209.869753256047, 5218.373795144607, 5222.451642577475, 5248.60635452335, 5275.788968441373, 5255.553277971415, 5269.473052694853, 5275.525202731773, 5287.603429742708, 5297.682139071668, 4946.3817022872145, 5103.157978933435, 5145.485451454824, 5181.326427564392, 5217.165253559412, 5051.87370315745, 5166.3800343958965, 5180.404253510744, 5275.340582427197, 5275.472452754694, 5150.819721309736, 5184.429712731521, 5186.636935414635, 5216.478352164753, 5220.1647736362], "value": 3937.0489418874995, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.3122352753137, 669.9296573859741, 717.1895757793692, 727.7380143148467, 762.5805806806256, 12964.222146671207, 13863.514776982782, 13872.16766993981, 13904.513342033044, 13926.65183370415, 12225.266484010966, 12277.508899644003, 12351.876481235186, 12457.0, 12475.563355282567, 669.3641041011035, 691.3777600159846, 777.3004296133469, 797.7885860247039, 817.9141190175035, 812.2121542104179, 827.0778082438069, 830.4311546590575, 833.6998680475054, 834.9123342049088, 874.64576846377, 883.0286684646869, 885.2431171348501, 885.9335549833756, 886.2068448738376, 879.0550158579489, 880.0495464897895, 880.1110878013188, 880.3617377401397, 880.5685214244974, 637.7449020391834, 639.46285120499, 640.2796853539768, 643.572024603638, 644.4168027934727, 12816.102872798907, 12873.0, 13038.478460861554, 13045.478180872753, 13427.060105792598, 11817.468213930393, 11845.94077029618, 12803.935980320039, 13511.459541618324, 13600.31998400076, 11655.0, 11727.53089876404, 11735.178537502377, 11811.586594469238, 11837.881621183764, 4099.1801639671885, 4099.9180016399505, 4117.485314335708, 4118.6499147572495, 4131.8224306072625, 61630.691846540925, 62705.0, 63480.682596586725, 64065.67967160181, 64175.0, 8639.222469977733, 8735.912640873637, 8742.912570874338, 8784.341174411817, 8975.37172397939, 15833.683326333408, 15852.207389630472, 15856.52430427089, 15930.123843188505, 15935.521934341989, 6320.336364681642, 6322.525810564226, 6345.36546345371, 6519.576227545194, 6592.571482853599, 3547.0777597824235, 3568.946465803015, 3611.0680785780005, 3611.1874828163977, 3611.6749492545273, 2902.201894479035, 2933.603963464948, 2941.7646588272887, 2973.2566858285427, 2988.9850550747105, 492.72161228905554, 495.25216922446646, 495.774422637699, 495.90825697246123, 502.06866263082185, 542.9674219546761, 547.6495043172373, 564.3689736661053, 566.742132329789, 588.0444278048171, 6498.707558159894, 7365.447591430664, 7400.925990740131, 7573.166951635316, 8163.38774591908, 519.3326575350657, 534.855588990978, 547.4197350808156, 554.8252300525321, 565.5588640860136, 3333.949990750141, 3365.899023029313, 3379.8648054078053, 3478.6869181773764, 3496.5629296337956, 65477.34522654808, 66041.66979165074, 66309.66845165743, 68914.3108568918, 68997.65501172462, 57149.14276285859, 57260.0, 57940.0, 59435.70282148562, 60214.39785602088, 52597.474025260024, 52612.0, 52797.0, 52981.0, 53035.73482132565, 6522.0, 6574.342565743387, 6727.663616819237, 6733.494987876027, 6886.69009894566, 37037.0, 37520.437193441576, 37940.62059379481, 38136.04659883525, 38473.4228986571, 43676.781616091714, 44121.338179926686, 45496.31755523736, 46011.76994115008, 47432.814179645786, 8590.312774978112, 8671.523066231417, 8800.427972181787, 8815.603297851483, 8889.822203555763, 9369.344145909721, 9395.671151509665, 9445.433274003306, 9519.762005949906, 9558.904410955802, 9251.76870578241, 9297.907020929704, 9447.811043779224, 9636.903630963601, 9644.517774111408, 8670.26302764272, 8733.214010739064, 8771.122887711177, 8835.116488351114, 8868.86696699537, 8649.481031138152, 8667.609957551798, 8671.653133874826, 8672.176143266379, 8675.089115642766, 8171.264586187034, 8253.09215986253, 8259.628316725642, 8345.95827020861, 8439.578021098798, 7837.686492540234, 7915.604219789103, 8189.22202390772, 8238.176182381714, 8262.380321475794], "value": 5924.649315805447, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1590.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 117.0, 114.0, 115.0, 114.0, 114.0, 116.0, 114.0, 115.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 128.0, 129.0, 177.0, 133.0, 133.0, 133.0, 128.0, 146.0, 127.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 166.0, 176.0, 165.0, 166.0, 163.0, 162.0, 164.0, 163.0], "value": 165.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 80.0, 110.0, 78.0, 79.0, 78.0, 78.0, 91.0, 79.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 109.0, 86.0, 85.0, 85.0, 103.0, 86.0, 86.0, 113.0], "value": 92.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 119.0, 119.0, 119.0, 122.0, 119.0, 119.0, 119.0, 118.0], "value": 119.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 150.0, 151.0, 151.0, 151.0, 151.0, 151.0, 150.0, 151.0, 151.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 97.0, 96.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 69.0, 70.0, 72.0, 71.0, 72.0, 72.0, 71.0, 71.0], "value": 70.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 68.0, 68.0, 69.0, 68.0, 69.0, 68.0, 68.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 91.0, 87.0, 92.0, 93.0, 85.0, 92.0, 101.0, 92.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 150.0, 141.0, 150.0, 136.0, 135.0, 155.0, 198.0, 148.0], "value": 148.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 177.0, 183.0, 180.0, 180.0, 183.0, 183.0, 188.0, 185.0, 177.0], "value": 181.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 70.0, 73.0, 73.0, 72.0, 69.0, 69.0, 69.0, 69.0], "value": 70.5, "unit": "score"}]}]} 11:47:34 INFO - Return code: 0 11:47:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:47:35 ERROR - # TBPL SUCCESS # 11:47:35 INFO - Running post-action listener: _resource_record_post_action 11:47:35 INFO - [mozharness: 2016-12-30 19:47:35.011742Z] Finished run-tests step (success) 11:47:35 INFO - Running post-run listener: _resource_record_post_run 11:47:35 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 406851584; Read time: 16; Write time: 285392 11:47:35 INFO - TinderboxPrint: CPU usage
13.5% 11:47:35 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 11:47:35 INFO - TinderboxPrint: I/O write bytes / time
406,851,584 / 285,392 11:47:35 INFO - TinderboxPrint: CPU idle
8,289.2 (86.5%) 11:47:35 INFO - TinderboxPrint: CPU user
1,233.5 (12.9%) 11:47:35 INFO - TinderboxPrint: Swap in / out
0 / 0 11:47:35 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110620672; Read time: 0; Write time: 63348 11:47:35 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 296230912; Read time: 0; Write time: 222044 11:47:35 INFO - Running post-run listener: _upload_blobber_files 11:47:35 INFO - Blob upload gear active. 11:47:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:47:35 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 11:47:35 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:47:35 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:47:36 INFO - (blobuploader) - INFO - Open directory for files ... 11:47:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:47:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:47:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:36 INFO - (blobuploader) - INFO - Done attempting. 11:47:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:47:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:47:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:37 INFO - (blobuploader) - INFO - Done attempting. 11:47:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:47:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:47:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:38 INFO - (blobuploader) - INFO - Done attempting. 11:47:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:47:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:47:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:41 INFO - (blobuploader) - INFO - Done attempting. 11:47:41 INFO - (blobuploader) - INFO - Iteration through files over. 11:47:41 INFO - Return code: 0 11:47:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:47:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:47:41 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cde19d3d1426012b755ed15128121b956a8090729198416095d290f7a19f097969ff08a8bab1fe7b12249882b8310480f6544e2c9d7145eba2b869b24b386068", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/795dd134f9c648b53387e71a5bde44f5fd34911f79f3d0ed44c826cf1a7e1f665ed386ca11cdd0ff98ba53b0e9919d5074bd54726c4934a7eda2fd64e1ba52f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f757d59c0003adcd7b497156a5bf98cbf887678eb14e49b5f2f92e04004f3834fe4a2f99daeaf2fa11a8977a9c275659a8c178296ac1e17191549ec9c1ecfb76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b2746424abe916f97f6e27aa36d8c44a27ce4cd0cded387bca33db16132c1d01cbf7ed27fe4b97e113c5b6b2b76552116e8ae6ef49dfd0cf3e71b5e8bd2a663f"} 11:47:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:47:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:47:41 INFO - Contents: 11:47:41 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cde19d3d1426012b755ed15128121b956a8090729198416095d290f7a19f097969ff08a8bab1fe7b12249882b8310480f6544e2c9d7145eba2b869b24b386068", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/795dd134f9c648b53387e71a5bde44f5fd34911f79f3d0ed44c826cf1a7e1f665ed386ca11cdd0ff98ba53b0e9919d5074bd54726c4934a7eda2fd64e1ba52f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f757d59c0003adcd7b497156a5bf98cbf887678eb14e49b5f2f92e04004f3834fe4a2f99daeaf2fa11a8977a9c275659a8c178296ac1e17191549ec9c1ecfb76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b2746424abe916f97f6e27aa36d8c44a27ce4cd0cded387bca33db16132c1d01cbf7ed27fe4b97e113c5b6b2b76552116e8ae6ef49dfd0cf3e71b5e8bd2a663f"} 11:47:41 INFO - Running post-run listener: copy_logs_to_upload_dir 11:47:41 INFO - Copying logs to upload dir... 11:47:41 INFO - mkdir: /builds/slave/test/build/upload/logs 11:47:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.582150 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-30 11:47:41.790642) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 11:47:41.794037) ========= 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=14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cde19d3d1426012b755ed15128121b956a8090729198416095d290f7a19f097969ff08a8bab1fe7b12249882b8310480f6544e2c9d7145eba2b869b24b386068", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/795dd134f9c648b53387e71a5bde44f5fd34911f79f3d0ed44c826cf1a7e1f665ed386ca11cdd0ff98ba53b0e9919d5074bd54726c4934a7eda2fd64e1ba52f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f757d59c0003adcd7b497156a5bf98cbf887678eb14e49b5f2f92e04004f3834fe4a2f99daeaf2fa11a8977a9c275659a8c178296ac1e17191549ec9c1ecfb76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b2746424abe916f97f6e27aa36d8c44a27ce4cd0cded387bca33db16132c1d01cbf7ed27fe4b97e113c5b6b2b76552116e8ae6ef49dfd0cf3e71b5e8bd2a663f"} build_url:https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011857 build_url: 'https://queue.taskcluster.net/v1/task/bQKw5tDeRKWWVTgCX90Rog/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cde19d3d1426012b755ed15128121b956a8090729198416095d290f7a19f097969ff08a8bab1fe7b12249882b8310480f6544e2c9d7145eba2b869b24b386068", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/795dd134f9c648b53387e71a5bde44f5fd34911f79f3d0ed44c826cf1a7e1f665ed386ca11cdd0ff98ba53b0e9919d5074bd54726c4934a7eda2fd64e1ba52f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f757d59c0003adcd7b497156a5bf98cbf887678eb14e49b5f2f92e04004f3834fe4a2f99daeaf2fa11a8977a9c275659a8c178296ac1e17191549ec9c1ecfb76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b2746424abe916f97f6e27aa36d8c44a27ce4cd0cded387bca33db16132c1d01cbf7ed27fe4b97e113c5b6b2b76552116e8ae6ef49dfd0cf3e71b5e8bd2a663f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 11:47:41.823120) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 11:47:41.823466) ========= 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=14ef55db36f7623abb31b9cf000001d5-1483125623.393613-744478237 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004505 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 11:47:41.908624) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 11:47:41.908966) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 11:47:41.909272) ========= ========= Total master_lag: 0.18 =========