builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1482383756.83 results: success (0) buildid: 20161221193152 builduid: 48c4ba2f67714781872625bebd4b16c6 revision: f179934df0c1bab590c558485d419c7910e41325 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 21:15:56.832911) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 21:15:56.833306) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 21:15:56.833598) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 21:15:56.970188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 21:15:56.970442) ========= 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=62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-21 21:15:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.1M=0s 2016-12-21 21:15:57 (50.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.076103 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 21:15:57.061096) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 21:15:57.061367) ========= 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=62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013338 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 21:15:57.122528) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 21:15:57.122834) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f179934df0c1bab590c558485d419c7910e41325 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f179934df0c1bab590c558485d419c7910e41325 --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=62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-21 21:15:57,460 truncating revision to first 12 chars 2016-12-21 21:15:57,460 Setting DEBUG logging. 2016-12-21 21:15:57,460 attempt 1/10 2016-12-21 21:15:57,460 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f179934df0c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 21:15:57,819 unpacking tar archive at: mozilla-central-f179934df0c1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.677792 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 21:15:57.815978) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 21:15:57.816245) ========= script_repo_revision: f179934df0c1bab590c558485d419c7910e41325 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 21:15:57.816612) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 21:15:57.816872) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 21:15:57.831124) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-21 21:15:57.831385) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:15:58 INFO - MultiFileLogger online at 20161221 21:15:58 in /builds/slave/test 21:15:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:15:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:15:58 INFO - {'append_to_log': False, 21:15:58 INFO - 'base_work_dir': '/builds/slave/test', 21:15:58 INFO - 'blob_upload_branch': 'Firefox', 21:15:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:15:58 INFO - 'branch': 'Firefox', 21:15:58 INFO - 'buildbot_json_path': 'buildprops.json', 21:15:58 INFO - 'config_files': ('talos/linux_config.py',), 21:15:58 INFO - 'default_actions': ('clobber', 21:15:58 INFO - 'read-buildbot-config', 21:15:58 INFO - 'download-and-extract', 21:15:58 INFO - 'populate-webroot', 21:15:58 INFO - 'create-virtualenv', 21:15:58 INFO - 'install', 21:15:58 INFO - 'run-tests'), 21:15:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:15:58 INFO - 'download_minidump_stackwalk': True, 21:15:58 INFO - 'download_symbols': 'ondemand', 21:15:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:15:58 INFO - 'tooltool.py': '/tools/tooltool.py', 21:15:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:15:58 INFO - '/tools/misc-python/virtualenv.py')}, 21:15:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:15:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:15:58 INFO - 'installer_path': 'installer.exe', 21:15:58 INFO - 'log_level': 'info', 21:15:58 INFO - 'log_name': 'talos', 21:15:58 INFO - 'log_to_console': True, 21:15:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:15:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:15:58 INFO - 'opt_config_files': (), 21:15:58 INFO - 'pip_index': False, 21:15:58 INFO - 'sps_profile': False, 21:15:58 INFO - 'sps_profile_interval': 0, 21:15:58 INFO - 'suite': 'dromaeojs', 21:15:58 INFO - 'system_bits': '32', 21:15:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:15:58 INFO - 'title': 'talos-linux64-ix-018', 21:15:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:15:58 INFO - 'use_talos_json': True, 21:15:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:15:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:15:58 INFO - 'work_dir': 'build'} 21:15:58 INFO - [mozharness: 2016-12-22 05:15:58.268827Z] Running clobber step. 21:15:58 INFO - Running pre-action listener: _resource_record_pre_action 21:15:58 INFO - Running main action method: clobber 21:15:58 INFO - rmtree: /builds/slave/test/build 21:15:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:15:58 INFO - Running post-action listener: _resource_record_post_action 21:15:58 INFO - [mozharness: 2016-12-22 05:15:58.733785Z] Finished clobber step (success) 21:15:58 INFO - [mozharness: 2016-12-22 05:15:58.733886Z] Running read-buildbot-config step. 21:15:58 INFO - Running pre-action listener: _resource_record_pre_action 21:15:58 INFO - Running main action method: read_buildbot_config 21:15:58 INFO - Using buildbot properties: 21:15:58 INFO - { 21:15:58 INFO - "project": "", 21:15:58 INFO - "product": "firefox", 21:15:58 INFO - "script_repo_revision": "production", 21:15:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:15:58 INFO - "repository": "", 21:15:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 21:15:58 INFO - "buildid": "20161221193152", 21:15:58 INFO - "pgo_build": "True", 21:15:58 INFO - "basedir": "/builds/slave/test", 21:15:58 INFO - "buildnumber": 125, 21:15:58 INFO - "slavename": "talos-linux64-ix-018", 21:15:58 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:15:58 INFO - "platform": "ubuntu64_hw", 21:15:58 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:15:58 INFO - "branch": "mozilla-central", 21:15:58 INFO - "repo_path": "mozilla-central", 21:15:58 INFO - "revision": "f179934df0c1bab590c558485d419c7910e41325", 21:15:58 INFO - "stage_platform": "linux64-pgo", 21:15:58 INFO - "builduid": "48c4ba2f67714781872625bebd4b16c6", 21:15:58 INFO - "slavebuilddir": "test" 21:15:58 INFO - } 21:15:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 21:15:58 INFO - Running post-action listener: _resource_record_post_action 21:15:58 INFO - [mozharness: 2016-12-22 05:15:58.736306Z] Finished read-buildbot-config step (success) 21:15:58 INFO - [mozharness: 2016-12-22 05:15:58.736396Z] Running download-and-extract step. 21:15:58 INFO - Running pre-action listener: _resource_record_pre_action 21:15:58 INFO - Running main action method: download_and_extract 21:15:58 INFO - mkdir: /builds/slave/test/build/tests 21:15: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')]} 21:15:58 INFO - trying https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:15:58 INFO - Downloading https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:15:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:15:59 INFO - Downloaded 1361 bytes. 21:15:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:15:59 INFO - Using the following test package requirements: 21:15:59 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:15:59 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:15:59 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:15:59 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'jsshell-linux-x86_64.zip'], 21:15:59 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:15:59 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:15:59 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:15:59 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:15:59 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:15:59 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:15:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:15:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:15:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:15:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:15:59 INFO - Fetch https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:16:00 INFO - Expected file size: 21842946 21:16:00 INFO - Obtained file size: 21842946 21:16:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:16:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:16:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:16:04 INFO - Fetch https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:16:04 INFO - Expected file size: 21842946 21:16:04 INFO - Obtained file size: 21842946 21:16:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 21:16:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:16:09 INFO - Fetch https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:16:11 INFO - Expected file size: 14409734 21:16:11 INFO - Obtained file size: 14409734 21:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:11 INFO - trying https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:16:11 INFO - Downloading https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:16:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:16:13 INFO - Downloaded 59837347 bytes. 21:16:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:16:13 INFO - mkdir: /builds/slave/test/properties 21:16:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:16:13 INFO - Writing to file /builds/slave/test/properties/build_url 21:16:13 INFO - Contents: 21:16:13 INFO - build_url:https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:16:13 INFO - Running post-action listener: _resource_record_post_action 21:16:13 INFO - Running post-action listener: set_extra_try_arguments 21:16:13 INFO - [mozharness: 2016-12-22 05:16:13.724232Z] Finished download-and-extract step (success) 21:16:13 INFO - [mozharness: 2016-12-22 05:16:13.724461Z] Running populate-webroot step. 21:16:13 INFO - Running pre-action listener: _resource_record_pre_action 21:16:13 INFO - Running main action method: populate_webroot 21:16:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:16:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:16:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:16:13 INFO - '--tppagecycles', 21:16:13 INFO - '7'], 21:16:13 INFO - 'tests': ['tsvgm']}}, 21:16:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:16:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:16:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:16:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:16:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:16:13 INFO - 'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:16:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:16:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:16:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:16:13 INFO - 'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['damp', 'tps']}, 21:16:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:16:13 INFO - 'tests': ['damp', 'tps']}, 21:16:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['dromaeo_dom']}, 21:16:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:16:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['basic_compositor_video']}, 21:16:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:16:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['a11yr', 21:16:13 INFO - 'ts_paint', 21:16:13 INFO - 'tpaint', 21:16:13 INFO - 'sessionrestore', 21:16:13 INFO - 'sessionrestore_no_auto_restore', 21:16:13 INFO - 'tabpaint']}, 21:16:13 INFO - 'other-e10s': {'tests': ['a11yr', 21:16:13 INFO - 'ts_paint', 21:16:13 INFO - 'tpaint', 21:16:13 INFO - 'sessionrestore', 21:16:13 INFO - 'sessionrestore_no_auto_restore', 21:16:13 INFO - 'tabpaint']}, 21:16:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:16:13 INFO - 'ts_paint', 21:16:13 INFO - 'tpaint', 21:16:13 INFO - 'sessionrestore', 21:16:13 INFO - 'sessionrestore_no_auto_restore', 21:16:13 INFO - 'tabpaint']}, 21:16:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:16:13 INFO - 'ts_paint', 21:16:13 INFO - 'tpaint', 21:16:13 INFO - 'sessionrestore', 21:16:13 INFO - 'sessionrestore_no_auto_restore', 21:16:13 INFO - 'tabpaint']}, 21:16:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['a11yr', 21:16:13 INFO - 'ts_paint', 21:16:13 INFO - 'tpaint', 21:16:13 INFO - 'sessionrestore', 21:16:13 INFO - 'sessionrestore_no_auto_restore', 21:16:13 INFO - 'tabpaint']}, 21:16:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['a11yr', 21:16:13 INFO - 'ts_paint', 21:16:13 INFO - 'tpaint', 21:16:13 INFO - 'sessionrestore', 21:16:13 INFO - 'sessionrestore_no_auto_restore', 21:16:13 INFO - 'tabpaint']}, 21:16:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['tsvgx', 21:16:13 INFO - 'tsvgr_opacity', 21:16:13 INFO - 'tart', 21:16:13 INFO - 'tscrollx', 21:16:13 INFO - 'cart', 21:16:13 INFO - 'tsvg_static']}, 21:16:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:16:13 INFO - 'tsvgr_opacity', 21:16:13 INFO - 'tart', 21:16:13 INFO - 'tscrollx', 21:16:13 INFO - 'cart', 21:16:13 INFO - 'tsvg_static']}, 21:16:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:16:13 INFO - 'talos_options': ['--disable-e10s'], 21:16:13 INFO - 'tests': ['tp5o']}, 21:16:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:16:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:16:13 INFO - 'talos_options': ['--disable-e10s', 21:16:13 INFO - '--xperf_path', 21:16:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:16:13 INFO - 'tests': ['tp5n']}, 21:16:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:16:13 INFO - 'talos_options': ['--xperf_path', 21:16:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:16:13 INFO - 'tests': ['tp5n']}}, 21:16:13 INFO - 'talos.zip': {'path': '', 21:16:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:16:13 INFO - Running post-action listener: _resource_record_post_action 21:16:13 INFO - [mozharness: 2016-12-22 05:16:13.743089Z] Finished populate-webroot step (success) 21:16:13 INFO - [mozharness: 2016-12-22 05:16:13.743178Z] Running create-virtualenv step. 21:16:13 INFO - Running pre-action listener: _resource_record_pre_action 21:16:13 INFO - Running main action method: create_virtualenv 21:16:13 INFO - Creating virtualenv /builds/slave/test/build/venv 21:16:13 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 21:16:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:16:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:16:13 INFO - Using real prefix '/usr' 21:16:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:16:14 INFO - Installing distribute.............................................................................................................................................................................................done. 21:16:16 INFO - Installing pip.................done. 21:16:16 INFO - Return code: 0 21:16:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:16:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:16:16 INFO - Reading from file tmpfile_stdout 21:16:16 INFO - Output received: 21:16:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:16:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1985a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b283f0>, '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 0x1b28cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b291a0>, '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': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', '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 21:16:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:16:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:16:16 INFO - Using env: {'DISPLAY': ':0', 21:16:16 INFO - 'HOME': '/home/cltbld', 21:16:16 INFO - 'LANG': 'en_US.UTF-8', 21:16:16 INFO - 'LANGUAGE': 'en_US:en', 21:16:16 INFO - 'LOGNAME': 'cltbld', 21:16:16 INFO - 'MAIL': '/var/mail/cltbld', 21:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:16 INFO - 'MOZ_NO_REMOTE': '1', 21:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:16 INFO - 'NO_EM_RESTART': '1', 21:16:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:16 INFO - 'PWD': '/builds/slave/test', 21:16:16 INFO - 'SHELL': '/bin/bash', 21:16:16 INFO - 'SHLVL': '1', 21:16:16 INFO - 'TERM': 'linux', 21:16:16 INFO - 'TMOUT': '86400', 21:16:16 INFO - 'USER': 'cltbld', 21:16:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', 21:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:16 INFO - '_': '/tools/buildbot/bin/python'} 21:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:16 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 21:16:16 INFO - Cleaning up... 21:16:16 INFO - Return code: 0 21:16:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1985a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b283f0>, '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 0x1b28cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b291a0>, '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': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', '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 21:16:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:16:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:16:16 INFO - Using env: {'DISPLAY': ':0', 21:16:16 INFO - 'HOME': '/home/cltbld', 21:16:16 INFO - 'LANG': 'en_US.UTF-8', 21:16:16 INFO - 'LANGUAGE': 'en_US:en', 21:16:16 INFO - 'LOGNAME': 'cltbld', 21:16:16 INFO - 'MAIL': '/var/mail/cltbld', 21:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:16 INFO - 'MOZ_NO_REMOTE': '1', 21:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:16 INFO - 'NO_EM_RESTART': '1', 21:16:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:16 INFO - 'PWD': '/builds/slave/test', 21:16:16 INFO - 'SHELL': '/bin/bash', 21:16:16 INFO - 'SHLVL': '1', 21:16:16 INFO - 'TERM': 'linux', 21:16:16 INFO - 'TMOUT': '86400', 21:16:16 INFO - 'USER': 'cltbld', 21:16:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', 21:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:16 INFO - '_': '/tools/buildbot/bin/python'} 21:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:16 INFO - Downloading/unpacking psutil>=3.1.1 21:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:16:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:16:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:16:19 INFO - Installing collected packages: psutil 21:16:19 INFO - Running setup.py install for psutil 21:16:19 INFO - building 'psutil._psutil_linux' extension 21:16:19 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 21:16:19 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 21:16:19 INFO - building 'psutil._psutil_posix' extension 21:16:19 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 21:16:19 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 21:16:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:16:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:16:19 INFO - Successfully installed psutil 21:16:19 INFO - Cleaning up... 21:16:19 INFO - Return code: 0 21:16:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:16: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')]} 21:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16: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')]} 21:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1985a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b283f0>, '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 0x1b28cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b291a0>, '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': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', '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 21:16:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:16:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:16:19 INFO - Using env: {'DISPLAY': ':0', 21:16:19 INFO - 'HOME': '/home/cltbld', 21:16:19 INFO - 'LANG': 'en_US.UTF-8', 21:16:19 INFO - 'LANGUAGE': 'en_US:en', 21:16:19 INFO - 'LOGNAME': 'cltbld', 21:16:19 INFO - 'MAIL': '/var/mail/cltbld', 21:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:19 INFO - 'MOZ_NO_REMOTE': '1', 21:16:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:19 INFO - 'NO_EM_RESTART': '1', 21:16:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:19 INFO - 'PWD': '/builds/slave/test', 21:16:19 INFO - 'SHELL': '/bin/bash', 21:16:19 INFO - 'SHLVL': '1', 21:16:19 INFO - 'TERM': 'linux', 21:16:19 INFO - 'TMOUT': '86400', 21:16:19 INFO - 'USER': 'cltbld', 21:16:19 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', 21:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:19 INFO - '_': '/tools/buildbot/bin/python'} 21:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:16:22 INFO - Installing collected packages: mozsystemmonitor 21:16:22 INFO - Running setup.py install for mozsystemmonitor 21:16:22 INFO - Successfully installed mozsystemmonitor 21:16:22 INFO - Cleaning up... 21:16:22 INFO - Return code: 0 21:16:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1985a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b283f0>, '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 0x1b28cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b291a0>, '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': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', '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 21:16:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:16:22 INFO - Using env: {'DISPLAY': ':0', 21:16:22 INFO - 'HOME': '/home/cltbld', 21:16:22 INFO - 'LANG': 'en_US.UTF-8', 21:16:22 INFO - 'LANGUAGE': 'en_US:en', 21:16:22 INFO - 'LOGNAME': 'cltbld', 21:16:22 INFO - 'MAIL': '/var/mail/cltbld', 21:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:22 INFO - 'MOZ_NO_REMOTE': '1', 21:16:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:22 INFO - 'NO_EM_RESTART': '1', 21:16:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:22 INFO - 'PWD': '/builds/slave/test', 21:16:22 INFO - 'SHELL': '/bin/bash', 21:16:22 INFO - 'SHLVL': '1', 21:16:22 INFO - 'TERM': 'linux', 21:16:22 INFO - 'TMOUT': '86400', 21:16:22 INFO - 'USER': 'cltbld', 21:16:22 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', 21:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:22 INFO - '_': '/tools/buildbot/bin/python'} 21:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:22 INFO - Downloading/unpacking jsonschema==2.5.1 21:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:16:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:24 INFO - Downloading functools32-3.2.3-2.tar.gz 21:16:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:16:24 INFO - warning: no files found matching '*.txt' 21:16:24 INFO - no previously-included directories found matching 'build' 21:16:24 INFO - no previously-included directories found matching 'dist' 21:16:24 INFO - no previously-included directories found matching '.git*' 21:16:24 INFO - Installing collected packages: jsonschema, functools32 21:16:24 INFO - Running setup.py install for functools32 21:16:24 INFO - warning: no files found matching '*.txt' 21:16:24 INFO - no previously-included directories found matching 'build' 21:16:24 INFO - no previously-included directories found matching 'dist' 21:16:24 INFO - no previously-included directories found matching '.git*' 21:16:24 INFO - Successfully installed jsonschema functools32 21:16:24 INFO - Cleaning up... 21:16:25 INFO - Return code: 0 21:16:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:16:25 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')]} 21:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:25 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')]} 21:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:25 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 0x17b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1985a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b283f0>, '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 0x1b28cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b291a0>, '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': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', '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 21:16:25 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 21:16:25 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 21:16:25 INFO - Using env: {'DISPLAY': ':0', 21:16:25 INFO - 'HOME': '/home/cltbld', 21:16:25 INFO - 'LANG': 'en_US.UTF-8', 21:16:25 INFO - 'LANGUAGE': 'en_US:en', 21:16:25 INFO - 'LOGNAME': 'cltbld', 21:16:25 INFO - 'MAIL': '/var/mail/cltbld', 21:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:25 INFO - 'MOZ_NO_REMOTE': '1', 21:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:25 INFO - 'NO_EM_RESTART': '1', 21:16:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:25 INFO - 'PWD': '/builds/slave/test', 21:16:25 INFO - 'SHELL': '/bin/bash', 21:16:25 INFO - 'SHLVL': '1', 21:16:25 INFO - 'TERM': 'linux', 21:16:25 INFO - 'TMOUT': '86400', 21:16:25 INFO - 'USER': 'cltbld', 21:16:25 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', 21:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:25 INFO - '_': '/tools/buildbot/bin/python'} 21:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:16:25 INFO - Cleaning up... 21:16:25 INFO - Return code: 0 21:16:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:16:25 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')]} 21:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:25 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')]} 21:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:25 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 0x17b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1985a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b283f0>, '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 0x1b28cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b291a0>, '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': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', '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 21:16:25 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 21:16:25 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 21:16:25 INFO - Using env: {'DISPLAY': ':0', 21:16:25 INFO - 'HOME': '/home/cltbld', 21:16:25 INFO - 'LANG': 'en_US.UTF-8', 21:16:25 INFO - 'LANGUAGE': 'en_US:en', 21:16:25 INFO - 'LOGNAME': 'cltbld', 21:16:25 INFO - 'MAIL': '/var/mail/cltbld', 21:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:25 INFO - 'MOZ_NO_REMOTE': '1', 21:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:25 INFO - 'NO_EM_RESTART': '1', 21:16:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:25 INFO - 'PWD': '/builds/slave/test', 21:16:25 INFO - 'SHELL': '/bin/bash', 21:16:25 INFO - 'SHLVL': '1', 21:16:25 INFO - 'TERM': 'linux', 21:16:25 INFO - 'TMOUT': '86400', 21:16:25 INFO - 'USER': 'cltbld', 21:16:25 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', 21:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:25 INFO - '_': '/tools/buildbot/bin/python'} 21:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:25 INFO - Downloading/unpacking blobuploader==1.2.4 21:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:16:27 INFO - Downloading blobuploader-1.2.4.tar.gz 21:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:16:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:16:27 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 21:16:27 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 21:16:27 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 21:16:27 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 21:16:27 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 21:16:27 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 21:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:16:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:16:28 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 21:16:28 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 21:16:28 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 21:16:28 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 21:16:28 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 21:16:28 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 21:16:28 INFO - Downloading docopt-0.6.1.tar.gz 21:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:16:28 INFO - Installing collected packages: blobuploader, requests, docopt 21:16:28 INFO - Running setup.py install for blobuploader 21:16:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:16:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:16:28 INFO - Running setup.py install for requests 21:16:28 INFO - Running setup.py install for docopt 21:16:28 INFO - Successfully installed blobuploader requests docopt 21:16:28 INFO - Cleaning up... 21:16:28 INFO - Return code: 0 21:16:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:16:28 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')]} 21:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:28 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')]} 21:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:28 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 0x17b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1985a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b283f0>, '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 0x1b28cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b291a0>, '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': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', '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 21:16:28 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 21:16:28 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 21:16:28 INFO - Using env: {'DISPLAY': ':0', 21:16:28 INFO - 'HOME': '/home/cltbld', 21:16:28 INFO - 'LANG': 'en_US.UTF-8', 21:16:28 INFO - 'LANGUAGE': 'en_US:en', 21:16:28 INFO - 'LOGNAME': 'cltbld', 21:16:28 INFO - 'MAIL': '/var/mail/cltbld', 21:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:28 INFO - 'MOZ_NO_REMOTE': '1', 21:16:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:28 INFO - 'NO_EM_RESTART': '1', 21:16:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:28 INFO - 'PWD': '/builds/slave/test', 21:16:28 INFO - 'SHELL': '/bin/bash', 21:16:28 INFO - 'SHLVL': '1', 21:16:28 INFO - 'TERM': 'linux', 21:16:28 INFO - 'TMOUT': '86400', 21:16:28 INFO - 'USER': 'cltbld', 21:16:28 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', 21:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:28 INFO - '_': '/tools/buildbot/bin/python'} 21:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:16:29 INFO - Running setup.py (path:/tmp/pip-1uZk6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:16:29 INFO - Running setup.py (path:/tmp/pip-Qe5v11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:16:29 INFO - Running setup.py (path:/tmp/pip-U_GMxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:16:29 INFO - Running setup.py (path:/tmp/pip-GOpAzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:16:29 INFO - Running setup.py (path:/tmp/pip-AhSWkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:16:29 INFO - Running setup.py (path:/tmp/pip-5WtbeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:16:29 INFO - Running setup.py (path:/tmp/pip-tceGQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:16:29 INFO - Running setup.py (path:/tmp/pip-KqOB17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:16:29 INFO - Running setup.py (path:/tmp/pip-CtYGTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:16:29 INFO - Running setup.py (path:/tmp/pip-uz4klK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:16:29 INFO - Running setup.py (path:/tmp/pip-L8kvtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:16:29 INFO - Running setup.py (path:/tmp/pip-pNFl7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:16:29 INFO - Running setup.py (path:/tmp/pip-nSEtcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:16:30 INFO - Running setup.py (path:/tmp/pip-0Uckr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:30 INFO - Running setup.py (path:/tmp/pip-7lYCos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:16:30 INFO - Running setup.py (path:/tmp/pip-vg1FjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:16:30 INFO - Running setup.py (path:/tmp/pip-JdctEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:16:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:16:30 INFO - Running setup.py install for manifestparser 21:16:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Running setup.py install for mozcrash 21:16:30 INFO - Running setup.py install for mozdebug 21:16:30 INFO - Running setup.py install for mozdevice 21:16:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Running setup.py install for mozfile 21:16:30 INFO - Running setup.py install for mozhttpd 21:16:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:16:30 INFO - Running setup.py install for mozinfo 21:16:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Running setup.py install for mozInstall 21:16:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Running setup.py install for mozleak 21:16:31 INFO - Running setup.py install for mozlog 21:16:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Running setup.py install for moznetwork 21:16:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Running setup.py install for mozprocess 21:16:31 INFO - Running setup.py install for mozprofile 21:16:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Running setup.py install for mozrunner 21:16:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:16:31 INFO - Running setup.py install for mozscreenshot 21:16:31 INFO - Running setup.py install for moztest 21:16:32 INFO - Running setup.py install for mozversion 21:16:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:16:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:16:32 INFO - Cleaning up... 21:16:32 INFO - Return code: 0 21:16:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:32 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 0x17b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1985a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b283f0>, '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 0x1b28cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b291a0>, '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': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', '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 21:16:32 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 21:16:32 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 21:16:32 INFO - Using env: {'DISPLAY': ':0', 21:16:32 INFO - 'HOME': '/home/cltbld', 21:16:32 INFO - 'LANG': 'en_US.UTF-8', 21:16:32 INFO - 'LANGUAGE': 'en_US:en', 21:16:32 INFO - 'LOGNAME': 'cltbld', 21:16:32 INFO - 'MAIL': '/var/mail/cltbld', 21:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:32 INFO - 'MOZ_NO_REMOTE': '1', 21:16:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:32 INFO - 'NO_EM_RESTART': '1', 21:16:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:32 INFO - 'PWD': '/builds/slave/test', 21:16:32 INFO - 'SHELL': '/bin/bash', 21:16:32 INFO - 'SHLVL': '1', 21:16:32 INFO - 'TERM': 'linux', 21:16:32 INFO - 'TMOUT': '86400', 21:16:32 INFO - 'USER': 'cltbld', 21:16:32 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', 21:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:32 INFO - '_': '/tools/buildbot/bin/python'} 21:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:16:32 INFO - Running setup.py (path:/tmp/pip-Y5IXX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:16:32 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)) 21:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:16:32 INFO - Running setup.py (path:/tmp/pip-XXe0ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:16:32 INFO - Running setup.py (path:/tmp/pip-WsCNl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:16:32 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)) 21:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:16:32 INFO - Running setup.py (path:/tmp/pip-frz5du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:16:32 INFO - Running setup.py (path:/tmp/pip-_IZFMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:16:32 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)) 21:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:16:32 INFO - Running setup.py (path:/tmp/pip-ppBsYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:16:32 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)) 21:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:16:32 INFO - Running setup.py (path:/tmp/pip-vaodt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:16:32 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)) 21:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:16:32 INFO - Running setup.py (path:/tmp/pip-9UlVwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:16:33 INFO - Running setup.py (path:/tmp/pip-o0Mk4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:16:33 INFO - Running setup.py (path:/tmp/pip-VSUMrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:16:33 INFO - Running setup.py (path:/tmp/pip-NMeaKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:16:33 INFO - Running setup.py (path:/tmp/pip-yzNy98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:16:33 INFO - Running setup.py (path:/tmp/pip-CYEKCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:16:33 INFO - Running setup.py (path:/tmp/pip-SCdh1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:33 INFO - Running setup.py (path:/tmp/pip-HTW28Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:16:33 INFO - Running setup.py (path:/tmp/pip-ItVIxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:16:33 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)) 21:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:16:33 INFO - Running setup.py (path:/tmp/pip-6ct26J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:16:33 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)) 21:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:16:33 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)) 21:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:16:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:16:33 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 21:16:33 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 21:16:33 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 21:16:33 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 21:16:33 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 21:16:33 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 21:16:35 INFO - Downloading blessings-1.6.tar.gz 21:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:16:35 INFO - Installing collected packages: blessings 21:16:35 INFO - Running setup.py install for blessings 21:16:35 INFO - Successfully installed blessings 21:16:35 INFO - Cleaning up... 21:16:35 INFO - Return code: 0 21:16:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:16:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:16:36 INFO - Reading from file tmpfile_stdout 21:16:36 INFO - Current package versions: 21:16:36 INFO - argparse == 1.2.1 21:16:36 INFO - blessings == 1.6 21:16:36 INFO - blobuploader == 1.2.4 21:16:36 INFO - docopt == 0.6.1 21:16:36 INFO - functools32 == 3.2.3-2 21:16:36 INFO - jsonschema == 2.5.1 21:16:36 INFO - manifestparser == 1.1 21:16:36 INFO - mozInstall == 1.12 21:16:36 INFO - mozcrash == 1.0 21:16:36 INFO - mozdebug == 0.1 21:16:36 INFO - mozdevice == 0.49 21:16:36 INFO - mozfile == 1.2 21:16:36 INFO - mozhttpd == 0.7 21:16:36 INFO - mozinfo == 0.9 21:16:36 INFO - mozleak == 0.1 21:16:36 INFO - mozlog == 3.4 21:16:36 INFO - moznetwork == 0.27 21:16:36 INFO - mozprocess == 0.23 21:16:36 INFO - mozprofile == 0.28 21:16:36 INFO - mozrunner == 6.13 21:16:36 INFO - mozscreenshot == 0.1 21:16:36 INFO - mozsystemmonitor == 0.3 21:16:36 INFO - moztest == 0.7 21:16:36 INFO - mozversion == 1.4 21:16:36 INFO - psutil == 3.1.1 21:16:36 INFO - requests == 1.2.3 21:16:36 INFO - wsgiref == 0.1.2 21:16:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:16:36 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')]} 21:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:36 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')]} 21:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:36 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 0x17b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1985a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b283f0>, '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 0x1b28cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b291a0>, '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': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', '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 21:16:36 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 21:16:36 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 21:16:36 INFO - Using env: {'DISPLAY': ':0', 21:16:36 INFO - 'HOME': '/home/cltbld', 21:16:36 INFO - 'LANG': 'en_US.UTF-8', 21:16:36 INFO - 'LANGUAGE': 'en_US:en', 21:16:36 INFO - 'LOGNAME': 'cltbld', 21:16:36 INFO - 'MAIL': '/var/mail/cltbld', 21:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:36 INFO - 'MOZ_NO_REMOTE': '1', 21:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:36 INFO - 'NO_EM_RESTART': '1', 21:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:36 INFO - 'PWD': '/builds/slave/test', 21:16:36 INFO - 'SHELL': '/bin/bash', 21:16:36 INFO - 'SHLVL': '1', 21:16:36 INFO - 'TERM': 'linux', 21:16:36 INFO - 'TMOUT': '86400', 21:16:36 INFO - 'USER': 'cltbld', 21:16:36 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', 21:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:36 INFO - '_': '/tools/buildbot/bin/python'} 21:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 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)) 21:16:36 INFO - Cleaning up... 21:16:36 INFO - Return code: 0 21:16:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:16:36 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')]} 21:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:36 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')]} 21:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:36 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 0x17b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1985a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b283f0>, '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 0x1b28cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b291a0>, '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': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', '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 21:16:36 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 21:16:36 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 21:16:36 INFO - Using env: {'DISPLAY': ':0', 21:16:36 INFO - 'HOME': '/home/cltbld', 21:16:36 INFO - 'LANG': 'en_US.UTF-8', 21:16:36 INFO - 'LANGUAGE': 'en_US:en', 21:16:36 INFO - 'LOGNAME': 'cltbld', 21:16:36 INFO - 'MAIL': '/var/mail/cltbld', 21:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:36 INFO - 'MOZ_NO_REMOTE': '1', 21:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:36 INFO - 'NO_EM_RESTART': '1', 21:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:36 INFO - 'PWD': '/builds/slave/test', 21:16:36 INFO - 'SHELL': '/bin/bash', 21:16:36 INFO - 'SHLVL': '1', 21:16:36 INFO - 'TERM': 'linux', 21:16:36 INFO - 'TMOUT': '86400', 21:16:36 INFO - 'USER': 'cltbld', 21:16:36 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', 21:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:36 INFO - '_': '/tools/buildbot/bin/python'} 21:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:16:36 INFO - Cleaning up... 21:16:36 INFO - Return code: 0 21:16:36 INFO - Running post-action listener: _resource_record_post_action 21:16:36 INFO - Running post-action listener: _start_resource_monitoring 21:16:36 INFO - Starting resource monitoring. 21:16:36 INFO - [mozharness: 2016-12-22 05:16:36.655713Z] Finished create-virtualenv step (success) 21:16:36 INFO - [mozharness: 2016-12-22 05:16:36.656191Z] Running install step. 21:16:36 INFO - Running pre-action listener: _resource_record_pre_action 21:16:36 INFO - Running main action method: install 21:16:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:16:36 INFO - Reading from file tmpfile_stdout 21:16:36 INFO - Detecting whether we're running mozinstall >=1.0... 21:16:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:16:36 INFO - Reading from file tmpfile_stdout 21:16:36 INFO - Output received: 21:16:36 INFO - Usage: mozinstall [options] installer 21:16:36 INFO - Options: 21:16:36 INFO - -h, --help show this help message and exit 21:16:36 INFO - -d DEST, --destination=DEST 21:16:36 INFO - Directory to install application into. [default: 21:16:36 INFO - "/builds/slave/test"] 21:16:36 INFO - --app=APP Application being installed. [default: firefox] 21:16:36 INFO - mkdir: /builds/slave/test/build/application 21:16:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:16:53 INFO - Reading from file tmpfile_stdout 21:16:53 INFO - Output received: 21:16:53 INFO - /builds/slave/test/build/application/firefox/firefox 21:16:53 INFO - Running post-action listener: _resource_record_post_action 21:16:53 INFO - [mozharness: 2016-12-22 05:16:53.962365Z] Finished install step (success) 21:16:53 INFO - [mozharness: 2016-12-22 05:16:53.962610Z] Running run-tests step. 21:16:53 INFO - Running pre-action listener: _resource_record_pre_action 21:16:53 INFO - Running main action method: run_tests 21:16:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:16:53 INFO - Python 2.7.3 21:16:53 INFO - Return code: 0 21:16:53 INFO - grabbing minidump binary from tooltool 21:16:53 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')]} 21:16:53 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 0x1b283f0>, '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 0x1b28cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b291a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:16:53 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 21:16:53 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 21:16:53 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 21:16:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:16:54 INFO - Return code: 0 21:16:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:16:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:16:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:16:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:16:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 21:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 21:16:54 INFO - Using env: {'DISPLAY': ':0', 21:16:54 INFO - 'HOME': '/home/cltbld', 21:16:54 INFO - 'LANG': 'en_US.UTF-8', 21:16:54 INFO - 'LANGUAGE': 'en_US:en', 21:16:54 INFO - 'LOGNAME': 'cltbld', 21:16:54 INFO - 'MAIL': '/var/mail/cltbld', 21:16:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:16:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:54 INFO - 'MOZ_NO_REMOTE': '1', 21:16:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:54 INFO - 'NO_EM_RESTART': '1', 21:16:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:54 INFO - 'PWD': '/builds/slave/test', 21:16:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:16:54 INFO - 'SHELL': '/bin/bash', 21:16:54 INFO - 'SHLVL': '1', 21:16:54 INFO - 'TERM': 'linux', 21:16:54 INFO - 'TMOUT': '86400', 21:16:54 INFO - 'USER': 'cltbld', 21:16:54 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399', 21:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:54 INFO - '_': '/tools/buildbot/bin/python'} 21:16:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 21:16:54 INFO - mozversion application_buildid: 20161221193152 21:16:54 INFO - mozversion application_changeset: f179934df0c1bab590c558485d419c7910e41325 21:16:54 INFO - mozversion application_display_name: Nightly 21:16:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:16:54 INFO - mozversion application_name: Firefox 21:16:54 INFO - mozversion application_remotingname: firefox 21:16:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:16:54 INFO - mozversion application_vendor: Mozilla 21:16:54 INFO - mozversion application_version: 53.0a1 21:16:54 INFO - mozversion platform_buildid: 20161221193152 21:16:54 INFO - mozversion platform_changeset: f179934df0c1bab590c558485d419c7910e41325 21:16:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:16:54 INFO - mozversion platform_version: 53.0a1 21:16:54 INFO - using testdate: 1482383814 21:16:54 INFO - actual date: 1482383814 21:16:54 INFO - starting webserver on 'localhost:48890' 21:16:54 INFO - SUITE-START | Running 2 tests 21:16:54 INFO - TEST-START | dromaeo_css 21:16:54 INFO - operating with platform_type : linux_ 21:16:54 INFO - Initialising browser for dromaeo_css test... 21:16:54 INFO - TEST-INFO | started process 7185 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpej30Tv/profile http://localhost:48890/getInfo.html) 21:16:57 INFO - PROCESS | 7185 | __metrics Screen width/height:1600/1200 21:16:57 INFO - PROCESS | 7185 | colorDepth:24 21:16:57 INFO - PROCESS | 7185 | Browser inner width/height: 1024/697 21:16:57 INFO - PROCESS | 7185 | __metrics 21:16:58 INFO - PROCESS | 7185 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:16:58 INFO - PROCESS | 7185 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:16:58 INFO - PROCESS | 7185 | console.error: PushService: 21:16:58 INFO - PROCESS | 7185 | stateChangeProcessEnqueue: Error transitioning state 21:16:58 INFO - PROCESS | 7185 | UnknownError 21:16:59 INFO - TEST-INFO | 7185: exit 0 21:16:59 INFO - Browser initialized. 21:16:59 INFO - Running cycle 1/1 for dromaeo_css test... 21:16:59 INFO - TEST-INFO | started process 7268 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpej30Tv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:17:11 INFO - PROCESS | 7268 | RSS: Main: 152621056 21:17:11 INFO - PROCESS | 7268 | 21:18:02 INFO - PROCESS | 7268 | 1482383882622 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:20:11 INFO - PROCESS | 7268 | Cycle 1(1): loaded http://localhost:48890/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48890/tests/dromaeo/cssquery-ext.html) 21:20:14 INFO - PROCESS | 7268 | RSS: Main: 400539648 21:20:14 INFO - PROCESS | 7268 | 21:23:17 INFO - PROCESS | 7268 | Cycle 1(1): loaded http://localhost:48890/tests/dromaeo/cssquery-ext.html (next: http://localhost:48890/tests/dromaeo/cssquery-jquery.html) 21:23:17 INFO - PROCESS | 7268 | RSS: Main: 256118784 21:23:17 INFO - PROCESS | 7268 | 21:26:20 INFO - PROCESS | 7268 | Cycle 1(1): loaded http://localhost:48890/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48890/tests/dromaeo/cssquery-mootools.html) 21:26:20 INFO - PROCESS | 7268 | RSS: Main: 241074176 21:26:20 INFO - PROCESS | 7268 | 21:29:22 INFO - PROCESS | 7268 | Cycle 1(1): loaded http://localhost:48890/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48890/tests/dromaeo/cssquery-prototype.html) 21:29:23 INFO - PROCESS | 7268 | RSS: Main: 218591232 21:29:23 INFO - PROCESS | 7268 | 21:32:26 INFO - PROCESS | 7268 | Cycle 1(1): loaded http://localhost:48890/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48890/tests/dromaeo/cssquery-yui.html) 21:32:26 INFO - PROCESS | 7268 | RSS: Main: 189419520 21:32:26 INFO - PROCESS | 7268 | 21:35:30 INFO - PROCESS | 7268 | Cycle 1(1): loaded http://localhost:48890/tests/dromaeo/cssquery-yui.html (next: http://localhost:48890/tests/dromaeo/cssquery-dojo.html) 21:35:30 INFO - PROCESS | 7268 | RSS: Main: 283836416 21:35:30 INFO - PROCESS | 7268 | 21:35:30 INFO - PROCESS | 7268 | __start_tp_report 21:35:30 INFO - PROCESS | 7268 | _x_x_mozilla_page_load 21:35:30 INFO - PROCESS | 7268 | _x_x_mozilla_page_load_details 21:35:30 INFO - PROCESS | 7268 | |i|pagename|runs| 21:35:30 INFO - PROCESS | 7268 | |0;dojo.html;3003.95494067589;3036.6659667436593;3052.1911693401253;3064.7088526589964;3073.1241596145105;5146.665466744659;5186.611004174692;5229.267902493649;5307.946920530793;5358.892822143554;5220.556252718514;5293.179557168645;5359.624826262163;5389.8113566025195;5438.91841622376;4786.593139583122;4793.257045158006;4800.855974320759;4834.4923783002805;4878.2682597610465;4834.347363105989;4842.539958703917;4909.165441874889;4921.966387058704;4959.8016079356785;4653.371794807692;4813.374261346037;4823.252395878644;4877.585405240558;4924.778384972667;4670.486246512881;4723.267893476516;4743.501932297128;4763.047390521875;4778.5699287064335;4746.644001699887;4760.1907675694865;4761.761911904426;4790.520947905216;4815.879603009917;5199.246109314128;5233.267342572043;5242.6330156889035;5253.86865328366;5254.605904557174;5426.21319908615;5447.4824891635235;5448.700321482317;5544.944550554524;5563.304586926634;5645.717714114319;5650.208970744099;5681.659100453945;5724.542036637059;5730.025895597759;5287.180487024518;5288.65623734456;5291.074062039128;5293.6559123657225;5296.390915044781;5579.6931168785295;5630.352509461464;5758.827235182951;5762.798302059407;5819.301683797971;5425.186222066722;5476.945230547723;5490.3411590609385;5511.58663100269;5586.13414920688;5554.888902221933;5592.972035139798;5604.943950560524;5609.214709940612;5610.49505544503;5591.440855914376;5593.692346920877;5600.85997850057;5603.047481928082;5604.8038318658655;5423.267858839006;5448.645838020516;5668.744906479218;5673.716314184274;5699.2590963174525;4380;4383.452068491439;4411.316245981878;4413.360062790877;4419.867403977886;3872.8450861965207;3912.8434862605177;3928.017995501125;3932.0366510204612;3963.1083006323925;5128.897422051538;5172.327597412387;5400.351957765015;5418.864528386743;5439.156930675752;5624.240727501817;5651.180578816049;5662.631928924607;5708.571857110734;5736.827895163152;5309.203619457112;5332.280142180756;5350.571954243651;5351.03681337363;5355.758990845421;4887.975560122177;4912.557869791736;4951.5048495150195;4954.430240522386;4982.501749824988;5520.089185284463;5524.530414914777;5554.555635549146;5784.421557844182;5809.912851307318;5693.715314234189;5698.6580805151825;5712.314522257356;5734.598578099493;5745.368009519033;5857.8535536610225;5862.706864656665;5862.94137058641;5876.676782776988;5882.617629854046;5269.1832765921345;5304.7082410466255;5332.493413125747;5596.5242954347705;5608.495235428831;5433.864153396196;5477.1510415885605;5482.643628164156;5483.122700367922;5491.2312276282155;5342.75957581902;5350.250964865;5356.330458692663;5361.973190134025;5367.194920761917;5347.973260133675;5349.97325013388;5356.330458692663;5358.303420555459;5360.812371566976;5289.47105289468;5293.947060529345;5299.390570084335;5301.231321458444;5301.840944771663;5341.973290133525;5365.38298095724;5376.435474275145;5378.435264297193;5382.892342153214;5324.707141107277;5326.121190003761;5378.919316210338;5380.865478362915;5400.486953739389 21:35:30 INFO - PROCESS | 7268 | |1;ext.html;544.7848100000499;569.2912324156426;582.8571999860035;594.5511139089989;603.6559161278074;32778.11659551494;39367.60632393679;40984.79507602465;41007.15467803952;44687.553124468744;42051.78974105125;42329.7883510582;42353.7882310588;42408.78795606025;42472;8480.406371553978;8650.221480066793;8655.221030107288;8662.566871656423;8663.047064822864;7775.727849525268;8007.879881801778;10107;10327.122194613466;10335.483225838716;10287.794244115114;10358.119559837423;10360.37837729735;10366.896331036707;10376.636817711382;10180.541875615578;10229.69310920666;10230.897691023069;10237.464380342934;10237.846432303522;10099.242556808269;10151.898481015169;10158.492075396201;10159.796804063877;10162.237832162617;38525.61474385276;38804.22391552181;38927;38927.22145557073;38959;3432.3993301172195;3499.16293945678;3548.2371290172478;3940.349842276021;3962.5443074046566;29301.26746831325;29473.11580652584;29985.250368740955;30012.24969375783;30050.24874378158;4073.1650011747815;4125.855595054189;4163.937540936889;4348.758673324339;4349.739015659071;308693;309687;309748;310040;310525;55450;55986.16020759692;55992.44007559954;56003;56044.43955560474;57979;58554;58673.70663146742;58742.11886821701;58799.41200588025;15372.84627153714;15760.76358854618;15764.369425223093;15767.369305227663;15783;10779.083777878968;11049.226554141294;12105.09211809118;12191.573294934635;12193;19230.423087307405;19610.313639022566;20277.69583456249;20333.288334908502;20368.898155509425;6947.548409353376;7040.401565866959;7087;7144.285571442918;7427.294407031323;858.7938894665235;953.2183609440331;976.2547408500882;1004.8241557727368;1034.6120204923154;28807.423851522854;29069.563956540667;29848.850755746083;30702;31790.523142152888;980.8332583460829;1005.4369553050316;1022.5654097008742;1051.1748277602046;1112.2603468693228;14075.366608502438;14741.262936853114;14800.333984970486;14965.476208332873;15051.247437628075;470244;473745;474463;474585;475266;47452;47911.520884791404;47929.76035119802;47932.5206747935;47943.28085078727;1178.3636836108576;1252.7181384188484;1259.785836407813;1309.7904335306305;1327.1042046618302;27043.594346085218;28123.57814632823;28412.857935711017;28671.13986580406;28882.566761499016;42576.57423425726;43064.784676077674;43069;43070.56929430665;43092.569074308856;395178;398557;400770;400984;401023;24662.50674986526;25572.744272557782;25690.229293121236;26847.509823723572;27069.323266918484;39316.410253846785;39543.80228098841;39670.60329396669;40309.39535907022;40612.18775624412;34295.828520857234;35042.649573503935;35170.47244291389;35224.47163292604;35260.82369588136;34010.489842651885;34456;34849.65150348566;35200.823995880884;35507.46738798972;34872.30255394929;35471;35764.64235357613;35902.281954361286;36370.63629363672;29972;32346.676533234368;32348.35303293968;32427.675723242464;32539.18652033718;24435.14476993368;24585.262442126765;24794.380140496633;25163.370915726522;25382.365440864127 21:35:30 INFO - PROCESS | 7268 | |2;jquery.html;349.2647976010496;374.31873989339397;378.2472878970849;378.67244833219263;398.9301872172369;13529.120607160528;17698.469045928625;18003.0098344591;18012.81987180128;18369.724454133164;17542;17620.11899405031;17746.82253177468;17747.73378399325;17882.37411690591;5141.82003629873;5495.203195536645;5521.779128834842;5524.723763811793;5529;3975.4633124528254;4156;5440.483154100354;5548.667079975215;5847.210626565423;6042.939570604303;6169.784057557987;6174.259088909314;6178.536609754264;6179.721912513925;5888.5877988540815;6012.458878700916;6061.51507879373;6064.939350606482;6169.568130230886;5275.076861549213;5307.150855863043;5307.2569840222395;5307.814226502092;5309.070912590281;21153.25963591267;21298.893505532527;21319.786802131934;21366.679499807517;21502.677459838116;2246.595612789697;2291.4156889992946;2320.220271555402;2425.1027119965547;2992.4613569557473;12760.744785104245;13273.867261327456;13469.663258418519;14225.359858806378;14229.430822767077;1863.282636185065;1863.8695291329611;1864.3940719266293;1877.07084992928;1882.4352694191687;75599;75788.24211757923;76363;76444.61777691131;77606.22393776047;9153.633854645872;9375.593471068587;9421.670241541513;9669.371490853073;9960.153386962189;13036.47854085826;13047.347632618332;14022.649433764238;14031.825067114614;14066.929665351608;7348.2284360142485;7402.814929626802;7458.5151965122095;7632.465727399029;7660.923390766133;7161.641917904084;7228.457865660096;7489.550626962399;7493.4379921505015;7505.737299194502;5985.9401405986255;6004.519638428916;6103.481204097613;6147.66187859672;6290.8427289317215;1771.5748220427004;1793.7130059190729;1811.4022372617005;1822.161805569453;1869.242956602578;900.4417261298037;933.2627224492575;972.2222222222335;998.8102260570469;1010.3584224017802;17183.374520792004;18399.35602253915;18548.350807721665;18760.24959001652;18793.718094228596;964.8070385922844;1013.7566983923805;1021.4739409204266;1026.712520494263;1033.89661033896;9982.201423886072;10247.641332553325;10538.473076346152;10608.628697995533;10619.575216991389;373727;376741;417970;425528;427135.3216169621;81051;86184.56907715547;89633;89684;90157.54921225352;989.188865130586;1003.4380746781679;1038.8493668418037;1039.5166247694708;1040.7398150462384;15888.205589720237;16303.918480407521;16383.508494745176;16414.589635258737;16700.916495417932;32310.83844580856;33184.336313274085;35500.467492988144;37091.443628345056;37891.05272368213;51507;53861;53987.190192146365;54869.72565137149;55906;7781.844363112593;7984.840303193788;8174.346052315682;8200.26197642215;8334.333253339839;11666.300021998708;12292.262464252173;12513.812292815433;12747;12926.741465170831;11028.889711103107;11138;11566;11615.825762613737;11665.59170429031;10841.783164336826;11030.65399508637;11074.224804263624;11075.280106793032;11160.944195279297;11305.773884522428;11392.94303528477;11457.770844582896;11504.712382190177;11757.706057348634;6895.896561551481;7205.531640443564;7210.531315464688;7473.290037446434;7781.805454863674;9767.560459779184;10624.203184761329;11087;11203.271787333797;11473.598424055452 21:35:30 INFO - PROCESS | 7268 | |3;mootools.html;1326.4694122351063;1412.4773833681536;1448.9203093829838;1584.532562893946;1765.8499027582654;6871.106756121703;7856.5286082835;8155.062167850699;8213.137620549838;8394.412391132622;8219;8269.710560130397;8286.41995060346;8289.95855020724;8291.378146638996;1843.2442698493621;1904.2573396375424;2230.0633733831787;2255.064148378421;2275.101334972685;2201.1415547936317;2259.6497542880797;2269.4326418395403;2269.909203631853;2280.2019293247313;2159.514109325397;2179.7057397251406;2181.1929586053184;2194.4294483434264;2199.3401979406194;2157.2988778647004;2194.221051526717;2195.549912267981;2196.3740334004733;2197.615417301966;2059.1351632314313;2087.5074321859943;2090.1325949730845;2091.111277706984;2092.6337890869036;8205.507669539847;8399.160083991614;8415.368847336475;8418.579071046423;8426.199511046441;4990.975045124787;5100.923486147711;5128.5640720538795;5129.512696293846;5143.382794064699;3159.4786860168047;3288.2765791525826;3365.680260375285;3374.7806392584403;3378.932421351584;1054.2462139570168;1122.337820685791;1153.0775379696208;1158.0793269350802;1176.5940750441084;10750.301230419998;11103.44482775866;11104.61133860311;11335.943320283428;11440.313581185155;4109.753414795062;4269.444972153662;4288.656907447396;4434.312681534303;4437.689361744711;9045.864312035194;9046.728598142066;9047.592858321392;9055.320850936074;9057.637694492147;4958.9008219835405;4963.354763880672;4977.726225057584;4983.476734942778;4987.077390682735;3970.5830887756947;4383.715058521186;4599.9080018399445;4662.6269898408045;4667.9766601166775;3542.2915416916203;3587.5694916610173;3599.9280014399565;3632.1282892106233;3704.7962362070343;1099.9945000274947;1111.4053981120062;1116.1349953785882;1117.742919128605;1131.3438205840594;776.6932061835544;900.7973206028591;918.7749001494676;920.1534588178891;946.8769060022153;5242.501962313574;5446.346438427414;5465.754041068161;5573.247611572467;5717.371089180187;964.8600952861937;985.5121714751244;986.6645340584236;1017.7404761785697;1057.3814318623538;3643.981780091083;3661.487391765155;3723.9813800930824;3758.4926034985474;3856.402257650069;10090.243231757648;10305.433201174008;10508.527116279785;10508.737281568023;10515.684529464128;10462.319949203278;10473.31923424975;10483.318584291997;10501.054905058581;10502.68491945243;7881.251281128284;7923.326517246099;7951.562664053533;8019.639116239783;8048.678052877819;5373.811916582901;5393.8112166074;5488.368837583729;5511.724413779375;5663.150527420919;9425.62297508092;9435.481048542397;9465.952670236606;9517.286203534624;9522;8916.242119419663;9328.067193280618;9333;9507.952460237655;9624.085711857362;6358.809235722751;6568;6571.080048793077;7352.77941661751;7531.472796904164;7082.0793296873135;7910.604469776603;8006.359491240803;8075.636596353295;8104.837903241784;7371.778846634609;7430.925690743024;7578.204288549843;7625.923740762521;7722.343600793995;7197.71209151628;7248.420126389981;7338.816529586803;7369.889451658337;7453.329200371993;6599.307072757483;6791.320867913367;6814.522983391115;6825.522213445011;6827.7610283641825;5174.353205849269;5181.326427564543;5217.556507696737;5266.920996184984;5506.724663766716;5031.4213865405945;5100.132977393778;5183.3261675983385;5222.477752224899;5347.43851895561 21:35:30 INFO - PROCESS | 7268 | |4;prototype.html;328.7337256821974;353.93983022886107;356.15768707007936;358.63598447575697;367.31129132875856;5221.321228240328;5268.525832675058;5281.70950597717;5286.3392075990505;5287.206918962158;4929.4824043475455;5013.248012798073;5091.490850914906;5105.8511834837145;5241.816536421226;4137.772422516768;4139.2135494256145;4152;4153.771542565158;4156.397322388252;4158.688098392618;4162.458880345551;4169.874903752877;4183.602557757008;4190.601892820177;3905.3751399776042;3920.7451515651533;4121.814518346681;4145.813438395279;4153.688473364495;4133.731307465006;4134.111166099273;4134.131832315217;4140.627343539081;4167.270727622684;3712;3730.3844865597143;3737.738358314919;3739.7943113128767;3750.83121259544;4989.276554899556;5020.497950204986;5021.121303771862;5080.822171223989;5096.79612815487;4730.084315852086;4732.574068333866;4758.833440829554;4762.499937506581;4764.118638051971;5445.646033007882;5468.699221542814;5480.232767412565;5481.671099733989;5482.780688772445;3885.261800257942;4068.4710987571425;4099.733517321364;4102.589741025903;4119.711620186588;5647.774089036434;5672.744726487318;5685.602007859451;5688.317401911797;5696.857578560528;4168.708190426641;4177.895552611149;4184.14225083857;4202.096549241927;4209.915801683949;4134.627883490501;4198.895027624334;4259.808308626058;4261.488621365394;4271.594198551133;4731.195696731495;4744.031227040739;4754.881127971759;4754.881127971759;4762.928556071662;5488;5493.176023596492;5513.558915286768;5534.916976245359;5537.2524709163745;1391.8886489080849;1420.389232629979;1425.1449130521605;1431.5633731711928;1439.1293267573176;1002.664107523973;1026.5124066068558;1043.9456149289272;1048.224314007629;1053.6312290698347;5077.974610126999;5101.362329708786;5112.616553758483;5139.306193663882;5141.640085194075;5324.707141107198;5367.698865197954;5374.596905232123;5417.5395091416885;5433.429489903582;5051.838077242255;5078.7206703631655;5120.61595380348;5137.563307118862;5151.974240128776;2870.2393865625627;2896.5075937090323;2920.2115428834095;2929.7841395820924;2932.709607772616;5618.286422641134;5636.830895073154;5653.703185578989;5679.829605111853;5685.5167310778215;5471.6169868108855;5507.614466987273;5564.638298510665;5620.8359781483605;5629.8592535186945;5600.971995139998;5605;5607.635503692329;5626.943730562805;5631.451350878459;5481.890362192814;5492.47821456961;5509.256250406304;5509.9806535791;5518.310211223597;5321.228421878728;5600.775968961196;5640.492355688008;5646.548276137982;5684.374718781013;5851.444112809277;5863.970680146572;5872.207252021093;5879.382664820303;5889.911651325147;3952.8616498422416;4040.2323558523785;4051.1695102504505;4059.188162367597;4067.593240675909;5424.457554244544;5536.363318218457;5570.579502226906;5651.265335506358;5707.429257074259;5566.192902029264;5568.276124103841;5602.9719851402115;5603.5797315202335;5603.6831344633165;5266.578673706171;5566.721663916869;5579.888402231852;5609;5615.494605485526;5417.374787563636;5435.619506634655;5458.235846981346;5464.316960379952;5493.176023596492;5533.944660553504;5555.611107222456;5587.916181257366;5591.860203494945;5603.523700485504;5223.817166399158;5433.293671822637;5448.373437054789;5460.842967028081;5471.972640136773 21:35:30 INFO - PROCESS | 7268 | |5;yui.html;706.3642721550606;717.5156769180802;722.8987941688168;736.69058995222;772.3319328780603;15258.389664413424;15662;15804.683906321867;15824.446144384949;15950.36198552058;14649.487267945624;14825.92587037066;14997.92501037496;15003.774943375858;15022.248887555634;788.948718333308;804.5534728225832;805.4482679364654;806.2904643913348;806.9717559885405;674.1539368093037;690.602903330587;758.0486489455753;795.5465384730705;807.5235610989515;857.5926434943415;858.2318824651933;860;860.6944534690189;862.7368652560966;858.2361698088706;859.1666083898608;859.3426029087752;861.3066481482435;861.4142383179435;517.425657520156;529.5684017525704;599.8590331272147;611.9938800611982;627.4353082225988;14771.704565908729;14784.186869722269;14799.926000370036;14825.036372635745;14839.480618178311;13876.930615346859;13937.651558711012;13986.30068496571;14014.789778153336;14038.36827342772;12567.811482827765;12659.36703164838;12759.106862519628;12897.419616117297;12927.870721292855;4203.243416185116;4262.893427664302;4271.188474189894;4339.045410009793;4526.366308716782;69322.30677693209;69810.65094674545;71324.93012604867;72564;73702;10351.792964140675;10644.042036216777;11023;11035.117190624731;11193;21258.893705531682;21374.78625213728;21561.784382155976;21771.238006670006;21806.236781712563;7963.243491868263;7995.760127196193;8046.758597242092;8135.064467586185;8166.877496837552;4543.318502224693;4567.200739870509;4623.260278355447;4723.669343145947;4738.644601654821;3366.444536651474;3423.623401425841;3438.329525742456;3470.2018535736415;3470.514128022079;551.4926267833603;578.6585914310577;583.125312031952;584.0829897061658;599.5832896137143;555.7276934302163;613;620.8230654263604;644.735658380062;661.8543920337518;8960.955195223982;8961.327900407365;8968.192862642394;9069.863952040725;9136.132067453582;598.4942723398808;600.7807150390164;603.5171862509975;603.7434090511515;604.474107526446;4116.835326586963;4205.2640787862;4404.361367601744;4431.667624928144;4520.615747661485;77174.61412692901;78248.60875695584;78920.6053969738;79164;79769.60115199386;54544;65963.34036659668;70069.64965175142;70375.64812175906;70664.2933570668;61890.38109618936;61991.690041549504;62269.37730622727;62407.37592624016;63074.36925630685;9094;9356.345055846026;9435.150836424755;9851.507424628597;9853.556589953327;33140;36788.264234715694;40344.596554035255;41193.794031029654;42534.36198456964;54221;55102.17346739722;56302.43697562972;56687.14969275382;59472.40527594669;11771.823422648496;12210.633680989584;12462.501499940263;12811.231326120462;13670;13701.657458563615;14458.927705361826;14727;14739.852601473847;15440.84559154439;12654.620361389174;13783.655408614864;14111.574349811477;14438.350274237895;14494.85505144935;12942.288174150512;13520.932395338354;13735.45058197661;14296.64520232123;14429.567112986628;13448.46206151743;13477.528286509925;13761.623837616558;14152.646183845487;14409.351579178752;10800.45997700096;11364.374959377314;11397.544098235978;11919.821202681793;12163.635090947286;11538.135656577155;11774.234674746114;11946.402679866145;12065;12091.818622720843 21:35:30 INFO - PROCESS | 7268 | __end_tp_report 21:35:30 INFO - PROCESS | 7268 | __start_cc_report 21:35:30 INFO - PROCESS | 7268 | _x_x_mozilla_cycle_collect,2630 21:35:30 INFO - PROCESS | 7268 | __end_cc_report 21:35:30 INFO - PROCESS | 7268 | __startTimestamp1482384930266__endTimestamp 21:35:30 INFO - PROCESS | 7268 | 21:35:30 INFO - PROCESS | 7268 | ------- Summary: start ------- 21:35:30 INFO - PROCESS | 7268 | Number of tests: 6 21:35:30 INFO - PROCESS | 7268 | 21:35:30 INFO - PROCESS | 7268 | [#0] dojo.html Cycles:165 Average:5206.28 Median:5355.76 stddev:564.23 (10.5%) stddev-sans-first:538.86 21:35:30 INFO - PROCESS | 7268 | Values: 3004.0 3036.7 3052.2 3064.7 3073.1 5146.7 5186.6 5229.3 5307.9 5358.9 5220.6 5293.2 5359.6 5389.8 5438.9 4786.6 4793.3 4800.9 4834.5 4878.3 4834.3 4842.5 4909.2 4922.0 4959.8 4653.4 4813.4 4823.3 4877.6 4924.8 4670.5 4723.3 4743.5 4763.0 4778.6 4746.6 4760.2 4761.8 4790.5 4815.9 5199.2 5233.3 5242.6 5253.9 5254.6 5426.2 5447.5 5448.7 5544.9 5563.3 5645.7 5650.2 5681.7 5724.5 5730.0 5287.2 5288.7 5291.1 5293.7 5296.4 5579.7 5630.4 5758.8 5762.8 5819.3 5425.2 5476.9 5490.3 5511.6 5586.1 5554.9 5593.0 5604.9 5609.2 5610.5 5591.4 5593.7 5600.9 5603.0 5604.8 5423.3 5448.6 5668.7 5673.7 5699.3 4380.0 4383.5 4411.3 4413.4 4419.9 3872.8 3912.8 3928.0 3932.0 3963.1 5128.9 5172.3 5400.4 5418.9 5439.2 5624.2 5651.2 5662.6 5708.6 5736.8 5309.2 5332.3 5350.6 5351.0 5355.8 4888.0 4912.6 4951.5 4954.4 4982.5 5520.1 5524.5 5554.6 5784.4 5809.9 5693.7 5698.7 5712.3 5734.6 5745.4 5857.9 5862.7 5862.9 5876.7 5882.6 5269.2 5304.7 5332.5 5596.5 5608.5 5433.9 5477.2 5482.6 5483.1 5491.2 5342.8 5350.3 5356.3 5362.0 5367.2 5348.0 5350.0 5356.3 5358.3 5360.8 5289.5 5293.9 5299.4 5301.2 5301.8 5342.0 5365.4 5376.4 5378.4 5382.9 5324.7 5326.1 5378.9 5380.9 5400.5 21:35:30 INFO - PROCESS | 7268 | 21:35:30 INFO - PROCESS | 7268 | [#1] ext.html Cycles:180 Average:54294.55 Median:19185.73 stddev:105830.83 (551.6%) stddev-sans-first:106050.77 21:35:30 INFO - PROCESS | 7268 | Values: 544.8 569.3 582.9 594.6 603.7 32778.1 39367.6 40984.8 41007.2 44687.6 42051.8 42329.8 42353.8 42408.8 42472.0 8480.4 8650.2 8655.2 8662.6 8663.0 7775.7 8007.9 10107.0 10327.1 10335.5 10287.8 10358.1 10360.4 10366.9 10376.6 10180.5 10229.7 10230.9 10237.5 10237.8 10099.2 10151.9 10158.5 10159.8 10162.2 38525.6 38804.2 38927.0 38927.2 38959.0 3432.4 3499.2 3548.2 3940.3 3962.5 29301.3 29473.1 29985.3 30012.2 30050.2 4073.2 4125.9 4163.9 4348.8 4349.7 308693.0 309687.0 309748.0 310040.0 310525.0 55450.0 55986.2 55992.4 56003.0 56044.4 57979.0 58554.0 58673.7 58742.1 58799.4 15372.8 15760.8 15764.4 15767.4 15783.0 10779.1 11049.2 12105.1 12191.6 12193.0 19230.4 19610.3 20277.7 20333.3 20368.9 6947.5 7040.4 7087.0 7144.3 7427.3 858.8 953.2 976.3 1004.8 1034.6 28807.4 29069.6 29848.9 30702.0 31790.5 980.8 1005.4 1022.6 1051.2 1112.3 14075.4 14741.3 14800.3 14965.5 15051.2 470244.0 473745.0 474463.0 474585.0 475266.0 47452.0 47911.5 47929.8 47932.5 47943.3 1178.4 1252.7 1259.8 1309.8 1327.1 27043.6 28123.6 28412.9 28671.1 28882.6 42576.6 43064.8 43069.0 43070.6 43092.6 395178.0 398557.0 400770.0 400984.0 401023.0 24662.5 25572.7 25690.2 26847.5 27069.3 39316.4 39543.8 39670.6 40309.4 40612.2 34295.8 35042.6 35170.5 35224.5 35260.8 34010.5 34456.0 34849.7 35200.8 35507.5 34872.3 35471.0 35764.6 35902.3 36370.6 29972.0 32346.7 32348.4 32427.7 32539.2 24435.1 24585.3 24794.4 25163.4 25382.4 21:35:30 INFO - PROCESS | 7268 | 21:35:30 INFO - PROCESS | 7268 | [#2] jquery.html Cycles:180 Average:25726.10 Median:36295.96 stddev:67084.25 (184.8%) stddev-sans-first:67245.38 21:35:30 INFO - PROCESS | 7268 | Values: 349.3 374.3 378.2 378.7 398.9 13529.1 17698.5 18003.0 18012.8 18369.7 17542.0 17620.1 17746.8 17747.7 17882.4 5141.8 5495.2 5521.8 5524.7 5529.0 3975.5 4156.0 5440.5 5548.7 5847.2 6042.9 6169.8 6174.3 6178.5 6179.7 5888.6 6012.5 6061.5 6064.9 6169.6 5275.1 5307.2 5307.3 5307.8 5309.1 21153.3 21298.9 21319.8 21366.7 21502.7 2246.6 2291.4 2320.2 2425.1 2992.5 12760.7 13273.9 13469.7 14225.4 14229.4 1863.3 1863.9 1864.4 1877.1 1882.4 75599.0 75788.2 76363.0 76444.6 77606.2 9153.6 9375.6 9421.7 9669.4 9960.2 13036.5 13047.3 14022.6 14031.8 14066.9 7348.2 7402.8 7458.5 7632.5 7660.9 7161.6 7228.5 7489.6 7493.4 7505.7 5985.9 6004.5 6103.5 6147.7 6290.8 1771.6 1793.7 1811.4 1822.2 1869.2 900.4 933.3 972.2 998.8 1010.4 17183.4 18399.4 18548.4 18760.2 18793.7 964.8 1013.8 1021.5 1026.7 1033.9 9982.2 10247.6 10538.5 10608.6 10619.6 373727.0 376741.0 417970.0 425528.0 427135.3 81051.0 86184.6 89633.0 89684.0 90157.5 989.2 1003.4 1038.8 1039.5 1040.7 15888.2 16303.9 16383.5 16414.6 16700.9 32310.8 33184.3 35500.5 37091.4 37891.1 51507.0 53861.0 53987.2 54869.7 55906.0 7781.8 7984.8 8174.3 8200.3 8334.3 11666.3 12292.3 12513.8 12747.0 12926.7 11028.9 11138.0 11566.0 11615.8 11665.6 10841.8 11030.7 11074.2 11075.3 11160.9 11305.8 11392.9 11457.8 11504.7 11757.7 6895.9 7205.5 7210.5 7473.3 7781.8 9767.6 10624.2 11087.0 11203.3 11473.6 21:35:30 INFO - PROCESS | 7268 | 21:35:30 INFO - PROCESS | 7268 | [#3] mootools.html Cycles:180 Average:5447.78 Median:5114.74 stddev:3058.83 (59.8%) stddev-sans-first:3051.73 21:35:30 INFO - PROCESS | 7268 | Values: 1326.5 1412.5 1448.9 1584.5 1765.8 6871.1 7856.5 8155.1 8213.1 8394.4 8219.0 8269.7 8286.4 8290.0 8291.4 1843.2 1904.3 2230.1 2255.1 2275.1 2201.1 2259.6 2269.4 2269.9 2280.2 2159.5 2179.7 2181.2 2194.4 2199.3 2157.3 2194.2 2195.5 2196.4 2197.6 2059.1 2087.5 2090.1 2091.1 2092.6 8205.5 8399.2 8415.4 8418.6 8426.2 4991.0 5100.9 5128.6 5129.5 5143.4 3159.5 3288.3 3365.7 3374.8 3378.9 1054.2 1122.3 1153.1 1158.1 1176.6 10750.3 11103.4 11104.6 11335.9 11440.3 4109.8 4269.4 4288.7 4434.3 4437.7 9045.9 9046.7 9047.6 9055.3 9057.6 4958.9 4963.4 4977.7 4983.5 4987.1 3970.6 4383.7 4599.9 4662.6 4668.0 3542.3 3587.6 3599.9 3632.1 3704.8 1100.0 1111.4 1116.1 1117.7 1131.3 776.7 900.8 918.8 920.2 946.9 5242.5 5446.3 5465.8 5573.2 5717.4 964.9 985.5 986.7 1017.7 1057.4 3644.0 3661.5 3724.0 3758.5 3856.4 10090.2 10305.4 10508.5 10508.7 10515.7 10462.3 10473.3 10483.3 10501.1 10502.7 7881.3 7923.3 7951.6 8019.6 8048.7 5373.8 5393.8 5488.4 5511.7 5663.2 9425.6 9435.5 9466.0 9517.3 9522.0 8916.2 9328.1 9333.0 9508.0 9624.1 6358.8 6568.0 6571.1 7352.8 7531.5 7082.1 7910.6 8006.4 8075.6 8104.8 7371.8 7430.9 7578.2 7625.9 7722.3 7197.7 7248.4 7338.8 7369.9 7453.3 6599.3 6791.3 6814.5 6825.5 6827.8 5174.4 5181.3 5217.6 5266.9 5506.7 5031.4 5100.1 5183.3 5222.5 5347.4 21:35:30 INFO - PROCESS | 7268 | 21:35:30 INFO - PROCESS | 7268 | [#4] prototype.html Cycles:180 Average:4626.01 Median:5138.43 stddev:1323.87 (25.8%) stddev-sans-first:1287.70 21:35:30 INFO - PROCESS | 7268 | Values: 328.7 353.9 356.2 358.6 367.3 5221.3 5268.5 5281.7 5286.3 5287.2 4929.5 5013.2 5091.5 5105.9 5241.8 4137.8 4139.2 4152.0 4153.8 4156.4 4158.7 4162.5 4169.9 4183.6 4190.6 3905.4 3920.7 4121.8 4145.8 4153.7 4133.7 4134.1 4134.1 4140.6 4167.3 3712.0 3730.4 3737.7 3739.8 3750.8 4989.3 5020.5 5021.1 5080.8 5096.8 4730.1 4732.6 4758.8 4762.5 4764.1 5445.6 5468.7 5480.2 5481.7 5482.8 3885.3 4068.5 4099.7 4102.6 4119.7 5647.8 5672.7 5685.6 5688.3 5696.9 4168.7 4177.9 4184.1 4202.1 4209.9 4134.6 4198.9 4259.8 4261.5 4271.6 4731.2 4744.0 4754.9 4754.9 4762.9 5488.0 5493.2 5513.6 5534.9 5537.3 1391.9 1420.4 1425.1 1431.6 1439.1 1002.7 1026.5 1043.9 1048.2 1053.6 5078.0 5101.4 5112.6 5139.3 5141.6 5324.7 5367.7 5374.6 5417.5 5433.4 5051.8 5078.7 5120.6 5137.6 5152.0 2870.2 2896.5 2920.2 2929.8 2932.7 5618.3 5636.8 5653.7 5679.8 5685.5 5471.6 5507.6 5564.6 5620.8 5629.9 5601.0 5605.0 5607.6 5626.9 5631.5 5481.9 5492.5 5509.3 5510.0 5518.3 5321.2 5600.8 5640.5 5646.5 5684.4 5851.4 5864.0 5872.2 5879.4 5889.9 3952.9 4040.2 4051.2 4059.2 4067.6 5424.5 5536.4 5570.6 5651.3 5707.4 5566.2 5568.3 5603.0 5603.6 5603.7 5266.6 5566.7 5579.9 5609.0 5615.5 5417.4 5435.6 5458.2 5464.3 5493.2 5533.9 5555.6 5587.9 5591.9 5603.5 5223.8 5433.3 5448.4 5460.8 5472.0 21:35:30 INFO - PROCESS | 7268 | 21:35:30 INFO - PROCESS | 7268 | [#5] yui.html Cycles:180 Average:17227.67 Median:4555.26 stddev:21647.88 (475.2%) stddev-sans-first:21673.06 21:35:30 INFO - PROCESS | 7268 | Values: 706.4 717.5 722.9 736.7 772.3 15258.4 15662.0 15804.7 15824.4 15950.4 14649.5 14825.9 14997.9 15003.8 15022.2 788.9 804.6 805.4 806.3 807.0 674.2 690.6 758.0 795.5 807.5 857.6 858.2 860.0 860.7 862.7 858.2 859.2 859.3 861.3 861.4 517.4 529.6 599.9 612.0 627.4 14771.7 14784.2 14799.9 14825.0 14839.5 13876.9 13937.7 13986.3 14014.8 14038.4 12567.8 12659.4 12759.1 12897.4 12927.9 4203.2 4262.9 4271.2 4339.0 4526.4 69322.3 69810.7 71324.9 72564.0 73702.0 10351.8 10644.0 11023.0 11035.1 11193.0 21258.9 21374.8 21561.8 21771.2 21806.2 7963.2 7995.8 8046.8 8135.1 8166.9 4543.3 4567.2 4623.3 4723.7 4738.6 3366.4 3423.6 3438.3 3470.2 3470.5 551.5 578.7 583.1 584.1 599.6 555.7 613.0 620.8 644.7 661.9 8961.0 8961.3 8968.2 9069.9 9136.1 598.5 600.8 603.5 603.7 604.5 4116.8 4205.3 4404.4 4431.7 4520.6 77174.6 78248.6 78920.6 79164.0 79769.6 54544.0 65963.3 70069.6 70375.6 70664.3 61890.4 61991.7 62269.4 62407.4 63074.4 9094.0 9356.3 9435.2 9851.5 9853.6 33140.0 36788.3 40344.6 41193.8 42534.4 54221.0 55102.2 56302.4 56687.1 59472.4 11771.8 12210.6 12462.5 12811.2 13670.0 13701.7 14458.9 14727.0 14739.9 15440.8 12654.6 13783.7 14111.6 14438.4 14494.9 12942.3 13520.9 13735.5 14296.6 14429.6 13448.5 13477.5 13761.6 14152.6 14409.4 10800.5 11364.4 11397.5 11919.8 12163.6 11538.1 11774.2 11946.4 12065.0 12091.8 21:35:30 INFO - PROCESS | 7268 | -------- Summary: end -------- 21:35:30 INFO - PROCESS | 7268 | 21:35:31 INFO - TEST-INFO | 7268: exit 0 21:35:31 INFO - TEST-OK | dromaeo_css | took 1116836ms 21:35:31 INFO - TEST-START | kraken 21:35:31 INFO - operating with platform_type : linux_ 21:35:31 INFO - Initialising browser for kraken test... 21:35:31 INFO - TEST-INFO | started process 7571 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfZa7XY/profile http://localhost:48890/getInfo.html) 21:35:34 INFO - PROCESS | 7571 | __metrics Screen width/height:1600/1200 21:35:34 INFO - PROCESS | 7571 | colorDepth:24 21:35:34 INFO - PROCESS | 7571 | Browser inner width/height: 1024/697 21:35:34 INFO - PROCESS | 7571 | __metrics 21:35:35 INFO - PROCESS | 7571 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:35:35 INFO - PROCESS | 7571 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:35:35 INFO - PROCESS | 7571 | console.error: PushService: 21:35:35 INFO - PROCESS | 7571 | stateChangeProcessEnqueue: Error transitioning state 21:35:35 INFO - PROCESS | 7571 | UnknownError 21:35:36 INFO - TEST-INFO | 7571: exit 0 21:35:36 INFO - Browser initialized. 21:35:36 INFO - Running cycle 1/1 for kraken test... 21:35:36 INFO - TEST-INFO | started process 7651 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfZa7XY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:35:47 INFO - PROCESS | 7651 | RSS: Main: 153149440 21:35:47 INFO - PROCESS | 7651 | 21:35:50 INFO - PROCESS | 7651 | Cycle 1(1): loaded http://localhost:48890/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48890/tests/kraken/driver.html?testName=audio-beat-detection) 21:35:51 INFO - PROCESS | 7651 | RSS: Main: 206295040 21:35:51 INFO - PROCESS | 7651 | 21:35:53 INFO - PROCESS | 7651 | Cycle 1(1): loaded http://localhost:48890/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48890/tests/kraken/driver.html?testName=audio-dft) 21:35:54 INFO - PROCESS | 7651 | RSS: Main: 185753600 21:35:54 INFO - PROCESS | 7651 | 21:35:57 INFO - PROCESS | 7651 | Cycle 1(1): loaded http://localhost:48890/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48890/tests/kraken/driver.html?testName=audio-fft) 21:35:58 INFO - PROCESS | 7651 | RSS: Main: 267464704 21:35:58 INFO - PROCESS | 7651 | 21:36:00 INFO - PROCESS | 7651 | Cycle 1(1): loaded http://localhost:48890/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48890/tests/kraken/driver.html?testName=audio-oscillator) 21:36:00 INFO - PROCESS | 7651 | RSS: Main: 178581504 21:36:00 INFO - PROCESS | 7651 | 21:36:02 INFO - PROCESS | 7651 | Cycle 1(1): loaded http://localhost:48890/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48890/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:36:03 INFO - PROCESS | 7651 | RSS: Main: 182280192 21:36:03 INFO - PROCESS | 7651 | 21:36:06 INFO - PROCESS | 7651 | Cycle 1(1): loaded http://localhost:48890/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48890/tests/kraken/driver.html?testName=imaging-darkroom) 21:36:07 INFO - PROCESS | 7651 | RSS: Main: 222015488 21:36:07 INFO - PROCESS | 7651 | 21:36:10 INFO - PROCESS | 7651 | Cycle 1(1): loaded http://localhost:48890/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48890/tests/kraken/driver.html?testName=imaging-desaturate) 21:36:11 INFO - PROCESS | 7651 | RSS: Main: 221491200 21:36:11 INFO - PROCESS | 7651 | 21:36:13 INFO - PROCESS | 7651 | Cycle 1(1): loaded http://localhost:48890/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48890/tests/kraken/driver.html?testName=json-parse-financial) 21:36:14 INFO - PROCESS | 7651 | RSS: Main: 220614656 21:36:14 INFO - PROCESS | 7651 | 21:36:16 INFO - PROCESS | 7651 | Cycle 1(1): loaded http://localhost:48890/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48890/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:36:16 INFO - PROCESS | 7651 | RSS: Main: 182689792 21:36:16 INFO - PROCESS | 7651 | 21:36:18 INFO - PROCESS | 7651 | Cycle 1(1): loaded http://localhost:48890/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48890/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:36:18 INFO - PROCESS | 7651 | RSS: Main: 189288448 21:36:18 INFO - PROCESS | 7651 | 21:36:20 INFO - PROCESS | 7651 | Cycle 1(1): loaded http://localhost:48890/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48890/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:36:21 INFO - PROCESS | 7651 | RSS: Main: 197648384 21:36:21 INFO - PROCESS | 7651 | 21:36:24 INFO - PROCESS | 7651 | Cycle 1(1): loaded http://localhost:48890/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48890/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:36:24 INFO - PROCESS | 7651 | RSS: Main: 203182080 21:36:24 INFO - PROCESS | 7651 | 21:36:27 INFO - PROCESS | 7651 | Cycle 1(1): loaded http://localhost:48890/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48890/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:36:28 INFO - PROCESS | 7651 | RSS: Main: 204472320 21:36:28 INFO - PROCESS | 7651 | 21:36:29 INFO - PROCESS | 7651 | Cycle 1(1): loaded http://localhost:48890/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48890/tests/kraken/driver.html?testName=ai-astar) 21:36:30 INFO - PROCESS | 7651 | RSS: Main: 256081920 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | __start_tp_report 21:36:30 INFO - PROCESS | 7651 | _x_x_mozilla_page_load 21:36:30 INFO - PROCESS | 7651 | _x_x_mozilla_page_load_details 21:36:30 INFO - PROCESS | 7651 | |i|pagename|runs| 21:36:30 INFO - PROCESS | 7651 | |0;ai-astar;104;111;113;112;109;113;110;111;112;110 21:36:30 INFO - PROCESS | 7651 | |1;audio-beat-detection;147;119;125;169;131;119;121;125;149;129 21:36:30 INFO - PROCESS | 7651 | |2;audio-dft;162;161;163;160;161;163;163;160;161;160 21:36:30 INFO - PROCESS | 7651 | |3;audio-fft;75;76;75;110;75;76;76;75;93;75 21:36:30 INFO - PROCESS | 7651 | |4;audio-oscillator;83;84;120;83;84;87;97;83;82;106 21:36:30 INFO - PROCESS | 7651 | |5;imaging-gaussian-blur;117;117;117;117;116;116;117;117;117;117 21:36:30 INFO - PROCESS | 7651 | |6;imaging-darkroom;146;146;145;146;146;145;147;146;147;146 21:36:30 INFO - PROCESS | 7651 | |7;imaging-desaturate;95;96;96;96;96;96;96;95;96;96 21:36:30 INFO - PROCESS | 7651 | |8;json-parse-financial;52;50;51;51;52;52;52;52;53;51 21:36:30 INFO - PROCESS | 7651 | |9;json-stringify-tinderbox;62;62;62;62;62;63;62;62;62;62 21:36:30 INFO - PROCESS | 7651 | |10;stanford-crypto-aes;80;79;85;79;80;79;78;79;78;79 21:36:30 INFO - PROCESS | 7651 | |11;stanford-crypto-ccm;133;136;128;125;125;124;128;123;217;138 21:36:30 INFO - PROCESS | 7651 | |12;stanford-crypto-pbkdf2;169;165;168;166;171;163;166;163;168;162 21:36:30 INFO - PROCESS | 7651 | |13;stanford-crypto-sha256-iterative;64;64;63;61;63;64;63;64;63;63 21:36:30 INFO - PROCESS | 7651 | __end_tp_report 21:36:30 INFO - PROCESS | 7651 | __start_cc_report 21:36:30 INFO - PROCESS | 7651 | _x_x_mozilla_cycle_collect,1640 21:36:30 INFO - PROCESS | 7651 | __end_cc_report 21:36:30 INFO - PROCESS | 7651 | __startTimestamp1482384990234__endTimestamp 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | ------- Summary: start ------- 21:36:30 INFO - PROCESS | 7651 | Number of tests: 14 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.50 stddev:2.64 (2.4%) stddev-sans-first:1.39 21:36:30 INFO - PROCESS | 7651 | Values: 104.0 111.0 113.0 112.0 109.0 113.0 110.0 111.0 112.0 110.0 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | [#1] audio-beat-detection Cycles:10 Average:133.40 Median:130.00 stddev:16.43 (12.6%) stddev-sans-first:16.68 21:36:30 INFO - PROCESS | 7651 | Values: 147.0 119.0 125.0 169.0 131.0 119.0 121.0 125.0 149.0 129.0 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.26 (0.8%) stddev-sans-first:1.32 21:36:30 INFO - PROCESS | 7651 | Values: 162.0 161.0 163.0 160.0 161.0 163.0 163.0 160.0 161.0 160.0 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | [#3] audio-fft Cycles:10 Average:80.60 Median:75.50 stddev:11.73 (15.5%) stddev-sans-first:12.27 21:36:30 INFO - PROCESS | 7651 | Values: 75.0 76.0 75.0 110.0 75.0 76.0 76.0 75.0 93.0 75.0 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:83.50 stddev:12.86 (15.4%) stddev-sans-first:13.32 21:36:30 INFO - PROCESS | 7651 | Values: 83.0 84.0 120.0 83.0 84.0 87.0 97.0 83.0 82.0 106.0 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 21:36:30 INFO - PROCESS | 7651 | Values: 117.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 21:36:30 INFO - PROCESS | 7651 | Values: 146.0 146.0 145.0 146.0 146.0 145.0 147.0 146.0 147.0 146.0 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 21:36:30 INFO - PROCESS | 7651 | Values: 95.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 96.0 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:52.00 stddev:0.84 (1.6%) stddev-sans-first:0.88 21:36:30 INFO - PROCESS | 7651 | Values: 52.0 50.0 51.0 51.0 52.0 52.0 52.0 52.0 53.0 51.0 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 21:36:30 INFO - PROCESS | 7651 | Values: 62.0 62.0 62.0 62.0 62.0 63.0 62.0 62.0 62.0 62.0 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:79.00 stddev:2.01 (2.5%) stddev-sans-first:2.13 21:36:30 INFO - PROCESS | 7651 | Values: 80.0 79.0 85.0 79.0 80.0 79.0 78.0 79.0 78.0 79.0 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | [#11] stanford-crypto-ccm Cycles:10 Average:137.70 Median:130.50 stddev:28.34 (21.7%) stddev-sans-first:30.01 21:36:30 INFO - PROCESS | 7651 | Values: 133.0 136.0 128.0 125.0 125.0 124.0 128.0 123.0 217.0 138.0 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.10 Median:167.00 stddev:2.92 (1.8%) stddev-sans-first:2.91 21:36:30 INFO - PROCESS | 7651 | Values: 169.0 165.0 168.0 166.0 171.0 163.0 166.0 163.0 168.0 162.0 21:36:30 INFO - PROCESS | 7651 | 21:36:30 INFO - PROCESS | 7651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.50 stddev:0.92 (1.4%) stddev-sans-first:0.93 21:36:30 INFO - PROCESS | 7651 | Values: 64.0 64.0 63.0 61.0 63.0 64.0 63.0 64.0 63.0 63.0 21:36:30 INFO - PROCESS | 7651 | -------- Summary: end -------- 21:36:30 INFO - PROCESS | 7651 | 21:36:31 INFO - TEST-INFO | 7651: exit 0 21:36:31 INFO - TEST-OK | kraken | took 60225ms 21:36:31 INFO - SUITE-END | took 1177s 21:36:31 INFO - Completed test suite (00:19:37) 21:36:31 INFO - javascript benchmark 21:36:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3003.95494067589, 3036.6659667436593, 3052.1911693401253, 3064.7088526589964, 3073.1241596145105, 5146.665466744659, 5186.611004174692, 5229.267902493649, 5307.946920530793, 5358.892822143554, 5220.556252718514, 5293.179557168645, 5359.624826262163, 5389.8113566025195, 5438.91841622376, 4786.593139583122, 4793.257045158006, 4800.855974320759, 4834.4923783002805, 4878.2682597610465, 4834.347363105989, 4842.539958703917, 4909.165441874889, 4921.966387058704, 4959.8016079356785, 4653.371794807692, 4813.374261346037, 4823.252395878644, 4877.585405240558, 4924.778384972667, 4670.486246512881, 4723.267893476516, 4743.501932297128, 4763.047390521875, 4778.5699287064335, 4746.644001699887, 4760.1907675694865, 4761.761911904426, 4790.520947905216, 4815.879603009917, 5199.246109314128, 5233.267342572043, 5242.6330156889035, 5253.86865328366, 5254.605904557174, 5426.21319908615, 5447.4824891635235, 5448.700321482317, 5544.944550554524, 5563.304586926634, 5645.717714114319, 5650.208970744099, 5681.659100453945, 5724.542036637059, 5730.025895597759, 5287.180487024518, 5288.65623734456, 5291.074062039128, 5293.6559123657225, 5296.390915044781, 5579.6931168785295, 5630.352509461464, 5758.827235182951, 5762.798302059407, 5819.301683797971, 5425.186222066722, 5476.945230547723, 5490.3411590609385, 5511.58663100269, 5586.13414920688, 5554.888902221933, 5592.972035139798, 5604.943950560524, 5609.214709940612, 5610.49505544503, 5591.440855914376, 5593.692346920877, 5600.85997850057, 5603.047481928082, 5604.8038318658655, 5423.267858839006, 5448.645838020516, 5668.744906479218, 5673.716314184274, 5699.2590963174525, 4380.0, 4383.452068491439, 4411.316245981878, 4413.360062790877, 4419.867403977886, 3872.8450861965207, 3912.8434862605177, 3928.017995501125, 3932.0366510204612, 3963.1083006323925, 5128.897422051538, 5172.327597412387, 5400.351957765015, 5418.864528386743, 5439.156930675752, 5624.240727501817, 5651.180578816049, 5662.631928924607, 5708.571857110734, 5736.827895163152, 5309.203619457112, 5332.280142180756, 5350.571954243651, 5351.03681337363, 5355.758990845421, 4887.975560122177, 4912.557869791736, 4951.5048495150195, 4954.430240522386, 4982.501749824988, 5520.089185284463, 5524.530414914777, 5554.555635549146, 5784.421557844182, 5809.912851307318, 5693.715314234189, 5698.6580805151825, 5712.314522257356, 5734.598578099493, 5745.368009519033, 5857.8535536610225, 5862.706864656665, 5862.94137058641, 5876.676782776988, 5882.617629854046, 5269.1832765921345, 5304.7082410466255, 5332.493413125747, 5596.5242954347705, 5608.495235428831, 5433.864153396196, 5477.1510415885605, 5482.643628164156, 5483.122700367922, 5491.2312276282155, 5342.75957581902, 5350.250964865, 5356.330458692663, 5361.973190134025, 5367.194920761917, 5347.973260133675, 5349.97325013388, 5356.330458692663, 5358.303420555459, 5360.812371566976, 5289.47105289468, 5293.947060529345, 5299.390570084335, 5301.231321458444, 5301.840944771663, 5341.973290133525, 5365.38298095724, 5376.435474275145, 5378.435264297193, 5382.892342153214, 5324.707141107277, 5326.121190003761, 5378.919316210338, 5380.865478362915, 5400.486953739389], "value": 5169.912948745873, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.7848100000499, 569.2912324156426, 582.8571999860035, 594.5511139089989, 603.6559161278074, 32778.11659551494, 39367.60632393679, 40984.79507602465, 41007.15467803952, 44687.553124468744, 42051.78974105125, 42329.7883510582, 42353.7882310588, 42408.78795606025, 42472.0, 8480.406371553978, 8650.221480066793, 8655.221030107288, 8662.566871656423, 8663.047064822864, 7775.727849525268, 8007.879881801778, 10107.0, 10327.122194613466, 10335.483225838716, 10287.794244115114, 10358.119559837423, 10360.37837729735, 10366.896331036707, 10376.636817711382, 10180.541875615578, 10229.69310920666, 10230.897691023069, 10237.464380342934, 10237.846432303522, 10099.242556808269, 10151.898481015169, 10158.492075396201, 10159.796804063877, 10162.237832162617, 38525.61474385276, 38804.22391552181, 38927.0, 38927.22145557073, 38959.0, 3432.3993301172195, 3499.16293945678, 3548.2371290172478, 3940.349842276021, 3962.5443074046566, 29301.26746831325, 29473.11580652584, 29985.250368740955, 30012.24969375783, 30050.24874378158, 4073.1650011747815, 4125.855595054189, 4163.937540936889, 4348.758673324339, 4349.739015659071, 308693.0, 309687.0, 309748.0, 310040.0, 310525.0, 55450.0, 55986.16020759692, 55992.44007559954, 56003.0, 56044.43955560474, 57979.0, 58554.0, 58673.70663146742, 58742.11886821701, 58799.41200588025, 15372.84627153714, 15760.76358854618, 15764.369425223093, 15767.369305227663, 15783.0, 10779.083777878968, 11049.226554141294, 12105.09211809118, 12191.573294934635, 12193.0, 19230.423087307405, 19610.313639022566, 20277.69583456249, 20333.288334908502, 20368.898155509425, 6947.548409353376, 7040.401565866959, 7087.0, 7144.285571442918, 7427.294407031323, 858.7938894665235, 953.2183609440331, 976.2547408500882, 1004.8241557727368, 1034.6120204923154, 28807.423851522854, 29069.563956540667, 29848.850755746083, 30702.0, 31790.523142152888, 980.8332583460829, 1005.4369553050316, 1022.5654097008742, 1051.1748277602046, 1112.2603468693228, 14075.366608502438, 14741.262936853114, 14800.333984970486, 14965.476208332873, 15051.247437628075, 470244.0, 473745.0, 474463.0, 474585.0, 475266.0, 47452.0, 47911.520884791404, 47929.76035119802, 47932.5206747935, 47943.28085078727, 1178.3636836108576, 1252.7181384188484, 1259.785836407813, 1309.7904335306305, 1327.1042046618302, 27043.594346085218, 28123.57814632823, 28412.857935711017, 28671.13986580406, 28882.566761499016, 42576.57423425726, 43064.784676077674, 43069.0, 43070.56929430665, 43092.569074308856, 395178.0, 398557.0, 400770.0, 400984.0, 401023.0, 24662.50674986526, 25572.744272557782, 25690.229293121236, 26847.509823723572, 27069.323266918484, 39316.410253846785, 39543.80228098841, 39670.60329396669, 40309.39535907022, 40612.18775624412, 34295.828520857234, 35042.649573503935, 35170.47244291389, 35224.47163292604, 35260.82369588136, 34010.489842651885, 34456.0, 34849.65150348566, 35200.823995880884, 35507.46738798972, 34872.30255394929, 35471.0, 35764.64235357613, 35902.281954361286, 36370.63629363672, 29972.0, 32346.676533234368, 32348.35303293968, 32427.675723242464, 32539.18652033718, 24435.14476993368, 24585.262442126765, 24794.380140496633, 25163.370915726522, 25382.365440864127], "value": 18887.024130897178, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.2647976010496, 374.31873989339397, 378.2472878970849, 378.67244833219263, 398.9301872172369, 13529.120607160528, 17698.469045928625, 18003.0098344591, 18012.81987180128, 18369.724454133164, 17542.0, 17620.11899405031, 17746.82253177468, 17747.73378399325, 17882.37411690591, 5141.82003629873, 5495.203195536645, 5521.779128834842, 5524.723763811793, 5529.0, 3975.4633124528254, 4156.0, 5440.483154100354, 5548.667079975215, 5847.210626565423, 6042.939570604303, 6169.784057557987, 6174.259088909314, 6178.536609754264, 6179.721912513925, 5888.5877988540815, 6012.458878700916, 6061.51507879373, 6064.939350606482, 6169.568130230886, 5275.076861549213, 5307.150855863043, 5307.2569840222395, 5307.814226502092, 5309.070912590281, 21153.25963591267, 21298.893505532527, 21319.786802131934, 21366.679499807517, 21502.677459838116, 2246.595612789697, 2291.4156889992946, 2320.220271555402, 2425.1027119965547, 2992.4613569557473, 12760.744785104245, 13273.867261327456, 13469.663258418519, 14225.359858806378, 14229.430822767077, 1863.282636185065, 1863.8695291329611, 1864.3940719266293, 1877.07084992928, 1882.4352694191687, 75599.0, 75788.24211757923, 76363.0, 76444.61777691131, 77606.22393776047, 9153.633854645872, 9375.593471068587, 9421.670241541513, 9669.371490853073, 9960.153386962189, 13036.47854085826, 13047.347632618332, 14022.649433764238, 14031.825067114614, 14066.929665351608, 7348.2284360142485, 7402.814929626802, 7458.5151965122095, 7632.465727399029, 7660.923390766133, 7161.641917904084, 7228.457865660096, 7489.550626962399, 7493.4379921505015, 7505.737299194502, 5985.9401405986255, 6004.519638428916, 6103.481204097613, 6147.66187859672, 6290.8427289317215, 1771.5748220427004, 1793.7130059190729, 1811.4022372617005, 1822.161805569453, 1869.242956602578, 900.4417261298037, 933.2627224492575, 972.2222222222335, 998.8102260570469, 1010.3584224017802, 17183.374520792004, 18399.35602253915, 18548.350807721665, 18760.24959001652, 18793.718094228596, 964.8070385922844, 1013.7566983923805, 1021.4739409204266, 1026.712520494263, 1033.89661033896, 9982.201423886072, 10247.641332553325, 10538.473076346152, 10608.628697995533, 10619.575216991389, 373727.0, 376741.0, 417970.0, 425528.0, 427135.3216169621, 81051.0, 86184.56907715547, 89633.0, 89684.0, 90157.54921225352, 989.188865130586, 1003.4380746781679, 1038.8493668418037, 1039.5166247694708, 1040.7398150462384, 15888.205589720237, 16303.918480407521, 16383.508494745176, 16414.589635258737, 16700.916495417932, 32310.83844580856, 33184.336313274085, 35500.467492988144, 37091.443628345056, 37891.05272368213, 51507.0, 53861.0, 53987.190192146365, 54869.72565137149, 55906.0, 7781.844363112593, 7984.840303193788, 8174.346052315682, 8200.26197642215, 8334.333253339839, 11666.300021998708, 12292.262464252173, 12513.812292815433, 12747.0, 12926.741465170831, 11028.889711103107, 11138.0, 11566.0, 11615.825762613737, 11665.59170429031, 10841.783164336826, 11030.65399508637, 11074.224804263624, 11075.280106793032, 11160.944195279297, 11305.773884522428, 11392.94303528477, 11457.770844582896, 11504.712382190177, 11757.706057348634, 6895.896561551481, 7205.531640443564, 7210.531315464688, 7473.290037446434, 7781.805454863674, 9767.560459779184, 10624.203184761329, 11087.0, 11203.271787333797, 11473.598424055452], "value": 8841.910933132502, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1326.4694122351063, 1412.4773833681536, 1448.9203093829838, 1584.532562893946, 1765.8499027582654, 6871.106756121703, 7856.5286082835, 8155.062167850699, 8213.137620549838, 8394.412391132622, 8219.0, 8269.710560130397, 8286.41995060346, 8289.95855020724, 8291.378146638996, 1843.2442698493621, 1904.2573396375424, 2230.0633733831787, 2255.064148378421, 2275.101334972685, 2201.1415547936317, 2259.6497542880797, 2269.4326418395403, 2269.909203631853, 2280.2019293247313, 2159.514109325397, 2179.7057397251406, 2181.1929586053184, 2194.4294483434264, 2199.3401979406194, 2157.2988778647004, 2194.221051526717, 2195.549912267981, 2196.3740334004733, 2197.615417301966, 2059.1351632314313, 2087.5074321859943, 2090.1325949730845, 2091.111277706984, 2092.6337890869036, 8205.507669539847, 8399.160083991614, 8415.368847336475, 8418.579071046423, 8426.199511046441, 4990.975045124787, 5100.923486147711, 5128.5640720538795, 5129.512696293846, 5143.382794064699, 3159.4786860168047, 3288.2765791525826, 3365.680260375285, 3374.7806392584403, 3378.932421351584, 1054.2462139570168, 1122.337820685791, 1153.0775379696208, 1158.0793269350802, 1176.5940750441084, 10750.301230419998, 11103.44482775866, 11104.61133860311, 11335.943320283428, 11440.313581185155, 4109.753414795062, 4269.444972153662, 4288.656907447396, 4434.312681534303, 4437.689361744711, 9045.864312035194, 9046.728598142066, 9047.592858321392, 9055.320850936074, 9057.637694492147, 4958.9008219835405, 4963.354763880672, 4977.726225057584, 4983.476734942778, 4987.077390682735, 3970.5830887756947, 4383.715058521186, 4599.9080018399445, 4662.6269898408045, 4667.9766601166775, 3542.2915416916203, 3587.5694916610173, 3599.9280014399565, 3632.1282892106233, 3704.7962362070343, 1099.9945000274947, 1111.4053981120062, 1116.1349953785882, 1117.742919128605, 1131.3438205840594, 776.6932061835544, 900.7973206028591, 918.7749001494676, 920.1534588178891, 946.8769060022153, 5242.501962313574, 5446.346438427414, 5465.754041068161, 5573.247611572467, 5717.371089180187, 964.8600952861937, 985.5121714751244, 986.6645340584236, 1017.7404761785697, 1057.3814318623538, 3643.981780091083, 3661.487391765155, 3723.9813800930824, 3758.4926034985474, 3856.402257650069, 10090.243231757648, 10305.433201174008, 10508.527116279785, 10508.737281568023, 10515.684529464128, 10462.319949203278, 10473.31923424975, 10483.318584291997, 10501.054905058581, 10502.68491945243, 7881.251281128284, 7923.326517246099, 7951.562664053533, 8019.639116239783, 8048.678052877819, 5373.811916582901, 5393.8112166074, 5488.368837583729, 5511.724413779375, 5663.150527420919, 9425.62297508092, 9435.481048542397, 9465.952670236606, 9517.286203534624, 9522.0, 8916.242119419663, 9328.067193280618, 9333.0, 9507.952460237655, 9624.085711857362, 6358.809235722751, 6568.0, 6571.080048793077, 7352.77941661751, 7531.472796904164, 7082.0793296873135, 7910.604469776603, 8006.359491240803, 8075.636596353295, 8104.837903241784, 7371.778846634609, 7430.925690743024, 7578.204288549843, 7625.923740762521, 7722.343600793995, 7197.71209151628, 7248.420126389981, 7338.816529586803, 7369.889451658337, 7453.329200371993, 6599.307072757483, 6791.320867913367, 6814.522983391115, 6825.522213445011, 6827.7610283641825, 5174.353205849269, 5181.326427564543, 5217.556507696737, 5266.920996184984, 5506.724663766716, 5031.4213865405945, 5100.132977393778, 5183.3261675983385, 5222.477752224899, 5347.43851895561], "value": 4379.1444285715725, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.7337256821974, 353.93983022886107, 356.15768707007936, 358.63598447575697, 367.31129132875856, 5221.321228240328, 5268.525832675058, 5281.70950597717, 5286.3392075990505, 5287.206918962158, 4929.4824043475455, 5013.248012798073, 5091.490850914906, 5105.8511834837145, 5241.816536421226, 4137.772422516768, 4139.2135494256145, 4152.0, 4153.771542565158, 4156.397322388252, 4158.688098392618, 4162.458880345551, 4169.874903752877, 4183.602557757008, 4190.601892820177, 3905.3751399776042, 3920.7451515651533, 4121.814518346681, 4145.813438395279, 4153.688473364495, 4133.731307465006, 4134.111166099273, 4134.131832315217, 4140.627343539081, 4167.270727622684, 3712.0, 3730.3844865597143, 3737.738358314919, 3739.7943113128767, 3750.83121259544, 4989.276554899556, 5020.497950204986, 5021.121303771862, 5080.822171223989, 5096.79612815487, 4730.084315852086, 4732.574068333866, 4758.833440829554, 4762.499937506581, 4764.118638051971, 5445.646033007882, 5468.699221542814, 5480.232767412565, 5481.671099733989, 5482.780688772445, 3885.261800257942, 4068.4710987571425, 4099.733517321364, 4102.589741025903, 4119.711620186588, 5647.774089036434, 5672.744726487318, 5685.602007859451, 5688.317401911797, 5696.857578560528, 4168.708190426641, 4177.895552611149, 4184.14225083857, 4202.096549241927, 4209.915801683949, 4134.627883490501, 4198.895027624334, 4259.808308626058, 4261.488621365394, 4271.594198551133, 4731.195696731495, 4744.031227040739, 4754.881127971759, 4754.881127971759, 4762.928556071662, 5488.0, 5493.176023596492, 5513.558915286768, 5534.916976245359, 5537.2524709163745, 1391.8886489080849, 1420.389232629979, 1425.1449130521605, 1431.5633731711928, 1439.1293267573176, 1002.664107523973, 1026.5124066068558, 1043.9456149289272, 1048.224314007629, 1053.6312290698347, 5077.974610126999, 5101.362329708786, 5112.616553758483, 5139.306193663882, 5141.640085194075, 5324.707141107198, 5367.698865197954, 5374.596905232123, 5417.5395091416885, 5433.429489903582, 5051.838077242255, 5078.7206703631655, 5120.61595380348, 5137.563307118862, 5151.974240128776, 2870.2393865625627, 2896.5075937090323, 2920.2115428834095, 2929.7841395820924, 2932.709607772616, 5618.286422641134, 5636.830895073154, 5653.703185578989, 5679.829605111853, 5685.5167310778215, 5471.6169868108855, 5507.614466987273, 5564.638298510665, 5620.8359781483605, 5629.8592535186945, 5600.971995139998, 5605.0, 5607.635503692329, 5626.943730562805, 5631.451350878459, 5481.890362192814, 5492.47821456961, 5509.256250406304, 5509.9806535791, 5518.310211223597, 5321.228421878728, 5600.775968961196, 5640.492355688008, 5646.548276137982, 5684.374718781013, 5851.444112809277, 5863.970680146572, 5872.207252021093, 5879.382664820303, 5889.911651325147, 3952.8616498422416, 4040.2323558523785, 4051.1695102504505, 4059.188162367597, 4067.593240675909, 5424.457554244544, 5536.363318218457, 5570.579502226906, 5651.265335506358, 5707.429257074259, 5566.192902029264, 5568.276124103841, 5602.9719851402115, 5603.5797315202335, 5603.6831344633165, 5266.578673706171, 5566.721663916869, 5579.888402231852, 5609.0, 5615.494605485526, 5417.374787563636, 5435.619506634655, 5458.235846981346, 5464.316960379952, 5493.176023596492, 5533.944660553504, 5555.611107222456, 5587.916181257366, 5591.860203494945, 5603.523700485504, 5223.817166399158, 5433.293671822637, 5448.373437054789, 5460.842967028081, 5471.972640136773], "value": 4216.200845639274, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.3642721550606, 717.5156769180802, 722.8987941688168, 736.69058995222, 772.3319328780603, 15258.389664413424, 15662.0, 15804.683906321867, 15824.446144384949, 15950.36198552058, 14649.487267945624, 14825.92587037066, 14997.92501037496, 15003.774943375858, 15022.248887555634, 788.948718333308, 804.5534728225832, 805.4482679364654, 806.2904643913348, 806.9717559885405, 674.1539368093037, 690.602903330587, 758.0486489455753, 795.5465384730705, 807.5235610989515, 857.5926434943415, 858.2318824651933, 860.0, 860.6944534690189, 862.7368652560966, 858.2361698088706, 859.1666083898608, 859.3426029087752, 861.3066481482435, 861.4142383179435, 517.425657520156, 529.5684017525704, 599.8590331272147, 611.9938800611982, 627.4353082225988, 14771.704565908729, 14784.186869722269, 14799.926000370036, 14825.036372635745, 14839.480618178311, 13876.930615346859, 13937.651558711012, 13986.30068496571, 14014.789778153336, 14038.36827342772, 12567.811482827765, 12659.36703164838, 12759.106862519628, 12897.419616117297, 12927.870721292855, 4203.243416185116, 4262.893427664302, 4271.188474189894, 4339.045410009793, 4526.366308716782, 69322.30677693209, 69810.65094674545, 71324.93012604867, 72564.0, 73702.0, 10351.792964140675, 10644.042036216777, 11023.0, 11035.117190624731, 11193.0, 21258.893705531682, 21374.78625213728, 21561.784382155976, 21771.238006670006, 21806.236781712563, 7963.243491868263, 7995.760127196193, 8046.758597242092, 8135.064467586185, 8166.877496837552, 4543.318502224693, 4567.200739870509, 4623.260278355447, 4723.669343145947, 4738.644601654821, 3366.444536651474, 3423.623401425841, 3438.329525742456, 3470.2018535736415, 3470.514128022079, 551.4926267833603, 578.6585914310577, 583.125312031952, 584.0829897061658, 599.5832896137143, 555.7276934302163, 613.0, 620.8230654263604, 644.735658380062, 661.8543920337518, 8960.955195223982, 8961.327900407365, 8968.192862642394, 9069.863952040725, 9136.132067453582, 598.4942723398808, 600.7807150390164, 603.5171862509975, 603.7434090511515, 604.474107526446, 4116.835326586963, 4205.2640787862, 4404.361367601744, 4431.667624928144, 4520.615747661485, 77174.61412692901, 78248.60875695584, 78920.6053969738, 79164.0, 79769.60115199386, 54544.0, 65963.34036659668, 70069.64965175142, 70375.64812175906, 70664.2933570668, 61890.38109618936, 61991.690041549504, 62269.37730622727, 62407.37592624016, 63074.36925630685, 9094.0, 9356.345055846026, 9435.150836424755, 9851.507424628597, 9853.556589953327, 33140.0, 36788.264234715694, 40344.596554035255, 41193.794031029654, 42534.36198456964, 54221.0, 55102.17346739722, 56302.43697562972, 56687.14969275382, 59472.40527594669, 11771.823422648496, 12210.633680989584, 12462.501499940263, 12811.231326120462, 13670.0, 13701.657458563615, 14458.927705361826, 14727.0, 14739.852601473847, 15440.84559154439, 12654.620361389174, 13783.655408614864, 14111.574349811477, 14438.350274237895, 14494.85505144935, 12942.288174150512, 13520.932395338354, 13735.45058197661, 14296.64520232123, 14429.567112986628, 13448.46206151743, 13477.528286509925, 13761.623837616558, 14152.646183845487, 14409.351579178752, 10800.45997700096, 11364.374959377314, 11397.544098235978, 11919.821202681793, 12163.635090947286, 11538.135656577155, 11774.234674746114, 11946.402679866145, 12065.0, 12091.818622720843], "value": 7086.177606758298, "unit": "score"}], "name": "dromaeo_css", "value": 6952.804975736097, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 113.0, 112.0, 109.0, 113.0, 110.0, 111.0, 112.0, 110.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 125.0, 169.0, 131.0, 119.0, 121.0, 125.0, 149.0, 129.0], "value": 133.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 160.0, 161.0, 163.0, 163.0, 160.0, 161.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 110.0, 75.0, 76.0, 76.0, 75.0, 93.0, 75.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 120.0, 83.0, 84.0, 87.0, 97.0, 83.0, 82.0, 106.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 147.0, 146.0, 147.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 51.0, 51.0, 52.0, 52.0, 52.0, 52.0, 53.0, 51.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 62.0, 62.0, 62.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 85.0, 79.0, 80.0, 79.0, 78.0, 79.0, 78.0, 79.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 136.0, 128.0, 125.0, 125.0, 124.0, 128.0, 123.0, 217.0, 138.0], "value": 137.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 165.0, 168.0, 166.0, 171.0, 163.0, 166.0, 163.0, 168.0, 162.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 61.0, 63.0, 64.0, 63.0, 64.0, 63.0, 63.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1495.6999999999998, "alertThreshold": 2.0}]} 21:36:31 INFO - Return code: 0 21:36:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:36:31 INFO - # TBPL SUCCESS # 21:36:31 INFO - Running post-action listener: _resource_record_post_action 21:36:31 INFO - [mozharness: 2016-12-22 05:36:31.548286Z] Finished run-tests step (success) 21:36:31 INFO - Running post-run listener: _resource_record_post_run 21:36:31 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 398401536; Read time: 36; Write time: 289840 21:36:31 INFO - TinderboxPrint: CPU usage
13.1% 21:36:31 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 36 21:36:31 INFO - TinderboxPrint: I/O write bytes / time
398,401,536 / 289,840 21:36:31 INFO - TinderboxPrint: CPU idle
8,277.6 (86.9%) 21:36:31 INFO - TinderboxPrint: CPU user
1,206.9 (12.7%) 21:36:31 INFO - TinderboxPrint: Swap in / out
0 / 0 21:36:31 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 104046592; Read time: 0; Write time: 76044 21:36:31 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 294354944; Read time: 0; Write time: 213796 21:36:32 INFO - Running post-run listener: _upload_blobber_files 21:36:32 INFO - Blob upload gear active. 21:36:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:36:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:36:32 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:36:32 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:36:32 INFO - (blobuploader) - INFO - Open directory for files ... 21:36:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:36:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:36:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:33 INFO - (blobuploader) - INFO - Done attempting. 21:36:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:36:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:36:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:34 INFO - (blobuploader) - INFO - Done attempting. 21:36:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:36:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:36:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:37 INFO - (blobuploader) - INFO - Done attempting. 21:36:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:36:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:36:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:36:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:36:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:36:38 INFO - (blobuploader) - INFO - Done attempting. 21:36:38 INFO - (blobuploader) - INFO - Iteration through files over. 21:36:38 INFO - Return code: 0 21:36:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:36:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:36:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5baef7513562d8c2f99ecd91b01b5cd2641dd7f3fcb662689894b463ee0d890783fbcce3912f41ff2ad0618b6d2ec629dfb626a1409f6a885f863963e14a48cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/836675503712399f7255163cf393a4a842ccb1a232c57238a4c63453c6aca0573cddd7bed6c38a8331de7af2e8c6728ee833a1b5d069e20356854ed6e2f8e3ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f785f431bf70b8c009a9e6291002b411c4036e64f66b50bbf633a92bd9f1f84b4db3d741b48998fdaf0d94319d65c3adf58f8b234dea01f56d6dcda4be8e79d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99b15cbec951336ad99ea2d3aa87d735b2a3e6e583994b7098e072f4875d69b466c03d951957587344975107fe9fb5b08f9204dd69ad29e5626c42e7e6692857"} 21:36:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:36:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:36:38 INFO - Contents: 21:36:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5baef7513562d8c2f99ecd91b01b5cd2641dd7f3fcb662689894b463ee0d890783fbcce3912f41ff2ad0618b6d2ec629dfb626a1409f6a885f863963e14a48cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/836675503712399f7255163cf393a4a842ccb1a232c57238a4c63453c6aca0573cddd7bed6c38a8331de7af2e8c6728ee833a1b5d069e20356854ed6e2f8e3ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f785f431bf70b8c009a9e6291002b411c4036e64f66b50bbf633a92bd9f1f84b4db3d741b48998fdaf0d94319d65c3adf58f8b234dea01f56d6dcda4be8e79d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99b15cbec951336ad99ea2d3aa87d735b2a3e6e583994b7098e072f4875d69b466c03d951957587344975107fe9fb5b08f9204dd69ad29e5626c42e7e6692857"} 21:36:38 INFO - Running post-run listener: copy_logs_to_upload_dir 21:36:38 INFO - Copying logs to upload dir... 21:36:38 INFO - mkdir: /builds/slave/test/build/upload/logs 21:36:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.652874 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-21 21:36:38.504042) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 21:36:38.508093) ========= 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=62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5baef7513562d8c2f99ecd91b01b5cd2641dd7f3fcb662689894b463ee0d890783fbcce3912f41ff2ad0618b6d2ec629dfb626a1409f6a885f863963e14a48cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/836675503712399f7255163cf393a4a842ccb1a232c57238a4c63453c6aca0573cddd7bed6c38a8331de7af2e8c6728ee833a1b5d069e20356854ed6e2f8e3ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f785f431bf70b8c009a9e6291002b411c4036e64f66b50bbf633a92bd9f1f84b4db3d741b48998fdaf0d94319d65c3adf58f8b234dea01f56d6dcda4be8e79d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99b15cbec951336ad99ea2d3aa87d735b2a3e6e583994b7098e072f4875d69b466c03d951957587344975107fe9fb5b08f9204dd69ad29e5626c42e7e6692857"} build_url:https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011904 build_url: 'https://queue.taskcluster.net/v1/task/etVO6Qf4S86wt41acTKNBg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5baef7513562d8c2f99ecd91b01b5cd2641dd7f3fcb662689894b463ee0d890783fbcce3912f41ff2ad0618b6d2ec629dfb626a1409f6a885f863963e14a48cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/836675503712399f7255163cf393a4a842ccb1a232c57238a4c63453c6aca0573cddd7bed6c38a8331de7af2e8c6728ee833a1b5d069e20356854ed6e2f8e3ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f785f431bf70b8c009a9e6291002b411c4036e64f66b50bbf633a92bd9f1f84b4db3d741b48998fdaf0d94319d65c3adf58f8b234dea01f56d6dcda4be8e79d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99b15cbec951336ad99ea2d3aa87d735b2a3e6e583994b7098e072f4875d69b466c03d951957587344975107fe9fb5b08f9204dd69ad29e5626c42e7e6692857"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 21:36:38.534144) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 21:36:38.534497) ========= 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=62c568580acbfdaac3eec7be000001dc-1482382312.289326-232662399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004381 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 21:36:38.618741) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 21:36:38.619076) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 21:36:38.619438) ========= ========= Total master_lag: 0.19 =========