builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-045 starttime: 1474129645.57 results: success (0) buildid: 20160917010517 builduid: 05b90feeea334f6a9acad1be365ec998 revision: f4be1a7f9b3ee81960fa2f81a55c87ea11784bb2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:25.567466) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:25.568066) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:25.568401) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:25.583738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:25.584181) ========= 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=4300f881736b422476174874000001bc-1474129645.388585-1764405660 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 09:27:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 28.0M=0s 2016-09-17 09:27:26 (28.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.090764 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:25.691515) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:25.692085) ========= 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=4300f881736b422476174874000001bc-1474129645.388585-1764405660 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020306 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:25.745340) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:25.746086) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev f4be1a7f9b3ee81960fa2f81a55c87ea11784bb2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev f4be1a7f9b3ee81960fa2f81a55c87ea11784bb2 --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=4300f881736b422476174874000001bc-1474129645.388585-1764405660 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 09:27:26,314 truncating revision to first 12 chars 2016-09-17 09:27:26,314 Setting DEBUG logging. 2016-09-17 09:27:26,314 attempt 1/10 2016-09-17 09:27:26,314 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/f4be1a7f9b3e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 09:27:26,840 unpacking tar archive at: autoland-f4be1a7f9b3e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.860930 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:26.621099) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:26.621485) ========= script_repo_revision: f4be1a7f9b3ee81960fa2f81a55c87ea11784bb2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:26.621959) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:26.622275) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 09:27:26.632991) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-09-17 09:27:26.633330) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] 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=4300f881736b422476174874000001bc-1474129645.388585-1764405660 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:27:27 INFO - MultiFileLogger online at 20160917 09:27:27 in /builds/slave/test 09:27:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 09:27:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:27:27 INFO - {'append_to_log': False, 09:27:27 INFO - 'base_work_dir': '/builds/slave/test', 09:27:27 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 09:27:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:27:27 INFO - 'branch': 'Autoland-Non-PGO', 09:27:27 INFO - 'buildbot_json_path': 'buildprops.json', 09:27:27 INFO - 'config_files': ('talos/linux_config.py',), 09:27:27 INFO - 'default_actions': ('clobber', 09:27:27 INFO - 'read-buildbot-config', 09:27:27 INFO - 'download-and-extract', 09:27:27 INFO - 'populate-webroot', 09:27:27 INFO - 'create-virtualenv', 09:27:27 INFO - 'install', 09:27:27 INFO - 'run-tests'), 09:27:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:27:27 INFO - 'download_minidump_stackwalk': True, 09:27:27 INFO - 'download_symbols': 'ondemand', 09:27:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:27:27 INFO - 'tooltool.py': '/tools/tooltool.py', 09:27:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:27:27 INFO - '/tools/misc-python/virtualenv.py')}, 09:27:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:27:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:27:27 INFO - 'installer_path': 'installer.exe', 09:27:27 INFO - 'log_level': 'info', 09:27:27 INFO - 'log_name': 'talos', 09:27:27 INFO - 'log_to_console': True, 09:27:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:27:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:27:27 INFO - 'opt_config_files': (), 09:27:27 INFO - 'pip_index': False, 09:27:27 INFO - 'sps_profile': False, 09:27:27 INFO - 'sps_profile_interval': 0, 09:27:27 INFO - 'suite': 'dromaeojs', 09:27:27 INFO - 'system_bits': '32', 09:27:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:27:27 INFO - 'title': 'talos-linux64-ix-045', 09:27:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:27:27 INFO - 'use_talos_json': True, 09:27:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:27:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:27:27 INFO - 'work_dir': 'build'} 09:27:27 INFO - [mozharness: 2016-09-17 16:27:27.299665Z] Running clobber step. 09:27:27 INFO - Running pre-action listener: _resource_record_pre_action 09:27:27 INFO - Running main action method: clobber 09:27:27 INFO - rmtree: /builds/slave/test/build 09:27:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:27:27 INFO - Running post-action listener: _resource_record_post_action 09:27:27 INFO - [mozharness: 2016-09-17 16:27:27.770598Z] Finished clobber step (success) 09:27:27 INFO - [mozharness: 2016-09-17 16:27:27.770701Z] Running read-buildbot-config step. 09:27:27 INFO - Running pre-action listener: _resource_record_pre_action 09:27:27 INFO - Running main action method: read_buildbot_config 09:27:27 INFO - Using buildbot properties: 09:27:27 INFO - { 09:27:27 INFO - "project": "", 09:27:27 INFO - "product": "firefox", 09:27:27 INFO - "script_repo_revision": "production", 09:27:27 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 09:27:27 INFO - "repository": "", 09:27:27 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 09:27:27 INFO - "buildid": "20160917010517", 09:27:27 INFO - "pgo_build": "False", 09:27:27 INFO - "basedir": "/builds/slave/test", 09:27:27 INFO - "buildnumber": 657, 09:27:27 INFO - "slavename": "talos-linux64-ix-045", 09:27:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:27:27 INFO - "platform": "ubuntu64_hw", 09:27:27 INFO - "scheduler": "tests-autoland-linux64-talos", 09:27:27 INFO - "branch": "autoland", 09:27:27 INFO - "repo_path": "integration/autoland", 09:27:27 INFO - "revision": "f4be1a7f9b3ee81960fa2f81a55c87ea11784bb2", 09:27:27 INFO - "stage_platform": "linux64", 09:27:27 INFO - "builduid": "05b90feeea334f6a9acad1be365ec998", 09:27:27 INFO - "slavebuilddir": "test" 09:27:27 INFO - } 09:27:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 09:27:27 INFO - Running post-action listener: _resource_record_post_action 09:27:27 INFO - [mozharness: 2016-09-17 16:27:27.773166Z] Finished read-buildbot-config step (success) 09:27:27 INFO - [mozharness: 2016-09-17 16:27:27.773256Z] Running download-and-extract step. 09:27:27 INFO - Running pre-action listener: _resource_record_pre_action 09:27:27 INFO - Running main action method: download_and_extract 09:27:27 INFO - mkdir: /builds/slave/test/build/tests 09:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:27 INFO - trying https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:27:27 INFO - Downloading https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:27:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:27:28 INFO - Downloaded 1361 bytes. 09:27:28 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:27:28 INFO - Using the following test package requirements: 09:27:28 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:27:28 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:27:28 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:27:28 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:28 INFO - u'jsshell-linux-x86_64.zip'], 09:27:28 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:27:28 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:27:28 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:27:28 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:27:28 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:27:28 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:27:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:27:28 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:27:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:27:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:27:28 INFO - Fetch https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:27:29 INFO - Expected file size: 20365601 09:27:33 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:27:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:27:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:27:33 INFO - Fetch https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:27:33 INFO - Expected file size: 20365601 09:27:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:27:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:27:38 INFO - Fetch https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:27:39 INFO - Expected file size: 14414048 09:27:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:39 INFO - trying https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:27:39 INFO - Downloading https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:27:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:27:40 INFO - Downloaded 55598646 bytes. 09:27:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:27:40 INFO - mkdir: /builds/slave/test/properties 09:27:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:27:40 INFO - Writing to file /builds/slave/test/properties/build_url 09:27:40 INFO - Contents: 09:27:40 INFO - build_url:https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:27:41 INFO - Running post-action listener: _resource_record_post_action 09:27:41 INFO - Running post-action listener: set_extra_try_arguments 09:27:41 INFO - [mozharness: 2016-09-17 16:27:41.366278Z] Finished download-and-extract step (success) 09:27:41 INFO - [mozharness: 2016-09-17 16:27:41.366509Z] Running populate-webroot step. 09:27:41 INFO - Running pre-action listener: _resource_record_pre_action 09:27:41 INFO - Running main action method: populate_webroot 09:27:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:27:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:27:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:27:41 INFO - '--tppagecycles', 09:27:41 INFO - '7'], 09:27:41 INFO - 'tests': ['tsvgm']}}, 09:27:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:27:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:27:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:27:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:27:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:27:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:27:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:41 INFO - 'talos_options': ['--disable-e10s'], 09:27:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:41 INFO - 'talos_options': ['--disable-e10s'], 09:27:41 INFO - 'tests': ['damp', 'tps']}, 09:27:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:41 INFO - 'tests': ['damp', 'tps']}, 09:27:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:27:41 INFO - 'tests': ['dromaeo_dom']}, 09:27:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:27:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:27:41 INFO - 'tests': ['basic_compositor_video']}, 09:27:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:27:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:27:41 INFO - 'tests': ['a11yr', 09:27:41 INFO - 'ts_paint', 09:27:41 INFO - 'tpaint', 09:27:41 INFO - 'sessionrestore', 09:27:41 INFO - 'sessionrestore_no_auto_restore', 09:27:41 INFO - 'tabpaint']}, 09:27:41 INFO - 'other-e10s': {'tests': ['a11yr', 09:27:41 INFO - 'ts_paint', 09:27:41 INFO - 'tpaint', 09:27:41 INFO - 'sessionrestore', 09:27:41 INFO - 'sessionrestore_no_auto_restore', 09:27:41 INFO - 'tabpaint']}, 09:27:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:27:41 INFO - 'ts_paint', 09:27:41 INFO - 'tpaint', 09:27:41 INFO - 'sessionrestore', 09:27:41 INFO - 'sessionrestore_no_auto_restore', 09:27:41 INFO - 'tabpaint']}, 09:27:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:27:41 INFO - 'ts_paint', 09:27:41 INFO - 'tpaint', 09:27:41 INFO - 'sessionrestore', 09:27:41 INFO - 'sessionrestore_no_auto_restore', 09:27:41 INFO - 'tabpaint']}, 09:27:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:27:41 INFO - 'tests': ['a11yr', 09:27:41 INFO - 'ts_paint', 09:27:41 INFO - 'tpaint', 09:27:41 INFO - 'sessionrestore', 09:27:41 INFO - 'sessionrestore_no_auto_restore', 09:27:41 INFO - 'tabpaint']}, 09:27:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:27:41 INFO - 'tests': ['a11yr', 09:27:41 INFO - 'ts_paint', 09:27:41 INFO - 'tpaint', 09:27:41 INFO - 'sessionrestore', 09:27:41 INFO - 'sessionrestore_no_auto_restore', 09:27:41 INFO - 'tabpaint']}, 09:27:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:27:41 INFO - 'tests': ['tsvgx', 09:27:41 INFO - 'tsvgr_opacity', 09:27:41 INFO - 'tart', 09:27:41 INFO - 'tscrollx', 09:27:41 INFO - 'cart']}, 09:27:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:27:41 INFO - 'tsvgr_opacity', 09:27:41 INFO - 'tart', 09:27:41 INFO - 'tscrollx', 09:27:41 INFO - 'cart']}, 09:27:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:41 INFO - 'talos_options': ['--disable-e10s'], 09:27:41 INFO - 'tests': ['tp5o']}, 09:27:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:41 INFO - 'tests': ['tp5o']}, 09:27:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:41 INFO - 'talos_options': ['--disable-e10s', 09:27:41 INFO - '--xperf_path', 09:27:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:41 INFO - 'tests': ['tp5n']}, 09:27:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:41 INFO - 'talos_options': ['--xperf_path', 09:27:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:41 INFO - 'tests': ['tp5n']}}, 09:27:41 INFO - 'talos.zip': {'path': '', 09:27:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:27:41 INFO - Running post-action listener: _resource_record_post_action 09:27:41 INFO - [mozharness: 2016-09-17 16:27:41.384374Z] Finished populate-webroot step (success) 09:27:41 INFO - [mozharness: 2016-09-17 16:27:41.384464Z] Running create-virtualenv step. 09:27:41 INFO - Running pre-action listener: _resource_record_pre_action 09:27:41 INFO - Running main action method: create_virtualenv 09:27:41 INFO - Creating virtualenv /builds/slave/test/build/venv 09:27:41 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 09:27:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:27:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:27:41 INFO - Using real prefix '/usr' 09:27:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:27:41 INFO - Installing distribute.............................................................................................................................................................................................done. 09:27:44 INFO - Installing pip.................done. 09:27:44 INFO - Return code: 0 09:27:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:27:44 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')]} 09:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:44 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')]} 09:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:44 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 0x7f575b782f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2773ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2770f60>, '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 0x2771d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27721d0>, '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': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', '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 09:27:44 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 09:27:44 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 09:27:44 INFO - Using env: {'DISPLAY': ':0', 09:27:44 INFO - 'HOME': '/home/cltbld', 09:27:44 INFO - 'LANG': 'en_US.UTF-8', 09:27:44 INFO - 'LANGUAGE': 'en_US:en', 09:27:44 INFO - 'LOGNAME': 'cltbld', 09:27:44 INFO - 'MAIL': '/var/mail/cltbld', 09:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:44 INFO - 'MOZ_NO_REMOTE': '1', 09:27:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:44 INFO - 'NO_EM_RESTART': '1', 09:27:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:44 INFO - 'PWD': '/builds/slave/test', 09:27:44 INFO - 'SHELL': '/bin/bash', 09:27:44 INFO - 'SHLVL': '1', 09:27:44 INFO - 'TERM': 'linux', 09:27:44 INFO - 'TMOUT': '86400', 09:27:44 INFO - 'USER': 'cltbld', 09:27:44 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', 09:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:44 INFO - '_': '/tools/buildbot/bin/python'} 09:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:44 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 09:27:44 INFO - Cleaning up... 09:27:44 INFO - Return code: 0 09:27:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:27:44 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')]} 09:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:44 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')]} 09:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:44 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 0x7f575b782f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2773ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2770f60>, '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 0x2771d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27721d0>, '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': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', '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 09:27:44 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 09:27:44 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 09:27:44 INFO - Using env: {'DISPLAY': ':0', 09:27:44 INFO - 'HOME': '/home/cltbld', 09:27:44 INFO - 'LANG': 'en_US.UTF-8', 09:27:44 INFO - 'LANGUAGE': 'en_US:en', 09:27:44 INFO - 'LOGNAME': 'cltbld', 09:27:44 INFO - 'MAIL': '/var/mail/cltbld', 09:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:44 INFO - 'MOZ_NO_REMOTE': '1', 09:27:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:44 INFO - 'NO_EM_RESTART': '1', 09:27:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:44 INFO - 'PWD': '/builds/slave/test', 09:27:44 INFO - 'SHELL': '/bin/bash', 09:27:44 INFO - 'SHLVL': '1', 09:27:44 INFO - 'TERM': 'linux', 09:27:44 INFO - 'TMOUT': '86400', 09:27:44 INFO - 'USER': 'cltbld', 09:27:44 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', 09:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:44 INFO - '_': '/tools/buildbot/bin/python'} 09:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:44 INFO - Downloading/unpacking psutil>=3.1.1 09:27:44 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 09:27:44 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 09:27:44 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 09:27:44 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 09:27:44 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 09:27:44 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 09:27:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:27:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:46 INFO - Installing collected packages: psutil 09:27:46 INFO - Running setup.py install for psutil 09:27:46 INFO - building 'psutil._psutil_linux' extension 09:27:46 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 09:27:46 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 09:27:46 INFO - building 'psutil._psutil_posix' extension 09:27:46 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 09:27:46 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 09:27:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:46 INFO - Successfully installed psutil 09:27:46 INFO - Cleaning up... 09:27:47 INFO - Return code: 0 09:27:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:27:47 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')]} 09:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:47 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')]} 09:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:47 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 0x7f575b782f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2773ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2770f60>, '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 0x2771d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27721d0>, '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': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', '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 09:27:47 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 09:27:47 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 09:27:47 INFO - Using env: {'DISPLAY': ':0', 09:27:47 INFO - 'HOME': '/home/cltbld', 09:27:47 INFO - 'LANG': 'en_US.UTF-8', 09:27:47 INFO - 'LANGUAGE': 'en_US:en', 09:27:47 INFO - 'LOGNAME': 'cltbld', 09:27:47 INFO - 'MAIL': '/var/mail/cltbld', 09:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:47 INFO - 'MOZ_NO_REMOTE': '1', 09:27:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:47 INFO - 'NO_EM_RESTART': '1', 09:27:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:47 INFO - 'PWD': '/builds/slave/test', 09:27:47 INFO - 'SHELL': '/bin/bash', 09:27:47 INFO - 'SHLVL': '1', 09:27:47 INFO - 'TERM': 'linux', 09:27:47 INFO - 'TMOUT': '86400', 09:27:47 INFO - 'USER': 'cltbld', 09:27:47 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', 09:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:47 INFO - '_': '/tools/buildbot/bin/python'} 09:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:27:47 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 09:27:47 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 09:27:47 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 09:27:47 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 09:27:47 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 09:27:47 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 09:27:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:27:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:27:49 INFO - Installing collected packages: mozsystemmonitor 09:27:49 INFO - Running setup.py install for mozsystemmonitor 09:27:49 INFO - Successfully installed mozsystemmonitor 09:27:49 INFO - Cleaning up... 09:27:49 INFO - Return code: 0 09:27:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:27:49 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')]} 09:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:49 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')]} 09:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:49 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 0x7f575b782f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2773ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2770f60>, '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 0x2771d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27721d0>, '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': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', '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 09:27:49 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 09:27:49 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 09:27:49 INFO - Using env: {'DISPLAY': ':0', 09:27:49 INFO - 'HOME': '/home/cltbld', 09:27:49 INFO - 'LANG': 'en_US.UTF-8', 09:27:49 INFO - 'LANGUAGE': 'en_US:en', 09:27:49 INFO - 'LOGNAME': 'cltbld', 09:27:49 INFO - 'MAIL': '/var/mail/cltbld', 09:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:49 INFO - 'MOZ_NO_REMOTE': '1', 09:27:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:49 INFO - 'NO_EM_RESTART': '1', 09:27:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:49 INFO - 'PWD': '/builds/slave/test', 09:27:49 INFO - 'SHELL': '/bin/bash', 09:27:49 INFO - 'SHLVL': '1', 09:27:49 INFO - 'TERM': 'linux', 09:27:49 INFO - 'TMOUT': '86400', 09:27:49 INFO - 'USER': 'cltbld', 09:27:49 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', 09:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:49 INFO - '_': '/tools/buildbot/bin/python'} 09:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:49 INFO - Downloading/unpacking jsonschema==2.5.1 09:27:49 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 09:27:49 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 09:27:49 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 09:27:49 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 09:27:49 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 09:27:49 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 09:27:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:27:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:51 INFO - Downloading functools32-3.2.3-2.tar.gz 09:27:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:27:51 INFO - warning: no files found matching '*.txt' 09:27:51 INFO - no previously-included directories found matching 'build' 09:27:51 INFO - no previously-included directories found matching 'dist' 09:27:51 INFO - no previously-included directories found matching '.git*' 09:27:51 INFO - Installing collected packages: jsonschema, functools32 09:27:51 INFO - Running setup.py install for functools32 09:27:51 INFO - warning: no files found matching '*.txt' 09:27:51 INFO - no previously-included directories found matching 'build' 09:27:51 INFO - no previously-included directories found matching 'dist' 09:27:51 INFO - no previously-included directories found matching '.git*' 09:27:51 INFO - Successfully installed jsonschema functools32 09:27:51 INFO - Cleaning up... 09:27:51 INFO - Return code: 0 09:27:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f575b782f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2773ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2770f60>, '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 0x2771d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27721d0>, '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': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', '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 09:27:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:27:51 INFO - Using env: {'DISPLAY': ':0', 09:27:51 INFO - 'HOME': '/home/cltbld', 09:27:51 INFO - 'LANG': 'en_US.UTF-8', 09:27:51 INFO - 'LANGUAGE': 'en_US:en', 09:27:51 INFO - 'LOGNAME': 'cltbld', 09:27:51 INFO - 'MAIL': '/var/mail/cltbld', 09:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:51 INFO - 'MOZ_NO_REMOTE': '1', 09:27:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:51 INFO - 'NO_EM_RESTART': '1', 09:27:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:51 INFO - 'PWD': '/builds/slave/test', 09:27:51 INFO - 'SHELL': '/bin/bash', 09:27:51 INFO - 'SHLVL': '1', 09:27:51 INFO - 'TERM': 'linux', 09:27:51 INFO - 'TMOUT': '86400', 09:27:51 INFO - 'USER': 'cltbld', 09:27:51 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', 09:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:51 INFO - '_': '/tools/buildbot/bin/python'} 09:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:27:52 INFO - Cleaning up... 09:27:52 INFO - Return code: 0 09:27:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:27:52 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')]} 09:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:52 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')]} 09:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:52 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 0x7f575b782f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2773ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2770f60>, '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 0x2771d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27721d0>, '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': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', '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 09:27:52 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 09:27:52 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 09:27:52 INFO - Using env: {'DISPLAY': ':0', 09:27:52 INFO - 'HOME': '/home/cltbld', 09:27:52 INFO - 'LANG': 'en_US.UTF-8', 09:27:52 INFO - 'LANGUAGE': 'en_US:en', 09:27:52 INFO - 'LOGNAME': 'cltbld', 09:27:52 INFO - 'MAIL': '/var/mail/cltbld', 09:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:52 INFO - 'MOZ_NO_REMOTE': '1', 09:27:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:52 INFO - 'NO_EM_RESTART': '1', 09:27:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:52 INFO - 'PWD': '/builds/slave/test', 09:27:52 INFO - 'SHELL': '/bin/bash', 09:27:52 INFO - 'SHLVL': '1', 09:27:52 INFO - 'TERM': 'linux', 09:27:52 INFO - 'TMOUT': '86400', 09:27:52 INFO - 'USER': 'cltbld', 09:27:52 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', 09:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:52 INFO - '_': '/tools/buildbot/bin/python'} 09:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:52 INFO - Downloading/unpacking blobuploader==1.2.4 09:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:54 INFO - Downloading blobuploader-1.2.4.tar.gz 09:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:27:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:27:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:54 INFO - Downloading docopt-0.6.1.tar.gz 09:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:27:55 INFO - Installing collected packages: blobuploader, requests, docopt 09:27:55 INFO - Running setup.py install for blobuploader 09:27:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:27:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:27:55 INFO - Running setup.py install for requests 09:27:55 INFO - Running setup.py install for docopt 09:27:55 INFO - Successfully installed blobuploader requests docopt 09:27:55 INFO - Cleaning up... 09:27:55 INFO - Return code: 0 09:27:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:55 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 0x7f575b782f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2773ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2770f60>, '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 0x2771d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27721d0>, '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': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', '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 09:27:55 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 09:27:55 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 09:27:55 INFO - Using env: {'DISPLAY': ':0', 09:27:55 INFO - 'HOME': '/home/cltbld', 09:27:55 INFO - 'LANG': 'en_US.UTF-8', 09:27:55 INFO - 'LANGUAGE': 'en_US:en', 09:27:55 INFO - 'LOGNAME': 'cltbld', 09:27:55 INFO - 'MAIL': '/var/mail/cltbld', 09:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:55 INFO - 'MOZ_NO_REMOTE': '1', 09:27:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:55 INFO - 'NO_EM_RESTART': '1', 09:27:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:55 INFO - 'PWD': '/builds/slave/test', 09:27:55 INFO - 'SHELL': '/bin/bash', 09:27:55 INFO - 'SHLVL': '1', 09:27:55 INFO - 'TERM': 'linux', 09:27:55 INFO - 'TMOUT': '86400', 09:27:55 INFO - 'USER': 'cltbld', 09:27:55 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', 09:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:55 INFO - '_': '/tools/buildbot/bin/python'} 09:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:55 INFO - Running setup.py (path:/tmp/pip-bqM58t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:55 INFO - Running setup.py (path:/tmp/pip-e5eupU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:55 INFO - Running setup.py (path:/tmp/pip-nDSlVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:56 INFO - Running setup.py (path:/tmp/pip-Q2MxM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:56 INFO - Running setup.py (path:/tmp/pip-KlJzYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:56 INFO - Running setup.py (path:/tmp/pip-TiBe2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:56 INFO - Running setup.py (path:/tmp/pip-QHV2nR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:56 INFO - Running setup.py (path:/tmp/pip-USL46B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:56 INFO - Running setup.py (path:/tmp/pip-EcXN6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:56 INFO - Running setup.py (path:/tmp/pip-5zy3jE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:56 INFO - Running setup.py (path:/tmp/pip-jq1ObP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:56 INFO - Running setup.py (path:/tmp/pip-iZwTTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:56 INFO - Running setup.py (path:/tmp/pip-kab2WN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:56 INFO - Running setup.py (path:/tmp/pip-NfwXbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:56 INFO - Running setup.py (path:/tmp/pip-OaJQ8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:56 INFO - Running setup.py (path:/tmp/pip-sRlqNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:56 INFO - Running setup.py (path:/tmp/pip-6sK6AH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:27:57 INFO - Running setup.py install for manifestparser 09:27:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:27:57 INFO - Running setup.py install for mozcrash 09:27:57 INFO - Running setup.py install for mozdebug 09:27:57 INFO - Running setup.py install for mozdevice 09:27:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:27:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:27:57 INFO - Running setup.py install for mozfile 09:27:57 INFO - Running setup.py install for mozhttpd 09:27:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:27:57 INFO - Running setup.py install for mozinfo 09:27:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:27:57 INFO - Running setup.py install for mozInstall 09:27:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:27:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:27:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:27:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:27:57 INFO - Running setup.py install for mozleak 09:27:57 INFO - Running setup.py install for mozlog 09:27:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:27:58 INFO - Running setup.py install for moznetwork 09:27:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:27:58 INFO - Running setup.py install for mozprocess 09:27:58 INFO - Running setup.py install for mozprofile 09:27:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:27:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:27:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:27:58 INFO - Running setup.py install for mozrunner 09:27:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:27:58 INFO - Running setup.py install for mozscreenshot 09:27:58 INFO - Running setup.py install for moztest 09:27:58 INFO - Running setup.py install for mozversion 09:27:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:27:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:27:58 INFO - Cleaning up... 09:27:58 INFO - Return code: 0 09:27:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:58 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')]} 09:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:58 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')]} 09:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:58 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 0x7f575b782f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2773ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2770f60>, '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 0x2771d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27721d0>, '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': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', '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 09:27:58 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 09:27:58 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 09:27:58 INFO - Using env: {'DISPLAY': ':0', 09:27:58 INFO - 'HOME': '/home/cltbld', 09:27:58 INFO - 'LANG': 'en_US.UTF-8', 09:27:58 INFO - 'LANGUAGE': 'en_US:en', 09:27:58 INFO - 'LOGNAME': 'cltbld', 09:27:58 INFO - 'MAIL': '/var/mail/cltbld', 09:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:58 INFO - 'MOZ_NO_REMOTE': '1', 09:27:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:58 INFO - 'NO_EM_RESTART': '1', 09:27:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:58 INFO - 'PWD': '/builds/slave/test', 09:27:58 INFO - 'SHELL': '/bin/bash', 09:27:58 INFO - 'SHLVL': '1', 09:27:58 INFO - 'TERM': 'linux', 09:27:58 INFO - 'TMOUT': '86400', 09:27:58 INFO - 'USER': 'cltbld', 09:27:58 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', 09:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:58 INFO - '_': '/tools/buildbot/bin/python'} 09:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:59 INFO - Running setup.py (path:/tmp/pip-GgfVIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:59 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)) 09:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:59 INFO - Running setup.py (path:/tmp/pip-OVlCtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:59 INFO - Running setup.py (path:/tmp/pip-DBKPyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:59 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)) 09:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:59 INFO - Running setup.py (path:/tmp/pip-D2O5IF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:59 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)) 09:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:59 INFO - Running setup.py (path:/tmp/pip-9ScNiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:59 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)) 09:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:59 INFO - Running setup.py (path:/tmp/pip-JjrVxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:59 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)) 09:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:59 INFO - Running setup.py (path:/tmp/pip-X9vJoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:59 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)) 09:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:59 INFO - Running setup.py (path:/tmp/pip-cjn9I7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:59 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)) 09:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:59 INFO - Running setup.py (path:/tmp/pip-pdJyJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:59 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)) 09:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:59 INFO - Running setup.py (path:/tmp/pip-spmRx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:59 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)) 09:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:59 INFO - Running setup.py (path:/tmp/pip-7KnirB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:59 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)) 09:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:59 INFO - Running setup.py (path:/tmp/pip-OmZNg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:59 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)) 09:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:59 INFO - Running setup.py (path:/tmp/pip-rnu5ij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:28:00 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)) 09:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:28:00 INFO - Running setup.py (path:/tmp/pip-rBkV5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:00 INFO - Running setup.py (path:/tmp/pip-doz7u1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:00 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)) 09:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:28:00 INFO - Running setup.py (path:/tmp/pip-O2Hke4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:28:00 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)) 09:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:28:00 INFO - Running setup.py (path:/tmp/pip-bOVEwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:28:00 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)) 09:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:28:00 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)) 09:28:00 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)) 09:28:00 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)) 09:28:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:28: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 09:28: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 09:28: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 09:28: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 09:28: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 09:28: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 09:28:02 INFO - Downloading blessings-1.6.tar.gz 09:28:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:28:02 INFO - Installing collected packages: blessings 09:28:02 INFO - Running setup.py install for blessings 09:28:02 INFO - Successfully installed blessings 09:28:02 INFO - Cleaning up... 09:28:02 INFO - Return code: 0 09:28:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:28:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:28:02 INFO - Reading from file tmpfile_stdout 09:28:02 INFO - Current package versions: 09:28:02 INFO - argparse == 1.2.1 09:28:02 INFO - blessings == 1.6 09:28:02 INFO - blobuploader == 1.2.4 09:28:02 INFO - docopt == 0.6.1 09:28:02 INFO - functools32 == 3.2.3-2 09:28:02 INFO - jsonschema == 2.5.1 09:28:02 INFO - manifestparser == 1.1 09:28:02 INFO - mozInstall == 1.12 09:28:02 INFO - mozcrash == 0.17 09:28:02 INFO - mozdebug == 0.1 09:28:02 INFO - mozdevice == 0.48 09:28:02 INFO - mozfile == 1.2 09:28:02 INFO - mozhttpd == 0.7 09:28:02 INFO - mozinfo == 0.9 09:28:02 INFO - mozleak == 0.1 09:28:02 INFO - mozlog == 3.4 09:28:02 INFO - moznetwork == 0.27 09:28:02 INFO - mozprocess == 0.23 09:28:02 INFO - mozprofile == 0.28 09:28:02 INFO - mozrunner == 6.12 09:28:02 INFO - mozscreenshot == 0.1 09:28:02 INFO - mozsystemmonitor == 0.3 09:28:02 INFO - moztest == 0.7 09:28:02 INFO - mozversion == 1.4 09:28:02 INFO - psutil == 3.1.1 09:28:02 INFO - requests == 1.2.3 09:28:02 INFO - wsgiref == 0.1.2 09:28:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:02 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 0x7f575b782f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2773ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2770f60>, '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 0x2771d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27721d0>, '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': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', '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 09:28:02 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 09:28:02 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 09:28:02 INFO - Using env: {'DISPLAY': ':0', 09:28:02 INFO - 'HOME': '/home/cltbld', 09:28:02 INFO - 'LANG': 'en_US.UTF-8', 09:28:02 INFO - 'LANGUAGE': 'en_US:en', 09:28:02 INFO - 'LOGNAME': 'cltbld', 09:28:02 INFO - 'MAIL': '/var/mail/cltbld', 09:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:02 INFO - 'MOZ_NO_REMOTE': '1', 09:28:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:28:02 INFO - 'NO_EM_RESTART': '1', 09:28:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:02 INFO - 'PWD': '/builds/slave/test', 09:28:02 INFO - 'SHELL': '/bin/bash', 09:28:02 INFO - 'SHLVL': '1', 09:28:02 INFO - 'TERM': 'linux', 09:28:02 INFO - 'TMOUT': '86400', 09:28:02 INFO - 'USER': 'cltbld', 09:28:02 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', 09:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:02 INFO - '_': '/tools/buildbot/bin/python'} 09:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 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)) 09:28:02 INFO - Cleaning up... 09:28:02 INFO - Return code: 0 09:28:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:02 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 0x7f575b782f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2773ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2770f60>, '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 0x2771d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27721d0>, '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': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', '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 09:28:02 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 09:28:02 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 09:28:02 INFO - Using env: {'DISPLAY': ':0', 09:28:02 INFO - 'HOME': '/home/cltbld', 09:28:02 INFO - 'LANG': 'en_US.UTF-8', 09:28:02 INFO - 'LANGUAGE': 'en_US:en', 09:28:02 INFO - 'LOGNAME': 'cltbld', 09:28:02 INFO - 'MAIL': '/var/mail/cltbld', 09:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:02 INFO - 'MOZ_NO_REMOTE': '1', 09:28:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:28:02 INFO - 'NO_EM_RESTART': '1', 09:28:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:02 INFO - 'PWD': '/builds/slave/test', 09:28:02 INFO - 'SHELL': '/bin/bash', 09:28:02 INFO - 'SHLVL': '1', 09:28:02 INFO - 'TERM': 'linux', 09:28:02 INFO - 'TMOUT': '86400', 09:28:02 INFO - 'USER': 'cltbld', 09:28:02 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', 09:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:02 INFO - '_': '/tools/buildbot/bin/python'} 09:28:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:28:03 INFO - Cleaning up... 09:28:03 INFO - Return code: 0 09:28:03 INFO - Running post-action listener: _resource_record_post_action 09:28:03 INFO - Running post-action listener: _start_resource_monitoring 09:28:03 INFO - Starting resource monitoring. 09:28:03 INFO - [mozharness: 2016-09-17 16:28:03.133085Z] Finished create-virtualenv step (success) 09:28:03 INFO - [mozharness: 2016-09-17 16:28:03.133654Z] Running install step. 09:28:03 INFO - Running pre-action listener: _resource_record_pre_action 09:28:03 INFO - Running main action method: install 09:28:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:28:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:28:03 INFO - Reading from file tmpfile_stdout 09:28:03 INFO - Detecting whether we're running mozinstall >=1.0... 09:28:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:28:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:28:03 INFO - Reading from file tmpfile_stdout 09:28:03 INFO - Output received: 09:28:03 INFO - Usage: mozinstall [options] installer 09:28:03 INFO - Options: 09:28:03 INFO - -h, --help show this help message and exit 09:28:03 INFO - -d DEST, --destination=DEST 09:28:03 INFO - Directory to install application into. [default: 09:28:03 INFO - "/builds/slave/test"] 09:28:03 INFO - --app=APP Application being installed. [default: firefox] 09:28:03 INFO - mkdir: /builds/slave/test/build/application 09:28:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:28:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:28:19 INFO - Reading from file tmpfile_stdout 09:28:19 INFO - Output received: 09:28:19 INFO - /builds/slave/test/build/application/firefox/firefox 09:28:19 INFO - Running post-action listener: _resource_record_post_action 09:28:19 INFO - [mozharness: 2016-09-17 16:28:19.451021Z] Finished install step (success) 09:28:19 INFO - [mozharness: 2016-09-17 16:28:19.451269Z] Running run-tests step. 09:28:19 INFO - Running pre-action listener: _resource_record_pre_action 09:28:19 INFO - Running main action method: run_tests 09:28:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:28:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:28:19 INFO - Python 2.7.3 09:28:19 INFO - Return code: 0 09:28:19 INFO - grabbing minidump binary from tooltool 09:28: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')]} 09:28:19 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 0x2770f60>, '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 0x2771d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27721d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:28:19 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 09:28:19 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 09:28:19 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 09:28:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:28:19 INFO - Return code: 0 09:28:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:28:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:28:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:28:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:28:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:28:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 09:28:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 09:28:19 INFO - Using env: {'DISPLAY': ':0', 09:28:19 INFO - 'HOME': '/home/cltbld', 09:28:19 INFO - 'LANG': 'en_US.UTF-8', 09:28:19 INFO - 'LANGUAGE': 'en_US:en', 09:28:19 INFO - 'LOGNAME': 'cltbld', 09:28:19 INFO - 'MAIL': '/var/mail/cltbld', 09:28:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:28:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:28:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:19 INFO - 'MOZ_NO_REMOTE': '1', 09:28:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:28:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:28:19 INFO - 'NO_EM_RESTART': '1', 09:28:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:28:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:19 INFO - 'PWD': '/builds/slave/test', 09:28:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:28:19 INFO - 'SHELL': '/bin/bash', 09:28:19 INFO - 'SHLVL': '1', 09:28:19 INFO - 'TERM': 'linux', 09:28:19 INFO - 'TMOUT': '86400', 09:28:19 INFO - 'USER': 'cltbld', 09:28:19 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1474129645.388585-1764405660', 09:28:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:19 INFO - '_': '/tools/buildbot/bin/python'} 09:28:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 09:28:19 INFO - mozversion application_buildid: 20160917010517 09:28:19 INFO - mozversion application_changeset: f4be1a7f9b3ee81960fa2f81a55c87ea11784bb2 09:28:19 INFO - mozversion application_display_name: Nightly 09:28:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:28:19 INFO - mozversion application_name: Firefox 09:28:19 INFO - mozversion application_remotingname: firefox 09:28:19 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:28:19 INFO - mozversion application_vendor: Mozilla 09:28:19 INFO - mozversion application_version: 51.0a1 09:28:19 INFO - mozversion platform_buildid: 20160917010517 09:28:19 INFO - mozversion platform_changeset: f4be1a7f9b3ee81960fa2f81a55c87ea11784bb2 09:28:19 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:28:19 INFO - mozversion platform_version: 51.0a1 09:28:19 INFO - using testdate: 1474129699 09:28:19 INFO - actual date: 1474129699 09:28:19 INFO - starting webserver on 'localhost:34105' 09:28:19 INFO - SUITE-START | Running 2 tests 09:28:19 INFO - TEST-START | dromaeo_css 09:28:19 INFO - operating with platform_type : linux_ 09:28:19 INFO - Initialising browser for dromaeo_css test... 09:28:19 INFO - TEST-INFO | started process 3935 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXsBZ6E/profile http://localhost:34105/getInfo.html) 09:28:24 INFO - PROCESS | 3935 | __metrics Screen width/height:1600/1200 09:28:24 INFO - PROCESS | 3935 | colorDepth:24 09:28:24 INFO - PROCESS | 3935 | Browser inner width/height: 1024/697 09:28:24 INFO - PROCESS | 3935 | __metrics 09:28:25 INFO - PROCESS | 3935 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:28:25 INFO - PROCESS | 3935 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:28:25 INFO - PROCESS | 3935 | console.error: PushService: 09:28:25 INFO - PROCESS | 3935 | stateChangeProcessEnqueue: Error transitioning state 09:28:25 INFO - PROCESS | 3935 | UnknownError 09:28:26 INFO - TEST-INFO | 3935: exit 0 09:28:26 INFO - Browser initialized. 09:28:26 INFO - Running cycle 1/1 for dromaeo_css test... 09:28:26 INFO - TEST-INFO | started process 4015 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXsBZ6E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:38 INFO - PROCESS | 4015 | RSS: Main: 174735360 09:28:38 INFO - PROCESS | 4015 | 09:28:57 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:28:58 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:29:01 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:29:04 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:29:14 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 09:29:16 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 09:29:27 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 09:29:29 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 09:29:30 INFO - PROCESS | 4015 | 1474129770643 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] 09:29:39 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 09:29:46 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0667 seconds. 09:29:51 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 09:30:07 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 09:30:12 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 09:30:26 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 09:30:29 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.10004 seconds. 09:30:33 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 09:30:38 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 09:30:57 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 09:30:59 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 09:31:15 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 09:31:17 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.08337 seconds. 09:31:22 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 09:31:37 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 09:31:42 INFO - PROCESS | 4015 | Cycle 1(1): loaded http://localhost:34105/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34105/tests/dromaeo/cssquery-ext.html) 09:31:43 INFO - PROCESS | 4015 | RSS: Main: 299319296 09:31:43 INFO - PROCESS | 4015 | 09:32:04 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:32:16 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:32:35 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 09:32:43 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:32:45 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:32:47 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:32:58 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 09:33:07 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:33:11 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:33:33 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:33:37 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 09:33:39 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:33:41 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 09:33:44 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 09:33:54 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 09:34:00 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 09:34:02 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 09:34:11 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:34:12 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:34:14 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 09:34:16 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:34:20 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 09:34:22 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:34:26 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:34:28 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:34:30 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:34:32 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:34:38 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:34:39 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 2.00005 seconds. 09:34:40 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:34:44 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:34:46 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 09:34:46 INFO - PROCESS | 4015 | Cycle 1(1): loaded http://localhost:34105/tests/dromaeo/cssquery-ext.html (next: http://localhost:34105/tests/dromaeo/cssquery-jquery.html) 09:34:47 INFO - PROCESS | 4015 | RSS: Main: 274980864 09:34:47 INFO - PROCESS | 4015 | 09:34:50 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 09:34:55 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:34:57 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:35:03 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 09:35:07 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:35:34 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:35:40 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:36:00 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:36:12 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:36:14 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:36:20 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:36:22 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:36:24 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:36:31 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:36:44 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 09:36:46 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 09:36:59 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 09:37:01 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:37:03 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:37:05 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:37:15 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:37:17 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:37:19 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:37:31 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:37:39 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:37:42 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:37:43 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:37:47 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:37:49 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:37:49 INFO - PROCESS | 4015 | Cycle 1(1): loaded http://localhost:34105/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34105/tests/dromaeo/cssquery-mootools.html) 09:37:50 INFO - PROCESS | 4015 | RSS: Main: 219136000 09:37:50 INFO - PROCESS | 4015 | 09:37:53 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 09:37:54 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 09:38:14 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:38:16 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:38:22 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:38:23 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 2.00005 seconds. 09:38:24 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:38:26 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:38:37 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 09:38:39 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 09:38:41 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:38:47 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:38:49 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:38:51 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 09:38:57 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:38:59 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:39:09 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 09:39:11 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:39:13 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:39:27 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:39:32 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 09:39:45 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:39:47 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:39:55 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:40:11 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:40:14 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 09:40:19 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:40:26 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:40:30 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:40:34 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:40:44 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:40:46 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 09:40:52 INFO - PROCESS | 4015 | Cycle 1(1): loaded http://localhost:34105/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34105/tests/dromaeo/cssquery-prototype.html) 09:40:52 INFO - PROCESS | 4015 | RSS: Main: 219557888 09:40:52 INFO - PROCESS | 4015 | 09:40:59 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 09:41:11 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:41:37 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:41:38 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:41:40 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 09:41:44 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 09:41:54 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 09:42:02 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 09:42:04 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:42:25 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:42:27 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:42:31 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 09:42:37 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:42:47 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:42:52 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:43:08 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 09:43:11 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:43:36 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:43:50 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:43:54 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:43:56 INFO - PROCESS | 4015 | Cycle 1(1): loaded http://localhost:34105/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34105/tests/dromaeo/cssquery-yui.html) 09:43:56 INFO - PROCESS | 4015 | RSS: Main: 219844608 09:43:56 INFO - PROCESS | 4015 | 09:44:02 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 09:44:05 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:44:13 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:44:16 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:44:23 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:44:25 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:44:27 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 09:44:29 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:44:31 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:44:43 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 09:45:01 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 09:45:10 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:45:12 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 09:45:24 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 09:45:26 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:45:40 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 09:45:42 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 09:45:44 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 09:45:46 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 09:45:48 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 09:45:50 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 09:45:52 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 09:45:56 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:46:04 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:46:08 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:46:12 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:46:25 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 09:46:40 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 09:46:41 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01663 seconds. 09:46:59 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.99997 seconds. 09:47:00 INFO - PROCESS | 4015 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 09:47:00 INFO - PROCESS | 4015 | Cycle 1(1): loaded http://localhost:34105/tests/dromaeo/cssquery-yui.html (next: http://localhost:34105/tests/dromaeo/cssquery-dojo.html) 09:47:00 INFO - PROCESS | 4015 | RSS: Main: 311795712 09:47:00 INFO - PROCESS | 4015 | 09:47:00 INFO - PROCESS | 4015 | __start_tp_report 09:47:00 INFO - PROCESS | 4015 | _x_x_mozilla_page_load 09:47:00 INFO - PROCESS | 4015 | _x_x_mozilla_page_load_details 09:47:00 INFO - PROCESS | 4015 | |i|pagename|runs| 09:47:00 INFO - PROCESS | 4015 | |0;dojo.html;2597.441550066735;2626.107123577983;2656.4687062587486;2659.933501662458;2666.6000099985;4491.416115904936;4585.564371384713;4703.106409782139;4796.8560943171715;4855.247436647316;4628.444586649598;4667.6965997210245;4738.90522189556;4749.620030397568;4762.547557981995;4327.177836211125;4363.454568178978;4363.54182810805;4381.824727010916;4451.376807246988;4407.052483716;4460.33095035744;4485.842995495158;4490.797914093873;4547.136044151617;4285.700000999931;4305.74165550068;4330.047389574289;4364.607185353333;4368;4324.762138082404;4349.891252718676;4356.564343565649;4360.760158191299;4449.933251001237;4333.090051089275;4342.522322544518;4352.260115780293;4359;4394.252976993918;4708.4820669726305;4710.26990816424;4728.881777955578;4736.431628204603;4740.241561350202;4926.605871530305;4988.551030407244;4994.775235114428;5019.247112933052;5022.824201152978;5009.398872135331;5028.949710502921;5029.321041659366;5030.044291584587;5084.288199652052;4707.105649926503;4711.081339138835;4713.9057218856115;4747.287906813971;4767.594754445841;5106.923396149061;5115.616328775282;5117.385913690307;5144.4855514449;5168.793248270103;4910.631702622318;4913.631477639191;4916.385451818523;4967.975160124176;4977.726225057584;4981.053599816023;4993.300937868701;4996.875078123076;5004.624653151028;5063.898722025612;4934.925976110361;4946.159152944008;4952.455229924705;5008.524190201925;5020.49795020495;4943.2585112233455;4946.777395017233;4967.254911763264;5031.094403007421;5038;4264.850730224427;4267.231898258343;4270.35944608305;4321.416608757841;4327.805248763751;3705.70354371655;3732.197577520846;3750.362438385481;3756.6431189037;3800.9239815204096;4230.15396920611;4280.743155410685;4281.143771245764;4323.783810809447;4348.499922508889;5096.898062038738;5098.337216161875;5148.897022059537;5153.201253805666;5216.895662086737;4381.824727010948;4385.495667998221;4385.824567017347;4391;4444.133393988206;4405.383246345515;4439.578240067189;4442.68901176921;4502;4506.2564676828615;4762.690425122356;4825.059113472909;4832.130216560912;4832.830850920201;4837.105135549935;5011.924821127759;5023.120953832991;5023.899522009612;5026.145555255543;5090.134677104827;4587.449506059294;4751.263554149113;4760.119377914958;4765.142274390643;4808.182608956416;4810.687305325143;4973.278874563241;5003.374578177728;5036.697798132123;5099.69401835891;5086.567641750493;5095.847124586268;5121.74391280442;5124.12889808726;5188.19582964626;4919.434265059564;4964.950350496449;4967.875803104951;4972.229304457815;4974.104661161025;4740.857774266778;4803.951960480351;4829.227323628203;4830.347903033186;4855.36880205571;4769.30845027476;4796.352492413619;4808.230683090689;4832.927506087482;4882.731449770296;4914.140025495595;4917.852464426073;4930.161872481758;4931.876703082452;4981.277714731416;4753.714777113384;4770.809167633394;4791.616670666408;4817.445993710769;4823.831165909176 09:47:00 INFO - PROCESS | 4015 | |1;ext.html;558.2881825672268;559.0300828063307;570.57207094679;571.3629303326792;572.6649909802766;27149.321266968313;38209;39351.99999999996;39486.60513394865;43724.78137609307;39357;39662.80168599153;39775.40336894942;39832.20335593286;39863.40204896929;7473.177950425463;7638.274363935445;7651.081870175587;7659.119201291867;7660.387169026464;7428.777136685908;7504.6998120075405;7510.09878814543;7511.812204694872;7512.286332798375;7507.211742767013;7556.093268807724;7565.697372105109;7570.659320330571;7575.1288601810675;7482.551046937147;7483.775486735407;7529.322360987483;7532.435067369969;7545.509541879787;7463.589502577356;7514.135874374462;7528.284812942762;7537.170911199763;7538.170801211862;37538.24923501542;37929.81035094835;37933;38035.04912377185;38067.23865522702;2927.385249097692;2957;2957.600723902267;2973.108067579739;3048.832314222717;28148.296292592644;28157.57763633547;28782.856085719646;28821;30542.236444088852;3095.071478556424;3140.5132204508336;3150;3160.747140228776;3161.0674850919036;262527;263435;264011;264038;264575;50313.24530132051;50360.24459633108;50549.49450505521;50584.494155057975;50916.49083509117;32257.838710806765;33270.83364583162;33313;53194;53470;11721;11827.822582661267;11868.465919033664;11875.76248475043;11906.285622862655;9035.186833185046;9067.727968160965;9102.453852768855;9112.22546083577;9143.31425143117;17515.299388024338;17585.648287034186;17620.295188192584;17652.205650745746;17708.734368984216;6496.675166241764;6676.766313179015;6850.4862135340045;6910.654467276616;6985.196702379291;774.3263360875961;829.9170082991774;856.1737922904302;918.3387960668308;974.1622204903778;26081.9134469242;27530.862345688143;27639.585406218925;27860.860695696796;28250.71749282522;736.443984791479;902.7517432706059;917.554985831871;967.8935317115112;973.7662960889336;13472.730545389037;14333.211673357857;14461.13233206011;14634.780478292621;14714.190719510316;380738;382947;383553;384207;384457;46955.29567056497;47323;47448.52551474441;47726;47810.76094619574;1022.2384323678907;1025.5743866295552;1031.7317497450717;1094.070040465598;1143.3025854228913;27105.1868443947;28983;31426;31559.684403155676;32026.679733203302;45088.323675145555;45667.54332456633;45672.086558269315;45699.77150114361;45714.142878568484;352455;356229;358226;358280;358986;24561;24848.50302993966;25528.74471255338;25541.744582553936;26587.734122658527;39839.40240896331;39998.60001399949;40165.1966960665;40547.59452405556;40552.79723601481;34643.307133857685;34687.47968780521;34927.65072349244;34979.65020349764;35066.474002890485;33746;34148.31703365869;34221.82889085539;34277.65722342845;34674;34377.140571485914;34506;34766.30467390588;34957.126071848405;35268.82365588258;29479.410411792072;31841;31880;31902.52146217855;31964.6803531971;24448.388790280384;24596.139135130186;24867.502649947262;24893.626595601443;25226.74773252244 09:47:00 INFO - PROCESS | 4015 | |2;jquery.html;332.87018062955434;349.2264633836053;354.81726910641027;354.85450965104303;378.64029172286337;10971.286866353681;15864.524064278075;16237;16651.50045498634;17179.91410042948;16081.195940203;16759.24583393747;17271.82728172718;17402.82597174028;17477.650446991054;4839.975800121003;4852.902941941176;5053.873653158682;5056.84829455117;5094.184930411135;3591.44332628443;5031.4465408805;5058.595312375001;5074.2895994560795;5077.517635824591;3746.9437958430644;5613.354464236604;5852.180694702745;5867.501262392701;5977.133315669225;4974;5231.712255825928;5950.166976623276;5965.970170149121;5968.224130863004;5154.433012368637;5156.458571849977;5162.690238585659;5162.74186290684;5163.690178589259;11008.449577521093;11054;19704.21183152672;19887.701684474745;20031.39905802828;1737.7045902196658;1739.060907110159;1850.8889466632045;2001.449601359623;2106.1891171898774;8434.957825210835;8554.700585479479;8608.440451370641;8894.42186257891;9042.593083311267;1479.8520147985116;1600.1839062078307;1660.875434342417;1701.106918867592;1705.1474262868567;63625.68187159081;68163;68887.31112688858;70064.29935700679;70419.29580704178;9550.761230969281;9703.320767546345;9793.657221997197;9831.655892043744;10024.849627255597;13362.465501379836;13365;13404.463821447032;13413.865861341457;13430.059895807395;6311.368863113652;6521.413072823469;6597.208334999735;6669;6683.498737594603;5848.64908105515;5957.523398128139;6071.2714474263375;6094.360092190344;6138.907916381258;4048.838046478108;4059.411385349108;4093.7748423836974;4268.637165840938;4360.956390436055;1737.044625455994;1742.1985886492103;1797.8921264724158;1833.4591295567839;1858.0709645177412;777.8230900066566;811.7240138352988;841.2092632925031;936.681528280388;941.091846368258;15880;16474.917625411796;16684.416045438593;17328;17391.47823565576;789.3961119743401;789.8420315936836;799.8520273749375;803.8070862992956;881.4931414436725;9093.727188184364;9142.451452912846;9480.668176613786;9507.286953478379;9694.854577181348;376643;379500;380739;402207;405953;72259.63870180721;76026.23973760303;76688.23311766922;76850;81045.59477202577;806.1696452653777;825.2449009156675;827.2926647716237;827.5270019365539;830.1280612219407;14496.492622758153;14782.63477843583;14855.777163342775;14924.7761283583;15441.15073670914;36609.816950915076;37112.25775484529;37393;37463.62536374601;38031;44945.55054449414;47524.7623761879;52119;52200.82393408594;52616;7066.505344625827;7165.677544510405;7226.4218862491925;7279.199288078413;7385.113786345673;10201.897981020391;10761.838572421264;10877.891221087688;10974.945125274322;12006;9379.296552758435;9882.890320284561;9934.701958941243;10122.949385253027;10271.178305735673;10110.292279540361;10143.695689129338;10239.744006399602;10505.737356566147;10882.619108331488;9394.76513087151;9404.95297523508;9729.270304727315;9836.5081745911;10486.947565262124;6454.838629034311;6656.467482601283;6733.69698363565;7255.709771609077;7485.47601667878;10063.949680251844;10391.84412233802;10410.895891040993;10477.109445696897;10489.370637761758 09:47:00 INFO - PROCESS | 4015 | |3;mootools.html;1234.3704710597592;1342.4428862022262;1377.0223141569486;1434.239852877975;1673.7071012572803;6797.796066118019;7671.884921726171;7931.2861842434095;7969.203079692028;8142.063662678794;7898.802529936755;7976.561289129096;7987.600619968993;7993.2806047455715;8011.198880111986;2146.055735476389;2174.7281589801273;2176.281826997087;2176.9564608707815;2179.585878683053;2151.9139234430604;2187.7155969723995;2195.648696208607;2198.9560208795815;2199.098369668436;2135.829133669303;2139.3368055902642;2141.1435425829636;2144.5710857828417;2194.18815038436;2093.6231478333893;2104.2635077722825;2104.452842261008;2105.2526353144567;2105.4420578546697;2082.6875968604677;2125.702401663753;2126.978730212709;2131.3712454825863;2136.188248465573;7741.651625676858;7911.485753426009;7915.525068495851;7917.406194535375;7926.762197134095;4315.287977483711;4524.6606504511965;4525.2533332000185;4577.473590537097;4583.060472603109;2764.101666958247;2879.8704058317426;2932.7067293270716;2948.749356304695;2955.0543825975697;919.0901008002097;920.5029284186531;928.4661319741108;929.4423345992418;948.7580666929957;10803.351798892014;10890.727731806688;10918.617848375268;10921.781564368668;10943.616973405971;3706.4625629283605;3849.7305188637088;3978.144698889753;3979.4030895365927;4012.9799351003057;8425.4944703318;8469.703560375357;8480;8485.915140848636;8497.787555311166;4373.037931655031;4374.671899607542;4374.671899607542;4376.868693939187;4378.846740364072;3460.2733425980323;3544.80503572306;3870.748401353903;4080.4491393661474;4089.0799570097083;3301.7028467437567;3356.5636467259587;3443.4318337474037;3466.618671946084;3493.2140268439907;998.650472334677;1022.0597050713361;1037.0407373179778;1038.5326603028668;1045.2265323660436;603.5141710922661;703.8099713077543;708.8369674974784;723.5080145501005;729.6351824087956;4988.650794444354;5046.571041461443;5100.719460429712;5172.534471897472;5230.241614965808;807.8667019941761;815.4251252866715;844.5523872347666;848.6053984897053;873.7640836974109;3316.718078963267;3443.0875817908272;3509.3332266869215;3520.489529018278;3575.6424357564038;9454.810903781887;9994.500274986367;10013.098821106136;10020.799584008279;10038.899611003942;9677.419354838592;9939.80120397588;9940.502974851228;9979.950100249453;10005.199584033406;7391.85216295671;7521.360684341892;7522.172561018284;7529.849403011909;7540.773776786705;5017.12200364942;5021.899562008666;5072.365954255718;5082.745862706776;5285.101532739367;9116.863247051167;9248.075192480697;9330.673426430043;9346.345755797029;9347.719568412958;8715.52064636451;9110.453372797654;9125.22435592982;9386.296027797805;9392.718218453458;5719.342275638189;5911.733971971357;6391.201099862517;6524.314946930694;6538.771143010162;6935.930640693528;7113.679884405111;7180.497365184387;7320.9267907320245;7322.414206863332;6539.265005758562;6541.34586541342;6700.028495867974;6737.69680364375;6807.489438292049;6594.505412094016;6650.9667451662435;6670.699818508082;6675.833104172434;6682.832929176615;6151.446369826558;6251.343608920998;6259.78090766821;6267.686615669107;6269.529785266032;4367.301231802896;4494.280915053576;4530.524294948983;4600.079984003146;4601.28680054592;4426.490953540384;4481.260592002283;4571.908561828678;4608.470025947059;4735.668503204743 09:47:00 INFO - PROCESS | 4015 | |4;prototype.html;273.99589006164905;275.41474366970186;280.607149990014;285.8693865760916;285.9142257322803;4625.560571745687;4778.808847646094;4804.399450068741;4830.565249127573;4863.513648635135;4636.559526844952;4717.386739723831;4719.669623126382;4731.503192164825;4787.44944331402;3562.9109272268142;3656.6891814195824;3772.188979369435;3786.318462676717;3819;3632.4914511968345;3759.3045286621928;3760.191558814854;3760.9059773505746;3771.3211621908044;3747.6627103560672;3755.830987605564;3773.5094437723196;3779.0930176757656;3803.1062700265416;3742.476053352519;3763.8870833874753;3768.171002379472;3774.1130834253927;3796.6962642988497;3259.1526203187104;3297.8186199759;3302.5871766029245;3326.8170250636203;3357.1607098225445;4642.675012749109;4658.976705116486;4668.2064049111395;4671.602913752336;4673.38290557289;4072.694547908889;4280.700350975433;4302.483701955754;4310.590493903105;4313.590208930146;4975.776090075955;4998.475464983178;5002.7498625068965;5011.699298042129;5016.698998060128;3526.9118272043406;3532.6114127445962;3536.398812201932;3550.5384300041087;3551.6448355164534;5142;5250;5286.973565132188;5294;5303.946960530422;3527;3533.505309256706;3537.0980399997834;3541.256336169382;3546.361654902091;3608.45873119029;3611.320735857839;3614.963850361515;3615.5661320641166;3628.600853906068;4181.749095054306;4186.832526698898;4211.368294755811;4212.599803018708;4225.619694227535;4829.251466022772;4870.4155501340065;4954.380702412199;4973.726445045485;5085.8728531787;1228.5441751524472;1232.8027515597462;1237.9133460657667;1238.4984081447028;1246.4702501436852;771.6874665760377;801.1347744435996;813.5566116466422;814.7026335387541;824.9257566818895;3930.816039008412;3961.643452089326;3968.1865217630893;3968.206358728266;3978.781167035783;4517.164324599894;4550.316382938334;4556.407667003268;4586.220342541776;4591.517890621437;3970.3398722855754;3986.5614782373914;3995.840166393312;4006.258842114218;4010;2277.4420267034684;2413.9275821725378;2421.9515609687705;2424.6120620700603;2452.546278938402;4321.611055005064;4327.437433133672;4363.956360436418;4385.254506733863;4398.406215160912;4674.672772905864;4724.267738500538;4742.217534106902;4749.026449577828;4760.714357138583;4130.483689538808;4308.289132293136;4327.874752564293;4371.497277813029;4384.430024096847;4529.233598896499;4562.908741825211;4570.657200710027;4575.209122435378;4584.610308123847;4500.752458614807;4514.413126293561;4526.2305408081365;4543.6365090792;4558.794854231502;4017.3773065174946;4315.331123675835;4323.740575565476;4357.564243575617;4365.279728844832;3230.7738458307695;3232;3268.460703983816;3269.983650081734;3270.738340932672;4554.931676024796;4593.425821772278;4596.195665758412;4632.305154226893;4941.555260026615;4777.641676874317;4935.692041608905;5028.446870844277;5029.9245511318095;5044.54599086084;5012.3910784235295;5027.974860125676;5028.321176641253;5032.723200224023;5045.899082018265;4811.663183577116;4966.975165124151;4970.776315065758;4981.875453113701;4998.350214472095;5023.136812017261;5034.874128146826;5053.924191137063;5055.418626857959;5063.367079115111;4942.184539551077;4948.020791683355;4954.777035033504;4988.902441462872;5008.348914641073 09:47:00 INFO - PROCESS | 4015 | |5;yui.html;568.6474385880754;582.830979016085;618.4712071179142;636.729390009246;653.9771108011219;11916.7616647667;12270.386480675952;12758.170718903277;12766.234025958449;13082.476700931975;12235.877641223607;12404.627861164157;12460;12464.93767531161;12466.06504512163;593.7892048322848;722.9240929702385;739.3087463221877;741.3661319571743;742.109468637637;605.0863196573184;729.5659082845698;744.5644298085626;751.3163021650299;754.128981026912;779.9883001754978;783.0955246690082;784.1961988961332;785.1285073568334;785.3677789379548;608.8782243551263;779.555653277632;781.3046388714049;781.6248200863589;781.9257170568787;461.2274440312463;546.9589780766458;561.4553882733742;568.5224411494323;569.7151424287856;12373.381330933418;12394.132410731252;12400.255984640951;12413.875861241362;12431.62705118848;11780.528778848835;12338.81491777624;12362.629121126382;12766.042546809027;13121.803172952414;11595.627438715608;11760.353180575064;11966.880331196664;12143.453544590426;12188.146829721923;3861.826217820205;3871.922561548753;3883.941740873889;4068.1863627274665;4089.8364065437345;63634;64326.35673643297;65725.3427465724;66061;66570.66714666445;9801.215902727765;9929.156021738088;10033.498325083716;10364;10448.947755261175;17607.911960440117;17815.46553603394;17972.281108755502;17972.281108755764;17975;6344.524160687874;6419.486441084796;6549.181352330958;6562.278149403562;6721.596704197764;3593.5328407307406;3617.8733744319347;3644.1436262478296;3699.500567423417;3705.8517659293866;2730.30377253799;2804.5232310507263;2825.251308403275;2841.019848152384;2842.6730925943366;462.14733816109225;470.83050101963266;472.1242095912102;477.07209477566494;478.0319852299122;361.72328168950776;417.9540250572435;454.5386432770674;478.75344197738195;479.0514780734166;7099.822504437327;7363.852722945619;7444.664990075459;7606.087269527692;7758.456908016385;467.7357293129371;481.91084649339984;489.2954146029706;517.3429744224813;519.9220116982407;3478.1652403422995;3594.4248920172645;3598.2623562170215;3617.4754660574595;3679.961190616437;98733.50633246788;100190;100984.49507752561;108434.45782771036;108563;91783.5410822955;92338;93994.53002735079;94032;94542;80934.26453164258;82463.58768206241;82754.58622707089;83379.5831020841;83474.58262708648;6690.665466726741;6939.618320992394;6962.651867406508;7102.112235970504;7180.964095179491;45319;45415.545844541135;45752.54247457483;46531.302030470244;47817.76091119522;43965;52425.21362179494;62690.373096270276;64016.679916601985;66807.33192668011;10681;10867.510962006887;10988.615398461016;11123.27698699582;11416;11872.58445954422;12147.878521214674;12245.69385765363;12990.805137923127;13217.471301147847;10680.57277708883;11229.71925701864;12283.385830708608;12462.563810266598;12590.622281331574;9877.259205559469;10085.539877117419;10279.53742081623;11398.20212585111;11883.405829708307;10040.447775372424;11067.778644427228;11445.599404020819;11732.237404568676;12448.514415963544;9177.587008584496;9373.437593744397;10027.448490333101;10380;10789.676309710721;9932.801343973224;9996;10181.389116653025;10277;10335.638252661121 09:47:00 INFO - PROCESS | 4015 | __end_tp_report 09:47:00 INFO - PROCESS | 4015 | __start_cc_report 09:47:00 INFO - PROCESS | 4015 | _x_x_mozilla_cycle_collect,1080 09:47:00 INFO - PROCESS | 4015 | __end_cc_report 09:47:00 INFO - PROCESS | 4015 | __startTimestamp1474130820286__endTimestamp 09:47:00 INFO - PROCESS | 4015 | 09:47:00 INFO - PROCESS | 4015 | ------- Summary: start ------- 09:47:00 INFO - PROCESS | 4015 | Number of tests: 6 09:47:00 INFO - PROCESS | 4015 | 09:47:00 INFO - PROCESS | 4015 | [#0] dojo.html Cycles:165 Average:4660.81 Median:4791.62 stddev:481.08 (10.0%) stddev-sans-first:454.51 09:47:00 INFO - PROCESS | 4015 | Values: 2597.4 2626.1 2656.5 2659.9 2666.6 4491.4 4585.6 4703.1 4796.9 4855.2 4628.4 4667.7 4738.9 4749.6 4762.5 4327.2 4363.5 4363.5 4381.8 4451.4 4407.1 4460.3 4485.8 4490.8 4547.1 4285.7 4305.7 4330.0 4364.6 4368.0 4324.8 4349.9 4356.6 4360.8 4449.9 4333.1 4342.5 4352.3 4359.0 4394.3 4708.5 4710.3 4728.9 4736.4 4740.2 4926.6 4988.6 4994.8 5019.2 5022.8 5009.4 5028.9 5029.3 5030.0 5084.3 4707.1 4711.1 4713.9 4747.3 4767.6 5106.9 5115.6 5117.4 5144.5 5168.8 4910.6 4913.6 4916.4 4968.0 4977.7 4981.1 4993.3 4996.9 5004.6 5063.9 4934.9 4946.2 4952.5 5008.5 5020.5 4943.3 4946.8 4967.3 5031.1 5038.0 4264.9 4267.2 4270.4 4321.4 4327.8 3705.7 3732.2 3750.4 3756.6 3800.9 4230.2 4280.7 4281.1 4323.8 4348.5 5096.9 5098.3 5148.9 5153.2 5216.9 4381.8 4385.5 4385.8 4391.0 4444.1 4405.4 4439.6 4442.7 4502.0 4506.3 4762.7 4825.1 4832.1 4832.8 4837.1 5011.9 5023.1 5023.9 5026.1 5090.1 4587.4 4751.3 4760.1 4765.1 4808.2 4810.7 4973.3 5003.4 5036.7 5099.7 5086.6 5095.8 5121.7 5124.1 5188.2 4919.4 4965.0 4967.9 4972.2 4974.1 4740.9 4804.0 4829.2 4830.3 4855.4 4769.3 4796.4 4808.2 4832.9 4882.7 4914.1 4917.9 4930.2 4931.9 4981.3 4753.7 4770.8 4791.6 4817.4 4823.8 09:47:00 INFO - PROCESS | 4015 | 09:47:00 INFO - PROCESS | 4015 | [#1] ext.html Cycles:180 Average:47853.78 Median:37931.41 stddev:89296.25 (235.4%) stddev-sans-first:89476.14 09:47:00 INFO - PROCESS | 4015 | Values: 558.3 559.0 570.6 571.4 572.7 27149.3 38209.0 39352.0 39486.6 43724.8 39357.0 39662.8 39775.4 39832.2 39863.4 7473.2 7638.3 7651.1 7659.1 7660.4 7428.8 7504.7 7510.1 7511.8 7512.3 7507.2 7556.1 7565.7 7570.7 7575.1 7482.6 7483.8 7529.3 7532.4 7545.5 7463.6 7514.1 7528.3 7537.2 7538.2 37538.2 37929.8 37933.0 38035.0 38067.2 2927.4 2957.0 2957.6 2973.1 3048.8 28148.3 28157.6 28782.9 28821.0 30542.2 3095.1 3140.5 3150.0 3160.7 3161.1 262527.0 263435.0 264011.0 264038.0 264575.0 50313.2 50360.2 50549.5 50584.5 50916.5 32257.8 33270.8 33313.0 53194.0 53470.0 11721.0 11827.8 11868.5 11875.8 11906.3 9035.2 9067.7 9102.5 9112.2 9143.3 17515.3 17585.6 17620.3 17652.2 17708.7 6496.7 6676.8 6850.5 6910.7 6985.2 774.3 829.9 856.2 918.3 974.2 26081.9 27530.9 27639.6 27860.9 28250.7 736.4 902.8 917.6 967.9 973.8 13472.7 14333.2 14461.1 14634.8 14714.2 380738.0 382947.0 383553.0 384207.0 384457.0 46955.3 47323.0 47448.5 47726.0 47810.8 1022.2 1025.6 1031.7 1094.1 1143.3 27105.2 28983.0 31426.0 31559.7 32026.7 45088.3 45667.5 45672.1 45699.8 45714.1 352455.0 356229.0 358226.0 358280.0 358986.0 24561.0 24848.5 25528.7 25541.7 26587.7 39839.4 39998.6 40165.2 40547.6 40552.8 34643.3 34687.5 34927.7 34979.7 35066.5 33746.0 34148.3 34221.8 34277.7 34674.0 34377.1 34506.0 34766.3 34957.1 35268.8 29479.4 31841.0 31880.0 31902.5 31964.7 24448.4 24596.1 24867.5 24893.6 25226.7 09:47:00 INFO - PROCESS | 4015 | 09:47:00 INFO - PROCESS | 4015 | [#2] jquery.html Cycles:180 Average:23862.04 Median:5002.72 stddev:64305.69 (1285.4%) stddev-sans-first:64461.81 09:47:00 INFO - PROCESS | 4015 | Values: 332.9 349.2 354.8 354.9 378.6 10971.3 15864.5 16237.0 16651.5 17179.9 16081.2 16759.2 17271.8 17402.8 17477.7 4840.0 4852.9 5053.9 5056.8 5094.2 3591.4 5031.4 5058.6 5074.3 5077.5 3746.9 5613.4 5852.2 5867.5 5977.1 4974.0 5231.7 5950.2 5966.0 5968.2 5154.4 5156.5 5162.7 5162.7 5163.7 11008.4 11054.0 19704.2 19887.7 20031.4 1737.7 1739.1 1850.9 2001.4 2106.2 8435.0 8554.7 8608.4 8894.4 9042.6 1479.9 1600.2 1660.9 1701.1 1705.1 63625.7 68163.0 68887.3 70064.3 70419.3 9550.8 9703.3 9793.7 9831.7 10024.8 13362.5 13365.0 13404.5 13413.9 13430.1 6311.4 6521.4 6597.2 6669.0 6683.5 5848.6 5957.5 6071.3 6094.4 6138.9 4048.8 4059.4 4093.8 4268.6 4361.0 1737.0 1742.2 1797.9 1833.5 1858.1 777.8 811.7 841.2 936.7 941.1 15880.0 16474.9 16684.4 17328.0 17391.5 789.4 789.8 799.9 803.8 881.5 9093.7 9142.5 9480.7 9507.3 9694.9 376643.0 379500.0 380739.0 402207.0 405953.0 72259.6 76026.2 76688.2 76850.0 81045.6 806.2 825.2 827.3 827.5 830.1 14496.5 14782.6 14855.8 14924.8 15441.2 36609.8 37112.3 37393.0 37463.6 38031.0 44945.6 47524.8 52119.0 52200.8 52616.0 7066.5 7165.7 7226.4 7279.2 7385.1 10201.9 10761.8 10877.9 10974.9 12006.0 9379.3 9882.9 9934.7 10122.9 10271.2 10110.3 10143.7 10239.7 10505.7 10882.6 9394.8 9405.0 9729.3 9836.5 10486.9 6454.8 6656.5 6733.7 7255.7 7485.5 10063.9 10391.8 10410.9 10477.1 10489.4 09:47:00 INFO - PROCESS | 4015 | 09:47:00 INFO - PROCESS | 4015 | [#3] mootools.html Cycles:180 Average:5080.92 Median:5034.24 stddev:2941.01 (58.4%) stddev-sans-first:2935.06 09:47:00 INFO - PROCESS | 4015 | Values: 1234.4 1342.4 1377.0 1434.2 1673.7 6797.8 7671.9 7931.3 7969.2 8142.1 7898.8 7976.6 7987.6 7993.3 8011.2 2146.1 2174.7 2176.3 2177.0 2179.6 2151.9 2187.7 2195.6 2199.0 2199.1 2135.8 2139.3 2141.1 2144.6 2194.2 2093.6 2104.3 2104.5 2105.3 2105.4 2082.7 2125.7 2127.0 2131.4 2136.2 7741.7 7911.5 7915.5 7917.4 7926.8 4315.3 4524.7 4525.3 4577.5 4583.1 2764.1 2879.9 2932.7 2948.7 2955.1 919.1 920.5 928.5 929.4 948.8 10803.4 10890.7 10918.6 10921.8 10943.6 3706.5 3849.7 3978.1 3979.4 4013.0 8425.5 8469.7 8480.0 8485.9 8497.8 4373.0 4374.7 4374.7 4376.9 4378.8 3460.3 3544.8 3870.7 4080.4 4089.1 3301.7 3356.6 3443.4 3466.6 3493.2 998.7 1022.1 1037.0 1038.5 1045.2 603.5 703.8 708.8 723.5 729.6 4988.7 5046.6 5100.7 5172.5 5230.2 807.9 815.4 844.6 848.6 873.8 3316.7 3443.1 3509.3 3520.5 3575.6 9454.8 9994.5 10013.1 10020.8 10038.9 9677.4 9939.8 9940.5 9980.0 10005.2 7391.9 7521.4 7522.2 7529.8 7540.8 5017.1 5021.9 5072.4 5082.7 5285.1 9116.9 9248.1 9330.7 9346.3 9347.7 8715.5 9110.5 9125.2 9386.3 9392.7 5719.3 5911.7 6391.2 6524.3 6538.8 6935.9 7113.7 7180.5 7320.9 7322.4 6539.3 6541.3 6700.0 6737.7 6807.5 6594.5 6651.0 6670.7 6675.8 6682.8 6151.4 6251.3 6259.8 6267.7 6269.5 4367.3 4494.3 4530.5 4600.1 4601.3 4426.5 4481.3 4571.9 4608.5 4735.7 09:47:00 INFO - PROCESS | 4015 | 09:47:00 INFO - PROCESS | 4015 | [#4] prototype.html Cycles:180 Average:3991.38 Median:4368.39 stddev:1163.38 (26.6%) stddev-sans-first:1132.70 09:47:00 INFO - PROCESS | 4015 | Values: 274.0 275.4 280.6 285.9 285.9 4625.6 4778.8 4804.4 4830.6 4863.5 4636.6 4717.4 4719.7 4731.5 4787.4 3562.9 3656.7 3772.2 3786.3 3819.0 3632.5 3759.3 3760.2 3760.9 3771.3 3747.7 3755.8 3773.5 3779.1 3803.1 3742.5 3763.9 3768.2 3774.1 3796.7 3259.2 3297.8 3302.6 3326.8 3357.2 4642.7 4659.0 4668.2 4671.6 4673.4 4072.7 4280.7 4302.5 4310.6 4313.6 4975.8 4998.5 5002.7 5011.7 5016.7 3526.9 3532.6 3536.4 3550.5 3551.6 5142.0 5250.0 5287.0 5294.0 5303.9 3527.0 3533.5 3537.1 3541.3 3546.4 3608.5 3611.3 3615.0 3615.6 3628.6 4181.7 4186.8 4211.4 4212.6 4225.6 4829.3 4870.4 4954.4 4973.7 5085.9 1228.5 1232.8 1237.9 1238.5 1246.5 771.7 801.1 813.6 814.7 824.9 3930.8 3961.6 3968.2 3968.2 3978.8 4517.2 4550.3 4556.4 4586.2 4591.5 3970.3 3986.6 3995.8 4006.3 4010.0 2277.4 2413.9 2422.0 2424.6 2452.5 4321.6 4327.4 4364.0 4385.3 4398.4 4674.7 4724.3 4742.2 4749.0 4760.7 4130.5 4308.3 4327.9 4371.5 4384.4 4529.2 4562.9 4570.7 4575.2 4584.6 4500.8 4514.4 4526.2 4543.6 4558.8 4017.4 4315.3 4323.7 4357.6 4365.3 3230.8 3232.0 3268.5 3270.0 3270.7 4554.9 4593.4 4596.2 4632.3 4941.6 4777.6 4935.7 5028.4 5029.9 5044.5 5012.4 5028.0 5028.3 5032.7 5045.9 4811.7 4967.0 4970.8 4981.9 4998.4 5023.1 5034.9 5053.9 5055.4 5063.4 4942.2 4948.0 4954.8 4988.9 5008.3 09:47:00 INFO - PROCESS | 4015 | 09:47:00 INFO - PROCESS | 4015 | [#5] yui.html Cycles:180 Average:18235.21 Median:22886.77 stddev:27130.19 (118.5%) stddev-sans-first:27173.87 09:47:00 INFO - PROCESS | 4015 | Values: 568.6 582.8 618.5 636.7 654.0 11916.8 12270.4 12758.2 12766.2 13082.5 12235.9 12404.6 12460.0 12464.9 12466.1 593.8 722.9 739.3 741.4 742.1 605.1 729.6 744.6 751.3 754.1 780.0 783.1 784.2 785.1 785.4 608.9 779.6 781.3 781.6 781.9 461.2 547.0 561.5 568.5 569.7 12373.4 12394.1 12400.3 12413.9 12431.6 11780.5 12338.8 12362.6 12766.0 13121.8 11595.6 11760.4 11966.9 12143.5 12188.1 3861.8 3871.9 3883.9 4068.2 4089.8 63634.0 64326.4 65725.3 66061.0 66570.7 9801.2 9929.2 10033.5 10364.0 10448.9 17607.9 17815.5 17972.3 17972.3 17975.0 6344.5 6419.5 6549.2 6562.3 6721.6 3593.5 3617.9 3644.1 3699.5 3705.9 2730.3 2804.5 2825.3 2841.0 2842.7 462.1 470.8 472.1 477.1 478.0 361.7 418.0 454.5 478.8 479.1 7099.8 7363.9 7444.7 7606.1 7758.5 467.7 481.9 489.3 517.3 519.9 3478.2 3594.4 3598.3 3617.5 3680.0 98733.5 100190.0 100984.5 108434.5 108563.0 91783.5 92338.0 93994.5 94032.0 94542.0 80934.3 82463.6 82754.6 83379.6 83474.6 6690.7 6939.6 6962.7 7102.1 7181.0 45319.0 45415.5 45752.5 46531.3 47817.8 43965.0 52425.2 62690.4 64016.7 66807.3 10681.0 10867.5 10988.6 11123.3 11416.0 11872.6 12147.9 12245.7 12990.8 13217.5 10680.6 11229.7 12283.4 12462.6 12590.6 9877.3 10085.5 10279.5 11398.2 11883.4 10040.4 11067.8 11445.6 11732.2 12448.5 9177.6 9373.4 10027.4 10380.0 10789.7 9932.8 9996.0 10181.4 10277.0 10335.6 09:47:00 INFO - PROCESS | 4015 | -------- Summary: end -------- 09:47:00 INFO - PROCESS | 4015 | 09:47:01 INFO - TEST-INFO | 4015: exit 0 09:47:01 INFO - TEST-OK | dromaeo_css | took 1121570ms 09:47:01 INFO - TEST-START | kraken 09:47:01 INFO - operating with platform_type : linux_ 09:47:01 INFO - Initialising browser for kraken test... 09:47:01 INFO - TEST-INFO | started process 4327 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpysJLws/profile http://localhost:34105/getInfo.html) 09:47:06 INFO - PROCESS | 4327 | __metrics Screen width/height:1600/1200 09:47:06 INFO - PROCESS | 4327 | colorDepth:24 09:47:06 INFO - PROCESS | 4327 | Browser inner width/height: 1024/697 09:47:06 INFO - PROCESS | 4327 | __metrics 09:47:06 INFO - PROCESS | 4327 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:47:06 INFO - PROCESS | 4327 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:47:07 INFO - PROCESS | 4327 | console.error: PushService: 09:47:07 INFO - PROCESS | 4327 | stateChangeProcessEnqueue: Error transitioning state 09:47:07 INFO - PROCESS | 4327 | UnknownError 09:47:07 INFO - TEST-INFO | 4327: exit 0 09:47:07 INFO - Browser initialized. 09:47:07 INFO - Running cycle 1/1 for kraken test... 09:47:07 INFO - TEST-INFO | started process 4411 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpysJLws/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:47:20 INFO - PROCESS | 4411 | RSS: Main: 174305280 09:47:20 INFO - PROCESS | 4411 | 09:47:23 INFO - PROCESS | 4411 | Cycle 1(1): loaded http://localhost:34105/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34105/tests/kraken/driver.html?testName=audio-beat-detection) 09:47:23 INFO - PROCESS | 4411 | RSS: Main: 241979392 09:47:23 INFO - PROCESS | 4411 | 09:47:26 INFO - PROCESS | 4411 | Cycle 1(1): loaded http://localhost:34105/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34105/tests/kraken/driver.html?testName=audio-dft) 09:47:27 INFO - PROCESS | 4411 | RSS: Main: 217939968 09:47:27 INFO - PROCESS | 4411 | 09:47:30 INFO - PROCESS | 4411 | Cycle 1(1): loaded http://localhost:34105/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34105/tests/kraken/driver.html?testName=audio-fft) 09:47:31 INFO - PROCESS | 4411 | RSS: Main: 299667456 09:47:31 INFO - PROCESS | 4411 | 09:47:33 INFO - PROCESS | 4411 | Cycle 1(1): loaded http://localhost:34105/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34105/tests/kraken/driver.html?testName=audio-oscillator) 09:47:33 INFO - PROCESS | 4411 | RSS: Main: 209190912 09:47:33 INFO - PROCESS | 4411 | 09:47:36 INFO - PROCESS | 4411 | Cycle 1(1): loaded http://localhost:34105/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34105/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:47:36 INFO - PROCESS | 4411 | RSS: Main: 213041152 09:47:36 INFO - PROCESS | 4411 | 09:47:40 INFO - PROCESS | 4411 | Cycle 1(1): loaded http://localhost:34105/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34105/tests/kraken/driver.html?testName=imaging-darkroom) 09:47:40 INFO - PROCESS | 4411 | RSS: Main: 249716736 09:47:40 INFO - PROCESS | 4411 | 09:47:44 INFO - PROCESS | 4411 | Cycle 1(1): loaded http://localhost:34105/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34105/tests/kraken/driver.html?testName=imaging-desaturate) 09:47:44 INFO - PROCESS | 4411 | RSS: Main: 248356864 09:47:44 INFO - PROCESS | 4411 | 09:47:47 INFO - PROCESS | 4411 | Cycle 1(1): loaded http://localhost:34105/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34105/tests/kraken/driver.html?testName=json-parse-financial) 09:47:48 INFO - PROCESS | 4411 | RSS: Main: 248315904 09:47:48 INFO - PROCESS | 4411 | 09:47:49 INFO - PROCESS | 4411 | Cycle 1(1): loaded http://localhost:34105/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34105/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:47:50 INFO - PROCESS | 4411 | RSS: Main: 210939904 09:47:50 INFO - PROCESS | 4411 | 09:47:52 INFO - PROCESS | 4411 | Cycle 1(1): loaded http://localhost:34105/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34105/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:47:53 INFO - PROCESS | 4411 | RSS: Main: 218685440 09:47:53 INFO - PROCESS | 4411 | 09:47:55 INFO - PROCESS | 4411 | Cycle 1(1): loaded http://localhost:34105/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34105/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:47:55 INFO - PROCESS | 4411 | RSS: Main: 222597120 09:47:55 INFO - PROCESS | 4411 | 09:47:58 INFO - PROCESS | 4411 | Cycle 1(1): loaded http://localhost:34105/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34105/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:47:59 INFO - PROCESS | 4411 | RSS: Main: 223256576 09:47:59 INFO - PROCESS | 4411 | 09:48:02 INFO - PROCESS | 4411 | Cycle 1(1): loaded http://localhost:34105/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34105/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:48:03 INFO - PROCESS | 4411 | RSS: Main: 222621696 09:48:03 INFO - PROCESS | 4411 | 09:48:04 INFO - PROCESS | 4411 | Cycle 1(1): loaded http://localhost:34105/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34105/tests/kraken/driver.html?testName=ai-astar) 09:48:05 INFO - PROCESS | 4411 | RSS: Main: 273956864 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | __start_tp_report 09:48:05 INFO - PROCESS | 4411 | _x_x_mozilla_page_load 09:48:05 INFO - PROCESS | 4411 | _x_x_mozilla_page_load_details 09:48:05 INFO - PROCESS | 4411 | |i|pagename|runs| 09:48:05 INFO - PROCESS | 4411 | |0;ai-astar;112;117;117;116;115;116;115;115;115;115 09:48:05 INFO - PROCESS | 4411 | |1;audio-beat-detection;149;132;126;169;124;125;124;129;169;136 09:48:05 INFO - PROCESS | 4411 | |2;audio-dft;160;160;162;162;161;161;162;163;161;161 09:48:05 INFO - PROCESS | 4411 | |3;audio-fft;76;76;77;103;77;76;77;76;111;78 09:48:05 INFO - PROCESS | 4411 | |4;audio-oscillator;84;84;127;85;85;85;116;84;85;117 09:48:05 INFO - PROCESS | 4411 | |5;imaging-gaussian-blur;118;117;116;116;117;116;118;118;117;117 09:48:05 INFO - PROCESS | 4411 | |6;imaging-darkroom;146;146;146;147;146;146;148;146;146;146 09:48:05 INFO - PROCESS | 4411 | |7;imaging-desaturate;95;95;95;94;95;107;94;96;95;94 09:48:05 INFO - PROCESS | 4411 | |8;json-parse-financial;67;65;67;65;67;65;67;65;66;65 09:48:05 INFO - PROCESS | 4411 | |9;json-stringify-tinderbox;66;66;66;66;66;66;66;66;67;67 09:48:05 INFO - PROCESS | 4411 | |10;stanford-crypto-aes;89;83;82;82;87;83;82;88;82;82 09:48:05 INFO - PROCESS | 4411 | |11;stanford-crypto-ccm;134;150;142;135;132;131;134;140;187;142 09:48:05 INFO - PROCESS | 4411 | |12;stanford-crypto-pbkdf2;177;178;180;177;173;175;171;172;179;174 09:48:05 INFO - PROCESS | 4411 | |13;stanford-crypto-sha256-iterative;69;68;69;69;69;68;68;69;69;71 09:48:05 INFO - PROCESS | 4411 | __end_tp_report 09:48:05 INFO - PROCESS | 4411 | __start_cc_report 09:48:05 INFO - PROCESS | 4411 | _x_x_mozilla_cycle_collect,1950 09:48:05 INFO - PROCESS | 4411 | __end_cc_report 09:48:05 INFO - PROCESS | 4411 | __startTimestamp1474130885030__endTimestamp 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | ------- Summary: start ------- 09:48:05 INFO - PROCESS | 4411 | Number of tests: 14 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | [#0] ai-astar Cycles:10 Average:115.30 Median:115.50 stddev:1.42 (1.2%) stddev-sans-first:0.87 09:48:05 INFO - PROCESS | 4411 | Values: 112.0 117.0 117.0 116.0 115.0 116.0 115.0 115.0 115.0 115.0 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:134.00 stddev:17.85 (13.3%) stddev-sans-first:18.51 09:48:05 INFO - PROCESS | 4411 | Values: 149.0 132.0 126.0 169.0 124.0 125.0 124.0 129.0 169.0 136.0 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.95 (0.6%) stddev-sans-first:0.88 09:48:05 INFO - PROCESS | 4411 | Values: 160.0 160.0 162.0 162.0 161.0 161.0 162.0 163.0 161.0 161.0 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | [#3] audio-fft Cycles:10 Average:82.70 Median:76.50 stddev:12.96 (16.9%) stddev-sans-first:13.52 09:48:05 INFO - PROCESS | 4411 | Values: 76.0 76.0 77.0 103.0 77.0 76.0 77.0 76.0 111.0 78.0 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:84.50 stddev:17.36 (20.5%) stddev-sans-first:17.93 09:48:05 INFO - PROCESS | 4411 | Values: 84.0 84.0 127.0 85.0 85.0 85.0 116.0 84.0 85.0 117.0 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.82 (0.7%) stddev-sans-first:0.78 09:48:05 INFO - PROCESS | 4411 | Values: 118.0 117.0 116.0 116.0 117.0 116.0 118.0 118.0 117.0 117.0 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 09:48:05 INFO - PROCESS | 4411 | Values: 146.0 146.0 146.0 147.0 146.0 146.0 148.0 146.0 146.0 146.0 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:95.00 stddev:3.92 (4.1%) stddev-sans-first:4.14 09:48:05 INFO - PROCESS | 4411 | Values: 95.0 95.0 95.0 94.0 95.0 107.0 94.0 96.0 95.0 94.0 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | [#8] json-parse-financial Cycles:10 Average:65.90 Median:66.50 stddev:0.99 (1.5%) stddev-sans-first:0.97 09:48:05 INFO - PROCESS | 4411 | Values: 67.0 65.0 67.0 65.0 67.0 65.0 67.0 65.0 66.0 65.0 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 09:48:05 INFO - PROCESS | 4411 | Values: 66.0 66.0 66.0 66.0 66.0 66.0 66.0 66.0 67.0 67.0 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:83.00 stddev:2.83 (3.4%) stddev-sans-first:2.35 09:48:05 INFO - PROCESS | 4411 | Values: 89.0 83.0 82.0 82.0 87.0 83.0 82.0 88.0 82.0 82.0 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | [#11] stanford-crypto-ccm Cycles:10 Average:142.70 Median:141.00 stddev:16.62 (11.8%) stddev-sans-first:17.33 09:48:05 INFO - PROCESS | 4411 | Values: 134.0 150.0 142.0 135.0 132.0 131.0 134.0 140.0 187.0 142.0 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:177.00 stddev:3.06 (1.7%) stddev-sans-first:3.21 09:48:05 INFO - PROCESS | 4411 | Values: 177.0 178.0 180.0 177.0 173.0 175.0 171.0 172.0 179.0 174.0 09:48:05 INFO - PROCESS | 4411 | 09:48:05 INFO - PROCESS | 4411 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 09:48:05 INFO - PROCESS | 4411 | Values: 69.0 68.0 69.0 69.0 69.0 68.0 68.0 69.0 69.0 71.0 09:48:05 INFO - PROCESS | 4411 | -------- Summary: end -------- 09:48:05 INFO - PROCESS | 4411 | 09:48:06 INFO - TEST-INFO | 4411: exit 0 09:48:06 INFO - TEST-OK | kraken | took 65038ms 09:48:06 INFO - SUITE-END | took 1186s 09:48:06 INFO - Completed test suite (00:19:46) 09:48:06 INFO - javascript benchmark 09:48:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2597.441550066735, 2626.107123577983, 2656.4687062587486, 2659.933501662458, 2666.6000099985, 4491.416115904936, 4585.564371384713, 4703.106409782139, 4796.8560943171715, 4855.247436647316, 4628.444586649598, 4667.6965997210245, 4738.90522189556, 4749.620030397568, 4762.547557981995, 4327.177836211125, 4363.454568178978, 4363.54182810805, 4381.824727010916, 4451.376807246988, 4407.052483716, 4460.33095035744, 4485.842995495158, 4490.797914093873, 4547.136044151617, 4285.700000999931, 4305.74165550068, 4330.047389574289, 4364.607185353333, 4368.0, 4324.762138082404, 4349.891252718676, 4356.564343565649, 4360.760158191299, 4449.933251001237, 4333.090051089275, 4342.522322544518, 4352.260115780293, 4359.0, 4394.252976993918, 4708.4820669726305, 4710.26990816424, 4728.881777955578, 4736.431628204603, 4740.241561350202, 4926.605871530305, 4988.551030407244, 4994.775235114428, 5019.247112933052, 5022.824201152978, 5009.398872135331, 5028.949710502921, 5029.321041659366, 5030.044291584587, 5084.288199652052, 4707.105649926503, 4711.081339138835, 4713.9057218856115, 4747.287906813971, 4767.594754445841, 5106.923396149061, 5115.616328775282, 5117.385913690307, 5144.4855514449, 5168.793248270103, 4910.631702622318, 4913.631477639191, 4916.385451818523, 4967.975160124176, 4977.726225057584, 4981.053599816023, 4993.300937868701, 4996.875078123076, 5004.624653151028, 5063.898722025612, 4934.925976110361, 4946.159152944008, 4952.455229924705, 5008.524190201925, 5020.49795020495, 4943.2585112233455, 4946.777395017233, 4967.254911763264, 5031.094403007421, 5038.0, 4264.850730224427, 4267.231898258343, 4270.35944608305, 4321.416608757841, 4327.805248763751, 3705.70354371655, 3732.197577520846, 3750.362438385481, 3756.6431189037, 3800.9239815204096, 4230.15396920611, 4280.743155410685, 4281.143771245764, 4323.783810809447, 4348.499922508889, 5096.898062038738, 5098.337216161875, 5148.897022059537, 5153.201253805666, 5216.895662086737, 4381.824727010948, 4385.495667998221, 4385.824567017347, 4391.0, 4444.133393988206, 4405.383246345515, 4439.578240067189, 4442.68901176921, 4502.0, 4506.2564676828615, 4762.690425122356, 4825.059113472909, 4832.130216560912, 4832.830850920201, 4837.105135549935, 5011.924821127759, 5023.120953832991, 5023.899522009612, 5026.145555255543, 5090.134677104827, 4587.449506059294, 4751.263554149113, 4760.119377914958, 4765.142274390643, 4808.182608956416, 4810.687305325143, 4973.278874563241, 5003.374578177728, 5036.697798132123, 5099.69401835891, 5086.567641750493, 5095.847124586268, 5121.74391280442, 5124.12889808726, 5188.19582964626, 4919.434265059564, 4964.950350496449, 4967.875803104951, 4972.229304457815, 4974.104661161025, 4740.857774266778, 4803.951960480351, 4829.227323628203, 4830.347903033186, 4855.36880205571, 4769.30845027476, 4796.352492413619, 4808.230683090689, 4832.927506087482, 4882.731449770296, 4914.140025495595, 4917.852464426073, 4930.161872481758, 4931.876703082452, 4981.277714731416, 4753.714777113384, 4770.809167633394, 4791.616670666408, 4817.445993710769, 4823.831165909176], "value": 4630.46200049826, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.2881825672268, 559.0300828063307, 570.57207094679, 571.3629303326792, 572.6649909802766, 27149.321266968313, 38209.0, 39351.99999999996, 39486.60513394865, 43724.78137609307, 39357.0, 39662.80168599153, 39775.40336894942, 39832.20335593286, 39863.40204896929, 7473.177950425463, 7638.274363935445, 7651.081870175587, 7659.119201291867, 7660.387169026464, 7428.777136685908, 7504.6998120075405, 7510.09878814543, 7511.812204694872, 7512.286332798375, 7507.211742767013, 7556.093268807724, 7565.697372105109, 7570.659320330571, 7575.1288601810675, 7482.551046937147, 7483.775486735407, 7529.322360987483, 7532.435067369969, 7545.509541879787, 7463.589502577356, 7514.135874374462, 7528.284812942762, 7537.170911199763, 7538.170801211862, 37538.24923501542, 37929.81035094835, 37933.0, 38035.04912377185, 38067.23865522702, 2927.385249097692, 2957.0, 2957.600723902267, 2973.108067579739, 3048.832314222717, 28148.296292592644, 28157.57763633547, 28782.856085719646, 28821.0, 30542.236444088852, 3095.071478556424, 3140.5132204508336, 3150.0, 3160.747140228776, 3161.0674850919036, 262527.0, 263435.0, 264011.0, 264038.0, 264575.0, 50313.24530132051, 50360.24459633108, 50549.49450505521, 50584.494155057975, 50916.49083509117, 32257.838710806765, 33270.83364583162, 33313.0, 53194.0, 53470.0, 11721.0, 11827.822582661267, 11868.465919033664, 11875.76248475043, 11906.285622862655, 9035.186833185046, 9067.727968160965, 9102.453852768855, 9112.22546083577, 9143.31425143117, 17515.299388024338, 17585.648287034186, 17620.295188192584, 17652.205650745746, 17708.734368984216, 6496.675166241764, 6676.766313179015, 6850.4862135340045, 6910.654467276616, 6985.196702379291, 774.3263360875961, 829.9170082991774, 856.1737922904302, 918.3387960668308, 974.1622204903778, 26081.9134469242, 27530.862345688143, 27639.585406218925, 27860.860695696796, 28250.71749282522, 736.443984791479, 902.7517432706059, 917.554985831871, 967.8935317115112, 973.7662960889336, 13472.730545389037, 14333.211673357857, 14461.13233206011, 14634.780478292621, 14714.190719510316, 380738.0, 382947.0, 383553.0, 384207.0, 384457.0, 46955.29567056497, 47323.0, 47448.52551474441, 47726.0, 47810.76094619574, 1022.2384323678907, 1025.5743866295552, 1031.7317497450717, 1094.070040465598, 1143.3025854228913, 27105.1868443947, 28983.0, 31426.0, 31559.684403155676, 32026.679733203302, 45088.323675145555, 45667.54332456633, 45672.086558269315, 45699.77150114361, 45714.142878568484, 352455.0, 356229.0, 358226.0, 358280.0, 358986.0, 24561.0, 24848.50302993966, 25528.74471255338, 25541.744582553936, 26587.734122658527, 39839.40240896331, 39998.60001399949, 40165.1966960665, 40547.59452405556, 40552.79723601481, 34643.307133857685, 34687.47968780521, 34927.65072349244, 34979.65020349764, 35066.474002890485, 33746.0, 34148.31703365869, 34221.82889085539, 34277.65722342845, 34674.0, 34377.140571485914, 34506.0, 34766.30467390588, 34957.126071848405, 35268.82365588258, 29479.410411792072, 31841.0, 31880.0, 31902.52146217855, 31964.6803531971, 24448.388790280384, 24596.139135130186, 24867.502649947262, 24893.626595601443, 25226.74773252244], "value": 16851.622964612507, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.87018062955434, 349.2264633836053, 354.81726910641027, 354.85450965104303, 378.64029172286337, 10971.286866353681, 15864.524064278075, 16237.0, 16651.50045498634, 17179.91410042948, 16081.195940203, 16759.24583393747, 17271.82728172718, 17402.82597174028, 17477.650446991054, 4839.975800121003, 4852.902941941176, 5053.873653158682, 5056.84829455117, 5094.184930411135, 3591.44332628443, 5031.4465408805, 5058.595312375001, 5074.2895994560795, 5077.517635824591, 3746.9437958430644, 5613.354464236604, 5852.180694702745, 5867.501262392701, 5977.133315669225, 4974.0, 5231.712255825928, 5950.166976623276, 5965.970170149121, 5968.224130863004, 5154.433012368637, 5156.458571849977, 5162.690238585659, 5162.74186290684, 5163.690178589259, 11008.449577521093, 11054.0, 19704.21183152672, 19887.701684474745, 20031.39905802828, 1737.7045902196658, 1739.060907110159, 1850.8889466632045, 2001.449601359623, 2106.1891171898774, 8434.957825210835, 8554.700585479479, 8608.440451370641, 8894.42186257891, 9042.593083311267, 1479.8520147985116, 1600.1839062078307, 1660.875434342417, 1701.106918867592, 1705.1474262868567, 63625.68187159081, 68163.0, 68887.31112688858, 70064.29935700679, 70419.29580704178, 9550.761230969281, 9703.320767546345, 9793.657221997197, 9831.655892043744, 10024.849627255597, 13362.465501379836, 13365.0, 13404.463821447032, 13413.865861341457, 13430.059895807395, 6311.368863113652, 6521.413072823469, 6597.208334999735, 6669.0, 6683.498737594603, 5848.64908105515, 5957.523398128139, 6071.2714474263375, 6094.360092190344, 6138.907916381258, 4048.838046478108, 4059.411385349108, 4093.7748423836974, 4268.637165840938, 4360.956390436055, 1737.044625455994, 1742.1985886492103, 1797.8921264724158, 1833.4591295567839, 1858.0709645177412, 777.8230900066566, 811.7240138352988, 841.2092632925031, 936.681528280388, 941.091846368258, 15880.0, 16474.917625411796, 16684.416045438593, 17328.0, 17391.47823565576, 789.3961119743401, 789.8420315936836, 799.8520273749375, 803.8070862992956, 881.4931414436725, 9093.727188184364, 9142.451452912846, 9480.668176613786, 9507.286953478379, 9694.854577181348, 376643.0, 379500.0, 380739.0, 402207.0, 405953.0, 72259.63870180721, 76026.23973760303, 76688.23311766922, 76850.0, 81045.59477202577, 806.1696452653777, 825.2449009156675, 827.2926647716237, 827.5270019365539, 830.1280612219407, 14496.492622758153, 14782.63477843583, 14855.777163342775, 14924.7761283583, 15441.15073670914, 36609.816950915076, 37112.25775484529, 37393.0, 37463.62536374601, 38031.0, 44945.55054449414, 47524.7623761879, 52119.0, 52200.82393408594, 52616.0, 7066.505344625827, 7165.677544510405, 7226.4218862491925, 7279.199288078413, 7385.113786345673, 10201.897981020391, 10761.838572421264, 10877.891221087688, 10974.945125274322, 12006.0, 9379.296552758435, 9882.890320284561, 9934.701958941243, 10122.949385253027, 10271.178305735673, 10110.292279540361, 10143.695689129338, 10239.744006399602, 10505.737356566147, 10882.619108331488, 9394.76513087151, 9404.95297523508, 9729.270304727315, 9836.5081745911, 10486.947565262124, 6454.838629034311, 6656.467482601283, 6733.69698363565, 7255.709771609077, 7485.47601667878, 10063.949680251844, 10391.84412233802, 10410.895891040993, 10477.109445696897, 10489.370637761758], "value": 7859.243012607518, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1234.3704710597592, 1342.4428862022262, 1377.0223141569486, 1434.239852877975, 1673.7071012572803, 6797.796066118019, 7671.884921726171, 7931.2861842434095, 7969.203079692028, 8142.063662678794, 7898.802529936755, 7976.561289129096, 7987.600619968993, 7993.2806047455715, 8011.198880111986, 2146.055735476389, 2174.7281589801273, 2176.281826997087, 2176.9564608707815, 2179.585878683053, 2151.9139234430604, 2187.7155969723995, 2195.648696208607, 2198.9560208795815, 2199.098369668436, 2135.829133669303, 2139.3368055902642, 2141.1435425829636, 2144.5710857828417, 2194.18815038436, 2093.6231478333893, 2104.2635077722825, 2104.452842261008, 2105.2526353144567, 2105.4420578546697, 2082.6875968604677, 2125.702401663753, 2126.978730212709, 2131.3712454825863, 2136.188248465573, 7741.651625676858, 7911.485753426009, 7915.525068495851, 7917.406194535375, 7926.762197134095, 4315.287977483711, 4524.6606504511965, 4525.2533332000185, 4577.473590537097, 4583.060472603109, 2764.101666958247, 2879.8704058317426, 2932.7067293270716, 2948.749356304695, 2955.0543825975697, 919.0901008002097, 920.5029284186531, 928.4661319741108, 929.4423345992418, 948.7580666929957, 10803.351798892014, 10890.727731806688, 10918.617848375268, 10921.781564368668, 10943.616973405971, 3706.4625629283605, 3849.7305188637088, 3978.144698889753, 3979.4030895365927, 4012.9799351003057, 8425.4944703318, 8469.703560375357, 8480.0, 8485.915140848636, 8497.787555311166, 4373.037931655031, 4374.671899607542, 4374.671899607542, 4376.868693939187, 4378.846740364072, 3460.2733425980323, 3544.80503572306, 3870.748401353903, 4080.4491393661474, 4089.0799570097083, 3301.7028467437567, 3356.5636467259587, 3443.4318337474037, 3466.618671946084, 3493.2140268439907, 998.650472334677, 1022.0597050713361, 1037.0407373179778, 1038.5326603028668, 1045.2265323660436, 603.5141710922661, 703.8099713077543, 708.8369674974784, 723.5080145501005, 729.6351824087956, 4988.650794444354, 5046.571041461443, 5100.719460429712, 5172.534471897472, 5230.241614965808, 807.8667019941761, 815.4251252866715, 844.5523872347666, 848.6053984897053, 873.7640836974109, 3316.718078963267, 3443.0875817908272, 3509.3332266869215, 3520.489529018278, 3575.6424357564038, 9454.810903781887, 9994.500274986367, 10013.098821106136, 10020.799584008279, 10038.899611003942, 9677.419354838592, 9939.80120397588, 9940.502974851228, 9979.950100249453, 10005.199584033406, 7391.85216295671, 7521.360684341892, 7522.172561018284, 7529.849403011909, 7540.773776786705, 5017.12200364942, 5021.899562008666, 5072.365954255718, 5082.745862706776, 5285.101532739367, 9116.863247051167, 9248.075192480697, 9330.673426430043, 9346.345755797029, 9347.719568412958, 8715.52064636451, 9110.453372797654, 9125.22435592982, 9386.296027797805, 9392.718218453458, 5719.342275638189, 5911.733971971357, 6391.201099862517, 6524.314946930694, 6538.771143010162, 6935.930640693528, 7113.679884405111, 7180.497365184387, 7320.9267907320245, 7322.414206863332, 6539.265005758562, 6541.34586541342, 6700.028495867974, 6737.69680364375, 6807.489438292049, 6594.505412094016, 6650.9667451662435, 6670.699818508082, 6675.833104172434, 6682.832929176615, 6151.446369826558, 6251.343608920998, 6259.78090766821, 6267.686615669107, 6269.529785266032, 4367.301231802896, 4494.280915053576, 4530.524294948983, 4600.079984003146, 4601.28680054592, 4426.490953540384, 4481.260592002283, 4571.908561828678, 4608.470025947059, 4735.668503204743], "value": 4038.6500475523653, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [273.99589006164905, 275.41474366970186, 280.607149990014, 285.8693865760916, 285.9142257322803, 4625.560571745687, 4778.808847646094, 4804.399450068741, 4830.565249127573, 4863.513648635135, 4636.559526844952, 4717.386739723831, 4719.669623126382, 4731.503192164825, 4787.44944331402, 3562.9109272268142, 3656.6891814195824, 3772.188979369435, 3786.318462676717, 3819.0, 3632.4914511968345, 3759.3045286621928, 3760.191558814854, 3760.9059773505746, 3771.3211621908044, 3747.6627103560672, 3755.830987605564, 3773.5094437723196, 3779.0930176757656, 3803.1062700265416, 3742.476053352519, 3763.8870833874753, 3768.171002379472, 3774.1130834253927, 3796.6962642988497, 3259.1526203187104, 3297.8186199759, 3302.5871766029245, 3326.8170250636203, 3357.1607098225445, 4642.675012749109, 4658.976705116486, 4668.2064049111395, 4671.602913752336, 4673.38290557289, 4072.694547908889, 4280.700350975433, 4302.483701955754, 4310.590493903105, 4313.590208930146, 4975.776090075955, 4998.475464983178, 5002.7498625068965, 5011.699298042129, 5016.698998060128, 3526.9118272043406, 3532.6114127445962, 3536.398812201932, 3550.5384300041087, 3551.6448355164534, 5142.0, 5250.0, 5286.973565132188, 5294.0, 5303.946960530422, 3527.0, 3533.505309256706, 3537.0980399997834, 3541.256336169382, 3546.361654902091, 3608.45873119029, 3611.320735857839, 3614.963850361515, 3615.5661320641166, 3628.600853906068, 4181.749095054306, 4186.832526698898, 4211.368294755811, 4212.599803018708, 4225.619694227535, 4829.251466022772, 4870.4155501340065, 4954.380702412199, 4973.726445045485, 5085.8728531787, 1228.5441751524472, 1232.8027515597462, 1237.9133460657667, 1238.4984081447028, 1246.4702501436852, 771.6874665760377, 801.1347744435996, 813.5566116466422, 814.7026335387541, 824.9257566818895, 3930.816039008412, 3961.643452089326, 3968.1865217630893, 3968.206358728266, 3978.781167035783, 4517.164324599894, 4550.316382938334, 4556.407667003268, 4586.220342541776, 4591.517890621437, 3970.3398722855754, 3986.5614782373914, 3995.840166393312, 4006.258842114218, 4010.0, 2277.4420267034684, 2413.9275821725378, 2421.9515609687705, 2424.6120620700603, 2452.546278938402, 4321.611055005064, 4327.437433133672, 4363.956360436418, 4385.254506733863, 4398.406215160912, 4674.672772905864, 4724.267738500538, 4742.217534106902, 4749.026449577828, 4760.714357138583, 4130.483689538808, 4308.289132293136, 4327.874752564293, 4371.497277813029, 4384.430024096847, 4529.233598896499, 4562.908741825211, 4570.657200710027, 4575.209122435378, 4584.610308123847, 4500.752458614807, 4514.413126293561, 4526.2305408081365, 4543.6365090792, 4558.794854231502, 4017.3773065174946, 4315.331123675835, 4323.740575565476, 4357.564243575617, 4365.279728844832, 3230.7738458307695, 3232.0, 3268.460703983816, 3269.983650081734, 3270.738340932672, 4554.931676024796, 4593.425821772278, 4596.195665758412, 4632.305154226893, 4941.555260026615, 4777.641676874317, 4935.692041608905, 5028.446870844277, 5029.9245511318095, 5044.54599086084, 5012.3910784235295, 5027.974860125676, 5028.321176641253, 5032.723200224023, 5045.899082018265, 4811.663183577116, 4966.975165124151, 4970.776315065758, 4981.875453113701, 4998.350214472095, 5023.136812017261, 5034.874128146826, 5053.924191137063, 5055.418626857959, 5063.367079115111, 4942.184539551077, 4948.020791683355, 4954.777035033504, 4988.902441462872, 5008.348914641073], "value": 3619.5525501737984, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.6474385880754, 582.830979016085, 618.4712071179142, 636.729390009246, 653.9771108011219, 11916.7616647667, 12270.386480675952, 12758.170718903277, 12766.234025958449, 13082.476700931975, 12235.877641223607, 12404.627861164157, 12460.0, 12464.93767531161, 12466.06504512163, 593.7892048322848, 722.9240929702385, 739.3087463221877, 741.3661319571743, 742.109468637637, 605.0863196573184, 729.5659082845698, 744.5644298085626, 751.3163021650299, 754.128981026912, 779.9883001754978, 783.0955246690082, 784.1961988961332, 785.1285073568334, 785.3677789379548, 608.8782243551263, 779.555653277632, 781.3046388714049, 781.6248200863589, 781.9257170568787, 461.2274440312463, 546.9589780766458, 561.4553882733742, 568.5224411494323, 569.7151424287856, 12373.381330933418, 12394.132410731252, 12400.255984640951, 12413.875861241362, 12431.62705118848, 11780.528778848835, 12338.81491777624, 12362.629121126382, 12766.042546809027, 13121.803172952414, 11595.627438715608, 11760.353180575064, 11966.880331196664, 12143.453544590426, 12188.146829721923, 3861.826217820205, 3871.922561548753, 3883.941740873889, 4068.1863627274665, 4089.8364065437345, 63634.0, 64326.35673643297, 65725.3427465724, 66061.0, 66570.66714666445, 9801.215902727765, 9929.156021738088, 10033.498325083716, 10364.0, 10448.947755261175, 17607.911960440117, 17815.46553603394, 17972.281108755502, 17972.281108755764, 17975.0, 6344.524160687874, 6419.486441084796, 6549.181352330958, 6562.278149403562, 6721.596704197764, 3593.5328407307406, 3617.8733744319347, 3644.1436262478296, 3699.500567423417, 3705.8517659293866, 2730.30377253799, 2804.5232310507263, 2825.251308403275, 2841.019848152384, 2842.6730925943366, 462.14733816109225, 470.83050101963266, 472.1242095912102, 477.07209477566494, 478.0319852299122, 361.72328168950776, 417.9540250572435, 454.5386432770674, 478.75344197738195, 479.0514780734166, 7099.822504437327, 7363.852722945619, 7444.664990075459, 7606.087269527692, 7758.456908016385, 467.7357293129371, 481.91084649339984, 489.2954146029706, 517.3429744224813, 519.9220116982407, 3478.1652403422995, 3594.4248920172645, 3598.2623562170215, 3617.4754660574595, 3679.961190616437, 98733.50633246788, 100190.0, 100984.49507752561, 108434.45782771036, 108563.0, 91783.5410822955, 92338.0, 93994.53002735079, 94032.0, 94542.0, 80934.26453164258, 82463.58768206241, 82754.58622707089, 83379.5831020841, 83474.58262708648, 6690.665466726741, 6939.618320992394, 6962.651867406508, 7102.112235970504, 7180.964095179491, 45319.0, 45415.545844541135, 45752.54247457483, 46531.302030470244, 47817.76091119522, 43965.0, 52425.21362179494, 62690.373096270276, 64016.679916601985, 66807.33192668011, 10681.0, 10867.510962006887, 10988.615398461016, 11123.27698699582, 11416.0, 11872.58445954422, 12147.878521214674, 12245.69385765363, 12990.805137923127, 13217.471301147847, 10680.57277708883, 11229.71925701864, 12283.385830708608, 12462.563810266598, 12590.622281331574, 9877.259205559469, 10085.539877117419, 10279.53742081623, 11398.20212585111, 11883.405829708307, 10040.447775372424, 11067.778644427228, 11445.599404020819, 11732.237404568676, 12448.514415963544, 9177.587008584496, 9373.437593744397, 10027.448490333101, 10380.0, 10789.676309710721, 9932.801343973224, 9996.0, 10181.389116653025, 10277.0, 10335.638252661121], "value": 6335.352462629852, "unit": "score"}], "name": "dromaeo_css", "value": 6200.021706592156, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 117.0, 117.0, 116.0, 115.0, 116.0, 115.0, 115.0, 115.0, 115.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 132.0, 126.0, 169.0, 124.0, 125.0, 124.0, 129.0, 169.0, 136.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 162.0, 162.0, 161.0, 161.0, 162.0, 163.0, 161.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 103.0, 77.0, 76.0, 77.0, 76.0, 111.0, 78.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 127.0, 85.0, 85.0, 85.0, 116.0, 84.0, 85.0, 117.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 116.0, 117.0, 116.0, 118.0, 118.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 148.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 107.0, 94.0, 96.0, 95.0, 94.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 67.0, 65.0, 67.0, 65.0, 67.0, 65.0, 66.0, 65.0], "value": 65.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 67.0, 67.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 83.0, 82.0, 82.0, 87.0, 83.0, 82.0, 88.0, 82.0, 82.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 150.0, 142.0, 135.0, 132.0, 131.0, 134.0, 140.0, 187.0, 142.0], "value": 142.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 178.0, 180.0, 177.0, 173.0, 175.0, 171.0, 172.0, 179.0, 174.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 69.0, 69.0, 68.0, 68.0, 69.0, 69.0, 71.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1555.4, "alertThreshold": 2.0}]} 09:48:06 INFO - Return code: 0 09:48:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:48:06 INFO - # TBPL SUCCESS # 09:48:06 INFO - Running post-action listener: _resource_record_post_action 09:48:06 INFO - [mozharness: 2016-09-17 16:48:06.816997Z] Finished run-tests step (success) 09:48:06 INFO - Running post-run listener: _resource_record_post_run 09:48:06 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 0; Write bytes: 394911744; Read time: 0; Write time: 289220 09:48:06 INFO - TinderboxPrint: CPU usage
13.1% 09:48:06 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:48:06 INFO - TinderboxPrint: I/O write bytes / time
394,911,744 / 289,220 09:48:06 INFO - TinderboxPrint: CPU idle
8,334.8 (86.8%) 09:48:06 INFO - TinderboxPrint: CPU user
1,209.6 (12.6%) 09:48:06 INFO - TinderboxPrint: Swap in / out
0 / 0 09:48:06 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99463168; Read time: 0; Write time: 102180 09:48:06 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 294834176; Read time: 0; Write time: 187040 09:48:07 INFO - Running post-run listener: _upload_blobber_files 09:48:07 INFO - Blob upload gear active. 09:48:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:48:07 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 09:48:07 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:48:07 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:48:07 INFO - (blobuploader) - INFO - Open directory for files ... 09:48:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:48:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:48:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:08 INFO - (blobuploader) - INFO - Done attempting. 09:48:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:48:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:48:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:13 INFO - (blobuploader) - INFO - Done attempting. 09:48:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:48:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:48:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:13 INFO - (blobuploader) - INFO - Done attempting. 09:48:13 INFO - (blobuploader) - INFO - Iteration through files over. 09:48:13 INFO - Return code: 0 09:48:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:48:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:48:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/262447c1e1dc4b79c74ccbb3f113af3f42ab311d37848ba063b9f98c78ac5b38a6ec261d6d52beb7f6fa701243d32c45b1300d92de8ee8bf5c1f842f23ab6e95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/502e18e26a1af8beabaece8d3279a1b82aadf8ea6df9544278caa342dd9f35e835a3a08210bbb2f13b0f1228e26d34781c3389eeaee3eca1e8da0f588f797554", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/868078d8efb5850bf4a8d86c8591241b00386631d5aa94c2b3e3f9c246b10ba0437646462aaa93bc1e950fa762b92caf603a6f62f695a70a051a5f1e7048978c"} 09:48:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:48:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:48:13 INFO - Contents: 09:48:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/262447c1e1dc4b79c74ccbb3f113af3f42ab311d37848ba063b9f98c78ac5b38a6ec261d6d52beb7f6fa701243d32c45b1300d92de8ee8bf5c1f842f23ab6e95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/502e18e26a1af8beabaece8d3279a1b82aadf8ea6df9544278caa342dd9f35e835a3a08210bbb2f13b0f1228e26d34781c3389eeaee3eca1e8da0f588f797554", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/868078d8efb5850bf4a8d86c8591241b00386631d5aa94c2b3e3f9c246b10ba0437646462aaa93bc1e950fa762b92caf603a6f62f695a70a051a5f1e7048978c"} 09:48:13 INFO - Running post-run listener: copy_logs_to_upload_dir 09:48:13 INFO - Copying logs to upload dir... 09:48:13 INFO - mkdir: /builds/slave/test/build/upload/logs 09:48:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.791970 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-09-17 09:48:13.443821) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 09:48:13.447151) ========= 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=4300f881736b422476174874000001bc-1474129645.388585-1764405660 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/262447c1e1dc4b79c74ccbb3f113af3f42ab311d37848ba063b9f98c78ac5b38a6ec261d6d52beb7f6fa701243d32c45b1300d92de8ee8bf5c1f842f23ab6e95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/502e18e26a1af8beabaece8d3279a1b82aadf8ea6df9544278caa342dd9f35e835a3a08210bbb2f13b0f1228e26d34781c3389eeaee3eca1e8da0f588f797554", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/868078d8efb5850bf4a8d86c8591241b00386631d5aa94c2b3e3f9c246b10ba0437646462aaa93bc1e950fa762b92caf603a6f62f695a70a051a5f1e7048978c"} build_url:https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/NDIJEReeTpWw9uTg5I8dmw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/262447c1e1dc4b79c74ccbb3f113af3f42ab311d37848ba063b9f98c78ac5b38a6ec261d6d52beb7f6fa701243d32c45b1300d92de8ee8bf5c1f842f23ab6e95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/502e18e26a1af8beabaece8d3279a1b82aadf8ea6df9544278caa342dd9f35e835a3a08210bbb2f13b0f1228e26d34781c3389eeaee3eca1e8da0f588f797554", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/868078d8efb5850bf4a8d86c8591241b00386631d5aa94c2b3e3f9c246b10ba0437646462aaa93bc1e950fa762b92caf603a6f62f695a70a051a5f1e7048978c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 09:48:13.473405) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:48:13.473789) ========= 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=4300f881736b422476174874000001bc-1474129645.388585-1764405660 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004437 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 09:48:13.558832) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 09:48:13.559184) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 09:48:13.559543) ========= ========= Total master_lag: 0.18 =========