builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-083 starttime: 1490829302.94 results: success (0) revision: 60d7a0496a3673450ddbc37ec387525148c32604 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 16:15:02.941536) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 16:15:02.941954) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-29 16:15:02.942243) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-29 16:15:02.956992) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:15:02.957341) ========= 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=3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-29 16:15:03-- 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% 220M=0s 2017-03-29 16:15:03 (220 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.077259 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:15:03.048512) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:15:03.048820) ========= 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=3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015231 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:15:03.097843) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-29 16:15:03.098186) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 60d7a0496a3673450ddbc37ec387525148c32604 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 60d7a0496a3673450ddbc37ec387525148c32604 --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=3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-29 16:15:03,262 truncating revision to first 12 chars 2017-03-29 16:15:03,262 Setting DEBUG logging. 2017-03-29 16:15:03,262 attempt 1/10 2017-03-29 16:15:03,262 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/60d7a0496a36?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-29 16:15:04,681 unpacking tar archive at: mozilla-central-60d7a0496a36/testing/mozharness/ program finished with exit code 0 elapsedTime=1.792186 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-29 16:15:04.900183) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 16:15:04.900498) ========= script_repo_revision: 60d7a0496a3673450ddbc37ec387525148c32604 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 16:15:04.900856) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-29 16:15:04.901132) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-29 16:15:04.908885) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-03-29 16:15:04.909262) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:15:05 INFO - MultiFileLogger online at 20170329 16:15:05 in /builds/slave/test 16:15:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:15:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:15:05 INFO - {'append_to_log': False, 16:15:05 INFO - 'base_work_dir': '/builds/slave/test', 16:15:05 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:15:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:15:05 INFO - 'branch': 'Firefox-Non-PGO', 16:15:05 INFO - 'buildbot_json_path': 'buildprops.json', 16:15:05 INFO - 'config_files': ('talos/linux_config.py',), 16:15:05 INFO - 'default_actions': ('clobber', 16:15:05 INFO - 'read-buildbot-config', 16:15:05 INFO - 'download-and-extract', 16:15:05 INFO - 'populate-webroot', 16:15:05 INFO - 'create-virtualenv', 16:15:05 INFO - 'install', 16:15:05 INFO - 'run-tests'), 16:15:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:15:05 INFO - 'download_minidump_stackwalk': True, 16:15:05 INFO - 'download_symbols': 'ondemand', 16:15:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:15:05 INFO - 'tooltool.py': '/tools/tooltool.py', 16:15:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:15:05 INFO - '/tools/misc-python/virtualenv.py')}, 16:15:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:15:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:15:05 INFO - 'gecko_profile': False, 16:15:05 INFO - 'gecko_profile_interval': 0, 16:15:05 INFO - 'installer_path': 'installer.exe', 16:15:05 INFO - 'log_level': 'info', 16:15:05 INFO - 'log_name': 'talos', 16:15:05 INFO - 'log_to_console': True, 16:15:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:15:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:15:05 INFO - 'opt_config_files': (), 16:15:05 INFO - 'pip_index': False, 16:15:05 INFO - 'suite': 'dromaeojs', 16:15:05 INFO - 'system_bits': '32', 16:15:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:15:05 INFO - 'title': 'talos-linux64-ix-083', 16:15:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:15:05 INFO - 'use_talos_json': True, 16:15:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:15:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:15:05 INFO - 'work_dir': 'build'} 16:15:05 INFO - [mozharness: 2017-03-29 23:15:05.173602Z] Running clobber step. 16:15:05 INFO - Running pre-action listener: _resource_record_pre_action 16:15:05 INFO - Running main action method: clobber 16:15:05 INFO - rmtree: /builds/slave/test/build 16:15:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:15:06 INFO - Running post-action listener: _resource_record_post_action 16:15:06 INFO - [mozharness: 2017-03-29 23:15:06.170627Z] Finished clobber step (success) 16:15:06 INFO - [mozharness: 2017-03-29 23:15:06.170725Z] Running read-buildbot-config step. 16:15:06 INFO - Running pre-action listener: _resource_record_pre_action 16:15:06 INFO - Running main action method: read_buildbot_config 16:15:06 INFO - Using buildbot properties: 16:15:06 INFO - { 16:15:06 INFO - "project": "", 16:15:06 INFO - "product": "firefox", 16:15:06 INFO - "who": "kwierso@gmail.com", 16:15:06 INFO - "installer_path": "public/build/target.tar.bz2", 16:15:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:15:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 16:15:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:15:06 INFO - "stage_platform": "linux64", 16:15:06 INFO - "basedir": "/builds/slave/test", 16:15:06 INFO - "buildnumber": 1041, 16:15:06 INFO - "platform": "ubuntu64_hw", 16:15:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:15:06 INFO - "slavebuilddir": "test", 16:15:06 INFO - "taskId": "Bmlk2LIJTj-guftymsXxfw", 16:15:06 INFO - "branch": "mozilla-central", 16:15:06 INFO - "script_repo_revision": "production", 16:15:06 INFO - "revision": "60d7a0496a3673450ddbc37ec387525148c32604", 16:15:06 INFO - "slavename": "talos-linux64-ix-083", 16:15:06 INFO - "repo_path": "mozilla-central" 16:15:06 INFO - } 16:15:06 INFO - Finding installer, test and symbols from parent task. 16:15:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bmlk2LIJTj-guftymsXxfw'}, attempt #1 16:15:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bmlk2LIJTj-guftymsXxfw'}, attempt #1 16:15:07 INFO - Task dependencies: KOQbI6cGSHC6z2G0BEe_2w 16:15:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w'}, attempt #1 16:15:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.tar.bz2 16:15:07 INFO - Set test_url: https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/test_packages.json 16:15:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.crashreporter-symbols.zip 16:15:07 INFO - Running post-action listener: _resource_record_post_action 16:15:07 INFO - [mozharness: 2017-03-29 23:15:07.560802Z] Finished read-buildbot-config step (success) 16:15:07 INFO - [mozharness: 2017-03-29 23:15:07.561019Z] Running download-and-extract step. 16:15:07 INFO - Running pre-action listener: _resource_record_pre_action 16:15:07 INFO - Running main action method: download_and_extract 16:15:07 INFO - mkdir: /builds/slave/test/build/tests 16:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:07 INFO - trying https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.test_packages.json 16:15:07 INFO - Downloading https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:15:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:15:08 INFO - Downloaded 986 bytes. 16:15:08 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:15:08 INFO - Using the following test package requirements: 16:15:08 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:15:08 INFO - u'common': [u'target.common.tests.zip'], 16:15:08 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:15:08 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:15:08 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:15:08 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:15:08 INFO - u'mozbase': [u'target.common.tests.zip'], 16:15:08 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:15:08 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:15:08 INFO - u'web-platform': [u'target.common.tests.zip', 16:15:08 INFO - u'target.web-platform.tests.zip'], 16:15:08 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:15:08 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:15:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.common.tests.zip 16:15:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:15:08 INFO - Fetch https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.common.tests.zip into memory 16:15:09 INFO - Expected file size: 58224439 16:15:09 INFO - Obtained file size: 58224439 16:15:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:15:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.common.tests.zip 16:15:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:15:19 INFO - Fetch https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.common.tests.zip into memory 16:15:20 INFO - Expected file size: 58224439 16:15:20 INFO - Obtained file size: 58224439 16:15:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.talos.tests.zip 16:15:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:15:31 INFO - Fetch https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.talos.tests.zip into memory 16:15:31 INFO - Expected file size: 14404171 16:15:31 INFO - Obtained file size: 14404171 16:15: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')]} 16:15:32 INFO - trying https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.tar.bz2 16:15:32 INFO - Downloading https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:15:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:15:33 INFO - Downloaded 62526814 bytes. 16:15:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.tar.bz2 16:15:33 INFO - mkdir: /builds/slave/test/properties 16:15:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:15:33 INFO - Writing to file /builds/slave/test/properties/build_url 16:15:33 INFO - Contents: 16:15:33 INFO - build_url:https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.tar.bz2 16:15:33 INFO - Running post-action listener: _resource_record_post_action 16:15:33 INFO - Running post-action listener: set_extra_try_arguments 16:15:33 INFO - [mozharness: 2017-03-29 23:15:33.251071Z] Finished download-and-extract step (success) 16:15:33 INFO - [mozharness: 2017-03-29 23:15:33.251168Z] Running populate-webroot step. 16:15:33 INFO - Running pre-action listener: _resource_record_pre_action 16:15:33 INFO - Running main action method: populate_webroot 16:15:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:15:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:15:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:15:33 INFO - '--tppagecycles', 16:15:33 INFO - '7'], 16:15:33 INFO - 'tests': ['tsvgm']}}, 16:15:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:15:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:15:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:15:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:15:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:15:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:15:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:15:33 INFO - 'talos_options': ['--disable-e10s'], 16:15:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:15:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:15:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:15:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:15:33 INFO - 'talos_options': ['--disable-e10s'], 16:15:33 INFO - 'tests': ['damp', 'tps']}, 16:15:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:15:33 INFO - 'tests': ['damp', 'tps']}, 16:15:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:15:33 INFO - 'tests': ['dromaeo_dom']}, 16:15:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:15:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:15:33 INFO - 'tests': ['basic_compositor_video']}, 16:15:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:15:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:15:33 INFO - 'tests': ['a11yr', 16:15:33 INFO - 'ts_paint', 16:15:33 INFO - 'tpaint', 16:15:33 INFO - 'sessionrestore', 16:15:33 INFO - 'sessionrestore_no_auto_restore', 16:15:33 INFO - 'tabpaint']}, 16:15:33 INFO - 'other-e10s': {'tests': ['a11yr', 16:15:33 INFO - 'ts_paint', 16:15:33 INFO - 'tpaint', 16:15:33 INFO - 'sessionrestore', 16:15:33 INFO - 'sessionrestore_no_auto_restore', 16:15:33 INFO - 'tabpaint']}, 16:15:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:15:33 INFO - 'ts_paint', 16:15:33 INFO - 'tpaint', 16:15:33 INFO - 'sessionrestore', 16:15:33 INFO - 'sessionrestore_no_auto_restore', 16:15:33 INFO - 'tabpaint']}, 16:15:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:15:33 INFO - 'ts_paint', 16:15:33 INFO - 'tpaint', 16:15:33 INFO - 'sessionrestore', 16:15:33 INFO - 'sessionrestore_no_auto_restore', 16:15:33 INFO - 'tabpaint']}, 16:15:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:15:33 INFO - 'tests': ['a11yr', 16:15:33 INFO - 'ts_paint', 16:15:33 INFO - 'tpaint', 16:15:33 INFO - 'sessionrestore', 16:15:33 INFO - 'sessionrestore_no_auto_restore', 16:15:33 INFO - 'tabpaint']}, 16:15:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:15:33 INFO - 'tests': ['a11yr', 16:15:33 INFO - 'ts_paint', 16:15:33 INFO - 'tpaint', 16:15:33 INFO - 'sessionrestore', 16:15:33 INFO - 'sessionrestore_no_auto_restore', 16:15:33 INFO - 'tabpaint']}, 16:15:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:15:33 INFO - 'tests': ['tsvgx', 16:15:33 INFO - 'tsvgr_opacity', 16:15:33 INFO - 'tart', 16:15:33 INFO - 'tscrollx', 16:15:33 INFO - 'cart', 16:15:33 INFO - 'tsvg_static']}, 16:15:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:15:33 INFO - 'tsvgr_opacity', 16:15:33 INFO - 'tart', 16:15:33 INFO - 'tscrollx', 16:15:33 INFO - 'cart', 16:15:33 INFO - 'tsvg_static']}, 16:15:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:15:33 INFO - 'talos_options': ['--disable-e10s'], 16:15:33 INFO - 'tests': ['tp5o']}, 16:15:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:15:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:15:33 INFO - 'talos_options': ['--disable-e10s', 16:15:33 INFO - '--xperf_path', 16:15:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:15:33 INFO - 'tests': ['tp5n']}, 16:15:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:15:33 INFO - 'talos_options': ['--xperf_path', 16:15:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:15:33 INFO - 'tests': ['tp5n']}}, 16:15:33 INFO - 'talos.zip': {'path': '', 16:15:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:15:33 INFO - Running post-action listener: _resource_record_post_action 16:15:33 INFO - [mozharness: 2017-03-29 23:15:33.264230Z] Finished populate-webroot step (success) 16:15:33 INFO - [mozharness: 2017-03-29 23:15:33.264318Z] Running create-virtualenv step. 16:15:33 INFO - Running pre-action listener: _resource_record_pre_action 16:15:33 INFO - Running main action method: create_virtualenv 16:15:33 INFO - Creating virtualenv /builds/slave/test/build/venv 16:15:33 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 16:15:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:15:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:15:33 INFO - Using real prefix '/usr' 16:15:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:15:33 INFO - Installing distribute.............................................................................................................................................................................................done. 16:15:35 INFO - Installing pip.................done. 16:15:35 INFO - Return code: 0 16:15:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:15:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:15:36 INFO - Reading from file tmpfile_stdout 16:15:36 INFO - Output received: 16:15:36 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) 16:15:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:15: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')]} 16:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15: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')]} 16:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x185ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fe290>, '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 0x19feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ff040>, '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': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', '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 16:15: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', 'pip>=1.5'] in /builds/slave/test/build 16:15: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 pip>=1.5 16:15:36 INFO - Using env: {'DISPLAY': ':0', 16:15:36 INFO - 'HOME': '/home/cltbld', 16:15:36 INFO - 'LANG': 'en_US.UTF-8', 16:15:36 INFO - 'LANGUAGE': 'en_US:en', 16:15:36 INFO - 'LOGNAME': 'cltbld', 16:15:36 INFO - 'MAIL': '/var/mail/cltbld', 16:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:36 INFO - 'MOZ_NO_REMOTE': '1', 16:15:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:36 INFO - 'NO_EM_RESTART': '1', 16:15:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:36 INFO - 'PWD': '/builds/slave/test', 16:15:36 INFO - 'SHELL': '/bin/bash', 16:15:36 INFO - 'SHLVL': '1', 16:15:36 INFO - 'TERM': 'linux', 16:15:36 INFO - 'TMOUT': '86400', 16:15:36 INFO - 'USER': 'cltbld', 16:15:36 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', 16:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:36 INFO - '_': '/tools/buildbot/bin/python'} 16:15:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:36 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 16:15:36 INFO - Cleaning up... 16:15:36 INFO - Return code: 0 16:15:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:15: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')]} 16:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15: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')]} 16:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15: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', '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 0x17631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x185ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fe290>, '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 0x19feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ff040>, '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': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', '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 16:15: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:15: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 psutil>=3.1.1 16:15:36 INFO - Using env: {'DISPLAY': ':0', 16:15:36 INFO - 'HOME': '/home/cltbld', 16:15:36 INFO - 'LANG': 'en_US.UTF-8', 16:15:36 INFO - 'LANGUAGE': 'en_US:en', 16:15:36 INFO - 'LOGNAME': 'cltbld', 16:15:36 INFO - 'MAIL': '/var/mail/cltbld', 16:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:36 INFO - 'MOZ_NO_REMOTE': '1', 16:15:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:36 INFO - 'NO_EM_RESTART': '1', 16:15:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:36 INFO - 'PWD': '/builds/slave/test', 16:15:36 INFO - 'SHELL': '/bin/bash', 16:15:36 INFO - 'SHLVL': '1', 16:15:36 INFO - 'TERM': 'linux', 16:15:36 INFO - 'TMOUT': '86400', 16:15:36 INFO - 'USER': 'cltbld', 16:15:36 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', 16:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:36 INFO - '_': '/tools/buildbot/bin/python'} 16:15:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:36 INFO - Downloading/unpacking psutil>=3.1.1 16:15:36 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 16:15:36 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 16:15:36 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 16:15:36 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 16:15:36 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 16:15:36 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 16:15:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:15:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:15:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:15:38 INFO - Installing collected packages: psutil 16:15:38 INFO - Running setup.py install for psutil 16:15:38 INFO - building 'psutil._psutil_linux' extension 16:15:38 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 16:15:38 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 16:15:38 INFO - building 'psutil._psutil_posix' extension 16:15:38 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 16:15:39 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 16:15:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:15:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:15:39 INFO - Successfully installed psutil 16:15:39 INFO - Cleaning up... 16:15:39 INFO - Return code: 0 16:15:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:15:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:39 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 0x17631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x185ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fe290>, '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 0x19feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ff040>, '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': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', '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 16:15:39 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 16:15:39 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 16:15:39 INFO - Using env: {'DISPLAY': ':0', 16:15:39 INFO - 'HOME': '/home/cltbld', 16:15:39 INFO - 'LANG': 'en_US.UTF-8', 16:15:39 INFO - 'LANGUAGE': 'en_US:en', 16:15:39 INFO - 'LOGNAME': 'cltbld', 16:15:39 INFO - 'MAIL': '/var/mail/cltbld', 16:15:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:39 INFO - 'MOZ_NO_REMOTE': '1', 16:15:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:39 INFO - 'NO_EM_RESTART': '1', 16:15:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:39 INFO - 'PWD': '/builds/slave/test', 16:15:39 INFO - 'SHELL': '/bin/bash', 16:15:39 INFO - 'SHLVL': '1', 16:15:39 INFO - 'TERM': 'linux', 16:15:39 INFO - 'TMOUT': '86400', 16:15:39 INFO - 'USER': 'cltbld', 16:15:39 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', 16:15:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:39 INFO - '_': '/tools/buildbot/bin/python'} 16:15:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:15:39 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 16:15:39 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 16:15:39 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 16:15:39 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 16:15:39 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 16:15:39 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 16:15:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:15:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:15:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:15:41 INFO - Installing collected packages: mozsystemmonitor 16:15:41 INFO - Running setup.py install for mozsystemmonitor 16:15:41 INFO - Successfully installed mozsystemmonitor 16:15:41 INFO - Cleaning up... 16:15:41 INFO - Return code: 0 16:15:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:15:41 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')]} 16:15:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:41 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')]} 16:15:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:41 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 0x17631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x185ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fe290>, '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 0x19feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ff040>, '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': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', '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 16:15:41 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 16:15:41 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 16:15:41 INFO - Using env: {'DISPLAY': ':0', 16:15:41 INFO - 'HOME': '/home/cltbld', 16:15:41 INFO - 'LANG': 'en_US.UTF-8', 16:15:41 INFO - 'LANGUAGE': 'en_US:en', 16:15:41 INFO - 'LOGNAME': 'cltbld', 16:15:41 INFO - 'MAIL': '/var/mail/cltbld', 16:15:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:41 INFO - 'MOZ_NO_REMOTE': '1', 16:15:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:41 INFO - 'NO_EM_RESTART': '1', 16:15:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:41 INFO - 'PWD': '/builds/slave/test', 16:15:41 INFO - 'SHELL': '/bin/bash', 16:15:41 INFO - 'SHLVL': '1', 16:15:41 INFO - 'TERM': 'linux', 16:15:41 INFO - 'TMOUT': '86400', 16:15:41 INFO - 'USER': 'cltbld', 16:15:41 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', 16:15:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:41 INFO - '_': '/tools/buildbot/bin/python'} 16:15:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:41 INFO - Downloading/unpacking jsonschema==2.5.1 16:15:41 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 16:15:41 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 16:15:41 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 16:15:41 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 16:15:41 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 16:15:41 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 16:15:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:15:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:15:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:44 INFO - Downloading functools32-3.2.3-2.tar.gz 16:15:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:15:44 INFO - warning: no files found matching '*.txt' 16:15:44 INFO - no previously-included directories found matching 'build' 16:15:44 INFO - no previously-included directories found matching 'dist' 16:15:44 INFO - no previously-included directories found matching '.git*' 16:15:44 INFO - Installing collected packages: jsonschema, functools32 16:15:44 INFO - Running setup.py install for functools32 16:15:44 INFO - warning: no files found matching '*.txt' 16:15:44 INFO - no previously-included directories found matching 'build' 16:15:44 INFO - no previously-included directories found matching 'dist' 16:15:44 INFO - no previously-included directories found matching '.git*' 16:15:44 INFO - Successfully installed jsonschema functools32 16:15:44 INFO - Cleaning up... 16:15:44 INFO - Return code: 0 16:15:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:15:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x185ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fe290>, '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 0x19feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ff040>, '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': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', '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 16:15:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:15:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:15:44 INFO - Using env: {'DISPLAY': ':0', 16:15:44 INFO - 'HOME': '/home/cltbld', 16:15:44 INFO - 'LANG': 'en_US.UTF-8', 16:15:44 INFO - 'LANGUAGE': 'en_US:en', 16:15:44 INFO - 'LOGNAME': 'cltbld', 16:15:44 INFO - 'MAIL': '/var/mail/cltbld', 16:15:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:44 INFO - 'MOZ_NO_REMOTE': '1', 16:15:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:44 INFO - 'NO_EM_RESTART': '1', 16:15:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:44 INFO - 'PWD': '/builds/slave/test', 16:15:44 INFO - 'SHELL': '/bin/bash', 16:15:44 INFO - 'SHLVL': '1', 16:15:44 INFO - 'TERM': 'linux', 16:15:44 INFO - 'TMOUT': '86400', 16:15:44 INFO - 'USER': 'cltbld', 16:15:44 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', 16:15:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:44 INFO - '_': '/tools/buildbot/bin/python'} 16:15:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:15:44 INFO - Cleaning up... 16:15:44 INFO - Return code: 0 16:15:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:15:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x185ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fe290>, '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 0x19feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ff040>, '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': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', '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 16:15:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:15:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:15:44 INFO - Using env: {'DISPLAY': ':0', 16:15:44 INFO - 'HOME': '/home/cltbld', 16:15:44 INFO - 'LANG': 'en_US.UTF-8', 16:15:44 INFO - 'LANGUAGE': 'en_US:en', 16:15:44 INFO - 'LOGNAME': 'cltbld', 16:15:44 INFO - 'MAIL': '/var/mail/cltbld', 16:15:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:44 INFO - 'MOZ_NO_REMOTE': '1', 16:15:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:44 INFO - 'NO_EM_RESTART': '1', 16:15:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:44 INFO - 'PWD': '/builds/slave/test', 16:15:44 INFO - 'SHELL': '/bin/bash', 16:15:44 INFO - 'SHLVL': '1', 16:15:44 INFO - 'TERM': 'linux', 16:15:44 INFO - 'TMOUT': '86400', 16:15:44 INFO - 'USER': 'cltbld', 16:15:44 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', 16:15:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:44 INFO - '_': '/tools/buildbot/bin/python'} 16:15:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:44 INFO - Downloading/unpacking blobuploader==1.2.4 16:15:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:46 INFO - Downloading blobuploader-1.2.4.tar.gz 16:15:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:15:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:15:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:47 INFO - Downloading docopt-0.6.1.tar.gz 16:15:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:15:47 INFO - Installing collected packages: blobuploader, requests, docopt 16:15:47 INFO - Running setup.py install for blobuploader 16:15:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:15:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:15:47 INFO - Running setup.py install for requests 16:15:48 INFO - Running setup.py install for docopt 16:15:48 INFO - Successfully installed blobuploader requests docopt 16:15:48 INFO - Cleaning up... 16:15:48 INFO - Return code: 0 16:15:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:48 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')]} 16:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:48 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')]} 16:15:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:48 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 0x17631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x185ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fe290>, '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 0x19feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ff040>, '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': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', '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 16:15:48 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 16:15:48 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 16:15:48 INFO - Using env: {'DISPLAY': ':0', 16:15:48 INFO - 'HOME': '/home/cltbld', 16:15:48 INFO - 'LANG': 'en_US.UTF-8', 16:15:48 INFO - 'LANGUAGE': 'en_US:en', 16:15:48 INFO - 'LOGNAME': 'cltbld', 16:15:48 INFO - 'MAIL': '/var/mail/cltbld', 16:15:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:48 INFO - 'MOZ_NO_REMOTE': '1', 16:15:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:48 INFO - 'NO_EM_RESTART': '1', 16:15:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:48 INFO - 'PWD': '/builds/slave/test', 16:15:48 INFO - 'SHELL': '/bin/bash', 16:15:48 INFO - 'SHLVL': '1', 16:15:48 INFO - 'TERM': 'linux', 16:15:48 INFO - 'TMOUT': '86400', 16:15:48 INFO - 'USER': 'cltbld', 16:15:48 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', 16:15:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:48 INFO - '_': '/tools/buildbot/bin/python'} 16:15:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:15:48 INFO - Running setup.py (path:/tmp/pip-Z5my0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:15:48 INFO - Running setup.py (path:/tmp/pip-53IpOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:15:48 INFO - Running setup.py (path:/tmp/pip-o_qEfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:15:48 INFO - Running setup.py (path:/tmp/pip-VT69jV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:15:48 INFO - Running setup.py (path:/tmp/pip-dDae52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:15:48 INFO - Running setup.py (path:/tmp/pip-VAchBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:15:49 INFO - Running setup.py (path:/tmp/pip-CTrmDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:15:49 INFO - Running setup.py (path:/tmp/pip-gOmFSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:15:49 INFO - Running setup.py (path:/tmp/pip-7nlacj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:15:49 INFO - Running setup.py (path:/tmp/pip-Lj6A7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:15:49 INFO - Running setup.py (path:/tmp/pip-xwJFfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:15:49 INFO - Running setup.py (path:/tmp/pip-oEOyEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:15:49 INFO - Running setup.py (path:/tmp/pip-XUy3LX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:15:49 INFO - Running setup.py (path:/tmp/pip-R3vNd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:49 INFO - Running setup.py (path:/tmp/pip-figEau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:15:49 INFO - Running setup.py (path:/tmp/pip-yiQFCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:15:49 INFO - Running setup.py (path:/tmp/pip-8h72pS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:15:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:15:49 INFO - Running setup.py install for manifestparser 16:15:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:15:49 INFO - Running setup.py install for mozcrash 16:15:50 INFO - Running setup.py install for mozdebug 16:15:50 INFO - Running setup.py install for mozdevice 16:15:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Running setup.py install for mozfile 16:15:50 INFO - Running setup.py install for mozhttpd 16:15:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Running setup.py install for mozinfo 16:15:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Running setup.py install for mozInstall 16:15:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Running setup.py install for mozleak 16:15:50 INFO - Running setup.py install for mozlog 16:15:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Running setup.py install for moznetwork 16:15:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:15:50 INFO - Running setup.py install for mozprocess 16:15:51 INFO - Running setup.py install for mozprofile 16:15:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:15:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:15:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:15:51 INFO - Running setup.py install for mozrunner 16:15:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:15:51 INFO - Running setup.py install for mozscreenshot 16:15:51 INFO - Running setup.py install for moztest 16:15:51 INFO - Running setup.py install for mozversion 16:15:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:15:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:15:51 INFO - Cleaning up... 16:15:51 INFO - Return code: 0 16:15:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:51 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 0x17631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x185ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fe290>, '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 0x19feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ff040>, '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': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', '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 16:15:51 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 16:15:51 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 16:15:51 INFO - Using env: {'DISPLAY': ':0', 16:15:51 INFO - 'HOME': '/home/cltbld', 16:15:51 INFO - 'LANG': 'en_US.UTF-8', 16:15:51 INFO - 'LANGUAGE': 'en_US:en', 16:15:51 INFO - 'LOGNAME': 'cltbld', 16:15:51 INFO - 'MAIL': '/var/mail/cltbld', 16:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:51 INFO - 'MOZ_NO_REMOTE': '1', 16:15:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:51 INFO - 'NO_EM_RESTART': '1', 16:15:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:51 INFO - 'PWD': '/builds/slave/test', 16:15:51 INFO - 'SHELL': '/bin/bash', 16:15:51 INFO - 'SHLVL': '1', 16:15:51 INFO - 'TERM': 'linux', 16:15:51 INFO - 'TMOUT': '86400', 16:15:51 INFO - 'USER': 'cltbld', 16:15:51 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', 16:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:51 INFO - '_': '/tools/buildbot/bin/python'} 16:15:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:15:51 INFO - Running setup.py (path:/tmp/pip-X6ejN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:15:51 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)) 16:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:15:51 INFO - Running setup.py (path:/tmp/pip-y4xgQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:15:52 INFO - Running setup.py (path:/tmp/pip-IdKWS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:15:52 INFO - Running setup.py (path:/tmp/pip-RzJwFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:15:52 INFO - Running setup.py (path:/tmp/pip-TX27un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:15:52 INFO - Running setup.py (path:/tmp/pip-LuRv3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:15:52 INFO - Running setup.py (path:/tmp/pip-t1i2_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:15:52 INFO - Running setup.py (path:/tmp/pip-5CzBPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:15:52 INFO - Running setup.py (path:/tmp/pip-IcD8YI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:15:52 INFO - Running setup.py (path:/tmp/pip-4M4iUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:15:52 INFO - Running setup.py (path:/tmp/pip-rzpBhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:15:52 INFO - Running setup.py (path:/tmp/pip-iUXlcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:15:52 INFO - Running setup.py (path:/tmp/pip-jTWjoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:15:52 INFO - Running setup.py (path:/tmp/pip-v_AEkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:52 INFO - Running setup.py (path:/tmp/pip-MNeweH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:52 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)) 16:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:15:52 INFO - Running setup.py (path:/tmp/pip-lJVu38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:15:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:15:53 INFO - Running setup.py (path:/tmp/pip-LCQmOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:15:53 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)) 16:15:53 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)) 16:15:53 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)) 16:15:53 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)) 16:15:53 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)) 16:15:53 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)) 16:15:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:15:53 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 16:15:53 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 16:15:53 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 16:15:53 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 16:15:53 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 16:15:53 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 16:15:55 INFO - Downloading blessings-1.6.tar.gz 16:15:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:15:55 INFO - Installing collected packages: blessings 16:15:55 INFO - Running setup.py install for blessings 16:15:55 INFO - Successfully installed blessings 16:15:55 INFO - Cleaning up... 16:15:55 INFO - Return code: 0 16:15:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:15:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:15:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:15:55 INFO - Reading from file tmpfile_stdout 16:15:55 INFO - Current package versions: 16:15:55 INFO - argparse == 1.2.1 16:15:55 INFO - blessings == 1.6 16:15:55 INFO - blobuploader == 1.2.4 16:15:55 INFO - docopt == 0.6.1 16:15:55 INFO - functools32 == 3.2.3-2 16:15:55 INFO - jsonschema == 2.5.1 16:15:55 INFO - manifestparser == 1.1 16:15:55 INFO - mozInstall == 1.12 16:15:55 INFO - mozcrash == 1.0 16:15:55 INFO - mozdebug == 0.1 16:15:55 INFO - mozdevice == 0.49 16:15:55 INFO - mozfile == 1.2 16:15:55 INFO - mozhttpd == 0.7 16:15:55 INFO - mozinfo == 0.9 16:15:55 INFO - mozleak == 0.1 16:15:55 INFO - mozlog == 3.4 16:15:55 INFO - moznetwork == 0.27 16:15:55 INFO - mozprocess == 0.25 16:15:55 INFO - mozprofile == 0.28 16:15:55 INFO - mozrunner == 6.13 16:15:55 INFO - mozscreenshot == 0.1 16:15:55 INFO - mozsystemmonitor == 0.3 16:15:55 INFO - moztest == 0.8 16:15:55 INFO - mozversion == 1.4 16:15:55 INFO - psutil == 3.1.1 16:15:55 INFO - requests == 1.2.3 16:15:55 INFO - wsgiref == 0.1.2 16:15:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:55 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 0x17631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x185ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fe290>, '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 0x19feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ff040>, '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': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', '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 16:15:55 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 16:15:55 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 16:15:55 INFO - Using env: {'DISPLAY': ':0', 16:15:55 INFO - 'HOME': '/home/cltbld', 16:15:55 INFO - 'LANG': 'en_US.UTF-8', 16:15:55 INFO - 'LANGUAGE': 'en_US:en', 16:15:55 INFO - 'LOGNAME': 'cltbld', 16:15:55 INFO - 'MAIL': '/var/mail/cltbld', 16:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:55 INFO - 'MOZ_NO_REMOTE': '1', 16:15:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:55 INFO - 'NO_EM_RESTART': '1', 16:15:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:55 INFO - 'PWD': '/builds/slave/test', 16:15:55 INFO - 'SHELL': '/bin/bash', 16:15:55 INFO - 'SHLVL': '1', 16:15:55 INFO - 'TERM': 'linux', 16:15:55 INFO - 'TMOUT': '86400', 16:15:55 INFO - 'USER': 'cltbld', 16:15:55 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', 16:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:55 INFO - '_': '/tools/buildbot/bin/python'} 16:15:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:55 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)) 16:15:55 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)) 16:15:55 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)) 16:15:55 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)) 16:15:55 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)) 16:15:55 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)) 16:15:55 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)) 16:15:55 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)) 16:15:55 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)) 16:15:55 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)) 16:15:55 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)) 16:15:55 INFO - Cleaning up... 16:15:55 INFO - Return code: 0 16:15:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:55 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 0x17631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x185ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fe290>, '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 0x19feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ff040>, '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': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', '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 16:15:55 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 16:15:55 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 16:15:55 INFO - Using env: {'DISPLAY': ':0', 16:15:55 INFO - 'HOME': '/home/cltbld', 16:15:55 INFO - 'LANG': 'en_US.UTF-8', 16:15:55 INFO - 'LANGUAGE': 'en_US:en', 16:15:55 INFO - 'LOGNAME': 'cltbld', 16:15:55 INFO - 'MAIL': '/var/mail/cltbld', 16:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:55 INFO - 'MOZ_NO_REMOTE': '1', 16:15:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:55 INFO - 'NO_EM_RESTART': '1', 16:15:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:55 INFO - 'PWD': '/builds/slave/test', 16:15:55 INFO - 'SHELL': '/bin/bash', 16:15:55 INFO - 'SHLVL': '1', 16:15:55 INFO - 'TERM': 'linux', 16:15:55 INFO - 'TMOUT': '86400', 16:15:55 INFO - 'USER': 'cltbld', 16:15:55 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', 16:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:55 INFO - '_': '/tools/buildbot/bin/python'} 16:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:15:56 INFO - Cleaning up... 16:15:56 INFO - Return code: 0 16:15:56 INFO - Running post-action listener: _resource_record_post_action 16:15:56 INFO - Running post-action listener: _start_resource_monitoring 16:15:56 INFO - Starting resource monitoring. 16:15:56 INFO - [mozharness: 2017-03-29 23:15:56.077366Z] Finished create-virtualenv step (success) 16:15:56 INFO - [mozharness: 2017-03-29 23:15:56.077929Z] Running install step. 16:15:56 INFO - Running pre-action listener: _resource_record_pre_action 16:15:56 INFO - Running main action method: install 16:15:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:15:56 INFO - Reading from file tmpfile_stdout 16:15:56 INFO - Detecting whether we're running mozinstall >=1.0... 16:15:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:15:56 INFO - Reading from file tmpfile_stdout 16:15:56 INFO - Output received: 16:15:56 INFO - Usage: mozinstall [options] installer 16:15:56 INFO - Options: 16:15:56 INFO - -h, --help show this help message and exit 16:15:56 INFO - -d DEST, --destination=DEST 16:15:56 INFO - Directory to install application into. [default: 16:15:56 INFO - "/builds/slave/test"] 16:15:56 INFO - --app=APP Application being installed. [default: firefox] 16:15:56 INFO - mkdir: /builds/slave/test/build/application 16:15:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:16:12 INFO - Reading from file tmpfile_stdout 16:16:12 INFO - Output received: 16:16:12 INFO - /builds/slave/test/build/application/firefox/firefox 16:16:12 INFO - Running post-action listener: _resource_record_post_action 16:16:12 INFO - [mozharness: 2017-03-29 23:16:12.041296Z] Finished install step (success) 16:16:12 INFO - [mozharness: 2017-03-29 23:16:12.041627Z] Running run-tests step. 16:16:12 INFO - Running pre-action listener: _resource_record_pre_action 16:16:12 INFO - Running main action method: run_tests 16:16:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:16:12 INFO - Python 2.7.3 16:16:12 INFO - Return code: 0 16:16:12 INFO - grabbing minidump binary from tooltool 16:16:12 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')]} 16:16:12 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 0x19fe290>, '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 0x19feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ff040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:16:12 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 16:16:12 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 16:16:12 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 16:16:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:16:12 INFO - Return code: 0 16:16:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:16:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:16:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:16:12 INFO - ENV: RUST_BACKTRACE is now 1 16:16:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:16:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:16:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-083', '--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 16:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-083 --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 16:16:12 INFO - Using env: {'DISPLAY': ':0', 16:16:12 INFO - 'HOME': '/home/cltbld', 16:16:12 INFO - 'LANG': 'en_US.UTF-8', 16:16:12 INFO - 'LANGUAGE': 'en_US:en', 16:16:12 INFO - 'LOGNAME': 'cltbld', 16:16:12 INFO - 'MAIL': '/var/mail/cltbld', 16:16:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:16:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:12 INFO - 'MOZ_NO_REMOTE': '1', 16:16:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:16:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:12 INFO - 'NO_EM_RESTART': '1', 16:16:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:12 INFO - 'PWD': '/builds/slave/test', 16:16:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:16:12 INFO - 'RUST_BACKTRACE': '1', 16:16:12 INFO - 'SHELL': '/bin/bash', 16:16:12 INFO - 'SHLVL': '1', 16:16:12 INFO - 'TERM': 'linux', 16:16:12 INFO - 'TMOUT': '86400', 16:16:12 INFO - 'USER': 'cltbld', 16:16:12 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182', 16:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:12 INFO - '_': '/tools/buildbot/bin/python'} 16:16:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-083', '--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 16:16:12 INFO - mozversion application_buildid: 20170329224207 16:16:12 INFO - mozversion application_changeset: 60d7a0496a3673450ddbc37ec387525148c32604 16:16:12 INFO - mozversion application_display_name: Nightly 16:16:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:16:12 INFO - mozversion application_name: Firefox 16:16:12 INFO - mozversion application_remotingname: firefox 16:16:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:16:12 INFO - mozversion application_vendor: Mozilla 16:16:12 INFO - mozversion application_version: 55.0a1 16:16:12 INFO - mozversion platform_buildid: 20170329224207 16:16:12 INFO - mozversion platform_changeset: 60d7a0496a3673450ddbc37ec387525148c32604 16:16:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:16:12 INFO - mozversion platform_version: 55.0a1 16:16:12 INFO - using testdate: 1490829372 16:16:12 INFO - actual date: 1490829372 16:16:12 INFO - starting webserver on 'localhost:46147' 16:16:12 INFO - SUITE-START | Running 2 tests 16:16:12 INFO - TEST-START | dromaeo_css 16:16:12 INFO - operating with platform_type : linux_ 16:16:12 INFO - Initialising browser for dromaeo_css test... 16:16:12 INFO - TEST-INFO | started process 30112 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuhBD8h/profile http://localhost:46147/getInfo.html) 16:16:16 INFO - PID 30112 | __metrics Screen width/height:1600/1200 16:16:16 INFO - PID 30112 | colorDepth:24 16:16:16 INFO - PID 30112 | Browser inner width/height: 1024/697 16:16:16 INFO - PID 30112 | __metrics 16:16:17 ERROR - PID 30112 | 1490829377504 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 16:16:17 INFO - PID 30112 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:16:17 INFO - PID 30112 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:16:18 INFO - TEST-INFO | 30112: exit 0 16:16:18 INFO - Browser initialized. 16:16:18 INFO - Running cycle 1/1 for dromaeo_css test... 16:16:18 INFO - TEST-INFO | started process 30210 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuhBD8h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:16:30 INFO - PID 30210 | RSS: Main: 149737472 16:16:30 INFO - PID 30210 | 16:19:36 INFO - PID 30210 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46147/tests/dromaeo/cssquery-ext.html) 16:19:39 INFO - PID 30210 | RSS: Main: 381968384 16:19:39 INFO - PID 30210 | 16:21:20 INFO - PID 30210 | ************************* 16:21:20 INFO - PID 30210 | A coding exception was thrown and uncaught in a Task. 16:21:20 INFO - PID 30210 | 16:21:20 ERROR - PID 30210 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:21:20 INFO - PID 30210 | Full stack: 16:21:20 INFO - PID 30210 | ************************* 16:21:20 INFO - PID 30210 | ************************* 16:21:20 INFO - PID 30210 | A coding exception was thrown and uncaught in a Task. 16:21:20 INFO - PID 30210 | 16:21:20 ERROR - PID 30210 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:21:20 INFO - PID 30210 | Full stack: 16:21:20 INFO - PID 30210 | ************************* 16:21:20 INFO - PID 30210 | ************************* 16:21:20 INFO - PID 30210 | A coding exception was thrown and uncaught in a Task. 16:21:20 INFO - PID 30210 | 16:21:20 ERROR - PID 30210 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:21:20 INFO - PID 30210 | Full stack: 16:21:20 INFO - PID 30210 | ************************* 16:21:20 INFO - PID 30210 | ************************* 16:21:20 INFO - PID 30210 | A coding exception was thrown and uncaught in a Task. 16:21:20 INFO - PID 30210 | 16:21:20 ERROR - PID 30210 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:21:20 INFO - PID 30210 | Full stack: 16:21:20 INFO - PID 30210 | ************************* 16:22:42 INFO - PID 30210 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-ext.html (next: http://localhost:46147/tests/dromaeo/cssquery-jquery.html) 16:22:42 INFO - PID 30210 | RSS: Main: 239525888 16:22:42 INFO - PID 30210 | 16:25:45 INFO - PID 30210 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46147/tests/dromaeo/cssquery-mootools.html) 16:25:45 INFO - PID 30210 | RSS: Main: 178970624 16:25:45 INFO - PID 30210 | 16:28:47 INFO - PID 30210 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46147/tests/dromaeo/cssquery-prototype.html) 16:28:48 INFO - PID 30210 | RSS: Main: 204730368 16:28:48 INFO - PID 30210 | 16:31:51 INFO - PID 30210 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46147/tests/dromaeo/cssquery-yui.html) 16:31:52 INFO - PID 30210 | RSS: Main: 190201856 16:31:52 INFO - PID 30210 | 16:34:55 INFO - PID 30210 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-yui.html (next: http://localhost:46147/tests/dromaeo/cssquery-dojo.html) 16:34:55 INFO - PID 30210 | RSS: Main: 276156416 16:34:55 INFO - PID 30210 | 16:34:55 INFO - PID 30210 | __start_tp_report 16:34:55 INFO - PID 30210 | _x_x_mozilla_page_load 16:34:55 INFO - PID 30210 | _x_x_mozilla_page_load_details 16:34:55 INFO - PID 30210 | |i|pagename|runs| 16:34:55 INFO - PID 30210 | |0;dojo.html;2631.565791644379;2662.161419152968;2679.799015073869;2695.905643302485;2705.3777631144835;4981.37732783402;5062.974685126569;5119.820806271786;5128.589712822974;5166.612504062196;5147.897042059166;5185.974070129644;5191.169412893938;5230.529252367286;5256.237845512398;4447.399601053865;4692.788824502889;4751.833685820997;4819.638527110464;4834.87912802181;4756.976215118937;4766.523347665223;4785.090832741786;4800.663953523254;4859.489753575876;4629.3518907353;4668.089722504113;4672.602828759559;4681.040386720714;4719.4100737407825;4656.8602941911795;4673.439187297512;4677.906441871143;4681.88295292617;4683.039976804746;4614.077184563101;4633.62930965522;4639.814407423699;4645.7909394077005;4653.278741795028;5021.799128034874;5060.367454068241;5064.898702025976;5075.619328550373;5082.339295891547;5453.563714902838;5460.262864513319;5476.424975377608;5478.45215478453;5496.532794712454;5462.863428414282;5484.972575137099;5511.173324001392;5627.549796016309;5692.345380281279;5222.660527065728;5230.97384513075;5231.633785635007;5276.076686579871;5282.656627319212;5791.507721843606;5797.565182611237;5798.217240672539;5805.564582656318;5807.128930660435;5317.1226747586215;5396.757145928443;5397.082495975694;5397.9460205397445;5406.810761623325;5465.152901300305;5544.972275138679;5624.55003599711;5635.8591035224445;5637.154426835926;5554.750036248379;5564.860878478071;5585.245991791056;5588.776448941997;5603.467670571289;5596.4403559644525;5599.916001259984;5604.327480702342;5613.494785469327;5623.493885550321;4528.41130653013;4587.816487340536;4642.791074401593;4696.647751418589;4709.058188362341;3988.561258261589;3991.92016159681;4003.91992160161;4020.6984476164403;4032.516098068251;4736.218523943511;4744.667873248839;4755.881102972384;4756.21522448799;4802.567768900815;5458.208559758813;5494.890102197934;5549.500544950974;5616.297962754656;5632.97183514088;4754.904901901942;4765.142274390643;4766.308885211625;4800.879978000508;4816.20532612115;4997.900041999213;4999.5500404963;5011.599072074226;5012.724300163453;5056.039352523009;5090.134677104975;5137.152369858932;5239.738013099253;5260.39505456862;5313.070212712682;5498.670079795225;5599.1041433370465;5652.039153343859;5680.034394153086;5696.943030569807;5255.395629502504;5264.499872512105;5266.394364647961;5268.736563171749;5311.017461769585;5471.562275017909;5486.780528778804;5530.944690553043;5537.141743029837;5581.804636837691;5448.101063324666;5494.532964697885;5600.635958662674;5630.943690563042;5676.801311954062;5457.563394928476;5462.535684466865;5465.945340546544;5471.671699698031;5527.143292789624;5195.792168313225;5270.552003079782;5283.154695248895;5285.867853303698;5291.3121294231505;5262.789488420573;5265.420803711512;5267.367915850123;5305.495977882095;5310.415854256106;5371.946280537145;5375.462453754593;5381.973090134525;5401.216823560483;5410.3507579090765;5206.583473322201;5223.112070948024;5246.842594722164;5249.160134378481;5254.15933450646 16:34:55 INFO - PID 30210 | |1;ext.html;693.3724978894099;719.3885197582055;1066.205676770806;1085.4247248958054;1092.8688557373112;32788.34423311536;33665.66334336659;33776.66223337766;33786.49320260198;33848.323033539345;35629.821850890774;35990.10024749383;35997.64002359976;36027.2794544109;36028.099297517576;5634.774609015634;7331.890021649666;7521;7524.811879703024;7532.698692052339;5756.568257380693;7113.964430177842;7314.195438501761;7318.194998550155;7323.707051717952;7426.257374262557;7458.776236712907;7459.254074592524;7461.403087752967;7481.064866891638;7350.264973502633;7372.594507302097;7390.852182956311;7428.480006399554;7432;7241.927580724178;7318.231585683533;7321.1331110567;7322.743703970335;7324.743633972838;33584.16039599029;33815.154621134425;33819.15452113692;33935.83032084824;33952.6604733952;2655.7875369970357;2674.358154043035;2681.249250209925;2698.2579790557556;2725.5775354819834;23119.422014449607;23350.883245583835;23392.883035584713;23532.764672353227;23757.524849503086;2761.654793150856;2780;2780.7497325240624;2788.567771995344;2792.3856751514636;145484.54515454816;146133;146146;146479;147378;34556.136096597285;35611.28777424437;40739.38890916639;40812.79593602013;40860;42702;43313.783431082644;43402.34896476555;43458.78270608627;43471;11827.52689892412;11877.762444751057;11948.701282468008;11961.162718609614;11965.581204657796;9500.382475139093;9526.190273826802;9535.713928582014;9535.856962145574;9536.66621668252;12058.577949771716;12074.758504829853;12160.391980400944;12187.085968552394;12260.386980650932;5069.670471419419;5130.102232109366;5130.256112863613;5172.741362931838;5197.480251974848;871.4205053639386;882.183979818678;890.6526454682679;899.8290324838259;920.0753242990885;20250.392488225378;20926.37220883376;21063.78936210649;21225.15099396007;21442.571148576943;966.120830044656;967.579103090158;973.7078876337213;979.1334668818147;994.5077186792583;10434.843477347846;10683.89316106829;10983.176261780398;11007.559697612165;11158.107351411867;193702;199426;199750;199883;214454;42902.78548607299;43092.569074308856;43099.784501077294;43107.13785724268;43183.13633727308;1188.5067696905687;1228.8525376954822;1243.975120497603;1260.9810852837215;1262.7663882181826;22457;22553.43616409603;23452;23554.646680300153;23568.6464703033;29196.7080329194;29227.123186304445;29238.853805730836;29294;29296.121116366543;188588;189127;189794;189985;190036;22072.33782986513;22448.32655020352;26159;26234.344141396617;26610.467790644467;32826.34347312993;33042.66957330492;33328.333433330714;38182.809085954395;39151.2169756609;29823.254418639706;30185.69814301829;30467.542986854773;30768.46856219944;31537.36925261436;30297.24256893595;30508.847455762578;30865.228369290948;31365.372692546476;31944.840275798473;34606;35007.824960875034;35046.649533505355;35080.298394032485;35335.29329413346;30554.388912221188;30799.538006929466;31050.378992419573;31087.533686994262;31164.376712466077;23029.424264393525;24408.26775196747;24645.260642180765;24707.752922471263;24872.502549949262 16:34:55 INFO - PID 30210 | |2;jquery.html;386.7389512079346;390.2662993572084;417.3822742341334;419.484034637396;423.7944596870517;12622.81065784014;16775.91612041938;17917.641647167064;18668.439946801584;18678.62642747146;13133.540326088589;14082;17764.911175444136;17976;18468.538286542844;5521.254630624874;5723.599348045638;5724.313082430114;5753.597248192628;5769.480746732793;5597.776088956437;5647.943520564803;5690.231818704463;5693.316801983768;5693.572982026344;5583.832485025456;5700.315962084555;5712.028955077647;5716.1997320375185;5719.313682358123;5640.294963129609;5664.801731939362;5703.03048481757;5704.0303148464445;5708.80019199326;5140.74296285188;5169.689818610896;5193.402758682762;5217.791288348461;5223.869403264911;21845.67231491529;22136.335909922727;22165.889170554045;22171.445713857283;22279.77720222793;1559.337281655292;1561.5627624265223;1582.6359937214504;1600.2638786158382;1633.9836601633951;13801.792973105412;13823.447062117502;14154.85845141556;14176.716465670628;14750.55748327552;1610.5731981025037;1628.3405220885443;1634.9427770027992;1652.3638399216268;1659.9170041497998;86769;88552.55723721405;89852.5507372459;90817.54591227001;92280.53859730726;10683.09193718527;10733.195010374253;10854.077403420797;11132.165087618461;11148.219624626361;15666.98164619319;15689.450869219523;15691.921540392224;15701.842981570038;15777.447789327318;6712.295209003082;7064.046353742282;7320.889349531053;7523.661435235318;7743.8838417423785;6335.651539165298;6537.084808126866;6621.801345959629;6630.801075967729;6674.532782705164;6942.618156001468;7000.094960591399;7006.929930700628;7080.256573059857;7252.005698432868;1971.3001884331143;1983.9107240174226;1993.451800754804;2022.5044864008412;2092.7384076990375;827.6358402302967;847.2544161138159;855.3883972959364;863.8747381629629;867.1458613265928;16091.436799712188;16621.335146594;17131.143442827808;17197.828021719874;17325.480235592953;839.3620848155446;866.2290561400358;868.8566386546275;870.5560164316245;884.3146561414954;8565.78585535354;9230.907690923004;9286.210672092948;9333.393329433566;9462.668806591873;461724;462604;469276;471799;474284;90773.54613226892;91301;91709.54145229231;91834.54082729544;92842.53578732064;1050.3435352904435;1079.551985925832;1100.125400306742;1113.3375641493299;1115.8158903780948;13899.61003899602;13908.095973761672;13972;14146.816966182527;14832.777508337167;38835.611643883196;39477;39514.2097158061;39626.801865990485;40492;53610.73194634002;54074.72962635162;54163.45836541584;54372.184417234566;54467;7919.524828510308;8059.0732065810835;8108.2297181767635;8315.95842020786;8480.618372173143;12507.624771256877;12874.871251287368;12911.160774549624;13036.674083147618;13185.802212967004;10029.247806414402;10210.897891021292;10304.227182961158;10331;10658.253922225369;10127.24045696561;10310.32982856112;10426.218033647356;10519.421431821018;10606.522706478072;11251;11365.204435689753;11440.542378304775;11452.599159029394;11825.82261266099;7960.721374751856;8147.9185208147155;8227.465214761261;8400.789980250544;8566.229039386486;9395.342326037386;9548.42709437436;9650.276229282692;9748.610055597981;9761.365511241747 16:34:55 INFO - PID 30210 | |3;mootools.html;1242.0622430065303;1290.5547586082805;1293.2434525802405;1298.9997701769637;1322.4577923051552;9255.120763527471;9672.758181045478;9675.516224188797;9678.177354924832;9678.516074196297;9408.717738467856;9430.528473576329;9701.70894873155;9719;9724.222062235021;2137.177186783088;2194.2539536557565;2194.4623567226054;2196.8242540596716;2199.461132022657;1861.8696691231619;2098.4544018555134;2153.9569208615817;2181.0512427094186;2230.297456301268;1899.4871384726116;2111.9260825871097;2190.9671354929687;2190.9780902191014;2196.802287794098;2096.6330892093824;2116.492041909938;2120.7349081364828;2128.531723020933;2129.4144110369693;2075.377386783974;2089.968650470244;2090.7491101067967;2092.1526781653456;2094.0890712540095;9236.491992940386;9377.484238366887;9379.155875971128;9387.249020078445;9392.67125650599;5303.443138470482;5512.173174023888;5512.641678290898;5525.4474552544425;5526.613137080406;3137.1043552300694;3252.886148984774;3320.9870989348483;3324.8670053197843;3333.100062983005;1000.6197644894893;1050.070687441612;1075.1774892207468;1077.7036315013356;1091.7598128411737;13459.327033648278;13781.173129612256;13933.094348867291;13935.372908219257;14122.435102595884;4747.098051370298;4906.779194936236;4989.625778066659;5007.874803129951;5012.172991456442;11663.76672466546;11707.707307317214;11709.473073711704;11710.1217408693;11715.882841171479;5566.415526369673;5581.134924086692;5584.972075139598;5587.916181257285;5596.1605759136455;4833.299171620095;4974.701517908937;4977.576905962961;5032.899342013139;5035;3681.9690486663776;3827.2919509890758;3836.731428800013;3836.7889766063136;3841.4237864320576;1125.6960620632383;1139.5726602524053;1140.3670962615827;1140.8185472450941;1141.7316930521322;559.5976226617978;683.2416018219772;696.4532841719226;698.3296035805582;718.4683334332557;5569.888602227933;5725.541956643458;5743.54051675865;5778.971105144531;5787.88424231513;799.3245707377373;807.1565214351017;824.4805772363372;827.3009307135461;833.0628043451118;3335.149536868083;3494.7728397654073;3572.446270827974;3592.604813470516;3681.3189559931498;11861.169718119649;12441.688957776129;12478.47806884012;12643.810342844865;12677.049221308438;12375.876241237653;12449.25304481734;12467.127301089018;12482.50069997208;12514.561990330476;8563.143685631387;8838.955805221061;8883.600237989305;8901.332400070021;8907.777305567282;5379.112446446449;5421.132618780976;5456.372517160577;5518.475744804237;5626.831195063991;11030.50362733695;11092.500837462496;11177.608783692533;11225.943870280596;11254.549818007188;11240.55037798479;11309.377984210947;11349.659510214708;11437.370944598128;11478.483468244116;7948.324392426708;8089.47418417801;8156.796080097808;8160.959195204224;8162.6734930602115;8820.691275805573;8874.201321881063;8952.68665596701;8961.865572016555;9191.310651701015;7296.854062918818;7346.191918889025;7372.29963153521;7376.4836461447185;7468.5145465544565;7123.786286411416;7148.249433809375;7156.320149585781;7363.8159046024275;7391.96304018498;7087.751928682472;7125.216226215009;7190.784276471714;7197.568145911262;7211.927880721336;4865.440474345354;4889.364382630378;4916;5054.19132938728;5146.408163061146;4902.509749025069;4997.600191984559;5013.523715247045;5162.328897243335;5241.082810508222 16:34:55 INFO - PID 30210 | |4;prototype.html;366.15783697495755;387.9883603491895;388.67545599424477;391.9745216560925;394.77300552182487;5305.204219367097;5337.893242135155;5366.409694933554;5381.408045115034;5388.299521062267;5001.8249361272365;5134.589232861372;5156.200788877721;5178.508041736039;5255.527002569768;4074.694397920153;4080.6735461163184;4085.5097388313443;4099.959000409995;4109.4041364002205;4078.2455245779624;4086.2031903778775;4089.141280331119;4098.918021639581;4112.794360281974;3988.5413177484525;4018.598140185987;4058.472398588179;4091.7340372875815;4097;4042.3936409538655;4049.1699201663587;4056.4523789288364;4065.3292206785845;4069.7151199416053;3562.0738607961857;3563.6080031196548;3566.0906468850276;3568.6082427853157;3576.2847430513743;4996.275540046673;5103.719295438749;5117.795288188468;5133.922991155136;5152.896942061176;4661.720296782204;4680.625549955996;4695.413073365829;4701.764911754399;4720.150372932871;5514.5864060195645;5538.667679939216;5539.390667026624;5543.944560554383;5568.86077848053;3939.1530820873363;3940.4877365942243;3952.7548822121225;3967.6429121378924;3981.7013723970526;5616.157576363537;5629.887402251932;5662.546996240291;5700.572457065695;5860.09168578871;4007.6593489553134;4008.198360327946;4016.6786657067364;4017.2367250222364;4029.073313137936;4053.351463765783;4078.490188726409;4089.7955102245364;4104.794760261975;4105.199486100182;4457;4472.016156445577;4563.452385713736;4687.601553867891;4701.412323459567;5594.300712410949;5595.272614560081;5597.580181486404;5605.5515558755205;5605.775768969195;1413.6041908265702;1421.2964582531713;1423.6369725720083;1427.1009264163717;1427.3086392624566;928.6146249306462;954.9809003819885;960.3421656165549;970.7087873638021;972.6498460554195;4815.15734746418;4828.348172996671;4833.492483289274;4833.709977401367;4844;5743.913841292384;5746.511546518509;5752.597318187687;5797.36229014808;5800;4679.227927391942;4799.256115302133;4828.5412885775795;4832.44426890905;4833.130036593377;3006.1582756828125;3042.117785842125;3042.756579473681;3049.9477680200293;3051.404976029697;5336.733163341817;5352.866178345573;5362.302900622972;5375.650582712111;5376.623636345418;5721.256236689287;5830.522028138024;5846.941530584725;5853.385394533598;5877.177195192677;5326.920096198638;5333.853221557384;5346.331708536433;5354.651947623392;5356.839294821161;5559.805406810742;5722.8569285766525;5722.914156287576;5724.169995350734;5738.426157384228;5592.105263158038;5626.915596266142;5636.915446268391;5646.181303711021;5672.404397538199;5120.71836049006;5252.579793616578;5263.815766448156;5290.761915713725;5354.7590358434745;3811.1424929390664;3917.472185847465;3926.784026878435;4006.4791577094793;4052.412400202013;5740.454656807597;5752.194692742935;5753.1370294456165;5754.683492407958;5759.9136012959;5377.2740680009265;5511.200875873056;5533.529649979797;5555.194496798023;5582.972085139549;5553.16702494629;5556.361018482765;5578.581606379619;5599.692016939108;5600.69196194197;5363.19552067193;5526.447355264441;5538.889222215453;5541.224228608079;5552.666839989613;5599.440055994368;5622.24099746529;5622.690752008515;5631.521320687787;5646.85882852932;5456.563474922076;5460.7542660579575;5472;5505.421930697379;5520.585956053232 16:34:55 INFO - PID 30210 | |5;yui.html;575.7898367096006;584.9970750146249;595.3748564007792;602.0968547179231;602.2171177469289;12057.39713014349;12327.938360308186;12479.385925459499;12566.497340106396;12815.102942794008;11806.82289765652;11990.820137697941;12029;12045;12050.096242781805;877.1798368525417;898.1871406377239;910.3581974707815;910.6266430763388;910.7404389748923;912.4753266871543;913.8720579118929;915.2952226785371;931.5668214280358;938.5119737736374;957.5690939077408;959.6209497248583;959.7648576098866;961.7499450142943;961.7643677299037;947.327397547742;948.2177203806887;949.9477528735916;950.0167326816746;950.5959967013991;703.3107554596525;703.9084918960554;705.0094617063035;705.3722187253348;705.8835292176784;12888;12998.545050923267;13637.386317615732;13779.793303100461;13819.654508637264;13552.457901683922;14192.432302707879;14305.427782888775;14384.136951782823;14499.855001449956;9483.525823708787;9618.711438656852;9624.47065411402;9655.420674759536;9863.506824658738;2484.7391023942405;2511.2842839790574;2524.192258477288;2525.7853082487827;2535.6196570514385;62751.6862415685;64655.67672161656;66812.3318766811;66957.3304266956;67877.32122678809;10734.141268698486;10877;10907.781844363068;10915.454227288605;11106.777864442665;21848.12607495714;21858.562828743656;21865.344039678836;21991.560168796535;22188.667169992463;5415.566754659618;5420.376656684532;5423.5932305076485;5573.5819813514145;5584.52531534816;3159.952600710991;3167.9683203167674;3185.235543469597;3185.5858738363863;3200.2159470929773;3546;3621.040424287566;3638.7452878298263;3642.307961487309;3659.213269147146;602.4698265526309;612.2163630552899;618.8452886778306;622.1228068423501;622.3185611755121;455.21793558666656;475.48647460742313;476.70920738349577;483.1303653423893;490.8134908734658;7249.021382113453;7456.701731930662;7612.391008719289;7615.390768738488;7629.694812207561;467.56282875511505;479.2212654436541;482.0479552883098;495.8487661263349;497.8423532206339;3270.738340932767;3310.741918071166;3332.166958260435;3374.2407958209205;3394.575678040245;56952.43047569554;58483.70758146267;61301;61574;61618;51729.22406163911;52152.478475215525;53160.468395315555;53528;53621.73189134029;47151;47821.28268075982;48023.75988120037;48834.51165488371;49242;6649.46804255668;6936;6980.197277312971;7004.076525469692;7120.430365570845;25818.612720808826;26152.60771088473;26163.607545887207;26278.474430511666;26709;48188.277175841686;49045.2643210345;49760.502394975585;50313.49686503088;50622;10781.784364312512;10841.457927103771;11376.943115284701;11454.941089582326;11458.484368203284;10742.946285268523;10807.567697292021;10910.181736369963;11121.610743623934;11241.269317494336;10929.617463388744;11203.43982800873;11911.52353905834;11917.22538035025;11926.642200733992;11217.943910280397;11258;11771.764564708828;11934.28394296345;11940.76118477643;10383.169346452416;10967.122630189726;11222.38276894746;11303.547858085585;11317.264377815416;8657.523836188819;8800.16398442147;8968.596413161555;9005.14451127142;9097.954510227406;8993.415427997159;10112.848307275544;10180.287379883337;10282.588696452358;10291.897081029094 16:34:55 INFO - PID 30210 | __end_tp_report 16:34:55 INFO - PID 30210 | __start_cc_report 16:34:55 INFO - PID 30210 | _x_x_mozilla_cycle_collect,3000 16:34:55 INFO - PID 30210 | __end_cc_report 16:34:55 INFO - PID 30210 | __startTimestamp1490830495655__endTimestamp 16:34:55 INFO - PID 30210 | 16:34:55 INFO - PID 30210 | ------- Summary: start ------- 16:34:55 INFO - PID 30210 | Number of tests: 6 16:34:55 INFO - PID 30210 | 16:34:55 INFO - PID 30210 | [#0] dojo.html Cycles:165 Average:5101.27 Median:5256.24 stddev:587.30 (11.2%) stddev-sans-first:556.23 16:34:55 INFO - PID 30210 | Values: 2631.6 2662.2 2679.8 2695.9 2705.4 4981.4 5063.0 5119.8 5128.6 5166.6 5147.9 5186.0 5191.2 5230.5 5256.2 4447.4 4692.8 4751.8 4819.6 4834.9 4757.0 4766.5 4785.1 4800.7 4859.5 4629.4 4668.1 4672.6 4681.0 4719.4 4656.9 4673.4 4677.9 4681.9 4683.0 4614.1 4633.6 4639.8 4645.8 4653.3 5021.8 5060.4 5064.9 5075.6 5082.3 5453.6 5460.3 5476.4 5478.5 5496.5 5462.9 5485.0 5511.2 5627.5 5692.3 5222.7 5231.0 5231.6 5276.1 5282.7 5791.5 5797.6 5798.2 5805.6 5807.1 5317.1 5396.8 5397.1 5397.9 5406.8 5465.2 5545.0 5624.6 5635.9 5637.2 5554.8 5564.9 5585.2 5588.8 5603.5 5596.4 5599.9 5604.3 5613.5 5623.5 4528.4 4587.8 4642.8 4696.6 4709.1 3988.6 3991.9 4003.9 4020.7 4032.5 4736.2 4744.7 4755.9 4756.2 4802.6 5458.2 5494.9 5549.5 5616.3 5633.0 4754.9 4765.1 4766.3 4800.9 4816.2 4997.9 4999.6 5011.6 5012.7 5056.0 5090.1 5137.2 5239.7 5260.4 5313.1 5498.7 5599.1 5652.0 5680.0 5696.9 5255.4 5264.5 5266.4 5268.7 5311.0 5471.6 5486.8 5530.9 5537.1 5581.8 5448.1 5494.5 5600.6 5630.9 5676.8 5457.6 5462.5 5465.9 5471.7 5527.1 5195.8 5270.6 5283.2 5285.9 5291.3 5262.8 5265.4 5267.4 5305.5 5310.4 5371.9 5375.5 5382.0 5401.2 5410.4 5206.6 5223.1 5246.8 5249.2 5254.2 16:34:55 INFO - PID 30210 | 16:34:55 INFO - PID 30210 | [#1] ext.html Cycles:180 Average:32483.62 Median:31451.37 stddev:46818.47 (148.9%) stddev-sans-first:46888.95 16:34:55 INFO - PID 30210 | Values: 693.4 719.4 1066.2 1085.4 1092.9 32788.3 33665.7 33776.7 33786.5 33848.3 35629.8 35990.1 35997.6 36027.3 36028.1 5634.8 7331.9 7521.0 7524.8 7532.7 5756.6 7114.0 7314.2 7318.2 7323.7 7426.3 7458.8 7459.3 7461.4 7481.1 7350.3 7372.6 7390.9 7428.5 7432.0 7241.9 7318.2 7321.1 7322.7 7324.7 33584.2 33815.2 33819.2 33935.8 33952.7 2655.8 2674.4 2681.2 2698.3 2725.6 23119.4 23350.9 23392.9 23532.8 23757.5 2761.7 2780.0 2780.7 2788.6 2792.4 145484.5 146133.0 146146.0 146479.0 147378.0 34556.1 35611.3 40739.4 40812.8 40860.0 42702.0 43313.8 43402.3 43458.8 43471.0 11827.5 11877.8 11948.7 11961.2 11965.6 9500.4 9526.2 9535.7 9535.9 9536.7 12058.6 12074.8 12160.4 12187.1 12260.4 5069.7 5130.1 5130.3 5172.7 5197.5 871.4 882.2 890.7 899.8 920.1 20250.4 20926.4 21063.8 21225.2 21442.6 966.1 967.6 973.7 979.1 994.5 10434.8 10683.9 10983.2 11007.6 11158.1 193702.0 199426.0 199750.0 199883.0 214454.0 42902.8 43092.6 43099.8 43107.1 43183.1 1188.5 1228.9 1244.0 1261.0 1262.8 22457.0 22553.4 23452.0 23554.6 23568.6 29196.7 29227.1 29238.9 29294.0 29296.1 188588.0 189127.0 189794.0 189985.0 190036.0 22072.3 22448.3 26159.0 26234.3 26610.5 32826.3 33042.7 33328.3 38182.8 39151.2 29823.3 30185.7 30467.5 30768.5 31537.4 30297.2 30508.8 30865.2 31365.4 31944.8 34606.0 35007.8 35046.6 35080.3 35335.3 30554.4 30799.5 31050.4 31087.5 31164.4 23029.4 24408.3 24645.3 24707.8 24872.5 16:34:55 INFO - PID 30210 | 16:34:55 INFO - PID 30210 | [#2] jquery.html Cycles:180 Average:27991.85 Median:462164.00 stddev:77490.62 (16.8%) stddev-sans-first:77680.28 16:34:55 INFO - PID 30210 | Values: 386.7 390.3 417.4 419.5 423.8 12622.8 16775.9 17917.6 18668.4 18678.6 13133.5 14082.0 17764.9 17976.0 18468.5 5521.3 5723.6 5724.3 5753.6 5769.5 5597.8 5647.9 5690.2 5693.3 5693.6 5583.8 5700.3 5712.0 5716.2 5719.3 5640.3 5664.8 5703.0 5704.0 5708.8 5140.7 5169.7 5193.4 5217.8 5223.9 21845.7 22136.3 22165.9 22171.4 22279.8 1559.3 1561.6 1582.6 1600.3 1634.0 13801.8 13823.4 14154.9 14176.7 14750.6 1610.6 1628.3 1634.9 1652.4 1659.9 86769.0 88552.6 89852.6 90817.5 92280.5 10683.1 10733.2 10854.1 11132.2 11148.2 15667.0 15689.5 15691.9 15701.8 15777.4 6712.3 7064.0 7320.9 7523.7 7743.9 6335.7 6537.1 6621.8 6630.8 6674.5 6942.6 7000.1 7006.9 7080.3 7252.0 1971.3 1983.9 1993.5 2022.5 2092.7 827.6 847.3 855.4 863.9 867.1 16091.4 16621.3 17131.1 17197.8 17325.5 839.4 866.2 868.9 870.6 884.3 8565.8 9230.9 9286.2 9333.4 9462.7 461724.0 462604.0 469276.0 471799.0 474284.0 90773.5 91301.0 91709.5 91834.5 92842.5 1050.3 1079.6 1100.1 1113.3 1115.8 13899.6 13908.1 13972.0 14146.8 14832.8 38835.6 39477.0 39514.2 39626.8 40492.0 53610.7 54074.7 54163.5 54372.2 54467.0 7919.5 8059.1 8108.2 8316.0 8480.6 12507.6 12874.9 12911.2 13036.7 13185.8 10029.2 10210.9 10304.2 10331.0 10658.3 10127.2 10310.3 10426.2 10519.4 10606.5 11251.0 11365.2 11440.5 11452.6 11825.8 7960.7 8147.9 8227.5 8400.8 8566.2 9395.3 9548.4 9650.3 9748.6 9761.4 16:34:55 INFO - PID 30210 | 16:34:55 INFO - PID 30210 | [#3] mootools.html Cycles:180 Average:6043.03 Median:4911.39 stddev:3809.58 (77.6%) stddev-sans-first:3803.19 16:34:55 INFO - PID 30210 | Values: 1242.1 1290.6 1293.2 1299.0 1322.5 9255.1 9672.8 9675.5 9678.2 9678.5 9408.7 9430.5 9701.7 9719.0 9724.2 2137.2 2194.3 2194.5 2196.8 2199.5 1861.9 2098.5 2154.0 2181.1 2230.3 1899.5 2111.9 2191.0 2191.0 2196.8 2096.6 2116.5 2120.7 2128.5 2129.4 2075.4 2090.0 2090.7 2092.2 2094.1 9236.5 9377.5 9379.2 9387.2 9392.7 5303.4 5512.2 5512.6 5525.4 5526.6 3137.1 3252.9 3321.0 3324.9 3333.1 1000.6 1050.1 1075.2 1077.7 1091.8 13459.3 13781.2 13933.1 13935.4 14122.4 4747.1 4906.8 4989.6 5007.9 5012.2 11663.8 11707.7 11709.5 11710.1 11715.9 5566.4 5581.1 5585.0 5587.9 5596.2 4833.3 4974.7 4977.6 5032.9 5035.0 3682.0 3827.3 3836.7 3836.8 3841.4 1125.7 1139.6 1140.4 1140.8 1141.7 559.6 683.2 696.5 698.3 718.5 5569.9 5725.5 5743.5 5779.0 5787.9 799.3 807.2 824.5 827.3 833.1 3335.1 3494.8 3572.4 3592.6 3681.3 11861.2 12441.7 12478.5 12643.8 12677.0 12375.9 12449.3 12467.1 12482.5 12514.6 8563.1 8839.0 8883.6 8901.3 8907.8 5379.1 5421.1 5456.4 5518.5 5626.8 11030.5 11092.5 11177.6 11225.9 11254.5 11240.6 11309.4 11349.7 11437.4 11478.5 7948.3 8089.5 8156.8 8161.0 8162.7 8820.7 8874.2 8952.7 8961.9 9191.3 7296.9 7346.2 7372.3 7376.5 7468.5 7123.8 7148.2 7156.3 7363.8 7392.0 7087.8 7125.2 7190.8 7197.6 7211.9 4865.4 4889.4 4916.0 5054.2 5146.4 4902.5 4997.6 5013.5 5162.3 5241.1 16:34:55 INFO - PID 30210 | 16:34:55 INFO - PID 30210 | [#4] prototype.html Cycles:180 Average:4592.54 Median:5254.05 stddev:1337.28 (25.5%) stddev-sans-first:1302.87 16:34:55 INFO - PID 30210 | Values: 366.2 388.0 388.7 392.0 394.8 5305.2 5337.9 5366.4 5381.4 5388.3 5001.8 5134.6 5156.2 5178.5 5255.5 4074.7 4080.7 4085.5 4100.0 4109.4 4078.2 4086.2 4089.1 4098.9 4112.8 3988.5 4018.6 4058.5 4091.7 4097.0 4042.4 4049.2 4056.5 4065.3 4069.7 3562.1 3563.6 3566.1 3568.6 3576.3 4996.3 5103.7 5117.8 5133.9 5152.9 4661.7 4680.6 4695.4 4701.8 4720.2 5514.6 5538.7 5539.4 5543.9 5568.9 3939.2 3940.5 3952.8 3967.6 3981.7 5616.2 5629.9 5662.5 5700.6 5860.1 4007.7 4008.2 4016.7 4017.2 4029.1 4053.4 4078.5 4089.8 4104.8 4105.2 4457.0 4472.0 4563.5 4687.6 4701.4 5594.3 5595.3 5597.6 5605.6 5605.8 1413.6 1421.3 1423.6 1427.1 1427.3 928.6 955.0 960.3 970.7 972.6 4815.2 4828.3 4833.5 4833.7 4844.0 5743.9 5746.5 5752.6 5797.4 5800.0 4679.2 4799.3 4828.5 4832.4 4833.1 3006.2 3042.1 3042.8 3049.9 3051.4 5336.7 5352.9 5362.3 5375.7 5376.6 5721.3 5830.5 5846.9 5853.4 5877.2 5326.9 5333.9 5346.3 5354.7 5356.8 5559.8 5722.9 5722.9 5724.2 5738.4 5592.1 5626.9 5636.9 5646.2 5672.4 5120.7 5252.6 5263.8 5290.8 5354.8 3811.1 3917.5 3926.8 4006.5 4052.4 5740.5 5752.2 5753.1 5754.7 5759.9 5377.3 5511.2 5533.5 5555.2 5583.0 5553.2 5556.4 5578.6 5599.7 5600.7 5363.2 5526.4 5538.9 5541.2 5552.7 5599.4 5622.2 5622.7 5631.5 5646.9 5456.6 5460.8 5472.0 5505.4 5520.6 16:34:55 INFO - PID 30210 | 16:34:55 INFO - PID 30210 | [#5] yui.html Cycles:180 Average:14213.16 Median:24251.49 stddev:17766.21 (73.3%) stddev-sans-first:17786.53 16:34:55 INFO - PID 30210 | Values: 575.8 585.0 595.4 602.1 602.2 12057.4 12327.9 12479.4 12566.5 12815.1 11806.8 11990.8 12029.0 12045.0 12050.1 877.2 898.2 910.4 910.6 910.7 912.5 913.9 915.3 931.6 938.5 957.6 959.6 959.8 961.7 961.8 947.3 948.2 949.9 950.0 950.6 703.3 703.9 705.0 705.4 705.9 12888.0 12998.5 13637.4 13779.8 13819.7 13552.5 14192.4 14305.4 14384.1 14499.9 9483.5 9618.7 9624.5 9655.4 9863.5 2484.7 2511.3 2524.2 2525.8 2535.6 62751.7 64655.7 66812.3 66957.3 67877.3 10734.1 10877.0 10907.8 10915.5 11106.8 21848.1 21858.6 21865.3 21991.6 22188.7 5415.6 5420.4 5423.6 5573.6 5584.5 3160.0 3168.0 3185.2 3185.6 3200.2 3546.0 3621.0 3638.7 3642.3 3659.2 602.5 612.2 618.8 622.1 622.3 455.2 475.5 476.7 483.1 490.8 7249.0 7456.7 7612.4 7615.4 7629.7 467.6 479.2 482.0 495.8 497.8 3270.7 3310.7 3332.2 3374.2 3394.6 56952.4 58483.7 61301.0 61574.0 61618.0 51729.2 52152.5 53160.5 53528.0 53621.7 47151.0 47821.3 48023.8 48834.5 49242.0 6649.5 6936.0 6980.2 7004.1 7120.4 25818.6 26152.6 26163.6 26278.5 26709.0 48188.3 49045.3 49760.5 50313.5 50622.0 10781.8 10841.5 11376.9 11454.9 11458.5 10742.9 10807.6 10910.2 11121.6 11241.3 10929.6 11203.4 11911.5 11917.2 11926.6 11217.9 11258.0 11771.8 11934.3 11940.8 10383.2 10967.1 11222.4 11303.5 11317.3 8657.5 8800.2 8968.6 9005.1 9098.0 8993.4 10112.8 10180.3 10282.6 10291.9 16:34:55 INFO - PID 30210 | -------- Summary: end -------- 16:34:55 INFO - PID 30210 | 16:34:56 INFO - TEST-INFO | 30210: exit 0 16:34:56 INFO - TEST-OK | dromaeo_css | took 1124220ms 16:34:56 INFO - TEST-START | kraken 16:34:56 INFO - operating with platform_type : linux_ 16:34:56 INFO - Initialising browser for kraken test... 16:34:56 INFO - TEST-INFO | started process 30538 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6PVoEO/profile http://localhost:46147/getInfo.html) 16:35:00 INFO - PID 30538 | __metrics Screen width/height:1600/1200 16:35:00 INFO - PID 30538 | colorDepth:24 16:35:00 INFO - PID 30538 | Browser inner width/height: 1024/697 16:35:00 INFO - PID 30538 | __metrics 16:35:01 ERROR - PID 30538 | 1490830501749 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 16:35:01 INFO - PID 30538 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:35:01 INFO - PID 30538 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:35:02 INFO - TEST-INFO | 30538: exit 0 16:35:02 INFO - Browser initialized. 16:35:02 INFO - Running cycle 1/1 for kraken test... 16:35:02 INFO - TEST-INFO | started process 30631 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6PVoEO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:35:14 INFO - PID 30631 | RSS: Main: 149655552 16:35:14 INFO - PID 30631 | 16:35:17 INFO - PID 30631 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46147/tests/kraken/driver.html?testName=audio-beat-detection) 16:35:18 INFO - PID 30631 | RSS: Main: 204873728 16:35:18 INFO - PID 30631 | 16:35:21 INFO - PID 30631 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46147/tests/kraken/driver.html?testName=audio-dft) 16:35:22 INFO - PID 30631 | RSS: Main: 184270848 16:35:22 INFO - PID 30631 | 16:35:25 INFO - PID 30631 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46147/tests/kraken/driver.html?testName=audio-fft) 16:35:25 INFO - PID 30631 | RSS: Main: 266424320 16:35:25 INFO - PID 30631 | 16:35:28 INFO - PID 30631 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46147/tests/kraken/driver.html?testName=audio-oscillator) 16:35:28 INFO - PID 30631 | RSS: Main: 176463872 16:35:28 INFO - PID 30631 | 16:35:30 INFO - PID 30631 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46147/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:35:31 INFO - PID 30631 | RSS: Main: 181264384 16:35:31 INFO - PID 30631 | 16:35:34 INFO - PID 30631 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46147/tests/kraken/driver.html?testName=imaging-darkroom) 16:35:35 INFO - PID 30631 | RSS: Main: 220504064 16:35:35 INFO - PID 30631 | 16:35:38 INFO - PID 30631 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46147/tests/kraken/driver.html?testName=imaging-desaturate) 16:35:39 INFO - PID 30631 | RSS: Main: 214200320 16:35:39 INFO - PID 30631 | 16:35:42 INFO - PID 30631 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46147/tests/kraken/driver.html?testName=json-parse-financial) 16:35:43 INFO - PID 30631 | RSS: Main: 216698880 16:35:43 INFO - PID 30631 | 16:35:44 INFO - PID 30631 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46147/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:35:45 INFO - PID 30631 | RSS: Main: 180133888 16:35:45 INFO - PID 30631 | 16:35:47 INFO - PID 30631 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:35:48 INFO - PID 30631 | RSS: Main: 186916864 16:35:48 INFO - PID 30631 | 16:35:50 INFO - PID 30631 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:35:50 INFO - PID 30631 | RSS: Main: 197156864 16:35:50 INFO - PID 30631 | 16:35:53 INFO - PID 30631 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:35:54 INFO - PID 30631 | RSS: Main: 205975552 16:35:54 INFO - PID 30631 | 16:35:57 INFO - PID 30631 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:35:58 INFO - PID 30631 | RSS: Main: 202944512 16:35:58 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46147/tests/kraken/driver.html?testName=ai-astar) 16:36:00 INFO - PID 30631 | RSS: Main: 271593472 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | __start_tp_report 16:36:00 INFO - PID 30631 | _x_x_mozilla_page_load 16:36:00 INFO - PID 30631 | _x_x_mozilla_page_load_details 16:36:00 INFO - PID 30631 | |i|pagename|runs| 16:36:00 INFO - PID 30631 | |0;ai-astar;108;112;111;112;113;111;113;112;113;112 16:36:00 INFO - PID 30631 | |1;audio-beat-detection;160;125;125;171;125;134;134;130;150;124 16:36:00 INFO - PID 30631 | |2;audio-dft;161;165;164;165;166;165;164;166;162;168 16:36:00 INFO - PID 30631 | |3;audio-fft;74;76;75;112;77;76;77;78;91;76 16:36:00 INFO - PID 30631 | |4;audio-oscillator;85;86;110;87;86;85;101;86;86;105 16:36:00 INFO - PID 30631 | |5;imaging-gaussian-blur;113;114;115;115;114;114;114;115;113;114 16:36:00 INFO - PID 30631 | |6;imaging-darkroom;150;149;150;150;149;149;150;151;150;150 16:36:00 INFO - PID 30631 | |7;imaging-desaturate;94;94;94;94;95;96;96;94;95;94 16:36:00 INFO - PID 30631 | |8;json-parse-financial;66;66;68;68;66;70;66;67;68;67 16:36:00 INFO - PID 30631 | |9;json-stringify-tinderbox;56;56;56;56;56;56;56;56;56;57 16:36:00 INFO - PID 30631 | |10;stanford-crypto-aes;84;86;86;83;84;83;84;86;83;86 16:36:00 INFO - PID 30631 | |11;stanford-crypto-ccm;145;136;146;132;143;134;141;135;187;144 16:36:00 INFO - PID 30631 | |12;stanford-crypto-pbkdf2;198;194;193;202;196;196;196;196;209;199 16:36:00 INFO - PID 30631 | |13;stanford-crypto-sha256-iterative;73;72;70;71;73;71;71;74;74;72 16:36:00 INFO - PID 30631 | __end_tp_report 16:36:00 INFO - PID 30631 | __start_cc_report 16:36:00 INFO - PID 30631 | _x_x_mozilla_cycle_collect,2042 16:36:00 INFO - PID 30631 | __end_cc_report 16:36:00 INFO - PID 30631 | __startTimestamp1490830560483__endTimestamp 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | ------- Summary: start ------- 16:36:00 INFO - PID 30631 | Number of tests: 14 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:1.49 (1.3%) stddev-sans-first:0.78 16:36:00 INFO - PID 30631 | Values: 108.0 112.0 111.0 112.0 113.0 111.0 113.0 112.0 113.0 112.0 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:134.00 stddev:16.72 (12.5%) stddev-sans-first:15.68 16:36:00 INFO - PID 30631 | Values: 160.0 125.0 125.0 171.0 125.0 134.0 134.0 130.0 150.0 124.0 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | [#2] audio-dft Cycles:10 Average:164.60 Median:165.00 stddev:2.01 (1.2%) stddev-sans-first:1.66 16:36:00 INFO - PID 30631 | Values: 161.0 165.0 164.0 165.0 166.0 165.0 164.0 166.0 162.0 168.0 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | [#3] audio-fft Cycles:10 Average:81.20 Median:76.50 stddev:11.84 (15.5%) stddev-sans-first:12.27 16:36:00 INFO - PID 30631 | Values: 74.0 76.0 75.0 112.0 77.0 76.0 77.0 78.0 91.0 76.0 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:86.00 stddev:9.66 (11.2%) stddev-sans-first:9.94 16:36:00 INFO - PID 30631 | Values: 85.0 86.0 110.0 87.0 86.0 85.0 101.0 86.0 86.0 105.0 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.67 16:36:00 INFO - PID 30631 | Values: 113.0 114.0 115.0 115.0 114.0 114.0 114.0 115.0 113.0 114.0 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | [#6] imaging-darkroom Cycles:10 Average:149.80 Median:150.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 16:36:00 INFO - PID 30631 | Values: 150.0 149.0 150.0 150.0 149.0 149.0 150.0 151.0 150.0 150.0 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:94.50 stddev:0.84 (0.9%) stddev-sans-first:0.87 16:36:00 INFO - PID 30631 | Values: 94.0 94.0 94.0 94.0 95.0 96.0 96.0 94.0 95.0 94.0 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:67.50 stddev:1.32 (2.0%) stddev-sans-first:1.32 16:36:00 INFO - PID 30631 | Values: 66.0 66.0 68.0 68.0 66.0 70.0 66.0 67.0 68.0 67.0 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | [#9] json-stringify-tinderbox Cycles:10 Average:56.10 Median:56.00 stddev:0.32 (0.6%) stddev-sans-first:0.33 16:36:00 INFO - PID 30631 | Values: 56.0 56.0 56.0 56.0 56.0 56.0 56.0 56.0 56.0 57.0 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:85.00 stddev:1.35 (1.6%) stddev-sans-first:1.42 16:36:00 INFO - PID 30631 | Values: 84.0 86.0 86.0 83.0 84.0 83.0 84.0 86.0 83.0 86.0 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | [#11] stanford-crypto-ccm Cycles:10 Average:144.30 Median:143.50 stddev:15.82 (11.0%) stddev-sans-first:16.78 16:36:00 INFO - PID 30631 | Values: 145.0 136.0 146.0 132.0 143.0 134.0 141.0 135.0 187.0 144.0 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.90 Median:197.00 stddev:4.65 (2.4%) stddev-sans-first:4.94 16:36:00 INFO - PID 30631 | Values: 198.0 194.0 193.0 202.0 196.0 196.0 196.0 196.0 209.0 199.0 16:36:00 INFO - PID 30631 | 16:36:00 INFO - PID 30631 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:72.50 stddev:1.37 (1.9%) stddev-sans-first:1.41 16:36:00 INFO - PID 30631 | Values: 73.0 72.0 70.0 71.0 73.0 71.0 71.0 74.0 74.0 72.0 16:36:00 INFO - PID 30631 | -------- Summary: end -------- 16:36:00 INFO - PID 30631 | 16:36:01 INFO - TEST-INFO | 30631: exit 0 16:36:01 INFO - TEST-OK | kraken | took 65123ms 16:36:01 INFO - SUITE-END | took 1189s 16:36:02 INFO - Completed test suite (00:19:49) 16:36:02 INFO - javascript benchmark 16:36:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6518.5142397251275, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2631.565791644379, 2662.161419152968, 2679.799015073869, 2695.905643302485, 2705.3777631144835, 4981.37732783402, 5062.974685126569, 5119.820806271786, 5128.589712822974, 5166.612504062196, 5147.897042059166, 5185.974070129644, 5191.169412893938, 5230.529252367286, 5256.237845512398, 4447.399601053865, 4692.788824502889, 4751.833685820997, 4819.638527110464, 4834.87912802181, 4756.976215118937, 4766.523347665223, 4785.090832741786, 4800.663953523254, 4859.489753575876, 4629.3518907353, 4668.089722504113, 4672.602828759559, 4681.040386720714, 4719.4100737407825, 4656.8602941911795, 4673.439187297512, 4677.906441871143, 4681.88295292617, 4683.039976804746, 4614.077184563101, 4633.62930965522, 4639.814407423699, 4645.7909394077005, 4653.278741795028, 5021.799128034874, 5060.367454068241, 5064.898702025976, 5075.619328550373, 5082.339295891547, 5453.563714902838, 5460.262864513319, 5476.424975377608, 5478.45215478453, 5496.532794712454, 5462.863428414282, 5484.972575137099, 5511.173324001392, 5627.549796016309, 5692.345380281279, 5222.660527065728, 5230.97384513075, 5231.633785635007, 5276.076686579871, 5282.656627319212, 5791.507721843606, 5797.565182611237, 5798.217240672539, 5805.564582656318, 5807.128930660435, 5317.1226747586215, 5396.757145928443, 5397.082495975694, 5397.9460205397445, 5406.810761623325, 5465.152901300305, 5544.972275138679, 5624.55003599711, 5635.8591035224445, 5637.154426835926, 5554.750036248379, 5564.860878478071, 5585.245991791056, 5588.776448941997, 5603.467670571289, 5596.4403559644525, 5599.916001259984, 5604.327480702342, 5613.494785469327, 5623.493885550321, 4528.41130653013, 4587.816487340536, 4642.791074401593, 4696.647751418589, 4709.058188362341, 3988.561258261589, 3991.92016159681, 4003.91992160161, 4020.6984476164403, 4032.516098068251, 4736.218523943511, 4744.667873248839, 4755.881102972384, 4756.21522448799, 4802.567768900815, 5458.208559758813, 5494.890102197934, 5549.500544950974, 5616.297962754656, 5632.97183514088, 4754.904901901942, 4765.142274390643, 4766.308885211625, 4800.879978000508, 4816.20532612115, 4997.900041999213, 4999.5500404963, 5011.599072074226, 5012.724300163453, 5056.039352523009, 5090.134677104975, 5137.152369858932, 5239.738013099253, 5260.39505456862, 5313.070212712682, 5498.670079795225, 5599.1041433370465, 5652.039153343859, 5680.034394153086, 5696.943030569807, 5255.395629502504, 5264.499872512105, 5266.394364647961, 5268.736563171749, 5311.017461769585, 5471.562275017909, 5486.780528778804, 5530.944690553043, 5537.141743029837, 5581.804636837691, 5448.101063324666, 5494.532964697885, 5600.635958662674, 5630.943690563042, 5676.801311954062, 5457.563394928476, 5462.535684466865, 5465.945340546544, 5471.671699698031, 5527.143292789624, 5195.792168313225, 5270.552003079782, 5283.154695248895, 5285.867853303698, 5291.3121294231505, 5262.789488420573, 5265.420803711512, 5267.367915850123, 5305.495977882095, 5310.415854256106, 5371.946280537145, 5375.462453754593, 5381.973090134525, 5401.216823560483, 5410.3507579090765, 5206.583473322201, 5223.112070948024, 5246.842594722164, 5249.160134378481, 5254.15933450646], "value": 5058.982838197548, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.3724978894099, 719.3885197582055, 1066.205676770806, 1085.4247248958054, 1092.8688557373112, 32788.34423311536, 33665.66334336659, 33776.66223337766, 33786.49320260198, 33848.323033539345, 35629.821850890774, 35990.10024749383, 35997.64002359976, 36027.2794544109, 36028.099297517576, 5634.774609015634, 7331.890021649666, 7521.0, 7524.811879703024, 7532.698692052339, 5756.568257380693, 7113.964430177842, 7314.195438501761, 7318.194998550155, 7323.707051717952, 7426.257374262557, 7458.776236712907, 7459.254074592524, 7461.403087752967, 7481.064866891638, 7350.264973502633, 7372.594507302097, 7390.852182956311, 7428.480006399554, 7432.0, 7241.927580724178, 7318.231585683533, 7321.1331110567, 7322.743703970335, 7324.743633972838, 33584.16039599029, 33815.154621134425, 33819.15452113692, 33935.83032084824, 33952.6604733952, 2655.7875369970357, 2674.358154043035, 2681.249250209925, 2698.2579790557556, 2725.5775354819834, 23119.422014449607, 23350.883245583835, 23392.883035584713, 23532.764672353227, 23757.524849503086, 2761.654793150856, 2780.0, 2780.7497325240624, 2788.567771995344, 2792.3856751514636, 145484.54515454816, 146133.0, 146146.0, 146479.0, 147378.0, 34556.136096597285, 35611.28777424437, 40739.38890916639, 40812.79593602013, 40860.0, 42702.0, 43313.783431082644, 43402.34896476555, 43458.78270608627, 43471.0, 11827.52689892412, 11877.762444751057, 11948.701282468008, 11961.162718609614, 11965.581204657796, 9500.382475139093, 9526.190273826802, 9535.713928582014, 9535.856962145574, 9536.66621668252, 12058.577949771716, 12074.758504829853, 12160.391980400944, 12187.085968552394, 12260.386980650932, 5069.670471419419, 5130.102232109366, 5130.256112863613, 5172.741362931838, 5197.480251974848, 871.4205053639386, 882.183979818678, 890.6526454682679, 899.8290324838259, 920.0753242990885, 20250.392488225378, 20926.37220883376, 21063.78936210649, 21225.15099396007, 21442.571148576943, 966.120830044656, 967.579103090158, 973.7078876337213, 979.1334668818147, 994.5077186792583, 10434.843477347846, 10683.89316106829, 10983.176261780398, 11007.559697612165, 11158.107351411867, 193702.0, 199426.0, 199750.0, 199883.0, 214454.0, 42902.78548607299, 43092.569074308856, 43099.784501077294, 43107.13785724268, 43183.13633727308, 1188.5067696905687, 1228.8525376954822, 1243.975120497603, 1260.9810852837215, 1262.7663882181826, 22457.0, 22553.43616409603, 23452.0, 23554.646680300153, 23568.6464703033, 29196.7080329194, 29227.123186304445, 29238.853805730836, 29294.0, 29296.121116366543, 188588.0, 189127.0, 189794.0, 189985.0, 190036.0, 22072.33782986513, 22448.32655020352, 26159.0, 26234.344141396617, 26610.467790644467, 32826.34347312993, 33042.66957330492, 33328.333433330714, 38182.809085954395, 39151.2169756609, 29823.254418639706, 30185.69814301829, 30467.542986854773, 30768.46856219944, 31537.36925261436, 30297.24256893595, 30508.847455762578, 30865.228369290948, 31365.372692546476, 31944.840275798473, 34606.0, 35007.824960875034, 35046.649533505355, 35080.298394032485, 35335.29329413346, 30554.388912221188, 30799.538006929466, 31050.378992419573, 31087.533686994262, 31164.376712466077, 23029.424264393525, 24408.26775196747, 24645.260642180765, 24707.752922471263, 24872.502549949262], "value": 14806.787254421575, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.7389512079346, 390.2662993572084, 417.3822742341334, 419.484034637396, 423.7944596870517, 12622.81065784014, 16775.91612041938, 17917.641647167064, 18668.439946801584, 18678.62642747146, 13133.540326088589, 14082.0, 17764.911175444136, 17976.0, 18468.538286542844, 5521.254630624874, 5723.599348045638, 5724.313082430114, 5753.597248192628, 5769.480746732793, 5597.776088956437, 5647.943520564803, 5690.231818704463, 5693.316801983768, 5693.572982026344, 5583.832485025456, 5700.315962084555, 5712.028955077647, 5716.1997320375185, 5719.313682358123, 5640.294963129609, 5664.801731939362, 5703.03048481757, 5704.0303148464445, 5708.80019199326, 5140.74296285188, 5169.689818610896, 5193.402758682762, 5217.791288348461, 5223.869403264911, 21845.67231491529, 22136.335909922727, 22165.889170554045, 22171.445713857283, 22279.77720222793, 1559.337281655292, 1561.5627624265223, 1582.6359937214504, 1600.2638786158382, 1633.9836601633951, 13801.792973105412, 13823.447062117502, 14154.85845141556, 14176.716465670628, 14750.55748327552, 1610.5731981025037, 1628.3405220885443, 1634.9427770027992, 1652.3638399216268, 1659.9170041497998, 86769.0, 88552.55723721405, 89852.5507372459, 90817.54591227001, 92280.53859730726, 10683.09193718527, 10733.195010374253, 10854.077403420797, 11132.165087618461, 11148.219624626361, 15666.98164619319, 15689.450869219523, 15691.921540392224, 15701.842981570038, 15777.447789327318, 6712.295209003082, 7064.046353742282, 7320.889349531053, 7523.661435235318, 7743.8838417423785, 6335.651539165298, 6537.084808126866, 6621.801345959629, 6630.801075967729, 6674.532782705164, 6942.618156001468, 7000.094960591399, 7006.929930700628, 7080.256573059857, 7252.005698432868, 1971.3001884331143, 1983.9107240174226, 1993.451800754804, 2022.5044864008412, 2092.7384076990375, 827.6358402302967, 847.2544161138159, 855.3883972959364, 863.8747381629629, 867.1458613265928, 16091.436799712188, 16621.335146594, 17131.143442827808, 17197.828021719874, 17325.480235592953, 839.3620848155446, 866.2290561400358, 868.8566386546275, 870.5560164316245, 884.3146561414954, 8565.78585535354, 9230.907690923004, 9286.210672092948, 9333.393329433566, 9462.668806591873, 461724.0, 462604.0, 469276.0, 471799.0, 474284.0, 90773.54613226892, 91301.0, 91709.54145229231, 91834.54082729544, 92842.53578732064, 1050.3435352904435, 1079.551985925832, 1100.125400306742, 1113.3375641493299, 1115.8158903780948, 13899.61003899602, 13908.095973761672, 13972.0, 14146.816966182527, 14832.777508337167, 38835.611643883196, 39477.0, 39514.2097158061, 39626.801865990485, 40492.0, 53610.73194634002, 54074.72962635162, 54163.45836541584, 54372.184417234566, 54467.0, 7919.524828510308, 8059.0732065810835, 8108.2297181767635, 8315.95842020786, 8480.618372173143, 12507.624771256877, 12874.871251287368, 12911.160774549624, 13036.674083147618, 13185.802212967004, 10029.247806414402, 10210.897891021292, 10304.227182961158, 10331.0, 10658.253922225369, 10127.24045696561, 10310.32982856112, 10426.218033647356, 10519.421431821018, 10606.522706478072, 11251.0, 11365.204435689753, 11440.542378304775, 11452.599159029394, 11825.82261266099, 7960.721374751856, 8147.9185208147155, 8227.465214761261, 8400.789980250544, 8566.229039386486, 9395.342326037386, 9548.42709437436, 9650.276229282692, 9748.610055597981, 9761.365511241747], "value": 8764.309867408998, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1242.0622430065303, 1290.5547586082805, 1293.2434525802405, 1298.9997701769637, 1322.4577923051552, 9255.120763527471, 9672.758181045478, 9675.516224188797, 9678.177354924832, 9678.516074196297, 9408.717738467856, 9430.528473576329, 9701.70894873155, 9719.0, 9724.222062235021, 2137.177186783088, 2194.2539536557565, 2194.4623567226054, 2196.8242540596716, 2199.461132022657, 1861.8696691231619, 2098.4544018555134, 2153.9569208615817, 2181.0512427094186, 2230.297456301268, 1899.4871384726116, 2111.9260825871097, 2190.9671354929687, 2190.9780902191014, 2196.802287794098, 2096.6330892093824, 2116.492041909938, 2120.7349081364828, 2128.531723020933, 2129.4144110369693, 2075.377386783974, 2089.968650470244, 2090.7491101067967, 2092.1526781653456, 2094.0890712540095, 9236.491992940386, 9377.484238366887, 9379.155875971128, 9387.249020078445, 9392.67125650599, 5303.443138470482, 5512.173174023888, 5512.641678290898, 5525.4474552544425, 5526.613137080406, 3137.1043552300694, 3252.886148984774, 3320.9870989348483, 3324.8670053197843, 3333.100062983005, 1000.6197644894893, 1050.070687441612, 1075.1774892207468, 1077.7036315013356, 1091.7598128411737, 13459.327033648278, 13781.173129612256, 13933.094348867291, 13935.372908219257, 14122.435102595884, 4747.098051370298, 4906.779194936236, 4989.625778066659, 5007.874803129951, 5012.172991456442, 11663.76672466546, 11707.707307317214, 11709.473073711704, 11710.1217408693, 11715.882841171479, 5566.415526369673, 5581.134924086692, 5584.972075139598, 5587.916181257285, 5596.1605759136455, 4833.299171620095, 4974.701517908937, 4977.576905962961, 5032.899342013139, 5035.0, 3681.9690486663776, 3827.2919509890758, 3836.731428800013, 3836.7889766063136, 3841.4237864320576, 1125.6960620632383, 1139.5726602524053, 1140.3670962615827, 1140.8185472450941, 1141.7316930521322, 559.5976226617978, 683.2416018219772, 696.4532841719226, 698.3296035805582, 718.4683334332557, 5569.888602227933, 5725.541956643458, 5743.54051675865, 5778.971105144531, 5787.88424231513, 799.3245707377373, 807.1565214351017, 824.4805772363372, 827.3009307135461, 833.0628043451118, 3335.149536868083, 3494.7728397654073, 3572.446270827974, 3592.604813470516, 3681.3189559931498, 11861.169718119649, 12441.688957776129, 12478.47806884012, 12643.810342844865, 12677.049221308438, 12375.876241237653, 12449.25304481734, 12467.127301089018, 12482.50069997208, 12514.561990330476, 8563.143685631387, 8838.955805221061, 8883.600237989305, 8901.332400070021, 8907.777305567282, 5379.112446446449, 5421.132618780976, 5456.372517160577, 5518.475744804237, 5626.831195063991, 11030.50362733695, 11092.500837462496, 11177.608783692533, 11225.943870280596, 11254.549818007188, 11240.55037798479, 11309.377984210947, 11349.659510214708, 11437.370944598128, 11478.483468244116, 7948.324392426708, 8089.47418417801, 8156.796080097808, 8160.959195204224, 8162.6734930602115, 8820.691275805573, 8874.201321881063, 8952.68665596701, 8961.865572016555, 9191.310651701015, 7296.854062918818, 7346.191918889025, 7372.29963153521, 7376.4836461447185, 7468.5145465544565, 7123.786286411416, 7148.249433809375, 7156.320149585781, 7363.8159046024275, 7391.96304018498, 7087.751928682472, 7125.216226215009, 7190.784276471714, 7197.568145911262, 7211.927880721336, 4865.440474345354, 4889.364382630378, 4916.0, 5054.19132938728, 5146.408163061146, 4902.509749025069, 4997.600191984559, 5013.523715247045, 5162.328897243335, 5241.082810508222], "value": 4585.258386836597, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.15783697495755, 387.9883603491895, 388.67545599424477, 391.9745216560925, 394.77300552182487, 5305.204219367097, 5337.893242135155, 5366.409694933554, 5381.408045115034, 5388.299521062267, 5001.8249361272365, 5134.589232861372, 5156.200788877721, 5178.508041736039, 5255.527002569768, 4074.694397920153, 4080.6735461163184, 4085.5097388313443, 4099.959000409995, 4109.4041364002205, 4078.2455245779624, 4086.2031903778775, 4089.141280331119, 4098.918021639581, 4112.794360281974, 3988.5413177484525, 4018.598140185987, 4058.472398588179, 4091.7340372875815, 4097.0, 4042.3936409538655, 4049.1699201663587, 4056.4523789288364, 4065.3292206785845, 4069.7151199416053, 3562.0738607961857, 3563.6080031196548, 3566.0906468850276, 3568.6082427853157, 3576.2847430513743, 4996.275540046673, 5103.719295438749, 5117.795288188468, 5133.922991155136, 5152.896942061176, 4661.720296782204, 4680.625549955996, 4695.413073365829, 4701.764911754399, 4720.150372932871, 5514.5864060195645, 5538.667679939216, 5539.390667026624, 5543.944560554383, 5568.86077848053, 3939.1530820873363, 3940.4877365942243, 3952.7548822121225, 3967.6429121378924, 3981.7013723970526, 5616.157576363537, 5629.887402251932, 5662.546996240291, 5700.572457065695, 5860.09168578871, 4007.6593489553134, 4008.198360327946, 4016.6786657067364, 4017.2367250222364, 4029.073313137936, 4053.351463765783, 4078.490188726409, 4089.7955102245364, 4104.794760261975, 4105.199486100182, 4457.0, 4472.016156445577, 4563.452385713736, 4687.601553867891, 4701.412323459567, 5594.300712410949, 5595.272614560081, 5597.580181486404, 5605.5515558755205, 5605.775768969195, 1413.6041908265702, 1421.2964582531713, 1423.6369725720083, 1427.1009264163717, 1427.3086392624566, 928.6146249306462, 954.9809003819885, 960.3421656165549, 970.7087873638021, 972.6498460554195, 4815.15734746418, 4828.348172996671, 4833.492483289274, 4833.709977401367, 4844.0, 5743.913841292384, 5746.511546518509, 5752.597318187687, 5797.36229014808, 5800.0, 4679.227927391942, 4799.256115302133, 4828.5412885775795, 4832.44426890905, 4833.130036593377, 3006.1582756828125, 3042.117785842125, 3042.756579473681, 3049.9477680200293, 3051.404976029697, 5336.733163341817, 5352.866178345573, 5362.302900622972, 5375.650582712111, 5376.623636345418, 5721.256236689287, 5830.522028138024, 5846.941530584725, 5853.385394533598, 5877.177195192677, 5326.920096198638, 5333.853221557384, 5346.331708536433, 5354.651947623392, 5356.839294821161, 5559.805406810742, 5722.8569285766525, 5722.914156287576, 5724.169995350734, 5738.426157384228, 5592.105263158038, 5626.915596266142, 5636.915446268391, 5646.181303711021, 5672.404397538199, 5120.71836049006, 5252.579793616578, 5263.815766448156, 5290.761915713725, 5354.7590358434745, 3811.1424929390664, 3917.472185847465, 3926.784026878435, 4006.4791577094793, 4052.412400202013, 5740.454656807597, 5752.194692742935, 5753.1370294456165, 5754.683492407958, 5759.9136012959, 5377.2740680009265, 5511.200875873056, 5533.529649979797, 5555.194496798023, 5582.972085139549, 5553.16702494629, 5556.361018482765, 5578.581606379619, 5599.692016939108, 5600.69196194197, 5363.19552067193, 5526.447355264441, 5538.889222215453, 5541.224228608079, 5552.666839989613, 5599.440055994368, 5622.24099746529, 5622.690752008515, 5631.521320687787, 5646.85882852932, 5456.563474922076, 5460.7542660579575, 5472.0, 5505.421930697379, 5520.585956053232], "value": 4185.816040505906, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.7898367096006, 584.9970750146249, 595.3748564007792, 602.0968547179231, 602.2171177469289, 12057.39713014349, 12327.938360308186, 12479.385925459499, 12566.497340106396, 12815.102942794008, 11806.82289765652, 11990.820137697941, 12029.0, 12045.0, 12050.096242781805, 877.1798368525417, 898.1871406377239, 910.3581974707815, 910.6266430763388, 910.7404389748923, 912.4753266871543, 913.8720579118929, 915.2952226785371, 931.5668214280358, 938.5119737736374, 957.5690939077408, 959.6209497248583, 959.7648576098866, 961.7499450142943, 961.7643677299037, 947.327397547742, 948.2177203806887, 949.9477528735916, 950.0167326816746, 950.5959967013991, 703.3107554596525, 703.9084918960554, 705.0094617063035, 705.3722187253348, 705.8835292176784, 12888.0, 12998.545050923267, 13637.386317615732, 13779.793303100461, 13819.654508637264, 13552.457901683922, 14192.432302707879, 14305.427782888775, 14384.136951782823, 14499.855001449956, 9483.525823708787, 9618.711438656852, 9624.47065411402, 9655.420674759536, 9863.506824658738, 2484.7391023942405, 2511.2842839790574, 2524.192258477288, 2525.7853082487827, 2535.6196570514385, 62751.6862415685, 64655.67672161656, 66812.3318766811, 66957.3304266956, 67877.32122678809, 10734.141268698486, 10877.0, 10907.781844363068, 10915.454227288605, 11106.777864442665, 21848.12607495714, 21858.562828743656, 21865.344039678836, 21991.560168796535, 22188.667169992463, 5415.566754659618, 5420.376656684532, 5423.5932305076485, 5573.5819813514145, 5584.52531534816, 3159.952600710991, 3167.9683203167674, 3185.235543469597, 3185.5858738363863, 3200.2159470929773, 3546.0, 3621.040424287566, 3638.7452878298263, 3642.307961487309, 3659.213269147146, 602.4698265526309, 612.2163630552899, 618.8452886778306, 622.1228068423501, 622.3185611755121, 455.21793558666656, 475.48647460742313, 476.70920738349577, 483.1303653423893, 490.8134908734658, 7249.021382113453, 7456.701731930662, 7612.391008719289, 7615.390768738488, 7629.694812207561, 467.56282875511505, 479.2212654436541, 482.0479552883098, 495.8487661263349, 497.8423532206339, 3270.738340932767, 3310.741918071166, 3332.166958260435, 3374.2407958209205, 3394.575678040245, 56952.43047569554, 58483.70758146267, 61301.0, 61574.0, 61618.0, 51729.22406163911, 52152.478475215525, 53160.468395315555, 53528.0, 53621.73189134029, 47151.0, 47821.28268075982, 48023.75988120037, 48834.51165488371, 49242.0, 6649.46804255668, 6936.0, 6980.197277312971, 7004.076525469692, 7120.430365570845, 25818.612720808826, 26152.60771088473, 26163.607545887207, 26278.474430511666, 26709.0, 48188.277175841686, 49045.2643210345, 49760.502394975585, 50313.49686503088, 50622.0, 10781.784364312512, 10841.457927103771, 11376.943115284701, 11454.941089582326, 11458.484368203284, 10742.946285268523, 10807.567697292021, 10910.181736369963, 11121.610743623934, 11241.269317494336, 10929.617463388744, 11203.43982800873, 11911.52353905834, 11917.22538035025, 11926.642200733992, 11217.943910280397, 11258.0, 11771.764564708828, 11934.28394296345, 11940.76118477643, 10383.169346452416, 10967.122630189726, 11222.38276894746, 11303.547858085585, 11317.264377815416, 8657.523836188819, 8800.16398442147, 8968.596413161555, 9005.14451127142, 9097.954510227406, 8993.415427997159, 10112.848307275544, 10180.287379883337, 10282.588696452358, 10291.897081029094], "value": 6087.951956087402, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1567.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 111.0, 112.0, 113.0, 111.0, 113.0, 112.0, 113.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 125.0, 125.0, 171.0, 125.0, 134.0, 134.0, 130.0, 150.0, 124.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 165.0, 164.0, 165.0, 166.0, 165.0, 164.0, 166.0, 162.0, 168.0], "value": 164.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 112.0, 77.0, 76.0, 77.0, 78.0, 91.0, 76.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 110.0, 87.0, 86.0, 85.0, 101.0, 86.0, 86.0, 105.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 115.0, 115.0, 114.0, 114.0, 114.0, 115.0, 113.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 150.0, 150.0, 149.0, 149.0, 150.0, 151.0, 150.0, 150.0], "value": 149.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 96.0, 96.0, 94.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 68.0, 68.0, 66.0, 70.0, 66.0, 67.0, 68.0, 67.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 56.0, 56.0, 56.0, 56.0, 56.0, 56.0, 57.0], "value": 56.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 86.0, 83.0, 84.0, 83.0, 84.0, 86.0, 83.0, 86.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 136.0, 146.0, 132.0, 143.0, 134.0, 141.0, 135.0, 187.0, 144.0], "value": 144.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 194.0, 193.0, 202.0, 196.0, 196.0, 196.0, 196.0, 209.0, 199.0], "value": 197.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 70.0, 71.0, 73.0, 71.0, 71.0, 74.0, 74.0, 72.0], "value": 72.1, "unit": "score"}]}]} 16:36:02 INFO - Return code: 0 16:36:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:36:02 ERROR - # TBPL SUCCESS # 16:36:02 INFO - Running post-action listener: _resource_record_post_action 16:36:02 INFO - [mozharness: 2017-03-29 23:36:02.287296Z] Finished run-tests step (success) 16:36:02 INFO - Running post-run listener: _resource_record_post_run 16:36:02 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 4096; Write bytes: 372678656; Read time: 12; Write time: 256956 16:36:02 INFO - TinderboxPrint: CPU usage
13.0% 16:36:02 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:36:02 INFO - TinderboxPrint: I/O write bytes / time
372,678,656 / 256,956 16:36:02 INFO - TinderboxPrint: CPU idle
8,373.6 (87.0%) 16:36:02 INFO - TinderboxPrint: CPU user
1,204.2 (12.5%) 16:36:02 INFO - TinderboxPrint: Swap in / out
0 / 0 16:36:02 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 112758784; Read time: 0; Write time: 114476 16:36:02 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 259010560; Read time: 12; Write time: 142460 16:36:02 INFO - Running post-run listener: _upload_blobber_files 16:36:02 INFO - Blob upload gear active. 16:36:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:36:02 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 16:36:02 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:36:02 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:36:03 INFO - (blobuploader) - INFO - Open directory for files ... 16:36:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:36:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:36:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:04 INFO - (blobuploader) - INFO - Done attempting. 16:36:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:36:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:36:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:05 INFO - (blobuploader) - INFO - Done attempting. 16:36:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:36:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:36:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:05 INFO - (blobuploader) - INFO - Done attempting. 16:36:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:36:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:36:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:09 INFO - (blobuploader) - INFO - Done attempting. 16:36:09 INFO - (blobuploader) - INFO - Iteration through files over. 16:36:09 INFO - Return code: 0 16:36:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:36:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:36:09 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e17966762abc84e40946d149e5e858ef2a740a54d84e5bd88b6448757b6ce54f9479675091df7caef8225d205928129c6f25e54df912c6371c128ec938d2169", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c292ec594b1ae7cfe558f42b8b63b0dd009a96374ec39f2da6c307ef0e0881710dd42495c84c0354f0a3a7e530e0803bbf2b9d9f7eecefd37b98a9f5c3cc8f67", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f97c6f547932987e30bad3f4736b879ed174311a55dacd7017cbf4931474f7f2b5b737f076b371989ca16d24e35fde576d3d02e6fe1eb60c52156d0785cdc1aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ba8758927a03a34885864cd04105ad5a4ee90fd1471c4834d4d27f1a4bfffe479d98ba486b5b976d3c8d8754b5b7b1233855c47e117150d2b2c69a3ae5af6c7"} 16:36:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:36:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:36:09 INFO - Contents: 16:36:09 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e17966762abc84e40946d149e5e858ef2a740a54d84e5bd88b6448757b6ce54f9479675091df7caef8225d205928129c6f25e54df912c6371c128ec938d2169", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c292ec594b1ae7cfe558f42b8b63b0dd009a96374ec39f2da6c307ef0e0881710dd42495c84c0354f0a3a7e530e0803bbf2b9d9f7eecefd37b98a9f5c3cc8f67", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f97c6f547932987e30bad3f4736b879ed174311a55dacd7017cbf4931474f7f2b5b737f076b371989ca16d24e35fde576d3d02e6fe1eb60c52156d0785cdc1aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ba8758927a03a34885864cd04105ad5a4ee90fd1471c4834d4d27f1a4bfffe479d98ba486b5b976d3c8d8754b5b7b1233855c47e117150d2b2c69a3ae5af6c7"} 16:36:09 INFO - Running post-run listener: copy_logs_to_upload_dir 16:36:09 INFO - Copying logs to upload dir... 16:36:09 INFO - mkdir: /builds/slave/test/build/upload/logs 16:36:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.057302 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-03-29 16:36:08.977929) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 16:36:08.981617) ========= 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=3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e17966762abc84e40946d149e5e858ef2a740a54d84e5bd88b6448757b6ce54f9479675091df7caef8225d205928129c6f25e54df912c6371c128ec938d2169", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c292ec594b1ae7cfe558f42b8b63b0dd009a96374ec39f2da6c307ef0e0881710dd42495c84c0354f0a3a7e530e0803bbf2b9d9f7eecefd37b98a9f5c3cc8f67", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f97c6f547932987e30bad3f4736b879ed174311a55dacd7017cbf4931474f7f2b5b737f076b371989ca16d24e35fde576d3d02e6fe1eb60c52156d0785cdc1aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ba8758927a03a34885864cd04105ad5a4ee90fd1471c4834d4d27f1a4bfffe479d98ba486b5b976d3c8d8754b5b7b1233855c47e117150d2b2c69a3ae5af6c7"} build_url:https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011463 build_url: 'https://queue.taskcluster.net/v1/task/KOQbI6cGSHC6z2G0BEe_2w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e17966762abc84e40946d149e5e858ef2a740a54d84e5bd88b6448757b6ce54f9479675091df7caef8225d205928129c6f25e54df912c6371c128ec938d2169", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c292ec594b1ae7cfe558f42b8b63b0dd009a96374ec39f2da6c307ef0e0881710dd42495c84c0354f0a3a7e530e0803bbf2b9d9f7eecefd37b98a9f5c3cc8f67", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f97c6f547932987e30bad3f4736b879ed174311a55dacd7017cbf4931474f7f2b5b737f076b371989ca16d24e35fde576d3d02e6fe1eb60c52156d0785cdc1aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ba8758927a03a34885864cd04105ad5a4ee90fd1471c4834d4d27f1a4bfffe479d98ba486b5b976d3c8d8754b5b7b1233855c47e117150d2b2c69a3ae5af6c7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 16:36:09.008785) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:36:09.009129) ========= 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=3297cc2eebb5444a7688c33f000001b5-1490829302.399173-416273182 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004451 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:36:09.094043) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 16:36:09.094376) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 16:36:09.094701) ========= ========= Total master_lag: 0.17 =========