builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-028 starttime: 1479431081.13 results: success (0) buildid: 20161117154028 builduid: fbf4a9b9b2724f529d5b1c357f97292a revision: 73f79bd8f8a38da87fa7b8acc3f81adfb49f3889 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 17:04:41.126036) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 17:04:41.126458) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 17:04:41.126755) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 17:04:41.137830) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 17:04:41.138087) ========= 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=836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-17 17:04:41-- 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% 223M=0s 2016-11-17 17:04:41 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.157151 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 17:04:41.306725) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 17:04:41.307099) ========= 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=836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019029 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 17:04:41.356249) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-17 17:04:41.356606) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 73f79bd8f8a38da87fa7b8acc3f81adfb49f3889 --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 73f79bd8f8a38da87fa7b8acc3f81adfb49f3889 --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=836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-17 17:04:42,013 truncating revision to first 12 chars 2016-11-17 17:04:42,013 Setting DEBUG logging. 2016-11-17 17:04:42,013 attempt 1/10 2016-11-17 17:04:42,014 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/73f79bd8f8a3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-17 17:04:42,614 unpacking tar archive at: graphics-73f79bd8f8a3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.201100 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-17 17:04:42.571053) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 17:04:42.571361) ========= script_repo_revision: 73f79bd8f8a38da87fa7b8acc3f81adfb49f3889 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 17:04:42.571753) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 17:04:42.572017) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 17:04:42.582668) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-11-17 17:04:42.582972) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:04:43 INFO - MultiFileLogger online at 20161117 17:04:43 in /builds/slave/test 17:04:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 17:04:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:04:43 INFO - {'append_to_log': False, 17:04:43 INFO - 'base_work_dir': '/builds/slave/test', 17:04:43 INFO - 'blob_upload_branch': 'Graphics', 17:04:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:04:43 INFO - 'branch': 'Graphics', 17:04:43 INFO - 'buildbot_json_path': 'buildprops.json', 17:04:43 INFO - 'config_files': ('talos/linux_config.py',), 17:04:43 INFO - 'default_actions': ('clobber', 17:04:43 INFO - 'read-buildbot-config', 17:04:43 INFO - 'download-and-extract', 17:04:43 INFO - 'populate-webroot', 17:04:43 INFO - 'create-virtualenv', 17:04:43 INFO - 'install', 17:04:43 INFO - 'run-tests'), 17:04:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:04:43 INFO - 'download_minidump_stackwalk': True, 17:04:43 INFO - 'download_symbols': 'ondemand', 17:04:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:04:43 INFO - 'tooltool.py': '/tools/tooltool.py', 17:04:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:04:43 INFO - '/tools/misc-python/virtualenv.py')}, 17:04:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:04:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:04:43 INFO - 'installer_path': 'installer.exe', 17:04:43 INFO - 'log_level': 'info', 17:04:43 INFO - 'log_name': 'talos', 17:04:43 INFO - 'log_to_console': True, 17:04:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:04:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:04:43 INFO - 'opt_config_files': (), 17:04:43 INFO - 'pip_index': False, 17:04:43 INFO - 'sps_profile': False, 17:04:43 INFO - 'sps_profile_interval': 0, 17:04:43 INFO - 'suite': 'dromaeojs', 17:04:43 INFO - 'system_bits': '32', 17:04:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:04:43 INFO - 'title': 'talos-linux64-ix-028', 17:04:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:04:43 INFO - 'use_talos_json': True, 17:04:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:04:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:04:43 INFO - 'work_dir': 'build'} 17:04:43 INFO - [mozharness: 2016-11-18 01:04:43.338070Z] Running clobber step. 17:04:43 INFO - Running pre-action listener: _resource_record_pre_action 17:04:43 INFO - Running main action method: clobber 17:04:43 INFO - rmtree: /builds/slave/test/build 17:04:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:04:43 INFO - Running post-action listener: _resource_record_post_action 17:04:43 INFO - [mozharness: 2016-11-18 01:04:43.797118Z] Finished clobber step (success) 17:04:43 INFO - [mozharness: 2016-11-18 01:04:43.797217Z] Running read-buildbot-config step. 17:04:43 INFO - Running pre-action listener: _resource_record_pre_action 17:04:43 INFO - Running main action method: read_buildbot_config 17:04:43 INFO - Using buildbot properties: 17:04:43 INFO - { 17:04:43 INFO - "project": "", 17:04:43 INFO - "product": "firefox", 17:04:43 INFO - "script_repo_revision": "default", 17:04:43 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 17:04:43 INFO - "repository": "", 17:04:43 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 17:04:43 INFO - "buildid": "20161117154028", 17:04:43 INFO - "pgo_build": "False", 17:04:43 INFO - "basedir": "/builds/slave/test", 17:04:43 INFO - "buildnumber": 6, 17:04:43 INFO - "slavename": "talos-linux64-ix-028", 17:04:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:04:43 INFO - "platform": "ubuntu64_hw", 17:04:43 INFO - "scheduler": "tests-graphics-linux64-talos", 17:04:43 INFO - "branch": "graphics", 17:04:43 INFO - "repo_path": "projects/graphics", 17:04:43 INFO - "revision": "73f79bd8f8a38da87fa7b8acc3f81adfb49f3889", 17:04:43 INFO - "stage_platform": "linux64", 17:04:43 INFO - "builduid": "fbf4a9b9b2724f529d5b1c357f97292a", 17:04:43 INFO - "slavebuilddir": "test" 17:04:43 INFO - } 17:04:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 17:04:43 INFO - Running post-action listener: _resource_record_post_action 17:04:43 INFO - [mozharness: 2016-11-18 01:04:43.799635Z] Finished read-buildbot-config step (success) 17:04:43 INFO - [mozharness: 2016-11-18 01:04:43.799722Z] Running download-and-extract step. 17:04:43 INFO - Running pre-action listener: _resource_record_pre_action 17:04:43 INFO - Running main action method: download_and_extract 17:04:43 INFO - mkdir: /builds/slave/test/build/tests 17:04:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:43 INFO - trying https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 17:04:43 INFO - Downloading https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/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 17:04:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/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 17:04:44 INFO - Downloaded 1361 bytes. 17:04:44 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 17:04:44 INFO - Using the following test package requirements: 17:04:44 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 17:04:44 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:04:44 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:04:44 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:04:44 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:04:44 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:04:44 INFO - u'jsshell-linux-x86_64.zip'], 17:04:44 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:04:44 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:04:44 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 17:04:44 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:04:44 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:04:44 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:04:44 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:04:44 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:04:44 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:04:44 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:04:44 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:04:44 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:04:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 17:04:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:04:44 INFO - Fetch https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 17:04:45 INFO - Http code: 200 17:04:45 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 17:04:45 INFO - x-amz-cf-id: rXUoFSHA23ODD26lf5guCrHkuxoWMM1rXWtVvjdsss0nTXJezaEmOQ== 17:04:45 INFO - x-amz-version-id: wyJHEZinPSZSPAcwyb5djHZBdw1huSxk 17:04:45 INFO - Expected file size: 20361480 17:04:45 INFO - Obtained file size: 20361480 17:04:49 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 17:04:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 17:04:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:04:49 INFO - Fetch https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 17:04:49 INFO - Http code: 200 17:04:49 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 17:04:49 INFO - x-amz-cf-id: dTEWflTrwL4IpUvJjnNt8tXYmP3saNBnhahWAbA0SnonjK2qymfQCQ== 17:04:49 INFO - x-amz-version-id: wyJHEZinPSZSPAcwyb5djHZBdw1huSxk 17:04:50 INFO - Expected file size: 20361480 17:04:50 INFO - Obtained file size: 20361480 17:04:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 17:04:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:04:54 INFO - Fetch https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 17:04:54 INFO - Http code: 200 17:04:54 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 17:04:54 INFO - x-amz-cf-id: XnNkK_i4uVDzfPQkAzux9sSNnTEiDGuFBuZfo124JEs3aOldQenuLQ== 17:04:54 INFO - x-amz-version-id: yeIdue8XRd.PDD5lHv6tV9fx35pH72oW 17:04:55 INFO - Expected file size: 14409392 17:04:55 INFO - Obtained file size: 14409392 17:04:55 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')]} 17:04:55 INFO - trying https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 17:04:55 INFO - Downloading https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/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 17:04:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/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 17:04:56 INFO - Downloaded 55729478 bytes. 17:04:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 17:04:56 INFO - mkdir: /builds/slave/test/properties 17:04:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:04:56 INFO - Writing to file /builds/slave/test/properties/build_url 17:04:56 INFO - Contents: 17:04:56 INFO - build_url:https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 17:04:57 INFO - Running post-action listener: _resource_record_post_action 17:04:57 INFO - Running post-action listener: set_extra_try_arguments 17:04:57 INFO - [mozharness: 2016-11-18 01:04:57.077636Z] Finished download-and-extract step (success) 17:04:57 INFO - [mozharness: 2016-11-18 01:04:57.077862Z] Running populate-webroot step. 17:04:57 INFO - Running pre-action listener: _resource_record_pre_action 17:04:57 INFO - Running main action method: populate_webroot 17:04:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:04:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:04:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:04:57 INFO - '--tppagecycles', 17:04:57 INFO - '7'], 17:04:57 INFO - 'tests': ['tsvgm']}}, 17:04:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:04:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:04:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:04:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:04:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:04:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:04:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:04:57 INFO - 'talos_options': ['--disable-e10s'], 17:04:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:04:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:04:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:04:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:04:57 INFO - 'talos_options': ['--disable-e10s'], 17:04:57 INFO - 'tests': ['damp', 'tps']}, 17:04:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:04:57 INFO - 'tests': ['damp', 'tps']}, 17:04:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:04:57 INFO - 'tests': ['dromaeo_dom']}, 17:04:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:04:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:04:57 INFO - 'tests': ['basic_compositor_video']}, 17:04:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:04:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:04:57 INFO - 'tests': ['a11yr', 17:04:57 INFO - 'ts_paint', 17:04:57 INFO - 'tpaint', 17:04:57 INFO - 'sessionrestore', 17:04:57 INFO - 'sessionrestore_no_auto_restore', 17:04:57 INFO - 'tabpaint']}, 17:04:57 INFO - 'other-e10s': {'tests': ['a11yr', 17:04:57 INFO - 'ts_paint', 17:04:57 INFO - 'tpaint', 17:04:57 INFO - 'sessionrestore', 17:04:57 INFO - 'sessionrestore_no_auto_restore', 17:04:57 INFO - 'tabpaint']}, 17:04:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:04:57 INFO - 'ts_paint', 17:04:57 INFO - 'tpaint', 17:04:57 INFO - 'sessionrestore', 17:04:57 INFO - 'sessionrestore_no_auto_restore', 17:04:57 INFO - 'tabpaint']}, 17:04:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:04:57 INFO - 'ts_paint', 17:04:57 INFO - 'tpaint', 17:04:57 INFO - 'sessionrestore', 17:04:57 INFO - 'sessionrestore_no_auto_restore', 17:04:57 INFO - 'tabpaint']}, 17:04:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:04:57 INFO - 'tests': ['a11yr', 17:04:57 INFO - 'ts_paint', 17:04:57 INFO - 'tpaint', 17:04:57 INFO - 'sessionrestore', 17:04:57 INFO - 'sessionrestore_no_auto_restore', 17:04:57 INFO - 'tabpaint']}, 17:04:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:04:57 INFO - 'tests': ['a11yr', 17:04:57 INFO - 'ts_paint', 17:04:57 INFO - 'tpaint', 17:04:57 INFO - 'sessionrestore', 17:04:57 INFO - 'sessionrestore_no_auto_restore', 17:04:57 INFO - 'tabpaint']}, 17:04:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:04:57 INFO - 'tests': ['tsvgx', 17:04:57 INFO - 'tsvgr_opacity', 17:04:57 INFO - 'tart', 17:04:57 INFO - 'tscrollx', 17:04:57 INFO - 'cart']}, 17:04:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:04:57 INFO - 'tsvgr_opacity', 17:04:57 INFO - 'tart', 17:04:57 INFO - 'tscrollx', 17:04:57 INFO - 'cart']}, 17:04:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:04:57 INFO - 'talos_options': ['--disable-e10s'], 17:04:57 INFO - 'tests': ['tp5o']}, 17:04:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:04:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:04:57 INFO - 'talos_options': ['--disable-e10s', 17:04:57 INFO - '--xperf_path', 17:04:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:04:57 INFO - 'tests': ['tp5n']}, 17:04:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:04:57 INFO - 'talos_options': ['--xperf_path', 17:04:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:04:57 INFO - 'tests': ['tp5n']}}, 17:04:57 INFO - 'talos.zip': {'path': '', 17:04:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:04:57 INFO - Running post-action listener: _resource_record_post_action 17:04:57 INFO - [mozharness: 2016-11-18 01:04:57.095957Z] Finished populate-webroot step (success) 17:04:57 INFO - [mozharness: 2016-11-18 01:04:57.096044Z] Running create-virtualenv step. 17:04:57 INFO - Running pre-action listener: _resource_record_pre_action 17:04:57 INFO - Running main action method: create_virtualenv 17:04:57 INFO - Creating virtualenv /builds/slave/test/build/venv 17:04:57 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 17:04:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:04:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:04:57 INFO - Using real prefix '/usr' 17:04:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:04:57 INFO - Installing distribute.............................................................................................................................................................................................done. 17:04:59 INFO - Installing pip.................done. 17:04:59 INFO - Return code: 0 17:04:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:04:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:04:59 INFO - Reading from file tmpfile_stdout 17:04:59 INFO - Output received: 17:04:59 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) 17:04:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:04:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2e50a7e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f20b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bc130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214afc0>, '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 0x214bec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214c350>, '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': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', '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 17:04:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:04:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:04:59 INFO - Using env: {'DISPLAY': ':0', 17:04:59 INFO - 'HOME': '/home/cltbld', 17:04:59 INFO - 'LANG': 'en_US.UTF-8', 17:04:59 INFO - 'LANGUAGE': 'en_US:en', 17:04:59 INFO - 'LOGNAME': 'cltbld', 17:04:59 INFO - 'MAIL': '/var/mail/cltbld', 17:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:59 INFO - 'MOZ_NO_REMOTE': '1', 17:04:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:04:59 INFO - 'NO_EM_RESTART': '1', 17:04:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:59 INFO - 'PWD': '/builds/slave/test', 17:04:59 INFO - 'SHELL': '/bin/bash', 17:04:59 INFO - 'SHLVL': '1', 17:04:59 INFO - 'TERM': 'linux', 17:04:59 INFO - 'TMOUT': '86400', 17:04:59 INFO - 'USER': 'cltbld', 17:04:59 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', 17:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:59 INFO - '_': '/tools/buildbot/bin/python'} 17:05:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:00 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 17:05:00 INFO - Cleaning up... 17:05:00 INFO - Return code: 0 17:05:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:05:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:00 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 0x7fd2e50a7e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f20b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bc130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214afc0>, '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 0x214bec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214c350>, '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': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', '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 17:05:00 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 17:05:00 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 17:05:00 INFO - Using env: {'DISPLAY': ':0', 17:05:00 INFO - 'HOME': '/home/cltbld', 17:05:00 INFO - 'LANG': 'en_US.UTF-8', 17:05:00 INFO - 'LANGUAGE': 'en_US:en', 17:05:00 INFO - 'LOGNAME': 'cltbld', 17:05:00 INFO - 'MAIL': '/var/mail/cltbld', 17:05:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:00 INFO - 'MOZ_NO_REMOTE': '1', 17:05:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:05:00 INFO - 'NO_EM_RESTART': '1', 17:05:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:05:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:00 INFO - 'PWD': '/builds/slave/test', 17:05:00 INFO - 'SHELL': '/bin/bash', 17:05:00 INFO - 'SHLVL': '1', 17:05:00 INFO - 'TERM': 'linux', 17:05:00 INFO - 'TMOUT': '86400', 17:05:00 INFO - 'USER': 'cltbld', 17:05:00 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', 17:05:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:00 INFO - '_': '/tools/buildbot/bin/python'} 17:05:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:00 INFO - Downloading/unpacking psutil>=3.1.1 17:05:00 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 17:05:00 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 17:05:00 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 17:05:00 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 17:05:00 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 17:05:00 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 17:05:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:05:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:05:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:05:02 INFO - Installing collected packages: psutil 17:05:02 INFO - Running setup.py install for psutil 17:05:02 INFO - building 'psutil._psutil_linux' extension 17:05:02 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 17:05:02 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 17:05:02 INFO - building 'psutil._psutil_posix' extension 17:05:02 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 17:05:02 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 17:05:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:05:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:05:03 INFO - Successfully installed psutil 17:05:03 INFO - Cleaning up... 17:05:03 INFO - Return code: 0 17:05:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:05:03 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')]} 17:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:03 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')]} 17:05:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:03 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 0x7fd2e50a7e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f20b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bc130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214afc0>, '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 0x214bec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214c350>, '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': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', '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 17:05:03 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 17:05:03 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 17:05:03 INFO - Using env: {'DISPLAY': ':0', 17:05:03 INFO - 'HOME': '/home/cltbld', 17:05:03 INFO - 'LANG': 'en_US.UTF-8', 17:05:03 INFO - 'LANGUAGE': 'en_US:en', 17:05:03 INFO - 'LOGNAME': 'cltbld', 17:05:03 INFO - 'MAIL': '/var/mail/cltbld', 17:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:03 INFO - 'MOZ_NO_REMOTE': '1', 17:05:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:05:03 INFO - 'NO_EM_RESTART': '1', 17:05:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:03 INFO - 'PWD': '/builds/slave/test', 17:05:03 INFO - 'SHELL': '/bin/bash', 17:05:03 INFO - 'SHLVL': '1', 17:05:03 INFO - 'TERM': 'linux', 17:05:03 INFO - 'TMOUT': '86400', 17:05:03 INFO - 'USER': 'cltbld', 17:05:03 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', 17:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:03 INFO - '_': '/tools/buildbot/bin/python'} 17:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:05:03 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 17:05:03 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 17:05:03 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 17:05:03 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 17:05:03 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 17:05:03 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 17:05:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:05:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:05:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:05:05 INFO - Installing collected packages: mozsystemmonitor 17:05:05 INFO - Running setup.py install for mozsystemmonitor 17:05:05 INFO - Successfully installed mozsystemmonitor 17:05:05 INFO - Cleaning up... 17:05:05 INFO - Return code: 0 17:05:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:05:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:05 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 0x7fd2e50a7e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f20b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bc130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214afc0>, '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 0x214bec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214c350>, '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': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', '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 17:05:05 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 17:05:05 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 17:05:05 INFO - Using env: {'DISPLAY': ':0', 17:05:05 INFO - 'HOME': '/home/cltbld', 17:05:05 INFO - 'LANG': 'en_US.UTF-8', 17:05:05 INFO - 'LANGUAGE': 'en_US:en', 17:05:05 INFO - 'LOGNAME': 'cltbld', 17:05:05 INFO - 'MAIL': '/var/mail/cltbld', 17:05:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:05 INFO - 'MOZ_NO_REMOTE': '1', 17:05:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:05:05 INFO - 'NO_EM_RESTART': '1', 17:05:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:05:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:05 INFO - 'PWD': '/builds/slave/test', 17:05:05 INFO - 'SHELL': '/bin/bash', 17:05:05 INFO - 'SHLVL': '1', 17:05:05 INFO - 'TERM': 'linux', 17:05:05 INFO - 'TMOUT': '86400', 17:05:05 INFO - 'USER': 'cltbld', 17:05:05 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', 17:05:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:05 INFO - '_': '/tools/buildbot/bin/python'} 17:05:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:05 INFO - Downloading/unpacking jsonschema==2.5.1 17:05:05 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 17:05:05 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 17:05:05 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 17:05:05 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 17:05:05 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 17:05:05 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 17:05:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:05:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:07 INFO - Downloading functools32-3.2.3-2.tar.gz 17:05:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:05:07 INFO - warning: no files found matching '*.txt' 17:05:07 INFO - no previously-included directories found matching 'build' 17:05:07 INFO - no previously-included directories found matching 'dist' 17:05:07 INFO - no previously-included directories found matching '.git*' 17:05:07 INFO - Installing collected packages: jsonschema, functools32 17:05:08 INFO - Running setup.py install for functools32 17:05:08 INFO - warning: no files found matching '*.txt' 17:05:08 INFO - no previously-included directories found matching 'build' 17:05:08 INFO - no previously-included directories found matching 'dist' 17:05:08 INFO - no previously-included directories found matching '.git*' 17:05:08 INFO - Successfully installed jsonschema functools32 17:05:08 INFO - Cleaning up... 17:05:08 INFO - Return code: 0 17:05:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:08 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 0x7fd2e50a7e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f20b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bc130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214afc0>, '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 0x214bec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214c350>, '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': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', '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 17:05:08 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 17:05:08 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 17:05:08 INFO - Using env: {'DISPLAY': ':0', 17:05:08 INFO - 'HOME': '/home/cltbld', 17:05:08 INFO - 'LANG': 'en_US.UTF-8', 17:05:08 INFO - 'LANGUAGE': 'en_US:en', 17:05:08 INFO - 'LOGNAME': 'cltbld', 17:05:08 INFO - 'MAIL': '/var/mail/cltbld', 17:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:08 INFO - 'MOZ_NO_REMOTE': '1', 17:05:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:05:08 INFO - 'NO_EM_RESTART': '1', 17:05:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:08 INFO - 'PWD': '/builds/slave/test', 17:05:08 INFO - 'SHELL': '/bin/bash', 17:05:08 INFO - 'SHLVL': '1', 17:05:08 INFO - 'TERM': 'linux', 17:05:08 INFO - 'TMOUT': '86400', 17:05:08 INFO - 'USER': 'cltbld', 17:05:08 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', 17:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:08 INFO - '_': '/tools/buildbot/bin/python'} 17:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:05:08 INFO - Cleaning up... 17:05:08 INFO - Return code: 0 17:05:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:08 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 0x7fd2e50a7e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f20b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bc130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214afc0>, '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 0x214bec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214c350>, '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': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', '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 17:05:08 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 17:05:08 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 17:05:08 INFO - Using env: {'DISPLAY': ':0', 17:05:08 INFO - 'HOME': '/home/cltbld', 17:05:08 INFO - 'LANG': 'en_US.UTF-8', 17:05:08 INFO - 'LANGUAGE': 'en_US:en', 17:05:08 INFO - 'LOGNAME': 'cltbld', 17:05:08 INFO - 'MAIL': '/var/mail/cltbld', 17:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:08 INFO - 'MOZ_NO_REMOTE': '1', 17:05:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:05:08 INFO - 'NO_EM_RESTART': '1', 17:05:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:08 INFO - 'PWD': '/builds/slave/test', 17:05:08 INFO - 'SHELL': '/bin/bash', 17:05:08 INFO - 'SHLVL': '1', 17:05:08 INFO - 'TERM': 'linux', 17:05:08 INFO - 'TMOUT': '86400', 17:05:08 INFO - 'USER': 'cltbld', 17:05:08 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', 17:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:08 INFO - '_': '/tools/buildbot/bin/python'} 17:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:08 INFO - Downloading/unpacking blobuploader==1.2.4 17:05:08 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 17:05:08 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 17:05:08 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 17:05:08 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 17:05:08 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 17:05:08 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 17:05:10 INFO - Downloading blobuploader-1.2.4.tar.gz 17:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:05:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:05:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:05:11 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 17:05:11 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 17:05:11 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 17:05:11 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 17:05:11 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 17:05:11 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 17:05:11 INFO - Downloading docopt-0.6.1.tar.gz 17:05:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:05:11 INFO - Installing collected packages: blobuploader, requests, docopt 17:05:11 INFO - Running setup.py install for blobuploader 17:05:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:05:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:05:11 INFO - Running setup.py install for requests 17:05:11 INFO - Running setup.py install for docopt 17:05:11 INFO - Successfully installed blobuploader requests docopt 17:05:11 INFO - Cleaning up... 17:05:11 INFO - Return code: 0 17:05:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:05:11 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')]} 17:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:11 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')]} 17:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:11 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 0x7fd2e50a7e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f20b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bc130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214afc0>, '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 0x214bec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214c350>, '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': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', '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 17:05:11 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 17:05:11 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 17:05:11 INFO - Using env: {'DISPLAY': ':0', 17:05:11 INFO - 'HOME': '/home/cltbld', 17:05:11 INFO - 'LANG': 'en_US.UTF-8', 17:05:11 INFO - 'LANGUAGE': 'en_US:en', 17:05:11 INFO - 'LOGNAME': 'cltbld', 17:05:11 INFO - 'MAIL': '/var/mail/cltbld', 17:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:11 INFO - 'MOZ_NO_REMOTE': '1', 17:05:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:05:11 INFO - 'NO_EM_RESTART': '1', 17:05:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:11 INFO - 'PWD': '/builds/slave/test', 17:05:11 INFO - 'SHELL': '/bin/bash', 17:05:11 INFO - 'SHLVL': '1', 17:05:11 INFO - 'TERM': 'linux', 17:05:11 INFO - 'TMOUT': '86400', 17:05:11 INFO - 'USER': 'cltbld', 17:05:11 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', 17:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:11 INFO - '_': '/tools/buildbot/bin/python'} 17:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:05:12 INFO - Running setup.py (path:/tmp/pip-T4u53m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:05:12 INFO - Running setup.py (path:/tmp/pip-PfwUoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:05:12 INFO - Running setup.py (path:/tmp/pip-NDgXP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:05:12 INFO - Running setup.py (path:/tmp/pip-Hc33n2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:05:12 INFO - Running setup.py (path:/tmp/pip-D7YZZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:05:12 INFO - Running setup.py (path:/tmp/pip-jiKsIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:05:12 INFO - Running setup.py (path:/tmp/pip-9SJTds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:05:12 INFO - Running setup.py (path:/tmp/pip-roKru7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:05:12 INFO - Running setup.py (path:/tmp/pip-IFcDM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:05:12 INFO - Running setup.py (path:/tmp/pip-9aMHd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:05:12 INFO - Running setup.py (path:/tmp/pip-PtG7mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:05:12 INFO - Running setup.py (path:/tmp/pip-CUaJif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:05:13 INFO - Running setup.py (path:/tmp/pip-hUf822-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:05:13 INFO - Running setup.py (path:/tmp/pip-1pWJ0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:05:13 INFO - Running setup.py (path:/tmp/pip-4il5DO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:05:13 INFO - Running setup.py (path:/tmp/pip-5sfwUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:05:13 INFO - Running setup.py (path:/tmp/pip-fiWN_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:05:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:05:13 INFO - Running setup.py install for manifestparser 17:05:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:05:13 INFO - Running setup.py install for mozcrash 17:05:13 INFO - Running setup.py install for mozdebug 17:05:13 INFO - Running setup.py install for mozdevice 17:05:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:05:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:05:13 INFO - Running setup.py install for mozfile 17:05:13 INFO - Running setup.py install for mozhttpd 17:05:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:05:13 INFO - Running setup.py install for mozinfo 17:05:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:05:14 INFO - Running setup.py install for mozInstall 17:05:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:05:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:05:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:05:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:05:14 INFO - Running setup.py install for mozleak 17:05:14 INFO - Running setup.py install for mozlog 17:05:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:05:14 INFO - Running setup.py install for moznetwork 17:05:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:05:14 INFO - Running setup.py install for mozprocess 17:05:14 INFO - Running setup.py install for mozprofile 17:05:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:05:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:05:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:05:14 INFO - Running setup.py install for mozrunner 17:05:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:05:14 INFO - Running setup.py install for mozscreenshot 17:05:14 INFO - Running setup.py install for moztest 17:05:15 INFO - Running setup.py install for mozversion 17:05:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:05:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:05:15 INFO - Cleaning up... 17:05:15 INFO - Return code: 0 17:05:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:05:15 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')]} 17:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:15 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')]} 17:05:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:15 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 0x7fd2e50a7e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f20b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bc130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214afc0>, '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 0x214bec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214c350>, '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': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', '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 17:05:15 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 17:05:15 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 17:05:15 INFO - Using env: {'DISPLAY': ':0', 17:05:15 INFO - 'HOME': '/home/cltbld', 17:05:15 INFO - 'LANG': 'en_US.UTF-8', 17:05:15 INFO - 'LANGUAGE': 'en_US:en', 17:05:15 INFO - 'LOGNAME': 'cltbld', 17:05:15 INFO - 'MAIL': '/var/mail/cltbld', 17:05:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:15 INFO - 'MOZ_NO_REMOTE': '1', 17:05:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:05:15 INFO - 'NO_EM_RESTART': '1', 17:05:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:05:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:15 INFO - 'PWD': '/builds/slave/test', 17:05:15 INFO - 'SHELL': '/bin/bash', 17:05:15 INFO - 'SHLVL': '1', 17:05:15 INFO - 'TERM': 'linux', 17:05:15 INFO - 'TMOUT': '86400', 17:05:15 INFO - 'USER': 'cltbld', 17:05:15 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', 17:05:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:15 INFO - '_': '/tools/buildbot/bin/python'} 17:05:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:05:15 INFO - Running setup.py (path:/tmp/pip-sxnQOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:05:15 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)) 17:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:05:15 INFO - Running setup.py (path:/tmp/pip-qkg6M4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:05:15 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)) 17:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:05:15 INFO - Running setup.py (path:/tmp/pip-ayczjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:05:15 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)) 17:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:05:15 INFO - Running setup.py (path:/tmp/pip-QBf9px-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:05:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:05:15 INFO - Running setup.py (path:/tmp/pip-U7YNL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:05:15 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)) 17:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:05:15 INFO - Running setup.py (path:/tmp/pip-bjxWWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:05:15 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)) 17:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:05:15 INFO - Running setup.py (path:/tmp/pip-08MPkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:05:15 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)) 17:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:05:15 INFO - Running setup.py (path:/tmp/pip-Yj5q8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:05:15 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)) 17:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:05:15 INFO - Running setup.py (path:/tmp/pip-s9zrhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:05:16 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)) 17:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:05:16 INFO - Running setup.py (path:/tmp/pip-2PaLPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:05:16 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)) 17:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:05:16 INFO - Running setup.py (path:/tmp/pip-bj917H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:05:16 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)) 17:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:05:16 INFO - Running setup.py (path:/tmp/pip-e9wWM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:05:16 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)) 17:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:05:16 INFO - Running setup.py (path:/tmp/pip-LmB_DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:05:16 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)) 17:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:05:16 INFO - Running setup.py (path:/tmp/pip-GYO0DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:05:16 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)) 17:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:05:16 INFO - Running setup.py (path:/tmp/pip-A5oB7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:05:16 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)) 17:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:05:16 INFO - Running setup.py (path:/tmp/pip-hOw_xo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:05:16 INFO - Running setup.py (path:/tmp/pip-ximhDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:05:16 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)) 17:05:16 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)) 17:05:16 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)) 17:05:16 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)) 17:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:05:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:18 INFO - Downloading blessings-1.6.tar.gz 17:05:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:05:18 INFO - Installing collected packages: blessings 17:05:18 INFO - Running setup.py install for blessings 17:05:18 INFO - Successfully installed blessings 17:05:18 INFO - Cleaning up... 17:05:18 INFO - Return code: 0 17:05:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:05:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:05:19 INFO - Reading from file tmpfile_stdout 17:05:19 INFO - Current package versions: 17:05:19 INFO - argparse == 1.2.1 17:05:19 INFO - blessings == 1.6 17:05:19 INFO - blobuploader == 1.2.4 17:05:19 INFO - docopt == 0.6.1 17:05:19 INFO - functools32 == 3.2.3-2 17:05:19 INFO - jsonschema == 2.5.1 17:05:19 INFO - manifestparser == 1.1 17:05:19 INFO - mozInstall == 1.12 17:05:19 INFO - mozcrash == 1.0 17:05:19 INFO - mozdebug == 0.1 17:05:19 INFO - mozdevice == 0.48 17:05:19 INFO - mozfile == 1.2 17:05:19 INFO - mozhttpd == 0.7 17:05:19 INFO - mozinfo == 0.9 17:05:19 INFO - mozleak == 0.1 17:05:19 INFO - mozlog == 3.4 17:05:19 INFO - moznetwork == 0.27 17:05:19 INFO - mozprocess == 0.23 17:05:19 INFO - mozprofile == 0.28 17:05:19 INFO - mozrunner == 6.13 17:05:19 INFO - mozscreenshot == 0.1 17:05:19 INFO - mozsystemmonitor == 0.3 17:05:19 INFO - moztest == 0.7 17:05:19 INFO - mozversion == 1.4 17:05:19 INFO - psutil == 3.1.1 17:05:19 INFO - requests == 1.2.3 17:05:19 INFO - wsgiref == 0.1.2 17:05:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:05: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')]} 17:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05: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')]} 17:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:19 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 0x7fd2e50a7e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f20b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bc130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214afc0>, '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 0x214bec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214c350>, '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': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', '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 17:05:19 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 17:05:19 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 17:05:19 INFO - Using env: {'DISPLAY': ':0', 17:05:19 INFO - 'HOME': '/home/cltbld', 17:05:19 INFO - 'LANG': 'en_US.UTF-8', 17:05:19 INFO - 'LANGUAGE': 'en_US:en', 17:05:19 INFO - 'LOGNAME': 'cltbld', 17:05:19 INFO - 'MAIL': '/var/mail/cltbld', 17:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:19 INFO - 'MOZ_NO_REMOTE': '1', 17:05:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:05:19 INFO - 'NO_EM_RESTART': '1', 17:05:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:19 INFO - 'PWD': '/builds/slave/test', 17:05:19 INFO - 'SHELL': '/bin/bash', 17:05:19 INFO - 'SHLVL': '1', 17:05:19 INFO - 'TERM': 'linux', 17:05:19 INFO - 'TMOUT': '86400', 17:05:19 INFO - 'USER': 'cltbld', 17:05:19 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', 17:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:19 INFO - '_': '/tools/buildbot/bin/python'} 17:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:19 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)) 17:05:19 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)) 17:05:19 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)) 17:05:19 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)) 17:05:19 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)) 17:05:19 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)) 17:05:19 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)) 17:05:19 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)) 17:05:19 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)) 17:05:19 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)) 17:05:19 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)) 17:05:19 INFO - Cleaning up... 17:05:19 INFO - Return code: 0 17:05:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:05: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')]} 17:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05: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')]} 17:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2e50a7e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f20b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bc130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x214afc0>, '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 0x214bec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214c350>, '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': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', '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 17:05: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'] in /builds/slave/test/build 17:05: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 17:05:19 INFO - Using env: {'DISPLAY': ':0', 17:05:19 INFO - 'HOME': '/home/cltbld', 17:05:19 INFO - 'LANG': 'en_US.UTF-8', 17:05:19 INFO - 'LANGUAGE': 'en_US:en', 17:05:19 INFO - 'LOGNAME': 'cltbld', 17:05:19 INFO - 'MAIL': '/var/mail/cltbld', 17:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:19 INFO - 'MOZ_NO_REMOTE': '1', 17:05:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:05:19 INFO - 'NO_EM_RESTART': '1', 17:05:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:19 INFO - 'PWD': '/builds/slave/test', 17:05:19 INFO - 'SHELL': '/bin/bash', 17:05:19 INFO - 'SHLVL': '1', 17:05:19 INFO - 'TERM': 'linux', 17:05:19 INFO - 'TMOUT': '86400', 17:05:19 INFO - 'USER': 'cltbld', 17:05:19 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', 17:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:19 INFO - '_': '/tools/buildbot/bin/python'} 17:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:05:19 INFO - Cleaning up... 17:05:19 INFO - Return code: 0 17:05:19 INFO - Running post-action listener: _resource_record_post_action 17:05:19 INFO - Running post-action listener: _start_resource_monitoring 17:05:19 INFO - Starting resource monitoring. 17:05:19 INFO - [mozharness: 2016-11-18 01:05:19.520236Z] Finished create-virtualenv step (success) 17:05:19 INFO - [mozharness: 2016-11-18 01:05:19.520755Z] Running install step. 17:05:19 INFO - Running pre-action listener: _resource_record_pre_action 17:05:19 INFO - Running main action method: install 17:05:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:05:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:05:19 INFO - Reading from file tmpfile_stdout 17:05:19 INFO - Detecting whether we're running mozinstall >=1.0... 17:05:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:05:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:05:19 INFO - Reading from file tmpfile_stdout 17:05:19 INFO - Output received: 17:05:19 INFO - Usage: mozinstall [options] installer 17:05:19 INFO - Options: 17:05:19 INFO - -h, --help show this help message and exit 17:05:19 INFO - -d DEST, --destination=DEST 17:05:19 INFO - Directory to install application into. [default: 17:05:19 INFO - "/builds/slave/test"] 17:05:19 INFO - --app=APP Application being installed. [default: firefox] 17:05:19 INFO - mkdir: /builds/slave/test/build/application 17:05:19 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'] 17:05:19 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 17:05:35 INFO - Reading from file tmpfile_stdout 17:05:35 INFO - Output received: 17:05:35 INFO - /builds/slave/test/build/application/firefox/firefox 17:05:35 INFO - Running post-action listener: _resource_record_post_action 17:05:35 INFO - [mozharness: 2016-11-18 01:05:35.886580Z] Finished install step (success) 17:05:35 INFO - [mozharness: 2016-11-18 01:05:35.886834Z] Running run-tests step. 17:05:35 INFO - Running pre-action listener: _resource_record_pre_action 17:05:35 INFO - Running main action method: run_tests 17:05:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:05:35 INFO - Python 2.7.3 17:05:35 INFO - Return code: 0 17:05:35 INFO - grabbing minidump binary from tooltool 17:05:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:35 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 0x214afc0>, '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 0x214bec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x214c350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:05:35 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 17:05:35 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 17:05:35 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 17:05:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:05:35 INFO - Return code: 0 17:05:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:05:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:05:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:05:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:05:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:05:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:05:35 INFO - Using env: {'DISPLAY': ':0', 17:05:35 INFO - 'HOME': '/home/cltbld', 17:05:35 INFO - 'LANG': 'en_US.UTF-8', 17:05:35 INFO - 'LANGUAGE': 'en_US:en', 17:05:35 INFO - 'LOGNAME': 'cltbld', 17:05:35 INFO - 'MAIL': '/var/mail/cltbld', 17:05:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:05:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:35 INFO - 'MOZ_NO_REMOTE': '1', 17:05:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:05:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:05:35 INFO - 'NO_EM_RESTART': '1', 17:05:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:35 INFO - 'PWD': '/builds/slave/test', 17:05:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:05:35 INFO - 'SHELL': '/bin/bash', 17:05:35 INFO - 'SHLVL': '1', 17:05:35 INFO - 'TERM': 'linux', 17:05:35 INFO - 'TMOUT': '86400', 17:05:35 INFO - 'USER': 'cltbld', 17:05:35 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828', 17:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:35 INFO - '_': '/tools/buildbot/bin/python'} 17:05:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:05:36 INFO - mozversion application_buildid: 20161117154028 17:05:36 INFO - mozversion application_changeset: 73f79bd8f8a38da87fa7b8acc3f81adfb49f3889 17:05:36 INFO - mozversion application_display_name: Nightly 17:05:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:05:36 INFO - mozversion application_name: Firefox 17:05:36 INFO - mozversion application_remotingname: firefox 17:05:36 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 17:05:36 INFO - mozversion application_vendor: Mozilla 17:05:36 INFO - mozversion application_version: 53.0a1 17:05:36 INFO - mozversion platform_buildid: 20161117154028 17:05:36 INFO - mozversion platform_changeset: 73f79bd8f8a38da87fa7b8acc3f81adfb49f3889 17:05:36 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 17:05:36 INFO - mozversion platform_version: 53.0a1 17:05:36 INFO - using testdate: 1479431136 17:05:36 INFO - actual date: 1479431136 17:05:36 INFO - starting webserver on 'localhost:41667' 17:05:36 INFO - SUITE-START | Running 2 tests 17:05:36 INFO - TEST-START | dromaeo_css 17:05:36 INFO - operating with platform_type : linux_ 17:05:36 INFO - Initialising browser for dromaeo_css test... 17:05:36 INFO - TEST-INFO | started process 25401 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5tF7cw/profile http://localhost:41667/getInfo.html) 17:05:40 INFO - PROCESS | 25401 | __metrics Screen width/height:1600/1200 17:05:40 INFO - PROCESS | 25401 | colorDepth:24 17:05:40 INFO - PROCESS | 25401 | Browser inner width/height: 1024/697 17:05:40 INFO - PROCESS | 25401 | __metrics 17:05:41 INFO - PROCESS | 25401 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:05:41 INFO - PROCESS | 25401 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:05:41 INFO - PROCESS | 25401 | console.error: PushService: 17:05:41 INFO - PROCESS | 25401 | stateChangeProcessEnqueue: Error transitioning state 17:05:41 INFO - PROCESS | 25401 | UnknownError 17:05:42 INFO - TEST-INFO | 25401: exit 0 17:05:42 INFO - Browser initialized. 17:05:42 INFO - Running cycle 1/1 for dromaeo_css test... 17:05:42 INFO - TEST-INFO | started process 25483 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5tF7cw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:05:55 INFO - PROCESS | 25483 | RSS: Main: 174907392 17:05:55 INFO - PROCESS | 25483 | 17:06:46 INFO - PROCESS | 25483 | 1479431206351 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 17:08:59 INFO - PROCESS | 25483 | Cycle 1(1): loaded http://localhost:41667/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41667/tests/dromaeo/cssquery-ext.html) 17:09:01 INFO - PROCESS | 25483 | RSS: Main: 308043776 17:09:01 INFO - PROCESS | 25483 | 17:12:04 INFO - PROCESS | 25483 | Cycle 1(1): loaded http://localhost:41667/tests/dromaeo/cssquery-ext.html (next: http://localhost:41667/tests/dromaeo/cssquery-jquery.html) 17:12:04 INFO - PROCESS | 25483 | RSS: Main: 271355904 17:12:04 INFO - PROCESS | 25483 | 17:15:06 INFO - PROCESS | 25483 | Cycle 1(1): loaded http://localhost:41667/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41667/tests/dromaeo/cssquery-mootools.html) 17:15:07 INFO - PROCESS | 25483 | RSS: Main: 221884416 17:15:07 INFO - PROCESS | 25483 | 17:18:09 INFO - PROCESS | 25483 | Cycle 1(1): loaded http://localhost:41667/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41667/tests/dromaeo/cssquery-prototype.html) 17:18:10 INFO - PROCESS | 25483 | RSS: Main: 218767360 17:18:10 INFO - PROCESS | 25483 | 17:21:13 INFO - PROCESS | 25483 | Cycle 1(1): loaded http://localhost:41667/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41667/tests/dromaeo/cssquery-yui.html) 17:21:14 INFO - PROCESS | 25483 | RSS: Main: 221274112 17:21:14 INFO - PROCESS | 25483 | 17:24:17 INFO - PROCESS | 25483 | Cycle 1(1): loaded http://localhost:41667/tests/dromaeo/cssquery-yui.html (next: http://localhost:41667/tests/dromaeo/cssquery-dojo.html) 17:24:17 INFO - PROCESS | 25483 | RSS: Main: 311713792 17:24:17 INFO - PROCESS | 25483 | 17:24:17 INFO - PROCESS | 25483 | __start_tp_report 17:24:17 INFO - PROCESS | 25483 | _x_x_mozilla_page_load 17:24:17 INFO - PROCESS | 25483 | _x_x_mozilla_page_load_details 17:24:17 INFO - PROCESS | 25483 | |i|pagename|runs| 17:24:17 INFO - PROCESS | 25483 | |0;dojo.html;2500.81243906707;2540.41570438799;2552.170544573014;2553.8978440862365;2560.039985005623;4366.607005369516;4421.027373977728;4432.844850430236;4479.798409071587;4505.324201369792;4447.955520444794;4448.888777780557;4464.218761716703;4468.10637872426;4474.709143905652;4070.0231944333295;4085.9591404086023;4098.139390727951;4132.318167502359;4136.875893723178;4102;4135.524414692304;4165.396017577449;4186.099988502471;4197.7271477354025;4112.6298633123015;4166.83332666693;4167.187398457302;4169.103642716816;4171.103212809246;4114.506259248879;4180.811863466151;4204.705670603059;4211.22092412903;4225.746455212667;4099.713020088595;4156.189543039109;4156.272652285868;4182.414461975326;4198.391233271159;4263.573642635712;4291.184674911788;4293.849715259982;4396.054848207618;4426.468823741139;4753.382060332169;4771.069641419925;4779.545943135431;4780.904381912343;4819.204831202847;4736.465110726435;4774.259989701602;4774.546418090275;4790.856274311776;4824.107540105092;4361.411209486742;4423.712458690175;4451.77741112943;4483.820647174077;4521.819127234907;4837.806487740451;4863.197572400514;4866.95133048665;4871.318015477836;4917.360743103445;4630.884227894329;4672.159011377985;4676.111538807616;4706.105839890478;4713.646476514206;4557.908841823145;4596.931046034312;4599.425071866017;4754.7147171169845;4759.262314341283;4640.651951103613;4697.929531057037;4703;4710.26990816424;4766.4280286365165;4683.274092515666;4695.5304469552775;4695.835645752382;4700.717956922527;4738.076075165379;3941.152652179795;4028.879133625996;4029.6373326400767;4040.8585699500964;4074.694397920168;3499.2126771476214;3506.50908872758;3528.4707293906117;3535.5580552430947;3536.186677064238;4098.016476045787;4099.85650502231;4126.112885729583;4142.068034692231;4142.378643203483;4760.7619619018915;4769.499202583748;4772.141014617333;4778.904421911542;4841.685290456109;3958.287508248543;3999.5200575931076;4030.0932290234464;4099.446574712376;4150.667946564267;4244.04508985482;4268.509121451073;4268.893277668083;4299.9570004300185;4304.612584867377;4436.889077773081;4437.556244375537;4463.464384273908;4474.261746811805;4475;4601.516840731675;4645.86062418128;4737.715737055788;4744.122337367598;4748.952510474851;4405.889852753578;4406.471223453206;4463.687541872037;4475.328700694922;4507.256302710016;4716.0332131913565;4717.3631559739;4720.551547602972;4762;4763.452202996561;4730.47964723887;4774.641901857444;4780.043991201843;4782.258749893772;4822.035592881368;4484.439445069366;4519.209138400833;4557.225271703885;4650.651201159994;4655.162070827284;4529.750863702397;4539.409876716138;4539.704919180374;4541.205289074464;4542.273236282179;4423.137488189772;4495.595396414338;4503.887402814825;4504.459464864237;4505.526919673518;4533.54664533544;4687.554682305175;4703.835365762182;4706.62347012245;4716.056788642217;4558.726476411426;4559.794809233659;4606.170889239969;4606.861794146181;4623.190941585276 17:24:17 INFO - PROCESS | 25483 | |1;ext.html;558.5196730811502;561.4946548106702;579.7420148034124;585.1749033862255;591.5090474905828;21604.351869443923;27616.861915690417;28218;28283.29291767704;29982.400351992947;27700.722992770065;27960.440791184163;28009.29976750582;28052.719472805267;28063.438731225364;5203.401608814978;5341.06531357013;5356.35723713153;5358.142697168434;5370;5447.210154527591;5511.862203444906;5515.862103447405;5521.585881058916;5532.502074813266;5536.280283563131;5569.081101618228;5575.219469274305;5577.079781836012;5577.470140336682;5534.003879301724;5551.056320425538;5558.138488534284;5562.499375056264;5565.9443405565835;5501.9174712379345;5534.391216966131;5553.4724201200825;5558.722063896789;5559.527440167591;25460.872695636404;25628.74371256301;25634.615480767803;25791;25807;2811.6485439320086;2932.6627437844495;2955.438466691322;3007.248187953012;3009.8344591047485;17717.379891703727;17879.016654084022;17893.463196104138;17901.283948642038;17970.640587188314;2641.339665083729;2652.880620372088;2695.9191224263304;2787.484315401637;2788.623535822658;234047;235788;236054;236553;237115;30055.549166762517;49080.7545962275;49418.75290623524;49449.25826112611;49476.752616236685;32322.676773232437;32466.83766581152;32523.34953300921;32576.67423325784;32743.50884736731;12551.748965020648;12711.491540338468;12713.872861271453;12713.93643031779;12714.936425318;10329.431881246604;10367.585296588202;10375.481225938824;10380.480975951172;10381.221408394249;7584.127825300051;7599.65801538921;7687.807804804925;7688.500247483896;7688.654010569426;3016.909492715222;3044.071558174778;3050.222193340684;3079.59965204522;3101.7673674474504;815.4291995603102;825.7852958230783;845.6913226672343;865.9696910608099;899.8065415935605;25421.49157016849;26058.869705651352;26524.60213096805;26821.329466763487;26912;933.5239028095721;936.3398803843411;949.9050094990446;953.7091187187974;986.8667729856378;13636.590902272948;14417.567472975827;14535.563933082025;14817.703645927237;14913.92543037278;358282;360490;361295;362489;362856;42934.57065429306;43547;43575.56424435778;43614;43647.781761091625;1007.1288335589662;1042.5777560088025;1167.2529581068122;1191.4995701805262;1194.474431250247;25385.492290153725;26199.345016374744;26652.675760755665;26673.33316667099;26790.732092678823;40934.1813163741;41214.79392603018;41261;41283.587164127974;41289.79355103205;278456;282499;282543;282733;284224;22823.657645135005;22884.199053033062;23036.193733219257;23297.18459853897;23367.76632233724;35310;35445.82277088598;35601.643983559836;35738.10654733652;35743.46384804282;30649.233769155948;30950.53574196434;30993.535096974014;31882.36235275328;32016.199595010312;31025.534616981215;31377.215569610944;31719.682803171672;32287.35425291528;33743.15642108967;28689.13932582026;29668.258293542833;32257.67742322547;32661.18347041343;33400.83299583486;27326.72673273242;28382.148535543965;29365.41269174562;29505;30485.847570762005;22685.773142268365;22812.4296892579;22926.426839328484;23544.529109418057;23576 17:24:17 INFO - PROCESS | 25483 | |2;jquery.html;347.1010083882743;355.0874253169355;355.88433759028317;365.1564885115384;371.14636336426213;11286.887131128697;11551.249168804032;12307.38463076847;12379.69050773731;12400.193987390836;13192.868071319284;13414.26221557814;13448.596542103727;13803;14098.083624564397;4013.819378127991;4293.677974151936;4297.333913243452;4297.871063868089;4299;4039.4950631171105;4129.761071678499;4194.622483976442;4220.831166753342;4227.661787057028;4192.287311157095;4201.579842015789;4209.179210054041;4209.6421804146685;4210.305299625558;4094.06664968712;4157.293260145782;4195.119025004753;4195.85314513992;4198.223328684203;3809.5238095238096;3835.9616403835885;3840.36633955397;3840.9423858642144;3841.78870162141;16339.26473308704;16404.507864764077;16494.83505164957;16530.338786448647;16668.41660541887;1894.7536820213422;2105.715728376665;2434.6834911461397;2469.4320306329464;2539.02247634662;10165.644202452953;10560.366378017266;10584.417857017861;10988.615398461096;11122.110231181487;1299.3762993762953;1367.7743172376545;1445.913245205291;1449.1015570346347;1509.0794615284776;40811.38782918259;41291.58708412937;48390.27414588784;61387.38612613861;66623.66688166576;9292;9443.575039123118;9614.278929080347;9770.706878793648;9968.750781230383;12727.045471589669;12768.425420856083;12785.232886026868;12841.486340546273;12855.29295888735;6762.019507171432;6784.253732089466;6922;6932.514723969374;6992.650367481606;6315.494760419156;6480.189976252968;6572.309907459744;6671.899921501182;6679.365460281266;3995.280849447069;3997.92004159921;4012.7592344459426;4173.937390939138;4223.1553689261655;1710.264586227934;1712.4263371770348;1723.4571110100278;1792.8794503435354;1802.1890149432545;766.7853001159684;819.5697258938985;832.704389941562;847.8855354527183;897.7127319257905;16011.439599613957;16543.586410339838;16546.58633534147;16885.15574221284;17509.7373539397;823.5717426938079;827.2596026556198;851.4423052900361;873.1879352202512;925.1627277314042;8950.194482496607;9612.663556775618;9748.512574371394;9779.804403912025;9894.901050989398;326244;330976;332129;333914;357953;48597.271040934414;57706.7114664424;77255.2274477248;77755.22244777593;82368.58815706003;830.1034882327073;836.4437648963371;842.9452085614538;849.5794581682106;850.2985037344216;14987;15124.39502419891;15398.230088495755;15690.529284121494;15777.211139443212;36836;37835;38923.22153556856;38983.805080974416;40112.1977560453;52036.73981630067;52087;52650;52805;53162.734186330366;7216.639168041682;7323.853522929405;7431.3683336916965;7539.246075392417;7544.6227688616445;9079.727608171765;11321.830172547583;11504.82742758876;11604;12280;10186.541605627617;10229.386236825814;10447.843282350923;10564.207684423853;10814.405207713651;9912.306138570231;9975.551100200653;10076.596936122767;10145.64490242838;10316.43259620698;9942;9945.353552019096;10584.682459526226;10612.29386506436;10838.729031774268;7207.531510451804;7274.454415918722;7444.813879653052;7454.701811927679;7744.070711514654;8364.289035432057;9919.107280344804;10095.091441770275;10216.438095904796;10464.790704185722 17:24:17 INFO - PROCESS | 25483 | |3;mootools.html;1201.2012012012012;1207.3057991654798;1225.871283515231;1285.3187810460456;1629.9511014669565;5520.972395138024;6044.153818465413;6253.062040693898;6259;6387.744490220397;6276.874462510747;6337.334579869105;6340.778072767454;6343.333949935248;6345.270293916201;1745.65959637871;1772.1847949943042;1775.2898840463788;1777.4045694692293;1778.1820362633139;1643.2276829889934;1645.169189559277;1659.145540046877;1660.286076986895;1757.3497805811867;1725.7583938248592;1748.029843436892;1748.370586588827;1749.5713550180224;1750.1074452029493;1706.3626070201171;1722.8966262024255;1726.0851748573277;1727.7322015087682;1728.8271172882737;1660.850523452895;1704.2245778171027;1717.2015013018881;1721.6212433264661;1721.6384559242574;6038.728257228435;6204.87590248197;6242.500599951993;6250.531210159211;6250.9062364064575;4004.2992476316817;4158.8336466541305;4172.541020487774;4177.436046133764;4180.163967206571;2553.3871870750886;2695.3261684578856;2763.6545431821023;2764.0187733354774;2766.294594878293;772.9652165652503;782.3701919954491;801.1187693537063;802.2498963469117;802.7190483330846;8567.100454452255;8688.3049356052;8708.346873984414;8723.12768723129;8724.302055835582;3485.5186398836263;3585.3546361655153;3593.7484376093944;3669.2478042001326;3672.9632703672623;7065.4700897432895;7067.187273463514;7067.787966361017;7077.186123595852;7081.398081163159;4010.3984402339297;4050.5746896576024;4051.3922911562913;4058.411530328086;4059.3707975263883;3407.2504049109157;3515.490253913168;3706.666400023957;3777.8299976501125;3779.60314167014;2938.8971386001813;2981.567672687448;2994.1766014345794;3057.98471007648;3077.5229839374933;888.5868071346855;914.6798620482778;915.6017132547363;919.1497864475333;922.064104933493;728.0644371981978;738.446165375968;741.9109706835106;756.4100001998451;783.416354815666;4461.933071003938;4518.638508919278;4518.932216016762;4583.587477127075;4601.493835678073;778.1945686214741;783.7570353190529;806.5362416610473;859.3941271403645;862.0646598440762;3047.6190476190477;3075.7693173012112;3085.2441151917924;3113.3461972985474;3168.952465713016;7309.926900730925;7362.9631851840395;7536.623168841536;7613.0483689538805;7680.615969201517;7797.220277972157;7811.179826118289;7814.257645523666;7814.882776758353;7836.529808211526;5887.676177810262;6050.697465126814;6074.271087469532;6083.726232319467;6088.178095957078;4270.402143699823;4389.209942210433;4396.802143903468;4499.482559505699;4608.815647374067;7282.45381596393;7290.781276561712;7379.3727533158135;7417.480776345604;7437.256274372519;7388.704451821867;7594.658240379085;7602.163761986067;7636.541807491568;7647.158812530506;5658.122990936411;5667.3765885753355;5684.602077854511;5703.287089113861;5741.598088133957;5492.91760623583;5743.282089738783;6052.364501727256;6172.135900973957;6195.349488303663;5748.39641837601;5752.9137062943255;5836.182934389274;5844.6785426801935;5965.701714914324;5658.462446067617;5672;5693.259876216065;5705.714714264187;5744.167095771007;5018.444282272361;5111.284420181103;5136.640435169502;5144.202648589475;5156;3972.702047346518;3980.2238563479846;4008.599140085968;4070.4097905804083;4179.561146079739;3776.8489260429274;3884.5532763731403;3943.507061617298;3985.3822657488136;4023.8994025149605 17:24:17 INFO - PROCESS | 25483 | |4;prototype.html;276.50781608736446;281.1845647621896;282.1788595188003;289.773976298487;292.26058073075114;4559.88600284993;4694.859154225374;4719.48085710572;4728.55078767517;4734.005858769654;4675.532446755323;4687.765611719409;4694.600958918496;4703;4703.459102203247;3406.841673830897;3530.6469353064745;3649.901306748617;3664.413693808991;3682.6317368263226;3505.4040813061715;3591.42537194049;3594.442861356481;3601.2437388148387;3615.9276814463697;3544.6455354464606;3548.172691064091;3560.6439356064448;3563.803990780506;3582.1223800168864;3413.7027929386923;3519.348920449725;3569.7505872944394;3583.6416358364218;3590.1922067534724;3070.6468756093113;3118.0022392834303;3125.484295091307;3128.577642018321;3144.4215743090676;4516.864494065183;4517.141743068806;4532.048269863332;4541.477730061019;4569.2003899317815;4110.260153172428;4111.218868414991;4118.217538667644;4121.608447197512;4150.232207041707;4644.303354496852;4836.613070954315;4862.939445562959;4877.317175595384;4893.672302834071;3409.1817963688786;3411.1472131967007;3412.744044196695;3415.7608967372294;3416.6754158354915;5128.8461346159675;5158.329417175781;5172.2241663750365;5185.974070129663;5203.973980130075;3316.9834150829092;3451.2752322012657;3463.6882680558365;3466.474751109504;3474.4961980512685;3535.5528359849454;3537.6816086552335;3541.184758117303;3550.5206797082064;3553.928921421557;4062.7765472899273;4068.7762173080764;4083.448289649914;4087.8773636790943;4088.3049881520215;4859.878503037382;4879.512048795092;4892.19278818998;4893.241547560134;4908.460069392364;1140.5323817234894;1190.2087358525364;1192.2369683402628;1194.4923407551755;1195.6966906072248;791.6818497202094;808.6159074439594;821.7247222180517;824.9010118785784;825.4263287015586;4135.834566617362;4143.751374917515;4149.958500414958;4230.1539692061715;4248.55549113303;4925.162722337212;4932.383452068491;4939.061578300111;4946.356973593482;4958.355413796256;4284.91430171401;4304.74171549708;4321.762303073361;4322.178786030644;4336.371226172188;2533.505966336547;2573.189445324717;2596.40282734972;2596.740325967426;2620.4104076583;4598.448186217675;4607.95392046082;4621.5378462154185;4622.67641265115;4649.418822647169;4983.152864013054;4997.25041243809;5010.974945125324;5019.598432125495;5072.391313042459;4441.444819397575;4588.197065513522;4619.838305659286;4623.907521849612;4733.715977041251;5055;5064.645474816727;5072.82245121419;5073.213651883964;5099.311592935054;4948.950510494849;4951.752412379438;4976.197968959971;4984.626153038609;5015.448300686994;4650.674452788272;4747.715137091785;4758.857234282977;4775.7583028413555;4788.233882578771;3469.809160496197;3470.2885908389226;3479.756417050782;3487.319972605419;3490;5156.607715916756;5161.458046905021;5162.819301324435;5165.302684137593;5168.328117344721;4757.526086434965;4945.7527123644395;4969.254611808257;4971.552560269593;4976.950230497649;4915.410150781929;4917.237828136645;4923.876903077452;4951.752412379438;4953.934903995658;4810.134175848241;4823.469418363907;4829.492903245249;4842.5157484251295;4874.53691899269;4746.5490778376;4925.21196608541;4933.9506604933495;4945.925811112764;4978.2034874419915;4792.904141917212;4794.688345257547;4795;4797.736124513185;4801.831935882227 17:24:17 INFO - PROCESS | 25483 | |5;yui.html;584.4622946888862;601.2694576090049;601.5488383712216;647.9546431749774;658.7463826426828;10516.106130978857;10532.47337633119;11104.056155226815;11286.830697539534;11353.545858165673;10868.728281792959;11095.4452277386;11107.944460277708;11109.333439993587;11125.388103654295;734.8750712378895;754.8414832885073;754.8829931360648;755.7128291249318;757.1482082657011;602.5541099586296;625.8153844615849;727.9818004549876;748.6705849426235;755.5391211361066;795.6220795122329;797.8523973064972;798.0064819299951;798.9041315042174;798.9480683755567;787.2796391301952;787.6180052674448;788.621461698388;789.2738680414033;795.0499153511556;453.1887920622083;460.2153328574772;556.3407362275676;573.1917995626147;573.237593999979;9984.500774961223;10012.749681257954;10029.348092373972;10050.246231532665;10056.245781566338;8186.386021048385;8363;8369.288610468118;8490.320774338039;9034.503102329369;8109.878351824727;8163.224493673151;8165.877511837327;8184.7953801155445;8195.221453961864;2407.205622144688;2435.257246539805;2445.42532504863;2459.114718701266;2488.191337815217;46925.765371173264;48639;49212.753936230445;49340.50659493395;49617.75191124057;6830.863382732317;6858.00558918954;6966.338197871194;7170.4980651354945;7179.712811487587;13552.59342219735;13565.932170339085;13668.93165534166;13816.516421925386;13828.792568111487;6051.273847138284;6083.300420451616;6146.0470489811505;6211.16149319845;6370.426661600478;3524.488949102417;3541.6812486876306;3554.5201397810965;3616.8553257869917;3616.8914932552066;2459.913903013386;2559.7312282210473;2560.001599376245;2560.270322957948;2561.9487610248066;440.9272470042407;457.83517933543897;457.9862604121882;460.27047130298183;463.6777439679458;408.2691981353344;456.1652176516966;493.3019777015483;501.2631431795255;501.34574380433884;6667.866642667216;6673.599584024974;6706.185258367778;6708.597484150869;7018.473614478935;489.801630339713;497.32612310318996;502.1438447447094;507.3151245818116;510.82631905151686;3251.4147453458604;3325.118843506473;3392.9151771205425;3409.914752131167;3444.51776751255;61831.38168618256;62361.376386236465;65799;66082.33917660858;66740.6662966682;54001;54287.45712542903;55009.449905500434;55133.173002405;55927.16109258364;50304;51175.23237151446;52211;52325.47674523206;54254.18618720801;6699.296573859675;6760.1549806274215;7044.119485064367;7055.118110236221;7063.081799366255;34754.478682820285;35000.2731060099;35850.28299434049;35873.82063089668;36245.09387265339;43665;47217.291740624605;47573.52426475829;47799.76100119477;48104.75947620379;8535.146485351415;8619.396642235235;8763.693270735494;8789.252913502169;9346.252299815862;9884.456354900549;10098.8485172721;10171.593136274467;10269.178465722875;10339.431331276852;6938.340857618518;8491.490510569385;8622.396432249932;9133.680321188727;9155.450672959643;8656.350773691873;9160.129787670161;9361.485118318558;9367.156955874005;9427;8920.73237802867;9093.545322733704;9632.27757918145;9640.42157470554;9665.3234273603;7398.445116616383;7471.775846724607;7777.688892444239;7783.649735762017;7824.176696073007;7752.573608451364;8039.638216280398;8404.411691181558;8433.704820331259;8467 17:24:17 INFO - PROCESS | 25483 | __end_tp_report 17:24:17 INFO - PROCESS | 25483 | __start_cc_report 17:24:17 INFO - PROCESS | 25483 | _x_x_mozilla_cycle_collect,2125 17:24:17 INFO - PROCESS | 25483 | __end_cc_report 17:24:17 INFO - PROCESS | 25483 | __startTimestamp1479432257381__endTimestamp 17:24:17 INFO - PROCESS | 25483 | 17:24:17 INFO - PROCESS | 25483 | ------- Summary: start ------- 17:24:17 INFO - PROCESS | 25483 | Number of tests: 6 17:24:17 INFO - PROCESS | 25483 | 17:24:17 INFO - PROCESS | 25483 | [#0] dojo.html Cycles:165 Average:4391.40 Median:4483.82 stddev:443.72 (9.9%) stddev-sans-first:419.57 17:24:17 INFO - PROCESS | 25483 | Values: 2500.8 2540.4 2552.2 2553.9 2560.0 4366.6 4421.0 4432.8 4479.8 4505.3 4448.0 4448.9 4464.2 4468.1 4474.7 4070.0 4086.0 4098.1 4132.3 4136.9 4102.0 4135.5 4165.4 4186.1 4197.7 4112.6 4166.8 4167.2 4169.1 4171.1 4114.5 4180.8 4204.7 4211.2 4225.7 4099.7 4156.2 4156.3 4182.4 4198.4 4263.6 4291.2 4293.8 4396.1 4426.5 4753.4 4771.1 4779.5 4780.9 4819.2 4736.5 4774.3 4774.5 4790.9 4824.1 4361.4 4423.7 4451.8 4483.8 4521.8 4837.8 4863.2 4867.0 4871.3 4917.4 4630.9 4672.2 4676.1 4706.1 4713.6 4557.9 4596.9 4599.4 4754.7 4759.3 4640.7 4697.9 4703.0 4710.3 4766.4 4683.3 4695.5 4695.8 4700.7 4738.1 3941.2 4028.9 4029.6 4040.9 4074.7 3499.2 3506.5 3528.5 3535.6 3536.2 4098.0 4099.9 4126.1 4142.1 4142.4 4760.8 4769.5 4772.1 4778.9 4841.7 3958.3 3999.5 4030.1 4099.4 4150.7 4244.0 4268.5 4268.9 4300.0 4304.6 4436.9 4437.6 4463.5 4474.3 4475.0 4601.5 4645.9 4737.7 4744.1 4749.0 4405.9 4406.5 4463.7 4475.3 4507.3 4716.0 4717.4 4720.6 4762.0 4763.5 4730.5 4774.6 4780.0 4782.3 4822.0 4484.4 4519.2 4557.2 4650.7 4655.2 4529.8 4539.4 4539.7 4541.2 4542.3 4423.1 4495.6 4503.9 4504.5 4505.5 4533.5 4687.6 4703.8 4706.6 4716.1 4558.7 4559.8 4606.2 4606.9 4623.2 17:24:17 INFO - PROCESS | 25483 | 17:24:17 INFO - PROCESS | 25483 | [#1] ext.html Cycles:180 Average:41205.48 Median:16849.73 stddev:78754.90 (467.4%) stddev-sans-first:78916.69 17:24:17 INFO - PROCESS | 25483 | Values: 558.5 561.5 579.7 585.2 591.5 21604.4 27616.9 28218.0 28283.3 29982.4 27700.7 27960.4 28009.3 28052.7 28063.4 5203.4 5341.1 5356.4 5358.1 5370.0 5447.2 5511.9 5515.9 5521.6 5532.5 5536.3 5569.1 5575.2 5577.1 5577.5 5534.0 5551.1 5558.1 5562.5 5565.9 5501.9 5534.4 5553.5 5558.7 5559.5 25460.9 25628.7 25634.6 25791.0 25807.0 2811.6 2932.7 2955.4 3007.2 3009.8 17717.4 17879.0 17893.5 17901.3 17970.6 2641.3 2652.9 2695.9 2787.5 2788.6 234047.0 235788.0 236054.0 236553.0 237115.0 30055.5 49080.8 49418.8 49449.3 49476.8 32322.7 32466.8 32523.3 32576.7 32743.5 12551.7 12711.5 12713.9 12713.9 12714.9 10329.4 10367.6 10375.5 10380.5 10381.2 7584.1 7599.7 7687.8 7688.5 7688.7 3016.9 3044.1 3050.2 3079.6 3101.8 815.4 825.8 845.7 866.0 899.8 25421.5 26058.9 26524.6 26821.3 26912.0 933.5 936.3 949.9 953.7 986.9 13636.6 14417.6 14535.6 14817.7 14913.9 358282.0 360490.0 361295.0 362489.0 362856.0 42934.6 43547.0 43575.6 43614.0 43647.8 1007.1 1042.6 1167.3 1191.5 1194.5 25385.5 26199.3 26652.7 26673.3 26790.7 40934.2 41214.8 41261.0 41283.6 41289.8 278456.0 282499.0 282543.0 282733.0 284224.0 22823.7 22884.2 23036.2 23297.2 23367.8 35310.0 35445.8 35601.6 35738.1 35743.5 30649.2 30950.5 30993.5 31882.4 32016.2 31025.5 31377.2 31719.7 32287.4 33743.2 28689.1 29668.3 32257.7 32661.2 33400.8 27326.7 28382.1 29365.4 29505.0 30485.8 22685.8 22812.4 22926.4 23544.5 23576.0 17:24:17 INFO - PROCESS | 25483 | 17:24:17 INFO - PROCESS | 25483 | [#2] jquery.html Cycles:180 Average:21605.63 Median:4013.29 stddev:55618.51 (1385.9%) stddev-sans-first:55751.64 17:24:17 INFO - PROCESS | 25483 | Values: 347.1 355.1 355.9 365.2 371.1 11286.9 11551.2 12307.4 12379.7 12400.2 13192.9 13414.3 13448.6 13803.0 14098.1 4013.8 4293.7 4297.3 4297.9 4299.0 4039.5 4129.8 4194.6 4220.8 4227.7 4192.3 4201.6 4209.2 4209.6 4210.3 4094.1 4157.3 4195.1 4195.9 4198.2 3809.5 3836.0 3840.4 3840.9 3841.8 16339.3 16404.5 16494.8 16530.3 16668.4 1894.8 2105.7 2434.7 2469.4 2539.0 10165.6 10560.4 10584.4 10988.6 11122.1 1299.4 1367.8 1445.9 1449.1 1509.1 40811.4 41291.6 48390.3 61387.4 66623.7 9292.0 9443.6 9614.3 9770.7 9968.8 12727.0 12768.4 12785.2 12841.5 12855.3 6762.0 6784.3 6922.0 6932.5 6992.7 6315.5 6480.2 6572.3 6671.9 6679.4 3995.3 3997.9 4012.8 4173.9 4223.2 1710.3 1712.4 1723.5 1792.9 1802.2 766.8 819.6 832.7 847.9 897.7 16011.4 16543.6 16546.6 16885.2 17509.7 823.6 827.3 851.4 873.2 925.2 8950.2 9612.7 9748.5 9779.8 9894.9 326244.0 330976.0 332129.0 333914.0 357953.0 48597.3 57706.7 77255.2 77755.2 82368.6 830.1 836.4 842.9 849.6 850.3 14987.0 15124.4 15398.2 15690.5 15777.2 36836.0 37835.0 38923.2 38983.8 40112.2 52036.7 52087.0 52650.0 52805.0 53162.7 7216.6 7323.9 7431.4 7539.2 7544.6 9079.7 11321.8 11504.8 11604.0 12280.0 10186.5 10229.4 10447.8 10564.2 10814.4 9912.3 9975.6 10076.6 10145.6 10316.4 9942.0 9945.4 10584.7 10612.3 10838.7 7207.5 7274.5 7444.8 7454.7 7744.1 8364.3 9919.1 10095.1 10216.4 10464.8 17:24:17 INFO - PROCESS | 25483 | 17:24:17 INFO - PROCESS | 25483 | [#3] mootools.html Cycles:180 Average:4258.12 Median:5506.95 stddev:2309.44 (41.9%) stddev-sans-first:2304.49 17:24:17 INFO - PROCESS | 25483 | Values: 1201.2 1207.3 1225.9 1285.3 1630.0 5521.0 6044.2 6253.1 6259.0 6387.7 6276.9 6337.3 6340.8 6343.3 6345.3 1745.7 1772.2 1775.3 1777.4 1778.2 1643.2 1645.2 1659.1 1660.3 1757.3 1725.8 1748.0 1748.4 1749.6 1750.1 1706.4 1722.9 1726.1 1727.7 1728.8 1660.9 1704.2 1717.2 1721.6 1721.6 6038.7 6204.9 6242.5 6250.5 6250.9 4004.3 4158.8 4172.5 4177.4 4180.2 2553.4 2695.3 2763.7 2764.0 2766.3 773.0 782.4 801.1 802.2 802.7 8567.1 8688.3 8708.3 8723.1 8724.3 3485.5 3585.4 3593.7 3669.2 3673.0 7065.5 7067.2 7067.8 7077.2 7081.4 4010.4 4050.6 4051.4 4058.4 4059.4 3407.3 3515.5 3706.7 3777.8 3779.6 2938.9 2981.6 2994.2 3058.0 3077.5 888.6 914.7 915.6 919.1 922.1 728.1 738.4 741.9 756.4 783.4 4461.9 4518.6 4518.9 4583.6 4601.5 778.2 783.8 806.5 859.4 862.1 3047.6 3075.8 3085.2 3113.3 3169.0 7309.9 7363.0 7536.6 7613.0 7680.6 7797.2 7811.2 7814.3 7814.9 7836.5 5887.7 6050.7 6074.3 6083.7 6088.2 4270.4 4389.2 4396.8 4499.5 4608.8 7282.5 7290.8 7379.4 7417.5 7437.3 7388.7 7594.7 7602.2 7636.5 7647.2 5658.1 5667.4 5684.6 5703.3 5741.6 5492.9 5743.3 6052.4 6172.1 6195.3 5748.4 5752.9 5836.2 5844.7 5965.7 5658.5 5672.0 5693.3 5705.7 5744.2 5018.4 5111.3 5136.6 5144.2 5156.0 3972.7 3980.2 4008.6 4070.4 4179.6 3776.8 3884.6 3943.5 3985.4 4023.9 17:24:17 INFO - PROCESS | 25483 | 17:24:17 INFO - PROCESS | 25483 | [#4] prototype.html Cycles:180 Average:4031.61 Median:4620.69 stddev:1197.99 (25.9%) stddev-sans-first:1167.72 17:24:17 INFO - PROCESS | 25483 | Values: 276.5 281.2 282.2 289.8 292.3 4559.9 4694.9 4719.5 4728.6 4734.0 4675.5 4687.8 4694.6 4703.0 4703.5 3406.8 3530.6 3649.9 3664.4 3682.6 3505.4 3591.4 3594.4 3601.2 3615.9 3544.6 3548.2 3560.6 3563.8 3582.1 3413.7 3519.3 3569.8 3583.6 3590.2 3070.6 3118.0 3125.5 3128.6 3144.4 4516.9 4517.1 4532.0 4541.5 4569.2 4110.3 4111.2 4118.2 4121.6 4150.2 4644.3 4836.6 4862.9 4877.3 4893.7 3409.2 3411.1 3412.7 3415.8 3416.7 5128.8 5158.3 5172.2 5186.0 5204.0 3317.0 3451.3 3463.7 3466.5 3474.5 3535.6 3537.7 3541.2 3550.5 3553.9 4062.8 4068.8 4083.4 4087.9 4088.3 4859.9 4879.5 4892.2 4893.2 4908.5 1140.5 1190.2 1192.2 1194.5 1195.7 791.7 808.6 821.7 824.9 825.4 4135.8 4143.8 4150.0 4230.2 4248.6 4925.2 4932.4 4939.1 4946.4 4958.4 4284.9 4304.7 4321.8 4322.2 4336.4 2533.5 2573.2 2596.4 2596.7 2620.4 4598.4 4608.0 4621.5 4622.7 4649.4 4983.2 4997.3 5011.0 5019.6 5072.4 4441.4 4588.2 4619.8 4623.9 4733.7 5055.0 5064.6 5072.8 5073.2 5099.3 4949.0 4951.8 4976.2 4984.6 5015.4 4650.7 4747.7 4758.9 4775.8 4788.2 3469.8 3470.3 3479.8 3487.3 3490.0 5156.6 5161.5 5162.8 5165.3 5168.3 4757.5 4945.8 4969.3 4971.6 4977.0 4915.4 4917.2 4923.9 4951.8 4953.9 4810.1 4823.5 4829.5 4842.5 4874.5 4746.5 4925.2 4934.0 4945.9 4978.2 4792.9 4794.7 4795.0 4797.7 4801.8 17:24:17 INFO - PROCESS | 25483 | 17:24:17 INFO - PROCESS | 25483 | [#5] yui.html Cycles:180 Average:13091.78 Median:602.05 stddev:17535.41 (2912.6%) stddev-sans-first:17559.45 17:24:17 INFO - PROCESS | 25483 | Values: 584.5 601.3 601.5 648.0 658.7 10516.1 10532.5 11104.1 11286.8 11353.5 10868.7 11095.4 11107.9 11109.3 11125.4 734.9 754.8 754.9 755.7 757.1 602.6 625.8 728.0 748.7 755.5 795.6 797.9 798.0 798.9 798.9 787.3 787.6 788.6 789.3 795.0 453.2 460.2 556.3 573.2 573.2 9984.5 10012.7 10029.3 10050.2 10056.2 8186.4 8363.0 8369.3 8490.3 9034.5 8109.9 8163.2 8165.9 8184.8 8195.2 2407.2 2435.3 2445.4 2459.1 2488.2 46925.8 48639.0 49212.8 49340.5 49617.8 6830.9 6858.0 6966.3 7170.5 7179.7 13552.6 13565.9 13668.9 13816.5 13828.8 6051.3 6083.3 6146.0 6211.2 6370.4 3524.5 3541.7 3554.5 3616.9 3616.9 2459.9 2559.7 2560.0 2560.3 2561.9 440.9 457.8 458.0 460.3 463.7 408.3 456.2 493.3 501.3 501.3 6667.9 6673.6 6706.2 6708.6 7018.5 489.8 497.3 502.1 507.3 510.8 3251.4 3325.1 3392.9 3409.9 3444.5 61831.4 62361.4 65799.0 66082.3 66740.7 54001.0 54287.5 55009.4 55133.2 55927.2 50304.0 51175.2 52211.0 52325.5 54254.2 6699.3 6760.2 7044.1 7055.1 7063.1 34754.5 35000.3 35850.3 35873.8 36245.1 43665.0 47217.3 47573.5 47799.8 48104.8 8535.1 8619.4 8763.7 8789.3 9346.3 9884.5 10098.8 10171.6 10269.2 10339.4 6938.3 8491.5 8622.4 9133.7 9155.5 8656.4 9160.1 9361.5 9367.2 9427.0 8920.7 9093.5 9632.3 9640.4 9665.3 7398.4 7471.8 7777.7 7783.6 7824.2 7752.6 8039.6 8404.4 8433.7 8467.0 17:24:17 INFO - PROCESS | 25483 | -------- Summary: end -------- 17:24:17 INFO - PROCESS | 25483 | 17:24:18 INFO - TEST-INFO | 25483: exit 0 17:24:18 INFO - TEST-OK | dromaeo_css | took 1122132ms 17:24:18 INFO - TEST-START | kraken 17:24:18 INFO - operating with platform_type : linux_ 17:24:18 INFO - Initialising browser for kraken test... 17:24:18 INFO - TEST-INFO | started process 25785 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYX4wMI/profile http://localhost:41667/getInfo.html) 17:24:22 INFO - PROCESS | 25785 | __metrics Screen width/height:1600/1200 17:24:22 INFO - PROCESS | 25785 | colorDepth:24 17:24:22 INFO - PROCESS | 25785 | Browser inner width/height: 1024/697 17:24:22 INFO - PROCESS | 25785 | __metrics 17:24:23 INFO - PROCESS | 25785 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:24:23 INFO - PROCESS | 25785 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:24:23 INFO - PROCESS | 25785 | console.error: PushService: 17:24:23 INFO - PROCESS | 25785 | stateChangeProcessEnqueue: Error transitioning state 17:24:23 INFO - PROCESS | 25785 | UnknownError 17:24:24 INFO - TEST-INFO | 25785: exit 0 17:24:24 INFO - Browser initialized. 17:24:24 INFO - Running cycle 1/1 for kraken test... 17:24:24 INFO - TEST-INFO | started process 25869 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYX4wMI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:24:37 INFO - PROCESS | 25869 | RSS: Main: 174903296 17:24:37 INFO - PROCESS | 25869 | 17:24:39 INFO - PROCESS | 25869 | Cycle 1(1): loaded http://localhost:41667/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41667/tests/kraken/driver.html?testName=audio-beat-detection) 17:24:40 INFO - PROCESS | 25869 | RSS: Main: 239955968 17:24:40 INFO - PROCESS | 25869 | 17:24:43 INFO - PROCESS | 25869 | Cycle 1(1): loaded http://localhost:41667/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41667/tests/kraken/driver.html?testName=audio-dft) 17:24:44 INFO - PROCESS | 25869 | RSS: Main: 218546176 17:24:44 INFO - PROCESS | 25869 | 17:24:47 INFO - PROCESS | 25869 | Cycle 1(1): loaded http://localhost:41667/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41667/tests/kraken/driver.html?testName=audio-fft) 17:24:47 INFO - PROCESS | 25869 | RSS: Main: 299745280 17:24:47 INFO - PROCESS | 25869 | 17:24:50 INFO - PROCESS | 25869 | Cycle 1(1): loaded http://localhost:41667/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41667/tests/kraken/driver.html?testName=audio-oscillator) 17:24:50 INFO - PROCESS | 25869 | RSS: Main: 211103744 17:24:50 INFO - PROCESS | 25869 | 17:24:52 INFO - PROCESS | 25869 | Cycle 1(1): loaded http://localhost:41667/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41667/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:24:53 INFO - PROCESS | 25869 | RSS: Main: 214560768 17:24:53 INFO - PROCESS | 25869 | 17:24:56 INFO - PROCESS | 25869 | Cycle 1(1): loaded http://localhost:41667/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41667/tests/kraken/driver.html?testName=imaging-darkroom) 17:24:57 INFO - PROCESS | 25869 | RSS: Main: 254070784 17:24:57 INFO - PROCESS | 25869 | 17:25:00 INFO - PROCESS | 25869 | Cycle 1(1): loaded http://localhost:41667/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41667/tests/kraken/driver.html?testName=imaging-desaturate) 17:25:01 INFO - PROCESS | 25869 | RSS: Main: 254132224 17:25:01 INFO - PROCESS | 25869 | 17:25:04 INFO - PROCESS | 25869 | Cycle 1(1): loaded http://localhost:41667/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41667/tests/kraken/driver.html?testName=json-parse-financial) 17:25:04 INFO - PROCESS | 25869 | RSS: Main: 252293120 17:25:04 INFO - PROCESS | 25869 | 17:25:06 INFO - PROCESS | 25869 | Cycle 1(1): loaded http://localhost:41667/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41667/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:25:07 INFO - PROCESS | 25869 | RSS: Main: 214577152 17:25:07 INFO - PROCESS | 25869 | 17:25:09 INFO - PROCESS | 25869 | Cycle 1(1): loaded http://localhost:41667/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41667/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:25:09 INFO - PROCESS | 25869 | RSS: Main: 222224384 17:25:09 INFO - PROCESS | 25869 | 17:25:11 INFO - PROCESS | 25869 | Cycle 1(1): loaded http://localhost:41667/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41667/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:25:12 INFO - PROCESS | 25869 | RSS: Main: 230547456 17:25:12 INFO - PROCESS | 25869 | 17:25:15 INFO - PROCESS | 25869 | Cycle 1(1): loaded http://localhost:41667/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41667/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:25:16 INFO - PROCESS | 25869 | RSS: Main: 239976448 17:25:16 INFO - PROCESS | 25869 | 17:25:19 INFO - PROCESS | 25869 | Cycle 1(1): loaded http://localhost:41667/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41667/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:25:19 INFO - PROCESS | 25869 | RSS: Main: 236400640 17:25:19 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | Cycle 1(1): loaded http://localhost:41667/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41667/tests/kraken/driver.html?testName=ai-astar) 17:25:21 INFO - PROCESS | 25869 | RSS: Main: 285884416 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | __start_tp_report 17:25:21 INFO - PROCESS | 25869 | _x_x_mozilla_page_load 17:25:21 INFO - PROCESS | 25869 | _x_x_mozilla_page_load_details 17:25:21 INFO - PROCESS | 25869 | |i|pagename|runs| 17:25:21 INFO - PROCESS | 25869 | |0;ai-astar;110;115;118;115;115;115;115;116;115;115 17:25:21 INFO - PROCESS | 25869 | |1;audio-beat-detection;149;128;129;182;128;128;134;128;175;133 17:25:21 INFO - PROCESS | 25869 | |2;audio-dft;162;162;163;161;161;162;161;161;162;162 17:25:21 INFO - PROCESS | 25869 | |3;audio-fft;76;76;77;102;77;77;77;76;102;75 17:25:21 INFO - PROCESS | 25869 | |4;audio-oscillator;84;84;116;83;84;85;111;83;85;112 17:25:21 INFO - PROCESS | 25869 | |5;imaging-gaussian-blur;117;118;117;118;118;117;118;117;118;117 17:25:21 INFO - PROCESS | 25869 | |6;imaging-darkroom;146;147;146;146;147;146;146;147;146;146 17:25:21 INFO - PROCESS | 25869 | |7;imaging-desaturate;96;95;95;96;96;96;95;96;95;95 17:25:21 INFO - PROCESS | 25869 | |8;json-parse-financial;65;65;64;65;66;66;77;65;68;65 17:25:21 INFO - PROCESS | 25869 | |9;json-stringify-tinderbox;65;66;66;66;66;66;66;66;66;70 17:25:21 INFO - PROCESS | 25869 | |10;stanford-crypto-aes;86;85;83;85;94;85;84;84;90;84 17:25:21 INFO - PROCESS | 25869 | |11;stanford-crypto-ccm;147;145;142;145;141;141;143;146;187;143 17:25:21 INFO - PROCESS | 25869 | |12;stanford-crypto-pbkdf2;179;179;179;179;177;178;186;190;179;183 17:25:21 INFO - PROCESS | 25869 | |13;stanford-crypto-sha256-iterative;69;70;69;68;69;72;73;71;72;69 17:25:21 INFO - PROCESS | 25869 | __end_tp_report 17:25:21 INFO - PROCESS | 25869 | __start_cc_report 17:25:21 INFO - PROCESS | 25869 | _x_x_mozilla_cycle_collect,1977 17:25:21 INFO - PROCESS | 25869 | __end_cc_report 17:25:21 INFO - PROCESS | 25869 | __startTimestamp1479432321899__endTimestamp 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | ------- Summary: start ------- 17:25:21 INFO - PROCESS | 25869 | Number of tests: 14 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | [#0] ai-astar Cycles:10 Average:114.90 Median:115.00 stddev:1.97 (1.7%) stddev-sans-first:1.01 17:25:21 INFO - PROCESS | 25869 | Values: 110.0 115.0 118.0 115.0 115.0 115.0 115.0 116.0 115.0 115.0 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | [#1] audio-beat-detection Cycles:10 Average:141.40 Median:133.50 stddev:20.64 (15.5%) stddev-sans-first:21.70 17:25:21 INFO - PROCESS | 25869 | Values: 149.0 128.0 129.0 182.0 128.0 128.0 134.0 128.0 175.0 133.0 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 17:25:21 INFO - PROCESS | 25869 | Values: 162.0 162.0 163.0 161.0 161.0 162.0 161.0 161.0 162.0 162.0 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | [#3] audio-fft Cycles:10 Average:81.50 Median:76.50 stddev:10.82 (14.1%) stddev-sans-first:11.30 17:25:21 INFO - PROCESS | 25869 | Values: 76.0 76.0 77.0 102.0 77.0 77.0 77.0 76.0 102.0 75.0 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | [#4] audio-oscillator Cycles:10 Average:92.70 Median:84.00 stddev:14.08 (16.8%) stddev-sans-first:14.58 17:25:21 INFO - PROCESS | 25869 | Values: 84.0 84.0 116.0 83.0 84.0 85.0 111.0 83.0 85.0 112.0 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 17:25:21 INFO - PROCESS | 25869 | Values: 117.0 118.0 117.0 118.0 118.0 117.0 118.0 117.0 118.0 117.0 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 17:25:21 INFO - PROCESS | 25869 | Values: 146.0 147.0 146.0 146.0 147.0 146.0 146.0 147.0 146.0 146.0 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 17:25:21 INFO - PROCESS | 25869 | Values: 96.0 95.0 95.0 96.0 96.0 96.0 95.0 96.0 95.0 95.0 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | [#8] json-parse-financial Cycles:10 Average:66.60 Median:65.50 stddev:3.81 (5.8%) stddev-sans-first:3.99 17:25:21 INFO - PROCESS | 25869 | Values: 65.0 65.0 64.0 65.0 66.0 66.0 77.0 65.0 68.0 65.0 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.00 stddev:1.34 (2.0%) stddev-sans-first:1.33 17:25:21 INFO - PROCESS | 25869 | Values: 65.0 66.0 66.0 66.0 66.0 66.0 66.0 66.0 66.0 70.0 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:85.00 stddev:3.40 (4.0%) stddev-sans-first:3.61 17:25:21 INFO - PROCESS | 25869 | Values: 86.0 85.0 83.0 85.0 94.0 85.0 84.0 84.0 90.0 84.0 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:145.00 stddev:13.86 (9.6%) stddev-sans-first:14.69 17:25:21 INFO - PROCESS | 25869 | Values: 147.0 145.0 142.0 145.0 141.0 141.0 143.0 146.0 187.0 143.0 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.90 Median:179.00 stddev:4.15 (2.3%) stddev-sans-first:4.34 17:25:21 INFO - PROCESS | 25869 | Values: 179.0 179.0 179.0 179.0 177.0 178.0 186.0 190.0 179.0 183.0 17:25:21 INFO - PROCESS | 25869 | 17:25:21 INFO - PROCESS | 25869 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:70.50 stddev:1.69 (2.4%) stddev-sans-first:1.73 17:25:21 INFO - PROCESS | 25869 | Values: 69.0 70.0 69.0 68.0 69.0 72.0 73.0 71.0 72.0 69.0 17:25:21 INFO - PROCESS | 25869 | -------- Summary: end -------- 17:25:21 INFO - PROCESS | 25869 | 17:25:23 INFO - TEST-INFO | 25869: exit 0 17:25:23 INFO - TEST-OK | kraken | took 64968ms 17:25:23 INFO - SUITE-END | took 1187s 17:25:23 INFO - Completed test suite (00:19:47) 17:25:23 INFO - javascript benchmark 17:25:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2500.81243906707, 2540.41570438799, 2552.170544573014, 2553.8978440862365, 2560.039985005623, 4366.607005369516, 4421.027373977728, 4432.844850430236, 4479.798409071587, 4505.324201369792, 4447.955520444794, 4448.888777780557, 4464.218761716703, 4468.10637872426, 4474.709143905652, 4070.0231944333295, 4085.9591404086023, 4098.139390727951, 4132.318167502359, 4136.875893723178, 4102.0, 4135.524414692304, 4165.396017577449, 4186.099988502471, 4197.7271477354025, 4112.6298633123015, 4166.83332666693, 4167.187398457302, 4169.103642716816, 4171.103212809246, 4114.506259248879, 4180.811863466151, 4204.705670603059, 4211.22092412903, 4225.746455212667, 4099.713020088595, 4156.189543039109, 4156.272652285868, 4182.414461975326, 4198.391233271159, 4263.573642635712, 4291.184674911788, 4293.849715259982, 4396.054848207618, 4426.468823741139, 4753.382060332169, 4771.069641419925, 4779.545943135431, 4780.904381912343, 4819.204831202847, 4736.465110726435, 4774.259989701602, 4774.546418090275, 4790.856274311776, 4824.107540105092, 4361.411209486742, 4423.712458690175, 4451.77741112943, 4483.820647174077, 4521.819127234907, 4837.806487740451, 4863.197572400514, 4866.95133048665, 4871.318015477836, 4917.360743103445, 4630.884227894329, 4672.159011377985, 4676.111538807616, 4706.105839890478, 4713.646476514206, 4557.908841823145, 4596.931046034312, 4599.425071866017, 4754.7147171169845, 4759.262314341283, 4640.651951103613, 4697.929531057037, 4703.0, 4710.26990816424, 4766.4280286365165, 4683.274092515666, 4695.5304469552775, 4695.835645752382, 4700.717956922527, 4738.076075165379, 3941.152652179795, 4028.879133625996, 4029.6373326400767, 4040.8585699500964, 4074.694397920168, 3499.2126771476214, 3506.50908872758, 3528.4707293906117, 3535.5580552430947, 3536.186677064238, 4098.016476045787, 4099.85650502231, 4126.112885729583, 4142.068034692231, 4142.378643203483, 4760.7619619018915, 4769.499202583748, 4772.141014617333, 4778.904421911542, 4841.685290456109, 3958.287508248543, 3999.5200575931076, 4030.0932290234464, 4099.446574712376, 4150.667946564267, 4244.04508985482, 4268.509121451073, 4268.893277668083, 4299.9570004300185, 4304.612584867377, 4436.889077773081, 4437.556244375537, 4463.464384273908, 4474.261746811805, 4475.0, 4601.516840731675, 4645.86062418128, 4737.715737055788, 4744.122337367598, 4748.952510474851, 4405.889852753578, 4406.471223453206, 4463.687541872037, 4475.328700694922, 4507.256302710016, 4716.0332131913565, 4717.3631559739, 4720.551547602972, 4762.0, 4763.452202996561, 4730.47964723887, 4774.641901857444, 4780.043991201843, 4782.258749893772, 4822.035592881368, 4484.439445069366, 4519.209138400833, 4557.225271703885, 4650.651201159994, 4655.162070827284, 4529.750863702397, 4539.409876716138, 4539.704919180374, 4541.205289074464, 4542.273236282179, 4423.137488189772, 4495.595396414338, 4503.887402814825, 4504.459464864237, 4505.526919673518, 4533.54664533544, 4687.554682305175, 4703.835365762182, 4706.62347012245, 4716.056788642217, 4558.726476411426, 4559.794809233659, 4606.170889239969, 4606.861794146181, 4623.190941585276], "value": 4364.250829006928, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.5196730811502, 561.4946548106702, 579.7420148034124, 585.1749033862255, 591.5090474905828, 21604.351869443923, 27616.861915690417, 28218.0, 28283.29291767704, 29982.400351992947, 27700.722992770065, 27960.440791184163, 28009.29976750582, 28052.719472805267, 28063.438731225364, 5203.401608814978, 5341.06531357013, 5356.35723713153, 5358.142697168434, 5370.0, 5447.210154527591, 5511.862203444906, 5515.862103447405, 5521.585881058916, 5532.502074813266, 5536.280283563131, 5569.081101618228, 5575.219469274305, 5577.079781836012, 5577.470140336682, 5534.003879301724, 5551.056320425538, 5558.138488534284, 5562.499375056264, 5565.9443405565835, 5501.9174712379345, 5534.391216966131, 5553.4724201200825, 5558.722063896789, 5559.527440167591, 25460.872695636404, 25628.74371256301, 25634.615480767803, 25791.0, 25807.0, 2811.6485439320086, 2932.6627437844495, 2955.438466691322, 3007.248187953012, 3009.8344591047485, 17717.379891703727, 17879.016654084022, 17893.463196104138, 17901.283948642038, 17970.640587188314, 2641.339665083729, 2652.880620372088, 2695.9191224263304, 2787.484315401637, 2788.623535822658, 234047.0, 235788.0, 236054.0, 236553.0, 237115.0, 30055.549166762517, 49080.7545962275, 49418.75290623524, 49449.25826112611, 49476.752616236685, 32322.676773232437, 32466.83766581152, 32523.34953300921, 32576.67423325784, 32743.50884736731, 12551.748965020648, 12711.491540338468, 12713.872861271453, 12713.93643031779, 12714.936425318, 10329.431881246604, 10367.585296588202, 10375.481225938824, 10380.480975951172, 10381.221408394249, 7584.127825300051, 7599.65801538921, 7687.807804804925, 7688.500247483896, 7688.654010569426, 3016.909492715222, 3044.071558174778, 3050.222193340684, 3079.59965204522, 3101.7673674474504, 815.4291995603102, 825.7852958230783, 845.6913226672343, 865.9696910608099, 899.8065415935605, 25421.49157016849, 26058.869705651352, 26524.60213096805, 26821.329466763487, 26912.0, 933.5239028095721, 936.3398803843411, 949.9050094990446, 953.7091187187974, 986.8667729856378, 13636.590902272948, 14417.567472975827, 14535.563933082025, 14817.703645927237, 14913.92543037278, 358282.0, 360490.0, 361295.0, 362489.0, 362856.0, 42934.57065429306, 43547.0, 43575.56424435778, 43614.0, 43647.781761091625, 1007.1288335589662, 1042.5777560088025, 1167.2529581068122, 1191.4995701805262, 1194.474431250247, 25385.492290153725, 26199.345016374744, 26652.675760755665, 26673.33316667099, 26790.732092678823, 40934.1813163741, 41214.79392603018, 41261.0, 41283.587164127974, 41289.79355103205, 278456.0, 282499.0, 282543.0, 282733.0, 284224.0, 22823.657645135005, 22884.199053033062, 23036.193733219257, 23297.18459853897, 23367.76632233724, 35310.0, 35445.82277088598, 35601.643983559836, 35738.10654733652, 35743.46384804282, 30649.233769155948, 30950.53574196434, 30993.535096974014, 31882.36235275328, 32016.199595010312, 31025.534616981215, 31377.215569610944, 31719.682803171672, 32287.35425291528, 33743.15642108967, 28689.13932582026, 29668.258293542833, 32257.67742322547, 32661.18347041343, 33400.83299583486, 27326.72673273242, 28382.148535543965, 29365.41269174562, 29505.0, 30485.847570762005, 22685.773142268365, 22812.4296892579, 22926.426839328484, 23544.529109418057, 23576.0], "value": 14122.141879512566, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.1010083882743, 355.0874253169355, 355.88433759028317, 365.1564885115384, 371.14636336426213, 11286.887131128697, 11551.249168804032, 12307.38463076847, 12379.69050773731, 12400.193987390836, 13192.868071319284, 13414.26221557814, 13448.596542103727, 13803.0, 14098.083624564397, 4013.819378127991, 4293.677974151936, 4297.333913243452, 4297.871063868089, 4299.0, 4039.4950631171105, 4129.761071678499, 4194.622483976442, 4220.831166753342, 4227.661787057028, 4192.287311157095, 4201.579842015789, 4209.179210054041, 4209.6421804146685, 4210.305299625558, 4094.06664968712, 4157.293260145782, 4195.119025004753, 4195.85314513992, 4198.223328684203, 3809.5238095238096, 3835.9616403835885, 3840.36633955397, 3840.9423858642144, 3841.78870162141, 16339.26473308704, 16404.507864764077, 16494.83505164957, 16530.338786448647, 16668.41660541887, 1894.7536820213422, 2105.715728376665, 2434.6834911461397, 2469.4320306329464, 2539.02247634662, 10165.644202452953, 10560.366378017266, 10584.417857017861, 10988.615398461096, 11122.110231181487, 1299.3762993762953, 1367.7743172376545, 1445.913245205291, 1449.1015570346347, 1509.0794615284776, 40811.38782918259, 41291.58708412937, 48390.27414588784, 61387.38612613861, 66623.66688166576, 9292.0, 9443.575039123118, 9614.278929080347, 9770.706878793648, 9968.750781230383, 12727.045471589669, 12768.425420856083, 12785.232886026868, 12841.486340546273, 12855.29295888735, 6762.019507171432, 6784.253732089466, 6922.0, 6932.514723969374, 6992.650367481606, 6315.494760419156, 6480.189976252968, 6572.309907459744, 6671.899921501182, 6679.365460281266, 3995.280849447069, 3997.92004159921, 4012.7592344459426, 4173.937390939138, 4223.1553689261655, 1710.264586227934, 1712.4263371770348, 1723.4571110100278, 1792.8794503435354, 1802.1890149432545, 766.7853001159684, 819.5697258938985, 832.704389941562, 847.8855354527183, 897.7127319257905, 16011.439599613957, 16543.586410339838, 16546.58633534147, 16885.15574221284, 17509.7373539397, 823.5717426938079, 827.2596026556198, 851.4423052900361, 873.1879352202512, 925.1627277314042, 8950.194482496607, 9612.663556775618, 9748.512574371394, 9779.804403912025, 9894.901050989398, 326244.0, 330976.0, 332129.0, 333914.0, 357953.0, 48597.271040934414, 57706.7114664424, 77255.2274477248, 77755.22244777593, 82368.58815706003, 830.1034882327073, 836.4437648963371, 842.9452085614538, 849.5794581682106, 850.2985037344216, 14987.0, 15124.39502419891, 15398.230088495755, 15690.529284121494, 15777.211139443212, 36836.0, 37835.0, 38923.22153556856, 38983.805080974416, 40112.1977560453, 52036.73981630067, 52087.0, 52650.0, 52805.0, 53162.734186330366, 7216.639168041682, 7323.853522929405, 7431.3683336916965, 7539.246075392417, 7544.6227688616445, 9079.727608171765, 11321.830172547583, 11504.82742758876, 11604.0, 12280.0, 10186.541605627617, 10229.386236825814, 10447.843282350923, 10564.207684423853, 10814.405207713651, 9912.306138570231, 9975.551100200653, 10076.596936122767, 10145.64490242838, 10316.43259620698, 9942.0, 9945.353552019096, 10584.682459526226, 10612.29386506436, 10838.729031774268, 7207.531510451804, 7274.454415918722, 7444.813879653052, 7454.701811927679, 7744.070711514654, 8364.289035432057, 9919.107280344804, 10095.091441770275, 10216.438095904796, 10464.790704185722], "value": 7533.588299880954, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1201.2012012012012, 1207.3057991654798, 1225.871283515231, 1285.3187810460456, 1629.9511014669565, 5520.972395138024, 6044.153818465413, 6253.062040693898, 6259.0, 6387.744490220397, 6276.874462510747, 6337.334579869105, 6340.778072767454, 6343.333949935248, 6345.270293916201, 1745.65959637871, 1772.1847949943042, 1775.2898840463788, 1777.4045694692293, 1778.1820362633139, 1643.2276829889934, 1645.169189559277, 1659.145540046877, 1660.286076986895, 1757.3497805811867, 1725.7583938248592, 1748.029843436892, 1748.370586588827, 1749.5713550180224, 1750.1074452029493, 1706.3626070201171, 1722.8966262024255, 1726.0851748573277, 1727.7322015087682, 1728.8271172882737, 1660.850523452895, 1704.2245778171027, 1717.2015013018881, 1721.6212433264661, 1721.6384559242574, 6038.728257228435, 6204.87590248197, 6242.500599951993, 6250.531210159211, 6250.9062364064575, 4004.2992476316817, 4158.8336466541305, 4172.541020487774, 4177.436046133764, 4180.163967206571, 2553.3871870750886, 2695.3261684578856, 2763.6545431821023, 2764.0187733354774, 2766.294594878293, 772.9652165652503, 782.3701919954491, 801.1187693537063, 802.2498963469117, 802.7190483330846, 8567.100454452255, 8688.3049356052, 8708.346873984414, 8723.12768723129, 8724.302055835582, 3485.5186398836263, 3585.3546361655153, 3593.7484376093944, 3669.2478042001326, 3672.9632703672623, 7065.4700897432895, 7067.187273463514, 7067.787966361017, 7077.186123595852, 7081.398081163159, 4010.3984402339297, 4050.5746896576024, 4051.3922911562913, 4058.411530328086, 4059.3707975263883, 3407.2504049109157, 3515.490253913168, 3706.666400023957, 3777.8299976501125, 3779.60314167014, 2938.8971386001813, 2981.567672687448, 2994.1766014345794, 3057.98471007648, 3077.5229839374933, 888.5868071346855, 914.6798620482778, 915.6017132547363, 919.1497864475333, 922.064104933493, 728.0644371981978, 738.446165375968, 741.9109706835106, 756.4100001998451, 783.416354815666, 4461.933071003938, 4518.638508919278, 4518.932216016762, 4583.587477127075, 4601.493835678073, 778.1945686214741, 783.7570353190529, 806.5362416610473, 859.3941271403645, 862.0646598440762, 3047.6190476190477, 3075.7693173012112, 3085.2441151917924, 3113.3461972985474, 3168.952465713016, 7309.926900730925, 7362.9631851840395, 7536.623168841536, 7613.0483689538805, 7680.615969201517, 7797.220277972157, 7811.179826118289, 7814.257645523666, 7814.882776758353, 7836.529808211526, 5887.676177810262, 6050.697465126814, 6074.271087469532, 6083.726232319467, 6088.178095957078, 4270.402143699823, 4389.209942210433, 4396.802143903468, 4499.482559505699, 4608.815647374067, 7282.45381596393, 7290.781276561712, 7379.3727533158135, 7417.480776345604, 7437.256274372519, 7388.704451821867, 7594.658240379085, 7602.163761986067, 7636.541807491568, 7647.158812530506, 5658.122990936411, 5667.3765885753355, 5684.602077854511, 5703.287089113861, 5741.598088133957, 5492.91760623583, 5743.282089738783, 6052.364501727256, 6172.135900973957, 6195.349488303663, 5748.39641837601, 5752.9137062943255, 5836.182934389274, 5844.6785426801935, 5965.701714914324, 5658.462446067617, 5672.0, 5693.259876216065, 5705.714714264187, 5744.167095771007, 5018.444282272361, 5111.284420181103, 5136.640435169502, 5144.202648589475, 5156.0, 3972.702047346518, 3980.2238563479846, 4008.599140085968, 4070.4097905804083, 4179.561146079739, 3776.8489260429274, 3884.5532763731403, 3943.507061617298, 3985.3822657488136, 4023.8994025149605], "value": 3461.433740330391, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.50781608736446, 281.1845647621896, 282.1788595188003, 289.773976298487, 292.26058073075114, 4559.88600284993, 4694.859154225374, 4719.48085710572, 4728.55078767517, 4734.005858769654, 4675.532446755323, 4687.765611719409, 4694.600958918496, 4703.0, 4703.459102203247, 3406.841673830897, 3530.6469353064745, 3649.901306748617, 3664.413693808991, 3682.6317368263226, 3505.4040813061715, 3591.42537194049, 3594.442861356481, 3601.2437388148387, 3615.9276814463697, 3544.6455354464606, 3548.172691064091, 3560.6439356064448, 3563.803990780506, 3582.1223800168864, 3413.7027929386923, 3519.348920449725, 3569.7505872944394, 3583.6416358364218, 3590.1922067534724, 3070.6468756093113, 3118.0022392834303, 3125.484295091307, 3128.577642018321, 3144.4215743090676, 4516.864494065183, 4517.141743068806, 4532.048269863332, 4541.477730061019, 4569.2003899317815, 4110.260153172428, 4111.218868414991, 4118.217538667644, 4121.608447197512, 4150.232207041707, 4644.303354496852, 4836.613070954315, 4862.939445562959, 4877.317175595384, 4893.672302834071, 3409.1817963688786, 3411.1472131967007, 3412.744044196695, 3415.7608967372294, 3416.6754158354915, 5128.8461346159675, 5158.329417175781, 5172.2241663750365, 5185.974070129663, 5203.973980130075, 3316.9834150829092, 3451.2752322012657, 3463.6882680558365, 3466.474751109504, 3474.4961980512685, 3535.5528359849454, 3537.6816086552335, 3541.184758117303, 3550.5206797082064, 3553.928921421557, 4062.7765472899273, 4068.7762173080764, 4083.448289649914, 4087.8773636790943, 4088.3049881520215, 4859.878503037382, 4879.512048795092, 4892.19278818998, 4893.241547560134, 4908.460069392364, 1140.5323817234894, 1190.2087358525364, 1192.2369683402628, 1194.4923407551755, 1195.6966906072248, 791.6818497202094, 808.6159074439594, 821.7247222180517, 824.9010118785784, 825.4263287015586, 4135.834566617362, 4143.751374917515, 4149.958500414958, 4230.1539692061715, 4248.55549113303, 4925.162722337212, 4932.383452068491, 4939.061578300111, 4946.356973593482, 4958.355413796256, 4284.91430171401, 4304.74171549708, 4321.762303073361, 4322.178786030644, 4336.371226172188, 2533.505966336547, 2573.189445324717, 2596.40282734972, 2596.740325967426, 2620.4104076583, 4598.448186217675, 4607.95392046082, 4621.5378462154185, 4622.67641265115, 4649.418822647169, 4983.152864013054, 4997.25041243809, 5010.974945125324, 5019.598432125495, 5072.391313042459, 4441.444819397575, 4588.197065513522, 4619.838305659286, 4623.907521849612, 4733.715977041251, 5055.0, 5064.645474816727, 5072.82245121419, 5073.213651883964, 5099.311592935054, 4948.950510494849, 4951.752412379438, 4976.197968959971, 4984.626153038609, 5015.448300686994, 4650.674452788272, 4747.715137091785, 4758.857234282977, 4775.7583028413555, 4788.233882578771, 3469.809160496197, 3470.2885908389226, 3479.756417050782, 3487.319972605419, 3490.0, 5156.607715916756, 5161.458046905021, 5162.819301324435, 5165.302684137593, 5168.328117344721, 4757.526086434965, 4945.7527123644395, 4969.254611808257, 4971.552560269593, 4976.950230497649, 4915.410150781929, 4917.237828136645, 4923.876903077452, 4951.752412379438, 4953.934903995658, 4810.134175848241, 4823.469418363907, 4829.492903245249, 4842.5157484251295, 4874.53691899269, 4746.5490778376, 4925.21196608541, 4933.9506604933495, 4945.925811112764, 4978.2034874419915, 4792.904141917212, 4794.688345257547, 4795.0, 4797.736124513185, 4801.831935882227], "value": 3648.2279135356066, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.4622946888862, 601.2694576090049, 601.5488383712216, 647.9546431749774, 658.7463826426828, 10516.106130978857, 10532.47337633119, 11104.056155226815, 11286.830697539534, 11353.545858165673, 10868.728281792959, 11095.4452277386, 11107.944460277708, 11109.333439993587, 11125.388103654295, 734.8750712378895, 754.8414832885073, 754.8829931360648, 755.7128291249318, 757.1482082657011, 602.5541099586296, 625.8153844615849, 727.9818004549876, 748.6705849426235, 755.5391211361066, 795.6220795122329, 797.8523973064972, 798.0064819299951, 798.9041315042174, 798.9480683755567, 787.2796391301952, 787.6180052674448, 788.621461698388, 789.2738680414033, 795.0499153511556, 453.1887920622083, 460.2153328574772, 556.3407362275676, 573.1917995626147, 573.237593999979, 9984.500774961223, 10012.749681257954, 10029.348092373972, 10050.246231532665, 10056.245781566338, 8186.386021048385, 8363.0, 8369.288610468118, 8490.320774338039, 9034.503102329369, 8109.878351824727, 8163.224493673151, 8165.877511837327, 8184.7953801155445, 8195.221453961864, 2407.205622144688, 2435.257246539805, 2445.42532504863, 2459.114718701266, 2488.191337815217, 46925.765371173264, 48639.0, 49212.753936230445, 49340.50659493395, 49617.75191124057, 6830.863382732317, 6858.00558918954, 6966.338197871194, 7170.4980651354945, 7179.712811487587, 13552.59342219735, 13565.932170339085, 13668.93165534166, 13816.516421925386, 13828.792568111487, 6051.273847138284, 6083.300420451616, 6146.0470489811505, 6211.16149319845, 6370.426661600478, 3524.488949102417, 3541.6812486876306, 3554.5201397810965, 3616.8553257869917, 3616.8914932552066, 2459.913903013386, 2559.7312282210473, 2560.001599376245, 2560.270322957948, 2561.9487610248066, 440.9272470042407, 457.83517933543897, 457.9862604121882, 460.27047130298183, 463.6777439679458, 408.2691981353344, 456.1652176516966, 493.3019777015483, 501.2631431795255, 501.34574380433884, 6667.866642667216, 6673.599584024974, 6706.185258367778, 6708.597484150869, 7018.473614478935, 489.801630339713, 497.32612310318996, 502.1438447447094, 507.3151245818116, 510.82631905151686, 3251.4147453458604, 3325.118843506473, 3392.9151771205425, 3409.914752131167, 3444.51776751255, 61831.38168618256, 62361.376386236465, 65799.0, 66082.33917660858, 66740.6662966682, 54001.0, 54287.45712542903, 55009.449905500434, 55133.173002405, 55927.16109258364, 50304.0, 51175.23237151446, 52211.0, 52325.47674523206, 54254.18618720801, 6699.296573859675, 6760.1549806274215, 7044.119485064367, 7055.118110236221, 7063.081799366255, 34754.478682820285, 35000.2731060099, 35850.28299434049, 35873.82063089668, 36245.09387265339, 43665.0, 47217.291740624605, 47573.52426475829, 47799.76100119477, 48104.75947620379, 8535.146485351415, 8619.396642235235, 8763.693270735494, 8789.252913502169, 9346.252299815862, 9884.456354900549, 10098.8485172721, 10171.593136274467, 10269.178465722875, 10339.431331276852, 6938.340857618518, 8491.490510569385, 8622.396432249932, 9133.680321188727, 9155.450672959643, 8656.350773691873, 9160.129787670161, 9361.485118318558, 9367.156955874005, 9427.0, 8920.73237802867, 9093.545322733704, 9632.27757918145, 9640.42157470554, 9665.3234273603, 7398.445116616383, 7471.775846724607, 7777.688892444239, 7783.649735762017, 7824.176696073007, 7752.573608451364, 8039.638216280398, 8404.411691181558, 8433.704820331259, 8467.0], "value": 5306.941458597617, "unit": "score"}], "name": "dromaeo_css", "value": 5608.421903876864, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 115.0, 118.0, 115.0, 115.0, 115.0, 115.0, 116.0, 115.0, 115.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 128.0, 129.0, 182.0, 128.0, 128.0, 134.0, 128.0, 175.0, 133.0], "value": 141.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 161.0, 161.0, 162.0, 161.0, 161.0, 162.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 102.0, 77.0, 77.0, 77.0, 76.0, 102.0, 75.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 116.0, 83.0, 84.0, 85.0, 111.0, 83.0, 85.0, 112.0], "value": 92.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 118.0, 117.0, 118.0, 117.0, 118.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 65.0, 66.0, 66.0, 77.0, 65.0, 68.0, 65.0], "value": 66.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 70.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 83.0, 85.0, 94.0, 85.0, 84.0, 84.0, 90.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 142.0, 145.0, 141.0, 141.0, 143.0, 146.0, 187.0, 143.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 179.0, 179.0, 179.0, 177.0, 178.0, 186.0, 190.0, 179.0, 183.0], "value": 180.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 69.0, 68.0, 69.0, 72.0, 73.0, 71.0, 72.0, 69.0], "value": 70.2, "unit": "score"}], "name": "kraken", "value": 1569.5000000000002, "alertThreshold": 2.0}]} 17:25:23 INFO - Return code: 0 17:25:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:25:23 INFO - # TBPL SUCCESS # 17:25:23 INFO - Running post-action listener: _resource_record_post_action 17:25:23 INFO - [mozharness: 2016-11-18 01:25:23.695080Z] Finished run-tests step (success) 17:25:23 INFO - Running post-run listener: _resource_record_post_run 17:25:23 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 4096; Write bytes: 391180288; Read time: 12; Write time: 320952 17:25:23 INFO - TinderboxPrint: CPU usage
13.0% 17:25:23 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 17:25:23 INFO - TinderboxPrint: I/O write bytes / time
391,180,288 / 320,952 17:25:23 INFO - TinderboxPrint: CPU idle
8,353.3 (86.9%) 17:25:23 INFO - TinderboxPrint: CPU user
1,204.2 (12.5%) 17:25:23 INFO - TinderboxPrint: Swap in / out
0 / 0 17:25:23 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99860480; Read time: 0; Write time: 110128 17:25:23 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 291319808; Read time: 0; Write time: 210824 17:25:24 INFO - Running post-run listener: _upload_blobber_files 17:25:24 INFO - Blob upload gear active. 17:25:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:25:24 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 17:25:24 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'] 17:25:24 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 17:25:24 INFO - (blobuploader) - INFO - Open directory for files ... 17:25:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:25:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:25:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:25 INFO - (blobuploader) - INFO - Done attempting. 17:25:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:25:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:25:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:30 INFO - (blobuploader) - INFO - Done attempting. 17:25:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:25:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:25:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:31 INFO - (blobuploader) - INFO - Done attempting. 17:25:31 INFO - (blobuploader) - INFO - Iteration through files over. 17:25:31 INFO - Return code: 0 17:25:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:25:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:25:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ba6b4c0fa215a1823374f0f71d2e00f7bfd7e7181790734d896b1a60d7e93d5751681e4ed286160b31cb2bc134a6d0bfe9d08bcde7326efa1a66c7847f033925", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9c61173b358def26365be2dd309527908bf7c7922a94d8dcb9708cae6b779eb1942a4546014cd02c5b127db78065708fd6e2a88e76e4904c0153c46a6febd219", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3aeab6c7f08985a4c0b5eb633a6f731c8b9ede706e08dc5eb23c9ecbc741e77fcff1c40410f3a3994340bda689f44d3498479a128bbbeb889f9e2f16c23d7915"} 17:25:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:25:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:25:31 INFO - Contents: 17:25:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ba6b4c0fa215a1823374f0f71d2e00f7bfd7e7181790734d896b1a60d7e93d5751681e4ed286160b31cb2bc134a6d0bfe9d08bcde7326efa1a66c7847f033925", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9c61173b358def26365be2dd309527908bf7c7922a94d8dcb9708cae6b779eb1942a4546014cd02c5b127db78065708fd6e2a88e76e4904c0153c46a6febd219", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3aeab6c7f08985a4c0b5eb633a6f731c8b9ede706e08dc5eb23c9ecbc741e77fcff1c40410f3a3994340bda689f44d3498479a128bbbeb889f9e2f16c23d7915"} 17:25:31 INFO - Running post-run listener: copy_logs_to_upload_dir 17:25:31 INFO - Copying logs to upload dir... 17:25:31 INFO - mkdir: /builds/slave/test/build/upload/logs 17:25:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.885602 ========= master_lag: 0.40 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-11-17 17:25:30.867818) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 17:25:30.870996) ========= 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=836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ba6b4c0fa215a1823374f0f71d2e00f7bfd7e7181790734d896b1a60d7e93d5751681e4ed286160b31cb2bc134a6d0bfe9d08bcde7326efa1a66c7847f033925", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9c61173b358def26365be2dd309527908bf7c7922a94d8dcb9708cae6b779eb1942a4546014cd02c5b127db78065708fd6e2a88e76e4904c0153c46a6febd219", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3aeab6c7f08985a4c0b5eb633a6f731c8b9ede706e08dc5eb23c9ecbc741e77fcff1c40410f3a3994340bda689f44d3498479a128bbbeb889f9e2f16c23d7915"} build_url:https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009540 build_url: 'https://queue.taskcluster.net/v1/task/FZ5Rq3klRzCQ0diTOQAKUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ba6b4c0fa215a1823374f0f71d2e00f7bfd7e7181790734d896b1a60d7e93d5751681e4ed286160b31cb2bc134a6d0bfe9d08bcde7326efa1a66c7847f033925", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9c61173b358def26365be2dd309527908bf7c7922a94d8dcb9708cae6b779eb1942a4546014cd02c5b127db78065708fd6e2a88e76e4904c0153c46a6febd219", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3aeab6c7f08985a4c0b5eb633a6f731c8b9ede706e08dc5eb23c9ecbc741e77fcff1c40410f3a3994340bda689f44d3498479a128bbbeb889f9e2f16c23d7915"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 17:25:30.894852) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 17:25:30.895175) ========= 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=836cfdc75598d54cb747368e000001a9-1479431081.80275-1799306828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004537 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 17:25:30.945526) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 17:25:30.945846) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 17:25:30.946133) ========= ========= Total master_lag: 0.51 =========